{"level":"warn","ts":"2026-04-17T00:27:52.117023Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T00:27:52.117158Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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-04-17T00:27:52.117263Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T00:27:52.117281Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-17T00:27:52.1174Z","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-04-17T00:27:52.118501Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-04-17T00:27:52.118664Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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-04-17T00:27:52.123711Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.564146ms"} {"level":"info","ts":"2026-04-17T00:27:52.134034Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-04-17T00:27:52.134149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T00:27:52.135164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-04-17T00:27:52.135191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T00:27:52.135204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-04-17T00:27:52.135259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-04-17T00:27:52.141826Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T00:27:52.143608Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T00:27:52.144861Z","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-04-17T00:27:52.14775Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T00:27:52.148512Z","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-04-17T00:27:52.148625Z","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-04-17T00:27:52.148637Z","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-04-17T00:27:52.148901Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T00:27:52.151328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-04-17T00:27:52.152122Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-17T00:27:52.154162Z","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-04-17T00:27:52.154354Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-17T00:27:52.154437Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-17T00:27:52.155476Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T00:27:52.155545Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T00:27:52.636287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T00:27:52.63637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T00:27:52.636403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-04-17T00:27:52.636422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-04-17T00:27:52.63643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-17T00:27:52.636444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-04-17T00:27:52.636456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-17T00:27:52.637311Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:27:52.638168Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T00:27:52.638206Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:27:52.638361Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:27:52.63867Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:27:52.638763Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:27:52.638683Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T00:27:52.638797Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:27:52.638824Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T00:27:52.642155Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-04-17T00:27:52.64244Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-17T00:28:03.544289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.156337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-mrg5w\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-04-17T00:28:03.544465Z","caller":"traceutil/trace.go:171","msg":"trace[579110700] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-mrg5w; range_end:; response_count:1; response_revision:390; }","duration":"183.38474ms","start":"2026-04-17T00:28:03.361048Z","end":"2026-04-17T00:28:03.544433Z","steps":["trace[579110700] 'range keys from in-memory index tree' (duration: 182.941413ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:28:03.691209Z","caller":"traceutil/trace.go:171","msg":"trace[496920766] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:400; }","duration":"103.352609ms","start":"2026-04-17T00:28:03.587822Z","end":"2026-04-17T00:28:03.691175Z","steps":["trace[496920766] 'read index received' (duration: 103.277318ms)","trace[496920766] 'applied index is now lower than readState.Index' (duration: 74.061µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:28:03.69157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.698675ms","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-04-17T00:28:03.691624Z","caller":"traceutil/trace.go:171","msg":"trace[1097168326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:391; }","duration":"103.865637ms","start":"2026-04-17T00:28:03.587745Z","end":"2026-04-17T00:28:03.69161Z","steps":["trace[1097168326] 'agreement among raft nodes before linearized reading' (duration: 103.564482ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:28:03.69182Z","caller":"traceutil/trace.go:171","msg":"trace[373583219] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"138.603461ms","start":"2026-04-17T00:28:03.553152Z","end":"2026-04-17T00:28:03.691755Z","steps":["trace[373583219] 'process raft request' (duration: 137.855569ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:28:04.020949Z","caller":"traceutil/trace.go:171","msg":"trace[478805851] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"325.153018ms","start":"2026-04-17T00:28:03.695755Z","end":"2026-04-17T00:28:04.020908Z","steps":["trace[478805851] 'process raft request' (duration: 324.914164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:28:04.021782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:28:03.695729Z","time spent":"325.3385ms","remote":"127.0.0.1:36520","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-04-17T00:28:04.155488Z","caller":"traceutil/trace.go:171","msg":"trace[1462799503] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"455.982865ms","start":"2026-04-17T00:28:03.69947Z","end":"2026-04-17T00:28:04.155453Z","steps":["trace[1462799503] 'process raft request' (duration: 455.555819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:28:04.156057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:28:03.699448Z","time spent":"456.490633ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:28:25.10767Z","caller":"traceutil/trace.go:171","msg":"trace[58066933] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"309.085662ms","start":"2026-04-17T00:28:24.798566Z","end":"2026-04-17T00:28:25.107652Z","steps":["trace[58066933] 'process raft request' (duration: 309.051442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:28:25.10789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:28:24.798544Z","time spent":"309.297305ms","remote":"127.0.0.1:36326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:28:25.107715Z","caller":"traceutil/trace.go:171","msg":"trace[2050759379] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"329.030955ms","start":"2026-04-17T00:28:24.778625Z","end":"2026-04-17T00:28:25.107656Z","steps":["trace[2050759379] 'process raft request' (duration: 322.985903ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:28:25.107754Z","caller":"traceutil/trace.go:171","msg":"trace[1575206093] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"316.845039ms","start":"2026-04-17T00:28:24.790877Z","end":"2026-04-17T00:28:25.107722Z","steps":["trace[1575206093] 'process raft request' (duration: 316.680116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:28:25.108107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:28:24.778597Z","time spent":"329.439441ms","remote":"127.0.0.1:36326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:28:25.108205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:28:24.790835Z","time spent":"317.241914ms","remote":"127.0.0.1:36326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:28:30.431455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.9087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:28:30.431562Z","caller":"traceutil/trace.go:171","msg":"trace[1912145813] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"195.505349ms","start":"2026-04-17T00:28:30.236039Z","end":"2026-04-17T00:28:30.431544Z","steps":["trace[1912145813] 'process raft request' (duration: 29.312225ms)","trace[1912145813] 'compare' (duration: 165.733078ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:28:31.143093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.523424ms","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-04-17T00:28:31.14318Z","caller":"traceutil/trace.go:171","msg":"trace[1700087381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"605.639236ms","start":"2026-04-17T00:28:30.537522Z","end":"2026-04-17T00:28:31.143161Z","steps":["trace[1700087381] 'range keys from in-memory index tree' (duration: 605.363552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:28:31.14322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:28:30.537506Z","time spent":"605.705576ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T00:28:31.148697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.214561ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T00:28:31.14947Z","caller":"traceutil/trace.go:171","msg":"trace[1152915427] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"277.522744ms","start":"2026-04-17T00:28:30.871933Z","end":"2026-04-17T00:28:31.149456Z","steps":["trace[1152915427] 'process raft request' (duration: 277.017947ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:18.783045Z","caller":"traceutil/trace.go:171","msg":"trace[999793674] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"135.603502ms","start":"2026-04-17T00:29:18.647421Z","end":"2026-04-17T00:29:18.783024Z","steps":["trace[999793674] 'process raft request' (duration: 135.257626ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:18.972123Z","caller":"traceutil/trace.go:171","msg":"trace[1871182379] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"257.647186ms","start":"2026-04-17T00:29:18.714453Z","end":"2026-04-17T00:29:18.9721Z","steps":["trace[1871182379] 'process raft request' (duration: 244.011743ms)","trace[1871182379] 'compare' (duration: 13.532118ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:29:18.973283Z","caller":"traceutil/trace.go:171","msg":"trace[27851075] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"186.737926ms","start":"2026-04-17T00:29:18.786523Z","end":"2026-04-17T00:29:18.973261Z","steps":["trace[27851075] 'process raft request' (duration: 186.581848ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:29:34.993933Z","caller":"traceutil/trace.go:171","msg":"trace[2001400118] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"127.550763ms","start":"2026-04-17T00:29:34.866337Z","end":"2026-04-17T00:29:34.993888Z","steps":["trace[2001400118] 'process raft request' (duration: 122.767402ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:06.779446Z","caller":"traceutil/trace.go:171","msg":"trace[112354645] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"190.845576ms","start":"2026-04-17T00:30:06.588573Z","end":"2026-04-17T00:30:06.779419Z","steps":["trace[112354645] 'process raft request' (duration: 190.574398ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:07.173308Z","caller":"traceutil/trace.go:171","msg":"trace[788859154] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"282.134443ms","start":"2026-04-17T00:30:06.891153Z","end":"2026-04-17T00:30:07.173287Z","steps":["trace[788859154] 'process raft request' (duration: 281.994688ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:07.215791Z","caller":"traceutil/trace.go:171","msg":"trace[1639864784] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1939; }","duration":"296.988076ms","start":"2026-04-17T00:30:06.918785Z","end":"2026-04-17T00:30:07.215773Z","steps":["trace[1639864784] 'read index received' (duration: 254.322994ms)","trace[1639864784] 'applied index is now lower than readState.Index' (duration: 42.664312ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:30:07.215841Z","caller":"traceutil/trace.go:171","msg":"trace[1833899021] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"322.676796ms","start":"2026-04-17T00:30:06.893118Z","end":"2026-04-17T00:30:07.215795Z","steps":["trace[1833899021] 'process raft request' (duration: 322.473861ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:07.215877Z","caller":"traceutil/trace.go:171","msg":"trace[1650808173] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"168.532593ms","start":"2026-04-17T00:30:07.047334Z","end":"2026-04-17T00:30:07.215867Z","steps":["trace[1650808173] 'process raft request' (duration: 168.363197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:07.215997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:06.89311Z","time spent":"322.800781ms","remote":"127.0.0.1:36520","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-04-17T00:30:07.216261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.40465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49224c5d-1a83-401e-ba84-94ca8b19c026\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-17T00:30:07.216346Z","caller":"traceutil/trace.go:171","msg":"trace[750081230] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49224c5d-1a83-401e-ba84-94ca8b19c026; range_end:; response_count:1; response_revision:1878; }","duration":"297.572104ms","start":"2026-04-17T00:30:06.918752Z","end":"2026-04-17T00:30:07.216324Z","steps":["trace[750081230] 'agreement among raft nodes before linearized reading' (duration: 297.260704ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:21.369452Z","caller":"traceutil/trace.go:171","msg":"trace[666514608] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"153.488906ms","start":"2026-04-17T00:30:21.215934Z","end":"2026-04-17T00:30:21.369423Z","steps":["trace[666514608] 'process raft request' (duration: 153.28873ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:21.372551Z","caller":"traceutil/trace.go:171","msg":"trace[132105582] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"153.888357ms","start":"2026-04-17T00:30:21.218646Z","end":"2026-04-17T00:30:21.372534Z","steps":["trace[132105582] 'process raft request' (duration: 153.764334ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:21.372706Z","caller":"traceutil/trace.go:171","msg":"trace[513322715] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"117.033819ms","start":"2026-04-17T00:30:21.255665Z","end":"2026-04-17T00:30:21.372699Z","steps":["trace[513322715] 'process raft request' (duration: 116.843884ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:21.374203Z","caller":"traceutil/trace.go:171","msg":"trace[789488846] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"116.940236ms","start":"2026-04-17T00:30:21.257252Z","end":"2026-04-17T00:30:21.374192Z","steps":["trace[789488846] 'process raft request' (duration: 115.422003ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:21.374624Z","caller":"traceutil/trace.go:171","msg":"trace[594622562] linearizableReadLoop","detail":"{readStateIndex:2061; appliedIndex:2056; }","duration":"102.862252ms","start":"2026-04-17T00:30:21.27175Z","end":"2026-04-17T00:30:21.374612Z","steps":["trace[594622562] 'read index received' (duration: 97.506008ms)","trace[594622562] 'applied index is now lower than readState.Index' (duration: 5.355204ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:30:21.374843Z","caller":"traceutil/trace.go:171","msg":"trace[993543927] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"110.904034ms","start":"2026-04-17T00:30:21.26393Z","end":"2026-04-17T00:30:21.374834Z","steps":["trace[993543927] 'process raft request' (duration: 110.230394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:21.374949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.196273ms","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-04-17T00:30:21.374988Z","caller":"traceutil/trace.go:171","msg":"trace[1790113464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1993; }","duration":"103.288375ms","start":"2026-04-17T00:30:21.271689Z","end":"2026-04-17T00:30:21.374977Z","steps":["trace[1790113464] 'agreement among raft nodes before linearized reading' (duration: 103.205382ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:39.541359Z","caller":"traceutil/trace.go:171","msg":"trace[151927987] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"106.199659ms","start":"2026-04-17T00:30:39.435138Z","end":"2026-04-17T00:30:39.541338Z","steps":["trace[151927987] 'process raft request' (duration: 105.684255ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:39.662376Z","caller":"traceutil/trace.go:171","msg":"trace[1205062865] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"116.806852ms","start":"2026-04-17T00:30:39.545541Z","end":"2026-04-17T00:30:39.662348Z","steps":["trace[1205062865] 'process raft request' (duration: 98.42007ms)","trace[1205062865] 'compare' (duration: 18.256418ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:30:39.709837Z","caller":"traceutil/trace.go:171","msg":"trace[1110266108] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"163.214614ms","start":"2026-04-17T00:30:39.546585Z","end":"2026-04-17T00:30:39.7098Z","steps":["trace[1110266108] 'process raft request' (duration: 163.110232ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:41.037978Z","caller":"traceutil/trace.go:171","msg":"trace[1935131325] linearizableReadLoop","detail":"{readStateIndex:2183; appliedIndex:2181; }","duration":"112.195472ms","start":"2026-04-17T00:30:40.925758Z","end":"2026-04-17T00:30:41.037954Z","steps":["trace[1935131325] 'read index received' (duration: 85.837762ms)","trace[1935131325] 'applied index is now lower than readState.Index' (duration: 26.3566ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:30:41.038025Z","caller":"traceutil/trace.go:171","msg":"trace[1460553101] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"178.130837ms","start":"2026-04-17T00:30:40.859869Z","end":"2026-04-17T00:30:41.038Z","steps":["trace[1460553101] 'process raft request' (duration: 177.913061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:41.039338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.548516ms","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-04-17T00:30:41.039391Z","caller":"traceutil/trace.go:171","msg":"trace[649699782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2111; }","duration":"113.66319ms","start":"2026-04-17T00:30:40.925716Z","end":"2026-04-17T00:30:41.039379Z","steps":["trace[649699782] 'agreement among raft nodes before linearized reading' (duration: 112.351957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:41.647888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.337826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:30:41.648074Z","caller":"traceutil/trace.go:171","msg":"trace[1948234456] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"604.163513ms","start":"2026-04-17T00:30:41.04389Z","end":"2026-04-17T00:30:41.648054Z","steps":["trace[1948234456] 'process raft request' (duration: 136.57361ms)","trace[1948234456] 'compare' (duration: 466.976847ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:30:41.64819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:41.043866Z","time spent":"604.280846ms","remote":"127.0.0.1:36520","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-04-17T00:30:42.13166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.491423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:30:42.132138Z","caller":"traceutil/trace.go:171","msg":"trace[345804565] linearizableReadLoop","detail":"{readStateIndex:2188; appliedIndex:2184; }","duration":"466.62968ms","start":"2026-04-17T00:30:41.665485Z","end":"2026-04-17T00:30:42.132115Z","steps":["trace[345804565] 'read index received' (duration: 153.587722ms)","trace[345804565] 'applied index is now lower than readState.Index' (duration: 313.040828ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:30:42.132336Z","caller":"traceutil/trace.go:171","msg":"trace[1424193744] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"635.919141ms","start":"2026-04-17T00:30:41.496397Z","end":"2026-04-17T00:30:42.132316Z","steps":["trace[1424193744] 'process raft request' (duration: 322.662299ms)","trace[1424193744] 'compare' (duration: 312.336589ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:30:42.132513Z","caller":"traceutil/trace.go:171","msg":"trace[1550587724] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"586.170138ms","start":"2026-04-17T00:30:41.546333Z","end":"2026-04-17T00:30:42.132503Z","steps":["trace[1550587724] 'process raft request' (duration: 585.571914ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:30:42.132557Z","caller":"traceutil/trace.go:171","msg":"trace[962777080] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"585.757128ms","start":"2026-04-17T00:30:41.546772Z","end":"2026-04-17T00:30:42.132529Z","steps":["trace[962777080] 'process raft request' (duration: 585.243405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:42.132633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:41.546305Z","time spent":"586.25324ms","remote":"127.0.0.1:36520","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-04-17T00:30:42.132666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.72762ms","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-04-17T00:30:42.132708Z","caller":"traceutil/trace.go:171","msg":"trace[755251359] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2116; }","duration":"408.80066ms","start":"2026-04-17T00:30:41.723889Z","end":"2026-04-17T00:30:42.132689Z","steps":["trace[755251359] 'agreement among raft nodes before linearized reading' (duration: 408.726758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:42.132719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:41.546748Z","time spent":"585.89063ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:30:42.132503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:41.496373Z","time spent":"636.071986ms","remote":"127.0.0.1:36520","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-04-17T00:30:42.132755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:41.723859Z","time spent":"408.887572ms","remote":"127.0.0.1:55760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-17T00:30:42.132597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.112172ms","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-04-17T00:30:42.132923Z","caller":"traceutil/trace.go:171","msg":"trace[1325456346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2116; }","duration":"467.463301ms","start":"2026-04-17T00:30:41.665439Z","end":"2026-04-17T00:30:42.132902Z","steps":["trace[1325456346] 'agreement among raft nodes before linearized reading' (duration: 467.106663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:42.13297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.524265ms","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-04-17T00:30:42.133194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.829515ms","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-04-17T00:30:42.133363Z","caller":"traceutil/trace.go:171","msg":"trace[1494729584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2116; }","duration":"201.036602ms","start":"2026-04-17T00:30:41.932306Z","end":"2026-04-17T00:30:42.133343Z","steps":["trace[1494729584] 'agreement among raft nodes before linearized reading' (duration: 200.614371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:42.133042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:41.665414Z","time spent":"467.607094ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T00:30:42.13235Z","caller":"traceutil/trace.go:171","msg":"trace[1592104362] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"584.350581ms","start":"2026-04-17T00:30:41.547972Z","end":"2026-04-17T00:30:42.132322Z","steps":["trace[1592104362] 'process raft request' (duration: 584.090805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:42.133683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:41.547962Z","time spent":"585.679995ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:30:42.132502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.661557ms","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-04-17T00:30:42.133859Z","caller":"traceutil/trace.go:171","msg":"trace[2135755046] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2116; }","duration":"421.068223ms","start":"2026-04-17T00:30:41.712776Z","end":"2026-04-17T00:30:42.133845Z","steps":["trace[2135755046] 'agreement among raft nodes before linearized reading' (duration: 419.576385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:42.133906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:41.712756Z","time spent":"421.135273ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T00:30:42.135024Z","caller":"traceutil/trace.go:171","msg":"trace[230340406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2116; }","duration":"377.866414ms","start":"2026-04-17T00:30:41.755336Z","end":"2026-04-17T00:30:42.133202Z","steps":["trace[230340406] 'agreement among raft nodes before linearized reading' (duration: 377.218298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:30:42.1351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:30:41.755318Z","time spent":"379.767202ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T00:31:36.057037Z","caller":"traceutil/trace.go:171","msg":"trace[1751400903] linearizableReadLoop","detail":"{readStateIndex:2594; appliedIndex:2593; }","duration":"462.537127ms","start":"2026-04-17T00:31:35.594485Z","end":"2026-04-17T00:31:36.057022Z","steps":["trace[1751400903] 'read index received' (duration: 462.409574ms)","trace[1751400903] 'applied index is now lower than readState.Index' (duration: 126.673µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:31:36.057234Z","caller":"traceutil/trace.go:171","msg":"trace[1294416614] transaction","detail":"{read_only:false; response_revision:2510; number_of_response:1; }","duration":"468.641791ms","start":"2026-04-17T00:31:35.588584Z","end":"2026-04-17T00:31:36.057226Z","steps":["trace[1294416614] 'process raft request' (duration: 468.344375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:31:36.057312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:35.588569Z","time spent":"468.687963ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:31:36.057474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.990016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-17T00:31:36.057495Z","caller":"traceutil/trace.go:171","msg":"trace[168442607] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:2510; }","duration":"463.049449ms","start":"2026-04-17T00:31:35.594439Z","end":"2026-04-17T00:31:36.057488Z","steps":["trace[168442607] 'agreement among raft nodes before linearized reading' (duration: 462.978257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:31:36.057513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:35.594415Z","time spent":"463.093199ms","remote":"127.0.0.1:36304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.19\" "} {"level":"warn","ts":"2026-04-17T00:31:36.057659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.192269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-17T00:31:36.057677Z","caller":"traceutil/trace.go:171","msg":"trace[1509280404] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2510; }","duration":"399.242043ms","start":"2026-04-17T00:31:35.65843Z","end":"2026-04-17T00:31:36.057672Z","steps":["trace[1509280404] 'agreement among raft nodes before linearized reading' (duration: 399.188021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:31:36.057694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:35.658391Z","time spent":"399.299243ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-17T00:31:36.057983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.608003ms","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-04-17T00:31:36.058043Z","caller":"traceutil/trace.go:171","msg":"trace[226992977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2510; }","duration":"167.725147ms","start":"2026-04-17T00:31:35.890303Z","end":"2026-04-17T00:31:36.058029Z","steps":["trace[226992977] 'agreement among raft nodes before linearized reading' (duration: 167.553383ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:08.749543Z","caller":"traceutil/trace.go:171","msg":"trace[1033138554] transaction","detail":"{read_only:false; response_revision:2691; number_of_response:1; }","duration":"299.315083ms","start":"2026-04-17T00:32:08.450204Z","end":"2026-04-17T00:32:08.749519Z","steps":["trace[1033138554] 'process raft request' (duration: 299.178811ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:08.834343Z","caller":"traceutil/trace.go:171","msg":"trace[47039213] linearizableReadLoop","detail":"{readStateIndex:2786; appliedIndex:2783; }","duration":"279.831898ms","start":"2026-04-17T00:32:08.554491Z","end":"2026-04-17T00:32:08.834323Z","steps":["trace[47039213] 'read index received' (duration: 195.36251ms)","trace[47039213] 'applied index is now lower than readState.Index' (duration: 84.468458ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:32:08.834402Z","caller":"traceutil/trace.go:171","msg":"trace[48469914] transaction","detail":"{read_only:false; response_revision:2693; number_of_response:1; }","duration":"374.619398ms","start":"2026-04-17T00:32:08.459753Z","end":"2026-04-17T00:32:08.834372Z","steps":["trace[48469914] 'process raft request' (duration: 374.499476ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:08.834464Z","caller":"traceutil/trace.go:171","msg":"trace[318236075] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"376.88057ms","start":"2026-04-17T00:32:08.457539Z","end":"2026-04-17T00:32:08.834419Z","steps":["trace[318236075] 'process raft request' (duration: 376.602275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:08.834606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:08.459733Z","time spent":"374.778251ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:32:08.834653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:08.4575Z","time spent":"377.056933ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:32:08.834616Z","caller":"traceutil/trace.go:171","msg":"trace[623853723] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"374.74955ms","start":"2026-04-17T00:32:08.459851Z","end":"2026-04-17T00:32:08.8346Z","steps":["trace[623853723] 'process raft request' (duration: 374.447235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:08.834861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.354477ms","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-04-17T00:32:08.834929Z","caller":"traceutil/trace.go:171","msg":"trace[705174182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2694; }","duration":"280.484648ms","start":"2026-04-17T00:32:08.554428Z","end":"2026-04-17T00:32:08.834913Z","steps":["trace[705174182] 'agreement among raft nodes before linearized reading' (duration: 280.268004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:08.835065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.519579ms","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-04-17T00:32:08.835003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:08.459845Z","time spent":"375.074517ms","remote":"127.0.0.1:36520","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-04-17T00:32:08.835077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.112553ms","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-04-17T00:32:08.835356Z","caller":"traceutil/trace.go:171","msg":"trace[408577501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2694; }","duration":"279.43932ms","start":"2026-04-17T00:32:08.5559Z","end":"2026-04-17T00:32:08.83534Z","steps":["trace[408577501] 'agreement among raft nodes before linearized reading' (duration: 278.877279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:08.835359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.205881ms","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-04-17T00:32:08.835526Z","caller":"traceutil/trace.go:171","msg":"trace[971424839] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2694; }","duration":"274.393655ms","start":"2026-04-17T00:32:08.561119Z","end":"2026-04-17T00:32:08.835512Z","steps":["trace[971424839] 'agreement among raft nodes before linearized reading' (duration: 273.654691ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:08.835111Z","caller":"traceutil/trace.go:171","msg":"trace[1248278738] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2694; }","duration":"280.612292ms","start":"2026-04-17T00:32:08.554489Z","end":"2026-04-17T00:32:08.835102Z","steps":["trace[1248278738] 'agreement among raft nodes before linearized reading' (duration: 280.239815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:10.275316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271537783s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:32:10.275742Z","caller":"traceutil/trace.go:171","msg":"trace[96316313] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2698; }","duration":"1.271965191s","start":"2026-04-17T00:32:09.003754Z","end":"2026-04-17T00:32:10.275719Z","steps":["trace[96316313] 'range keys from in-memory index tree' (duration: 1.271527123s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:10.276205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.53038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:32:10.276307Z","caller":"traceutil/trace.go:171","msg":"trace[1159805414] linearizableReadLoop","detail":"{readStateIndex:2791; appliedIndex:2790; }","duration":"881.03776ms","start":"2026-04-17T00:32:09.395252Z","end":"2026-04-17T00:32:10.27629Z","steps":["trace[1159805414] 'read index received' (duration: 215.120494ms)","trace[1159805414] 'applied index is now lower than readState.Index' (duration: 665.915136ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:32:10.276382Z","caller":"traceutil/trace.go:171","msg":"trace[1092040199] transaction","detail":"{read_only:false; response_revision:2699; number_of_response:1; }","duration":"1.311382811s","start":"2026-04-17T00:32:08.964984Z","end":"2026-04-17T00:32:10.276367Z","steps":["trace[1092040199] 'process raft request' (duration: 645.480364ms)","trace[1092040199] 'compare' (duration: 665.380497ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:32:10.27645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:08.964961Z","time spent":"1.311452492s","remote":"127.0.0.1:36346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:32:10.2767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.415067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:32:10.276777Z","caller":"traceutil/trace.go:171","msg":"trace[1761647870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2699; }","duration":"881.55781ms","start":"2026-04-17T00:32:09.3952Z","end":"2026-04-17T00:32:10.276758Z","steps":["trace[1761647870] 'agreement among raft nodes before linearized reading' (duration: 881.344076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:10.276847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:09.395178Z","time spent":"881.658373ms","remote":"127.0.0.1:36266","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-04-17T00:32:10.488751Z","caller":"traceutil/trace.go:171","msg":"trace[1541542420] linearizableReadLoop","detail":"{readStateIndex:2792; appliedIndex:2791; }","duration":"212.394986ms","start":"2026-04-17T00:32:10.276334Z","end":"2026-04-17T00:32:10.488729Z","steps":["trace[1541542420] 'read index received' (duration: 186.873688ms)","trace[1541542420] 'applied index is now lower than readState.Index' (duration: 25.520238ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:32:10.488891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.391599ms","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":"info","ts":"2026-04-17T00:32:10.488913Z","caller":"traceutil/trace.go:171","msg":"trace[1199941993] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"416.377182ms","start":"2026-04-17T00:32:10.072497Z","end":"2026-04-17T00:32:10.488874Z","steps":["trace[1199941993] 'process raft request' (duration: 390.892935ms)","trace[1199941993] 'compare' (duration: 25.237823ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:32:10.488979Z","caller":"traceutil/trace.go:171","msg":"trace[471484459] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2700; }","duration":"217.525041ms","start":"2026-04-17T00:32:10.271434Z","end":"2026-04-17T00:32:10.488959Z","steps":["trace[471484459] 'agreement among raft nodes before linearized reading' (duration: 217.320877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:10.489055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:10.072473Z","time spent":"416.504136ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:32:10.489059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.093183ms","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-04-17T00:32:10.489127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.24142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-04-17T00:32:10.489245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.995924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:32:10.489281Z","caller":"traceutil/trace.go:171","msg":"trace[28456963] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2700; }","duration":"976.058736ms","start":"2026-04-17T00:32:09.51321Z","end":"2026-04-17T00:32:10.489269Z","steps":["trace[28456963] 'agreement among raft nodes before linearized reading' (duration: 975.995024ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:10.489237Z","caller":"traceutil/trace.go:171","msg":"trace[99814201] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2700; }","duration":"830.406984ms","start":"2026-04-17T00:32:09.658815Z","end":"2026-04-17T00:32:10.489222Z","steps":["trace[99814201] 'agreement among raft nodes before linearized reading' (duration: 830.044268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:10.489319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:09.513192Z","time spent":"976.119406ms","remote":"127.0.0.1:40692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-04-17T00:32:10.489163Z","caller":"traceutil/trace.go:171","msg":"trace[671228104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2700; }","duration":"256.269266ms","start":"2026-04-17T00:32:10.232874Z","end":"2026-04-17T00:32:10.489143Z","steps":["trace[671228104] 'agreement among raft nodes before linearized reading' (duration: 255.915359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:10.489505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:09.658793Z","time spent":"830.595738ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-17T00:32:10.489212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.271254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:32:10.489908Z","caller":"traceutil/trace.go:171","msg":"trace[1247137414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2700; }","duration":"212.005468ms","start":"2026-04-17T00:32:10.277885Z","end":"2026-04-17T00:32:10.48989Z","steps":["trace[1247137414] 'agreement among raft nodes before linearized reading' (duration: 211.176883ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:43.203271Z","caller":"traceutil/trace.go:171","msg":"trace[2119798849] transaction","detail":"{read_only:false; response_revision:3183; number_of_response:1; }","duration":"247.542267ms","start":"2026-04-17T00:33:42.955701Z","end":"2026-04-17T00:33:43.203243Z","steps":["trace[2119798849] 'process raft request' (duration: 247.382452ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:43.36511Z","caller":"traceutil/trace.go:171","msg":"trace[159604315] transaction","detail":"{read_only:false; response_revision:3187; number_of_response:1; }","duration":"224.873061ms","start":"2026-04-17T00:33:43.140209Z","end":"2026-04-17T00:33:43.365082Z","steps":["trace[159604315] 'process raft request' (duration: 224.823569ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:43.365199Z","caller":"traceutil/trace.go:171","msg":"trace[1531853814] transaction","detail":"{read_only:false; response_revision:3184; number_of_response:1; }","duration":"273.394079ms","start":"2026-04-17T00:33:43.091772Z","end":"2026-04-17T00:33:43.365166Z","steps":["trace[1531853814] 'process raft request' (duration: 202.534257ms)","trace[1531853814] 'compare' (duration: 70.51612ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:33:43.365383Z","caller":"traceutil/trace.go:171","msg":"trace[870873998] transaction","detail":"{read_only:false; response_revision:3185; number_of_response:1; }","duration":"228.139275ms","start":"2026-04-17T00:33:43.137216Z","end":"2026-04-17T00:33:43.365355Z","steps":["trace[870873998] 'process raft request' (duration: 227.732753ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:43.365467Z","caller":"traceutil/trace.go:171","msg":"trace[287838853] transaction","detail":"{read_only:false; response_revision:3186; number_of_response:1; }","duration":"226.678479ms","start":"2026-04-17T00:33:43.138766Z","end":"2026-04-17T00:33:43.365445Z","steps":["trace[287838853] 'process raft request' (duration: 226.225525ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:43.553839Z","caller":"traceutil/trace.go:171","msg":"trace[958063358] transaction","detail":"{read_only:false; response_revision:3190; number_of_response:1; }","duration":"179.882735ms","start":"2026-04-17T00:33:43.373934Z","end":"2026-04-17T00:33:43.553817Z","steps":["trace[958063358] 'process raft request' (duration: 179.809304ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:43.55396Z","caller":"traceutil/trace.go:171","msg":"trace[297207498] linearizableReadLoop","detail":"{readStateIndex:3303; appliedIndex:3299; }","duration":"159.59841ms","start":"2026-04-17T00:33:43.394346Z","end":"2026-04-17T00:33:43.553944Z","steps":["trace[297207498] 'read index received' (duration: 37.12ms)","trace[297207498] 'applied index is now lower than readState.Index' (duration: 122.47732ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:33:43.55384Z","caller":"traceutil/trace.go:171","msg":"trace[1713468228] transaction","detail":"{read_only:false; response_revision:3189; number_of_response:1; }","duration":"180.624909ms","start":"2026-04-17T00:33:43.373193Z","end":"2026-04-17T00:33:43.553818Z","steps":["trace[1713468228] 'process raft request' (duration: 109.298014ms)","trace[1713468228] 'compare' (duration: 71.112849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:33:43.554039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.688043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:33:43.554066Z","caller":"traceutil/trace.go:171","msg":"trace[1760257961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3191; }","duration":"159.793437ms","start":"2026-04-17T00:33:43.394264Z","end":"2026-04-17T00:33:43.554058Z","steps":["trace[1760257961] 'agreement among raft nodes before linearized reading' (duration: 159.722384ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:43.554232Z","caller":"traceutil/trace.go:171","msg":"trace[245460681] transaction","detail":"{read_only:false; response_revision:3191; number_of_response:1; }","duration":"180.00509ms","start":"2026-04-17T00:33:43.374211Z","end":"2026-04-17T00:33:43.554216Z","steps":["trace[245460681] 'process raft request' (duration: 179.571026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:33:43.919893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.36365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-17T00:33:43.91995Z","caller":"traceutil/trace.go:171","msg":"trace[1214562005] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3191; }","duration":"261.428803ms","start":"2026-04-17T00:33:43.658508Z","end":"2026-04-17T00:33:43.919937Z","steps":["trace[1214562005] 'range keys from in-memory index tree' (duration: 261.206985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:33:51.635109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.049536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:33:51.635217Z","caller":"traceutil/trace.go:171","msg":"trace[1753274617] transaction","detail":"{read_only:false; response_revision:3240; number_of_response:1; }","duration":"216.195618ms","start":"2026-04-17T00:33:51.419002Z","end":"2026-04-17T00:33:51.635197Z","steps":["trace[1753274617] 'process raft request' (duration: 84.982396ms)","trace[1753274617] 'compare' (duration: 130.942193ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:33:51.64353Z","caller":"traceutil/trace.go:171","msg":"trace[1523211283] linearizableReadLoop","detail":"{readStateIndex:3360; appliedIndex:3354; }","duration":"164.102237ms","start":"2026-04-17T00:33:51.479406Z","end":"2026-04-17T00:33:51.643508Z","steps":["trace[1523211283] 'read index received' (duration: 24.47754ms)","trace[1523211283] 'applied index is now lower than readState.Index' (duration: 139.623697ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:33:51.643853Z","caller":"traceutil/trace.go:171","msg":"trace[967199215] transaction","detail":"{read_only:false; response_revision:3244; number_of_response:1; }","duration":"169.190917ms","start":"2026-04-17T00:33:51.474646Z","end":"2026-04-17T00:33:51.643837Z","steps":["trace[967199215] 'process raft request' (duration: 168.799885ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:51.644013Z","caller":"traceutil/trace.go:171","msg":"trace[188075531] transaction","detail":"{read_only:false; response_revision:3241; number_of_response:1; }","duration":"205.7879ms","start":"2026-04-17T00:33:51.438198Z","end":"2026-04-17T00:33:51.643985Z","steps":["trace[188075531] 'process raft request' (duration: 205.073638ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:51.64406Z","caller":"traceutil/trace.go:171","msg":"trace[770195111] transaction","detail":"{read_only:false; response_revision:3242; number_of_response:1; }","duration":"182.157926ms","start":"2026-04-17T00:33:51.461885Z","end":"2026-04-17T00:33:51.644043Z","steps":["trace[770195111] 'process raft request' (duration: 181.483735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:33:51.644235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.790558ms","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-04-17T00:33:51.644247Z","caller":"traceutil/trace.go:171","msg":"trace[613198512] transaction","detail":"{read_only:false; response_revision:3245; number_of_response:1; }","duration":"169.179157ms","start":"2026-04-17T00:33:51.475034Z","end":"2026-04-17T00:33:51.644213Z","steps":["trace[613198512] 'process raft request' (duration: 168.441464ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:51.644324Z","caller":"traceutil/trace.go:171","msg":"trace[756642143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3245; }","duration":"164.932993ms","start":"2026-04-17T00:33:51.479374Z","end":"2026-04-17T00:33:51.644307Z","steps":["trace[756642143] 'agreement among raft nodes before linearized reading' (duration: 164.663285ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:51.644724Z","caller":"traceutil/trace.go:171","msg":"trace[40677390] transaction","detail":"{read_only:false; response_revision:3243; number_of_response:1; }","duration":"175.874368ms","start":"2026-04-17T00:33:51.468836Z","end":"2026-04-17T00:33:51.644711Z","steps":["trace[40677390] 'process raft request' (duration: 174.570297ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:51.815508Z","caller":"traceutil/trace.go:171","msg":"trace[1940291979] transaction","detail":"{read_only:false; response_revision:3248; number_of_response:1; }","duration":"165.821341ms","start":"2026-04-17T00:33:51.649663Z","end":"2026-04-17T00:33:51.815484Z","steps":["trace[1940291979] 'process raft request' (duration: 165.747729ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:51.815539Z","caller":"traceutil/trace.go:171","msg":"trace[1550668152] transaction","detail":"{read_only:false; response_revision:3247; number_of_response:1; }","duration":"165.927164ms","start":"2026-04-17T00:33:51.649582Z","end":"2026-04-17T00:33:51.81551Z","steps":["trace[1550668152] 'process raft request' (duration: 100.090391ms)","trace[1550668152] 'compare' (duration: 65.607206ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:33:51.816376Z","caller":"traceutil/trace.go:171","msg":"trace[1290934325] transaction","detail":"{read_only:false; response_revision:3249; number_of_response:1; }","duration":"166.628596ms","start":"2026-04-17T00:33:51.649726Z","end":"2026-04-17T00:33:51.816355Z","steps":["trace[1290934325] 'process raft request' (duration: 165.721987ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:51.816627Z","caller":"traceutil/trace.go:171","msg":"trace[207150493] linearizableReadLoop","detail":"{readStateIndex:3365; appliedIndex:3360; }","duration":"162.501997ms","start":"2026-04-17T00:33:51.654117Z","end":"2026-04-17T00:33:51.816619Z","steps":["trace[207150493] 'read index received' (duration: 44.498031ms)","trace[207150493] 'applied index is now lower than readState.Index' (duration: 118.003316ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:33:51.816747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.61539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/auth-system/keycloak.199-204-45-19.nip.io-tls\" ","response":"range_response_count:1 size:2375"} {"level":"info","ts":"2026-04-17T00:33:51.816774Z","caller":"traceutil/trace.go:171","msg":"trace[2047411766] range","detail":"{range_begin:/registry/cert-manager.io/certificates/auth-system/keycloak.199-204-45-19.nip.io-tls; range_end:; response_count:1; response_revision:3250; }","duration":"162.668622ms","start":"2026-04-17T00:33:51.654096Z","end":"2026-04-17T00:33:51.816765Z","steps":["trace[2047411766] 'agreement among raft nodes before linearized reading' (duration: 162.552927ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:51.816934Z","caller":"traceutil/trace.go:171","msg":"trace[1390098332] transaction","detail":"{read_only:false; response_revision:3250; number_of_response:1; }","duration":"164.772547ms","start":"2026-04-17T00:33:51.652155Z","end":"2026-04-17T00:33:51.816928Z","steps":["trace[1390098332] 'process raft request' (duration: 164.141217ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:52.097074Z","caller":"traceutil/trace.go:171","msg":"trace[1747393845] linearizableReadLoop","detail":"{readStateIndex:3367; appliedIndex:3366; }","duration":"166.535624ms","start":"2026-04-17T00:33:51.930515Z","end":"2026-04-17T00:33:52.097051Z","steps":["trace[1747393845] 'read index received' (duration: 160.223387ms)","trace[1747393845] 'applied index is now lower than readState.Index' (duration: 6.311127ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:33:52.097325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.753821ms","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-04-17T00:33:52.097401Z","caller":"traceutil/trace.go:171","msg":"trace[1307704578] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3252; }","duration":"166.873744ms","start":"2026-04-17T00:33:51.930484Z","end":"2026-04-17T00:33:52.097358Z","steps":["trace[1307704578] 'agreement among raft nodes before linearized reading' (duration: 166.654577ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:52.097192Z","caller":"traceutil/trace.go:171","msg":"trace[1876025741] transaction","detail":"{read_only:false; response_revision:3252; number_of_response:1; }","duration":"211.360956ms","start":"2026-04-17T00:33:51.885801Z","end":"2026-04-17T00:33:52.097162Z","steps":["trace[1876025741] 'process raft request' (duration: 204.993496ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:52.3192Z","caller":"traceutil/trace.go:171","msg":"trace[480460908] transaction","detail":"{read_only:false; response_revision:3260; number_of_response:1; }","duration":"201.076481ms","start":"2026-04-17T00:33:52.118099Z","end":"2026-04-17T00:33:52.319175Z","steps":["trace[480460908] 'process raft request' (duration: 200.999439ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:52.319345Z","caller":"traceutil/trace.go:171","msg":"trace[1929078636] transaction","detail":"{read_only:false; response_revision:3256; number_of_response:1; }","duration":"216.429933ms","start":"2026-04-17T00:33:52.102885Z","end":"2026-04-17T00:33:52.319314Z","steps":["trace[1929078636] 'process raft request' (duration: 216.091813ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:52.319363Z","caller":"traceutil/trace.go:171","msg":"trace[1438427215] transaction","detail":"{read_only:false; response_revision:3254; number_of_response:1; }","duration":"216.571007ms","start":"2026-04-17T00:33:52.102766Z","end":"2026-04-17T00:33:52.319337Z","steps":["trace[1438427215] 'process raft request' (duration: 215.612137ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:52.319552Z","caller":"traceutil/trace.go:171","msg":"trace[906667895] transaction","detail":"{read_only:false; response_revision:3255; number_of_response:1; }","duration":"216.714571ms","start":"2026-04-17T00:33:52.102828Z","end":"2026-04-17T00:33:52.319543Z","steps":["trace[906667895] 'process raft request' (duration: 216.081411ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:52.319737Z","caller":"traceutil/trace.go:171","msg":"trace[1115247895] transaction","detail":"{read_only:false; response_revision:3259; number_of_response:1; }","duration":"216.637949ms","start":"2026-04-17T00:33:52.103076Z","end":"2026-04-17T00:33:52.319714Z","steps":["trace[1115247895] 'process raft request' (duration: 215.991219ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:52.319827Z","caller":"traceutil/trace.go:171","msg":"trace[1634014240] transaction","detail":"{read_only:false; response_revision:3257; number_of_response:1; }","duration":"216.895177ms","start":"2026-04-17T00:33:52.102922Z","end":"2026-04-17T00:33:52.319817Z","steps":["trace[1634014240] 'process raft request' (duration: 216.084722ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:52.319863Z","caller":"traceutil/trace.go:171","msg":"trace[1860725107] transaction","detail":"{read_only:false; response_revision:3258; number_of_response:1; }","duration":"216.796224ms","start":"2026-04-17T00:33:52.103052Z","end":"2026-04-17T00:33:52.319848Z","steps":["trace[1860725107] 'process raft request' (duration: 215.985028ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:36.488282Z","caller":"traceutil/trace.go:171","msg":"trace[1947983550] transaction","detail":"{read_only:false; response_revision:3975; number_of_response:1; }","duration":"131.353866ms","start":"2026-04-17T00:35:36.356885Z","end":"2026-04-17T00:35:36.488238Z","steps":["trace[1947983550] 'process raft request' (duration: 103.429901ms)","trace[1947983550] 'compare' (duration: 27.139887ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:35:36.488511Z","caller":"traceutil/trace.go:171","msg":"trace[46949931] transaction","detail":"{read_only:false; response_revision:3976; number_of_response:1; }","duration":"131.508082ms","start":"2026-04-17T00:35:36.356972Z","end":"2026-04-17T00:35:36.48848Z","steps":["trace[46949931] 'process raft request' (duration: 130.935367ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:44.796156Z","caller":"traceutil/trace.go:171","msg":"trace[1696996675] transaction","detail":"{read_only:false; response_revision:4372; number_of_response:1; }","duration":"118.350192ms","start":"2026-04-17T00:35:44.677782Z","end":"2026-04-17T00:35:44.796132Z","steps":["trace[1696996675] 'process raft request' (duration: 118.169847ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:44.83847Z","caller":"traceutil/trace.go:171","msg":"trace[2112008299] transaction","detail":"{read_only:false; response_revision:4373; number_of_response:1; }","duration":"160.25745ms","start":"2026-04-17T00:35:44.678183Z","end":"2026-04-17T00:35:44.838441Z","steps":["trace[2112008299] 'process raft request' (duration: 160.054215ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:44.838746Z","caller":"traceutil/trace.go:171","msg":"trace[1737083828] transaction","detail":"{read_only:false; response_revision:4374; number_of_response:1; }","duration":"159.340668ms","start":"2026-04-17T00:35:44.679373Z","end":"2026-04-17T00:35:44.838714Z","steps":["trace[1737083828] 'process raft request' (duration: 159.023531ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:45.104745Z","caller":"traceutil/trace.go:171","msg":"trace[1091073485] transaction","detail":"{read_only:false; response_revision:4379; number_of_response:1; }","duration":"104.851015ms","start":"2026-04-17T00:35:44.999843Z","end":"2026-04-17T00:35:45.104694Z","steps":["trace[1091073485] 'process raft request' (duration: 70.666701ms)","trace[1091073485] 'compare' (duration: 33.919027ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:35:48.081412Z","caller":"traceutil/trace.go:171","msg":"trace[1435596624] linearizableReadLoop","detail":"{readStateIndex:4648; appliedIndex:4647; }","duration":"173.510221ms","start":"2026-04-17T00:35:47.907886Z","end":"2026-04-17T00:35:48.081396Z","steps":["trace[1435596624] 'read index received' (duration: 173.242864ms)","trace[1435596624] 'applied index is now lower than readState.Index' (duration: 266.667µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:35:48.082141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.239088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193158"} {"level":"info","ts":"2026-04-17T00:35:48.082197Z","caller":"traceutil/trace.go:171","msg":"trace[758192510] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4484; }","duration":"174.33668ms","start":"2026-04-17T00:35:47.907852Z","end":"2026-04-17T00:35:48.082189Z","steps":["trace[758192510] 'agreement among raft nodes before linearized reading' (duration: 173.663385ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:48.082416Z","caller":"traceutil/trace.go:171","msg":"trace[1937299559] transaction","detail":"{read_only:false; response_revision:4484; number_of_response:1; }","duration":"260.33828ms","start":"2026-04-17T00:35:47.82203Z","end":"2026-04-17T00:35:48.082368Z","steps":["trace[1937299559] 'process raft request' (duration: 259.146392ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:48.315436Z","caller":"traceutil/trace.go:171","msg":"trace[128253357] linearizableReadLoop","detail":"{readStateIndex:4652; appliedIndex:4649; }","duration":"162.850505ms","start":"2026-04-17T00:35:48.152557Z","end":"2026-04-17T00:35:48.315408Z","steps":["trace[128253357] 'read index received' (duration: 15.020025ms)","trace[128253357] 'applied index is now lower than readState.Index' (duration: 147.82835ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:35:48.316145Z","caller":"traceutil/trace.go:171","msg":"trace[1042980899] transaction","detail":"{read_only:false; response_revision:4487; number_of_response:1; }","duration":"168.009958ms","start":"2026-04-17T00:35:48.148119Z","end":"2026-04-17T00:35:48.316129Z","steps":["trace[1042980899] 'process raft request' (duration: 166.953413ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:48.316748Z","caller":"traceutil/trace.go:171","msg":"trace[1590258775] transaction","detail":"{read_only:false; response_revision:4488; number_of_response:1; }","duration":"168.480528ms","start":"2026-04-17T00:35:48.148252Z","end":"2026-04-17T00:35:48.316733Z","steps":["trace[1590258775] 'process raft request' (duration: 167.063035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:48.317435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.807801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-17T00:35:48.317737Z","caller":"traceutil/trace.go:171","msg":"trace[2041359194] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4488; }","duration":"165.19738ms","start":"2026-04-17T00:35:48.152523Z","end":"2026-04-17T00:35:48.31772Z","steps":["trace[2041359194] 'agreement among raft nodes before linearized reading' (duration: 164.634967ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:50.427431Z","caller":"traceutil/trace.go:171","msg":"trace[1293531950] transaction","detail":"{read_only:false; response_revision:4511; number_of_response:1; }","duration":"104.314591ms","start":"2026-04-17T00:35:50.32307Z","end":"2026-04-17T00:35:50.427384Z","steps":["trace[1293531950] 'process raft request' (duration: 99.108317ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:50.429451Z","caller":"traceutil/trace.go:171","msg":"trace[1658432302] linearizableReadLoop","detail":"{readStateIndex:4676; appliedIndex:4674; }","duration":"101.407912ms","start":"2026-04-17T00:35:50.328017Z","end":"2026-04-17T00:35:50.429425Z","steps":["trace[1658432302] 'read index received' (duration: 94.15053ms)","trace[1658432302] 'applied index is now lower than readState.Index' (duration: 7.256342ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:35:50.429553Z","caller":"traceutil/trace.go:171","msg":"trace[591353800] transaction","detail":"{read_only:false; response_revision:4512; number_of_response:1; }","duration":"105.850068ms","start":"2026-04-17T00:35:50.323681Z","end":"2026-04-17T00:35:50.429531Z","steps":["trace[591353800] 'process raft request' (duration: 105.602481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:50.429701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.667509ms","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-04-17T00:35:50.429763Z","caller":"traceutil/trace.go:171","msg":"trace[1294039479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4512; }","duration":"101.794212ms","start":"2026-04-17T00:35:50.327956Z","end":"2026-04-17T00:35:50.42975Z","steps":["trace[1294039479] 'agreement among raft nodes before linearized reading' (duration: 101.603477ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:50.90194Z","caller":"traceutil/trace.go:171","msg":"trace[1064548187] linearizableReadLoop","detail":"{readStateIndex:4682; appliedIndex:4681; }","duration":"124.004155ms","start":"2026-04-17T00:35:50.777911Z","end":"2026-04-17T00:35:50.901915Z","steps":["trace[1064548187] 'read index received' (duration: 98.129805ms)","trace[1064548187] 'applied index is now lower than readState.Index' (duration: 25.87261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:35:50.902074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.141559ms","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":"warn","ts":"2026-04-17T00:35:50.902123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.15139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:35:50.902164Z","caller":"traceutil/trace.go:171","msg":"trace[78137794] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:4517; }","duration":"119.244762ms","start":"2026-04-17T00:35:50.782907Z","end":"2026-04-17T00:35:50.902152Z","steps":["trace[78137794] 'agreement among raft nodes before linearized reading' (duration: 119.1377ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:50.902169Z","caller":"traceutil/trace.go:171","msg":"trace[1000592343] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4517; }","duration":"124.292502ms","start":"2026-04-17T00:35:50.777855Z","end":"2026-04-17T00:35:50.902148Z","steps":["trace[1000592343] 'agreement among raft nodes before linearized reading' (duration: 124.124589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:58.115595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.29695ms","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-04-17T00:35:58.115756Z","caller":"traceutil/trace.go:171","msg":"trace[506647781] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:4648; }","duration":"719.477214ms","start":"2026-04-17T00:35:57.39626Z","end":"2026-04-17T00:35:58.115737Z","steps":["trace[506647781] 'count revisions from in-memory index tree' (duration: 719.228878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:58.115814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.778467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be978430-0a17-4668-a782-cae119b3ff56\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-17T00:35:58.115858Z","caller":"traceutil/trace.go:171","msg":"trace[1822693037] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-be978430-0a17-4668-a782-cae119b3ff56; range_end:; response_count:1; response_revision:4648; }","duration":"483.860629ms","start":"2026-04-17T00:35:57.631985Z","end":"2026-04-17T00:35:58.115845Z","steps":["trace[1822693037] 'range keys from in-memory index tree' (duration: 483.635864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:58.115831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:57.396215Z","time spent":"719.602026ms","remote":"127.0.0.1:36710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":17,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:35:58.115966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.215346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be978430-0a17-4668-a782-cae119b3ff56\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-17T00:35:58.115998Z","caller":"traceutil/trace.go:171","msg":"trace[2005985357] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-be978430-0a17-4668-a782-cae119b3ff56; range_end:; response_count:1; response_revision:4648; }","duration":"734.287759ms","start":"2026-04-17T00:35:57.381702Z","end":"2026-04-17T00:35:58.11599Z","steps":["trace[2005985357] 'range keys from in-memory index tree' (duration: 734.079514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:58.116026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:57.38167Z","time spent":"734.34894ms","remote":"127.0.0.1:36446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be978430-0a17-4668-a782-cae119b3ff56\" "} {"level":"warn","ts":"2026-04-17T00:35:58.11589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:57.631964Z","time spent":"483.91799ms","remote":"127.0.0.1:36446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be978430-0a17-4668-a782-cae119b3ff56\" "} {"level":"warn","ts":"2026-04-17T00:35:58.116105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.34106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:35:58.116196Z","caller":"traceutil/trace.go:171","msg":"trace[121123889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4648; }","duration":"722.458142ms","start":"2026-04-17T00:35:57.393719Z","end":"2026-04-17T00:35:58.116177Z","steps":["trace[121123889] 'range keys from in-memory index tree' (duration: 722.29928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:58.116246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:57.393711Z","time spent":"722.526494ms","remote":"127.0.0.1:36266","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-04-17T00:35:58.619354Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150448009768820,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:35:58.721799Z","caller":"traceutil/trace.go:171","msg":"trace[1190491221] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"798.054213ms","start":"2026-04-17T00:35:57.923723Z","end":"2026-04-17T00:35:58.721777Z","steps":["trace[1190491221] 'process raft request' (duration: 797.741956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:58.721999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:57.923696Z","time spent":"798.224547ms","remote":"127.0.0.1:36440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:35:58.878567Z","caller":"traceutil/trace.go:171","msg":"trace[762248062] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"658.91952ms","start":"2026-04-17T00:35:58.219622Z","end":"2026-04-17T00:35:58.878542Z","steps":["trace[762248062] 'process raft request' (duration: 658.791478ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:58.878584Z","caller":"traceutil/trace.go:171","msg":"trace[861531189] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"754.101026ms","start":"2026-04-17T00:35:58.12444Z","end":"2026-04-17T00:35:58.878541Z","steps":["trace[861531189] 'process raft request' (duration: 694.442588ms)","trace[861531189] 'compare' (duration: 59.365531ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:35:58.87877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:58.219595Z","time spent":"659.039373ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:35:58.87887Z","caller":"traceutil/trace.go:171","msg":"trace[879509673] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"658.710146ms","start":"2026-04-17T00:35:58.220132Z","end":"2026-04-17T00:35:58.878843Z","steps":["trace[879509673] 'process raft request' (duration: 658.337537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:58.879034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:58.220118Z","time spent":"658.827989ms","remote":"127.0.0.1:36520","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-04-17T00:35:58.879067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:58.124421Z","time spent":"754.237949ms","remote":"127.0.0.1:36446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:35:58.879097Z","caller":"traceutil/trace.go:171","msg":"trace[1684060194] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"425.827804ms","start":"2026-04-17T00:35:58.453241Z","end":"2026-04-17T00:35:58.879068Z","steps":["trace[1684060194] 'process raft request' (duration: 425.488566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:58.879245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:58.453224Z","time spent":"425.941316ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:35:58.879401Z","caller":"traceutil/trace.go:171","msg":"trace[1947093235] linearizableReadLoop","detail":"{readStateIndex:4821; appliedIndex:4815; }","duration":"761.003887ms","start":"2026-04-17T00:35:58.118381Z","end":"2026-04-17T00:35:58.879385Z","steps":["trace[1947093235] 'read index received' (duration: 603.177803ms)","trace[1947093235] 'applied index is now lower than readState.Index' (duration: 157.824554ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:35:58.879543Z","caller":"traceutil/trace.go:171","msg":"trace[1283629539] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"418.509113ms","start":"2026-04-17T00:35:58.461021Z","end":"2026-04-17T00:35:58.87953Z","steps":["trace[1283629539] 'process raft request' (duration: 417.964541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:58.87963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:58.460988Z","time spent":"418.589976ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:35:58.879796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.390597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:35:58.879844Z","caller":"traceutil/trace.go:171","msg":"trace[685613217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4654; }","duration":"761.49636ms","start":"2026-04-17T00:35:58.118338Z","end":"2026-04-17T00:35:58.879834Z","steps":["trace[685613217] 'agreement among raft nodes before linearized reading' (duration: 761.390397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:58.879897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:58.118303Z","time spent":"761.586181ms","remote":"127.0.0.1:36272","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-04-17T00:35:58.879977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.786915ms","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-04-17T00:35:58.880022Z","caller":"traceutil/trace.go:171","msg":"trace[328770476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4654; }","duration":"298.868738ms","start":"2026-04-17T00:35:58.581143Z","end":"2026-04-17T00:35:58.880012Z","steps":["trace[328770476] 'agreement among raft nodes before linearized reading' (duration: 298.731805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:58.880214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.529433ms","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-04-17T00:35:58.880244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.346565ms","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-04-17T00:35:58.880242Z","caller":"traceutil/trace.go:171","msg":"trace[1249711459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4654; }","duration":"186.585714ms","start":"2026-04-17T00:35:58.693647Z","end":"2026-04-17T00:35:58.880233Z","steps":["trace[1249711459] 'agreement among raft nodes before linearized reading' (duration: 186.507602ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:58.880295Z","caller":"traceutil/trace.go:171","msg":"trace[26392829] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4654; }","duration":"185.399126ms","start":"2026-04-17T00:35:58.694868Z","end":"2026-04-17T00:35:58.880267Z","steps":["trace[26392829] 'agreement among raft nodes before linearized reading' (duration: 185.263313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:58.880554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.814351ms","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-04-17T00:35:58.880585Z","caller":"traceutil/trace.go:171","msg":"trace[60257702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4654; }","duration":"409.873093ms","start":"2026-04-17T00:35:58.470702Z","end":"2026-04-17T00:35:58.880576Z","steps":["trace[60257702] 'agreement among raft nodes before linearized reading' (duration: 409.787651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:58.880613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:58.470689Z","time spent":"409.917043ms","remote":"127.0.0.1:36520","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-04-17T00:35:58.880668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.83447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-04-17T00:35:58.880715Z","caller":"traceutil/trace.go:171","msg":"trace[1793502417] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4654; }","duration":"442.919191ms","start":"2026-04-17T00:35:58.437788Z","end":"2026-04-17T00:35:58.880707Z","steps":["trace[1793502417] 'agreement among raft nodes before linearized reading' (duration: 442.800758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:58.880737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:58.43777Z","time spent":"442.959924ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-04-17T00:35:58.881532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.830829ms","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-04-17T00:35:58.881592Z","caller":"traceutil/trace.go:171","msg":"trace[287570075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4654; }","duration":"649.91357ms","start":"2026-04-17T00:35:58.23167Z","end":"2026-04-17T00:35:58.881584Z","steps":["trace[287570075] 'agreement among raft nodes before linearized reading' (duration: 649.807947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:58.881613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:58.231657Z","time spent":"649.948731ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T00:35:59.011586Z","caller":"traceutil/trace.go:171","msg":"trace[1659824513] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"123.705429ms","start":"2026-04-17T00:35:58.887858Z","end":"2026-04-17T00:35:59.011563Z","steps":["trace[1659824513] 'process raft request' (duration: 123.638698ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:59.011679Z","caller":"traceutil/trace.go:171","msg":"trace[279562981] transaction","detail":"{read_only:false; response_revision:4656; number_of_response:1; }","duration":"124.618391ms","start":"2026-04-17T00:35:58.887002Z","end":"2026-04-17T00:35:59.01162Z","steps":["trace[279562981] 'process raft request' (duration: 124.342484ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:59.012294Z","caller":"traceutil/trace.go:171","msg":"trace[1420618159] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"123.931135ms","start":"2026-04-17T00:35:58.88834Z","end":"2026-04-17T00:35:59.012271Z","steps":["trace[1420618159] 'process raft request' (duration: 123.188237ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:59.012407Z","caller":"traceutil/trace.go:171","msg":"trace[1931626933] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"123.958735ms","start":"2026-04-17T00:35:58.888423Z","end":"2026-04-17T00:35:59.012382Z","steps":["trace[1931626933] 'process raft request' (duration: 123.769421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:59.012523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:35:59.012566Z","caller":"traceutil/trace.go:171","msg":"trace[1499204000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4659; }","duration":"125.754458ms","start":"2026-04-17T00:35:58.8868Z","end":"2026-04-17T00:35:59.012554Z","steps":["trace[1499204000] 'agreement among raft nodes before linearized reading' (duration: 125.651315ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:59.012465Z","caller":"traceutil/trace.go:171","msg":"trace[1522932793] linearizableReadLoop","detail":"{readStateIndex:4823; appliedIndex:4821; }","duration":"125.285637ms","start":"2026-04-17T00:35:58.887069Z","end":"2026-04-17T00:35:59.012354Z","steps":["trace[1522932793] 'read index received' (duration: 58.174165ms)","trace[1522932793] 'applied index is now lower than readState.Index' (duration: 67.110162ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:35:59.013088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.360258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1321"} {"level":"info","ts":"2026-04-17T00:35:59.013479Z","caller":"traceutil/trace.go:171","msg":"trace[1627633270] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4659; }","duration":"125.461781ms","start":"2026-04-17T00:35:58.887683Z","end":"2026-04-17T00:35:59.013145Z","steps":["trace[1627633270] 'agreement among raft nodes before linearized reading' (duration: 125.239906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:59.296685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.865303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:35:59.296798Z","caller":"traceutil/trace.go:171","msg":"trace[713505843] linearizableReadLoop","detail":"{readStateIndex:4828; appliedIndex:4827; }","duration":"220.696521ms","start":"2026-04-17T00:35:59.076082Z","end":"2026-04-17T00:35:59.296778Z","steps":["trace[713505843] 'read index received' (duration: 95.575872ms)","trace[713505843] 'applied index is now lower than readState.Index' (duration: 125.119279ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:35:59.296894Z","caller":"traceutil/trace.go:171","msg":"trace[849594057] transaction","detail":"{read_only:false; response_revision:4661; number_of_response:1; }","duration":"230.304223ms","start":"2026-04-17T00:35:59.066573Z","end":"2026-04-17T00:35:59.296878Z","steps":["trace[849594057] 'process raft request' (duration: 105.133943ms)","trace[849594057] 'compare' (duration: 124.713629ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:35:59.297187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.047048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be978430-0a17-4668-a782-cae119b3ff56\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-04-17T00:35:59.29729Z","caller":"traceutil/trace.go:171","msg":"trace[1570634989] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-be978430-0a17-4668-a782-cae119b3ff56; range_end:; response_count:1; response_revision:4661; }","duration":"175.176422ms","start":"2026-04-17T00:35:59.122085Z","end":"2026-04-17T00:35:59.297262Z","steps":["trace[1570634989] 'agreement among raft nodes before linearized reading' (duration: 174.894296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:59.297265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.023718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-04-17T00:35:59.297457Z","caller":"traceutil/trace.go:171","msg":"trace[1242092922] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4661; }","duration":"221.336014ms","start":"2026-04-17T00:35:59.076048Z","end":"2026-04-17T00:35:59.297384Z","steps":["trace[1242092922] 'agreement among raft nodes before linearized reading' (duration: 220.908714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:00.667866Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150448009768848,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:36:00.9344Z","caller":"traceutil/trace.go:171","msg":"trace[923283772] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"813.380456ms","start":"2026-04-17T00:36:00.120984Z","end":"2026-04-17T00:36:00.934365Z","steps":["trace[923283772] 'process raft request' (duration: 769.696902ms)","trace[923283772] 'compare' (duration: 43.033849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:36:00.934573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:00.120955Z","time spent":"813.525759ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:36:00.934713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.302406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:00.934815Z","caller":"traceutil/trace.go:171","msg":"trace[1172013159] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:4663; }","duration":"767.543972ms","start":"2026-04-17T00:36:00.167259Z","end":"2026-04-17T00:36:00.934803Z","steps":["trace[1172013159] 'agreement among raft nodes before linearized reading' (duration: 767.313777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:00.934858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:00.167232Z","time spent":"767.613333ms","remote":"127.0.0.1:40566","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/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-04-17T00:36:00.934536Z","caller":"traceutil/trace.go:171","msg":"trace[617403963] linearizableReadLoop","detail":"{readStateIndex:4830; appliedIndex:4829; }","duration":"767.199284ms","start":"2026-04-17T00:36:00.167314Z","end":"2026-04-17T00:36:00.934514Z","steps":["trace[617403963] 'read index received' (duration: 723.363107ms)","trace[617403963] 'applied index is now lower than readState.Index' (duration: 43.13433ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:01.102504Z","caller":"traceutil/trace.go:171","msg":"trace[124872900] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"216.03361ms","start":"2026-04-17T00:36:00.886447Z","end":"2026-04-17T00:36:01.102481Z","steps":["trace[124872900] 'process raft request' (duration: 215.780493ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:01.144583Z","caller":"traceutil/trace.go:171","msg":"trace[1069817050] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"258.039023ms","start":"2026-04-17T00:36:00.886514Z","end":"2026-04-17T00:36:01.144553Z","steps":["trace[1069817050] 'process raft request' (duration: 215.949758ms)","trace[1069817050] 'compare' (duration: 41.847869ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:01.144997Z","caller":"traceutil/trace.go:171","msg":"trace[524852494] linearizableReadLoop","detail":"{readStateIndex:4835; appliedIndex:4830; }","duration":"209.763934ms","start":"2026-04-17T00:36:00.935211Z","end":"2026-04-17T00:36:01.144975Z","steps":["trace[524852494] 'read index received' (duration: 94.968684ms)","trace[524852494] 'applied index is now lower than readState.Index' (duration: 114.79407ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:01.145054Z","caller":"traceutil/trace.go:171","msg":"trace[1242027964] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"255.963545ms","start":"2026-04-17T00:36:00.889061Z","end":"2026-04-17T00:36:01.145024Z","steps":["trace[1242027964] 'process raft request' (duration: 255.816511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:01.145325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.845199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:01.145362Z","caller":"traceutil/trace.go:171","msg":"trace[1994781942] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"257.00446ms","start":"2026-04-17T00:36:00.888344Z","end":"2026-04-17T00:36:01.145348Z","steps":["trace[1994781942] 'process raft request' (duration: 256.15575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:01.145387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.240154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be978430-0a17-4668-a782-cae119b3ff56\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-04-17T00:36:01.145431Z","caller":"traceutil/trace.go:171","msg":"trace[1069561370] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-be978430-0a17-4668-a782-cae119b3ff56; range_end:; response_count:1; response_revision:4667; }","duration":"843.330755ms","start":"2026-04-17T00:36:00.302088Z","end":"2026-04-17T00:36:01.145419Z","steps":["trace[1069561370] 'agreement among raft nodes before linearized reading' (duration: 843.171932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:01.145475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:00.30207Z","time spent":"843.396207ms","remote":"127.0.0.1:36446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3788,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be978430-0a17-4668-a782-cae119b3ff56\" "} {"level":"info","ts":"2026-04-17T00:36:01.145411Z","caller":"traceutil/trace.go:171","msg":"trace[2123333610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4667; }","duration":"750.978412ms","start":"2026-04-17T00:36:00.394413Z","end":"2026-04-17T00:36:01.145392Z","steps":["trace[2123333610] 'agreement among raft nodes before linearized reading' (duration: 750.835449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:01.145652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:00.394392Z","time spent":"751.214747ms","remote":"127.0.0.1:36266","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-04-17T00:36:01.145824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.271385ms","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-04-17T00:36:01.145878Z","caller":"traceutil/trace.go:171","msg":"trace[1109199005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4667; }","duration":"129.365978ms","start":"2026-04-17T00:36:01.016499Z","end":"2026-04-17T00:36:01.145865Z","steps":["trace[1109199005] 'agreement among raft nodes before linearized reading' (duration: 129.184573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:01.14599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.083782ms","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-04-17T00:36:01.146045Z","caller":"traceutil/trace.go:171","msg":"trace[1808438531] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4667; }","duration":"123.166244ms","start":"2026-04-17T00:36:01.022866Z","end":"2026-04-17T00:36:01.146032Z","steps":["trace[1808438531] 'agreement among raft nodes before linearized reading' (duration: 122.99675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:01.146101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.531021ms","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-04-17T00:36:01.146146Z","caller":"traceutil/trace.go:171","msg":"trace[1509917705] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4667; }","duration":"129.608023ms","start":"2026-04-17T00:36:01.016527Z","end":"2026-04-17T00:36:01.146135Z","steps":["trace[1509917705] 'agreement among raft nodes before linearized reading' (duration: 129.5027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:01.14621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.968875ms","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-04-17T00:36:01.146243Z","caller":"traceutil/trace.go:171","msg":"trace[1147927131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4667; }","duration":"131.034576ms","start":"2026-04-17T00:36:01.0152Z","end":"2026-04-17T00:36:01.146234Z","steps":["trace[1147927131] 'agreement among raft nodes before linearized reading' (duration: 130.943794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:01.146237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.437969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-04-17T00:36:01.146451Z","caller":"traceutil/trace.go:171","msg":"trace[1860221664] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4667; }","duration":"179.646514ms","start":"2026-04-17T00:36:00.966785Z","end":"2026-04-17T00:36:01.146432Z","steps":["trace[1860221664] 'agreement among raft nodes before linearized reading' (duration: 179.257344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:01.146455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.442048ms","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-04-17T00:36:01.146509Z","caller":"traceutil/trace.go:171","msg":"trace[1904469170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4667; }","duration":"197.539939ms","start":"2026-04-17T00:36:00.948955Z","end":"2026-04-17T00:36:01.146495Z","steps":["trace[1904469170] 'agreement among raft nodes before linearized reading' (duration: 196.319141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:01.146332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.505847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:01.146592Z","caller":"traceutil/trace.go:171","msg":"trace[147204506] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:4667; }","duration":"181.804094ms","start":"2026-04-17T00:36:00.964772Z","end":"2026-04-17T00:36:01.146576Z","steps":["trace[147204506] 'agreement among raft nodes before linearized reading' (duration: 181.484476ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:01.44386Z","caller":"traceutil/trace.go:171","msg":"trace[392201974] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"292.62952ms","start":"2026-04-17T00:36:01.151199Z","end":"2026-04-17T00:36:01.443829Z","steps":["trace[392201974] 'process raft request' (duration: 292.370224ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:01.443886Z","caller":"traceutil/trace.go:171","msg":"trace[596692405] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"292.592439ms","start":"2026-04-17T00:36:01.151263Z","end":"2026-04-17T00:36:01.443856Z","steps":["trace[596692405] 'process raft request' (duration: 292.481546ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:01.444141Z","caller":"traceutil/trace.go:171","msg":"trace[1129042666] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"292.18867ms","start":"2026-04-17T00:36:01.151939Z","end":"2026-04-17T00:36:01.444127Z","steps":["trace[1129042666] 'process raft request' (duration: 292.076918ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:01.444235Z","caller":"traceutil/trace.go:171","msg":"trace[1413685249] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"292.855485ms","start":"2026-04-17T00:36:01.151361Z","end":"2026-04-17T00:36:01.444217Z","steps":["trace[1413685249] 'process raft request' (duration: 292.437036ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:01.514629Z","caller":"traceutil/trace.go:171","msg":"trace[696197302] linearizableReadLoop","detail":"{readStateIndex:4841; appliedIndex:4837; }","duration":"119.489388ms","start":"2026-04-17T00:36:01.395116Z","end":"2026-04-17T00:36:01.514605Z","steps":["trace[696197302] 'read index received' (duration: 48.656368ms)","trace[696197302] 'applied index is now lower than readState.Index' (duration: 70.8316ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:01.514686Z","caller":"traceutil/trace.go:171","msg":"trace[1473627368] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"231.053114ms","start":"2026-04-17T00:36:01.283588Z","end":"2026-04-17T00:36:01.514641Z","steps":["trace[1473627368] 'process raft request' (duration: 230.87922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:01.51478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.647952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:01.514832Z","caller":"traceutil/trace.go:171","msg":"trace[1862851662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4673; }","duration":"119.749793ms","start":"2026-04-17T00:36:01.395072Z","end":"2026-04-17T00:36:01.514822Z","steps":["trace[1862851662] 'agreement among raft nodes before linearized reading' (duration: 119.629801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:02.465492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.602079ms","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-04-17T00:36:02.465609Z","caller":"traceutil/trace.go:171","msg":"trace[1337340532] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4675; }","duration":"404.752734ms","start":"2026-04-17T00:36:02.060832Z","end":"2026-04-17T00:36:02.465584Z","steps":["trace[1337340532] 'range keys from in-memory index tree' (duration: 404.477837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:02.465661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:02.060813Z","time spent":"404.833595ms","remote":"127.0.0.1:36682","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":"warn","ts":"2026-04-17T00:36:02.465787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:02.135005Z","time spent":"330.773732ms","remote":"127.0.0.1:50248","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-17T00:36:02.465889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.617528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be978430-0a17-4668-a782-cae119b3ff56\" ","response":"range_response_count:1 size:3765"} {"level":"warn","ts":"2026-04-17T00:36:02.465909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.469205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:36:02.465966Z","caller":"traceutil/trace.go:171","msg":"trace[1894008198] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-be978430-0a17-4668-a782-cae119b3ff56; range_end:; response_count:1; response_revision:4675; }","duration":"314.72067ms","start":"2026-04-17T00:36:02.151226Z","end":"2026-04-17T00:36:02.465947Z","steps":["trace[1894008198] 'range keys from in-memory index tree' (duration: 314.437073ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:02.465974Z","caller":"traceutil/trace.go:171","msg":"trace[1897523592] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:4675; }","duration":"234.578576ms","start":"2026-04-17T00:36:02.231378Z","end":"2026-04-17T00:36:02.465957Z","steps":["trace[1897523592] 'count revisions from in-memory index tree' (duration: 234.369051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:02.466019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:02.15121Z","time spent":"314.795122ms","remote":"127.0.0.1:36446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3788,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be978430-0a17-4668-a782-cae119b3ff56\" "} {"level":"warn","ts":"2026-04-17T00:36:03.808423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.976492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-17T00:36:03.80895Z","caller":"traceutil/trace.go:171","msg":"trace[634484193] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4721; }","duration":"282.609357ms","start":"2026-04-17T00:36:03.526312Z","end":"2026-04-17T00:36:03.808921Z","steps":["trace[634484193] 'range keys from in-memory index tree' (duration: 281.791648ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:06.704824Z","caller":"traceutil/trace.go:171","msg":"trace[818701865] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"441.412358ms","start":"2026-04-17T00:36:06.263388Z","end":"2026-04-17T00:36:06.7048Z","steps":["trace[818701865] 'process raft request' (duration: 441.271275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:06.704969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:06.263362Z","time spent":"441.53219ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:36:06.708183Z","caller":"traceutil/trace.go:171","msg":"trace[429154752] linearizableReadLoop","detail":"{readStateIndex:4940; appliedIndex:4938; }","duration":"314.307205ms","start":"2026-04-17T00:36:06.393854Z","end":"2026-04-17T00:36:06.708161Z","steps":["trace[429154752] 'read index received' (duration: 310.833025ms)","trace[429154752] 'applied index is now lower than readState.Index' (duration: 3.47319ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:06.708327Z","caller":"traceutil/trace.go:171","msg":"trace[706594989] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"355.158955ms","start":"2026-04-17T00:36:06.353153Z","end":"2026-04-17T00:36:06.708312Z","steps":["trace[706594989] 'process raft request' (duration: 354.896478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:06.708572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:06.353125Z","time spent":"355.238626ms","remote":"127.0.0.1:36446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9210,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:36:06.708879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.86332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:06.708969Z","caller":"traceutil/trace.go:171","msg":"trace[1636090208] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:4767; }","duration":"222.010135ms","start":"2026-04-17T00:36:06.486934Z","end":"2026-04-17T00:36:06.708945Z","steps":["trace[1636090208] 'agreement among raft nodes before linearized reading' (duration: 221.773539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:06.70924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.37777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:06.709309Z","caller":"traceutil/trace.go:171","msg":"trace[1468244712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4767; }","duration":"315.485504ms","start":"2026-04-17T00:36:06.393807Z","end":"2026-04-17T00:36:06.709293Z","steps":["trace[1468244712] 'agreement among raft nodes before linearized reading' (duration: 315.387461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:06.709342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:06.393788Z","time spent":"315.546884ms","remote":"127.0.0.1:36266","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-04-17T00:36:09.622229Z","caller":"traceutil/trace.go:171","msg":"trace[1792857430] linearizableReadLoop","detail":"{readStateIndex:4997; appliedIndex:4996; }","duration":"118.579783ms","start":"2026-04-17T00:36:09.503628Z","end":"2026-04-17T00:36:09.622207Z","steps":["trace[1792857430] 'read index received' (duration: 109.653309ms)","trace[1792857430] 'applied index is now lower than readState.Index' (duration: 8.925444ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:09.622373Z","caller":"traceutil/trace.go:171","msg":"trace[1817720549] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"123.655059ms","start":"2026-04-17T00:36:09.498695Z","end":"2026-04-17T00:36:09.62235Z","steps":["trace[1817720549] 'process raft request' (duration: 114.611902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:09.622403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.760358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-17T00:36:09.622432Z","caller":"traceutil/trace.go:171","msg":"trace[1994963933] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4823; }","duration":"118.827468ms","start":"2026-04-17T00:36:09.503596Z","end":"2026-04-17T00:36:09.622423Z","steps":["trace[1994963933] 'agreement among raft nodes before linearized reading' (duration: 118.708836ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:09.622848Z","caller":"traceutil/trace.go:171","msg":"trace[967746322] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4824; }","duration":"115.723819ms","start":"2026-04-17T00:36:09.507115Z","end":"2026-04-17T00:36:09.622839Z","steps":["trace[967746322] 'process raft request' (duration: 115.699899ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:09.623212Z","caller":"traceutil/trace.go:171","msg":"trace[1029449750] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"116.136817ms","start":"2026-04-17T00:36:09.507054Z","end":"2026-04-17T00:36:09.62319Z","steps":["trace[1029449750] 'process raft request' (duration: 115.686837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:12.252454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.743982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:12.252557Z","caller":"traceutil/trace.go:171","msg":"trace[1720834654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4840; }","duration":"857.898075ms","start":"2026-04-17T00:36:11.394636Z","end":"2026-04-17T00:36:12.252534Z","steps":["trace[1720834654] 'range keys from in-memory index tree' (duration: 857.658321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:12.253083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.325962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13846"} {"level":"info","ts":"2026-04-17T00:36:12.253196Z","caller":"traceutil/trace.go:171","msg":"trace[1025149330] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4840; }","duration":"335.486796ms","start":"2026-04-17T00:36:11.917679Z","end":"2026-04-17T00:36:12.253166Z","steps":["trace[1025149330] 'range keys from in-memory index tree' (duration: 335.127827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:12.253256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:11.917657Z","time spent":"335.588699ms","remote":"127.0.0.1:36446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":13869,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-17T00:36:12.253241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.160941ms","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-04-17T00:36:12.253326Z","caller":"traceutil/trace.go:171","msg":"trace[2063948073] 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:4840; }","duration":"322.262214ms","start":"2026-04-17T00:36:11.931041Z","end":"2026-04-17T00:36:12.253304Z","steps":["trace[2063948073] 'range keys from in-memory index tree' (duration: 321.971468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:12.253371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:11.931019Z","time spent":"322.341527ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-17T00:36:12.253704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.104968ms","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-04-17T00:36:12.253768Z","caller":"traceutil/trace.go:171","msg":"trace[198442036] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4840; }","duration":"761.217429ms","start":"2026-04-17T00:36:11.492536Z","end":"2026-04-17T00:36:12.253753Z","steps":["trace[198442036] 'range keys from in-memory index tree' (duration: 760.764099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:12.253803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:11.492514Z","time spent":"761.2788ms","remote":"127.0.0.1:36520","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-04-17T00:36:12.253994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.098182ms","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-04-17T00:36:12.254045Z","caller":"traceutil/trace.go:171","msg":"trace[148704288] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4840; }","duration":"628.176014ms","start":"2026-04-17T00:36:11.625857Z","end":"2026-04-17T00:36:12.254033Z","steps":["trace[148704288] 'range keys from in-memory index tree' (duration: 627.979409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:12.25408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:11.625794Z","time spent":"628.278726ms","remote":"127.0.0.1:36520","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-04-17T00:36:12.254368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.031404ms","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-04-17T00:36:12.254419Z","caller":"traceutil/trace.go:171","msg":"trace[47408114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4840; }","duration":"757.102396ms","start":"2026-04-17T00:36:11.497304Z","end":"2026-04-17T00:36:12.254407Z","steps":["trace[47408114] 'range keys from in-memory index tree' (duration: 756.90749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:12.254448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:11.497293Z","time spent":"757.148117ms","remote":"127.0.0.1:36520","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-04-17T00:36:12.254642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.995038ms","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-04-17T00:36:12.254692Z","caller":"traceutil/trace.go:171","msg":"trace[1648925007] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:4840; }","duration":"197.050099ms","start":"2026-04-17T00:36:12.057631Z","end":"2026-04-17T00:36:12.254681Z","steps":["trace[1648925007] 'count revisions from in-memory index tree' (duration: 196.903226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:12.255414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.953277ms","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-04-17T00:36:12.255461Z","caller":"traceutil/trace.go:171","msg":"trace[1770752061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4840; }","duration":"762.038578ms","start":"2026-04-17T00:36:11.49341Z","end":"2026-04-17T00:36:12.255449Z","steps":["trace[1770752061] 'range keys from in-memory index tree' (duration: 760.496324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:12.255491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:11.493386Z","time spent":"762.098009ms","remote":"127.0.0.1:36520","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-04-17T00:36:12.252605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:11.394616Z","time spent":"857.977496ms","remote":"127.0.0.1:36266","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-04-17T00:36:17.524373Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150448009769395,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:36:18.024713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150448009769395,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:36:18.524968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150448009769395,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:36:19.025892Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150448009769395,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:36:19.340126Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.352715085s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T00:36:19.340556Z","caller":"traceutil/trace.go:171","msg":"trace[1039460816] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"2.368524714s","start":"2026-04-17T00:36:16.972007Z","end":"2026-04-17T00:36:19.340532Z","steps":["trace[1039460816] 'process raft request' (duration: 2.368313329s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.340699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:16.971954Z","time spent":"2.368657898s","remote":"127.0.0.1:36446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17215,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:36:19.388931Z","caller":"traceutil/trace.go:171","msg":"trace[889262609] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"2.235068691s","start":"2026-04-17T00:36:17.153816Z","end":"2026-04-17T00:36:19.388885Z","steps":["trace[889262609] 'process raft request' (duration: 2.234736334s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.389087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:17.153792Z","time spent":"2.235214615s","remote":"127.0.0.1:36520","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-04-17T00:36:19.38964Z","caller":"traceutil/trace.go:171","msg":"trace[1146684531] linearizableReadLoop","detail":"{readStateIndex:5070; appliedIndex:5064; }","duration":"2.365373999s","start":"2026-04-17T00:36:17.024243Z","end":"2026-04-17T00:36:19.389617Z","steps":["trace[1146684531] 'read index received' (duration: 2.316072297s)","trace[1146684531] 'applied index is now lower than readState.Index' (duration: 49.300642ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:19.389911Z","caller":"traceutil/trace.go:171","msg":"trace[1451198747] transaction","detail":"{read_only:false; response_revision:4893; number_of_response:1; }","duration":"1.68223176s","start":"2026-04-17T00:36:17.707636Z","end":"2026-04-17T00:36:19.389868Z","steps":["trace[1451198747] 'process raft request' (duration: 1.681943093s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:19.389973Z","caller":"traceutil/trace.go:171","msg":"trace[1040605323] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"2.183386473s","start":"2026-04-17T00:36:17.206545Z","end":"2026-04-17T00:36:19.389932Z","steps":["trace[1040605323] 'process raft request' (duration: 2.182966584s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:19.390001Z","caller":"traceutil/trace.go:171","msg":"trace[127153435] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"2.182991644s","start":"2026-04-17T00:36:17.206991Z","end":"2026-04-17T00:36:19.389982Z","steps":["trace[127153435] 'process raft request' (duration: 2.182557824s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.390144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:17.206495Z","time spent":"2.183545648s","remote":"127.0.0.1:36520","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-04-17T00:36:19.390166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.102193826s","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-04-17T00:36:19.390219Z","caller":"traceutil/trace.go:171","msg":"trace[525032125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4893; }","duration":"2.102297759s","start":"2026-04-17T00:36:17.287908Z","end":"2026-04-17T00:36:19.390206Z","steps":["trace[525032125] 'agreement among raft nodes before linearized reading' (duration: 2.102124785s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.39026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:17.28788Z","time spent":"2.10237349s","remote":"127.0.0.1:36520","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-04-17T00:36:19.390361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056668992s","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-04-17T00:36:19.390378Z","caller":"traceutil/trace.go:171","msg":"trace[528940142] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"2.183918684s","start":"2026-04-17T00:36:17.206442Z","end":"2026-04-17T00:36:19.390361Z","steps":["trace[528940142] 'process raft request' (duration: 2.182296577s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:19.390423Z","caller":"traceutil/trace.go:171","msg":"trace[1572133077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4893; }","duration":"1.056790525s","start":"2026-04-17T00:36:18.333621Z","end":"2026-04-17T00:36:19.390411Z","steps":["trace[1572133077] 'agreement among raft nodes before linearized reading' (duration: 1.0565641s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.390467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:18.333601Z","time spent":"1.056855185s","remote":"127.0.0.1:36520","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-04-17T00:36:19.390471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:17.206406Z","time spent":"2.184018308s","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:36:19.390501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.706500028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-17T00:36:19.390538Z","caller":"traceutil/trace.go:171","msg":"trace[1722784747] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4893; }","duration":"1.706580958s","start":"2026-04-17T00:36:17.683946Z","end":"2026-04-17T00:36:19.390527Z","steps":["trace[1722784747] 'agreement among raft nodes before linearized reading' (duration: 1.706472166s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.390565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:17.683923Z","time spent":"1.706636721s","remote":"127.0.0.1:36446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-17T00:36:19.390248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:17.206967Z","time spent":"2.183123608s","remote":"127.0.0.1:36520","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-04-17T00:36:19.390629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.995636808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:36:19.390634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06103051s","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-04-17T00:36:19.390683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.366448662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-17T00:36:19.390695Z","caller":"traceutil/trace.go:171","msg":"trace[1507094972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4893; }","duration":"1.995739188s","start":"2026-04-17T00:36:17.394941Z","end":"2026-04-17T00:36:19.39068Z","steps":["trace[1507094972] 'agreement among raft nodes before linearized reading' (duration: 1.995643097s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:19.390722Z","caller":"traceutil/trace.go:171","msg":"trace[885945644] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4893; }","duration":"2.366520436s","start":"2026-04-17T00:36:17.02419Z","end":"2026-04-17T00:36:19.39071Z","steps":["trace[885945644] 'agreement among raft nodes before linearized reading' (duration: 2.366403593s)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:19.390714Z","caller":"traceutil/trace.go:171","msg":"trace[6948682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4893; }","duration":"1.061175094s","start":"2026-04-17T00:36:18.329519Z","end":"2026-04-17T00:36:19.390694Z","steps":["trace[6948682] 'agreement among raft nodes before linearized reading' (duration: 1.060879087s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.390746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:17.394918Z","time spent":"1.995817561s","remote":"127.0.0.1:36266","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-04-17T00:36:19.390772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:18.329501Z","time spent":"1.061261774s","remote":"127.0.0.1:36520","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-04-17T00:36:19.390341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.484972517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:36:19.390759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:17.024168Z","time spent":"2.366580606s","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-17T00:36:19.391012Z","caller":"traceutil/trace.go:171","msg":"trace[1036164566] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4893; }","duration":"1.485706643s","start":"2026-04-17T00:36:17.905285Z","end":"2026-04-17T00:36:19.390992Z","steps":["trace[1036164566] 'agreement among raft nodes before linearized reading' (duration: 1.484919646s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.391046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050066203s","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-04-17T00:36:19.39108Z","caller":"traceutil/trace.go:171","msg":"trace[2012627205] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4893; }","duration":"1.050115664s","start":"2026-04-17T00:36:18.340954Z","end":"2026-04-17T00:36:19.391069Z","steps":["trace[2012627205] 'agreement among raft nodes before linearized reading' (duration: 1.050043062s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.391085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:17.905261Z","time spent":"1.485803315s","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:36:19.391119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:18.340943Z","time spent":"1.050169594s","remote":"127.0.0.1:36520","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-04-17T00:36:19.390974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05569577s","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-04-17T00:36:19.391189Z","caller":"traceutil/trace.go:171","msg":"trace[1962841109] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4893; }","duration":"1.055904795s","start":"2026-04-17T00:36:18.335267Z","end":"2026-04-17T00:36:19.391172Z","steps":["trace[1962841109] 'agreement among raft nodes before linearized reading' (duration: 1.055603308s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.391259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:18.335202Z","time spent":"1.056039867s","remote":"127.0.0.1:36520","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-04-17T00:36:19.394624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:17.707619Z","time spent":"1.682388502s","remote":"127.0.0.1:36520","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-04-17T00:36:19.843995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.626076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:36:19.8441Z","caller":"traceutil/trace.go:171","msg":"trace[1277234522] linearizableReadLoop","detail":"{readStateIndex:5071; appliedIndex:5070; }","duration":"444.795292ms","start":"2026-04-17T00:36:19.399287Z","end":"2026-04-17T00:36:19.844083Z","steps":["trace[1277234522] 'read index received' (duration: 266.975031ms)","trace[1277234522] 'applied index is now lower than readState.Index' (duration: 177.818441ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:19.844294Z","caller":"traceutil/trace.go:171","msg":"trace[1895125924] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"445.419185ms","start":"2026-04-17T00:36:19.398858Z","end":"2026-04-17T00:36:19.844277Z","steps":["trace[1895125924] 'process raft request' (duration: 267.38717ms)","trace[1895125924] 'compare' (duration: 177.068613ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:36:19.844454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:19.398839Z","time spent":"445.530188ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:36:19.844521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.20377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:10746"} {"level":"info","ts":"2026-04-17T00:36:19.844614Z","caller":"traceutil/trace.go:171","msg":"trace[568300166] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4894; }","duration":"445.335983ms","start":"2026-04-17T00:36:19.399252Z","end":"2026-04-17T00:36:19.844588Z","steps":["trace[568300166] 'agreement among raft nodes before linearized reading' (duration: 445.080347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.844693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:19.399236Z","time spent":"445.436345ms","remote":"127.0.0.1:36440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":10769,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-17T00:36:19.895975Z","caller":"traceutil/trace.go:171","msg":"trace[1312407893] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"496.406274ms","start":"2026-04-17T00:36:19.399542Z","end":"2026-04-17T00:36:19.895948Z","steps":["trace[1312407893] 'process raft request' (duration: 496.22333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.896109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:19.399529Z","time spent":"496.507977ms","remote":"127.0.0.1:36520","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-04-17T00:36:19.896139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.564474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-17T00:36:19.896208Z","caller":"traceutil/trace.go:171","msg":"trace[1350645665] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4899; }","duration":"494.657065ms","start":"2026-04-17T00:36:19.401532Z","end":"2026-04-17T00:36:19.896189Z","steps":["trace[1350645665] 'agreement among raft nodes before linearized reading' (duration: 494.475222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.896245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:19.401523Z","time spent":"494.714797ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-04-17T00:36:19.896322Z","caller":"traceutil/trace.go:171","msg":"trace[515632915] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"493.875847ms","start":"2026-04-17T00:36:19.402432Z","end":"2026-04-17T00:36:19.896308Z","steps":["trace[515632915] 'process raft request' (duration: 493.499959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.896372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:19.402421Z","time spent":"493.930019ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:36:19.896421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.939146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:19.896436Z","caller":"traceutil/trace.go:171","msg":"trace[381781696] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"494.583925ms","start":"2026-04-17T00:36:19.401844Z","end":"2026-04-17T00:36:19.896428Z","steps":["trace[381781696] 'process raft request' (duration: 494.064863ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:19.896445Z","caller":"traceutil/trace.go:171","msg":"trace[1960561382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4899; }","duration":"492.978278ms","start":"2026-04-17T00:36:19.40346Z","end":"2026-04-17T00:36:19.896439Z","steps":["trace[1960561382] 'agreement among raft nodes before linearized reading' (duration: 492.943106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.896466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:19.403443Z","time spent":"493.017149ms","remote":"127.0.0.1:36266","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-04-17T00:36:19.896449Z","caller":"traceutil/trace.go:171","msg":"trace[947830597] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"491.487943ms","start":"2026-04-17T00:36:19.40492Z","end":"2026-04-17T00:36:19.896408Z","steps":["trace[947830597] 'process raft request' (duration: 491.046333ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:19.896418Z","caller":"traceutil/trace.go:171","msg":"trace[1668404623] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"494.593715ms","start":"2026-04-17T00:36:19.401772Z","end":"2026-04-17T00:36:19.896366Z","steps":["trace[1668404623] 'process raft request' (duration: 494.109084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:19.896673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:19.404898Z","time spent":"491.707039ms","remote":"127.0.0.1:36446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:36:19.89668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:19.401761Z","time spent":"494.788909ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:36:19.89647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:19.401818Z","time spent":"494.632295ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:36:20.148195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.219733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:36:20.148454Z","caller":"traceutil/trace.go:171","msg":"trace[1054027249] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"243.776463ms","start":"2026-04-17T00:36:19.904636Z","end":"2026-04-17T00:36:20.148413Z","steps":["trace[1054027249] 'process raft request' (duration: 81.223932ms)","trace[1054027249] 'compare' (duration: 162.03754ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:20.148479Z","caller":"traceutil/trace.go:171","msg":"trace[1037458970] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"243.644601ms","start":"2026-04-17T00:36:19.904807Z","end":"2026-04-17T00:36:20.148452Z","steps":["trace[1037458970] 'process raft request' (duration: 243.501377ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:20.148578Z","caller":"traceutil/trace.go:171","msg":"trace[1015794871] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"243.120469ms","start":"2026-04-17T00:36:19.905443Z","end":"2026-04-17T00:36:20.148563Z","steps":["trace[1015794871] 'process raft request' (duration: 242.951004ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:20.148696Z","caller":"traceutil/trace.go:171","msg":"trace[517171614] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"241.649055ms","start":"2026-04-17T00:36:19.907036Z","end":"2026-04-17T00:36:20.148685Z","steps":["trace[517171614] 'process raft request' (duration: 241.4109ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:20.148697Z","caller":"traceutil/trace.go:171","msg":"trace[1117508176] linearizableReadLoop","detail":"{readStateIndex:5080; appliedIndex:5076; }","duration":"240.139511ms","start":"2026-04-17T00:36:19.908541Z","end":"2026-04-17T00:36:20.14868Z","steps":["trace[1117508176] 'read index received' (duration: 77.331794ms)","trace[1117508176] 'applied index is now lower than readState.Index' (duration: 162.806497ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:20.148919Z","caller":"traceutil/trace.go:171","msg":"trace[1323898827] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"235.903046ms","start":"2026-04-17T00:36:19.91299Z","end":"2026-04-17T00:36:20.148893Z","steps":["trace[1323898827] 'process raft request' (duration: 235.515327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:20.149126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.838479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:36:20.149187Z","caller":"traceutil/trace.go:171","msg":"trace[208746663] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4904; }","duration":"174.948691ms","start":"2026-04-17T00:36:19.974223Z","end":"2026-04-17T00:36:20.149172Z","steps":["trace[208746663] 'agreement among raft nodes before linearized reading' (duration: 174.838438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:20.149436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.784744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-17T00:36:20.149583Z","caller":"traceutil/trace.go:171","msg":"trace[1656313073] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4904; }","duration":"142.99151ms","start":"2026-04-17T00:36:20.006563Z","end":"2026-04-17T00:36:20.149554Z","steps":["trace[1656313073] 'agreement among raft nodes before linearized reading' (duration: 142.55971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:20.149496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.947869ms","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-04-17T00:36:20.150042Z","caller":"traceutil/trace.go:171","msg":"trace[1515063909] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4904; }","duration":"241.362188ms","start":"2026-04-17T00:36:19.908509Z","end":"2026-04-17T00:36:20.149871Z","steps":["trace[1515063909] 'agreement among raft nodes before linearized reading' (duration: 240.542339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:20.584208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.933103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:20.584287Z","caller":"traceutil/trace.go:171","msg":"trace[35706055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4904; }","duration":"188.065296ms","start":"2026-04-17T00:36:20.396204Z","end":"2026-04-17T00:36:20.58427Z","steps":["trace[35706055] 'range keys from in-memory index tree' (duration: 187.780349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:21.118375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.789125ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T00:36:21.118471Z","caller":"traceutil/trace.go:171","msg":"trace[1499933109] linearizableReadLoop","detail":"{readStateIndex:5082; appliedIndex:5081; }","duration":"120.06229ms","start":"2026-04-17T00:36:20.998389Z","end":"2026-04-17T00:36:21.118451Z","steps":["trace[1499933109] 'read index received' (duration: 107.133µs)","trace[1499933109] 'applied index is now lower than readState.Index' (duration: 119.953547ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:36:21.118661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.271934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:36:21.118713Z","caller":"traceutil/trace.go:171","msg":"trace[409836255] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:4904; }","duration":"120.344616ms","start":"2026-04-17T00:36:20.998358Z","end":"2026-04-17T00:36:21.118703Z","steps":["trace[409836255] 'agreement among raft nodes before linearized reading' (duration: 120.138101ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:21.50125Z","caller":"traceutil/trace.go:171","msg":"trace[963689262] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"102.969186ms","start":"2026-04-17T00:36:21.398248Z","end":"2026-04-17T00:36:21.501217Z","steps":["trace[963689262] 'process raft request' (duration: 102.609808ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:21.501325Z","caller":"traceutil/trace.go:171","msg":"trace[1088608590] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"102.831243ms","start":"2026-04-17T00:36:21.398454Z","end":"2026-04-17T00:36:21.501286Z","steps":["trace[1088608590] 'process raft request' (duration: 102.642668ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:21.501248Z","caller":"traceutil/trace.go:171","msg":"trace[801995307] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"100.07539ms","start":"2026-04-17T00:36:21.401138Z","end":"2026-04-17T00:36:21.501213Z","steps":["trace[801995307] 'process raft request' (duration: 100.010308ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:21.975343Z","caller":"traceutil/trace.go:171","msg":"trace[2127939519] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"121.771939ms","start":"2026-04-17T00:36:21.853528Z","end":"2026-04-17T00:36:21.9753Z","steps":["trace[2127939519] 'process raft request' (duration: 31.99241ms)","trace[2127939519] 'compare' (duration: 89.281137ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:54.257978Z","caller":"traceutil/trace.go:171","msg":"trace[1905651615] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"123.944713ms","start":"2026-04-17T00:36:54.133994Z","end":"2026-04-17T00:36:54.257939Z","steps":["trace[1905651615] 'process raft request' (duration: 123.689728ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:54.53099Z","caller":"traceutil/trace.go:171","msg":"trace[1933758912] linearizableReadLoop","detail":"{readStateIndex:5391; appliedIndex:5389; }","duration":"386.014477ms","start":"2026-04-17T00:36:54.144938Z","end":"2026-04-17T00:36:54.530952Z","steps":["trace[1933758912] 'read index received' (duration: 112.697921ms)","trace[1933758912] 'applied index is now lower than readState.Index' (duration: 273.315176ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:54.531386Z","caller":"traceutil/trace.go:171","msg":"trace[542097155] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"394.335988ms","start":"2026-04-17T00:36:54.137035Z","end":"2026-04-17T00:36:54.531371Z","steps":["trace[542097155] 'process raft request' (duration: 393.697004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:54.53154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:54.137016Z","time spent":"394.413349ms","remote":"127.0.0.1:36520","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-04-17T00:36:54.531901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.958668ms","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-04-17T00:36:54.531941Z","caller":"traceutil/trace.go:171","msg":"trace[715708289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5205; }","duration":"387.02746ms","start":"2026-04-17T00:36:54.144899Z","end":"2026-04-17T00:36:54.531927Z","steps":["trace[715708289] 'agreement among raft nodes before linearized reading' (duration: 386.873717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:54.531973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:54.144887Z","time spent":"387.079462ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T00:36:54.53227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.20831ms","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-04-17T00:36:54.532317Z","caller":"traceutil/trace.go:171","msg":"trace[249483709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5205; }","duration":"379.268963ms","start":"2026-04-17T00:36:54.153038Z","end":"2026-04-17T00:36:54.532307Z","steps":["trace[249483709] 'agreement among raft nodes before linearized reading' (duration: 379.157671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:54.532347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:54.153028Z","time spent":"379.311793ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T00:36:54.532878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.553397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:54.532907Z","caller":"traceutil/trace.go:171","msg":"trace[282456906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5205; }","duration":"137.606009ms","start":"2026-04-17T00:36:54.395292Z","end":"2026-04-17T00:36:54.532898Z","steps":["trace[282456906] 'agreement among raft nodes before linearized reading' (duration: 137.563839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:54.533076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.976482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:54.533109Z","caller":"traceutil/trace.go:171","msg":"trace[952005146] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:5205; }","duration":"186.086785ms","start":"2026-04-17T00:36:54.34701Z","end":"2026-04-17T00:36:54.533096Z","steps":["trace[952005146] 'agreement among raft nodes before linearized reading' (duration: 186.029983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:54.533284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.979862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:54.533313Z","caller":"traceutil/trace.go:171","msg":"trace[1133082484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5205; }","duration":"338.064673ms","start":"2026-04-17T00:36:54.19524Z","end":"2026-04-17T00:36:54.533305Z","steps":["trace[1133082484] 'agreement among raft nodes before linearized reading' (duration: 338.021723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:54.533354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:54.195207Z","time spent":"338.141534ms","remote":"127.0.0.1:36266","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-04-17T00:36:54.533498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.457193ms","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-04-17T00:36:54.533596Z","caller":"traceutil/trace.go:171","msg":"trace[205096793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5205; }","duration":"365.597507ms","start":"2026-04-17T00:36:54.167976Z","end":"2026-04-17T00:36:54.533573Z","steps":["trace[205096793] 'agreement among raft nodes before linearized reading' (duration: 365.308711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:54.533645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:54.167963Z","time spent":"365.671938ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T00:36:58.534302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.015855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:36:58.534393Z","caller":"traceutil/trace.go:171","msg":"trace[1037519113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5224; }","duration":"139.177289ms","start":"2026-04-17T00:36:58.395193Z","end":"2026-04-17T00:36:58.53437Z","steps":["trace[1037519113] 'range keys from in-memory index tree' (duration: 138.917253ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:58.704393Z","caller":"traceutil/trace.go:171","msg":"trace[1056302235] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"128.237243ms","start":"2026-04-17T00:36:58.576118Z","end":"2026-04-17T00:36:58.704356Z","steps":["trace[1056302235] 'process raft request' (duration: 117.117334ms)","trace[1056302235] 'compare' (duration: 10.488416ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:58.704378Z","caller":"traceutil/trace.go:171","msg":"trace[1299746669] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"128.168413ms","start":"2026-04-17T00:36:58.576173Z","end":"2026-04-17T00:36:58.704341Z","steps":["trace[1299746669] 'process raft request' (duration: 128.03319ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:37:13.837199Z","caller":"traceutil/trace.go:171","msg":"trace[474741585] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"148.111221ms","start":"2026-04-17T00:37:13.689064Z","end":"2026-04-17T00:37:13.837175Z","steps":["trace[474741585] 'process raft request' (duration: 67.111929ms)","trace[474741585] 'compare' (duration: 80.9016ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:37:13.846342Z","caller":"traceutil/trace.go:171","msg":"trace[540671588] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"148.03572ms","start":"2026-04-17T00:37:13.698297Z","end":"2026-04-17T00:37:13.846333Z","steps":["trace[540671588] 'process raft request' (duration: 147.945658ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:37:13.846501Z","caller":"traceutil/trace.go:171","msg":"trace[2016493189] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"147.484958ms","start":"2026-04-17T00:37:13.698987Z","end":"2026-04-17T00:37:13.846472Z","steps":["trace[2016493189] 'process raft request' (duration: 147.322125ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:37:52.662955Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2922} {"level":"info","ts":"2026-04-17T00:37:52.772805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2922,"took":"107.733742ms","hash":2784272879,"current-db-size-bytes":54177792,"current-db-size":"54 MB","current-db-size-in-use-bytes":29474816,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-17T00:37:52.772865Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2784272879,"revision":2922,"compact-revision":-1} {"level":"info","ts":"2026-04-17T00:42:52.671351Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5643} {"level":"info","ts":"2026-04-17T00:42:52.777295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5643,"took":"104.452036ms","hash":4131773188,"current-db-size-bytes":54177792,"current-db-size":"54 MB","current-db-size-in-use-bytes":18395136,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-17T00:42:52.777368Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4131773188,"revision":5643,"compact-revision":2922} {"level":"info","ts":"2026-04-17T00:44:28.787909Z","caller":"traceutil/trace.go:171","msg":"trace[1055629577] linearizableReadLoop","detail":"{readStateIndex:8211; appliedIndex:8210; }","duration":"173.271564ms","start":"2026-04-17T00:44:28.614616Z","end":"2026-04-17T00:44:28.787887Z","steps":["trace[1055629577] 'read index received' (duration: 173.039728ms)","trace[1055629577] 'applied index is now lower than readState.Index' (duration: 230.666µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:44:28.788092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.44932ms","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-04-17T00:44:28.788139Z","caller":"traceutil/trace.go:171","msg":"trace[507045279] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7924; }","duration":"173.541753ms","start":"2026-04-17T00:44:28.614584Z","end":"2026-04-17T00:44:28.788126Z","steps":["trace[507045279] 'agreement among raft nodes before linearized reading' (duration: 173.44277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:44:28.788343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.444463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/keystone-openstack-keystone-credential-rotate\" ","response":"range_response_count:1 size:691"} {"level":"info","ts":"2026-04-17T00:44:28.788419Z","caller":"traceutil/trace.go:171","msg":"trace[899990034] range","detail":"{range_begin:/registry/roles/openstack/keystone-openstack-keystone-credential-rotate; range_end:; response_count:1; response_revision:7924; }","duration":"116.556205ms","start":"2026-04-17T00:44:28.671843Z","end":"2026-04-17T00:44:28.788399Z","steps":["trace[899990034] 'agreement among raft nodes before linearized reading' (duration: 116.324159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:44:28.78855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.354162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/keystone-openstack-keystone-bootstrap\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-04-17T00:44:28.789215Z","caller":"traceutil/trace.go:171","msg":"trace[2069968035] range","detail":"{range_begin:/registry/roles/openstack/keystone-openstack-keystone-bootstrap; range_end:; response_count:1; response_revision:7924; }","duration":"159.069941ms","start":"2026-04-17T00:44:28.630112Z","end":"2026-04-17T00:44:28.789182Z","steps":["trace[2069968035] 'agreement among raft nodes before linearized reading' (duration: 157.96826ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:44:28.789375Z","caller":"traceutil/trace.go:171","msg":"trace[148144301] transaction","detail":"{read_only:false; response_revision:7924; number_of_response:1; }","duration":"185.264975ms","start":"2026-04-17T00:44:28.604046Z","end":"2026-04-17T00:44:28.789311Z","steps":["trace[148144301] 'process raft request' (duration: 183.67822ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:44:33.625071Z","caller":"traceutil/trace.go:171","msg":"trace[29338383] transaction","detail":"{read_only:false; response_revision:7952; number_of_response:1; }","duration":"129.860812ms","start":"2026-04-17T00:44:33.495189Z","end":"2026-04-17T00:44:33.62505Z","steps":["trace[29338383] 'process raft request' (duration: 129.78323ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:44:33.625083Z","caller":"traceutil/trace.go:171","msg":"trace[1086696107] transaction","detail":"{read_only:false; response_revision:7951; number_of_response:1; }","duration":"131.520369ms","start":"2026-04-17T00:44:33.493528Z","end":"2026-04-17T00:44:33.625049Z","steps":["trace[1086696107] 'process raft request' (duration: 131.303363ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:44:37.122042Z","caller":"traceutil/trace.go:171","msg":"trace[506713529] transaction","detail":"{read_only:false; response_revision:7965; number_of_response:1; }","duration":"108.081885ms","start":"2026-04-17T00:44:37.013899Z","end":"2026-04-17T00:44:37.121981Z","steps":["trace[506713529] 'process raft request' (duration: 107.579481ms)"],"step_count":1}