{"level":"warn","ts":"2026-07-08T02:08:58.731892Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T02:08:58.73201Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177: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-07-08T02:08:58.732097Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T02:08:58.732115Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-08T02:08:58.732138Z","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-07-08T02:08:58.732949Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-07-08T02:08:58.733047Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177: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-07-08T02:08:58.758538Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"25.229955ms"} {"level":"info","ts":"2026-07-08T02:08:58.794596Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-07-08T02:08:58.794692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T02:08:58.794715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-07-08T02:08:58.794728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T02:08:58.794736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-07-08T02:08:58.794773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-07-08T02:08:58.853156Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T02:08:58.857762Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T02:08:58.859468Z","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-07-08T02:08:58.866818Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T02:08:58.866969Z","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-07-08T02:08:58.866975Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T02:08:58.86707Z","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-07-08T02:08:58.867088Z","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-07-08T02:08:58.876096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-07-08T02:08:58.876247Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-08T02:08:58.878001Z","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-07-08T02:08:58.878118Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-08T02:08:58.878157Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-08T02:08:58.878476Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T02:08:58.878518Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T02:08:59.502369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T02:08:59.502425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T02:08:59.502468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-07-08T02:08:59.502482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-07-08T02:08:59.502489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-08T02:08:59.502499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-07-08T02:08:59.502509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-08T02:08:59.503548Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:08:59.504602Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:08:59.504607Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T02:08:59.504639Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T02:08:59.504701Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:08:59.504735Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:08:59.504598Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T02:08:59.504945Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T02:08:59.504967Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T02:08:59.508243Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"info","ts":"2026-07-08T02:08:59.508781Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T02:09:04.389126Z","caller":"traceutil/trace.go:171","msg":"trace[1881939887] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"156.816063ms","start":"2026-07-08T02:09:04.232279Z","end":"2026-07-08T02:09:04.389095Z","steps":["trace[1881939887] 'process raft request' (duration: 147.759501ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:09:04.38928Z","caller":"traceutil/trace.go:171","msg":"trace[1219676031] linearizableReadLoop","detail":"{readStateIndex:232; appliedIndex:231; }","duration":"101.680369ms","start":"2026-07-08T02:09:04.287578Z","end":"2026-07-08T02:09:04.389258Z","steps":["trace[1219676031] 'read index received' (duration: 92.412966ms)","trace[1219676031] 'applied index is now lower than readState.Index' (duration: 9.266433ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:09:04.389419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.82333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:09:04.389462Z","caller":"traceutil/trace.go:171","msg":"trace[244286618] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:227; }","duration":"101.902031ms","start":"2026-07-08T02:09:04.287553Z","end":"2026-07-08T02:09:04.389455Z","steps":["trace[244286618] 'agreement among raft nodes before linearized reading' (duration: 101.8271ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:09:09.884477Z","caller":"traceutil/trace.go:171","msg":"trace[1835473995] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:315; }","duration":"187.505172ms","start":"2026-07-08T02:09:09.696947Z","end":"2026-07-08T02:09:09.884453Z","steps":["trace[1835473995] 'read index received' (duration: 187.29884ms)","trace[1835473995] 'applied index is now lower than readState.Index' (duration: 205.402µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:09:09.884534Z","caller":"traceutil/trace.go:171","msg":"trace[1691608383] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"230.870034ms","start":"2026-07-08T02:09:09.653626Z","end":"2026-07-08T02:09:09.884496Z","steps":["trace[1691608383] 'process raft request' (duration: 230.678543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:09.884674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.9921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-08T02:09:09.884724Z","caller":"traceutil/trace.go:171","msg":"trace[1690635779] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:303; }","duration":"138.104582ms","start":"2026-07-08T02:09:09.746604Z","end":"2026-07-08T02:09:09.884709Z","steps":["trace[1690635779] 'agreement among raft nodes before linearized reading' (duration: 137.983471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:09.88498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.291683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T02:09:09.885042Z","caller":"traceutil/trace.go:171","msg":"trace[1480207518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:303; }","duration":"138.376103ms","start":"2026-07-08T02:09:09.746647Z","end":"2026-07-08T02:09:09.885023Z","steps":["trace[1480207518] 'agreement among raft nodes before linearized reading' (duration: 138.228012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:09.885058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.090596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-08T02:09:09.885179Z","caller":"traceutil/trace.go:171","msg":"trace[910503951] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:303; }","duration":"188.238987ms","start":"2026-07-08T02:09:09.696921Z","end":"2026-07-08T02:09:09.88516Z","steps":["trace[910503951] 'agreement among raft nodes before linearized reading' (duration: 187.664423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:21.36327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186306256516201,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T02:09:21.863686Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186306256516201,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T02:09:22.021592Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.177036634s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T02:09:22.021918Z","caller":"traceutil/trace.go:171","msg":"trace[1606274876] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"1.177456534s","start":"2026-07-08T02:09:20.84444Z","end":"2026-07-08T02:09:22.021896Z","steps":["trace[1606274876] 'process raft request' (duration: 1.177354689s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:22.022354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:09:20.844418Z","time spent":"1.177555449s","remote":"127.0.0.1:41680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4361,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:09:22.024668Z","caller":"traceutil/trace.go:171","msg":"trace[2096723264] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:395; }","duration":"1.162220829s","start":"2026-07-08T02:09:20.86243Z","end":"2026-07-08T02:09:22.024651Z","steps":["trace[2096723264] 'read index received' (duration: 1.159347819s)","trace[2096723264] 'applied index is now lower than readState.Index' (duration: 2.87238ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:09:22.024953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097613801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-08T02:09:22.024957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162506623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T02:09:22.02506Z","caller":"traceutil/trace.go:171","msg":"trace[1571610700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:381; }","duration":"1.16265425s","start":"2026-07-08T02:09:20.862388Z","end":"2026-07-08T02:09:22.025042Z","steps":["trace[1571610700] 'agreement among raft nodes before linearized reading' (duration: 1.16244237s)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:09:22.025075Z","caller":"traceutil/trace.go:171","msg":"trace[28890253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:381; }","duration":"1.097748608s","start":"2026-07-08T02:09:20.927279Z","end":"2026-07-08T02:09:22.025028Z","steps":["trace[28890253] 'agreement among raft nodes before linearized reading' (duration: 1.09758649s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:22.025411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:09:20.927261Z","time spent":"1.098136967s","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-08T02:09:22.025093Z","caller":"traceutil/trace.go:171","msg":"trace[282814774] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"800.166605ms","start":"2026-07-08T02:09:21.224831Z","end":"2026-07-08T02:09:22.024998Z","steps":["trace[282814774] 'process raft request' (duration: 799.741124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:22.025617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:09:21.224811Z","time spent":"800.749054ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:09:22.02515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069889206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-08T02:09:22.025331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:09:20.86237Z","time spent":"1.162841069s","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T02:09:22.025767Z","caller":"traceutil/trace.go:171","msg":"trace[1324986155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:381; }","duration":"1.070533517s","start":"2026-07-08T02:09:20.955218Z","end":"2026-07-08T02:09:22.025752Z","steps":["trace[1324986155] 'agreement among raft nodes before linearized reading' (duration: 1.069898376s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:22.026075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:09:20.955201Z","time spent":"1.070847162s","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T02:09:37.704889Z","caller":"traceutil/trace.go:171","msg":"trace[334224585] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"142.663639ms","start":"2026-07-08T02:09:37.562195Z","end":"2026-07-08T02:09:37.704858Z","steps":["trace[334224585] 'read index received' (duration: 142.479061ms)","trace[334224585] 'applied index is now lower than readState.Index' (duration: 183.338µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:09:37.704952Z","caller":"traceutil/trace.go:171","msg":"trace[2061822193] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"155.022836ms","start":"2026-07-08T02:09:37.549901Z","end":"2026-07-08T02:09:37.704924Z","steps":["trace[2061822193] 'process raft request' (duration: 154.748825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:37.705072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.855956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:09:37.705122Z","caller":"traceutil/trace.go:171","msg":"trace[1493411833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"142.94504ms","start":"2026-07-08T02:09:37.562161Z","end":"2026-07-08T02:09:37.705106Z","steps":["trace[1493411833] 'agreement among raft nodes before linearized reading' (duration: 142.829435ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:09:37.735387Z","caller":"traceutil/trace.go:171","msg":"trace[2135829801] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"109.35092ms","start":"2026-07-08T02:09:37.626006Z","end":"2026-07-08T02:09:37.735357Z","steps":["trace[2135829801] 'process raft request' (duration: 109.020966ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:09:44.633613Z","caller":"traceutil/trace.go:171","msg":"trace[1241395134] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"206.242255ms","start":"2026-07-08T02:09:44.427349Z","end":"2026-07-08T02:09:44.633591Z","steps":["trace[1241395134] 'read index received' (duration: 205.976655ms)","trace[1241395134] 'applied index is now lower than readState.Index' (duration: 264.69µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:09:44.633753Z","caller":"traceutil/trace.go:171","msg":"trace[1840710525] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"423.301751ms","start":"2026-07-08T02:09:44.210413Z","end":"2026-07-08T02:09:44.633714Z","steps":["trace[1840710525] 'process raft request' (duration: 423.0054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:44.633915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.558217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-22cdf\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-08T02:09:44.633946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:09:44.210391Z","time spent":"423.459677ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:09:44.633984Z","caller":"traceutil/trace.go:171","msg":"trace[1602234687] range","detail":"{range_begin:/registry/pods/kube-system/cilium-22cdf; range_end:; response_count:1; response_revision:600; }","duration":"206.682802ms","start":"2026-07-08T02:09:44.427288Z","end":"2026-07-08T02:09:44.633971Z","steps":["trace[1602234687] 'agreement among raft nodes before linearized reading' (duration: 206.461013ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:09:48.890244Z","caller":"traceutil/trace.go:171","msg":"trace[1050250857] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"327.61197ms","start":"2026-07-08T02:09:48.562599Z","end":"2026-07-08T02:09:48.890211Z","steps":["trace[1050250857] 'read index received' (duration: 327.412193ms)","trace[1050250857] 'applied index is now lower than readState.Index' (duration: 197.727µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:09:48.890401Z","caller":"traceutil/trace.go:171","msg":"trace[1290084670] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"421.835125ms","start":"2026-07-08T02:09:48.468556Z","end":"2026-07-08T02:09:48.890391Z","steps":["trace[1290084670] 'process raft request' (duration: 421.476992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:48.890665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:09:48.46854Z","time spent":"421.906918ms","remote":"127.0.0.1:41684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18052,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:09:48.890867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.237554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:09:48.891254Z","caller":"traceutil/trace.go:171","msg":"trace[2126053484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"328.649309ms","start":"2026-07-08T02:09:48.562564Z","end":"2026-07-08T02:09:48.891213Z","steps":["trace[2126053484] 'agreement among raft nodes before linearized reading' (duration: 328.14102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:48.892855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:09:48.562549Z","time spent":"330.292588ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T02:09:48.891303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.688397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-07-08T02:09:48.893002Z","caller":"traceutil/trace.go:171","msg":"trace[1549772461] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:659; }","duration":"244.43028ms","start":"2026-07-08T02:09:48.64856Z","end":"2026-07-08T02:09:48.89299Z","steps":["trace[1549772461] 'agreement among raft nodes before linearized reading' (duration: 242.623104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:48.891302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.34531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T02:09:48.893154Z","caller":"traceutil/trace.go:171","msg":"trace[746604625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:659; }","duration":"248.246289ms","start":"2026-07-08T02:09:48.644899Z","end":"2026-07-08T02:09:48.893146Z","steps":["trace[746604625] 'agreement among raft nodes before linearized reading' (duration: 246.248146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:49.775056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.745254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-07-08T02:09:49.775157Z","caller":"traceutil/trace.go:171","msg":"trace[412813401] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:661; }","duration":"726.899239ms","start":"2026-07-08T02:09:49.048234Z","end":"2026-07-08T02:09:49.775133Z","steps":["trace[412813401] 'range keys from in-memory index tree' (duration: 726.551627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:49.775216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:09:49.048211Z","time spent":"726.990953ms","remote":"127.0.0.1:41530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10399,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-07-08T02:09:49.775861Z","caller":"traceutil/trace.go:171","msg":"trace[2112341953] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"326.515398ms","start":"2026-07-08T02:09:49.449319Z","end":"2026-07-08T02:09:49.775835Z","steps":["trace[2112341953] 'read index received' (duration: 326.477856ms)","trace[2112341953] 'applied index is now lower than readState.Index' (duration: 36.711µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:09:49.775875Z","caller":"traceutil/trace.go:171","msg":"trace[2065107358] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"521.665516ms","start":"2026-07-08T02:09:49.254185Z","end":"2026-07-08T02:09:49.775851Z","steps":["trace[2065107358] 'process raft request' (duration: 521.539842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:49.775991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:09:49.254163Z","time spent":"521.76955ms","remote":"127.0.0.1:41530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T02:09:49.776035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.700554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-07-08T02:09:49.776061Z","caller":"traceutil/trace.go:171","msg":"trace[84013582] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:662; }","duration":"326.776436ms","start":"2026-07-08T02:09:49.449276Z","end":"2026-07-08T02:09:49.776052Z","steps":["trace[84013582] 'agreement among raft nodes before linearized reading' (duration: 326.643602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:49.77609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:09:49.449255Z","time spent":"326.828659ms","remote":"127.0.0.1:41530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-07-08T02:09:49.781738Z","caller":"traceutil/trace.go:171","msg":"trace[831217537] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"102.832529ms","start":"2026-07-08T02:09:49.678884Z","end":"2026-07-08T02:09:49.781716Z","steps":["trace[831217537] 'process raft request' (duration: 102.689214ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:09:55.807725Z","caller":"traceutil/trace.go:171","msg":"trace[2018342975] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"377.216259ms","start":"2026-07-08T02:09:55.430446Z","end":"2026-07-08T02:09:55.807662Z","steps":["trace[2018342975] 'process raft request' (duration: 377.048853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:09:55.807874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:09:55.430424Z","time spent":"377.374594ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:10:46.161524Z","caller":"traceutil/trace.go:171","msg":"trace[965219566] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"100.981945ms","start":"2026-07-08T02:10:46.060517Z","end":"2026-07-08T02:10:46.161499Z","steps":["trace[965219566] 'process raft request' (duration: 97.149822ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:11:04.854534Z","caller":"traceutil/trace.go:171","msg":"trace[1957420389] linearizableReadLoop","detail":"{readStateIndex:1716; appliedIndex:1715; }","duration":"293.307678ms","start":"2026-07-08T02:11:04.561212Z","end":"2026-07-08T02:11:04.85452Z","steps":["trace[1957420389] 'read index received' (duration: 292.462102ms)","trace[1957420389] 'applied index is now lower than readState.Index' (duration: 844.886µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:11:04.854699Z","caller":"traceutil/trace.go:171","msg":"trace[990299833] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"294.804117ms","start":"2026-07-08T02:11:04.55988Z","end":"2026-07-08T02:11:04.854684Z","steps":["trace[990299833] 'process raft request' (duration: 293.786318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:04.85482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.595124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:11:04.854844Z","caller":"traceutil/trace.go:171","msg":"trace[1910837094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1648; }","duration":"293.662284ms","start":"2026-07-08T02:11:04.561176Z","end":"2026-07-08T02:11:04.854838Z","steps":["trace[1910837094] 'agreement among raft nodes before linearized reading' (duration: 293.611473ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:11:04.856212Z","caller":"traceutil/trace.go:171","msg":"trace[611004827] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"295.003871ms","start":"2026-07-08T02:11:04.561197Z","end":"2026-07-08T02:11:04.856201Z","steps":["trace[611004827] 'process raft request' (duration: 294.752556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:08.063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186306256518717,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T02:11:08.541127Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.033667869s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T02:11:08.54159Z","caller":"traceutil/trace.go:171","msg":"trace[511270349] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1679; }","duration":"1.03418901s","start":"2026-07-08T02:11:07.507373Z","end":"2026-07-08T02:11:08.541562Z","steps":["trace[511270349] 'process raft request' (duration: 1.034099058s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:08.541751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:11:07.507362Z","time spent":"1.034302862s","remote":"127.0.0.1:41530","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T02:11:08.547809Z","caller":"traceutil/trace.go:171","msg":"trace[1514297667] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1679; }","duration":"1.040134052s","start":"2026-07-08T02:11:07.507615Z","end":"2026-07-08T02:11:08.547749Z","steps":["trace[1514297667] 'process raft request' (duration: 1.039940699s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:08.547924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:11:07.507589Z","time spent":"1.040285136s","remote":"127.0.0.1:41530","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T02:11:08.549223Z","caller":"traceutil/trace.go:171","msg":"trace[767884500] linearizableReadLoop","detail":"{readStateIndex:1751; appliedIndex:1749; }","duration":"987.11098ms","start":"2026-07-08T02:11:07.562103Z","end":"2026-07-08T02:11:08.549214Z","steps":["trace[767884500] 'read index received' (duration: 979.869473ms)","trace[767884500] 'applied index is now lower than readState.Index' (duration: 7.240817ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:11:08.549288Z","caller":"traceutil/trace.go:171","msg":"trace[2048258126] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1679; }","duration":"455.094266ms","start":"2026-07-08T02:11:08.094158Z","end":"2026-07-08T02:11:08.549252Z","steps":["trace[2048258126] 'process raft request' (duration: 454.879672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:08.549372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.099836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:11:08.549431Z","caller":"traceutil/trace.go:171","msg":"trace[373947084] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1679; }","duration":"309.203737ms","start":"2026-07-08T02:11:08.240218Z","end":"2026-07-08T02:11:08.549422Z","steps":["trace[373947084] 'agreement among raft nodes before linearized reading' (duration: 309.107895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:08.549439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:11:08.094118Z","time spent":"455.259439ms","remote":"127.0.0.1:41530","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T02:11:08.549483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:11:08.240199Z","time spent":"309.275039ms","remote":"127.0.0.1:41586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":17,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-07-08T02:11:08.549568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.743885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:11:08.549634Z","caller":"traceutil/trace.go:171","msg":"trace[769676729] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1679; }","duration":"126.867278ms","start":"2026-07-08T02:11:08.42275Z","end":"2026-07-08T02:11:08.549618Z","steps":["trace[769676729] 'agreement among raft nodes before linearized reading' (duration: 126.697224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:08.54974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.620499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:11:08.549769Z","caller":"traceutil/trace.go:171","msg":"trace[428030346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1679; }","duration":"987.720221ms","start":"2026-07-08T02:11:07.562042Z","end":"2026-07-08T02:11:08.549763Z","steps":["trace[428030346] 'agreement among raft nodes before linearized reading' (duration: 987.239052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:08.549844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:11:07.562018Z","time spent":"987.817954ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T02:11:20.692112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.549798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:11:20.692256Z","caller":"traceutil/trace.go:171","msg":"trace[2000326123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1850; }","duration":"127.771652ms","start":"2026-07-08T02:11:20.564452Z","end":"2026-07-08T02:11:20.692224Z","steps":["trace[2000326123] 'range keys from in-memory index tree' (duration: 127.436847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:20.692779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.551049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-476f462a-6f29-4557-97f4-acbccf01807d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-08T02:11:20.692894Z","caller":"traceutil/trace.go:171","msg":"trace[1447695319] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-476f462a-6f29-4557-97f4-acbccf01807d; range_end:; response_count:1; response_revision:1850; }","duration":"145.736262ms","start":"2026-07-08T02:11:20.547131Z","end":"2026-07-08T02:11:20.692867Z","steps":["trace[1447695319] 'range keys from in-memory index tree' (duration: 145.301424ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:11:33.454034Z","caller":"traceutil/trace.go:171","msg":"trace[1887733690] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"155.264217ms","start":"2026-07-08T02:11:33.298753Z","end":"2026-07-08T02:11:33.454018Z","steps":["trace[1887733690] 'process raft request' (duration: 155.150705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:43.389746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186306256519444,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T02:11:43.436987Z","caller":"traceutil/trace.go:171","msg":"trace[352245742] linearizableReadLoop","detail":"{readStateIndex:2095; appliedIndex:2094; }","duration":"548.089088ms","start":"2026-07-08T02:11:42.888876Z","end":"2026-07-08T02:11:43.436965Z","steps":["trace[352245742] 'read index received' (duration: 547.942776ms)","trace[352245742] 'applied index is now lower than readState.Index' (duration: 145.462µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:11:43.437052Z","caller":"traceutil/trace.go:171","msg":"trace[1971826151] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"556.397222ms","start":"2026-07-08T02:11:42.880626Z","end":"2026-07-08T02:11:43.437023Z","steps":["trace[1971826151] 'process raft request' (duration: 556.195019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:43.437216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:11:42.880607Z","time spent":"556.509634ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:11:43.437336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.445284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-08T02:11:43.437387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.003661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-08T02:11:43.437493Z","caller":"traceutil/trace.go:171","msg":"trace[54779018] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2013; }","duration":"542.129303ms","start":"2026-07-08T02:11:42.895346Z","end":"2026-07-08T02:11:43.437475Z","steps":["trace[54779018] 'agreement among raft nodes before linearized reading' (duration: 541.769067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:43.437585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:11:42.895336Z","time spent":"542.232884ms","remote":"127.0.0.1:58494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-08T02:11:43.437418Z","caller":"traceutil/trace.go:171","msg":"trace[103910978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2013; }","duration":"548.522646ms","start":"2026-07-08T02:11:42.888845Z","end":"2026-07-08T02:11:43.437368Z","steps":["trace[103910978] 'agreement among raft nodes before linearized reading' (duration: 548.234991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:43.437701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:11:42.888825Z","time spent":"548.862381ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T02:11:44.099959Z","caller":"traceutil/trace.go:171","msg":"trace[458453781] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"657.860039ms","start":"2026-07-08T02:11:43.442077Z","end":"2026-07-08T02:11:44.099937Z","steps":["trace[458453781] 'process raft request' (duration: 651.366865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:44.100096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:11:43.442058Z","time spent":"657.9679ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:11:44.10036Z","caller":"traceutil/trace.go:171","msg":"trace[400173955] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"206.860913ms","start":"2026-07-08T02:11:43.893468Z","end":"2026-07-08T02:11:44.100329Z","steps":["trace[400173955] 'process raft request' (duration: 206.425536ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:11:44.100631Z","caller":"traceutil/trace.go:171","msg":"trace[1404252735] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"206.284844ms","start":"2026-07-08T02:11:43.894331Z","end":"2026-07-08T02:11:44.100616Z","steps":["trace[1404252735] 'process raft request' (duration: 205.860407ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:11:44.100684Z","caller":"traceutil/trace.go:171","msg":"trace[1009237283] linearizableReadLoop","detail":"{readStateIndex:2099; appliedIndex:2095; }","duration":"205.051734ms","start":"2026-07-08T02:11:43.895613Z","end":"2026-07-08T02:11:44.100664Z","steps":["trace[1009237283] 'read index received' (duration: 197.848679ms)","trace[1009237283] 'applied index is now lower than readState.Index' (duration: 7.202245ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:11:44.100774Z","caller":"traceutil/trace.go:171","msg":"trace[1643850540] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"205.888417ms","start":"2026-07-08T02:11:43.894879Z","end":"2026-07-08T02:11:44.100768Z","steps":["trace[1643850540] 'process raft request' (duration: 205.693944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:44.100924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.298438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T02:11:44.100946Z","caller":"traceutil/trace.go:171","msg":"trace[1506615358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2017; }","duration":"205.354389ms","start":"2026-07-08T02:11:43.895585Z","end":"2026-07-08T02:11:44.10094Z","steps":["trace[1506615358] 'agreement among raft nodes before linearized reading' (duration: 205.251937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:44.100997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.922087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-08T02:11:44.10102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.358998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T02:11:44.101079Z","caller":"traceutil/trace.go:171","msg":"trace[1496346208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2017; }","duration":"175.466949ms","start":"2026-07-08T02:11:43.925599Z","end":"2026-07-08T02:11:44.101065Z","steps":["trace[1496346208] 'agreement among raft nodes before linearized reading' (duration: 175.317326ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:11:44.101176Z","caller":"traceutil/trace.go:171","msg":"trace[642423275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2017; }","duration":"176.017288ms","start":"2026-07-08T02:11:43.92502Z","end":"2026-07-08T02:11:44.101037Z","steps":["trace[642423275] 'agreement among raft nodes before linearized reading' (duration: 175.855616ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:11:52.316064Z","caller":"traceutil/trace.go:171","msg":"trace[501490464] linearizableReadLoop","detail":"{readStateIndex:2155; appliedIndex:2152; }","duration":"181.620828ms","start":"2026-07-08T02:11:52.134413Z","end":"2026-07-08T02:11:52.316034Z","steps":["trace[501490464] 'read index received' (duration: 14.149289ms)","trace[501490464] 'applied index is now lower than readState.Index' (duration: 167.469979ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:11:52.316099Z","caller":"traceutil/trace.go:171","msg":"trace[1292690682] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"186.805768ms","start":"2026-07-08T02:11:52.129252Z","end":"2026-07-08T02:11:52.316057Z","steps":["trace[1292690682] 'process raft request' (duration: 186.557504ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:11:52.316287Z","caller":"traceutil/trace.go:171","msg":"trace[1746100046] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"186.508275ms","start":"2026-07-08T02:11:52.129761Z","end":"2026-07-08T02:11:52.316269Z","steps":["trace[1746100046] 'process raft request' (duration: 186.21498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:52.316585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.015954ms","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-07-08T02:11:52.316618Z","caller":"traceutil/trace.go:171","msg":"trace[162528396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2072; }","duration":"182.120316ms","start":"2026-07-08T02:11:52.134488Z","end":"2026-07-08T02:11:52.316609Z","steps":["trace[162528396] 'agreement among raft nodes before linearized reading' (duration: 181.935433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:52.316637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.55849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-08T02:11:52.316638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.196427ms","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-07-08T02:11:52.316691Z","caller":"traceutil/trace.go:171","msg":"trace[1994736787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2072; }","duration":"176.668512ms","start":"2026-07-08T02:11:52.140006Z","end":"2026-07-08T02:11:52.316674Z","steps":["trace[1994736787] 'agreement among raft nodes before linearized reading' (duration: 176.462819ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:11:52.316699Z","caller":"traceutil/trace.go:171","msg":"trace[526257731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2072; }","duration":"182.297529ms","start":"2026-07-08T02:11:52.134386Z","end":"2026-07-08T02:11:52.316684Z","steps":["trace[526257731] 'agreement among raft nodes before linearized reading' (duration: 182.100946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:52.316888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.248071ms","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-07-08T02:11:52.316972Z","caller":"traceutil/trace.go:171","msg":"trace[990965264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2072; }","duration":"177.354172ms","start":"2026-07-08T02:11:52.139597Z","end":"2026-07-08T02:11:52.316951Z","steps":["trace[990965264] 'agreement among raft nodes before linearized reading' (duration: 177.050827ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:11:52.527438Z","caller":"traceutil/trace.go:171","msg":"trace[1566422638] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"205.796523ms","start":"2026-07-08T02:11:52.32159Z","end":"2026-07-08T02:11:52.527386Z","steps":["trace[1566422638] 'process raft request' (duration: 205.513998ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:11:52.527453Z","caller":"traceutil/trace.go:171","msg":"trace[1855563575] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"205.744791ms","start":"2026-07-08T02:11:52.321683Z","end":"2026-07-08T02:11:52.527428Z","steps":["trace[1855563575] 'process raft request' (duration: 205.613259ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:11:52.528022Z","caller":"traceutil/trace.go:171","msg":"trace[1478645129] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"176.968665ms","start":"2026-07-08T02:11:52.350976Z","end":"2026-07-08T02:11:52.527944Z","steps":["trace[1478645129] 'process raft request' (duration: 176.697221ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:11:52.5282Z","caller":"traceutil/trace.go:171","msg":"trace[1378289993] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"206.111877ms","start":"2026-07-08T02:11:52.322073Z","end":"2026-07-08T02:11:52.528185Z","steps":["trace[1378289993] 'process raft request' (duration: 205.293764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:11:53.947113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.858615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:11:53.947208Z","caller":"traceutil/trace.go:171","msg":"trace[334615310] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"191.426989ms","start":"2026-07-08T02:11:53.755764Z","end":"2026-07-08T02:11:53.947191Z","steps":["trace[334615310] 'process raft request' (duration: 65.265329ms)","trace[334615310] 'compare' (duration: 125.686433ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:13:05.204402Z","caller":"traceutil/trace.go:171","msg":"trace[925703051] transaction","detail":"{read_only:false; response_revision:2517; number_of_response:1; }","duration":"121.72565ms","start":"2026-07-08T02:13:05.08263Z","end":"2026-07-08T02:13:05.204356Z","steps":["trace[925703051] 'process raft request' (duration: 121.513748ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:13:56.324728Z","caller":"traceutil/trace.go:171","msg":"trace[1566027655] transaction","detail":"{read_only:false; response_revision:2841; number_of_response:1; }","duration":"234.719994ms","start":"2026-07-08T02:13:56.089983Z","end":"2026-07-08T02:13:56.324703Z","steps":["trace[1566027655] 'process raft request' (duration: 234.591553ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:13:57.348074Z","caller":"traceutil/trace.go:171","msg":"trace[1701964997] transaction","detail":"{read_only:false; response_revision:2844; number_of_response:1; }","duration":"629.9612ms","start":"2026-07-08T02:13:56.718079Z","end":"2026-07-08T02:13:57.348041Z","steps":["trace[1701964997] 'process raft request' (duration: 629.793548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:13:57.348272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:13:56.718058Z","time spent":"630.129882ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:13:57.35145Z","caller":"traceutil/trace.go:171","msg":"trace[150399226] linearizableReadLoop","detail":"{readStateIndex:2956; appliedIndex:2954; }","duration":"321.009145ms","start":"2026-07-08T02:13:57.030424Z","end":"2026-07-08T02:13:57.351433Z","steps":["trace[150399226] 'read index received' (duration: 317.528951ms)","trace[150399226] 'applied index is now lower than readState.Index' (duration: 3.479544ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:13:57.3515Z","caller":"traceutil/trace.go:171","msg":"trace[698348200] transaction","detail":"{read_only:false; response_revision:2845; number_of_response:1; }","duration":"613.911089ms","start":"2026-07-08T02:13:56.737563Z","end":"2026-07-08T02:13:57.351474Z","steps":["trace[698348200] 'process raft request' (duration: 613.706067ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:13:57.351527Z","caller":"traceutil/trace.go:171","msg":"trace[826407418] transaction","detail":"{read_only:false; response_revision:2846; number_of_response:1; }","duration":"290.982668ms","start":"2026-07-08T02:13:57.060536Z","end":"2026-07-08T02:13:57.351519Z","steps":["trace[826407418] 'process raft request' (duration: 290.850126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:13:57.351622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:13:56.737545Z","time spent":"614.00756ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:13:57.351694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.636291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-08T02:13:57.351763Z","caller":"traceutil/trace.go:171","msg":"trace[1479303772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2846; }","duration":"222.732462ms","start":"2026-07-08T02:13:57.129014Z","end":"2026-07-08T02:13:57.351746Z","steps":["trace[1479303772] 'agreement among raft nodes before linearized reading' (duration: 222.533069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:13:57.351794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.527959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-08T02:13:57.351802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.387395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T02:13:57.351816Z","caller":"traceutil/trace.go:171","msg":"trace[103248782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2846; }","duration":"218.55878ms","start":"2026-07-08T02:13:57.133251Z","end":"2026-07-08T02:13:57.35181Z","steps":["trace[103248782] 'agreement among raft nodes before linearized reading' (duration: 218.457238ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:13:57.351851Z","caller":"traceutil/trace.go:171","msg":"trace[2096918075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2846; }","duration":"217.444676ms","start":"2026-07-08T02:13:57.134388Z","end":"2026-07-08T02:13:57.351833Z","steps":["trace[2096918075] 'agreement among raft nodes before linearized reading' (duration: 217.308134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:13:57.351925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.498401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T02:13:57.35197Z","caller":"traceutil/trace.go:171","msg":"trace[1855151176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2846; }","duration":"321.568041ms","start":"2026-07-08T02:13:57.030382Z","end":"2026-07-08T02:13:57.35195Z","steps":["trace[1855151176] 'agreement among raft nodes before linearized reading' (duration: 321.48523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:13:57.351999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:13:57.030355Z","time spent":"321.635593ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T02:17:31.865298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.824367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:17:31.865379Z","caller":"traceutil/trace.go:171","msg":"trace[1971231053] linearizableReadLoop","detail":"{readStateIndex:4933; appliedIndex:4932; }","duration":"248.949766ms","start":"2026-07-08T02:17:31.616419Z","end":"2026-07-08T02:17:31.865368Z","steps":["trace[1971231053] 'read index received' (duration: 147.909496ms)","trace[1971231053] 'applied index is now lower than readState.Index' (duration: 101.03958ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:17:31.865419Z","caller":"traceutil/trace.go:171","msg":"trace[2120337776] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"253.653283ms","start":"2026-07-08T02:17:31.611734Z","end":"2026-07-08T02:17:31.865387Z","steps":["trace[2120337776] 'process raft request' (duration: 152.661024ms)","trace[2120337776] 'compare' (duration: 100.551894ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:17:31.866008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.583084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-08T02:17:31.866042Z","caller":"traceutil/trace.go:171","msg":"trace[1313587834] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4751; }","duration":"249.618884ms","start":"2026-07-08T02:17:31.616413Z","end":"2026-07-08T02:17:31.866032Z","steps":["trace[1313587834] 'agreement among raft nodes before linearized reading' (duration: 248.997727ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:17:31.901392Z","caller":"traceutil/trace.go:171","msg":"trace[1634725029] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"130.802389ms","start":"2026-07-08T02:17:31.770567Z","end":"2026-07-08T02:17:31.901369Z","steps":["trace[1634725029] 'process raft request' (duration: 130.673768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:17:31.901643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.650965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:17:31.901714Z","caller":"traceutil/trace.go:171","msg":"trace[1947391584] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:4752; }","duration":"259.736196ms","start":"2026-07-08T02:17:31.641941Z","end":"2026-07-08T02:17:31.901677Z","steps":["trace[1947391584] 'agreement among raft nodes before linearized reading' (duration: 259.629215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:17:31.901789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.243718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-08T02:17:31.901885Z","caller":"traceutil/trace.go:171","msg":"trace[266393072] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4752; }","duration":"186.38515ms","start":"2026-07-08T02:17:31.715484Z","end":"2026-07-08T02:17:31.901869Z","steps":["trace[266393072] 'agreement among raft nodes before linearized reading' (duration: 186.065717ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:17:32.002106Z","caller":"traceutil/trace.go:171","msg":"trace[769479031] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"128.759884ms","start":"2026-07-08T02:17:31.873322Z","end":"2026-07-08T02:17:32.002082Z","steps":["trace[769479031] 'process raft request' (duration: 100.304111ms)","trace[769479031] 'compare' (duration: 28.23769ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:17:32.002483Z","caller":"traceutil/trace.go:171","msg":"trace[1482357634] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"128.666793ms","start":"2026-07-08T02:17:31.87379Z","end":"2026-07-08T02:17:32.002456Z","steps":["trace[1482357634] 'process raft request' (duration: 128.231868ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:17:32.002676Z","caller":"traceutil/trace.go:171","msg":"trace[2062766465] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"127.647801ms","start":"2026-07-08T02:17:31.875011Z","end":"2026-07-08T02:17:32.002659Z","steps":["trace[2062766465] 'process raft request' (duration: 127.318587ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:17:32.002777Z","caller":"traceutil/trace.go:171","msg":"trace[399324906] linearizableReadLoop","detail":"{readStateIndex:4938; appliedIndex:4934; }","duration":"101.418674ms","start":"2026-07-08T02:17:31.901345Z","end":"2026-07-08T02:17:32.002764Z","steps":["trace[399324906] 'read index received' (duration: 72.262942ms)","trace[399324906] 'applied index is now lower than readState.Index' (duration: 29.154632ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:17:32.00293Z","caller":"traceutil/trace.go:171","msg":"trace[1056592568] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"126.453106ms","start":"2026-07-08T02:17:31.876455Z","end":"2026-07-08T02:17:32.002908Z","steps":["trace[1056592568] 'process raft request' (duration: 126.153562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:17:32.003179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.615948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-74393749-1b30-4ca7-bad5-488f3168408b\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-07-08T02:17:32.005344Z","caller":"traceutil/trace.go:171","msg":"trace[34084936] range","detail":"{range_begin:/registry/persistentvolumes/pvc-74393749-1b30-4ca7-bad5-488f3168408b; range_end:; response_count:1; response_revision:4756; }","duration":"134.027748ms","start":"2026-07-08T02:17:31.871299Z","end":"2026-07-08T02:17:32.005326Z","steps":["trace[34084936] 'agreement among raft nodes before linearized reading' (duration: 131.503588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:17:32.005372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.271911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-08T02:17:32.005402Z","caller":"traceutil/trace.go:171","msg":"trace[1869593013] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4756; }","duration":"100.340781ms","start":"2026-07-08T02:17:31.905053Z","end":"2026-07-08T02:17:32.005394Z","steps":["trace[1869593013] 'agreement among raft nodes before linearized reading' (duration: 100.22392ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:17:45.430065Z","caller":"traceutil/trace.go:171","msg":"trace[239355842] linearizableReadLoop","detail":"{readStateIndex:5113; appliedIndex:5112; }","duration":"109.785135ms","start":"2026-07-08T02:17:45.320258Z","end":"2026-07-08T02:17:45.430043Z","steps":["trace[239355842] 'read index received' (duration: 109.548072ms)","trace[239355842] 'applied index is now lower than readState.Index' (duration: 236.243µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:17:45.430186Z","caller":"traceutil/trace.go:171","msg":"trace[55558141] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"114.256258ms","start":"2026-07-08T02:17:45.315888Z","end":"2026-07-08T02:17:45.430144Z","steps":["trace[55558141] 'process raft request' (duration: 114.015836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:17:45.430295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.996708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-07-08T02:17:45.430383Z","caller":"traceutil/trace.go:171","msg":"trace[532439317] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4926; }","duration":"110.139169ms","start":"2026-07-08T02:17:45.320231Z","end":"2026-07-08T02:17:45.43037Z","steps":["trace[532439317] 'agreement among raft nodes before linearized reading' (duration: 109.901076ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:18:59.536473Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2862} {"level":"info","ts":"2026-07-08T02:18:59.692871Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2862,"took":"154.786206ms","hash":1827170203,"current-db-size-bytes":53006336,"current-db-size":"53 MB","current-db-size-in-use-bytes":29270016,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-08T02:18:59.692959Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1827170203,"revision":2862,"compact-revision":-1} {"level":"info","ts":"2026-07-08T02:21:43.131474Z","caller":"traceutil/trace.go:171","msg":"trace[1185446906] transaction","detail":"{read_only:false; response_revision:6625; number_of_response:1; }","duration":"107.775099ms","start":"2026-07-08T02:21:43.023667Z","end":"2026-07-08T02:21:43.131442Z","steps":["trace[1185446906] 'process raft request' (duration: 107.582057ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:23:59.541603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5528} {"level":"info","ts":"2026-07-08T02:23:59.616199Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5528,"took":"73.335294ms","hash":337483932,"current-db-size-bytes":53006336,"current-db-size":"53 MB","current-db-size-in-use-bytes":18505728,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-08T02:23:59.616315Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":337483932,"revision":5528,"compact-revision":2862} {"level":"warn","ts":"2026-07-08T02:24:17.024786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.56867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:24:17.024874Z","caller":"traceutil/trace.go:171","msg":"trace[291781038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7483; }","duration":"461.670914ms","start":"2026-07-08T02:24:16.563184Z","end":"2026-07-08T02:24:17.024855Z","steps":["trace[291781038] 'range keys from in-memory index tree' (duration: 461.457895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.024914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.563109Z","time spent":"461.795741ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T02:24:17.025143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.177803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:718"} {"level":"info","ts":"2026-07-08T02:24:17.025334Z","caller":"traceutil/trace.go:171","msg":"trace[1546109978] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:7483; }","duration":"502.683366ms","start":"2026-07-08T02:24:16.522625Z","end":"2026-07-08T02:24:17.025308Z","steps":["trace[1546109978] 'range keys from in-memory index tree' (duration: 502.080349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.025459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.52261Z","time spent":"502.834252ms","remote":"127.0.0.1:41860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":740,"request content":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" "} {"level":"warn","ts":"2026-07-08T02:24:17.025695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.531088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-08T02:24:17.025758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.305451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T02:24:17.025763Z","caller":"traceutil/trace.go:171","msg":"trace[405672007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7483; }","duration":"443.623161ms","start":"2026-07-08T02:24:16.582122Z","end":"2026-07-08T02:24:17.025746Z","steps":["trace[405672007] 'range keys from in-memory index tree' (duration: 443.041777ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:17.025812Z","caller":"traceutil/trace.go:171","msg":"trace[225246077] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7483; }","duration":"444.391706ms","start":"2026-07-08T02:24:16.581408Z","end":"2026-07-08T02:24:17.0258Z","steps":["trace[225246077] 'range keys from in-memory index tree' (duration: 443.317028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.025821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.582114Z","time spent":"443.695115ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T02:24:17.025847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.581393Z","time spent":"444.446208ms","remote":"127.0.0.1:41776","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-07-08T02:24:17.02597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.923253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-08T02:24:17.026054Z","caller":"traceutil/trace.go:171","msg":"trace[373689914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7483; }","duration":"446.054839ms","start":"2026-07-08T02:24:16.579978Z","end":"2026-07-08T02:24:17.026033Z","steps":["trace[373689914] 'range keys from in-memory index tree' (duration: 445.40334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.026093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.579957Z","time spent":"446.125631ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T02:24:17.026372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.22952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-07-08T02:24:17.026457Z","caller":"traceutil/trace.go:171","msg":"trace[26787623] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver; range_end:; response_count:1; response_revision:7483; }","duration":"442.345806ms","start":"2026-07-08T02:24:16.584098Z","end":"2026-07-08T02:24:17.026444Z","steps":["trace[26787623] 'range keys from in-memory index tree' (duration: 440.522206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.026518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.58408Z","time spent":"442.428879ms","remote":"127.0.0.1:41860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":722,"request content":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" "} {"level":"warn","ts":"2026-07-08T02:24:17.455749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.785207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium\" ","response":"range_response_count:1 size:749"} {"level":"info","ts":"2026-07-08T02:24:17.455831Z","caller":"traceutil/trace.go:171","msg":"trace[753598178] range","detail":"{range_begin:/registry/clusterrolebindings/cilium; range_end:; response_count:1; response_revision:7487; }","duration":"252.927053ms","start":"2026-07-08T02:24:17.202888Z","end":"2026-07-08T02:24:17.455815Z","steps":["trace[753598178] 'range keys from in-memory index tree' (duration: 252.714183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.456313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.685347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cert-manager-webhook:subjectaccessreviews\" ","response":"range_response_count:1 size:1182"} {"level":"info","ts":"2026-07-08T02:24:17.45636Z","caller":"traceutil/trace.go:171","msg":"trace[737567173] range","detail":"{range_begin:/registry/clusterrolebindings/cert-manager-webhook:subjectaccessreviews; range_end:; response_count:1; response_revision:7487; }","duration":"256.790022ms","start":"2026-07-08T02:24:17.199557Z","end":"2026-07-08T02:24:17.456347Z","steps":["trace[737567173] 'range keys from in-memory index tree' (duration: 256.571093ms)"],"step_count":1}