{"level":"warn","ts":"2026-05-07T13:14:17.488897Z","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-07T13:14:17.488971Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.154:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.154:2380","--initial-cluster=instance=https://199.19.213.154:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.154:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.154: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-07T13:14:17.489082Z","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-07T13:14:17.489098Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.154:2380"]} {"level":"info","ts":"2026-05-07T13:14:17.48913Z","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-07T13:14:17.489762Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.154:2379"]} {"level":"info","ts":"2026-05-07T13:14:17.489886Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.154:2380"],"listen-peer-urls":["https://199.19.213.154:2380"],"advertise-client-urls":["https://199.19.213.154:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.154:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.154: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-07T13:14:17.494052Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.928171ms"} {"level":"info","ts":"2026-05-07T13:14:17.502157Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f4aebb100319a62d","cluster-id":"23a505f69026554e"} {"level":"info","ts":"2026-05-07T13:14:17.502225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T13:14:17.502299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became follower at term 0"} {"level":"info","ts":"2026-05-07T13:14:17.502318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f4aebb100319a62d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T13:14:17.502329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became follower at term 1"} {"level":"info","ts":"2026-05-07T13:14:17.502444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d switched to configuration voters=(17631235268647953965)"} {"level":"warn","ts":"2026-05-07T13:14:17.508258Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T13:14:17.50973Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T13:14:17.510903Z","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-07T13:14:17.513013Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f4aebb100319a62d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T13:14:17.513076Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f4aebb100319a62d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T13:14:17.513406Z","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-07T13:14:17.513553Z","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-07T13:14:17.513583Z","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-07T13:14:17.514987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d switched to configuration voters=(17631235268647953965)"} {"level":"info","ts":"2026-05-07T13:14:17.515228Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"23a505f69026554e","local-member-id":"f4aebb100319a62d","added-peer-id":"f4aebb100319a62d","added-peer-peer-urls":["https://199.19.213.154:2380"]} {"level":"info","ts":"2026-05-07T13:14:17.523313Z","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-07T13:14:17.523493Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.154:2380"} {"level":"info","ts":"2026-05-07T13:14:17.523567Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.154:2380"} {"level":"info","ts":"2026-05-07T13:14:17.523555Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f4aebb100319a62d","initial-advertise-peer-urls":["https://199.19.213.154:2380"],"listen-peer-urls":["https://199.19.213.154:2380"],"advertise-client-urls":["https://199.19.213.154:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.154:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T13:14:17.523596Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T13:14:17.903071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T13:14:17.903237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T13:14:17.903345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d received MsgPreVoteResp from f4aebb100319a62d at term 1"} {"level":"info","ts":"2026-05-07T13:14:17.903496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became candidate at term 2"} {"level":"info","ts":"2026-05-07T13:14:17.903612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d received MsgVoteResp from f4aebb100319a62d at term 2"} {"level":"info","ts":"2026-05-07T13:14:17.903688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became leader at term 2"} {"level":"info","ts":"2026-05-07T13:14:17.903788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f4aebb100319a62d elected leader f4aebb100319a62d at term 2"} {"level":"info","ts":"2026-05-07T13:14:17.911976Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f4aebb100319a62d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.154:2379]}","request-path":"/0/members/f4aebb100319a62d/attributes","cluster-id":"23a505f69026554e","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T13:14:17.912005Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:14:17.912252Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T13:14:17.912299Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T13:14:17.912707Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T13:14:17.912736Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T13:14:17.915606Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T13:14:17.917132Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.154:2379"} {"level":"info","ts":"2026-05-07T13:14:17.920909Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"23a505f69026554e","local-member-id":"f4aebb100319a62d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:14:17.921017Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:14:17.921057Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-07T13:14:19.956311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.950095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T13:14:19.956406Z","caller":"traceutil/trace.go:171","msg":"trace[1395575892] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:74; }","duration":"234.146721ms","start":"2026-05-07T13:14:19.72224Z","end":"2026-05-07T13:14:19.956387Z","steps":["trace[1395575892] 'range keys from in-memory index tree' (duration: 233.848953ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:14:20.147807Z","caller":"traceutil/trace.go:171","msg":"trace[135777062] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"188.537083ms","start":"2026-05-07T13:14:19.959243Z","end":"2026-05-07T13:14:20.14778Z","steps":["trace[135777062] 'process raft request' (duration: 188.390409ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:14:46.388582Z","caller":"traceutil/trace.go:171","msg":"trace[1987403822] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"180.501538ms","start":"2026-05-07T13:14:46.208056Z","end":"2026-05-07T13:14:46.388557Z","steps":["trace[1987403822] 'process raft request' (duration: 180.339794ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:14:46.397036Z","caller":"traceutil/trace.go:171","msg":"trace[1262243282] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"182.361136ms","start":"2026-05-07T13:14:46.214637Z","end":"2026-05-07T13:14:46.396999Z","steps":["trace[1262243282] 'process raft request' (duration: 182.180103ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:14:46.397131Z","caller":"traceutil/trace.go:171","msg":"trace[1868005764] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"150.216168ms","start":"2026-05-07T13:14:46.246885Z","end":"2026-05-07T13:14:46.397101Z","steps":["trace[1868005764] 'process raft request' (duration: 150.014794ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:14:46.39715Z","caller":"traceutil/trace.go:171","msg":"trace[164316081] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"146.885985ms","start":"2026-05-07T13:14:46.250229Z","end":"2026-05-07T13:14:46.397115Z","steps":["trace[164316081] 'process raft request' (duration: 146.69658ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:14:46.397247Z","caller":"traceutil/trace.go:171","msg":"trace[816506723] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"146.228081ms","start":"2026-05-07T13:14:46.251013Z","end":"2026-05-07T13:14:46.397241Z","steps":["trace[816506723] 'process raft request' (duration: 145.945574ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:14:46.39709Z","caller":"traceutil/trace.go:171","msg":"trace[1230216142] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"158.435927ms","start":"2026-05-07T13:14:46.23863Z","end":"2026-05-07T13:14:46.397066Z","steps":["trace[1230216142] 'process raft request' (duration: 158.215012ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:14:46.39707Z","caller":"traceutil/trace.go:171","msg":"trace[1065995945] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"150.979043ms","start":"2026-05-07T13:14:46.246069Z","end":"2026-05-07T13:14:46.397048Z","steps":["trace[1065995945] 'process raft request' (duration: 150.80358ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:14:46.397137Z","caller":"traceutil/trace.go:171","msg":"trace[1210710989] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"183.140944ms","start":"2026-05-07T13:14:46.213989Z","end":"2026-05-07T13:14:46.39713Z","steps":["trace[1210710989] 'process raft request' (duration: 182.736846ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:14:46.398298Z","caller":"traceutil/trace.go:171","msg":"trace[633804977] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"147.08178ms","start":"2026-05-07T13:14:46.251202Z","end":"2026-05-07T13:14:46.398284Z","steps":["trace[633804977] 'process raft request' (duration: 146.239333ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:14:46.398446Z","caller":"traceutil/trace.go:171","msg":"trace[1443141522] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"136.626713ms","start":"2026-05-07T13:14:46.261808Z","end":"2026-05-07T13:14:46.398435Z","steps":["trace[1443141522] 'process raft request' (duration: 136.473519ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:14:46.398536Z","caller":"traceutil/trace.go:171","msg":"trace[1619655189] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"118.617311ms","start":"2026-05-07T13:14:46.279906Z","end":"2026-05-07T13:14:46.398524Z","steps":["trace[1619655189] 'process raft request' (duration: 118.455637ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:14:52.468523Z","caller":"traceutil/trace.go:171","msg":"trace[2025072107] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"154.969247ms","start":"2026-05-07T13:14:52.313515Z","end":"2026-05-07T13:14:52.468484Z","steps":["trace[2025072107] 'process raft request' (duration: 149.235602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:14:52.90293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.912544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:14:52.903133Z","caller":"traceutil/trace.go:171","msg":"trace[2146269447] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"379.075376ms","start":"2026-05-07T13:14:52.523999Z","end":"2026-05-07T13:14:52.903075Z","steps":["trace[2146269447] 'process raft request' (duration: 36.517828ms)","trace[2146269447] 'compare' (duration: 341.71074ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:14:52.903319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:14:52.523975Z","time spent":"379.246941ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:14:53.590673Z","caller":"traceutil/trace.go:171","msg":"trace[1745829334] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"105.867276ms","start":"2026-05-07T13:14:53.484766Z","end":"2026-05-07T13:14:53.590633Z","steps":["trace[1745829334] 'read index received' (duration: 105.065409ms)","trace[1745829334] 'applied index is now lower than readState.Index' (duration: 800.757µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:14:53.590795Z","caller":"traceutil/trace.go:171","msg":"trace[1605575232] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"288.113553ms","start":"2026-05-07T13:14:53.302626Z","end":"2026-05-07T13:14:53.590739Z","steps":["trace[1605575232] 'process raft request' (duration: 287.375367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:14:53.591013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.565938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T13:14:53.591063Z","caller":"traceutil/trace.go:171","msg":"trace[1358148723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"104.6543ms","start":"2026-05-07T13:14:53.486395Z","end":"2026-05-07T13:14:53.591049Z","steps":["trace[1358148723] 'agreement among raft nodes before linearized reading' (duration: 104.458555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:14:53.591583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.818707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T13:14:53.591623Z","caller":"traceutil/trace.go:171","msg":"trace[1573038219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"106.897159ms","start":"2026-05-07T13:14:53.484716Z","end":"2026-05-07T13:14:53.591613Z","steps":["trace[1573038219] 'agreement among raft nodes before linearized reading' (duration: 106.0747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:15:08.850533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.299297ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:15:08.850665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.106684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:15:08.850967Z","caller":"traceutil/trace.go:171","msg":"trace[162749099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"117.444121ms","start":"2026-05-07T13:15:08.7335Z","end":"2026-05-07T13:15:08.850944Z","steps":["trace[162749099] 'range keys from in-memory index tree' (duration: 117.031672ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:15:08.850995Z","caller":"traceutil/trace.go:171","msg":"trace[1803305872] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:776; }","duration":"330.748016ms","start":"2026-05-07T13:15:08.520174Z","end":"2026-05-07T13:15:08.850923Z","steps":["trace[1803305872] 'range keys from in-memory index tree' (duration: 330.269316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:15:52.009367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.26312ms","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-07T13:15:52.009667Z","caller":"traceutil/trace.go:171","msg":"trace[769423822] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1315; }","duration":"108.601487ms","start":"2026-05-07T13:15:51.901042Z","end":"2026-05-07T13:15:52.009643Z","steps":["trace[769423822] 'range keys from in-memory index tree' (duration: 108.062985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:15:52.009835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.427805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T13:15:52.009925Z","caller":"traceutil/trace.go:171","msg":"trace[508939343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1315; }","duration":"102.530747ms","start":"2026-05-07T13:15:51.907373Z","end":"2026-05-07T13:15:52.009904Z","steps":["trace[508939343] 'range keys from in-memory index tree' (duration: 102.23729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:15:52.010061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.944205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T13:15:52.010196Z","caller":"traceutil/trace.go:171","msg":"trace[1417478873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1315; }","duration":"103.11991ms","start":"2026-05-07T13:15:51.907064Z","end":"2026-05-07T13:15:52.010184Z","steps":["trace[1417478873] 'range keys from in-memory index tree' (duration: 102.69268ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:22.459377Z","caller":"traceutil/trace.go:171","msg":"trace[1529429833] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1922; }","duration":"282.5495ms","start":"2026-05-07T13:16:22.176799Z","end":"2026-05-07T13:16:22.459349Z","steps":["trace[1529429833] 'read index received' (duration: 282.300434ms)","trace[1529429833] 'applied index is now lower than readState.Index' (duration: 246.235µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:22.459624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.491628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T13:16:22.459668Z","caller":"traceutil/trace.go:171","msg":"trace[1590783410] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1851; }","duration":"270.558389ms","start":"2026-05-07T13:16:22.189099Z","end":"2026-05-07T13:16:22.459658Z","steps":["trace[1590783410] 'agreement among raft nodes before linearized reading' (duration: 270.426087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:22.459792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.796356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-07T13:16:22.459831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.892304ms","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-07T13:16:22.45986Z","caller":"traceutil/trace.go:171","msg":"trace[1052483158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1851; }","duration":"283.089622ms","start":"2026-05-07T13:16:22.176754Z","end":"2026-05-07T13:16:22.459844Z","steps":["trace[1052483158] 'agreement among raft nodes before linearized reading' (duration: 282.740185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:22.459875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.523775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T13:16:22.4599Z","caller":"traceutil/trace.go:171","msg":"trace[858773252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1851; }","duration":"262.551655ms","start":"2026-05-07T13:16:22.197343Z","end":"2026-05-07T13:16:22.459895Z","steps":["trace[858773252] 'agreement among raft nodes before linearized reading' (duration: 262.486264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:22.459902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.893849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-76081cf5-c892-4746-aa56-eeb629a0d5b3\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-07T13:16:22.459923Z","caller":"traceutil/trace.go:171","msg":"trace[541218173] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1851; }","duration":"131.019978ms","start":"2026-05-07T13:16:22.328887Z","end":"2026-05-07T13:16:22.459907Z","steps":["trace[541218173] 'agreement among raft nodes before linearized reading' (duration: 130.803073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:22.459816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.120146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-07T13:16:22.459954Z","caller":"traceutil/trace.go:171","msg":"trace[1122543239] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-76081cf5-c892-4746-aa56-eeb629a0d5b3; range_end:; response_count:1; response_revision:1851; }","duration":"156.95706ms","start":"2026-05-07T13:16:22.302981Z","end":"2026-05-07T13:16:22.459938Z","steps":["trace[1122543239] 'agreement among raft nodes before linearized reading' (duration: 156.859438ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:22.459963Z","caller":"traceutil/trace.go:171","msg":"trace[465165433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1851; }","duration":"257.301691ms","start":"2026-05-07T13:16:22.202656Z","end":"2026-05-07T13:16:22.459957Z","steps":["trace[465165433] 'agreement among raft nodes before linearized reading' (duration: 257.099946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:22.459795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.417242ms","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-07T13:16:22.460169Z","caller":"traceutil/trace.go:171","msg":"trace[1086974192] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1851; }","duration":"187.838133ms","start":"2026-05-07T13:16:22.272316Z","end":"2026-05-07T13:16:22.460154Z","steps":["trace[1086974192] 'agreement among raft nodes before linearized reading' (duration: 187.316181ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:22.459541Z","caller":"traceutil/trace.go:171","msg":"trace[1991515017] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"338.847593ms","start":"2026-05-07T13:16:22.120659Z","end":"2026-05-07T13:16:22.459507Z","steps":["trace[1991515017] 'process raft request' (duration: 338.557296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:22.460643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:22.120638Z","time spent":"339.735172ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:16:22.606842Z","caller":"traceutil/trace.go:171","msg":"trace[900001418] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"142.064108ms","start":"2026-05-07T13:16:22.464752Z","end":"2026-05-07T13:16:22.606816Z","steps":["trace[900001418] 'process raft request' (duration: 137.338814ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:22.607123Z","caller":"traceutil/trace.go:171","msg":"trace[187777660] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"142.162969ms","start":"2026-05-07T13:16:22.46494Z","end":"2026-05-07T13:16:22.607103Z","steps":["trace[187777660] 'process raft request' (duration: 141.830842ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:22.607479Z","caller":"traceutil/trace.go:171","msg":"trace[960621611] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"142.465695ms","start":"2026-05-07T13:16:22.464992Z","end":"2026-05-07T13:16:22.607458Z","steps":["trace[960621611] 'process raft request' (duration: 142.007465ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:22.607817Z","caller":"traceutil/trace.go:171","msg":"trace[1831870044] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"139.203755ms","start":"2026-05-07T13:16:22.468597Z","end":"2026-05-07T13:16:22.607801Z","steps":["trace[1831870044] 'process raft request' (duration: 138.779376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:22.939623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.718371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:16:22.939772Z","caller":"traceutil/trace.go:171","msg":"trace[425595700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1856; }","duration":"206.913866ms","start":"2026-05-07T13:16:22.732835Z","end":"2026-05-07T13:16:22.939749Z","steps":["trace[425595700] 'range keys from in-memory index tree' (duration: 206.567528ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:33.487191Z","caller":"traceutil/trace.go:171","msg":"trace[2028056870] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"114.339773ms","start":"2026-05-07T13:16:33.372828Z","end":"2026-05-07T13:16:33.487168Z","steps":["trace[2028056870] 'process raft request' (duration: 114.20605ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:33.54892Z","caller":"traceutil/trace.go:171","msg":"trace[1141584703] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"170.820476ms","start":"2026-05-07T13:16:33.378073Z","end":"2026-05-07T13:16:33.548893Z","steps":["trace[1141584703] 'process raft request' (duration: 170.727283ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:36.965056Z","caller":"traceutil/trace.go:171","msg":"trace[1907313717] linearizableReadLoop","detail":"{readStateIndex:2044; appliedIndex:2043; }","duration":"294.580259ms","start":"2026-05-07T13:16:36.670454Z","end":"2026-05-07T13:16:36.965034Z","steps":["trace[1907313717] 'read index received' (duration: 294.437616ms)","trace[1907313717] 'applied index is now lower than readState.Index' (duration: 141.753µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:36.965108Z","caller":"traceutil/trace.go:171","msg":"trace[1000222431] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"294.974349ms","start":"2026-05-07T13:16:36.670108Z","end":"2026-05-07T13:16:36.965082Z","steps":["trace[1000222431] 'process raft request' (duration: 294.780905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:36.96529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.84272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:16:36.96534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.858606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T13:16:36.965362Z","caller":"traceutil/trace.go:171","msg":"trace[1718223281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1968; }","duration":"231.951772ms","start":"2026-05-07T13:16:36.733388Z","end":"2026-05-07T13:16:36.96534Z","steps":["trace[1718223281] 'agreement among raft nodes before linearized reading' (duration: 231.84319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:36.965361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.708453ms","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-07T13:16:36.965418Z","caller":"traceutil/trace.go:171","msg":"trace[76418404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1968; }","duration":"294.981848ms","start":"2026-05-07T13:16:36.67042Z","end":"2026-05-07T13:16:36.965402Z","steps":["trace[76418404] 'agreement among raft nodes before linearized reading' (duration: 294.727822ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:36.965485Z","caller":"traceutil/trace.go:171","msg":"trace[1535397307] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1968; }","duration":"294.824386ms","start":"2026-05-07T13:16:36.67062Z","end":"2026-05-07T13:16:36.965444Z","steps":["trace[1535397307] 'agreement among raft nodes before linearized reading' (duration: 294.621141ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:55.206777Z","caller":"traceutil/trace.go:171","msg":"trace[1955762287] linearizableReadLoop","detail":"{readStateIndex:2159; appliedIndex:2158; }","duration":"145.342897ms","start":"2026-05-07T13:16:55.061416Z","end":"2026-05-07T13:16:55.206759Z","steps":["trace[1955762287] 'read index received' (duration: 145.211535ms)","trace[1955762287] 'applied index is now lower than readState.Index' (duration: 130.602µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:55.206857Z","caller":"traceutil/trace.go:171","msg":"trace[1295496379] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"154.450586ms","start":"2026-05-07T13:16:55.052371Z","end":"2026-05-07T13:16:55.206822Z","steps":["trace[1295496379] 'process raft request' (duration: 154.243712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:55.20698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.554722ms","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-07T13:16:55.207009Z","caller":"traceutil/trace.go:171","msg":"trace[1366477012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2080; }","duration":"145.640823ms","start":"2026-05-07T13:16:55.061362Z","end":"2026-05-07T13:16:55.207002Z","steps":["trace[1366477012] 'agreement among raft nodes before linearized reading' (duration: 145.50102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:55.207597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.732318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T13:16:55.207666Z","caller":"traceutil/trace.go:171","msg":"trace[600860463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2080; }","duration":"115.851201ms","start":"2026-05-07T13:16:55.091798Z","end":"2026-05-07T13:16:55.207649Z","steps":["trace[600860463] 'agreement among raft nodes before linearized reading' (duration: 115.239667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:55.69137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.12463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:16:55.691647Z","caller":"traceutil/trace.go:171","msg":"trace[640121936] linearizableReadLoop","detail":"{readStateIndex:2165; appliedIndex:2163; }","duration":"268.973428ms","start":"2026-05-07T13:16:55.422657Z","end":"2026-05-07T13:16:55.69163Z","steps":["trace[640121936] 'read index received' (duration: 72.52962ms)","trace[640121936] 'applied index is now lower than readState.Index' (duration: 196.433498ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:55.691736Z","caller":"traceutil/trace.go:171","msg":"trace[1121377335] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"282.243819ms","start":"2026-05-07T13:16:55.409479Z","end":"2026-05-07T13:16:55.691722Z","steps":["trace[1121377335] 'process raft request' (duration: 281.997614ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:55.691746Z","caller":"traceutil/trace.go:171","msg":"trace[2084252820] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"298.896033ms","start":"2026-05-07T13:16:55.392828Z","end":"2026-05-07T13:16:55.691724Z","steps":["trace[2084252820] 'process raft request' (duration: 102.339254ms)","trace[2084252820] 'compare' (duration: 195.821924ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:55.692026Z","caller":"traceutil/trace.go:171","msg":"trace[770080572] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"137.150858ms","start":"2026-05-07T13:16:55.554868Z","end":"2026-05-07T13:16:55.692018Z","steps":["trace[770080572] 'process raft request' (duration: 136.715829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:55.692379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.711153ms","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-07T13:16:55.69241Z","caller":"traceutil/trace.go:171","msg":"trace[250411283] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2088; }","duration":"269.779595ms","start":"2026-05-07T13:16:55.422624Z","end":"2026-05-07T13:16:55.692404Z","steps":["trace[250411283] 'agreement among raft nodes before linearized reading' (duration: 269.068589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:56.121161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.944056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:16:56.121303Z","caller":"traceutil/trace.go:171","msg":"trace[795383766] linearizableReadLoop","detail":"{readStateIndex:2169; appliedIndex:2168; }","duration":"390.696847ms","start":"2026-05-07T13:16:55.730554Z","end":"2026-05-07T13:16:56.121251Z","steps":["trace[795383766] 'read index received' (duration: 133.17094ms)","trace[795383766] 'applied index is now lower than readState.Index' (duration: 257.524597ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:56.121394Z","caller":"traceutil/trace.go:171","msg":"trace[75563381] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"394.599374ms","start":"2026-05-07T13:16:55.726782Z","end":"2026-05-07T13:16:56.121381Z","steps":["trace[75563381] 'process raft request' (duration: 137.346622ms)","trace[75563381] 'compare' (duration: 256.798322ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:56.121546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:55.726754Z","time spent":"394.755017ms","remote":"127.0.0.1:46860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:16:56.12162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.043675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:16:56.121704Z","caller":"traceutil/trace.go:171","msg":"trace[2055025592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2090; }","duration":"391.156748ms","start":"2026-05-07T13:16:55.730524Z","end":"2026-05-07T13:16:56.121681Z","steps":["trace[2055025592] 'agreement among raft nodes before linearized reading' (duration: 391.016574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:56.12175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:55.730514Z","time spent":"391.22758ms","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:16:56.121747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.24618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T13:16:56.121833Z","caller":"traceutil/trace.go:171","msg":"trace[1676643220] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2090; }","duration":"261.385313ms","start":"2026-05-07T13:16:55.860426Z","end":"2026-05-07T13:16:56.121812Z","steps":["trace[1676643220] 'agreement among raft nodes before linearized reading' (duration: 261.179669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:56.121918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.703851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-05-07T13:16:56.122005Z","caller":"traceutil/trace.go:171","msg":"trace[1704134913] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2090; }","duration":"355.833754ms","start":"2026-05-07T13:16:55.766156Z","end":"2026-05-07T13:16:56.121989Z","steps":["trace[1704134913] 'agreement among raft nodes before linearized reading' (duration: 355.308892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:56.122066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:55.766137Z","time spent":"355.918005ms","remote":"127.0.0.1:46870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12409,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-07T13:16:56.738557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.98119ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-07T13:16:56.73864Z","caller":"traceutil/trace.go:171","msg":"trace[2057127125] linearizableReadLoop","detail":"{readStateIndex:2170; appliedIndex:2169; }","duration":"402.750344ms","start":"2026-05-07T13:16:56.335876Z","end":"2026-05-07T13:16:56.738626Z","steps":["trace[2057127125] 'read index received' (duration: 154.708953ms)","trace[2057127125] 'applied index is now lower than readState.Index' (duration: 248.040361ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:56.738774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.868476ms","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-07T13:16:56.738878Z","caller":"traceutil/trace.go:171","msg":"trace[656376206] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2090; }","duration":"403.032639ms","start":"2026-05-07T13:16:56.33582Z","end":"2026-05-07T13:16:56.738853Z","steps":["trace[656376206] 'agreement among raft nodes before linearized reading' (duration: 402.874876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:56.738942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:56.335796Z","time spent":"403.129441ms","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:16:56.738978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.27867ms","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-07T13:16:56.739053Z","caller":"traceutil/trace.go:171","msg":"trace[293086668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2090; }","duration":"116.397132ms","start":"2026-05-07T13:16:56.622599Z","end":"2026-05-07T13:16:56.738996Z","steps":["trace[293086668] 'agreement among raft nodes before linearized reading' (duration: 116.195338ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:53.344451Z","caller":"traceutil/trace.go:171","msg":"trace[2037947341] linearizableReadLoop","detail":"{readStateIndex:2592; appliedIndex:2591; }","duration":"309.382009ms","start":"2026-05-07T13:17:53.035046Z","end":"2026-05-07T13:17:53.344428Z","steps":["trace[2037947341] 'read index received' (duration: 309.249106ms)","trace[2037947341] 'applied index is now lower than readState.Index' (duration: 131.893µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:17:53.344575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.517141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T13:17:53.344611Z","caller":"traceutil/trace.go:171","msg":"trace[1037050664] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2499; }","duration":"309.572582ms","start":"2026-05-07T13:17:53.035026Z","end":"2026-05-07T13:17:53.344599Z","steps":["trace[1037050664] 'agreement among raft nodes before linearized reading' (duration: 309.49603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:53.344664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:17:53.034964Z","time spent":"309.689325ms","remote":"127.0.0.1:44286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-07T13:17:53.344687Z","caller":"traceutil/trace.go:171","msg":"trace[230281572] transaction","detail":"{read_only:false; response_revision:2499; number_of_response:1; }","duration":"333.043861ms","start":"2026-05-07T13:17:53.011614Z","end":"2026-05-07T13:17:53.344657Z","steps":["trace[230281572] 'process raft request' (duration: 332.683954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:53.344816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:17:53.011595Z","time spent":"333.152743ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:18:25.927159Z","caller":"traceutil/trace.go:171","msg":"trace[891379310] linearizableReadLoop","detail":"{readStateIndex:2781; appliedIndex:2778; }","duration":"194.990699ms","start":"2026-05-07T13:18:25.732143Z","end":"2026-05-07T13:18:25.927134Z","steps":["trace[891379310] 'read index received' (duration: 175.209091ms)","trace[891379310] 'applied index is now lower than readState.Index' (duration: 19.780548ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:18:25.927303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.149872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:18:25.927343Z","caller":"traceutil/trace.go:171","msg":"trace[1978907767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2682; }","duration":"195.265554ms","start":"2026-05-07T13:18:25.732066Z","end":"2026-05-07T13:18:25.927332Z","steps":["trace[1978907767] 'agreement among raft nodes before linearized reading' (duration: 195.174672ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:18:25.927493Z","caller":"traceutil/trace.go:171","msg":"trace[946877395] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"225.100046ms","start":"2026-05-07T13:18:25.702378Z","end":"2026-05-07T13:18:25.927478Z","steps":["trace[946877395] 'process raft request' (duration: 224.641456ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:18:25.927665Z","caller":"traceutil/trace.go:171","msg":"trace[1209290889] transaction","detail":"{read_only:false; response_revision:2682; number_of_response:1; }","duration":"148.95987ms","start":"2026-05-07T13:18:25.778645Z","end":"2026-05-07T13:18:25.927605Z","steps":["trace[1209290889] 'process raft request' (duration: 148.454788ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:18:25.927687Z","caller":"traceutil/trace.go:171","msg":"trace[680650371] transaction","detail":"{read_only:false; response_revision:2679; number_of_response:1; }","duration":"225.441233ms","start":"2026-05-07T13:18:25.702173Z","end":"2026-05-07T13:18:25.927614Z","steps":["trace[680650371] 'process raft request' (duration: 205.182544ms)","trace[680650371] 'compare' (duration: 19.518762ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:18:25.927976Z","caller":"traceutil/trace.go:171","msg":"trace[1444670687] transaction","detail":"{read_only:false; response_revision:2681; number_of_response:1; }","duration":"225.435363ms","start":"2026-05-07T13:18:25.702522Z","end":"2026-05-07T13:18:25.927958Z","steps":["trace[1444670687] 'process raft request' (duration: 224.541063ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:22:07.042867Z","caller":"traceutil/trace.go:171","msg":"trace[255726752] transaction","detail":"{read_only:false; response_revision:4615; number_of_response:1; }","duration":"138.401502ms","start":"2026-05-07T13:22:06.90444Z","end":"2026-05-07T13:22:07.042842Z","steps":["trace[255726752] 'process raft request' (duration: 135.922131ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:22:07.261389Z","caller":"traceutil/trace.go:171","msg":"trace[1675053459] linearizableReadLoop","detail":"{readStateIndex:4792; appliedIndex:4789; }","duration":"300.114796ms","start":"2026-05-07T13:22:06.961259Z","end":"2026-05-07T13:22:07.261374Z","steps":["trace[1675053459] 'read index received' (duration: 79.0812ms)","trace[1675053459] 'applied index is now lower than readState.Index' (duration: 221.033096ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:22:07.262106Z","caller":"traceutil/trace.go:171","msg":"trace[1045229597] transaction","detail":"{read_only:false; response_revision:4616; number_of_response:1; }","duration":"330.876343ms","start":"2026-05-07T13:22:06.93122Z","end":"2026-05-07T13:22:07.262097Z","steps":["trace[1045229597] 'process raft request' (duration: 316.467653ms)","trace[1045229597] 'compare' (duration: 13.213561ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:22:07.262179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:22:06.931202Z","time spent":"330.926425ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:22:07.262423Z","caller":"traceutil/trace.go:171","msg":"trace[1098014379] transaction","detail":"{read_only:false; response_revision:4617; number_of_response:1; }","duration":"330.35326ms","start":"2026-05-07T13:22:06.932062Z","end":"2026-05-07T13:22:07.262415Z","steps":["trace[1098014379] 'process raft request' (duration: 329.275324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:22:07.262457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:22:06.932054Z","time spent":"330.385592ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:22:07.262568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.309325ms","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-07T13:22:07.262585Z","caller":"traceutil/trace.go:171","msg":"trace[336640132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4617; }","duration":"301.354166ms","start":"2026-05-07T13:22:06.961226Z","end":"2026-05-07T13:22:07.262581Z","steps":["trace[336640132] 'agreement among raft nodes before linearized reading' (duration: 301.287485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:22:07.2626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:22:06.961206Z","time spent":"301.389477ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T13:22:07.262659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.723342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c6e3d65-778c-47b7-9afc-ce0dfa1f8019\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-07T13:22:07.262672Z","caller":"traceutil/trace.go:171","msg":"trace[2097513554] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9c6e3d65-778c-47b7-9afc-ce0dfa1f8019; range_end:; response_count:1; response_revision:4617; }","duration":"111.755103ms","start":"2026-05-07T13:22:07.150912Z","end":"2026-05-07T13:22:07.262667Z","steps":["trace[2097513554] 'agreement among raft nodes before linearized reading' (duration: 111.717512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:22:07.262839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.37213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T13:22:07.26299Z","caller":"traceutil/trace.go:171","msg":"trace[1561665841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4617; }","duration":"115.544446ms","start":"2026-05-07T13:22:07.14743Z","end":"2026-05-07T13:22:07.262974Z","steps":["trace[1561665841] 'agreement among raft nodes before linearized reading' (duration: 115.156735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:22:07.26438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.867787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T13:22:07.264417Z","caller":"traceutil/trace.go:171","msg":"trace[420547689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4617; }","duration":"116.926799ms","start":"2026-05-07T13:22:07.147482Z","end":"2026-05-07T13:22:07.264409Z","steps":["trace[420547689] 'agreement among raft nodes before linearized reading' (duration: 115.853263ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:22:07.533713Z","caller":"traceutil/trace.go:171","msg":"trace[8937794] linearizableReadLoop","detail":"{readStateIndex:4810; appliedIndex:4809; }","duration":"114.974973ms","start":"2026-05-07T13:22:07.418711Z","end":"2026-05-07T13:22:07.533686Z","steps":["trace[8937794] 'read index received' (duration: 94.021834ms)","trace[8937794] 'applied index is now lower than readState.Index' (duration: 20.951228ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:22:07.533901Z","caller":"traceutil/trace.go:171","msg":"trace[1843908685] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"142.325276ms","start":"2026-05-07T13:22:07.39153Z","end":"2026-05-07T13:22:07.533855Z","steps":["trace[1843908685] 'process raft request' (duration: 121.252644ms)","trace[1843908685] 'compare' (duration: 20.784325ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:22:07.534048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.31428ms","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-07T13:22:07.534352Z","caller":"traceutil/trace.go:171","msg":"trace[213891886] 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:4635; }","duration":"115.657458ms","start":"2026-05-07T13:22:07.418675Z","end":"2026-05-07T13:22:07.534333Z","steps":["trace[213891886] 'agreement among raft nodes before linearized reading' (duration: 115.233858ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:22:15.169532Z","caller":"traceutil/trace.go:171","msg":"trace[231854066] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"103.002441ms","start":"2026-05-07T13:22:15.066508Z","end":"2026-05-07T13:22:15.16951Z","steps":["trace[231854066] 'process raft request' (duration: 102.887768ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:22:20.803702Z","caller":"traceutil/trace.go:171","msg":"trace[1226358645] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"111.901981ms","start":"2026-05-07T13:22:20.691777Z","end":"2026-05-07T13:22:20.803679Z","steps":["trace[1226358645] 'process raft request' (duration: 111.770688ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:18.007089Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2954} {"level":"info","ts":"2026-05-07T13:24:18.084494Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2954,"took":"76.069284ms","hash":1933110027,"current-db-size-bytes":52862976,"current-db-size":"53 MB","current-db-size-in-use-bytes":29241344,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-07T13:24:18.084558Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1933110027,"revision":2954,"compact-revision":-1} {"level":"warn","ts":"2026-05-07T13:24:27.233302Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400718143639788,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T13:24:27.260695Z","caller":"traceutil/trace.go:171","msg":"trace[269388674] transaction","detail":"{read_only:false; response_revision:5719; number_of_response:1; }","duration":"930.209185ms","start":"2026-05-07T13:24:26.330464Z","end":"2026-05-07T13:24:27.260673Z","steps":["trace[269388674] 'process raft request' (duration: 930.084762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:27.260809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:26.330445Z","time spent":"930.302288ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:27.284823Z","caller":"traceutil/trace.go:171","msg":"trace[1865811141] linearizableReadLoop","detail":"{readStateIndex:5930; appliedIndex:5927; }","duration":"551.967856ms","start":"2026-05-07T13:24:26.732831Z","end":"2026-05-07T13:24:27.284799Z","steps":["trace[1865811141] 'read index received' (duration: 527.794542ms)","trace[1865811141] 'applied index is now lower than readState.Index' (duration: 24.172164ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:27.284873Z","caller":"traceutil/trace.go:171","msg":"trace[476078284] transaction","detail":"{read_only:false; response_revision:5720; number_of_response:1; }","duration":"527.113557ms","start":"2026-05-07T13:24:26.757724Z","end":"2026-05-07T13:24:27.284837Z","steps":["trace[476078284] 'process raft request' (duration: 526.878411ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:27.284895Z","caller":"traceutil/trace.go:171","msg":"trace[2035965062] transaction","detail":"{read_only:false; response_revision:5721; number_of_response:1; }","duration":"281.061656ms","start":"2026-05-07T13:24:27.0038Z","end":"2026-05-07T13:24:27.284862Z","steps":["trace[2035965062] 'process raft request' (duration: 280.951684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:27.285005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.16236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:27.285047Z","caller":"traceutil/trace.go:171","msg":"trace[1146505752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5721; }","duration":"552.254902ms","start":"2026-05-07T13:24:26.73278Z","end":"2026-05-07T13:24:27.285035Z","steps":["trace[1146505752] 'agreement among raft nodes before linearized reading' (duration: 552.17791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:27.28506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:26.757707Z","time spent":"527.24596ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:27.285086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:26.732759Z","time spent":"552.319664ms","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:24:27.285158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.940196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-05-07T13:24:27.285203Z","caller":"traceutil/trace.go:171","msg":"trace[1816496739] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5721; }","duration":"543.006227ms","start":"2026-05-07T13:24:26.742182Z","end":"2026-05-07T13:24:27.285189Z","steps":["trace[1816496739] 'agreement among raft nodes before linearized reading' (duration: 542.875164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:27.285231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:26.742172Z","time spent":"543.053949ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":698,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"warn","ts":"2026-05-07T13:24:28.233582Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400718143639799,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:24:28.734091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400718143639799,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:24:29.098162Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.673100999s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T13:24:29.0987Z","caller":"traceutil/trace.go:171","msg":"trace[2101150414] transaction","detail":"{read_only:false; response_revision:5722; number_of_response:1; }","duration":"1.673724484s","start":"2026-05-07T13:24:27.42495Z","end":"2026-05-07T13:24:29.098675Z","steps":["trace[2101150414] 'process raft request' (duration: 1.67355276s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.098856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.424925Z","time spent":"1.673836336s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:29.367849Z","caller":"traceutil/trace.go:171","msg":"trace[683711799] linearizableReadLoop","detail":"{readStateIndex:5934; appliedIndex:5930; }","duration":"1.634845245s","start":"2026-05-07T13:24:27.732978Z","end":"2026-05-07T13:24:29.367824Z","steps":["trace[683711799] 'read index received' (duration: 1.365492335s)","trace[683711799] 'applied index is now lower than readState.Index' (duration: 269.35207ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:29.368085Z","caller":"traceutil/trace.go:171","msg":"trace[1887453463] transaction","detail":"{read_only:false; response_revision:5723; number_of_response:1; }","duration":"1.710505688s","start":"2026-05-07T13:24:27.657565Z","end":"2026-05-07T13:24:29.368071Z","steps":["trace[1887453463] 'process raft request' (duration: 1.699486372s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.368206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.657542Z","time spent":"1.710578269s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:29.368347Z","caller":"traceutil/trace.go:171","msg":"trace[147994755] transaction","detail":"{read_only:false; response_revision:5725; number_of_response:1; }","duration":"1.634943818s","start":"2026-05-07T13:24:27.733383Z","end":"2026-05-07T13:24:29.368327Z","steps":["trace[147994755] 'process raft request' (duration: 1.634403685s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.368406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635426369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:29.368445Z","caller":"traceutil/trace.go:171","msg":"trace[1677464290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5725; }","duration":"1.63550292s","start":"2026-05-07T13:24:27.732931Z","end":"2026-05-07T13:24:29.368434Z","steps":["trace[1677464290] 'agreement among raft nodes before linearized reading' (duration: 1.635447099s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.368439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.733372Z","time spent":"1.635020479s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:29.368475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.732908Z","time spent":"1.635561432s","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T13:24:29.368465Z","caller":"traceutil/trace.go:171","msg":"trace[1965147374] transaction","detail":"{read_only:false; response_revision:5724; number_of_response:1; }","duration":"1.635263995s","start":"2026-05-07T13:24:27.733119Z","end":"2026-05-07T13:24:29.368383Z","steps":["trace[1965147374] 'process raft request' (duration: 1.6346178s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.368994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.733093Z","time spent":"1.635511331s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:29.378096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.390022796s","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-07T13:24:29.378153Z","caller":"traceutil/trace.go:171","msg":"trace[1637361223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5726; }","duration":"1.390123828s","start":"2026-05-07T13:24:27.988015Z","end":"2026-05-07T13:24:29.378139Z","steps":["trace[1637361223] 'agreement among raft nodes before linearized reading' (duration: 1.389955924s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.378202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.987997Z","time spent":"1.390196649s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T13:24:29.378288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43615314s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-07T13:24:29.378333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.396787362s","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-07T13:24:29.37839Z","caller":"traceutil/trace.go:171","msg":"trace[1162194076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5726; }","duration":"1.436337933s","start":"2026-05-07T13:24:27.942017Z","end":"2026-05-07T13:24:29.378355Z","steps":["trace[1162194076] 'agreement among raft nodes before linearized reading' (duration: 1.435992576s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:29.378386Z","caller":"traceutil/trace.go:171","msg":"trace[955012673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5726; }","duration":"1.396861234s","start":"2026-05-07T13:24:27.98151Z","end":"2026-05-07T13:24:29.378371Z","steps":["trace[955012673] 'agreement among raft nodes before linearized reading' (duration: 1.396713821s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.378482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.941999Z","time spent":"1.436474436s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T13:24:29.378565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.105931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:24:29.378599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389902802s","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-07T13:24:29.378616Z","caller":"traceutil/trace.go:171","msg":"trace[506076407] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:5726; }","duration":"798.335937ms","start":"2026-05-07T13:24:28.580267Z","end":"2026-05-07T13:24:29.378603Z","steps":["trace[506076407] 'agreement among raft nodes before linearized reading' (duration: 798.131922ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:29.378648Z","caller":"traceutil/trace.go:171","msg":"trace[243625539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5726; }","duration":"1.389988964s","start":"2026-05-07T13:24:27.988647Z","end":"2026-05-07T13:24:29.378636Z","steps":["trace[243625539] 'agreement among raft nodes before linearized reading' (duration: 1.389891032s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.378656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:28.580247Z","time spent":"798.398168ms","remote":"127.0.0.1:57808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:24:29.378672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.988625Z","time spent":"1.390042385s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T13:24:29.378487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.736132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-07T13:24:29.378887Z","caller":"traceutil/trace.go:171","msg":"trace[1631392566] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5726; }","duration":"688.140371ms","start":"2026-05-07T13:24:28.690691Z","end":"2026-05-07T13:24:29.378831Z","steps":["trace[1631392566] 'agreement among raft nodes before linearized reading' (duration: 687.65524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.378967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:28.690672Z","time spent":"688.262914ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-07T13:24:29.378525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.981498Z","time spent":"1.396957496s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T13:24:29.378783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.417054074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:29.379271Z","caller":"traceutil/trace.go:171","msg":"trace[247880417] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5726; }","duration":"1.417568915s","start":"2026-05-07T13:24:27.961679Z","end":"2026-05-07T13:24:29.379248Z","steps":["trace[247880417] 'agreement among raft nodes before linearized reading' (duration: 1.416959611s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.379346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.961657Z","time spent":"1.417648258s","remote":"127.0.0.1:47110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:24:31.264023Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.813115485s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T13:24:31.26882Z","caller":"traceutil/trace.go:171","msg":"trace[404411658] transaction","detail":"{read_only:false; response_revision:5728; number_of_response:1; }","duration":"1.886113114s","start":"2026-05-07T13:24:29.382684Z","end":"2026-05-07T13:24:31.268797Z","steps":["trace[404411658] 'process raft request' (duration: 1.881571229s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:31.269181Z","caller":"traceutil/trace.go:171","msg":"trace[148143043] linearizableReadLoop","detail":"{readStateIndex:5940; appliedIndex:5935; }","duration":"1.881959627s","start":"2026-05-07T13:24:29.387199Z","end":"2026-05-07T13:24:31.269158Z","steps":["trace[148143043] 'read index received' (duration: 63.739173ms)","trace[148143043] 'applied index is now lower than readState.Index' (duration: 1.818219564s)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:31.269444Z","caller":"traceutil/trace.go:171","msg":"trace[1411247181] transaction","detail":"{read_only:false; response_revision:5729; number_of_response:1; }","duration":"1.886686177s","start":"2026-05-07T13:24:29.382745Z","end":"2026-05-07T13:24:31.269431Z","steps":["trace[1411247181] 'process raft request' (duration: 1.886002681s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.269596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.382737Z","time spent":"1.886775379s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:31.269987Z","caller":"traceutil/trace.go:171","msg":"trace[172459315] transaction","detail":"{read_only:false; response_revision:5730; number_of_response:1; }","duration":"1.886534704s","start":"2026-05-07T13:24:29.38344Z","end":"2026-05-07T13:24:31.269975Z","steps":["trace[172459315] 'process raft request' (duration: 1.885618402s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.270089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.383427Z","time spent":"1.886626786s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:31.270332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.382667Z","time spent":"1.886233807s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:31.270429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.883213746s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:31.27032Z","caller":"traceutil/trace.go:171","msg":"trace[568076323] transaction","detail":"{read_only:false; response_revision:5731; number_of_response:1; }","duration":"1.886776099s","start":"2026-05-07T13:24:29.38351Z","end":"2026-05-07T13:24:31.270286Z","steps":["trace[568076323] 'process raft request' (duration: 1.885606232s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.270541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.66643162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:31.270579Z","caller":"traceutil/trace.go:171","msg":"trace[319571196] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:5731; }","duration":"1.666473141s","start":"2026-05-07T13:24:29.604095Z","end":"2026-05-07T13:24:31.270568Z","steps":["trace[319571196] 'agreement among raft nodes before linearized reading' (duration: 1.666416s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.270621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.604055Z","time spent":"1.666556183s","remote":"127.0.0.1:44166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:24:31.270723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.383503Z","time spent":"1.887023645s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:31.270931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.141983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-07T13:24:31.271284Z","caller":"traceutil/trace.go:171","msg":"trace[1032242915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5731; }","duration":"168.525312ms","start":"2026-05-07T13:24:31.102741Z","end":"2026-05-07T13:24:31.271266Z","steps":["trace[1032242915] 'agreement among raft nodes before linearized reading' (duration: 168.077322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.270983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.164945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T13:24:31.271544Z","caller":"traceutil/trace.go:171","msg":"trace[242609514] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:5731; }","duration":"562.751549ms","start":"2026-05-07T13:24:30.708774Z","end":"2026-05-07T13:24:31.271526Z","steps":["trace[242609514] 'agreement among raft nodes before linearized reading' (duration: 562.176705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.270981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.262833ms","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-07T13:24:31.271843Z","caller":"traceutil/trace.go:171","msg":"trace[321129383] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5731; }","duration":"455.156123ms","start":"2026-05-07T13:24:30.816662Z","end":"2026-05-07T13:24:31.271818Z","steps":["trace[321129383] 'agreement among raft nodes before linearized reading' (duration: 454.187132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.271906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:30.816643Z","time spent":"455.239336ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-07T13:24:31.271923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:30.708754Z","time spent":"562.959453ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":21,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:24:31.271101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.589571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.154\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T13:24:31.272472Z","caller":"traceutil/trace.go:171","msg":"trace[365454908] range","detail":"{range_begin:/registry/masterleases/199.19.213.154; range_end:; response_count:1; response_revision:5731; }","duration":"266.951302ms","start":"2026-05-07T13:24:31.005506Z","end":"2026-05-07T13:24:31.272457Z","steps":["trace[365454908] 'agreement among raft nodes before linearized reading' (duration: 265.51875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.271177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.198145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-07T13:24:31.272772Z","caller":"traceutil/trace.go:171","msg":"trace[1885689118] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5731; }","duration":"398.813882ms","start":"2026-05-07T13:24:30.873938Z","end":"2026-05-07T13:24:31.272751Z","steps":["trace[1885689118] 'agreement among raft nodes before linearized reading' (duration: 397.181895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.272847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:30.873919Z","time spent":"398.913125ms","remote":"127.0.0.1:46870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-07T13:24:31.270481Z","caller":"traceutil/trace.go:171","msg":"trace[1067258946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5731; }","duration":"1.883295519s","start":"2026-05-07T13:24:29.387175Z","end":"2026-05-07T13:24:31.27047Z","steps":["trace[1067258946] 'agreement among raft nodes before linearized reading' (duration: 1.883196076s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.273029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.387167Z","time spent":"1.885852908s","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:24:31.641572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.512107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:24:31.641959Z","caller":"traceutil/trace.go:171","msg":"trace[1410639078] transaction","detail":"{read_only:false; response_revision:5732; number_of_response:1; }","duration":"366.92943ms","start":"2026-05-07T13:24:31.274989Z","end":"2026-05-07T13:24:31.641918Z","steps":["trace[1410639078] 'process raft request' (duration: 183.986013ms)","trace[1410639078] 'compare' (duration: 182.293732ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:31.642197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.274972Z","time spent":"367.081243ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:31.642536Z","caller":"traceutil/trace.go:171","msg":"trace[1323330222] linearizableReadLoop","detail":"{readStateIndex:5943; appliedIndex:5941; }","duration":"362.186869ms","start":"2026-05-07T13:24:31.280329Z","end":"2026-05-07T13:24:31.642515Z","steps":["trace[1323330222] 'read index received' (duration: 178.679399ms)","trace[1323330222] 'applied index is now lower than readState.Index' (duration: 183.50614ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:31.642666Z","caller":"traceutil/trace.go:171","msg":"trace[875829028] transaction","detail":"{read_only:false; response_revision:5733; number_of_response:1; }","duration":"366.202873ms","start":"2026-05-07T13:24:31.276426Z","end":"2026-05-07T13:24:31.642629Z","steps":["trace[875829028] 'process raft request' (duration: 365.720751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.642846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.276405Z","time spent":"366.350866ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:31.642904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.37518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-07T13:24:31.642983Z","caller":"traceutil/trace.go:171","msg":"trace[1198789416] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5733; }","duration":"367.473473ms","start":"2026-05-07T13:24:31.275491Z","end":"2026-05-07T13:24:31.642964Z","steps":["trace[1198789416] 'agreement among raft nodes before linearized reading' (duration: 367.155795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.64303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.27546Z","time spent":"367.561115ms","remote":"127.0.0.1:46870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-07T13:24:32.180393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.844026Z","time spent":"336.359317ms","remote":"127.0.0.1:56902","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-07T13:24:32.180594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.86908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:24:32.18083Z","caller":"traceutil/trace.go:171","msg":"trace[301095631] transaction","detail":"{read_only:false; response_revision:5734; number_of_response:1; }","duration":"899.944093ms","start":"2026-05-07T13:24:31.280855Z","end":"2026-05-07T13:24:32.180799Z","steps":["trace[301095631] 'process raft request' (duration: 736.808048ms)","trace[301095631] 'compare' (duration: 162.787278ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:32.180891Z","caller":"traceutil/trace.go:171","msg":"trace[792892923] transaction","detail":"{read_only:false; response_revision:5735; number_of_response:1; }","duration":"807.265606ms","start":"2026-05-07T13:24:31.373604Z","end":"2026-05-07T13:24:32.18087Z","steps":["trace[792892923] 'process raft request' (duration: 807.043942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:32.180976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.869385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:24:32.180996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.373575Z","time spent":"807.368419ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:32.181016Z","caller":"traceutil/trace.go:171","msg":"trace[607703140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5738; }","duration":"894.924086ms","start":"2026-05-07T13:24:31.286084Z","end":"2026-05-07T13:24:32.181008Z","steps":["trace[607703140] 'agreement among raft nodes before linearized reading' (duration: 894.845344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:32.181044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.286078Z","time spent":"894.960797ms","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:24:32.181107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.280847Z","time spent":"900.065796ms","remote":"127.0.0.1:46704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:32.180899Z","caller":"traceutil/trace.go:171","msg":"trace[790020941] linearizableReadLoop","detail":"{readStateIndex:5948; appliedIndex:5943; }","duration":"538.161263ms","start":"2026-05-07T13:24:31.642723Z","end":"2026-05-07T13:24:32.180884Z","steps":["trace[790020941] 'read index received' (duration: 374.975387ms)","trace[790020941] 'applied index is now lower than readState.Index' (duration: 163.184946ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:32.181231Z","caller":"traceutil/trace.go:171","msg":"trace[2015414994] transaction","detail":"{read_only:false; response_revision:5737; number_of_response:1; }","duration":"806.721683ms","start":"2026-05-07T13:24:31.374503Z","end":"2026-05-07T13:24:32.181224Z","steps":["trace[2015414994] 'process raft request' (duration: 806.223861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:32.181286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.374476Z","time spent":"806.775675ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:32.181203Z","caller":"traceutil/trace.go:171","msg":"trace[1141050195] transaction","detail":"{read_only:false; response_revision:5736; number_of_response:1; }","duration":"807.157353ms","start":"2026-05-07T13:24:31.374035Z","end":"2026-05-07T13:24:32.181193Z","steps":["trace[1141050195] 'process raft request' (duration: 806.659703ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:32.181372Z","caller":"traceutil/trace.go:171","msg":"trace[1308887948] transaction","detail":"{read_only:false; response_revision:5738; number_of_response:1; }","duration":"798.743838ms","start":"2026-05-07T13:24:31.38262Z","end":"2026-05-07T13:24:32.181364Z","steps":["trace[1308887948] 'process raft request' (duration: 798.148844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:32.181421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.382603Z","time spent":"798.793509ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:32.181556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.374015Z","time spent":"807.351589ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:32.18169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.714522ms","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-07T13:24:32.18173Z","caller":"traceutil/trace.go:171","msg":"trace[1162956835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5738; }","duration":"726.795984ms","start":"2026-05-07T13:24:31.454926Z","end":"2026-05-07T13:24:32.181722Z","steps":["trace[1162956835] 'agreement among raft nodes before linearized reading' (duration: 726.672211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:32.181762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.454905Z","time spent":"726.851645ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T13:24:32.181766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.412767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:12170"} {"level":"info","ts":"2026-05-07T13:24:32.181803Z","caller":"traceutil/trace.go:171","msg":"trace[874207485] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5738; }","duration":"537.475018ms","start":"2026-05-07T13:24:31.644317Z","end":"2026-05-07T13:24:32.181792Z","steps":["trace[874207485] 'agreement among raft nodes before linearized reading' (duration: 537.340734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:32.181841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.644299Z","time spent":"537.53527ms","remote":"127.0.0.1:46860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":12193,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-07T13:24:32.181964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.619711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-05-07T13:24:32.182022Z","caller":"traceutil/trace.go:171","msg":"trace[13788980] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5738; }","duration":"797.702984ms","start":"2026-05-07T13:24:31.384309Z","end":"2026-05-07T13:24:32.182012Z","steps":["trace[13788980] 'agreement among raft nodes before linearized reading' (duration: 797.388556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:32.18206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.384298Z","time spent":"797.755005ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":698,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"info","ts":"2026-05-07T13:24:33.623373Z","caller":"traceutil/trace.go:171","msg":"trace[1355723899] transaction","detail":"{read_only:false; response_revision:5741; number_of_response:1; }","duration":"344.791233ms","start":"2026-05-07T13:24:33.278549Z","end":"2026-05-07T13:24:33.62334Z","steps":["trace[1355723899] 'process raft request' (duration: 344.607138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:33.623625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:33.278527Z","time spent":"344.940675ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:33.898362Z","caller":"traceutil/trace.go:171","msg":"trace[987176434] linearizableReadLoop","detail":"{readStateIndex:5953; appliedIndex:5951; }","duration":"251.991072ms","start":"2026-05-07T13:24:33.646346Z","end":"2026-05-07T13:24:33.898337Z","steps":["trace[987176434] 'read index received' (duration: 181.673406ms)","trace[987176434] 'applied index is now lower than readState.Index' (duration: 70.316586ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:33.898563Z","caller":"traceutil/trace.go:171","msg":"trace[630900864] transaction","detail":"{read_only:false; response_revision:5743; number_of_response:1; }","duration":"617.028366ms","start":"2026-05-07T13:24:33.281522Z","end":"2026-05-07T13:24:33.89855Z","steps":["trace[630900864] 'process raft request' (duration: 616.76387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:33.898713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:33.281512Z","time spent":"617.111217ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:33.898817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.761196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:33.89853Z","caller":"traceutil/trace.go:171","msg":"trace[499769368] transaction","detail":"{read_only:false; response_revision:5742; number_of_response:1; }","duration":"617.318892ms","start":"2026-05-07T13:24:33.281167Z","end":"2026-05-07T13:24:33.898486Z","steps":["trace[499769368] 'process raft request' (duration: 546.858563ms)","trace[499769368] 'compare' (duration: 70.108882ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:33.898868Z","caller":"traceutil/trace.go:171","msg":"trace[407019664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5743; }","duration":"165.862479ms","start":"2026-05-07T13:24:33.732991Z","end":"2026-05-07T13:24:33.898853Z","steps":["trace[407019664] 'agreement among raft nodes before linearized reading' (duration: 165.803867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:33.898755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.344105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:33.898936Z","caller":"traceutil/trace.go:171","msg":"trace[1975317147] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5743; }","duration":"223.587521ms","start":"2026-05-07T13:24:33.675334Z","end":"2026-05-07T13:24:33.898922Z","steps":["trace[1975317147] 'agreement among raft nodes before linearized reading' (duration: 223.321315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:33.898924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.547415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-07T13:24:33.898965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:33.281129Z","time spent":"617.750042ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:33.89901Z","caller":"traceutil/trace.go:171","msg":"trace[207102097] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5743; }","duration":"252.689048ms","start":"2026-05-07T13:24:33.646278Z","end":"2026-05-07T13:24:33.898967Z","steps":["trace[207102097] 'agreement among raft nodes before linearized reading' (duration: 252.424142ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:34.094463Z","caller":"traceutil/trace.go:171","msg":"trace[2132142414] transaction","detail":"{read_only:false; response_revision:5744; number_of_response:1; }","duration":"191.455964ms","start":"2026-05-07T13:24:33.90298Z","end":"2026-05-07T13:24:34.094436Z","steps":["trace[2132142414] 'process raft request' (duration: 103.902167ms)","trace[2132142414] 'compare' (duration: 87.324681ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:34.707258Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400718143639852,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:24:34.748147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.961273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:24:34.748249Z","caller":"traceutil/trace.go:171","msg":"trace[605395918] transaction","detail":"{read_only:false; response_revision:5745; number_of_response:1; }","duration":"558.908391ms","start":"2026-05-07T13:24:34.189318Z","end":"2026-05-07T13:24:34.748227Z","steps":["trace[605395918] 'process raft request' (duration: 11.776144ms)","trace[605395918] 'compare' (duration: 546.758869ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:34.748312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:34.1893Z","time spent":"558.979163ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:34.768326Z","caller":"traceutil/trace.go:171","msg":"trace[1792305781] linearizableReadLoop","detail":"{readStateIndex:5958; appliedIndex:5955; }","duration":"561.423221ms","start":"2026-05-07T13:24:34.206876Z","end":"2026-05-07T13:24:34.7683Z","steps":["trace[1792305781] 'read index received' (duration: 561.252307ms)","trace[1792305781] 'applied index is now lower than readState.Index' (duration: 169.954µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:34.768393Z","caller":"traceutil/trace.go:171","msg":"trace[1030277530] transaction","detail":"{read_only:false; response_revision:5747; number_of_response:1; }","duration":"578.64831ms","start":"2026-05-07T13:24:34.189714Z","end":"2026-05-07T13:24:34.768362Z","steps":["trace[1030277530] 'process raft request' (duration: 578.503477ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:34.768393Z","caller":"traceutil/trace.go:171","msg":"trace[447379455] transaction","detail":"{read_only:false; response_revision:5746; number_of_response:1; }","duration":"578.655611ms","start":"2026-05-07T13:24:34.189662Z","end":"2026-05-07T13:24:34.768317Z","steps":["trace[447379455] 'process raft request' (duration: 578.406765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:34.768546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:34.189684Z","time spent":"578.778603ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:34.768586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.691057ms","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-07T13:24:34.76862Z","caller":"traceutil/trace.go:171","msg":"trace[1415477057] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5748; }","duration":"561.779079ms","start":"2026-05-07T13:24:34.206831Z","end":"2026-05-07T13:24:34.76861Z","steps":["trace[1415477057] 'agreement among raft nodes before linearized reading' (duration: 561.631405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:34.768662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:34.206787Z","time spent":"561.866501ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-07T13:24:34.768463Z","caller":"traceutil/trace.go:171","msg":"trace[864038597] transaction","detail":"{read_only:false; response_revision:5748; number_of_response:1; }","duration":"577.369831ms","start":"2026-05-07T13:24:34.191066Z","end":"2026-05-07T13:24:34.768436Z","steps":["trace[864038597] 'process raft request' (duration: 577.191417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:34.768704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:34.18964Z","time spent":"578.843355ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:34.769013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:34.191052Z","time spent":"577.869933ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:34.932312Z","caller":"traceutil/trace.go:171","msg":"trace[751587467] transaction","detail":"{read_only:false; response_revision:5750; number_of_response:1; }","duration":"148.679789ms","start":"2026-05-07T13:24:34.783605Z","end":"2026-05-07T13:24:34.932285Z","steps":["trace[751587467] 'process raft request' (duration: 148.576747ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:34.932393Z","caller":"traceutil/trace.go:171","msg":"trace[1825148587] transaction","detail":"{read_only:false; response_revision:5749; number_of_response:1; }","duration":"157.856922ms","start":"2026-05-07T13:24:34.774472Z","end":"2026-05-07T13:24:34.932329Z","steps":["trace[1825148587] 'process raft request' (duration: 141.211385ms)","trace[1825148587] 'compare' (duration: 16.224857ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:36.00892Z","caller":"traceutil/trace.go:171","msg":"trace[949863406] linearizableReadLoop","detail":"{readStateIndex:5961; appliedIndex:5960; }","duration":"275.62271ms","start":"2026-05-07T13:24:35.733186Z","end":"2026-05-07T13:24:36.008808Z","steps":["trace[949863406] 'read index received' (duration: 275.456986ms)","trace[949863406] 'applied index is now lower than readState.Index' (duration: 164.654µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:36.009044Z","caller":"traceutil/trace.go:171","msg":"trace[819668858] transaction","detail":"{read_only:false; response_revision:5751; number_of_response:1; }","duration":"375.471672ms","start":"2026-05-07T13:24:35.633392Z","end":"2026-05-07T13:24:36.008864Z","steps":["trace[819668858] 'process raft request' (duration: 375.263167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.009037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.828874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:36.009113Z","caller":"traceutil/trace.go:171","msg":"trace[913726021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5751; }","duration":"276.007519ms","start":"2026-05-07T13:24:35.733088Z","end":"2026-05-07T13:24:36.009095Z","steps":["trace[913726021] 'agreement among raft nodes before linearized reading' (duration: 275.841555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.009157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:35.633364Z","time spent":"375.713588ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:36.009659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.740596ms","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-07T13:24:36.009703Z","caller":"traceutil/trace.go:171","msg":"trace[1003439442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5751; }","duration":"104.795647ms","start":"2026-05-07T13:24:35.904898Z","end":"2026-05-07T13:24:36.009693Z","steps":["trace[1003439442] 'agreement among raft nodes before linearized reading' (duration: 104.655653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.009659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.693029ms","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-07T13:24:36.009802Z","caller":"traceutil/trace.go:171","msg":"trace[832399798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5751; }","duration":"105.870013ms","start":"2026-05-07T13:24:35.903917Z","end":"2026-05-07T13:24:36.009787Z","steps":["trace[832399798] 'agreement among raft nodes before linearized reading' (duration: 105.638517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.602151Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400718143639865,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T13:24:36.813114Z","caller":"traceutil/trace.go:171","msg":"trace[838425676] transaction","detail":"{read_only:false; response_revision:5752; number_of_response:1; }","duration":"798.326916ms","start":"2026-05-07T13:24:36.014757Z","end":"2026-05-07T13:24:36.813084Z","steps":["trace[838425676] 'process raft request' (duration: 795.083009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.813278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:36.014731Z","time spent":"798.466789ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:36.81496Z","caller":"traceutil/trace.go:171","msg":"trace[340490083] transaction","detail":"{read_only:false; response_revision:5753; number_of_response:1; }","duration":"799.677646ms","start":"2026-05-07T13:24:36.015261Z","end":"2026-05-07T13:24:36.814939Z","steps":["trace[340490083] 'process raft request' (duration: 799.530953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.815061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:36.015237Z","time spent":"799.775809ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:36.817235Z","caller":"traceutil/trace.go:171","msg":"trace[1981674656] linearizableReadLoop","detail":"{readStateIndex:5964; appliedIndex:5961; }","duration":"715.407596ms","start":"2026-05-07T13:24:36.101816Z","end":"2026-05-07T13:24:36.817224Z","steps":["trace[1981674656] 'read index received' (duration: 708.040475ms)","trace[1981674656] 'applied index is now lower than readState.Index' (duration: 7.366181ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:36.81742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.6052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-07T13:24:36.817471Z","caller":"traceutil/trace.go:171","msg":"trace[1206033311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5757; }","duration":"715.694963ms","start":"2026-05-07T13:24:36.101764Z","end":"2026-05-07T13:24:36.817459Z","steps":["trace[1206033311] 'agreement among raft nodes before linearized reading' (duration: 715.556849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.817503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:36.101744Z","time spent":"715.751815ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T13:24:36.817632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.643943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T13:24:36.817712Z","caller":"traceutil/trace.go:171","msg":"trace[1024581385] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5757; }","duration":"187.801306ms","start":"2026-05-07T13:24:36.629894Z","end":"2026-05-07T13:24:36.817695Z","steps":["trace[1024581385] 'agreement among raft nodes before linearized reading' (duration: 187.495449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.817697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.834649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:24:36.817761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.174211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"warn","ts":"2026-05-07T13:24:36.817785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.558026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T13:24:36.817795Z","caller":"traceutil/trace.go:171","msg":"trace[1001117967] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5757; }","duration":"623.244332ms","start":"2026-05-07T13:24:36.194541Z","end":"2026-05-07T13:24:36.817785Z","steps":["trace[1001117967] 'agreement among raft nodes before linearized reading' (duration: 623.15308ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:36.817799Z","caller":"traceutil/trace.go:171","msg":"trace[722486872] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:5757; }","duration":"553.996713ms","start":"2026-05-07T13:24:36.263779Z","end":"2026-05-07T13:24:36.817776Z","steps":["trace[722486872] 'agreement among raft nodes before linearized reading' (duration: 553.824069ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:36.817819Z","caller":"traceutil/trace.go:171","msg":"trace[249290781] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:5757; }","duration":"379.635648ms","start":"2026-05-07T13:24:36.438176Z","end":"2026-05-07T13:24:36.817812Z","steps":["trace[249290781] 'agreement among raft nodes before linearized reading' (duration: 379.583437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.817835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:36.194489Z","time spent":"623.339014ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":698,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"warn","ts":"2026-05-07T13:24:36.817843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:36.438155Z","time spent":"379.6829ms","remote":"127.0.0.1:46982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":3,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:24:36.817856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:36.263714Z","time spent":"554.128977ms","remote":"127.0.0.1:47094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:24:36.818336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.658687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:36.818622Z","caller":"traceutil/trace.go:171","msg":"trace[1996459061] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:5757; }","duration":"653.038026ms","start":"2026-05-07T13:24:36.165549Z","end":"2026-05-07T13:24:36.818587Z","steps":["trace[1996459061] 'agreement among raft nodes before linearized reading' (duration: 651.830498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.820626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:36.165519Z","time spent":"654.98138ms","remote":"127.0.0.1:42268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"info","ts":"2026-05-07T13:24:42.353912Z","caller":"traceutil/trace.go:171","msg":"trace[1317146656] transaction","detail":"{read_only:false; response_revision:5780; number_of_response:1; }","duration":"313.665048ms","start":"2026-05-07T13:24:42.04022Z","end":"2026-05-07T13:24:42.353885Z","steps":["trace[1317146656] 'process raft request' (duration: 313.507704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:42.354073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.040208Z","time spent":"313.77443ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:42.942809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400718143639945,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:24:43.443799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400718143639945,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:24:43.944339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400718143639945,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:24:44.227064Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.789244384s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T13:24:44.227539Z","caller":"traceutil/trace.go:171","msg":"trace[588732420] transaction","detail":"{read_only:false; response_revision:5781; number_of_response:1; }","duration":"1.78989955s","start":"2026-05-07T13:24:42.437615Z","end":"2026-05-07T13:24:44.227515Z","steps":["trace[588732420] 'process raft request' (duration: 1.789708536s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.227695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.437584Z","time spent":"1.790031193s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:44.230063Z","caller":"traceutil/trace.go:171","msg":"trace[294368483] transaction","detail":"{read_only:false; response_revision:5782; number_of_response:1; }","duration":"1.390372138s","start":"2026-05-07T13:24:42.839674Z","end":"2026-05-07T13:24:44.230046Z","steps":["trace[294368483] 'process raft request' (duration: 1.390250865s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.230216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.839647Z","time spent":"1.39045833s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:44.230617Z","caller":"traceutil/trace.go:171","msg":"trace[312166383] transaction","detail":"{read_only:false; response_revision:5784; number_of_response:1; }","duration":"1.389609951s","start":"2026-05-07T13:24:42.840984Z","end":"2026-05-07T13:24:44.230594Z","steps":["trace[312166383] 'process raft request' (duration: 1.389211502s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:44.230638Z","caller":"traceutil/trace.go:171","msg":"trace[1537973392] transaction","detail":"{read_only:false; response_revision:5783; number_of_response:1; }","duration":"1.389739733s","start":"2026-05-07T13:24:42.840547Z","end":"2026-05-07T13:24:44.230287Z","steps":["trace[1537973392] 'process raft request' (duration: 1.389469196s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.230704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.840966Z","time spent":"1.389695293s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:44.230749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.840535Z","time spent":"1.390150872s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:44.230957Z","caller":"traceutil/trace.go:171","msg":"trace[1154087152] linearizableReadLoop","detail":"{readStateIndex:5998; appliedIndex:5992; }","duration":"1.788905428s","start":"2026-05-07T13:24:42.442017Z","end":"2026-05-07T13:24:44.230923Z","steps":["trace[1154087152] 'read index received' (duration: 1.785340625s)","trace[1154087152] 'applied index is now lower than readState.Index' (duration: 3.563293ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:44.231134Z","caller":"traceutil/trace.go:171","msg":"trace[506521255] transaction","detail":"{read_only:false; response_revision:5786; number_of_response:1; }","duration":"1.347562414s","start":"2026-05-07T13:24:42.883458Z","end":"2026-05-07T13:24:44.23102Z","steps":["trace[506521255] 'process raft request' (duration: 1.34740606s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.231296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391230178s","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-07T13:24:44.231325Z","caller":"traceutil/trace.go:171","msg":"trace[1767313447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5786; }","duration":"1.39130531s","start":"2026-05-07T13:24:42.840012Z","end":"2026-05-07T13:24:44.231317Z","steps":["trace[1767313447] 'agreement among raft nodes before linearized reading' (duration: 1.391250399s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.231326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.387736147s","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-07T13:24:44.231389Z","caller":"traceutil/trace.go:171","msg":"trace[45219071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5786; }","duration":"1.387809958s","start":"2026-05-07T13:24:42.843566Z","end":"2026-05-07T13:24:44.231376Z","steps":["trace[45219071] 'agreement among raft nodes before linearized reading' (duration: 1.387635434s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.231398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.393704925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-05-07T13:24:44.231429Z","caller":"traceutil/trace.go:171","msg":"trace[343854111] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5786; }","duration":"1.393770537s","start":"2026-05-07T13:24:42.83765Z","end":"2026-05-07T13:24:44.231421Z","steps":["trace[343854111] 'agreement among raft nodes before linearized reading' (duration: 1.393709715s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.231424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.883441Z","time spent":"1.347871561s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:44.23145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.837631Z","time spent":"1.393814548s","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":698,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"warn","ts":"2026-05-07T13:24:44.231445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.843559Z","time spent":"1.38787254s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T13:24:44.23116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.789106152s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:44.231538Z","caller":"traceutil/trace.go:171","msg":"trace[666265279] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5786; }","duration":"1.789576903s","start":"2026-05-07T13:24:42.441946Z","end":"2026-05-07T13:24:44.231523Z","steps":["trace[666265279] 'agreement among raft nodes before linearized reading' (duration: 1.789124722s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.231164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.499134315s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:24:44.231582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.441918Z","time spent":"1.789654545s","remote":"127.0.0.1:47152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-07T13:24:44.231611Z","caller":"traceutil/trace.go:171","msg":"trace[358212927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5786; }","duration":"1.499607357s","start":"2026-05-07T13:24:42.731992Z","end":"2026-05-07T13:24:44.2316Z","steps":["trace[358212927] 'agreement among raft nodes before linearized reading' (duration: 1.499141986s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.231638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.731977Z","time spent":"1.499655558s","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:24:44.23163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385530375s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-07T13:24:44.231682Z","caller":"traceutil/trace.go:171","msg":"trace[1011008796] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5786; }","duration":"1.385593177s","start":"2026-05-07T13:24:42.846071Z","end":"2026-05-07T13:24:44.231665Z","steps":["trace[1011008796] 'agreement among raft nodes before linearized reading' (duration: 1.385413413s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.231716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.846066Z","time spent":"1.385643768s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T13:24:44.230984Z","caller":"traceutil/trace.go:171","msg":"trace[1473542312] transaction","detail":"{read_only:false; response_revision:5785; number_of_response:1; }","duration":"1.389252953s","start":"2026-05-07T13:24:42.841707Z","end":"2026-05-07T13:24:44.23096Z","steps":["trace[1473542312] 'process raft request' (duration: 1.388749591s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.23185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.841699Z","time spent":"1.390123242s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:44.23125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.265994259s","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-07T13:24:44.231907Z","caller":"traceutil/trace.go:171","msg":"trace[627563837] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5786; }","duration":"1.266695205s","start":"2026-05-07T13:24:42.965207Z","end":"2026-05-07T13:24:44.231902Z","steps":["trace[627563837] 'agreement among raft nodes before linearized reading' (duration: 1.265981809s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.231926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.965188Z","time spent":"1.266732445s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T13:24:44.231349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.839985Z","time spent":"1.391359861s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T13:25:10.871899Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400718143640303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:25:11.372259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400718143640303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:25:11.872609Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400718143640303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:25:12.372893Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400718143640303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:25:12.732768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000217902s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-07T13:25:12.732901Z","caller":"traceutil/trace.go:171","msg":"trace[1913009679] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000384875s","start":"2026-05-07T13:25:10.732473Z","end":"2026-05-07T13:25:12.732858Z","steps":["trace[1913009679] 'agreement among raft nodes before linearized reading' (duration: 2.000246582s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:12.732956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.732452Z","time spent":"2.000492038s","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/07 13:25:12 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-07T13:25:12.873691Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974400718143640303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:25:13.209912Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.854611355s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T13:25:13.213128Z","caller":"traceutil/trace.go:171","msg":"trace[1318052771] transaction","detail":"{read_only:false; response_revision:5927; number_of_response:1; }","duration":"2.85787003s","start":"2026-05-07T13:25:10.355228Z","end":"2026-05-07T13:25:13.213098Z","steps":["trace[1318052771] 'process raft request' (duration: 2.85485882s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.213269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.355213Z","time spent":"2.857979553s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:25:13.216352Z","caller":"traceutil/trace.go:171","msg":"trace[961164184] transaction","detail":"{read_only:false; response_revision:5928; number_of_response:1; }","duration":"2.859705212s","start":"2026-05-07T13:25:10.356623Z","end":"2026-05-07T13:25:13.216328Z","steps":["trace[961164184] 'process raft request' (duration: 2.859514718s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:25:13.216609Z","caller":"traceutil/trace.go:171","msg":"trace[1951790468] transaction","detail":"{read_only:false; response_revision:5929; number_of_response:1; }","duration":"2.859001516s","start":"2026-05-07T13:25:10.357581Z","end":"2026-05-07T13:25:13.216583Z","steps":["trace[1951790468] 'process raft request' (duration: 2.858697419s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.217071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.35757Z","time spent":"2.859433956s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:25:13.216958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.356605Z","time spent":"2.860267355s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:25:13.216692Z","caller":"traceutil/trace.go:171","msg":"trace[168211371] linearizableReadLoop","detail":"{readStateIndex:6148; appliedIndex:6144; }","duration":"2.845285908s","start":"2026-05-07T13:25:10.371396Z","end":"2026-05-07T13:25:13.216682Z","steps":["trace[168211371] 'read index received' (duration: 2.83888452s)","trace[168211371] 'applied index is now lower than readState.Index' (duration: 6.400358ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:25:13.216757Z","caller":"traceutil/trace.go:171","msg":"trace[1776402731] transaction","detail":"{read_only:false; response_revision:5930; number_of_response:1; }","duration":"884.032686ms","start":"2026-05-07T13:25:12.332694Z","end":"2026-05-07T13:25:13.216727Z","steps":["trace[1776402731] 'process raft request' (duration: 883.788641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.217677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.945104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T13:25:13.217715Z","caller":"traceutil/trace.go:171","msg":"trace[1615081903] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5930; }","duration":"600.006524ms","start":"2026-05-07T13:25:12.617698Z","end":"2026-05-07T13:25:13.217705Z","steps":["trace[1615081903] 'agreement among raft nodes before linearized reading' (duration: 599.947033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.217747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:12.617682Z","time spent":"600.057466ms","remote":"127.0.0.1:47188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":18,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:25:13.21782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.20937273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.154\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T13:25:13.217878Z","caller":"traceutil/trace.go:171","msg":"trace[1682080297] range","detail":"{range_begin:/registry/masterleases/199.19.213.154; range_end:; response_count:1; response_revision:5930; }","duration":"2.209467572s","start":"2026-05-07T13:25:11.008397Z","end":"2026-05-07T13:25:13.217864Z","steps":["trace[1682080297] 'agreement among raft nodes before linearized reading' (duration: 2.209256137s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.217914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:11.008381Z","time spent":"2.209524693s","remote":"127.0.0.1:46704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.154\" "} {"level":"warn","ts":"2026-05-07T13:25:13.217978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.716476538s","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-07T13:25:13.218014Z","caller":"traceutil/trace.go:171","msg":"trace[1022379406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5930; }","duration":"2.71654731s","start":"2026-05-07T13:25:10.501458Z","end":"2026-05-07T13:25:13.218005Z","steps":["trace[1022379406] 'agreement among raft nodes before linearized reading' (duration: 2.716437297s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.218045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.501438Z","time spent":"2.71660072s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T13:25:13.218197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.837944999s","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-07T13:25:13.218234Z","caller":"traceutil/trace.go:171","msg":"trace[1238501618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5930; }","duration":"2.838030771s","start":"2026-05-07T13:25:10.380196Z","end":"2026-05-07T13:25:13.218227Z","steps":["trace[1238501618] 'agreement among raft nodes before linearized reading' (duration: 2.837941159s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.218218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.843874216s","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-07T13:25:13.218273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.380179Z","time spent":"2.838089032s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T13:25:13.217513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.845276259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-07T13:25:13.218414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.670384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T13:25:13.218449Z","caller":"traceutil/trace.go:171","msg":"trace[891641892] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:5930; }","duration":"719.756096ms","start":"2026-05-07T13:25:12.498684Z","end":"2026-05-07T13:25:13.21844Z","steps":["trace[891641892] 'agreement among raft nodes before linearized reading' (duration: 719.692834ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:25:13.218449Z","caller":"traceutil/trace.go:171","msg":"trace[1354674114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5930; }","duration":"2.846213821s","start":"2026-05-07T13:25:10.372185Z","end":"2026-05-07T13:25:13.218399Z","steps":["trace[1354674114] 'agreement among raft nodes before linearized reading' (duration: 2.845281219s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.218489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:12.498593Z","time spent":"719.888639ms","remote":"127.0.0.1:46944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":2,"response size":30,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:25:13.21849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.368887847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:25:13.21854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.372163Z","time spent":"2.846330683s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T13:25:13.218574Z","caller":"traceutil/trace.go:171","msg":"trace[994908487] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:5930; }","duration":"1.369033611s","start":"2026-05-07T13:25:11.849522Z","end":"2026-05-07T13:25:13.218556Z","steps":["trace[994908487] 'agreement among raft nodes before linearized reading' (duration: 1.368849126s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.218414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.854551ms","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":"warn","ts":"2026-05-07T13:25:13.218628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:11.8495Z","time spent":"1.369115442s","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-05-07T13:25:13.218654Z","caller":"traceutil/trace.go:171","msg":"trace[68956346] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:5930; }","duration":"699.108976ms","start":"2026-05-07T13:25:12.519527Z","end":"2026-05-07T13:25:13.218636Z","steps":["trace[68956346] 'agreement among raft nodes before linearized reading' (duration: 698.679817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.216787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.845366971s","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-07T13:25:13.218314Z","caller":"traceutil/trace.go:171","msg":"trace[818547916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5930; }","duration":"2.843999699s","start":"2026-05-07T13:25:10.374297Z","end":"2026-05-07T13:25:13.218297Z","steps":["trace[818547916] 'agreement among raft nodes before linearized reading' (duration: 2.843770634s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.218204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:12.33267Z","time spent":"885.00652ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:25:13.219428Z","caller":"traceutil/trace.go:171","msg":"trace[1289053605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5930; }","duration":"2.848068154s","start":"2026-05-07T13:25:10.371339Z","end":"2026-05-07T13:25:13.219407Z","steps":["trace[1289053605] 'agreement among raft nodes before linearized reading' (duration: 2.845381261s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.219687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.371327Z","time spent":"2.848319109s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T13:25:13.217597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.773386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:25:13.219898Z","caller":"traceutil/trace.go:171","msg":"trace[1798655605] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:5930; }","duration":"200.09783ms","start":"2026-05-07T13:25:13.01978Z","end":"2026-05-07T13:25:13.219878Z","steps":["trace[1798655605] 'agreement among raft nodes before linearized reading' (duration: 197.794796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.21763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.723607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:25:13.219995Z","caller":"traceutil/trace.go:171","msg":"trace[893204314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5930; }","duration":"487.113872ms","start":"2026-05-07T13:25:12.732863Z","end":"2026-05-07T13:25:13.219976Z","steps":["trace[893204314] 'agreement among raft nodes before linearized reading' (duration: 484.751317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.220057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:12.732842Z","time spent":"487.196854ms","remote":"127.0.0.1:46684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:25:13.217558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.605193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:25:13.218858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:12.51947Z","time spent":"699.275091ms","remote":"127.0.0.1:57776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:25:13.219533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.374286Z","time spent":"2.845206457s","remote":"127.0.0.1:46966","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-07T13:25:13.220576Z","caller":"traceutil/trace.go:171","msg":"trace[747036314] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:5930; }","duration":"143.666025ms","start":"2026-05-07T13:25:13.076895Z","end":"2026-05-07T13:25:13.220561Z","steps":["trace[747036314] 'agreement among raft nodes before linearized reading' (duration: 140.639774ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:25:14.7382Z","caller":"traceutil/trace.go:171","msg":"trace[1367358100] transaction","detail":"{read_only:false; response_revision:5938; number_of_response:1; }","duration":"483.027755ms","start":"2026-05-07T13:25:14.255148Z","end":"2026-05-07T13:25:14.738176Z","steps":["trace[1367358100] 'process raft request' (duration: 482.841311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:14.738408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:14.255124Z","time spent":"483.200448ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:25:14.741882Z","caller":"traceutil/trace.go:171","msg":"trace[1288639233] transaction","detail":"{read_only:false; response_revision:5939; number_of_response:1; }","duration":"348.761769ms","start":"2026-05-07T13:25:14.393099Z","end":"2026-05-07T13:25:14.74186Z","steps":["trace[1288639233] 'process raft request' (duration: 348.551054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:14.741975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:14.39307Z","time spent":"348.859581ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:25:16.537737Z","caller":"traceutil/trace.go:171","msg":"trace[1115086041] transaction","detail":"{read_only:false; response_revision:5950; number_of_response:1; }","duration":"235.729161ms","start":"2026-05-07T13:25:16.301983Z","end":"2026-05-07T13:25:16.537713Z","steps":["trace[1115086041] 'process raft request' (duration: 235.516947ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:29:18.013716Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5679} {"level":"info","ts":"2026-05-07T13:29:18.097849Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5679,"took":"82.730944ms","hash":2581511572,"current-db-size-bytes":52862976,"current-db-size":"53 MB","current-db-size-in-use-bytes":17801216,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-07T13:29:18.098036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2581511572,"revision":5679,"compact-revision":2954}