{"level":"warn","ts":"2026-08-24T15:22:07.775699Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:22:07.775781Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.138:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.138:2380","--initial-cluster=instance=https://199.19.213.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.138:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-24T15:22:07.775846Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:22:07.775859Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.138:2380"]} {"level":"info","ts":"2026-08-24T15:22:07.775877Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T15:22:07.776487Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"]} {"level":"info","ts":"2026-08-24T15:22:07.776556Z","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.19.213.138:2380"],"listen-peer-urls":["https://199.19.213.138:2380"],"advertise-client-urls":["https://199.19.213.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.138:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-24T15:22:07.780986Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.20409ms"} {"level":"info","ts":"2026-08-24T15:22:07.789695Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2062be27cfb43adb","cluster-id":"f353072e75ed4d57"} {"level":"info","ts":"2026-08-24T15:22:07.789832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T15:22:07.78988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became follower at term 0"} {"level":"info","ts":"2026-08-24T15:22:07.789908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2062be27cfb43adb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T15:22:07.789931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became follower at term 1"} {"level":"info","ts":"2026-08-24T15:22:07.790042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=(2333636635129035483)"} {"level":"warn","ts":"2026-08-24T15:22:07.801629Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T15:22:07.803679Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T15:22:07.805013Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-24T15:22:07.807754Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2062be27cfb43adb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T15:22:07.808558Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2062be27cfb43adb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T15:22:07.808646Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:22:07.808672Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:22:07.808682Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:22:07.811493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=(2333636635129035483)"} {"level":"info","ts":"2026-08-24T15:22:07.812258Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f353072e75ed4d57","local-member-id":"2062be27cfb43adb","added-peer-id":"2062be27cfb43adb","added-peer-peer-urls":["https://199.19.213.138:2380"]} {"level":"info","ts":"2026-08-24T15:22:07.815159Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T15:22:07.815315Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.138:2380"} {"level":"info","ts":"2026-08-24T15:22:07.815392Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.138:2380"} {"level":"info","ts":"2026-08-24T15:22:07.815476Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2062be27cfb43adb","initial-advertise-peer-urls":["https://199.19.213.138:2380"],"listen-peer-urls":["https://199.19.213.138:2380"],"advertise-client-urls":["https://199.19.213.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T15:22:07.815533Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T15:22:08.79106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T15:22:08.791125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T15:22:08.791166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb received MsgPreVoteResp from 2062be27cfb43adb at term 1"} {"level":"info","ts":"2026-08-24T15:22:08.791186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became candidate at term 2"} {"level":"info","ts":"2026-08-24T15:22:08.791197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb received MsgVoteResp from 2062be27cfb43adb at term 2"} {"level":"info","ts":"2026-08-24T15:22:08.791214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became leader at term 2"} {"level":"info","ts":"2026-08-24T15:22:08.791227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2062be27cfb43adb elected leader 2062be27cfb43adb at term 2"} {"level":"info","ts":"2026-08-24T15:22:08.792218Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2062be27cfb43adb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.138:2379]}","request-path":"/0/members/2062be27cfb43adb/attributes","cluster-id":"f353072e75ed4d57","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T15:22:08.792218Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:22:08.792241Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:22:08.792252Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:22:08.792543Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T15:22:08.792648Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T15:22:08.793248Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f353072e75ed4d57","local-member-id":"2062be27cfb43adb","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:22:08.793362Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:22:08.793392Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:22:08.794517Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.138:2379"} {"level":"info","ts":"2026-08-24T15:22:08.795544Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T15:22:44.548009Z","caller":"traceutil/trace.go:171","msg":"trace[1189178371] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"225.551041ms","start":"2026-08-24T15:22:44.3224Z","end":"2026-08-24T15:22:44.547951Z","steps":["trace[1189178371] 'process raft request' (duration: 225.292828ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:44.548159Z","caller":"traceutil/trace.go:171","msg":"trace[147540320] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"208.00304ms","start":"2026-08-24T15:22:44.3401Z","end":"2026-08-24T15:22:44.548103Z","steps":["trace[147540320] 'process raft request' (duration: 207.815461ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:44.54851Z","caller":"traceutil/trace.go:171","msg":"trace[1454821184] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"207.80596ms","start":"2026-08-24T15:22:44.340696Z","end":"2026-08-24T15:22:44.548502Z","steps":["trace[1454821184] 'process raft request' (duration: 207.359398ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:44.548605Z","caller":"traceutil/trace.go:171","msg":"trace[1841491018] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"214.25202ms","start":"2026-08-24T15:22:44.333801Z","end":"2026-08-24T15:22:44.548053Z","steps":["trace[1841491018] 'process raft request' (duration: 214.04643ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:44.548616Z","caller":"traceutil/trace.go:171","msg":"trace[1912887251] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"208.319746ms","start":"2026-08-24T15:22:44.340253Z","end":"2026-08-24T15:22:44.548573Z","steps":["trace[1912887251] 'process raft request' (duration: 207.737897ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:44.549725Z","caller":"traceutil/trace.go:171","msg":"trace[82421050] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"203.810562ms","start":"2026-08-24T15:22:44.345894Z","end":"2026-08-24T15:22:44.549704Z","steps":["trace[82421050] 'process raft request' (duration: 202.248484ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:44.54984Z","caller":"traceutil/trace.go:171","msg":"trace[364661909] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:545; }","duration":"178.79915ms","start":"2026-08-24T15:22:44.371024Z","end":"2026-08-24T15:22:44.549823Z","steps":["trace[364661909] 'read index received' (duration: 7.991047ms)","trace[364661909] 'applied index is now lower than readState.Index' (duration: 170.805793ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:22:44.54993Z","caller":"traceutil/trace.go:171","msg":"trace[952642216] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"203.958778ms","start":"2026-08-24T15:22:44.34596Z","end":"2026-08-24T15:22:44.549918Z","steps":["trace[952642216] 'process raft request' (duration: 203.737477ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:44.549936Z","caller":"traceutil/trace.go:171","msg":"trace[1755972708] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"203.989941ms","start":"2026-08-24T15:22:44.345933Z","end":"2026-08-24T15:22:44.549923Z","steps":["trace[1755972708] 'process raft request' (duration: 203.847884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:22:44.550156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.111305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-24T15:22:44.550236Z","caller":"traceutil/trace.go:171","msg":"trace[842614237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"179.240702ms","start":"2026-08-24T15:22:44.370983Z","end":"2026-08-24T15:22:44.550224Z","steps":["trace[842614237] 'agreement among raft nodes before linearized reading' (duration: 179.066643ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:44.55477Z","caller":"traceutil/trace.go:171","msg":"trace[1667920556] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"164.89806ms","start":"2026-08-24T15:22:44.389854Z","end":"2026-08-24T15:22:44.554752Z","steps":["trace[1667920556] 'process raft request' (duration: 164.626376ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:44.55485Z","caller":"traceutil/trace.go:171","msg":"trace[938567646] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"164.971034ms","start":"2026-08-24T15:22:44.389859Z","end":"2026-08-24T15:22:44.55483Z","steps":["trace[938567646] 'process raft request' (duration: 164.756213ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:44.555038Z","caller":"traceutil/trace.go:171","msg":"trace[1548361269] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"156.677732ms","start":"2026-08-24T15:22:44.398352Z","end":"2026-08-24T15:22:44.555029Z","steps":["trace[1548361269] 'process raft request' (duration: 156.420989ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:44.55512Z","caller":"traceutil/trace.go:171","msg":"trace[813626494] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"156.837539ms","start":"2026-08-24T15:22:44.398271Z","end":"2026-08-24T15:22:44.555109Z","steps":["trace[813626494] 'process raft request' (duration: 156.429739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:22:44.555152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.875862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T15:22:44.555257Z","caller":"traceutil/trace.go:171","msg":"trace[1676091077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"180.983948ms","start":"2026-08-24T15:22:44.374238Z","end":"2026-08-24T15:22:44.555222Z","steps":["trace[1676091077] 'agreement among raft nodes before linearized reading' (duration: 180.82204ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:51.234139Z","caller":"traceutil/trace.go:171","msg":"trace[362253938] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"107.514027ms","start":"2026-08-24T15:22:51.126604Z","end":"2026-08-24T15:22:51.234118Z","steps":["trace[362253938] 'process raft request' (duration: 107.048574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:22:51.804312Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241159620864258945,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T15:22:51.828532Z","caller":"traceutil/trace.go:171","msg":"trace[333479529] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"536.036789ms","start":"2026-08-24T15:22:51.292474Z","end":"2026-08-24T15:22:51.828511Z","steps":["trace[333479529] 'process raft request' (duration: 535.916283ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:51.828564Z","caller":"traceutil/trace.go:171","msg":"trace[1732442220] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:665; }","duration":"524.8003ms","start":"2026-08-24T15:22:51.303712Z","end":"2026-08-24T15:22:51.828512Z","steps":["trace[1732442220] 'read index received' (duration: 524.79121ms)","trace[1732442220] 'applied index is now lower than readState.Index' (duration: 7.48µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:22:51.828766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.027261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:22:51.82881Z","caller":"traceutil/trace.go:171","msg":"trace[1836082949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"525.113016ms","start":"2026-08-24T15:22:51.303685Z","end":"2026-08-24T15:22:51.828798Z","steps":["trace[1836082949] 'agreement among raft nodes before linearized reading' (duration: 524.975869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:22:51.82885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:22:51.303649Z","time spent":"525.19212ms","remote":"127.0.0.1:42230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:22:51.829027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:22:51.292451Z","time spent":"536.139694ms","remote":"127.0.0.1:42450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:23:02.417864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.534756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:02.41805Z","caller":"traceutil/trace.go:171","msg":"trace[1158806862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"115.760298ms","start":"2026-08-24T15:23:02.302272Z","end":"2026-08-24T15:23:02.418032Z","steps":["trace[1158806862] 'range keys from in-memory index tree' (duration: 115.451751ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:11.694491Z","caller":"traceutil/trace.go:171","msg":"trace[2005122497] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"213.396131ms","start":"2026-08-24T15:23:11.48105Z","end":"2026-08-24T15:23:11.694446Z","steps":["trace[2005122497] 'read index received' (duration: 203.846638ms)","trace[2005122497] 'applied index is now lower than readState.Index' (duration: 9.548613ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:23:11.694686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.619373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:23:11.694771Z","caller":"traceutil/trace.go:171","msg":"trace[27652038] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:814; }","duration":"213.757041ms","start":"2026-08-24T15:23:11.480994Z","end":"2026-08-24T15:23:11.694751Z","steps":["trace[27652038] 'agreement among raft nodes before linearized reading' (duration: 213.614733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:11.694803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.13062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:11.694872Z","caller":"traceutil/trace.go:171","msg":"trace[1476629641] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:814; }","duration":"102.299019ms","start":"2026-08-24T15:23:11.592559Z","end":"2026-08-24T15:23:11.694858Z","steps":["trace[1476629641] 'agreement among raft nodes before linearized reading' (duration: 102.084048ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:11.695007Z","caller":"traceutil/trace.go:171","msg":"trace[1404712630] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"267.947205ms","start":"2026-08-24T15:23:11.427044Z","end":"2026-08-24T15:23:11.694991Z","steps":["trace[1404712630] 'process raft request' (duration: 257.97101ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:34.915383Z","caller":"traceutil/trace.go:171","msg":"trace[1541597722] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"350.285099ms","start":"2026-08-24T15:23:34.565078Z","end":"2026-08-24T15:23:34.915363Z","steps":["trace[1541597722] 'process raft request' (duration: 350.180683ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:34.915803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:34.565054Z","time spent":"350.396816ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:24:05.262731Z","caller":"traceutil/trace.go:171","msg":"trace[1611713081] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"117.445541ms","start":"2026-08-24T15:24:05.145175Z","end":"2026-08-24T15:24:05.26262Z","steps":["trace[1611713081] 'process raft request' (duration: 117.26039ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:07.343217Z","caller":"traceutil/trace.go:171","msg":"trace[2041332804] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"201.431543ms","start":"2026-08-24T15:24:07.141758Z","end":"2026-08-24T15:24:07.343189Z","steps":["trace[2041332804] 'process raft request' (duration: 201.119016ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:07.522853Z","caller":"traceutil/trace.go:171","msg":"trace[266456470] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"102.64353ms","start":"2026-08-24T15:24:07.42018Z","end":"2026-08-24T15:24:07.522824Z","steps":["trace[266456470] 'process raft request' (duration: 35.70102ms)","trace[266456470] 'compare' (duration: 66.802662ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:11.683714Z","caller":"traceutil/trace.go:171","msg":"trace[607451] linearizableReadLoop","detail":"{readStateIndex:1188; appliedIndex:1186; }","duration":"116.869603ms","start":"2026-08-24T15:24:11.566825Z","end":"2026-08-24T15:24:11.683694Z","steps":["trace[607451] 'read index received' (duration: 33.404466ms)","trace[607451] 'applied index is now lower than readState.Index' (duration: 83.464457ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:11.683819Z","caller":"traceutil/trace.go:171","msg":"trace[1707426510] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"133.936722ms","start":"2026-08-24T15:24:11.549844Z","end":"2026-08-24T15:24:11.68378Z","steps":["trace[1707426510] 'process raft request' (duration: 112.063413ms)","trace[1707426510] 'compare' (duration: 21.693909ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:11.683881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.043233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T15:24:11.683906Z","caller":"traceutil/trace.go:171","msg":"trace[1097702910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1142; }","duration":"117.129968ms","start":"2026-08-24T15:24:11.566767Z","end":"2026-08-24T15:24:11.683897Z","steps":["trace[1097702910] 'agreement among raft nodes before linearized reading' (duration: 117.000201ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:12.020615Z","caller":"traceutil/trace.go:171","msg":"trace[41391210] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"237.150728ms","start":"2026-08-24T15:24:11.783439Z","end":"2026-08-24T15:24:12.020589Z","steps":["trace[41391210] 'process raft request' (duration: 236.940787ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:12.060305Z","caller":"traceutil/trace.go:171","msg":"trace[1187165695] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"273.801309ms","start":"2026-08-24T15:24:11.786408Z","end":"2026-08-24T15:24:12.060209Z","steps":["trace[1187165695] 'process raft request' (duration: 273.549105ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:13.849976Z","caller":"traceutil/trace.go:171","msg":"trace[927025045] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1196; }","duration":"139.735067ms","start":"2026-08-24T15:24:13.71018Z","end":"2026-08-24T15:24:13.849915Z","steps":["trace[927025045] 'read index received' (duration: 64.987494ms)","trace[927025045] 'applied index is now lower than readState.Index' (duration: 74.746383ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:13.850263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.068446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T15:24:13.850336Z","caller":"traceutil/trace.go:171","msg":"trace[205554519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1150; }","duration":"140.193873ms","start":"2026-08-24T15:24:13.710131Z","end":"2026-08-24T15:24:13.850325Z","steps":["trace[205554519] 'agreement among raft nodes before linearized reading' (duration: 139.938929ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:13.85081Z","caller":"traceutil/trace.go:171","msg":"trace[1025911624] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"158.020946ms","start":"2026-08-24T15:24:13.692774Z","end":"2026-08-24T15:24:13.850795Z","steps":["trace[1025911624] 'process raft request' (duration: 82.463827ms)","trace[1025911624] 'compare' (duration: 74.526041ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:16.065979Z","caller":"traceutil/trace.go:171","msg":"trace[1414592713] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1202; }","duration":"167.813379ms","start":"2026-08-24T15:24:15.898147Z","end":"2026-08-24T15:24:16.06596Z","steps":["trace[1414592713] 'read index received' (duration: 167.622699ms)","trace[1414592713] 'applied index is now lower than readState.Index' (duration: 189.83µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:16.066059Z","caller":"traceutil/trace.go:171","msg":"trace[1824025648] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"206.926001ms","start":"2026-08-24T15:24:15.859098Z","end":"2026-08-24T15:24:16.066024Z","steps":["trace[1824025648] 'process raft request' (duration: 206.73817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:16.066209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.036941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T15:24:16.066295Z","caller":"traceutil/trace.go:171","msg":"trace[215778524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1156; }","duration":"168.172619ms","start":"2026-08-24T15:24:15.898109Z","end":"2026-08-24T15:24:16.066281Z","steps":["trace[215778524] 'agreement among raft nodes before linearized reading' (duration: 167.944347ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:16.247386Z","caller":"traceutil/trace.go:171","msg":"trace[1023881183] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"114.613855ms","start":"2026-08-24T15:24:16.132743Z","end":"2026-08-24T15:24:16.247357Z","steps":["trace[1023881183] 'process raft request' (duration: 94.26039ms)","trace[1023881183] 'compare' (duration: 20.244749ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:17.833011Z","caller":"traceutil/trace.go:171","msg":"trace[1767146491] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1208; }","duration":"104.215434ms","start":"2026-08-24T15:24:17.728775Z","end":"2026-08-24T15:24:17.83299Z","steps":["trace[1767146491] 'read index received' (duration: 85.642907ms)","trace[1767146491] 'applied index is now lower than readState.Index' (duration: 18.571717ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:17.833076Z","caller":"traceutil/trace.go:171","msg":"trace[1444873091] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"139.936166ms","start":"2026-08-24T15:24:17.693111Z","end":"2026-08-24T15:24:17.833047Z","steps":["trace[1444873091] 'process raft request' (duration: 121.304696ms)","trace[1444873091] 'compare' (duration: 18.471401ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:17.83321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.419975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T15:24:17.833254Z","caller":"traceutil/trace.go:171","msg":"trace[686328661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1161; }","duration":"104.50441ms","start":"2026-08-24T15:24:17.728739Z","end":"2026-08-24T15:24:17.833243Z","steps":["trace[686328661] 'agreement among raft nodes before linearized reading' (duration: 104.32906ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:20.119407Z","caller":"traceutil/trace.go:171","msg":"trace[836427453] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1214; }","duration":"233.146988ms","start":"2026-08-24T15:24:19.886235Z","end":"2026-08-24T15:24:20.119382Z","steps":["trace[836427453] 'read index received' (duration: 232.8374ms)","trace[836427453] 'applied index is now lower than readState.Index' (duration: 308.658µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:20.119445Z","caller":"traceutil/trace.go:171","msg":"trace[346145640] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"280.318459ms","start":"2026-08-24T15:24:19.839104Z","end":"2026-08-24T15:24:20.119422Z","steps":["trace[346145640] 'process raft request' (duration: 280.121218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:20.119667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.413423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T15:24:20.11971Z","caller":"traceutil/trace.go:171","msg":"trace[1611998639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1167; }","duration":"233.515189ms","start":"2026-08-24T15:24:19.886184Z","end":"2026-08-24T15:24:20.1197Z","steps":["trace[1611998639] 'agreement among raft nodes before linearized reading' (duration: 233.331758ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:20.298879Z","caller":"traceutil/trace.go:171","msg":"trace[79197064] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"104.463897ms","start":"2026-08-24T15:24:20.194389Z","end":"2026-08-24T15:24:20.298853Z","steps":["trace[79197064] 'process raft request' (duration: 63.694786ms)","trace[79197064] 'compare' (duration: 40.437923ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:22.187747Z","caller":"traceutil/trace.go:171","msg":"trace[1263891736] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"229.782925ms","start":"2026-08-24T15:24:21.957938Z","end":"2026-08-24T15:24:22.187721Z","steps":["trace[1263891736] 'process raft request' (duration: 197.803137ms)","trace[1263891736] 'compare' (duration: 31.817188ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:22.251945Z","caller":"traceutil/trace.go:171","msg":"trace[557998984] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"127.844184ms","start":"2026-08-24T15:24:22.124068Z","end":"2026-08-24T15:24:22.251912Z","steps":["trace[557998984] 'process raft request' (duration: 127.565248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:22.444278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.233239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:24:22.444449Z","caller":"traceutil/trace.go:171","msg":"trace[912427914] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1223; }","duration":"143.308188ms","start":"2026-08-24T15:24:22.301125Z","end":"2026-08-24T15:24:22.444433Z","steps":["trace[912427914] 'read index received' (duration: 23.680058ms)","trace[912427914] 'applied index is now lower than readState.Index' (duration: 119.62726ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:22.444658Z","caller":"traceutil/trace.go:171","msg":"trace[486873602] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"188.998161ms","start":"2026-08-24T15:24:22.255627Z","end":"2026-08-24T15:24:22.444625Z","steps":["trace[486873602] 'process raft request' (duration: 69.107586ms)","trace[486873602] 'compare' (duration: 119.112581ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:22.444752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.602046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:24:22.444793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.373367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T15:24:22.444815Z","caller":"traceutil/trace.go:171","msg":"trace[869331377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1176; }","duration":"143.719922ms","start":"2026-08-24T15:24:22.301078Z","end":"2026-08-24T15:24:22.444798Z","steps":["trace[869331377] 'agreement among raft nodes before linearized reading' (duration: 143.604715ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:22.44483Z","caller":"traceutil/trace.go:171","msg":"trace[685051819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1176; }","duration":"137.224635ms","start":"2026-08-24T15:24:22.307595Z","end":"2026-08-24T15:24:22.444819Z","steps":["trace[685051819] 'agreement among raft nodes before linearized reading' (duration: 137.101928ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:22.444971Z","caller":"traceutil/trace.go:171","msg":"trace[884506902] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"187.20825ms","start":"2026-08-24T15:24:22.25773Z","end":"2026-08-24T15:24:22.444939Z","steps":["trace[884506902] 'process raft request' (duration: 186.650078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:22.445092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.385518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-24T15:24:22.445119Z","caller":"traceutil/trace.go:171","msg":"trace[646956545] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1176; }","duration":"110.450312ms","start":"2026-08-24T15:24:22.334658Z","end":"2026-08-24T15:24:22.445108Z","steps":["trace[646956545] 'agreement among raft nodes before linearized reading' (duration: 110.337066ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:22.897077Z","caller":"traceutil/trace.go:171","msg":"trace[749371201] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"164.095294ms","start":"2026-08-24T15:24:22.732952Z","end":"2026-08-24T15:24:22.897047Z","steps":["trace[749371201] 'process raft request' (duration: 163.939335ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:24.786047Z","caller":"traceutil/trace.go:171","msg":"trace[1858806971] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"216.795587ms","start":"2026-08-24T15:24:24.569225Z","end":"2026-08-24T15:24:24.786021Z","steps":["trace[1858806971] 'process raft request' (duration: 182.020484ms)","trace[1858806971] 'compare' (duration: 34.487998ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:26.041821Z","caller":"traceutil/trace.go:171","msg":"trace[1929976409] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"150.193215ms","start":"2026-08-24T15:24:25.891605Z","end":"2026-08-24T15:24:26.041798Z","steps":["trace[1929976409] 'process raft request' (duration: 150.062844ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:26.919597Z","caller":"traceutil/trace.go:171","msg":"trace[1645877848] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1238; }","duration":"129.434779ms","start":"2026-08-24T15:24:26.790132Z","end":"2026-08-24T15:24:26.919566Z","steps":["trace[1645877848] 'read index received' (duration: 52.38265ms)","trace[1645877848] 'applied index is now lower than readState.Index' (duration: 77.050939ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:26.919632Z","caller":"traceutil/trace.go:171","msg":"trace[1590882363] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"346.756633ms","start":"2026-08-24T15:24:26.572854Z","end":"2026-08-24T15:24:26.91961Z","steps":["trace[1590882363] 'process raft request' (duration: 269.621743ms)","trace[1590882363] 'compare' (duration: 76.759579ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:26.919714Z","caller":"traceutil/trace.go:171","msg":"trace[293138517] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"339.649899ms","start":"2026-08-24T15:24:26.580046Z","end":"2026-08-24T15:24:26.919696Z","steps":["trace[293138517] 'process raft request' (duration: 339.418479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:26.919821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:26.580033Z","time spent":"339.7107ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:26.919837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:26.572828Z","time spent":"346.889274ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:26.919873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.71815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-24T15:24:26.919948Z","caller":"traceutil/trace.go:171","msg":"trace[1564059167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1191; }","duration":"129.84024ms","start":"2026-08-24T15:24:26.790091Z","end":"2026-08-24T15:24:26.919931Z","steps":["trace[1564059167] 'agreement among raft nodes before linearized reading' (duration: 129.65243ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:28.790791Z","caller":"traceutil/trace.go:171","msg":"trace[1116847908] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"167.7997ms","start":"2026-08-24T15:24:28.622964Z","end":"2026-08-24T15:24:28.790763Z","steps":["trace[1116847908] 'process raft request' (duration: 167.60998ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:31.179031Z","caller":"traceutil/trace.go:171","msg":"trace[1923500129] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"200.219947ms","start":"2026-08-24T15:24:30.978792Z","end":"2026-08-24T15:24:31.179012Z","steps":["trace[1923500129] 'read index received' (duration: 200.015766ms)","trace[1923500129] 'applied index is now lower than readState.Index' (duration: 203.081µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:31.179156Z","caller":"traceutil/trace.go:171","msg":"trace[1738268116] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"381.183766ms","start":"2026-08-24T15:24:30.797941Z","end":"2026-08-24T15:24:31.179125Z","steps":["trace[1738268116] 'process raft request' (duration: 380.940975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:31.179229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.424458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T15:24:31.179251Z","caller":"traceutil/trace.go:171","msg":"trace[1987157634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1202; }","duration":"200.501478ms","start":"2026-08-24T15:24:30.978743Z","end":"2026-08-24T15:24:31.179245Z","steps":["trace[1987157634] 'agreement among raft nodes before linearized reading' (duration: 200.364477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:31.179312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:30.797931Z","time spent":"381.292747ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:31.179343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.527278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-24T15:24:31.179407Z","caller":"traceutil/trace.go:171","msg":"trace[738284792] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1202; }","duration":"167.643369ms","start":"2026-08-24T15:24:31.011751Z","end":"2026-08-24T15:24:31.179394Z","steps":["trace[738284792] 'agreement among raft nodes before linearized reading' (duration: 167.429828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:31.5169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.149117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:24:31.517137Z","caller":"traceutil/trace.go:171","msg":"trace[1332354300] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1252; }","duration":"213.386292ms","start":"2026-08-24T15:24:31.303733Z","end":"2026-08-24T15:24:31.517119Z","steps":["trace[1332354300] 'read index received' (duration: 79.516907ms)","trace[1332354300] 'applied index is now lower than readState.Index' (duration: 133.868295ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:31.517216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.484173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:31.517243Z","caller":"traceutil/trace.go:171","msg":"trace[1415999264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1204; }","duration":"213.541983ms","start":"2026-08-24T15:24:31.30369Z","end":"2026-08-24T15:24:31.517231Z","steps":["trace[1415999264] 'agreement among raft nodes before linearized reading' (duration: 213.493703ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:31.517319Z","caller":"traceutil/trace.go:171","msg":"trace[1531439755] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"333.89914ms","start":"2026-08-24T15:24:31.183394Z","end":"2026-08-24T15:24:31.517293Z","steps":["trace[1531439755] 'process raft request' (duration: 333.622898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:31.517448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:31.183373Z","time spent":"333.99131ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:24:31.517114Z","caller":"traceutil/trace.go:171","msg":"trace[653979434] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"334.094041ms","start":"2026-08-24T15:24:31.182967Z","end":"2026-08-24T15:24:31.517061Z","steps":["trace[653979434] 'process raft request' (duration: 70.637772ms)","trace[653979434] 'compare' (duration: 262.690705ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:31.518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:31.182945Z","time spent":"335.011705ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:31.871094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.314335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-24T15:24:31.87121Z","caller":"traceutil/trace.go:171","msg":"trace[198028232] range","detail":"{range_begin:/registry/masterleases/199.19.213.138; range_end:; response_count:1; response_revision:1204; }","duration":"112.473456ms","start":"2026-08-24T15:24:31.758712Z","end":"2026-08-24T15:24:31.871186Z","steps":["trace[198028232] 'range keys from in-memory index tree' (duration: 112.101744ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:32.974798Z","caller":"traceutil/trace.go:171","msg":"trace[840104269] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"300.688091ms","start":"2026-08-24T15:24:32.674069Z","end":"2026-08-24T15:24:32.974757Z","steps":["trace[840104269] 'process raft request' (duration: 300.53533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:32.974955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:32.674051Z","time spent":"300.822221ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:24:33.386936Z","caller":"traceutil/trace.go:171","msg":"trace[1475837491] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"176.471283ms","start":"2026-08-24T15:24:33.210434Z","end":"2026-08-24T15:24:33.386905Z","steps":["trace[1475837491] 'process raft request' (duration: 132.325995ms)","trace[1475837491] 'compare' (duration: 43.994827ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:33.722703Z","caller":"traceutil/trace.go:171","msg":"trace[2039502650] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"196.236468ms","start":"2026-08-24T15:24:33.526422Z","end":"2026-08-24T15:24:33.722659Z","steps":["trace[2039502650] 'process raft request' (duration: 127.563146ms)","trace[2039502650] 'compare' (duration: 68.442471ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:34.755111Z","caller":"traceutil/trace.go:171","msg":"trace[2109631688] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"230.672554ms","start":"2026-08-24T15:24:34.524414Z","end":"2026-08-24T15:24:34.755087Z","steps":["trace[2109631688] 'process raft request' (duration: 230.546283ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:35.120993Z","caller":"traceutil/trace.go:171","msg":"trace[1013268362] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"138.970647ms","start":"2026-08-24T15:24:34.981997Z","end":"2026-08-24T15:24:35.120968Z","steps":["trace[1013268362] 'process raft request' (duration: 138.837136ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:36.642327Z","caller":"traceutil/trace.go:171","msg":"trace[277928665] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"379.828683ms","start":"2026-08-24T15:24:36.262472Z","end":"2026-08-24T15:24:36.6423Z","steps":["trace[277928665] 'process raft request' (duration: 379.677192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:36.642476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:36.262449Z","time spent":"379.947584ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:37.029202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.200568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:37.029302Z","caller":"traceutil/trace.go:171","msg":"trace[2134810908] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1219; }","duration":"343.34401ms","start":"2026-08-24T15:24:36.68593Z","end":"2026-08-24T15:24:37.029274Z","steps":["trace[2134810908] 'count revisions from in-memory index tree' (duration: 343.126668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:37.029356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:36.685912Z","time spent":"343.43072ms","remote":"127.0.0.1:60120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:24:37.029437Z","caller":"traceutil/trace.go:171","msg":"trace[1585389880] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"231.734416ms","start":"2026-08-24T15:24:36.797664Z","end":"2026-08-24T15:24:37.029399Z","steps":["trace[1585389880] 'read index received' (duration: 197.563269ms)","trace[1585389880] 'applied index is now lower than readState.Index' (duration: 34.169587ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:37.029643Z","caller":"traceutil/trace.go:171","msg":"trace[424447758] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"268.332149ms","start":"2026-08-24T15:24:36.761294Z","end":"2026-08-24T15:24:37.029626Z","steps":["trace[424447758] 'process raft request' (duration: 233.922481ms)","trace[424447758] 'compare' (duration: 34.024326ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:37.030108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.437161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"info","ts":"2026-08-24T15:24:37.030195Z","caller":"traceutil/trace.go:171","msg":"trace[21210117] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1220; }","duration":"232.572932ms","start":"2026-08-24T15:24:36.797601Z","end":"2026-08-24T15:24:37.030174Z","steps":["trace[21210117] 'agreement among raft nodes before linearized reading' (duration: 232.20332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:37.343017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.575341ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T15:24:37.343228Z","caller":"traceutil/trace.go:171","msg":"trace[1604155272] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1271; }","duration":"217.874187ms","start":"2026-08-24T15:24:37.12533Z","end":"2026-08-24T15:24:37.343204Z","steps":["trace[1604155272] 'read index received' (duration: 76.211295ms)","trace[1604155272] 'applied index is now lower than readState.Index' (duration: 141.660502ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:37.34356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.203449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T15:24:37.343652Z","caller":"traceutil/trace.go:171","msg":"trace[2025634195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1221; }","duration":"218.354401ms","start":"2026-08-24T15:24:37.125278Z","end":"2026-08-24T15:24:37.343632Z","steps":["trace[2025634195] 'agreement among raft nodes before linearized reading' (duration: 218.094539ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:38.147095Z","caller":"traceutil/trace.go:171","msg":"trace[205971160] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"197.065817ms","start":"2026-08-24T15:24:37.949985Z","end":"2026-08-24T15:24:38.147051Z","steps":["trace[205971160] 'process raft request' (duration: 196.850125ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:39.302774Z","caller":"traceutil/trace.go:171","msg":"trace[923614570] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"267.97548ms","start":"2026-08-24T15:24:39.034769Z","end":"2026-08-24T15:24:39.302744Z","steps":["trace[923614570] 'process raft request' (duration: 267.798078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:39.524343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.638327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T15:24:39.52442Z","caller":"traceutil/trace.go:171","msg":"trace[23314356] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1227; }","duration":"133.832678ms","start":"2026-08-24T15:24:39.390569Z","end":"2026-08-24T15:24:39.524402Z","steps":["trace[23314356] 'range keys from in-memory index tree' (duration: 133.280134ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:40.320164Z","caller":"traceutil/trace.go:171","msg":"trace[877776547] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"165.083975ms","start":"2026-08-24T15:24:40.155056Z","end":"2026-08-24T15:24:40.32014Z","steps":["trace[877776547] 'process raft request' (duration: 164.922304ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:42.085667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.447029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:24:42.085728Z","caller":"traceutil/trace.go:171","msg":"trace[761323856] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"326.391789ms","start":"2026-08-24T15:24:41.759325Z","end":"2026-08-24T15:24:42.085717Z","steps":["trace[761323856] 'read index received' (duration: 60.24µs)","trace[761323856] 'applied index is now lower than readState.Index' (duration: 326.330959ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:42.08584Z","caller":"traceutil/trace.go:171","msg":"trace[1719539668] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"439.121817ms","start":"2026-08-24T15:24:41.646712Z","end":"2026-08-24T15:24:42.085834Z","steps":["trace[1719539668] 'process raft request' (duration: 96.443056ms)","trace[1719539668] 'compare' (duration: 342.376518ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:42.08587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:41.646685Z","time spent":"439.169327ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:42.086013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.698652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-24T15:24:42.086027Z","caller":"traceutil/trace.go:171","msg":"trace[430824184] range","detail":"{range_begin:/registry/masterleases/199.19.213.138; range_end:; response_count:1; response_revision:1235; }","duration":"326.757693ms","start":"2026-08-24T15:24:41.759265Z","end":"2026-08-24T15:24:42.086022Z","steps":["trace[430824184] 'agreement among raft nodes before linearized reading' (duration: 326.694792ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:42.08604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:41.759242Z","time spent":"326.795233ms","remote":"127.0.0.1:42266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.138\" "} {"level":"warn","ts":"2026-08-24T15:24:42.08618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.639082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T15:24:42.086259Z","caller":"traceutil/trace.go:171","msg":"trace[1572352909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1235; }","duration":"288.800824ms","start":"2026-08-24T15:24:41.797444Z","end":"2026-08-24T15:24:42.086245Z","steps":["trace[1572352909] 'agreement among raft nodes before linearized reading' (duration: 288.576422ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:42.476644Z","caller":"traceutil/trace.go:171","msg":"trace[1190794734] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1287; }","duration":"310.461698ms","start":"2026-08-24T15:24:42.166162Z","end":"2026-08-24T15:24:42.476624Z","steps":["trace[1190794734] 'read index received' (duration: 2.935426ms)","trace[1190794734] 'applied index is now lower than readState.Index' (duration: 307.523312ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:42.476865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.69611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5548"} {"level":"info","ts":"2026-08-24T15:24:42.47693Z","caller":"traceutil/trace.go:171","msg":"trace[181326438] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1236; }","duration":"310.77379ms","start":"2026-08-24T15:24:42.166142Z","end":"2026-08-24T15:24:42.476915Z","steps":["trace[181326438] 'agreement among raft nodes before linearized reading' (duration: 310.581539ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:42.47682Z","caller":"traceutil/trace.go:171","msg":"trace[680705519] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"386.382042ms","start":"2026-08-24T15:24:42.090357Z","end":"2026-08-24T15:24:42.476739Z","steps":["trace[680705519] 'process raft request' (duration: 386.090019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:42.476976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:42.166079Z","time spent":"310.886862ms","remote":"127.0.0.1:42390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5571,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-24T15:24:42.477189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:42.090337Z","time spent":"386.726235ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:24:42.577559Z","caller":"traceutil/trace.go:171","msg":"trace[1838220817] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"100.827989ms","start":"2026-08-24T15:24:42.4767Z","end":"2026-08-24T15:24:42.577528Z","steps":["trace[1838220817] 'read index received' (duration: 80.723868ms)","trace[1838220817] 'applied index is now lower than readState.Index' (duration: 20.103041ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:42.577636Z","caller":"traceutil/trace.go:171","msg":"trace[636334538] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"407.29229ms","start":"2026-08-24T15:24:42.170257Z","end":"2026-08-24T15:24:42.57755Z","steps":["trace[636334538] 'process raft request' (duration: 387.109068ms)","trace[636334538] 'compare' (duration: 20.035251ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:42.577894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.844537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:42.577946Z","caller":"traceutil/trace.go:171","msg":"trace[1836853126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"274.939798ms","start":"2026-08-24T15:24:42.302994Z","end":"2026-08-24T15:24:42.577934Z","steps":["trace[1836853126] 'agreement among raft nodes before linearized reading' (duration: 274.662425ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:42.577942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:42.170229Z","time spent":"407.456662ms","remote":"127.0.0.1:42266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:42.578039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.717696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-24T15:24:42.578151Z","caller":"traceutil/trace.go:171","msg":"trace[1966066327] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1237; }","duration":"254.972429ms","start":"2026-08-24T15:24:42.323158Z","end":"2026-08-24T15:24:42.57813Z","steps":["trace[1966066327] 'agreement among raft nodes before linearized reading' (duration: 254.446484ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:43.872971Z","caller":"traceutil/trace.go:171","msg":"trace[2012216007] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"114.516221ms","start":"2026-08-24T15:24:43.758428Z","end":"2026-08-24T15:24:43.872944Z","steps":["trace[2012216007] 'process raft request' (duration: 114.277599ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:45.830557Z","caller":"traceutil/trace.go:171","msg":"trace[1343059223] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"133.741289ms","start":"2026-08-24T15:24:45.696791Z","end":"2026-08-24T15:24:45.830533Z","steps":["trace[1343059223] 'process raft request' (duration: 133.563067ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:47.932302Z","caller":"traceutil/trace.go:171","msg":"trace[193774395] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"198.441878ms","start":"2026-08-24T15:24:47.733824Z","end":"2026-08-24T15:24:47.932266Z","steps":["trace[193774395] 'process raft request' (duration: 198.215496ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:49.616143Z","caller":"traceutil/trace.go:171","msg":"trace[613614554] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"146.450987ms","start":"2026-08-24T15:24:49.469667Z","end":"2026-08-24T15:24:49.616118Z","steps":["trace[613614554] 'process raft request' (duration: 146.307966ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:51.061546Z","caller":"traceutil/trace.go:171","msg":"trace[55894041] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"206.476273ms","start":"2026-08-24T15:24:50.855049Z","end":"2026-08-24T15:24:51.061525Z","steps":["trace[55894041] 'process raft request' (duration: 203.544038ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:51.063125Z","caller":"traceutil/trace.go:171","msg":"trace[2118481151] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"205.004916ms","start":"2026-08-24T15:24:50.858109Z","end":"2026-08-24T15:24:51.063114Z","steps":["trace[2118481151] 'process raft request' (duration: 204.850884ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:51.677438Z","caller":"traceutil/trace.go:171","msg":"trace[7071148] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"220.423145ms","start":"2026-08-24T15:24:51.456989Z","end":"2026-08-24T15:24:51.677412Z","steps":["trace[7071148] 'read index received' (duration: 146.38584ms)","trace[7071148] 'applied index is now lower than readState.Index' (duration: 74.036335ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:51.677462Z","caller":"traceutil/trace.go:171","msg":"trace[53483731] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"250.97127ms","start":"2026-08-24T15:24:51.426457Z","end":"2026-08-24T15:24:51.677428Z","steps":["trace[53483731] 'process raft request' (duration: 176.979046ms)","trace[53483731] 'compare' (duration: 73.868993ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:51.677625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.617207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:24:51.677668Z","caller":"traceutil/trace.go:171","msg":"trace[1876368991] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1269; }","duration":"220.718339ms","start":"2026-08-24T15:24:51.456938Z","end":"2026-08-24T15:24:51.677656Z","steps":["trace[1876368991] 'agreement among raft nodes before linearized reading' (duration: 220.598048ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:51.996635Z","caller":"traceutil/trace.go:171","msg":"trace[2077315000] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"236.729631ms","start":"2026-08-24T15:24:51.759879Z","end":"2026-08-24T15:24:51.996609Z","steps":["trace[2077315000] 'read index received' (duration: 216.682441ms)","trace[2077315000] 'applied index is now lower than readState.Index' (duration: 20.04615ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:51.996681Z","caller":"traceutil/trace.go:171","msg":"trace[861208805] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"314.560271ms","start":"2026-08-24T15:24:51.682089Z","end":"2026-08-24T15:24:51.996649Z","steps":["trace[861208805] 'process raft request' (duration: 294.43655ms)","trace[861208805] 'compare' (duration: 19.746897ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:51.996817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:51.682065Z","time spent":"314.679542ms","remote":"127.0.0.1:42376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1026,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:51.996827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.928453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-24T15:24:51.996867Z","caller":"traceutil/trace.go:171","msg":"trace[1588803898] range","detail":"{range_begin:/registry/masterleases/199.19.213.138; range_end:; response_count:1; response_revision:1271; }","duration":"237.025214ms","start":"2026-08-24T15:24:51.759831Z","end":"2026-08-24T15:24:51.996856Z","steps":["trace[1588803898] 'agreement among raft nodes before linearized reading' (duration: 236.880692ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:52.206938Z","caller":"traceutil/trace.go:171","msg":"trace[1311438344] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"112.268517ms","start":"2026-08-24T15:24:52.094645Z","end":"2026-08-24T15:24:52.206914Z","steps":["trace[1311438344] 'read index received' (duration: 112.130916ms)","trace[1311438344] 'applied index is now lower than readState.Index' (duration: 136.201µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:52.206989Z","caller":"traceutil/trace.go:171","msg":"trace[982286451] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"151.528099ms","start":"2026-08-24T15:24:52.055416Z","end":"2026-08-24T15:24:52.206944Z","steps":["trace[982286451] 'process raft request' (duration: 151.337496ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:52.207068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.823451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:52.207199Z","caller":"traceutil/trace.go:171","msg":"trace[1253410038] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:1274; }","duration":"150.985603ms","start":"2026-08-24T15:24:52.05619Z","end":"2026-08-24T15:24:52.207175Z","steps":["trace[1253410038] 'agreement among raft nodes before linearized reading' (duration: 150.75633ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:52.207565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.534343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/test-pvc.18cec72b8e55f0fb\" ","response":"range_response_count:1 size:916"} {"level":"info","ts":"2026-08-24T15:24:52.207639Z","caller":"traceutil/trace.go:171","msg":"trace[1656512131] range","detail":"{range_begin:/registry/events/default/test-pvc.18cec72b8e55f0fb; range_end:; response_count:1; response_revision:1274; }","duration":"157.610014ms","start":"2026-08-24T15:24:52.049997Z","end":"2026-08-24T15:24:52.207607Z","steps":["trace[1656512131] 'agreement among raft nodes before linearized reading' (duration: 157.047707ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:52.227973Z","caller":"traceutil/trace.go:171","msg":"trace[196829981] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"129.55031ms","start":"2026-08-24T15:24:52.098398Z","end":"2026-08-24T15:24:52.227948Z","steps":["trace[196829981] 'process raft request' (duration: 129.429818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:52.231937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.467402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-24T15:24:52.232059Z","caller":"traceutil/trace.go:171","msg":"trace[1466341487] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1275; }","duration":"135.646335ms","start":"2026-08-24T15:24:52.096399Z","end":"2026-08-24T15:24:52.232045Z","steps":["trace[1466341487] 'agreement among raft nodes before linearized reading' (duration: 135.470733ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:52.491858Z","caller":"traceutil/trace.go:171","msg":"trace[2072602564] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"188.366191ms","start":"2026-08-24T15:24:52.303466Z","end":"2026-08-24T15:24:52.491832Z","steps":["trace[2072602564] 'read index received' (duration: 138.764033ms)","trace[2072602564] 'applied index is now lower than readState.Index' (duration: 49.600848ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:52.491935Z","caller":"traceutil/trace.go:171","msg":"trace[735154131] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"218.251928ms","start":"2026-08-24T15:24:52.273645Z","end":"2026-08-24T15:24:52.491897Z","steps":["trace[735154131] 'process raft request' (duration: 168.611419ms)","trace[735154131] 'compare' (duration: 49.446477ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:52.492041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.553224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:52.49208Z","caller":"traceutil/trace.go:171","msg":"trace[1108704049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1277; }","duration":"188.641944ms","start":"2026-08-24T15:24:52.303425Z","end":"2026-08-24T15:24:52.492067Z","steps":["trace[1108704049] 'agreement among raft nodes before linearized reading' (duration: 188.536433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:52.492116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.837185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-24T15:24:52.492182Z","caller":"traceutil/trace.go:171","msg":"trace[315846056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1277; }","duration":"112.939685ms","start":"2026-08-24T15:24:52.379225Z","end":"2026-08-24T15:24:52.492165Z","steps":["trace[315846056] 'agreement among raft nodes before linearized reading' (duration: 112.764413ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:52.694684Z","caller":"traceutil/trace.go:171","msg":"trace[1877665868] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"196.793225ms","start":"2026-08-24T15:24:52.497864Z","end":"2026-08-24T15:24:52.694657Z","steps":["trace[1877665868] 'read index received' (duration: 126.695675ms)","trace[1877665868] 'applied index is now lower than readState.Index' (duration: 70.09663ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:52.694755Z","caller":"traceutil/trace.go:171","msg":"trace[1074196651] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"198.514016ms","start":"2026-08-24T15:24:52.496209Z","end":"2026-08-24T15:24:52.694723Z","steps":["trace[1074196651] 'process raft request' (duration: 128.344905ms)","trace[1074196651] 'compare' (duration: 69.986899ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:52.695035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.965499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bdd08326-bb61-42c5-bdf0-c65ebd961ca8\" ","response":"range_response_count:1 size:2513"} {"level":"info","ts":"2026-08-24T15:24:52.695125Z","caller":"traceutil/trace.go:171","msg":"trace[1490520485] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bdd08326-bb61-42c5-bdf0-c65ebd961ca8; range_end:; response_count:1; response_revision:1278; }","duration":"189.111331ms","start":"2026-08-24T15:24:52.505996Z","end":"2026-08-24T15:24:52.695108Z","steps":["trace[1490520485] 'agreement among raft nodes before linearized reading' (duration: 188.890568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:52.695533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.659226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bdd08326-bb61-42c5-bdf0-c65ebd961ca8\" ","response":"range_response_count:1 size:2513"} {"level":"info","ts":"2026-08-24T15:24:52.69565Z","caller":"traceutil/trace.go:171","msg":"trace[1677918986] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bdd08326-bb61-42c5-bdf0-c65ebd961ca8; range_end:; response_count:1; response_revision:1278; }","duration":"197.778477ms","start":"2026-08-24T15:24:52.497857Z","end":"2026-08-24T15:24:52.695636Z","steps":["trace[1677918986] 'agreement among raft nodes before linearized reading' (duration: 196.934287ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:53.00065Z","caller":"traceutil/trace.go:171","msg":"trace[984339574] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"268.525825ms","start":"2026-08-24T15:24:52.732101Z","end":"2026-08-24T15:24:53.000627Z","steps":["trace[984339574] 'read index received' (duration: 230.384087ms)","trace[984339574] 'applied index is now lower than readState.Index' (duration: 38.140478ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:53.000897Z","caller":"traceutil/trace.go:171","msg":"trace[137256392] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"299.063029ms","start":"2026-08-24T15:24:52.701606Z","end":"2026-08-24T15:24:53.000669Z","steps":["trace[137256392] 'process raft request' (duration: 260.82487ms)","trace[137256392] 'compare' (duration: 37.979576ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:53.001425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.313354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T15:24:53.001466Z","caller":"traceutil/trace.go:171","msg":"trace[467814826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1279; }","duration":"269.402426ms","start":"2026-08-24T15:24:52.732054Z","end":"2026-08-24T15:24:53.001456Z","steps":["trace[467814826] 'agreement among raft nodes before linearized reading' (duration: 268.702767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:53.001547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.168959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-08-24T15:24:53.00174Z","caller":"traceutil/trace.go:171","msg":"trace[1513763341] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:1279; }","duration":"232.440333ms","start":"2026-08-24T15:24:52.769231Z","end":"2026-08-24T15:24:53.001671Z","steps":["trace[1513763341] 'agreement among raft nodes before linearized reading' (duration: 231.622442ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:53.182556Z","caller":"traceutil/trace.go:171","msg":"trace[1838266625] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"100.670446ms","start":"2026-08-24T15:24:53.08186Z","end":"2026-08-24T15:24:53.182531Z","steps":["trace[1838266625] 'read index received' (duration: 22.725036ms)","trace[1838266625] 'applied index is now lower than readState.Index' (duration: 77.94448ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:53.182656Z","caller":"traceutil/trace.go:171","msg":"trace[1051402465] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"110.028754ms","start":"2026-08-24T15:24:53.072587Z","end":"2026-08-24T15:24:53.182616Z","steps":["trace[1051402465] 'process raft request' (duration: 32.069454ms)","trace[1051402465] 'compare' (duration: 77.716797ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:53.182989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.090762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T15:24:53.183038Z","caller":"traceutil/trace.go:171","msg":"trace[1284443351] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1281; }","duration":"101.224304ms","start":"2026-08-24T15:24:53.081802Z","end":"2026-08-24T15:24:53.183026Z","steps":["trace[1284443351] 'agreement among raft nodes before linearized reading' (duration: 100.823939ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:53.774252Z","caller":"traceutil/trace.go:171","msg":"trace[1869232843] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"202.517668ms","start":"2026-08-24T15:24:53.571694Z","end":"2026-08-24T15:24:53.774212Z","steps":["trace[1869232843] 'process raft request' (duration: 202.085863ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:53.910529Z","caller":"traceutil/trace.go:171","msg":"trace[1084775344] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"128.900572ms","start":"2026-08-24T15:24:53.781603Z","end":"2026-08-24T15:24:53.910504Z","steps":["trace[1084775344] 'process raft request' (duration: 128.742ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:55.188308Z","caller":"traceutil/trace.go:171","msg":"trace[1241149573] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"107.378428ms","start":"2026-08-24T15:24:55.080906Z","end":"2026-08-24T15:24:55.188285Z","steps":["trace[1241149573] 'read index received' (duration: 107.115684ms)","trace[1241149573] 'applied index is now lower than readState.Index' (duration: 261.784µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:55.188352Z","caller":"traceutil/trace.go:171","msg":"trace[286206508] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"142.313848ms","start":"2026-08-24T15:24:55.046014Z","end":"2026-08-24T15:24:55.188328Z","steps":["trace[286206508] 'process raft request' (duration: 142.107915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:55.188687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.722742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:55.188827Z","caller":"traceutil/trace.go:171","msg":"trace[716536330] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1290; }","duration":"107.956915ms","start":"2026-08-24T15:24:55.080858Z","end":"2026-08-24T15:24:55.188814Z","steps":["trace[716536330] 'agreement among raft nodes before linearized reading' (duration: 107.559219ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:55.349258Z","caller":"traceutil/trace.go:171","msg":"trace[489855910] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"115.17289ms","start":"2026-08-24T15:24:55.234043Z","end":"2026-08-24T15:24:55.349216Z","steps":["trace[489855910] 'process raft request' (duration: 80.753606ms)","trace[489855910] 'compare' (duration: 34.16502ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:57.354226Z","caller":"traceutil/trace.go:171","msg":"trace[503429919] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"118.321674ms","start":"2026-08-24T15:24:57.235884Z","end":"2026-08-24T15:24:57.354205Z","steps":["trace[503429919] 'read index received' (duration: 118.133492ms)","trace[503429919] 'applied index is now lower than readState.Index' (duration: 187.282µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:57.35427Z","caller":"traceutil/trace.go:171","msg":"trace[1448404525] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"156.530681ms","start":"2026-08-24T15:24:57.197718Z","end":"2026-08-24T15:24:57.354248Z","steps":["trace[1448404525] 'process raft request' (duration: 156.355168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:57.354482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.577897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-24T15:24:57.354548Z","caller":"traceutil/trace.go:171","msg":"trace[302711939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1296; }","duration":"118.693969ms","start":"2026-08-24T15:24:57.23584Z","end":"2026-08-24T15:24:57.354534Z","steps":["trace[302711939] 'agreement among raft nodes before linearized reading' (duration: 118.480366ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:59.050232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.866064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-24T15:24:59.050305Z","caller":"traceutil/trace.go:171","msg":"trace[755301430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1304; }","duration":"221.983756ms","start":"2026-08-24T15:24:58.828297Z","end":"2026-08-24T15:24:59.05028Z","steps":["trace[755301430] 'range keys from in-memory index tree' (duration: 221.679941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:59.050456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.853488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bdd08326-bb61-42c5-bdf0-c65ebd961ca8\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-08-24T15:24:59.05054Z","caller":"traceutil/trace.go:171","msg":"trace[1877283561] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bdd08326-bb61-42c5-bdf0-c65ebd961ca8; range_end:; response_count:1; response_revision:1304; }","duration":"217.978829ms","start":"2026-08-24T15:24:58.832542Z","end":"2026-08-24T15:24:59.050521Z","steps":["trace[1877283561] 'range keys from in-memory index tree' (duration: 217.685005ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:13.29575Z","caller":"traceutil/trace.go:171","msg":"trace[527627825] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"136.90384ms","start":"2026-08-24T15:25:13.158817Z","end":"2026-08-24T15:25:13.295721Z","steps":["trace[527627825] 'process raft request' (duration: 136.688926ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:25:13.926975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.452264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:25:13.92717Z","caller":"traceutil/trace.go:171","msg":"trace[1189988100] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1435; }","duration":"402.403518ms","start":"2026-08-24T15:25:13.524748Z","end":"2026-08-24T15:25:13.927152Z","steps":["trace[1189988100] 'read index received' (duration: 134.583117ms)","trace[1189988100] 'applied index is now lower than readState.Index' (duration: 267.818531ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:25:13.927343Z","caller":"traceutil/trace.go:171","msg":"trace[1736978323] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"406.935271ms","start":"2026-08-24T15:25:13.52034Z","end":"2026-08-24T15:25:13.927276Z","steps":["trace[1736978323] 'process raft request' (duration: 139.09637ms)","trace[1736978323] 'compare' (duration: 267.351142ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:25:13.927701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:25:13.520318Z","time spent":"407.299858ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:25:13.927961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.191143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-24T15:25:13.928156Z","caller":"traceutil/trace.go:171","msg":"trace[527173236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1375; }","duration":"403.325025ms","start":"2026-08-24T15:25:13.524716Z","end":"2026-08-24T15:25:13.928041Z","steps":["trace[527173236] 'agreement among raft nodes before linearized reading' (duration: 402.630012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:25:13.928334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:25:13.524705Z","time spent":"403.60936ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-24T15:25:14.428115Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241159620864260690,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T15:25:14.431073Z","caller":"traceutil/trace.go:171","msg":"trace[819607580] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"504.709388ms","start":"2026-08-24T15:25:13.926338Z","end":"2026-08-24T15:25:14.431047Z","steps":["trace[819607580] 'process raft request' (duration: 504.510374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:25:14.431263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:25:13.926302Z","time spent":"504.83847ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:25:14.636403Z","caller":"traceutil/trace.go:171","msg":"trace[254680983] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1436; }","duration":"709.156781ms","start":"2026-08-24T15:25:13.927225Z","end":"2026-08-24T15:25:14.636382Z","steps":["trace[254680983] 'read index received' (duration: 503.575617ms)","trace[254680983] 'applied index is now lower than readState.Index' (duration: 205.580284ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:25:14.636476Z","caller":"traceutil/trace.go:171","msg":"trace[470164679] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"213.291528ms","start":"2026-08-24T15:25:14.423178Z","end":"2026-08-24T15:25:14.63647Z","steps":["trace[470164679] 'process raft request' (duration: 213.112314ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:14.636418Z","caller":"traceutil/trace.go:171","msg":"trace[116748166] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"704.337242ms","start":"2026-08-24T15:25:13.932057Z","end":"2026-08-24T15:25:14.636394Z","steps":["trace[116748166] 'process raft request' (duration: 670.536133ms)","trace[116748166] 'compare' (duration: 33.343911ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:25:14.636611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:25:13.932026Z","time spent":"704.502346ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:25:14.636642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.773009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:25:14.636667Z","caller":"traceutil/trace.go:171","msg":"trace[675093304] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1378; }","duration":"475.853521ms","start":"2026-08-24T15:25:14.160807Z","end":"2026-08-24T15:25:14.63666Z","steps":["trace[675093304] 'agreement among raft nodes before linearized reading' (duration: 475.77995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:25:14.636698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:25:14.160783Z","time spent":"475.906982ms","remote":"127.0.0.1:42524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:25:14.636612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.240729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:25:14.636968Z","caller":"traceutil/trace.go:171","msg":"trace[275543766] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1378; }","duration":"897.654517ms","start":"2026-08-24T15:25:13.739282Z","end":"2026-08-24T15:25:14.636936Z","steps":["trace[275543766] 'agreement among raft nodes before linearized reading' (duration: 897.192879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:25:14.636888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.173747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:25:14.637217Z","caller":"traceutil/trace.go:171","msg":"trace[1254591487] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1378; }","duration":"447.524894ms","start":"2026-08-24T15:25:14.189667Z","end":"2026-08-24T15:25:14.637192Z","steps":["trace[1254591487] 'agreement among raft nodes before linearized reading' (duration: 447.141477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:25:14.637335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:25:14.189646Z","time spent":"447.665206ms","remote":"127.0.0.1:42538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":54,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:25:14.637101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:25:13.739258Z","time spent":"897.82316ms","remote":"127.0.0.1:42342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":6,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:25:15.202932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.661832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:25:15.203037Z","caller":"traceutil/trace.go:171","msg":"trace[345058060] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"560.678061ms","start":"2026-08-24T15:25:14.642339Z","end":"2026-08-24T15:25:15.203017Z","steps":["trace[345058060] 'process raft request' (duration: 335.848946ms)","trace[345058060] 'compare' (duration: 224.534309ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:25:15.2031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:25:14.642314Z","time spent":"560.755723ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}