{"level":"warn","ts":"2026-05-28T00:28:29.4405Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T00:28:29.440603Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-05-28T00:28:29.440681Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T00:28:29.44069Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-28T00:28:29.440726Z","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-05-28T00:28:29.441389Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-05-28T00:28:29.441474Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-05-28T00:28:29.44565Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.93865ms"} {"level":"info","ts":"2026-05-28T00:28:29.451225Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-05-28T00:28:29.451323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T00:28:29.451348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-05-28T00:28:29.451366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-28T00:28:29.451403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-05-28T00:28:29.451551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-05-28T00:28:29.456739Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T00:28:29.458208Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T00:28:29.459334Z","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-05-28T00:28:29.463158Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-28T00:28:29.463568Z","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-05-28T00:28:29.464431Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-28T00:28:29.464518Z","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-05-28T00:28:29.464576Z","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-05-28T00:28:29.465448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-05-28T00:28:29.466075Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-28T00:28:29.468631Z","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-05-28T00:28:29.46886Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-28T00:28:29.468889Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T00:28:29.468949Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-28T00:28:29.468957Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-28T00:28:29.752392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T00:28:29.752474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T00:28:29.752523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-05-28T00:28:29.752566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-05-28T00:28:29.752579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-28T00:28:29.752609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-05-28T00:28:29.752625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-28T00:28:29.754139Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T00:28:29.754856Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T00:28:29.754843Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T00:28:29.755261Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T00:28:29.755337Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T00:28:29.755921Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T00:28:29.756155Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T00:28:29.756778Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T00:28:29.758715Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-05-28T00:28:29.758718Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-28T00:28:29.767647Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-28T00:28:39.825714Z","caller":"traceutil/trace.go:171","msg":"trace[1091591570] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:359; }","duration":"124.626455ms","start":"2026-05-28T00:28:39.701059Z","end":"2026-05-28T00:28:39.825686Z","steps":["trace[1091591570] 'read index received' (duration: 123.716242ms)","trace[1091591570] 'applied index is now lower than readState.Index' (duration: 908.343µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:28:39.825795Z","caller":"traceutil/trace.go:171","msg":"trace[1455693795] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"132.124459ms","start":"2026-05-28T00:28:39.693621Z","end":"2026-05-28T00:28:39.825745Z","steps":["trace[1455693795] 'process raft request' (duration: 131.223136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:28:39.826032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.320522ms","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-05-28T00:28:39.826166Z","caller":"traceutil/trace.go:171","msg":"trace[47669214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:349; }","duration":"116.48896ms","start":"2026-05-28T00:28:39.709657Z","end":"2026-05-28T00:28:39.826146Z","steps":["trace[47669214] 'agreement among raft nodes before linearized reading' (duration: 116.29512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:28:39.826114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.956961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-28T00:28:39.8263Z","caller":"traceutil/trace.go:171","msg":"trace[715797165] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:349; }","duration":"125.232153ms","start":"2026-05-28T00:28:39.701014Z","end":"2026-05-28T00:28:39.826246Z","steps":["trace[715797165] 'agreement among raft nodes before linearized reading' (duration: 124.825083ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:28:45.747696Z","caller":"traceutil/trace.go:171","msg":"trace[1483493628] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"389.465319ms","start":"2026-05-28T00:28:45.358199Z","end":"2026-05-28T00:28:45.747664Z","steps":["trace[1483493628] 'read index received' (duration: 389.167685ms)","trace[1483493628] 'applied index is now lower than readState.Index' (duration: 296.104µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:28:45.747738Z","caller":"traceutil/trace.go:171","msg":"trace[1139187936] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"545.499455ms","start":"2026-05-28T00:28:45.202206Z","end":"2026-05-28T00:28:45.747705Z","steps":["trace[1139187936] 'process raft request' (duration: 545.207442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:28:45.747987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.739013ms","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-05-28T00:28:45.748041Z","caller":"traceutil/trace.go:171","msg":"trace[1607970716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:408; }","duration":"389.877569ms","start":"2026-05-28T00:28:45.358146Z","end":"2026-05-28T00:28:45.748023Z","steps":["trace[1607970716] 'agreement among raft nodes before linearized reading' (duration: 389.712372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:28:45.748062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.679482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T00:28:45.74809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:28:45.358123Z","time spent":"389.956304ms","remote":"127.0.0.1:46798","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-05-28T00:28:45.748173Z","caller":"traceutil/trace.go:171","msg":"trace[726560841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"133.838489ms","start":"2026-05-28T00:28:45.614312Z","end":"2026-05-28T00:28:45.74815Z","steps":["trace[726560841] 'agreement among raft nodes before linearized reading' (duration: 133.680361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:28:45.749656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:28:45.202192Z","time spent":"545.623781ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:28:46.487385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.277553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:28:46.487557Z","caller":"traceutil/trace.go:171","msg":"trace[468660269] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:421; }","duration":"631.915546ms","start":"2026-05-28T00:28:45.855626Z","end":"2026-05-28T00:28:46.487542Z","steps":["trace[468660269] 'read index received' (duration: 178.606834ms)","trace[468660269] 'applied index is now lower than readState.Index' (duration: 453.307852ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:28:46.487673Z","caller":"traceutil/trace.go:171","msg":"trace[1213768506] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"729.060113ms","start":"2026-05-28T00:28:45.758604Z","end":"2026-05-28T00:28:46.487664Z","steps":["trace[1213768506] 'process raft request' (duration: 728.871083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:28:46.487719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:28:45.758573Z","time spent":"729.120106ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:28:46.487754Z","caller":"traceutil/trace.go:171","msg":"trace[88003689] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"734.706384ms","start":"2026-05-28T00:28:45.753001Z","end":"2026-05-28T00:28:46.487708Z","steps":["trace[88003689] 'process raft request' (duration: 281.212353ms)","trace[88003689] 'compare' (duration: 451.884724ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:28:46.487792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.761054ms","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-05-28T00:28:46.487874Z","caller":"traceutil/trace.go:171","msg":"trace[572422354] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:410; }","duration":"618.857119ms","start":"2026-05-28T00:28:45.868991Z","end":"2026-05-28T00:28:46.487848Z","steps":["trace[572422354] 'agreement among raft nodes before linearized reading' (duration: 618.720003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:28:46.487921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:28:45.86898Z","time spent":"618.931913ms","remote":"127.0.0.1:46798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T00:28:46.487984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:28:45.752976Z","time spent":"734.875783ms","remote":"127.0.0.1:46798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:28:46.487789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.151529ms","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-05-28T00:28:46.488148Z","caller":"traceutil/trace.go:171","msg":"trace[2132660074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:410; }","duration":"632.594329ms","start":"2026-05-28T00:28:45.855539Z","end":"2026-05-28T00:28:46.488133Z","steps":["trace[2132660074] 'agreement among raft nodes before linearized reading' (duration: 632.168528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:28:46.488184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:28:45.855519Z","time spent":"632.654252ms","remote":"127.0.0.1:46798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T00:29:09.644634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.587154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:29:09.644728Z","caller":"traceutil/trace.go:171","msg":"trace[157695042] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:616; }","duration":"112.802944ms","start":"2026-05-28T00:29:09.531907Z","end":"2026-05-28T00:29:09.64471Z","steps":["trace[157695042] 'count revisions from in-memory index tree' (duration: 112.521171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:29:09.64488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.568254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T00:29:09.644894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.43316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"} {"level":"info","ts":"2026-05-28T00:29:09.644958Z","caller":"traceutil/trace.go:171","msg":"trace[1924304409] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:616; }","duration":"112.671339ms","start":"2026-05-28T00:29:09.532269Z","end":"2026-05-28T00:29:09.64494Z","steps":["trace[1924304409] 'range keys from in-memory index tree' (duration: 112.498249ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:29:09.644981Z","caller":"traceutil/trace.go:171","msg":"trace[1289146425] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:616; }","duration":"131.577646ms","start":"2026-05-28T00:29:09.513385Z","end":"2026-05-28T00:29:09.644962Z","steps":["trace[1289146425] 'range keys from in-memory index tree' (duration: 131.26146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:29:09.64533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.625137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:29:09.645371Z","caller":"traceutil/trace.go:171","msg":"trace[1627362602] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:616; }","duration":"113.717161ms","start":"2026-05-28T00:29:09.531642Z","end":"2026-05-28T00:29:09.645359Z","steps":["trace[1627362602] 'range keys from in-memory index tree' (duration: 113.539343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:29:10.819639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.263795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:29:10.819943Z","caller":"traceutil/trace.go:171","msg":"trace[1942917603] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"114.728309ms","start":"2026-05-28T00:29:10.705189Z","end":"2026-05-28T00:29:10.819917Z","steps":["trace[1942917603] 'read index received' (duration: 4.98659ms)","trace[1942917603] 'applied index is now lower than readState.Index' (duration: 109.740059ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:29:10.820055Z","caller":"traceutil/trace.go:171","msg":"trace[1749423149] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"115.888437ms","start":"2026-05-28T00:29:10.704115Z","end":"2026-05-28T00:29:10.820003Z","steps":["trace[1749423149] 'process raft request' (duration: 115.706508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:29:10.820198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.003583ms","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-05-28T00:29:10.820245Z","caller":"traceutil/trace.go:171","msg":"trace[730687005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"115.073575ms","start":"2026-05-28T00:29:10.705156Z","end":"2026-05-28T00:29:10.82023Z","steps":["trace[730687005] 'agreement among raft nodes before linearized reading' (duration: 114.895587ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:29:10.820055Z","caller":"traceutil/trace.go:171","msg":"trace[1567939144] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"128.580723ms","start":"2026-05-28T00:29:10.691425Z","end":"2026-05-28T00:29:10.820005Z","steps":["trace[1567939144] 'process raft request' (duration: 18.73671ms)","trace[1567939144] 'compare' (duration: 109.010891ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:29:16.406229Z","caller":"traceutil/trace.go:171","msg":"trace[948102430] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"260.705755ms","start":"2026-05-28T00:29:16.145483Z","end":"2026-05-28T00:29:16.406189Z","steps":["trace[948102430] 'process raft request' (duration: 259.849682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:29:16.645685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.518532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:29:16.645949Z","caller":"traceutil/trace.go:171","msg":"trace[1740772892] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"184.635223ms","start":"2026-05-28T00:29:16.461262Z","end":"2026-05-28T00:29:16.645897Z","steps":["trace[1740772892] 'process raft request' (duration: 30.810745ms)","trace[1740772892] 'compare' (duration: 153.315112ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:29:17.018876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.076996ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:29:17.019072Z","caller":"traceutil/trace.go:171","msg":"trace[1956403711] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:683; }","duration":"165.34529ms","start":"2026-05-28T00:29:16.853697Z","end":"2026-05-28T00:29:17.019042Z","steps":["trace[1956403711] 'range keys from in-memory index tree' (duration: 164.94525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:29:17.019694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.461877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:29:17.01983Z","caller":"traceutil/trace.go:171","msg":"trace[1873926912] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"166.798455ms","start":"2026-05-28T00:29:16.85301Z","end":"2026-05-28T00:29:17.019808Z","steps":["trace[1873926912] 'read index received' (duration: 67.264µs)","trace[1873926912] 'applied index is now lower than readState.Index' (duration: 166.729271ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:29:17.019844Z","caller":"traceutil/trace.go:171","msg":"trace[1938948557] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"296.721135ms","start":"2026-05-28T00:29:16.723096Z","end":"2026-05-28T00:29:17.019817Z","steps":["trace[1938948557] 'process raft request' (duration: 111.941253ms)","trace[1938948557] 'compare' (duration: 183.620053ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:29:17.020035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.025987ms","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-05-28T00:29:17.020089Z","caller":"traceutil/trace.go:171","msg":"trace[1807468310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:684; }","duration":"167.138331ms","start":"2026-05-28T00:29:16.852935Z","end":"2026-05-28T00:29:17.020073Z","steps":["trace[1807468310] 'agreement among raft nodes before linearized reading' (duration: 166.960593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:29:17.020111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.225517ms","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-05-28T00:29:17.020148Z","caller":"traceutil/trace.go:171","msg":"trace[9812224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:684; }","duration":"149.261428ms","start":"2026-05-28T00:29:16.870874Z","end":"2026-05-28T00:29:17.020136Z","steps":["trace[9812224] 'agreement among raft nodes before linearized reading' (duration: 149.055828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:29:17.502532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.039798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:29:17.502676Z","caller":"traceutil/trace.go:171","msg":"trace[29503005] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"477.210121ms","start":"2026-05-28T00:29:17.02544Z","end":"2026-05-28T00:29:17.50265Z","steps":["trace[29503005] 'process raft request' (duration: 320.577095ms)","trace[29503005] 'compare' (duration: 155.576954ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:29:17.502866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:29:17.025417Z","time spent":"477.36784ms","remote":"127.0.0.1:46798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:29:17.514807Z","caller":"traceutil/trace.go:171","msg":"trace[430631797] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"489.288351ms","start":"2026-05-28T00:29:17.025481Z","end":"2026-05-28T00:29:17.514769Z","steps":["trace[430631797] 'process raft request' (duration: 489.025937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:29:17.514994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:29:17.025451Z","time spent":"489.446829ms","remote":"127.0.0.1:46798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:29:17.515237Z","caller":"traceutil/trace.go:171","msg":"trace[100963187] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:706; }","duration":"485.341032ms","start":"2026-05-28T00:29:17.029873Z","end":"2026-05-28T00:29:17.515214Z","steps":["trace[100963187] 'read index received' (duration: 316.240776ms)","trace[100963187] 'applied index is now lower than readState.Index' (duration: 169.099036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:29:17.515484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.588994ms","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-05-28T00:29:17.515432Z","caller":"traceutil/trace.go:171","msg":"trace[61710716] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"448.879828ms","start":"2026-05-28T00:29:17.066477Z","end":"2026-05-28T00:29:17.515357Z","steps":["trace[61710716] 'process raft request' (duration: 448.373743ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:29:17.515521Z","caller":"traceutil/trace.go:171","msg":"trace[1352515781] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:687; }","duration":"485.668359ms","start":"2026-05-28T00:29:17.029842Z","end":"2026-05-28T00:29:17.51551Z","steps":["trace[1352515781] 'agreement among raft nodes before linearized reading' (duration: 485.488729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:29:17.515558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:29:17.029832Z","time spent":"485.720081ms","remote":"127.0.0.1:46798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T00:29:17.515796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.904803ms","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-05-28T00:29:17.515892Z","caller":"traceutil/trace.go:171","msg":"trace[143901493] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:687; }","duration":"290.04719ms","start":"2026-05-28T00:29:17.225816Z","end":"2026-05-28T00:29:17.515863Z","steps":["trace[143901493] 'agreement among raft nodes before linearized reading' (duration: 289.580167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:29:17.51615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:29:17.066439Z","time spent":"449.597885ms","remote":"127.0.0.1:46606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:29:29.892816Z","caller":"traceutil/trace.go:171","msg":"trace[1196009297] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"100.992119ms","start":"2026-05-28T00:29:29.791801Z","end":"2026-05-28T00:29:29.892793Z","steps":["trace[1196009297] 'process raft request' (duration: 100.9384ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:29:29.893025Z","caller":"traceutil/trace.go:171","msg":"trace[84609949] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"102.287833ms","start":"2026-05-28T00:29:29.790691Z","end":"2026-05-28T00:29:29.892979Z","steps":["trace[84609949] 'process raft request' (duration: 101.910276ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:30:18.164244Z","caller":"traceutil/trace.go:171","msg":"trace[1445651560] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"111.110511ms","start":"2026-05-28T00:30:18.053106Z","end":"2026-05-28T00:30:18.164216Z","steps":["trace[1445651560] 'process raft request' (duration: 110.951398ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:30:43.62803Z","caller":"traceutil/trace.go:171","msg":"trace[857711790] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"102.465073ms","start":"2026-05-28T00:30:43.525539Z","end":"2026-05-28T00:30:43.628004Z","steps":["trace[857711790] 'process raft request' (duration: 102.274587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:30:51.726459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.608933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:30:51.726697Z","caller":"traceutil/trace.go:171","msg":"trace[2058970226] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1960; }","duration":"378.121279ms","start":"2026-05-28T00:30:51.348556Z","end":"2026-05-28T00:30:51.726677Z","steps":["trace[2058970226] 'read index received' (duration: 259.245487ms)","trace[2058970226] 'applied index is now lower than readState.Index' (duration: 118.874702ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:30:51.726795Z","caller":"traceutil/trace.go:171","msg":"trace[1631713693] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"463.705876ms","start":"2026-05-28T00:30:51.263076Z","end":"2026-05-28T00:30:51.726782Z","steps":["trace[1631713693] 'process raft request' (duration: 344.679509ms)","trace[1631713693] 'compare' (duration: 118.49673ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:30:51.72686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:30:51.263037Z","time spent":"463.788858ms","remote":"127.0.0.1:46684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:30:51.726884Z","caller":"traceutil/trace.go:171","msg":"trace[1374906001] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"463.054415ms","start":"2026-05-28T00:30:51.263801Z","end":"2026-05-28T00:30:51.726855Z","steps":["trace[1374906001] 'process raft request' (duration: 462.831738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:30:51.727212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:30:51.263787Z","time spent":"463.277902ms","remote":"127.0.0.1:46810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:30:51.727264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.066708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9335f5e8-7707-44f6-9b7d-326b3a09d6da\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-28T00:30:51.727853Z","caller":"traceutil/trace.go:171","msg":"trace[2137638560] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9335f5e8-7707-44f6-9b7d-326b3a09d6da; range_end:; response_count:1; response_revision:1893; }","duration":"263.642175ms","start":"2026-05-28T00:30:51.464155Z","end":"2026-05-28T00:30:51.727798Z","steps":["trace[2137638560] 'agreement among raft nodes before linearized reading' (duration: 262.822429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:30:51.727391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.283735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:30:51.728195Z","caller":"traceutil/trace.go:171","msg":"trace[1301389258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1893; }","duration":"113.140112ms","start":"2026-05-28T00:30:51.615039Z","end":"2026-05-28T00:30:51.72818Z","steps":["trace[1301389258] 'agreement among raft nodes before linearized reading' (duration: 112.294555ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:30:51.727461Z","caller":"traceutil/trace.go:171","msg":"trace[1163721938] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"464.301625ms","start":"2026-05-28T00:30:51.263146Z","end":"2026-05-28T00:30:51.727448Z","steps":["trace[1163721938] 'process raft request' (duration: 463.427597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:30:51.728741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:30:51.263117Z","time spent":"465.495222ms","remote":"127.0.0.1:46810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:30:51.727457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.890873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:30:51.728849Z","caller":"traceutil/trace.go:171","msg":"trace[1557120428] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1893; }","duration":"380.271517ms","start":"2026-05-28T00:30:51.348543Z","end":"2026-05-28T00:30:51.728815Z","steps":["trace[1557120428] 'agreement among raft nodes before linearized reading' (duration: 378.841671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:30:51.728959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:30:51.348482Z","time spent":"380.443172ms","remote":"127.0.0.1:58272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-05-28T00:30:52.232013Z","caller":"traceutil/trace.go:171","msg":"trace[181960613] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"100.492596ms","start":"2026-05-28T00:30:52.131486Z","end":"2026-05-28T00:30:52.231979Z","steps":["trace[181960613] 'process raft request' (duration: 100.275109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:31:20.130892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.584089ms","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-05-28T00:31:20.131012Z","caller":"traceutil/trace.go:171","msg":"trace[1289918386] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2102; }","duration":"153.719535ms","start":"2026-05-28T00:31:19.977237Z","end":"2026-05-28T00:31:20.130957Z","steps":["trace[1289918386] 'range keys from in-memory index tree' (duration: 153.394914ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:31:26.649967Z","caller":"traceutil/trace.go:171","msg":"trace[177482164] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"226.284612ms","start":"2026-05-28T00:31:26.423644Z","end":"2026-05-28T00:31:26.649929Z","steps":["trace[177482164] 'process raft request' (duration: 226.030053ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:31:26.65022Z","caller":"traceutil/trace.go:171","msg":"trace[1627542255] linearizableReadLoop","detail":"{readStateIndex:2215; appliedIndex:2213; }","duration":"166.753349ms","start":"2026-05-28T00:31:26.483452Z","end":"2026-05-28T00:31:26.650206Z","steps":["trace[1627542255] 'read index received' (duration: 166.212189ms)","trace[1627542255] 'applied index is now lower than readState.Index' (duration: 540.12µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:31:26.650411Z","caller":"traceutil/trace.go:171","msg":"trace[1746757914] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"226.745959ms","start":"2026-05-28T00:31:26.42365Z","end":"2026-05-28T00:31:26.650396Z","steps":["trace[1746757914] 'process raft request' (duration: 226.228471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:31:26.650422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.949066ms","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-05-28T00:31:26.650464Z","caller":"traceutil/trace.go:171","msg":"trace[1648308184] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2138; }","duration":"167.009979ms","start":"2026-05-28T00:31:26.483438Z","end":"2026-05-28T00:31:26.650448Z","steps":["trace[1648308184] 'agreement among raft nodes before linearized reading' (duration: 166.816181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:31:27.007806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.244803ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:31:27.008071Z","caller":"traceutil/trace.go:171","msg":"trace[765359539] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2142; }","duration":"154.531503ms","start":"2026-05-28T00:31:26.853515Z","end":"2026-05-28T00:31:27.008047Z","steps":["trace[765359539] 'range keys from in-memory index tree' (duration: 154.221242ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:31:27.044197Z","caller":"traceutil/trace.go:171","msg":"trace[1090990375] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"130.734169ms","start":"2026-05-28T00:31:26.913432Z","end":"2026-05-28T00:31:27.044166Z","steps":["trace[1090990375] 'process raft request' (duration: 130.519251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:33:06.723971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.887666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:33:06.724095Z","caller":"traceutil/trace.go:171","msg":"trace[1594733902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2780; }","duration":"111.052142ms","start":"2026-05-28T00:33:06.613006Z","end":"2026-05-28T00:33:06.724058Z","steps":["trace[1594733902] 'range keys from in-memory index tree' (duration: 110.782141ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:33:06.894899Z","caller":"traceutil/trace.go:171","msg":"trace[1227654301] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"139.32351ms","start":"2026-05-28T00:33:06.755552Z","end":"2026-05-28T00:33:06.894876Z","steps":["trace[1227654301] 'process raft request' (duration: 139.209545ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:36.399155Z","caller":"traceutil/trace.go:171","msg":"trace[438823266] linearizableReadLoop","detail":"{readStateIndex:4608; appliedIndex:4607; }","duration":"123.132486ms","start":"2026-05-28T00:36:36.275996Z","end":"2026-05-28T00:36:36.399128Z","steps":["trace[438823266] 'read index received' (duration: 122.92493ms)","trace[438823266] 'applied index is now lower than readState.Index' (duration: 206.496µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:36:36.399246Z","caller":"traceutil/trace.go:171","msg":"trace[1480513750] transaction","detail":"{read_only:false; response_revision:4441; number_of_response:1; }","duration":"126.647678ms","start":"2026-05-28T00:36:36.27256Z","end":"2026-05-28T00:36:36.399208Z","steps":["trace[1480513750] 'process raft request' (duration: 126.429302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:36:36.399396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.379734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-28T00:36:36.399425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.269188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-28T00:36:36.399474Z","caller":"traceutil/trace.go:171","msg":"trace[1188566369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4441; }","duration":"123.473476ms","start":"2026-05-28T00:36:36.275974Z","end":"2026-05-28T00:36:36.399448Z","steps":["trace[1188566369] 'agreement among raft nodes before linearized reading' (duration: 123.25656ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:36.399489Z","caller":"traceutil/trace.go:171","msg":"trace[1362010471] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4441; }","duration":"115.34505ms","start":"2026-05-28T00:36:36.284106Z","end":"2026-05-28T00:36:36.399451Z","steps":["trace[1362010471] 'agreement among raft nodes before linearized reading' (duration: 115.198706ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:45.697688Z","caller":"traceutil/trace.go:171","msg":"trace[1679637299] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"145.036772ms","start":"2026-05-28T00:36:45.552623Z","end":"2026-05-28T00:36:45.697659Z","steps":["trace[1679637299] 'process raft request' (duration: 144.888288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:36:49.18447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.077287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-051a513e-6253-4dbc-a648-31c29495510b\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-05-28T00:36:49.184534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.266444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T00:36:49.18447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.725432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T00:36:49.18457Z","caller":"traceutil/trace.go:171","msg":"trace[295800717] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:4731; }","duration":"175.895758ms","start":"2026-05-28T00:36:49.008667Z","end":"2026-05-28T00:36:49.184563Z","steps":["trace[295800717] 'count revisions from in-memory index tree' (duration: 175.657601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:36:49.184558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.424331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-28T00:36:49.184559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.68996ms","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-05-28T00:36:49.184613Z","caller":"traceutil/trace.go:171","msg":"trace[1076150984] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4731; }","duration":"607.512764ms","start":"2026-05-28T00:36:48.577088Z","end":"2026-05-28T00:36:49.1846Z","steps":["trace[1076150984] 'range keys from in-memory index tree' (duration: 607.287466ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:49.184624Z","caller":"traceutil/trace.go:171","msg":"trace[264810761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4731; }","duration":"650.826274ms","start":"2026-05-28T00:36:48.533783Z","end":"2026-05-28T00:36:49.184609Z","steps":["trace[264810761] 'range keys from in-memory index tree' (duration: 650.560867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:36:49.184641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:36:48.577077Z","time spent":"607.558234ms","remote":"127.0.0.1:46798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T00:36:49.184658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:36:48.533759Z","time spent":"650.890587ms","remote":"127.0.0.1:46798","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":"info","ts":"2026-05-28T00:36:49.18454Z","caller":"traceutil/trace.go:171","msg":"trace[347752580] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-051a513e-6253-4dbc-a648-31c29495510b; range_end:; response_count:1; response_revision:4731; }","duration":"271.196031ms","start":"2026-05-28T00:36:48.913327Z","end":"2026-05-28T00:36:49.184523Z","steps":["trace[347752580] 'range keys from in-memory index tree' (duration: 270.943193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:36:49.184685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.724095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-051a513e-6253-4dbc-a648-31c29495510b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-28T00:36:49.184579Z","caller":"traceutil/trace.go:171","msg":"trace[43867607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4731; }","duration":"570.324097ms","start":"2026-05-28T00:36:48.614238Z","end":"2026-05-28T00:36:49.184562Z","steps":["trace[43867607] 'range keys from in-memory index tree' (duration: 570.217982ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:49.184744Z","caller":"traceutil/trace.go:171","msg":"trace[728836832] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-051a513e-6253-4dbc-a648-31c29495510b; range_end:; response_count:1; response_revision:4731; }","duration":"495.829828ms","start":"2026-05-28T00:36:48.6889Z","end":"2026-05-28T00:36:49.184729Z","steps":["trace[728836832] 'range keys from in-memory index tree' (duration: 495.582101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:36:49.184759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:36:48.614227Z","time spent":"570.524803ms","remote":"127.0.0.1:46514","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-05-28T00:36:49.184781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:36:48.688882Z","time spent":"495.88735ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-051a513e-6253-4dbc-a648-31c29495510b\" "} {"level":"warn","ts":"2026-05-28T00:36:51.057618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.317848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-05-28T00:36:51.05772Z","caller":"traceutil/trace.go:171","msg":"trace[691770534] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4749; }","duration":"121.474133ms","start":"2026-05-28T00:36:50.936227Z","end":"2026-05-28T00:36:51.057701Z","steps":["trace[691770534] 'range keys from in-memory index tree' (duration: 121.121662ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:51.058056Z","caller":"traceutil/trace.go:171","msg":"trace[1048819916] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"115.386719ms","start":"2026-05-28T00:36:50.942646Z","end":"2026-05-28T00:36:51.058033Z","steps":["trace[1048819916] 'process raft request' (duration: 98.774996ms)","trace[1048819916] 'compare' (duration: 15.978623ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:36:51.219679Z","caller":"traceutil/trace.go:171","msg":"trace[813855209] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"151.124216ms","start":"2026-05-28T00:36:51.068528Z","end":"2026-05-28T00:36:51.219652Z","steps":["trace[813855209] 'process raft request' (duration: 146.868927ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:52.07026Z","caller":"traceutil/trace.go:171","msg":"trace[460427980] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"142.19071ms","start":"2026-05-28T00:36:51.928045Z","end":"2026-05-28T00:36:52.070236Z","steps":["trace[460427980] 'process raft request' (duration: 142.028315ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:52.095545Z","caller":"traceutil/trace.go:171","msg":"trace[1032794923] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"167.401535ms","start":"2026-05-28T00:36:51.928118Z","end":"2026-05-28T00:36:52.09552Z","steps":["trace[1032794923] 'process raft request' (duration: 166.962581ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:52.095879Z","caller":"traceutil/trace.go:171","msg":"trace[485684239] linearizableReadLoop","detail":"{readStateIndex:4944; appliedIndex:4942; }","duration":"146.201174ms","start":"2026-05-28T00:36:51.949659Z","end":"2026-05-28T00:36:52.09586Z","steps":["trace[485684239] 'read index received' (duration: 120.643818ms)","trace[485684239] 'applied index is now lower than readState.Index' (duration: 25.556706ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:36:52.095987Z","caller":"traceutil/trace.go:171","msg":"trace[854168287] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"150.096251ms","start":"2026-05-28T00:36:51.945882Z","end":"2026-05-28T00:36:52.095978Z","steps":["trace[854168287] 'process raft request' (duration: 149.351439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:36:52.09623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.549084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16557"} {"level":"info","ts":"2026-05-28T00:36:52.096294Z","caller":"traceutil/trace.go:171","msg":"trace[783579695] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4769; }","duration":"146.657696ms","start":"2026-05-28T00:36:51.949622Z","end":"2026-05-28T00:36:52.09628Z","steps":["trace[783579695] 'agreement among raft nodes before linearized reading' (duration: 146.448389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:36:52.096303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.653665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-28T00:36:52.096394Z","caller":"traceutil/trace.go:171","msg":"trace[1781709193] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4769; }","duration":"140.755378ms","start":"2026-05-28T00:36:51.955621Z","end":"2026-05-28T00:36:52.096376Z","steps":["trace[1781709193] 'agreement among raft nodes before linearized reading' (duration: 140.519131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:36:52.096524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.684422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-28T00:36:52.096571Z","caller":"traceutil/trace.go:171","msg":"trace[1272239061] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4769; }","duration":"123.757374ms","start":"2026-05-28T00:36:51.972799Z","end":"2026-05-28T00:36:52.096556Z","steps":["trace[1272239061] 'agreement among raft nodes before linearized reading' (duration: 123.65575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:36:52.096856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.18633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:36:52.09695Z","caller":"traceutil/trace.go:171","msg":"trace[364600115] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:4769; }","duration":"137.282493ms","start":"2026-05-28T00:36:51.959651Z","end":"2026-05-28T00:36:52.096934Z","steps":["trace[364600115] 'agreement among raft nodes before linearized reading' (duration: 137.157118ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:52.096299Z","caller":"traceutil/trace.go:171","msg":"trace[263705292] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"144.235424ms","start":"2026-05-28T00:36:51.952056Z","end":"2026-05-28T00:36:52.096291Z","steps":["trace[263705292] 'process raft request' (duration: 143.721258ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:52.204216Z","caller":"traceutil/trace.go:171","msg":"trace[751864983] linearizableReadLoop","detail":"{readStateIndex:4947; appliedIndex:4945; }","duration":"101.580413ms","start":"2026-05-28T00:36:52.102613Z","end":"2026-05-28T00:36:52.204193Z","steps":["trace[751864983] 'read index received' (duration: 21.333838ms)","trace[751864983] 'applied index is now lower than readState.Index' (duration: 80.245735ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:36:52.204224Z","caller":"traceutil/trace.go:171","msg":"trace[1948805395] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"102.43409ms","start":"2026-05-28T00:36:52.101763Z","end":"2026-05-28T00:36:52.204197Z","steps":["trace[1948805395] 'process raft request' (duration: 102.205272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:36:52.204588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.955395ms","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-05-28T00:36:52.204623Z","caller":"traceutil/trace.go:171","msg":"trace[364208824] 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:4773; }","duration":"102.014986ms","start":"2026-05-28T00:36:52.102597Z","end":"2026-05-28T00:36:52.204612Z","steps":["trace[364208824] 'agreement among raft nodes before linearized reading' (duration: 101.679166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:36:52.479091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.322026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:36:52.479702Z","caller":"traceutil/trace.go:171","msg":"trace[1523265487] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"241.632413ms","start":"2026-05-28T00:36:52.238039Z","end":"2026-05-28T00:36:52.479672Z","steps":["trace[1523265487] 'process raft request' (duration: 241.236802ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:52.47998Z","caller":"traceutil/trace.go:171","msg":"trace[235394885] linearizableReadLoop","detail":"{readStateIndex:4953; appliedIndex:4951; }","duration":"242.212201ms","start":"2026-05-28T00:36:52.237738Z","end":"2026-05-28T00:36:52.47995Z","steps":["trace[235394885] 'read index received' (duration: 49.909884ms)","trace[235394885] 'applied index is now lower than readState.Index' (duration: 192.300267ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:36:52.48001Z","caller":"traceutil/trace.go:171","msg":"trace[71594824] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"242.306193ms","start":"2026-05-28T00:36:52.237681Z","end":"2026-05-28T00:36:52.479988Z","steps":["trace[71594824] 'process raft request' (duration: 241.51447ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:52.480126Z","caller":"traceutil/trace.go:171","msg":"trace[1801344961] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"237.445006ms","start":"2026-05-28T00:36:52.242665Z","end":"2026-05-28T00:36:52.48011Z","steps":["trace[1801344961] 'process raft request' (duration: 236.732195ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:52.480284Z","caller":"traceutil/trace.go:171","msg":"trace[251496737] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"242.82708ms","start":"2026-05-28T00:36:52.237435Z","end":"2026-05-28T00:36:52.480262Z","steps":["trace[251496737] 'process raft request' (duration: 50.258366ms)","trace[251496737] 'compare' (duration: 191.216773ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:36:52.480397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.638154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-051a513e-6253-4dbc-a648-31c29495510b\" ","response":"range_response_count:1 size:3954"} {"level":"info","ts":"2026-05-28T00:36:52.480443Z","caller":"traceutil/trace.go:171","msg":"trace[1127889412] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-051a513e-6253-4dbc-a648-31c29495510b; range_end:; response_count:1; response_revision:4781; }","duration":"242.728596ms","start":"2026-05-28T00:36:52.237703Z","end":"2026-05-28T00:36:52.480432Z","steps":["trace[1127889412] 'agreement among raft nodes before linearized reading' (duration: 242.619543ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:52.479808Z","caller":"traceutil/trace.go:171","msg":"trace[1339886138] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"241.417027ms","start":"2026-05-28T00:36:52.238376Z","end":"2026-05-28T00:36:52.479793Z","steps":["trace[1339886138] 'process raft request' (duration: 240.948663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:36:52.480525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.786057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-28T00:36:52.480609Z","caller":"traceutil/trace.go:171","msg":"trace[189839949] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4781; }","duration":"124.914821ms","start":"2026-05-28T00:36:52.355675Z","end":"2026-05-28T00:36:52.48059Z","steps":["trace[189839949] 'agreement among raft nodes before linearized reading' (duration: 124.694715ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:53.162121Z","caller":"traceutil/trace.go:171","msg":"trace[1039441679] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"101.243761ms","start":"2026-05-28T00:36:53.060858Z","end":"2026-05-28T00:36:53.162102Z","steps":["trace[1039441679] 'process raft request' (duration: 101.098927ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:36:53.347249Z","caller":"traceutil/trace.go:171","msg":"trace[652225072] linearizableReadLoop","detail":"{readStateIndex:4974; appliedIndex:4973; }","duration":"106.550462ms","start":"2026-05-28T00:36:53.240672Z","end":"2026-05-28T00:36:53.347223Z","steps":["trace[652225072] 'read index received' (duration: 103.23088ms)","trace[652225072] 'applied index is now lower than readState.Index' (duration: 3.318462ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:36:53.347351Z","caller":"traceutil/trace.go:171","msg":"trace[304671793] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"176.399378ms","start":"2026-05-28T00:36:53.170924Z","end":"2026-05-28T00:36:53.347323Z","steps":["trace[304671793] 'process raft request' (duration: 173.100927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:36:53.347706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.021886ms","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-05-28T00:36:53.347802Z","caller":"traceutil/trace.go:171","msg":"trace[1402527401] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4796; }","duration":"107.166501ms","start":"2026-05-28T00:36:53.240621Z","end":"2026-05-28T00:36:53.347787Z","steps":["trace[1402527401] 'agreement among raft nodes before linearized reading' (duration: 106.871583ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:37:02.939644Z","caller":"traceutil/trace.go:171","msg":"trace[440980728] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"338.936677ms","start":"2026-05-28T00:37:02.600614Z","end":"2026-05-28T00:37:02.93955Z","steps":["trace[440980728] 'process raft request' (duration: 338.787042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:37:02.939819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.14737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:37:02.939859Z","caller":"traceutil/trace.go:171","msg":"trace[1626132091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4906; }","duration":"326.238292ms","start":"2026-05-28T00:37:02.613612Z","end":"2026-05-28T00:37:02.939851Z","steps":["trace[1626132091] 'agreement among raft nodes before linearized reading' (duration: 326.147439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:37:02.939884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:37:02.613595Z","time spent":"326.284243ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T00:37:02.939715Z","caller":"traceutil/trace.go:171","msg":"trace[1101656389] linearizableReadLoop","detail":"{readStateIndex:5088; appliedIndex:5088; }","duration":"326.017406ms","start":"2026-05-28T00:37:02.613667Z","end":"2026-05-28T00:37:02.939684Z","steps":["trace[1101656389] 'read index received' (duration: 325.997164ms)","trace[1101656389] 'applied index is now lower than readState.Index' (duration: 17.381µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:37:02.939882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:37:02.600596Z","time spent":"339.209356ms","remote":"127.0.0.1:46798","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-05-28T00:37:02.969833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.329166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"warn","ts":"2026-05-28T00:37:02.969853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.376739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-05-28T00:37:02.9699Z","caller":"traceutil/trace.go:171","msg":"trace[221939047] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4907; }","duration":"328.446521ms","start":"2026-05-28T00:37:02.641436Z","end":"2026-05-28T00:37:02.969883Z","steps":["trace[221939047] 'agreement among raft nodes before linearized reading' (duration: 328.255275ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:37:02.969916Z","caller":"traceutil/trace.go:171","msg":"trace[1828914787] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4907; }","duration":"328.467181ms","start":"2026-05-28T00:37:02.64143Z","end":"2026-05-28T00:37:02.969898Z","steps":["trace[1828914787] 'agreement among raft nodes before linearized reading' (duration: 328.279615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:37:02.969932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:37:02.641416Z","time spent":"328.510103ms","remote":"127.0.0.1:46682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1875,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-28T00:37:02.969947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:37:02.641422Z","time spent":"328.518672ms","remote":"127.0.0.1:60556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3338,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-05-28T00:37:02.970039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.908203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T00:37:02.970163Z","caller":"traceutil/trace.go:171","msg":"trace[532533316] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:4907; }","duration":"336.124629ms","start":"2026-05-28T00:37:02.634011Z","end":"2026-05-28T00:37:02.970135Z","steps":["trace[532533316] 'agreement among raft nodes before linearized reading' (duration: 335.82066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:37:02.970261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:37:02.633997Z","time spent":"336.239793ms","remote":"127.0.0.1:42756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":29,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-05-28T00:37:02.970766Z","caller":"traceutil/trace.go:171","msg":"trace[1454765748] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"280.133488ms","start":"2026-05-28T00:37:02.690586Z","end":"2026-05-28T00:37:02.970719Z","steps":["trace[1454765748] 'process raft request' (duration: 278.901589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:37:03.48694Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410686881019448,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T00:37:03.571337Z","caller":"traceutil/trace.go:171","msg":"trace[1584751717] linearizableReadLoop","detail":"{readStateIndex:5090; appliedIndex:5089; }","duration":"584.600294ms","start":"2026-05-28T00:37:02.986709Z","end":"2026-05-28T00:37:03.571309Z","steps":["trace[1584751717] 'read index received' (duration: 568.892115ms)","trace[1584751717] 'applied index is now lower than readState.Index' (duration: 15.707069ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:37:03.571469Z","caller":"traceutil/trace.go:171","msg":"trace[440427104] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"587.967109ms","start":"2026-05-28T00:37:02.983485Z","end":"2026-05-28T00:37:03.571452Z","steps":["trace[440427104] 'process raft request' (duration: 572.145756ms)","trace[440427104] 'compare' (duration: 15.200864ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:37:03.571618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:37:02.983472Z","time spent":"588.024901ms","remote":"127.0.0.1:46592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:37:03.571822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.424736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-28T00:37:03.57211Z","caller":"traceutil/trace.go:171","msg":"trace[368780009] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:4908; }","duration":"528.743356ms","start":"2026-05-28T00:37:03.043342Z","end":"2026-05-28T00:37:03.572085Z","steps":["trace[368780009] 'agreement among raft nodes before linearized reading' (duration: 528.296501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:37:03.572238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:37:03.043331Z","time spent":"528.856459ms","remote":"127.0.0.1:46548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-05-28T00:37:03.572407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.910156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-28T00:37:03.572458Z","caller":"traceutil/trace.go:171","msg":"trace[803858709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4908; }","duration":"179.987578ms","start":"2026-05-28T00:37:03.392459Z","end":"2026-05-28T00:37:03.572447Z","steps":["trace[803858709] 'agreement among raft nodes before linearized reading' (duration: 179.833534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:37:03.572611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.557528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-b5d5f76bc-pcvnn\" ","response":"range_response_count:1 size:11843"} {"level":"info","ts":"2026-05-28T00:37:03.572671Z","caller":"traceutil/trace.go:171","msg":"trace[176155819] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-b5d5f76bc-pcvnn; range_end:; response_count:1; response_revision:4908; }","duration":"182.672331ms","start":"2026-05-28T00:37:03.389981Z","end":"2026-05-28T00:37:03.572653Z","steps":["trace[176155819] 'agreement among raft nodes before linearized reading' (duration: 182.495686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:37:03.572677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.875491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-28T00:37:03.572679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.965788ms","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-05-28T00:37:03.572709Z","caller":"traceutil/trace.go:171","msg":"trace[727523561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4908; }","duration":"176.944973ms","start":"2026-05-28T00:37:03.395756Z","end":"2026-05-28T00:37:03.5727Z","steps":["trace[727523561] 'agreement among raft nodes before linearized reading' (duration: 176.858942ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:37:03.57273Z","caller":"traceutil/trace.go:171","msg":"trace[1559914143] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4908; }","duration":"586.02143ms","start":"2026-05-28T00:37:02.986696Z","end":"2026-05-28T00:37:03.572717Z","steps":["trace[1559914143] 'agreement among raft nodes before linearized reading' (duration: 585.860934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:37:03.572778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:37:02.986646Z","time spent":"586.121802ms","remote":"127.0.0.1:46986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9661,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-05-28T00:37:03.573118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.609378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:37:03.573161Z","caller":"traceutil/trace.go:171","msg":"trace[326035368] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4908; }","duration":"518.65357ms","start":"2026-05-28T00:37:03.054495Z","end":"2026-05-28T00:37:03.573149Z","steps":["trace[326035368] 'agreement among raft nodes before linearized reading' (duration: 518.586668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:37:03.573191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:37:03.054481Z","time spent":"518.702123ms","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-28T00:37:03.573633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.317984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-28T00:37:03.573705Z","caller":"traceutil/trace.go:171","msg":"trace[1645355912] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4908; }","duration":"495.447538ms","start":"2026-05-28T00:37:03.078242Z","end":"2026-05-28T00:37:03.57369Z","steps":["trace[1645355912] 'agreement among raft nodes before linearized reading' (duration: 494.636852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:37:03.573847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:37:03.07822Z","time spent":"495.613053ms","remote":"127.0.0.1:46682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-05-28T00:37:03.716726Z","caller":"traceutil/trace.go:171","msg":"trace[454409198] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"133.140518ms","start":"2026-05-28T00:37:03.583559Z","end":"2026-05-28T00:37:03.716699Z","steps":["trace[454409198] 'process raft request' (duration: 132.86002ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:37:03.716716Z","caller":"traceutil/trace.go:171","msg":"trace[1560262216] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"132.969072ms","start":"2026-05-28T00:37:03.583723Z","end":"2026-05-28T00:37:03.716692Z","steps":["trace[1560262216] 'process raft request' (duration: 132.850949ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:37:03.717259Z","caller":"traceutil/trace.go:171","msg":"trace[663314582] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"132.785587ms","start":"2026-05-28T00:37:03.584463Z","end":"2026-05-28T00:37:03.717249Z","steps":["trace[663314582] 'process raft request' (duration: 132.165578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:37:04.014274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.606405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:37:04.014375Z","caller":"traceutil/trace.go:171","msg":"trace[1314938137] linearizableReadLoop","detail":"{readStateIndex:5101; appliedIndex:5100; }","duration":"164.554928ms","start":"2026-05-28T00:37:03.849802Z","end":"2026-05-28T00:37:04.014356Z","steps":["trace[1314938137] 'read index received' (duration: 105.513µs)","trace[1314938137] 'applied index is now lower than readState.Index' (duration: 164.447955ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:37:04.014555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.784475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-28T00:37:04.014597Z","caller":"traceutil/trace.go:171","msg":"trace[1280451760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4918; }","duration":"164.888369ms","start":"2026-05-28T00:37:03.849696Z","end":"2026-05-28T00:37:04.014584Z","steps":["trace[1280451760] 'agreement among raft nodes before linearized reading' (duration: 164.725314ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:37:04.014806Z","caller":"traceutil/trace.go:171","msg":"trace[1978996387] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"227.680885ms","start":"2026-05-28T00:37:03.7871Z","end":"2026-05-28T00:37:04.01478Z","steps":["trace[1978996387] 'process raft request' (duration: 58.493503ms)","trace[1978996387] 'compare' (duration: 168.382578ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:37:04.31303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.068008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:37:04.31327Z","caller":"traceutil/trace.go:171","msg":"trace[2002886817] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"187.211697ms","start":"2026-05-28T00:37:04.126024Z","end":"2026-05-28T00:37:04.313236Z","steps":["trace[2002886817] 'process raft request' (duration: 43.862641ms)","trace[2002886817] 'compare' (duration: 142.761238ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:37:04.31349Z","caller":"traceutil/trace.go:171","msg":"trace[1861448970] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"142.701087ms","start":"2026-05-28T00:37:04.170743Z","end":"2026-05-28T00:37:04.313444Z","steps":["trace[1861448970] 'process raft request' (duration: 142.564372ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:37:04.313487Z","caller":"traceutil/trace.go:171","msg":"trace[679484423] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"143.572013ms","start":"2026-05-28T00:37:04.16989Z","end":"2026-05-28T00:37:04.313462Z","steps":["trace[679484423] 'process raft request' (duration: 143.230893ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:37:04.31425Z","caller":"traceutil/trace.go:171","msg":"trace[1894176176] linearizableReadLoop","detail":"{readStateIndex:5106; appliedIndex:5102; }","duration":"137.185514ms","start":"2026-05-28T00:37:04.177048Z","end":"2026-05-28T00:37:04.314233Z","steps":["trace[1894176176] 'read index received' (duration: 6.624747ms)","trace[1894176176] 'applied index is now lower than readState.Index' (duration: 130.559727ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:37:04.314366Z","caller":"traceutil/trace.go:171","msg":"trace[60317779] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"141.828209ms","start":"2026-05-28T00:37:04.172524Z","end":"2026-05-28T00:37:04.314353Z","steps":["trace[60317779] 'process raft request' (duration: 140.885899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:37:04.314576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.520804ms","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-05-28T00:37:04.314611Z","caller":"traceutil/trace.go:171","msg":"trace[217565374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4924; }","duration":"137.584576ms","start":"2026-05-28T00:37:04.177018Z","end":"2026-05-28T00:37:04.314602Z","steps":["trace[217565374] 'agreement among raft nodes before linearized reading' (duration: 137.433142ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:37:21.738089Z","caller":"traceutil/trace.go:171","msg":"trace[679340053] linearizableReadLoop","detail":"{readStateIndex:5229; appliedIndex:5228; }","duration":"125.117596ms","start":"2026-05-28T00:37:21.612952Z","end":"2026-05-28T00:37:21.738069Z","steps":["trace[679340053] 'read index received' (duration: 124.962232ms)","trace[679340053] 'applied index is now lower than readState.Index' (duration: 154.404µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:37:21.73813Z","caller":"traceutil/trace.go:171","msg":"trace[1223004742] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"155.594091ms","start":"2026-05-28T00:37:21.582516Z","end":"2026-05-28T00:37:21.73811Z","steps":["trace[1223004742] 'process raft request' (duration: 155.409126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:37:21.738198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.233191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:37:21.738417Z","caller":"traceutil/trace.go:171","msg":"trace[917895179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5043; }","duration":"125.483398ms","start":"2026-05-28T00:37:21.612916Z","end":"2026-05-28T00:37:21.7384Z","steps":["trace[917895179] 'agreement among raft nodes before linearized reading' (duration: 125.23647ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:37:49.829793Z","caller":"traceutil/trace.go:171","msg":"trace[2142990845] transaction","detail":"{read_only:false; response_revision:5307; number_of_response:1; }","duration":"143.446919ms","start":"2026-05-28T00:37:49.686319Z","end":"2026-05-28T00:37:49.829766Z","steps":["trace[2142990845] 'process raft request' (duration: 143.17414ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:00.777121Z","caller":"traceutil/trace.go:171","msg":"trace[760095810] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"116.272739ms","start":"2026-05-28T00:38:00.660807Z","end":"2026-05-28T00:38:00.77708Z","steps":["trace[760095810] 'process raft request' (duration: 96.284771ms)","trace[760095810] 'compare' (duration: 19.838902ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:00.954981Z","caller":"traceutil/trace.go:171","msg":"trace[1973293980] linearizableReadLoop","detail":"{readStateIndex:5590; appliedIndex:5588; }","duration":"106.667058ms","start":"2026-05-28T00:38:00.848294Z","end":"2026-05-28T00:38:00.954961Z","steps":["trace[1973293980] 'read index received' (duration: 58.197301ms)","trace[1973293980] 'applied index is now lower than readState.Index' (duration: 48.468857ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:00.955014Z","caller":"traceutil/trace.go:171","msg":"trace[710038069] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"111.436642ms","start":"2026-05-28T00:38:00.843548Z","end":"2026-05-28T00:38:00.954985Z","steps":["trace[710038069] 'process raft request' (duration: 62.78856ms)","trace[710038069] 'compare' (duration: 48.473056ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:00.95506Z","caller":"traceutil/trace.go:171","msg":"trace[366835941] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"110.523701ms","start":"2026-05-28T00:38:00.844509Z","end":"2026-05-28T00:38:00.955033Z","steps":["trace[366835941] 'process raft request' (duration: 110.420627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:00.955209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.881245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:38:00.9553Z","caller":"traceutil/trace.go:171","msg":"trace[1495473257] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5394; }","duration":"107.040461ms","start":"2026-05-28T00:38:00.84824Z","end":"2026-05-28T00:38:00.95528Z","steps":["trace[1495473257] 'agreement among raft nodes before linearized reading' (duration: 106.831024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:38:00.955526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.888392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-28T00:38:00.95563Z","caller":"traceutil/trace.go:171","msg":"trace[867931709] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5394; }","duration":"103.978056ms","start":"2026-05-28T00:38:00.851576Z","end":"2026-05-28T00:38:00.955555Z","steps":["trace[867931709] 'agreement among raft nodes before linearized reading' (duration: 103.669855ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:38:29.060143Z","caller":"traceutil/trace.go:171","msg":"trace[736122907] transaction","detail":"{read_only:false; response_revision:5593; number_of_response:1; }","duration":"171.92073ms","start":"2026-05-28T00:38:28.888156Z","end":"2026-05-28T00:38:29.060077Z","steps":["trace[736122907] 'process raft request' (duration: 108.192124ms)","trace[736122907] 'compare' (duration: 63.586251ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:38:29.931215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2908} {"level":"info","ts":"2026-05-28T00:38:30.005786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2908,"took":"73.36038ms","hash":3123649535,"current-db-size-bytes":54013952,"current-db-size":"54 MB","current-db-size-in-use-bytes":29134848,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-28T00:38:30.005869Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3123649535,"revision":2908,"compact-revision":-1} {"level":"info","ts":"2026-05-28T00:39:03.378795Z","caller":"traceutil/trace.go:171","msg":"trace[1091599738] transaction","detail":"{read_only:false; response_revision:5819; number_of_response:1; }","duration":"111.08786ms","start":"2026-05-28T00:39:03.267679Z","end":"2026-05-28T00:39:03.378767Z","steps":["trace[1091599738] 'process raft request' (duration: 87.07121ms)","trace[1091599738] 'compare' (duration: 23.846223ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:43:29.936216Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5600} {"level":"info","ts":"2026-05-28T00:43:30.052169Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5600,"took":"114.450285ms","hash":3670326451,"current-db-size-bytes":54013952,"current-db-size":"54 MB","current-db-size-in-use-bytes":18362368,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-28T00:43:30.052234Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3670326451,"revision":5600,"compact-revision":2908}