{"level":"warn","ts":"2026-04-08T01:21:53.559896Z","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-08T01:21:53.560014Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222: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.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-08T01:21:53.560101Z","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-08T01:21:53.56012Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-04-08T01:21:53.560178Z","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-08T01:21:53.560998Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-04-08T01:21:53.561148Z","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":16,"max-cpu-available":16,"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.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-08T01:21:53.567247Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.843443ms"} {"level":"info","ts":"2026-04-08T01:21:53.577472Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-04-08T01:21:53.577588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T01:21:53.577622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-04-08T01:21:53.577639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T01:21:53.577665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-04-08T01:21:53.577723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-04-08T01:21:53.583811Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T01:21:53.585301Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T01:21:53.586644Z","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-08T01:21:53.589419Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T01:21:53.589597Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T01:21:53.589565Z","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-08T01:21:53.589711Z","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-08T01:21:53.589774Z","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-08T01:21:53.591831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-04-08T01:21:53.591955Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-04-08T01:21:53.59531Z","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-08T01:21:53.595414Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-04-08T01:21:53.595478Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-04-08T01:21:53.595638Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T01:21:53.595693Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T01:21:54.07822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T01:21:54.078278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T01:21:54.078317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-04-08T01:21:54.07833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-04-08T01:21:54.078338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-04-08T01:21:54.078348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-04-08T01:21:54.078357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-04-08T01:21:54.092044Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T01:21:54.094105Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T01:21:54.09412Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T01:21:54.094202Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T01:21:54.094461Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T01:21:54.094571Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T01:21:54.096782Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-04-08T01:21:54.097582Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T01:21:54.098223Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T01:21:54.098361Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T01:21:54.098395Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T01:21:55.903698Z","caller":"traceutil/trace.go:171","msg":"trace[404260607] linearizableReadLoop","detail":"{readStateIndex:47; appliedIndex:44; }","duration":"122.950783ms","start":"2026-04-08T01:21:55.780723Z","end":"2026-04-08T01:21:55.903673Z","steps":["trace[404260607] 'read index received' (duration: 31.382083ms)","trace[404260607] 'applied index is now lower than readState.Index' (duration: 91.56792ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:21:55.903712Z","caller":"traceutil/trace.go:171","msg":"trace[1819501012] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"126.432364ms","start":"2026-04-08T01:21:55.777238Z","end":"2026-04-08T01:21:55.90367Z","steps":["trace[1819501012] 'process raft request' (duration: 34.858364ms)","trace[1819501012] 'compare' (duration: 91.375305ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:21:55.903786Z","caller":"traceutil/trace.go:171","msg":"trace[892495280] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"125.006578ms","start":"2026-04-08T01:21:55.778753Z","end":"2026-04-08T01:21:55.90376Z","steps":["trace[892495280] 'process raft request' (duration: 124.888844ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:21:55.903807Z","caller":"traceutil/trace.go:171","msg":"trace[730257677] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"125.824589ms","start":"2026-04-08T01:21:55.777926Z","end":"2026-04-08T01:21:55.903751Z","steps":["trace[730257677] 'process raft request' (duration: 125.656314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:21:55.903939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.133118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T01:21:55.904162Z","caller":"traceutil/trace.go:171","msg":"trace[1178833576] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:43; }","duration":"123.375605ms","start":"2026-04-08T01:21:55.780692Z","end":"2026-04-08T01:21:55.904068Z","steps":["trace[1178833576] 'agreement among raft nodes before linearized reading' (duration: 123.085558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:21:56.22969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.10557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-08T01:21:56.22989Z","caller":"traceutil/trace.go:171","msg":"trace[1939581693] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"127.322498ms","start":"2026-04-08T01:21:56.102546Z","end":"2026-04-08T01:21:56.229868Z","steps":["trace[1939581693] 'process raft request' (duration: 127.248826ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:21:56.230101Z","caller":"traceutil/trace.go:171","msg":"trace[1794165828] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"203.66979ms","start":"2026-04-08T01:21:56.026368Z","end":"2026-04-08T01:21:56.230038Z","steps":["trace[1794165828] 'process raft request' (duration: 101.727337ms)","trace[1794165828] 'compare' (duration: 100.945126ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:21:56.445513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.84897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-08T01:21:56.445997Z","caller":"traceutil/trace.go:171","msg":"trace[1035136784] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"193.799141ms","start":"2026-04-08T01:21:56.252163Z","end":"2026-04-08T01:21:56.445963Z","steps":["trace[1035136784] 'process raft request' (duration: 82.996696ms)","trace[1035136784] 'compare' (duration: 109.770058ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:21:56.446095Z","caller":"traceutil/trace.go:171","msg":"trace[487098848] linearizableReadLoop","detail":"{readStateIndex:64; appliedIndex:62; }","duration":"172.847072ms","start":"2026-04-08T01:21:56.273197Z","end":"2026-04-08T01:21:56.446044Z","steps":["trace[487098848] 'read index received' (duration: 29.950926ms)","trace[487098848] 'applied index is now lower than readState.Index' (duration: 142.893366ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:21:56.446451Z","caller":"traceutil/trace.go:171","msg":"trace[1632710597] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"140.134194ms","start":"2026-04-08T01:21:56.306266Z","end":"2026-04-08T01:21:56.446401Z","steps":["trace[1632710597] 'process raft request' (duration: 139.705502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:21:56.44654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.341525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T01:21:56.44659Z","caller":"traceutil/trace.go:171","msg":"trace[1030040790] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:61; }","duration":"173.423437ms","start":"2026-04-08T01:21:56.273151Z","end":"2026-04-08T01:21:56.446575Z","steps":["trace[1030040790] 'agreement among raft nodes before linearized reading' (duration: 173.239012ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:21:56.621287Z","caller":"traceutil/trace.go:171","msg":"trace[325051242] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"170.299975ms","start":"2026-04-08T01:21:56.450955Z","end":"2026-04-08T01:21:56.621255Z","steps":["trace[325051242] 'process raft request' (duration: 159.297117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:21:56.621449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.45638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T01:21:56.62148Z","caller":"traceutil/trace.go:171","msg":"trace[294367059] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"170.199513ms","start":"2026-04-08T01:21:56.45126Z","end":"2026-04-08T01:21:56.621459Z","steps":["trace[294367059] 'process raft request' (duration: 169.903154ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:21:56.621304Z","caller":"traceutil/trace.go:171","msg":"trace[991765664] linearizableReadLoop","detail":"{readStateIndex:67; appliedIndex:66; }","duration":"170.305906ms","start":"2026-04-08T01:21:56.450971Z","end":"2026-04-08T01:21:56.621277Z","steps":["trace[991765664] 'read index received' (duration: 84.603329ms)","trace[991765664] 'applied index is now lower than readState.Index' (duration: 85.701537ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:21:56.621495Z","caller":"traceutil/trace.go:171","msg":"trace[747783575] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:65; }","duration":"170.520601ms","start":"2026-04-08T01:21:56.450963Z","end":"2026-04-08T01:21:56.621483Z","steps":["trace[747783575] 'agreement among raft nodes before linearized reading' (duration: 170.391738ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:21:56.621646Z","caller":"traceutil/trace.go:171","msg":"trace[1232313069] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"169.755411ms","start":"2026-04-08T01:21:56.451864Z","end":"2026-04-08T01:21:56.621619Z","steps":["trace[1232313069] 'process raft request' (duration: 169.34297ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:21:56.670323Z","caller":"traceutil/trace.go:171","msg":"trace[1142789805] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"131.124959ms","start":"2026-04-08T01:21:56.539173Z","end":"2026-04-08T01:21:56.670298Z","steps":["trace[1142789805] 'process raft request' (duration: 130.972924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:21:56.670442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.263667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T01:21:56.670524Z","caller":"traceutil/trace.go:171","msg":"trace[1338489075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:66; }","duration":"196.3896ms","start":"2026-04-08T01:21:56.474116Z","end":"2026-04-08T01:21:56.670506Z","steps":["trace[1338489075] 'agreement among raft nodes before linearized reading' (duration: 196.174205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:21:57.859128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.160519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:21:57.859241Z","caller":"traceutil/trace.go:171","msg":"trace[1748756162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:197; }","duration":"184.325653ms","start":"2026-04-08T01:21:57.67489Z","end":"2026-04-08T01:21:57.859215Z","steps":["trace[1748756162] 'range keys from in-memory index tree' (duration: 184.078976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:22:02.884142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.493632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-04-08T01:22:02.884253Z","caller":"traceutil/trace.go:171","msg":"trace[1278329958] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:301; }","duration":"135.653016ms","start":"2026-04-08T01:22:02.748582Z","end":"2026-04-08T01:22:02.884235Z","steps":["trace[1278329958] 'range keys from in-memory index tree' (duration: 135.355458ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:11.628721Z","caller":"traceutil/trace.go:171","msg":"trace[1755738699] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"326.123441ms","start":"2026-04-08T01:22:11.302564Z","end":"2026-04-08T01:22:11.628687Z","steps":["trace[1755738699] 'read index received' (duration: 325.868814ms)","trace[1755738699] 'applied index is now lower than readState.Index' (duration: 253.527µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:22:11.628817Z","caller":"traceutil/trace.go:171","msg":"trace[87910805] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"520.970949ms","start":"2026-04-08T01:22:11.107813Z","end":"2026-04-08T01:22:11.628784Z","steps":["trace[87910805] 'process raft request' (duration: 520.691792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:22:11.628893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.302595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:22:11.628939Z","caller":"traceutil/trace.go:171","msg":"trace[1064824159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"326.414578ms","start":"2026-04-08T01:22:11.302508Z","end":"2026-04-08T01:22:11.628923Z","steps":["trace[1064824159] 'agreement among raft nodes before linearized reading' (duration: 326.312805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:22:11.629014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:22:11.107794Z","time spent":"521.110003ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:22:11.628987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:22:11.302486Z","time spent":"326.492ms","remote":"127.0.0.1:52896","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-08T01:22:12.133967Z","caller":"traceutil/trace.go:171","msg":"trace[264436372] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"158.611958ms","start":"2026-04-08T01:22:11.975334Z","end":"2026-04-08T01:22:12.133946Z","steps":["trace[264436372] 'read index received' (duration: 158.430454ms)","trace[264436372] 'applied index is now lower than readState.Index' (duration: 180.654µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:22:12.134084Z","caller":"traceutil/trace.go:171","msg":"trace[1014642029] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"490.777178ms","start":"2026-04-08T01:22:11.643267Z","end":"2026-04-08T01:22:12.134044Z","steps":["trace[1014642029] 'process raft request' (duration: 490.554141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:22:12.134166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.795093ms","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-08T01:22:12.134205Z","caller":"traceutil/trace.go:171","msg":"trace[294089875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:406; }","duration":"158.905086ms","start":"2026-04-08T01:22:11.975288Z","end":"2026-04-08T01:22:12.134193Z","steps":["trace[294089875] 'agreement among raft nodes before linearized reading' (duration: 158.798654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:22:12.134277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:22:11.64325Z","time spent":"490.928642ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:22:12.134329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.691201ms","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-08T01:22:12.13438Z","caller":"traceutil/trace.go:171","msg":"trace[19367473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:406; }","duration":"155.760594ms","start":"2026-04-08T01:22:11.978606Z","end":"2026-04-08T01:22:12.134367Z","steps":["trace[19367473] 'agreement among raft nodes before linearized reading' (duration: 155.674781ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:21.829524Z","caller":"traceutil/trace.go:171","msg":"trace[326248364] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"102.153609ms","start":"2026-04-08T01:22:21.727344Z","end":"2026-04-08T01:22:21.829498Z","steps":["trace[326248364] 'process raft request' (duration: 102.021186ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:21.856707Z","caller":"traceutil/trace.go:171","msg":"trace[1012202479] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"128.763697ms","start":"2026-04-08T01:22:21.727925Z","end":"2026-04-08T01:22:21.856689Z","steps":["trace[1012202479] 'process raft request' (duration: 128.671484ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:24.008382Z","caller":"traceutil/trace.go:171","msg":"trace[98787944] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"184.366004ms","start":"2026-04-08T01:22:23.823995Z","end":"2026-04-08T01:22:24.008361Z","steps":["trace[98787944] 'process raft request' (duration: 184.205209ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:24.014676Z","caller":"traceutil/trace.go:171","msg":"trace[888363773] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"184.673283ms","start":"2026-04-08T01:22:23.829974Z","end":"2026-04-08T01:22:24.014647Z","steps":["trace[888363773] 'process raft request' (duration: 184.553059ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:24.014705Z","caller":"traceutil/trace.go:171","msg":"trace[303085660] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"185.262268ms","start":"2026-04-08T01:22:23.829417Z","end":"2026-04-08T01:22:24.014679Z","steps":["trace[303085660] 'process raft request' (duration: 185.00408ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:24.014893Z","caller":"traceutil/trace.go:171","msg":"trace[1842415744] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"174.874646ms","start":"2026-04-08T01:22:23.840005Z","end":"2026-04-08T01:22:24.014879Z","steps":["trace[1842415744] 'process raft request' (duration: 174.674841ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:24.014899Z","caller":"traceutil/trace.go:171","msg":"trace[2121894467] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"177.929216ms","start":"2026-04-08T01:22:23.83691Z","end":"2026-04-08T01:22:24.014839Z","steps":["trace[2121894467] 'process raft request' (duration: 177.655718ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:24.014941Z","caller":"traceutil/trace.go:171","msg":"trace[227244642] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"174.860365ms","start":"2026-04-08T01:22:23.840031Z","end":"2026-04-08T01:22:24.014891Z","steps":["trace[227244642] 'process raft request' (duration: 174.610309ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:24.015004Z","caller":"traceutil/trace.go:171","msg":"trace[970716936] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"174.922516ms","start":"2026-04-08T01:22:23.840068Z","end":"2026-04-08T01:22:24.014991Z","steps":["trace[970716936] 'process raft request' (duration: 174.6499ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:24.015031Z","caller":"traceutil/trace.go:171","msg":"trace[1162201062] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"169.907685ms","start":"2026-04-08T01:22:23.845116Z","end":"2026-04-08T01:22:24.015024Z","steps":["trace[1162201062] 'process raft request' (duration: 169.676279ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:24.014908Z","caller":"traceutil/trace.go:171","msg":"trace[699682351] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"175.08931ms","start":"2026-04-08T01:22:23.839739Z","end":"2026-04-08T01:22:24.014829Z","steps":["trace[699682351] 'process raft request' (duration: 174.865805ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:24.015144Z","caller":"traceutil/trace.go:171","msg":"trace[1759926617] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"143.375908ms","start":"2026-04-08T01:22:23.871761Z","end":"2026-04-08T01:22:24.015137Z","steps":["trace[1759926617] 'process raft request' (duration: 143.270625ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:24.015124Z","caller":"traceutil/trace.go:171","msg":"trace[922119634] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"164.283617ms","start":"2026-04-08T01:22:23.850818Z","end":"2026-04-08T01:22:24.015101Z","steps":["trace[922119634] 'process raft request' (duration: 164.145484ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:30.053748Z","caller":"traceutil/trace.go:171","msg":"trace[2008501186] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"211.581348ms","start":"2026-04-08T01:22:29.842146Z","end":"2026-04-08T01:22:30.053727Z","steps":["trace[2008501186] 'process raft request' (duration: 211.218798ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:30.401891Z","caller":"traceutil/trace.go:171","msg":"trace[962684117] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"169.087094ms","start":"2026-04-08T01:22:30.232774Z","end":"2026-04-08T01:22:30.401861Z","steps":["trace[962684117] 'process raft request' (duration: 70.515669ms)","trace[962684117] 'compare' (duration: 98.333649ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:22:31.093261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.358375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:22:31.093358Z","caller":"traceutil/trace.go:171","msg":"trace[1712684295] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"513.150854ms","start":"2026-04-08T01:22:30.580193Z","end":"2026-04-08T01:22:31.093343Z","steps":["trace[1712684295] 'read index received' (duration: 199.671795ms)","trace[1712684295] 'applied index is now lower than readState.Index' (duration: 313.478159ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:22:31.093408Z","caller":"traceutil/trace.go:171","msg":"trace[126010602] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"608.053953ms","start":"2026-04-08T01:22:30.485347Z","end":"2026-04-08T01:22:31.093401Z","steps":["trace[126010602] 'process raft request' (duration: 294.492271ms)","trace[126010602] 'compare' (duration: 313.11562ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:22:31.093452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:22:30.485331Z","time spent":"608.101025ms","remote":"127.0.0.1:53120","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-08T01:22:31.096012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.791283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rdcg5\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-08T01:22:31.096141Z","caller":"traceutil/trace.go:171","msg":"trace[1994974059] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rdcg5; range_end:; response_count:1; response_revision:581; }","duration":"515.952648ms","start":"2026-04-08T01:22:30.580144Z","end":"2026-04-08T01:22:31.096096Z","steps":["trace[1994974059] 'agreement among raft nodes before linearized reading' (duration: 513.271997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:22:31.096232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:22:30.580125Z","time spent":"516.091642ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-rdcg5\" "} {"level":"info","ts":"2026-04-08T01:22:31.24173Z","caller":"traceutil/trace.go:171","msg":"trace[175414809] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"148.122253ms","start":"2026-04-08T01:22:31.093377Z","end":"2026-04-08T01:22:31.241499Z","steps":["trace[175414809] 'read index received' (duration: 147.59913ms)","trace[175414809] 'applied index is now lower than readState.Index' (duration: 522.083µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:22:31.241877Z","caller":"traceutil/trace.go:171","msg":"trace[1288117055] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"634.331441ms","start":"2026-04-08T01:22:30.607529Z","end":"2026-04-08T01:22:31.24186Z","steps":["trace[1288117055] 'process raft request' (duration: 633.443088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:22:31.241987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:22:30.607505Z","time spent":"634.407404ms","remote":"127.0.0.1:52984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:22:31.242165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.553554ms","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-08T01:22:31.242219Z","caller":"traceutil/trace.go:171","msg":"trace[1150526802] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"455.896413ms","start":"2026-04-08T01:22:30.786309Z","end":"2026-04-08T01:22:31.242205Z","steps":["trace[1150526802] 'agreement among raft nodes before linearized reading' (duration: 455.500113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:22:31.242253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:22:30.786285Z","time spent":"455.959815ms","remote":"127.0.0.1:53120","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-08T01:22:31.262956Z","caller":"traceutil/trace.go:171","msg":"trace[1931819133] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"152.031377ms","start":"2026-04-08T01:22:31.110891Z","end":"2026-04-08T01:22:31.262922Z","steps":["trace[1931819133] 'process raft request' (duration: 151.843282ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:33.006501Z","caller":"traceutil/trace.go:171","msg":"trace[2044444221] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"296.827662ms","start":"2026-04-08T01:22:32.709649Z","end":"2026-04-08T01:22:33.006476Z","steps":["trace[2044444221] 'process raft request' (duration: 296.657088ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:33.037004Z","caller":"traceutil/trace.go:171","msg":"trace[1181512396] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"176.772281ms","start":"2026-04-08T01:22:32.860205Z","end":"2026-04-08T01:22:33.036978Z","steps":["trace[1181512396] 'process raft request' (duration: 176.663688ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:36.482894Z","caller":"traceutil/trace.go:171","msg":"trace[136344869] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"109.345013ms","start":"2026-04-08T01:22:36.373524Z","end":"2026-04-08T01:22:36.482869Z","steps":["trace[136344869] 'process raft request' (duration: 108.84353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:22:47.446483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.455368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:22:47.44658Z","caller":"traceutil/trace.go:171","msg":"trace[1171847425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"143.58819ms","start":"2026-04-08T01:22:47.302965Z","end":"2026-04-08T01:22:47.446553Z","steps":["trace[1171847425] 'range keys from in-memory index tree' (duration: 143.378166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:22:48.363595Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117561443004239,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T01:22:48.504041Z","caller":"traceutil/trace.go:171","msg":"trace[2120431091] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"1.053174873s","start":"2026-04-08T01:22:47.450837Z","end":"2026-04-08T01:22:48.504012Z","steps":["trace[2120431091] 'process raft request' (duration: 1.053038089s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:22:48.504226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:22:47.45082Z","time spent":"1.053291755s","remote":"127.0.0.1:53120","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-08T01:22:48.678629Z","caller":"traceutil/trace.go:171","msg":"trace[347795025] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:774; }","duration":"815.351809ms","start":"2026-04-08T01:22:47.863253Z","end":"2026-04-08T01:22:48.678605Z","steps":["trace[347795025] 'read index received' (duration: 640.679634ms)","trace[347795025] 'applied index is now lower than readState.Index' (duration: 174.671005ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:22:48.67887Z","caller":"traceutil/trace.go:171","msg":"trace[1366531276] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"945.307546ms","start":"2026-04-08T01:22:47.733535Z","end":"2026-04-08T01:22:48.678843Z","steps":["trace[1366531276] 'process raft request' (duration: 944.899496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:22:48.678937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.663718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T01:22:48.678988Z","caller":"traceutil/trace.go:171","msg":"trace[745357749] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:748; }","duration":"815.7746ms","start":"2026-04-08T01:22:47.863197Z","end":"2026-04-08T01:22:48.678972Z","steps":["trace[745357749] 'agreement among raft nodes before linearized reading' (duration: 815.565744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:22:48.679028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:22:47.863178Z","time spent":"815.841783ms","remote":"127.0.0.1:52916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.222\" "} {"level":"warn","ts":"2026-04-08T01:22:48.679049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:22:47.733509Z","time spent":"945.446781ms","remote":"127.0.0.1:53090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:22:48.679046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.786271ms","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-08T01:22:48.679138Z","caller":"traceutil/trace.go:171","msg":"trace[1006694239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:748; }","duration":"132.921225ms","start":"2026-04-08T01:22:48.546196Z","end":"2026-04-08T01:22:48.679117Z","steps":["trace[1006694239] 'agreement among raft nodes before linearized reading' (duration: 132.653197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:22:48.679439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.585196ms","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-08T01:22:48.679553Z","caller":"traceutil/trace.go:171","msg":"trace[622127454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:748; }","duration":"100.72398ms","start":"2026-04-08T01:22:48.578789Z","end":"2026-04-08T01:22:48.679513Z","steps":["trace[622127454] 'agreement among raft nodes before linearized reading' (duration: 100.177236ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:23:02.277948Z","caller":"traceutil/trace.go:171","msg":"trace[1317426027] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"131.987399ms","start":"2026-04-08T01:23:02.14594Z","end":"2026-04-08T01:23:02.277927Z","steps":["trace[1317426027] 'process raft request' (duration: 131.862426ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:23:14.987224Z","caller":"traceutil/trace.go:171","msg":"trace[411143740] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"107.616609ms","start":"2026-04-08T01:23:14.879587Z","end":"2026-04-08T01:23:14.987204Z","steps":["trace[411143740] 'process raft request' (duration: 107.418673ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:23:15.713928Z","caller":"traceutil/trace.go:171","msg":"trace[59996527] linearizableReadLoop","detail":"{readStateIndex:1175; appliedIndex:1173; }","duration":"414.129403ms","start":"2026-04-08T01:23:15.299758Z","end":"2026-04-08T01:23:15.713888Z","steps":["trace[59996527] 'read index received' (duration: 323.33354ms)","trace[59996527] 'applied index is now lower than readState.Index' (duration: 90.794753ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:23:15.714076Z","caller":"traceutil/trace.go:171","msg":"trace[1214267227] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"723.723526ms","start":"2026-04-08T01:23:14.99032Z","end":"2026-04-08T01:23:15.714043Z","steps":["trace[1214267227] 'process raft request' (duration: 632.82636ms)","trace[1214267227] 'compare' (duration: 90.304561ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:23:15.714168Z","caller":"traceutil/trace.go:171","msg":"trace[1038313463] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"723.656804ms","start":"2026-04-08T01:23:14.990462Z","end":"2026-04-08T01:23:15.714119Z","steps":["trace[1038313463] 'process raft request' (duration: 723.302935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:23:15.7144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.976908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-08T01:23:15.714408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:23:14.990299Z","time spent":"723.851349ms","remote":"127.0.0.1:53120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:23:15.714443Z","caller":"traceutil/trace.go:171","msg":"trace[1069483234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1140; }","duration":"370.03038ms","start":"2026-04-08T01:23:15.344397Z","end":"2026-04-08T01:23:15.714428Z","steps":["trace[1069483234] 'agreement among raft nodes before linearized reading' (duration: 369.657881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:23:15.714485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:23:15.344348Z","time spent":"370.128503ms","remote":"127.0.0.1:53120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T01:23:15.714508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:23:14.990412Z","time spent":"724.019094ms","remote":"127.0.0.1:53120","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-08T01:23:15.714374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.373189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:23:15.714734Z","caller":"traceutil/trace.go:171","msg":"trace[1311991295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1140; }","duration":"415.000256ms","start":"2026-04-08T01:23:15.299707Z","end":"2026-04-08T01:23:15.714707Z","steps":["trace[1311991295] 'agreement among raft nodes before linearized reading' (duration: 414.342518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:23:15.714881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:23:15.299686Z","time spent":"415.17485ms","remote":"127.0.0.1:52888","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-08T01:23:38.153617Z","caller":"traceutil/trace.go:171","msg":"trace[416835700] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"198.344654ms","start":"2026-04-08T01:23:37.95525Z","end":"2026-04-08T01:23:38.153594Z","steps":["trace[416835700] 'process raft request' (duration: 198.18577ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:23:38.163644Z","caller":"traceutil/trace.go:171","msg":"trace[2042264361] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1442; }","duration":"132.209716ms","start":"2026-04-08T01:23:38.031415Z","end":"2026-04-08T01:23:38.163625Z","steps":["trace[2042264361] 'read index received' (duration: 122.038902ms)","trace[2042264361] 'applied index is now lower than readState.Index' (duration: 10.169903ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:23:38.163701Z","caller":"traceutil/trace.go:171","msg":"trace[1661465670] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"205.005718ms","start":"2026-04-08T01:23:37.958674Z","end":"2026-04-08T01:23:38.163679Z","steps":["trace[1661465670] 'process raft request' (duration: 204.848163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:23:38.16374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.296689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T01:23:38.163766Z","caller":"traceutil/trace.go:171","msg":"trace[761356005] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1400; }","duration":"132.34756ms","start":"2026-04-08T01:23:38.031411Z","end":"2026-04-08T01:23:38.163759Z","steps":["trace[761356005] 'agreement among raft nodes before linearized reading' (duration: 132.277478ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:23:38.34303Z","caller":"traceutil/trace.go:171","msg":"trace[1020008493] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1444; }","duration":"109.148976ms","start":"2026-04-08T01:23:38.23386Z","end":"2026-04-08T01:23:38.343009Z","steps":["trace[1020008493] 'read index received' (duration: 108.966111ms)","trace[1020008493] 'applied index is now lower than readState.Index' (duration: 181.825µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:23:38.343164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.28821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T01:23:38.343199Z","caller":"traceutil/trace.go:171","msg":"trace[638652816] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1401; }","duration":"109.370951ms","start":"2026-04-08T01:23:38.233819Z","end":"2026-04-08T01:23:38.34319Z","steps":["trace[638652816] 'agreement among raft nodes before linearized reading' (duration: 109.287549ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:23:38.343358Z","caller":"traceutil/trace.go:171","msg":"trace[764841762] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"167.587995ms","start":"2026-04-08T01:23:38.175739Z","end":"2026-04-08T01:23:38.343327Z","steps":["trace[764841762] 'process raft request' (duration: 167.112642ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:23:47.355376Z","caller":"traceutil/trace.go:171","msg":"trace[1865393442] linearizableReadLoop","detail":"{readStateIndex:1647; appliedIndex:1646; }","duration":"189.915687ms","start":"2026-04-08T01:23:47.165441Z","end":"2026-04-08T01:23:47.355356Z","steps":["trace[1865393442] 'read index received' (duration: 189.788603ms)","trace[1865393442] 'applied index is now lower than readState.Index' (duration: 126.143µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:23:47.35543Z","caller":"traceutil/trace.go:171","msg":"trace[964737512] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"190.82024ms","start":"2026-04-08T01:23:47.164581Z","end":"2026-04-08T01:23:47.355401Z","steps":["trace[964737512] 'process raft request' (duration: 190.552582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:23:47.355636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.170192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1114"} {"level":"info","ts":"2026-04-08T01:23:47.355776Z","caller":"traceutil/trace.go:171","msg":"trace[1146301956] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:1587; }","duration":"190.293596ms","start":"2026-04-08T01:23:47.165399Z","end":"2026-04-08T01:23:47.355693Z","steps":["trace[1146301956] 'agreement among raft nodes before linearized reading' (duration: 190.06717ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:23:47.387105Z","caller":"traceutil/trace.go:171","msg":"trace[757043067] transaction","detail":"{read_only:false; response_revision:1589; number_of_response:1; }","duration":"197.345538ms","start":"2026-04-08T01:23:47.189732Z","end":"2026-04-08T01:23:47.387078Z","steps":["trace[757043067] 'process raft request' (duration: 197.248086ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:23:47.387129Z","caller":"traceutil/trace.go:171","msg":"trace[466002702] transaction","detail":"{read_only:false; response_revision:1588; number_of_response:1; }","duration":"221.074365ms","start":"2026-04-08T01:23:47.166022Z","end":"2026-04-08T01:23:47.387097Z","steps":["trace[466002702] 'process raft request' (duration: 220.833299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:23:47.387357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.114775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1082"} {"level":"warn","ts":"2026-04-08T01:23:47.387404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.466628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T01:23:47.387439Z","caller":"traceutil/trace.go:171","msg":"trace[406180564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1589; }","duration":"148.540141ms","start":"2026-04-08T01:23:47.238889Z","end":"2026-04-08T01:23:47.387429Z","steps":["trace[406180564] 'agreement among raft nodes before linearized reading' (duration: 148.401467ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:23:47.387446Z","caller":"traceutil/trace.go:171","msg":"trace[390665396] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:1589; }","duration":"221.24473ms","start":"2026-04-08T01:23:47.166179Z","end":"2026-04-08T01:23:47.387424Z","steps":["trace[390665396] 'agreement among raft nodes before linearized reading' (duration: 220.968042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:23:47.387497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.448659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:23:47.387605Z","caller":"traceutil/trace.go:171","msg":"trace[1723384392] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1589; }","duration":"163.613512ms","start":"2026-04-08T01:23:47.223979Z","end":"2026-04-08T01:23:47.387593Z","steps":["trace[1723384392] 'agreement among raft nodes before linearized reading' (duration: 163.447878ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:01.98752Z","caller":"traceutil/trace.go:171","msg":"trace[655893419] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"109.68459ms","start":"2026-04-08T01:24:01.877805Z","end":"2026-04-08T01:24:01.98749Z","steps":["trace[655893419] 'process raft request' (duration: 63.602373ms)","trace[655893419] 'compare' (duration: 45.818821ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:24:02.222175Z","caller":"traceutil/trace.go:171","msg":"trace[1244147781] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"163.107369ms","start":"2026-04-08T01:24:02.058992Z","end":"2026-04-08T01:24:02.222099Z","steps":["trace[1244147781] 'process raft request' (duration: 70.728798ms)","trace[1244147781] 'compare' (duration: 92.168216ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:24:02.240042Z","caller":"traceutil/trace.go:171","msg":"trace[976072699] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"178.043488ms","start":"2026-04-08T01:24:02.061973Z","end":"2026-04-08T01:24:02.240017Z","steps":["trace[976072699] 'process raft request' (duration: 178.008767ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:02.240051Z","caller":"traceutil/trace.go:171","msg":"trace[93980808] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"180.548422ms","start":"2026-04-08T01:24:02.059472Z","end":"2026-04-08T01:24:02.24002Z","steps":["trace[93980808] 'process raft request' (duration: 180.289194ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:02.240971Z","caller":"traceutil/trace.go:171","msg":"trace[1823928605] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"180.824829ms","start":"2026-04-08T01:24:02.060113Z","end":"2026-04-08T01:24:02.240938Z","steps":["trace[1823928605] 'process raft request' (duration: 179.808223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:03.990656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.915728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:24:03.990876Z","caller":"traceutil/trace.go:171","msg":"trace[1268301045] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"920.967074ms","start":"2026-04-08T01:24:03.069877Z","end":"2026-04-08T01:24:03.990844Z","steps":["trace[1268301045] 'process raft request' (duration: 130.770148ms)","trace[1268301045] 'compare' (duration: 789.760654ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:24:03.990927Z","caller":"traceutil/trace.go:171","msg":"trace[1177262771] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"920.859992ms","start":"2026-04-08T01:24:03.070019Z","end":"2026-04-08T01:24:03.990879Z","steps":["trace[1177262771] 'process raft request' (duration: 920.727188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:03.991091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:24:03.069991Z","time spent":"921.030016ms","remote":"127.0.0.1:53120","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-08T01:24:03.991175Z","caller":"traceutil/trace.go:171","msg":"trace[2130419573] linearizableReadLoop","detail":"{readStateIndex:1893; appliedIndex:1891; }","duration":"825.091842ms","start":"2026-04-08T01:24:03.166044Z","end":"2026-04-08T01:24:03.991136Z","steps":["trace[2130419573] 'read index received' (duration: 34.557208ms)","trace[2130419573] 'applied index is now lower than readState.Index' (duration: 790.530284ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:24:03.991091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:24:03.069865Z","time spent":"921.124209ms","remote":"127.0.0.1:53120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:24:04.179046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.191331ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-08T01:24:04.179087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012914974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:24:04.179226Z","caller":"traceutil/trace.go:171","msg":"trace[338703111] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1823; }","duration":"1.013229843s","start":"2026-04-08T01:24:03.165957Z","end":"2026-04-08T01:24:04.179187Z","steps":["trace[338703111] 'agreement among raft nodes before linearized reading' (duration: 825.342699ms)","trace[338703111] 'count revisions from in-memory index tree' (duration: 187.585895ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:24:04.179285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:24:03.165923Z","time spent":"1.013348555s","remote":"127.0.0.1:60910","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/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-04-08T01:24:04.179523Z","caller":"traceutil/trace.go:171","msg":"trace[358107426] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"185.162552ms","start":"2026-04-08T01:24:03.994334Z","end":"2026-04-08T01:24:04.179497Z","steps":["trace[358107426] 'process raft request' (duration: 185.102351ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:04.179671Z","caller":"traceutil/trace.go:171","msg":"trace[238258079] linearizableReadLoop","detail":"{readStateIndex:1896; appliedIndex:1893; }","duration":"188.384286ms","start":"2026-04-08T01:24:03.99126Z","end":"2026-04-08T01:24:04.179644Z","steps":["trace[238258079] 'read index received' (duration: 82.062133ms)","trace[238258079] 'applied index is now lower than readState.Index' (duration: 106.318503ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:24:04.179669Z","caller":"traceutil/trace.go:171","msg":"trace[2070362117] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"294.380209ms","start":"2026-04-08T01:24:03.885231Z","end":"2026-04-08T01:24:04.179611Z","steps":["trace[2070362117] 'process raft request' (duration: 294.150274ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:04.179696Z","caller":"traceutil/trace.go:171","msg":"trace[643393174] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"732.199758ms","start":"2026-04-08T01:24:03.447358Z","end":"2026-04-08T01:24:04.179558Z","steps":["trace[643393174] 'process raft request' (duration: 731.839599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:04.179844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.373691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T01:24:04.179884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.085869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:24:04.179908Z","caller":"traceutil/trace.go:171","msg":"trace[1852010465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1826; }","duration":"877.482314ms","start":"2026-04-08T01:24:03.302412Z","end":"2026-04-08T01:24:04.179894Z","steps":["trace[1852010465] 'agreement among raft nodes before linearized reading' (duration: 877.382721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:04.179956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:24:03.302383Z","time spent":"877.562356ms","remote":"127.0.0.1:52896","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-08T01:24:04.179978Z","caller":"traceutil/trace.go:171","msg":"trace[1917710049] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1826; }","duration":"594.202402ms","start":"2026-04-08T01:24:03.585762Z","end":"2026-04-08T01:24:04.179964Z","steps":["trace[1917710049] 'agreement among raft nodes before linearized reading' (duration: 594.003296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:04.179985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.38824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-08T01:24:04.180025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:24:03.585751Z","time spent":"594.260984ms","remote":"127.0.0.1:32778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-04-08T01:24:04.180043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.493152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c3423718-2c4e-4870-b677-3f3a64467c00\" ","response":"range_response_count:1 size:3747"} {"level":"warn","ts":"2026-04-08T01:24:04.180044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.441862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T01:24:04.180425Z","caller":"traceutil/trace.go:171","msg":"trace[961518551] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c3423718-2c4e-4870-b677-3f3a64467c00; range_end:; response_count:1; response_revision:1826; }","duration":"611.920983ms","start":"2026-04-08T01:24:03.568477Z","end":"2026-04-08T01:24:04.180398Z","steps":["trace[961518551] 'agreement among raft nodes before linearized reading' (duration: 611.41605ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:04.18055Z","caller":"traceutil/trace.go:171","msg":"trace[746224701] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1826; }","duration":"943.006847ms","start":"2026-04-08T01:24:03.23752Z","end":"2026-04-08T01:24:04.180527Z","steps":["trace[746224701] 'agreement among raft nodes before linearized reading' (duration: 942.420112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:04.180626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:24:03.237489Z","time spent":"943.114451ms","remote":"127.0.0.1:53196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":23,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-08T01:24:04.180689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:24:03.568452Z","time spent":"612.085146ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c3423718-2c4e-4870-b677-3f3a64467c00\" "} {"level":"info","ts":"2026-04-08T01:24:04.180052Z","caller":"traceutil/trace.go:171","msg":"trace[18635218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1826; }","duration":"703.503383ms","start":"2026-04-08T01:24:03.476532Z","end":"2026-04-08T01:24:04.180035Z","steps":["trace[18635218] 'agreement among raft nodes before linearized reading' (duration: 703.228885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:04.180868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:24:03.476507Z","time spent":"704.323944ms","remote":"127.0.0.1:53120","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-08T01:24:04.180138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.263823ms","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-08T01:24:04.18101Z","caller":"traceutil/trace.go:171","msg":"trace[876658999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1826; }","duration":"139.120086ms","start":"2026-04-08T01:24:04.041844Z","end":"2026-04-08T01:24:04.180964Z","steps":["trace[876658999] 'agreement among raft nodes before linearized reading' (duration: 138.238143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:04.180181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:24:03.447323Z","time spent":"732.64323ms","remote":"127.0.0.1:53120","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-08T01:24:04.180242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.57116ms","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-08T01:24:04.181238Z","caller":"traceutil/trace.go:171","msg":"trace[1132618965] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1826; }","duration":"179.642888ms","start":"2026-04-08T01:24:04.001584Z","end":"2026-04-08T01:24:04.181227Z","steps":["trace[1132618965] 'agreement among raft nodes before linearized reading' (duration: 178.420667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:04.467771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.44758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:24:04.467869Z","caller":"traceutil/trace.go:171","msg":"trace[657449202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1828; }","duration":"165.618454ms","start":"2026-04-08T01:24:04.30223Z","end":"2026-04-08T01:24:04.467848Z","steps":["trace[657449202] 'range keys from in-memory index tree' (duration: 165.358739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:04.467885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.633516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-08T01:24:04.467954Z","caller":"traceutil/trace.go:171","msg":"trace[1253754934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1828; }","duration":"157.727189ms","start":"2026-04-08T01:24:04.310208Z","end":"2026-04-08T01:24:04.467935Z","steps":["trace[1253754934] 'range keys from in-memory index tree' (duration: 157.467953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:04.467972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.761296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-08T01:24:04.468059Z","caller":"traceutil/trace.go:171","msg":"trace[556332710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1828; }","duration":"163.92054ms","start":"2026-04-08T01:24:04.30412Z","end":"2026-04-08T01:24:04.468041Z","steps":["trace[556332710] 'range keys from in-memory index tree' (duration: 163.591391ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:09.417916Z","caller":"traceutil/trace.go:171","msg":"trace[1838320620] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1949; }","duration":"115.647285ms","start":"2026-04-08T01:24:09.302248Z","end":"2026-04-08T01:24:09.417895Z","steps":["trace[1838320620] 'read index received' (duration: 115.465361ms)","trace[1838320620] 'applied index is now lower than readState.Index' (duration: 181.054µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:24:09.418021Z","caller":"traceutil/trace.go:171","msg":"trace[2119941267] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"196.22143ms","start":"2026-04-08T01:24:09.221778Z","end":"2026-04-08T01:24:09.417999Z","steps":["trace[2119941267] 'process raft request' (duration: 195.987363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:09.418038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.7814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:24:09.418081Z","caller":"traceutil/trace.go:171","msg":"trace[445059689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1877; }","duration":"115.879132ms","start":"2026-04-08T01:24:09.30219Z","end":"2026-04-08T01:24:09.41807Z","steps":["trace[445059689] 'agreement among raft nodes before linearized reading' (duration: 115.801661ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:16.229974Z","caller":"traceutil/trace.go:171","msg":"trace[1420649337] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"197.828471ms","start":"2026-04-08T01:24:16.032115Z","end":"2026-04-08T01:24:16.229944Z","steps":["trace[1420649337] 'process raft request' (duration: 197.658746ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:16.271896Z","caller":"traceutil/trace.go:171","msg":"trace[818086557] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"238.694382ms","start":"2026-04-08T01:24:16.033178Z","end":"2026-04-08T01:24:16.271873Z","steps":["trace[818086557] 'process raft request' (duration: 238.502677ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:20.445392Z","caller":"traceutil/trace.go:171","msg":"trace[112234430] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"739.691493ms","start":"2026-04-08T01:24:19.70567Z","end":"2026-04-08T01:24:20.445361Z","steps":["trace[112234430] 'process raft request' (duration: 739.475417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:20.44557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:24:19.705646Z","time spent":"739.832207ms","remote":"127.0.0.1:53120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:24:20.50483Z","caller":"traceutil/trace.go:171","msg":"trace[979307193] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"220.996583ms","start":"2026-04-08T01:24:20.283812Z","end":"2026-04-08T01:24:20.504809Z","steps":["trace[979307193] 'process raft request' (duration: 220.904621ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:20.504852Z","caller":"traceutil/trace.go:171","msg":"trace[1456762237] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"264.871944ms","start":"2026-04-08T01:24:20.239948Z","end":"2026-04-08T01:24:20.50482Z","steps":["trace[1456762237] 'process raft request' (duration: 264.664138ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:20.505044Z","caller":"traceutil/trace.go:171","msg":"trace[1561734051] linearizableReadLoop","detail":"{readStateIndex:2034; appliedIndex:2029; }","duration":"203.128808ms","start":"2026-04-08T01:24:20.301897Z","end":"2026-04-08T01:24:20.505026Z","steps":["trace[1561734051] 'read index received' (duration: 143.239412ms)","trace[1561734051] 'applied index is now lower than readState.Index' (duration: 59.888766ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:24:20.505105Z","caller":"traceutil/trace.go:171","msg":"trace[738768343] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"217.967504ms","start":"2026-04-08T01:24:20.287123Z","end":"2026-04-08T01:24:20.50509Z","steps":["trace[738768343] 'process raft request' (duration: 217.82697ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:20.505119Z","caller":"traceutil/trace.go:171","msg":"trace[655460561] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"218.14622ms","start":"2026-04-08T01:24:20.28693Z","end":"2026-04-08T01:24:20.505077Z","steps":["trace[655460561] 'process raft request' (duration: 217.843142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:20.505187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.063117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:24:20.505238Z","caller":"traceutil/trace.go:171","msg":"trace[282810350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1959; }","duration":"203.149459ms","start":"2026-04-08T01:24:20.302079Z","end":"2026-04-08T01:24:20.505228Z","steps":["trace[282810350] 'agreement among raft nodes before linearized reading' (duration: 203.054727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:20.505274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.369925ms","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-08T01:24:20.505319Z","caller":"traceutil/trace.go:171","msg":"trace[1237737676] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1959; }","duration":"203.422806ms","start":"2026-04-08T01:24:20.301882Z","end":"2026-04-08T01:24:20.505305Z","steps":["trace[1237737676] 'agreement among raft nodes before linearized reading' (duration: 203.240962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:20.505323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.250442ms","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-08T01:24:20.505359Z","caller":"traceutil/trace.go:171","msg":"trace[675344827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1959; }","duration":"193.310213ms","start":"2026-04-08T01:24:20.312039Z","end":"2026-04-08T01:24:20.505349Z","steps":["trace[675344827] 'agreement among raft nodes before linearized reading' (duration: 193.207641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:20.505382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.8632ms","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-08T01:24:20.505436Z","caller":"traceutil/trace.go:171","msg":"trace[1688467367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1959; }","duration":"175.939233ms","start":"2026-04-08T01:24:20.329483Z","end":"2026-04-08T01:24:20.505422Z","steps":["trace[1688467367] 'agreement among raft nodes before linearized reading' (duration: 175.760448ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:36.738395Z","caller":"traceutil/trace.go:171","msg":"trace[1205571778] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"184.033402ms","start":"2026-04-08T01:24:36.554337Z","end":"2026-04-08T01:24:36.738371Z","steps":["trace[1205571778] 'process raft request' (duration: 183.919849ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:36.836564Z","caller":"traceutil/trace.go:171","msg":"trace[826800030] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"276.589288ms","start":"2026-04-08T01:24:36.559951Z","end":"2026-04-08T01:24:36.83654Z","steps":["trace[826800030] 'process raft request' (duration: 276.504675ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:36.836614Z","caller":"traceutil/trace.go:171","msg":"trace[1087137788] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"279.319729ms","start":"2026-04-08T01:24:36.557265Z","end":"2026-04-08T01:24:36.836585Z","steps":["trace[1087137788] 'process raft request' (duration: 274.144033ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:36.836788Z","caller":"traceutil/trace.go:171","msg":"trace[2072731673] linearizableReadLoop","detail":"{readStateIndex:2129; appliedIndex:2125; }","duration":"266.406804ms","start":"2026-04-08T01:24:36.570352Z","end":"2026-04-08T01:24:36.836759Z","steps":["trace[2072731673] 'read index received' (duration: 167.921504ms)","trace[2072731673] 'applied index is now lower than readState.Index' (duration: 98.48363ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:24:36.837048Z","caller":"traceutil/trace.go:171","msg":"trace[1663730404] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"275.90125ms","start":"2026-04-08T01:24:36.561132Z","end":"2026-04-08T01:24:36.837033Z","steps":["trace[1663730404] 'process raft request' (duration: 275.375967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:36.837339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.556625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-08T01:24:36.837331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.954138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-08T01:24:36.837358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.998389ms","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-08T01:24:36.837413Z","caller":"traceutil/trace.go:171","msg":"trace[1670813894] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2051; }","duration":"105.086041ms","start":"2026-04-08T01:24:36.732312Z","end":"2026-04-08T01:24:36.837398Z","steps":["trace[1670813894] 'agreement among raft nodes before linearized reading' (duration: 104.923067ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:36.837391Z","caller":"traceutil/trace.go:171","msg":"trace[1021436286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2051; }","duration":"157.73519ms","start":"2026-04-08T01:24:36.679644Z","end":"2026-04-08T01:24:36.837379Z","steps":["trace[1021436286] 'agreement among raft nodes before linearized reading' (duration: 157.568276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:36.837413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.286523ms","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-08T01:24:36.837536Z","caller":"traceutil/trace.go:171","msg":"trace[1652423626] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2051; }","duration":"223.448427ms","start":"2026-04-08T01:24:36.614069Z","end":"2026-04-08T01:24:36.837518Z","steps":["trace[1652423626] 'agreement among raft nodes before linearized reading' (duration: 223.198651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:36.837607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.322301ms","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-08T01:24:36.837406Z","caller":"traceutil/trace.go:171","msg":"trace[364627613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2051; }","duration":"267.072872ms","start":"2026-04-08T01:24:36.570315Z","end":"2026-04-08T01:24:36.837388Z","steps":["trace[364627613] 'agreement among raft nodes before linearized reading' (duration: 266.688911ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:36.837733Z","caller":"traceutil/trace.go:171","msg":"trace[491798356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2051; }","duration":"266.517665ms","start":"2026-04-08T01:24:36.571184Z","end":"2026-04-08T01:24:36.837702Z","steps":["trace[491798356] 'agreement among raft nodes before linearized reading' (duration: 266.032613ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:52.986889Z","caller":"traceutil/trace.go:171","msg":"trace[1977981408] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"287.138979ms","start":"2026-04-08T01:24:52.699711Z","end":"2026-04-08T01:24:52.98685Z","steps":["trace[1977981408] 'process raft request' (duration: 286.923864ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:53.107581Z","caller":"traceutil/trace.go:171","msg":"trace[291617186] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"320.388873ms","start":"2026-04-08T01:24:52.787156Z","end":"2026-04-08T01:24:53.107545Z","steps":["trace[291617186] 'process raft request' (duration: 320.007302ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:53.10758Z","caller":"traceutil/trace.go:171","msg":"trace[1999946055] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"217.277568ms","start":"2026-04-08T01:24:52.890269Z","end":"2026-04-08T01:24:53.107546Z","steps":["trace[1999946055] 'process raft request' (duration: 217.148885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:53.107755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:24:52.787114Z","time spent":"320.547197ms","remote":"127.0.0.1:53120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:24:53.107905Z","caller":"traceutil/trace.go:171","msg":"trace[1818905021] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"217.491942ms","start":"2026-04-08T01:24:52.890384Z","end":"2026-04-08T01:24:53.107876Z","steps":["trace[1818905021] 'process raft request' (duration: 217.114793ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:53.107923Z","caller":"traceutil/trace.go:171","msg":"trace[806482891] linearizableReadLoop","detail":"{readStateIndex:2218; appliedIndex:2213; }","duration":"166.071038ms","start":"2026-04-08T01:24:52.941814Z","end":"2026-04-08T01:24:53.107885Z","steps":["trace[806482891] 'read index received' (duration: 44.924445ms)","trace[806482891] 'applied index is now lower than readState.Index' (duration: 121.144973ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:24:53.108061Z","caller":"traceutil/trace.go:171","msg":"trace[1888403949] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"214.870735ms","start":"2026-04-08T01:24:52.893118Z","end":"2026-04-08T01:24:53.107989Z","steps":["trace[1888403949] 'process raft request' (duration: 214.640469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:53.108226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.391187ms","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-08T01:24:53.108281Z","caller":"traceutil/trace.go:171","msg":"trace[122256371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2137; }","duration":"166.52792ms","start":"2026-04-08T01:24:52.941736Z","end":"2026-04-08T01:24:53.108264Z","steps":["trace[122256371] 'agreement among raft nodes before linearized reading' (duration: 166.309754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:53.153135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.653687ms","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":"warn","ts":"2026-04-08T01:24:53.153161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.933406ms","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-08T01:24:53.153187Z","caller":"traceutil/trace.go:171","msg":"trace[567312177] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"135.050934ms","start":"2026-04-08T01:24:53.018105Z","end":"2026-04-08T01:24:53.153156Z","steps":["trace[567312177] 'process raft request' (duration: 134.678734ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:53.153224Z","caller":"traceutil/trace.go:171","msg":"trace[977727557] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2138; }","duration":"200.780479ms","start":"2026-04-08T01:24:52.952421Z","end":"2026-04-08T01:24:53.153202Z","steps":["trace[977727557] 'agreement among raft nodes before linearized reading' (duration: 200.517073ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:53.153234Z","caller":"traceutil/trace.go:171","msg":"trace[1733220737] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2138; }","duration":"178.024849ms","start":"2026-04-08T01:24:52.975193Z","end":"2026-04-08T01:24:53.153218Z","steps":["trace[1733220737] 'agreement among raft nodes before linearized reading' (duration: 177.794143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:24:53.153226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.368419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-08T01:24:53.153258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.005594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T01:24:53.1533Z","caller":"traceutil/trace.go:171","msg":"trace[375753807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2138; }","duration":"185.469392ms","start":"2026-04-08T01:24:52.967813Z","end":"2026-04-08T01:24:53.153282Z","steps":["trace[375753807] 'agreement among raft nodes before linearized reading' (duration: 185.286198ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:53.153352Z","caller":"traceutil/trace.go:171","msg":"trace[1669542539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2138; }","duration":"184.187108ms","start":"2026-04-08T01:24:52.969143Z","end":"2026-04-08T01:24:53.15333Z","steps":["trace[1669542539] 'agreement among raft nodes before linearized reading' (duration: 183.890221ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:53.31432Z","caller":"traceutil/trace.go:171","msg":"trace[1879674189] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"114.951352ms","start":"2026-04-08T01:24:53.199334Z","end":"2026-04-08T01:24:53.314286Z","steps":["trace[1879674189] 'process raft request' (duration: 77.764057ms)","trace[1879674189] 'compare' (duration: 37.005271ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:24:53.416725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.466278ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-08T01:24:53.416881Z","caller":"traceutil/trace.go:171","msg":"trace[1414571828] linearizableReadLoop","detail":"{readStateIndex:2225; appliedIndex:2223; }","duration":"114.190704ms","start":"2026-04-08T01:24:53.302662Z","end":"2026-04-08T01:24:53.416853Z","steps":["trace[1414571828] 'read index received' (duration: 501.913µs)","trace[1414571828] 'applied index is now lower than readState.Index' (duration: 113.686761ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:24:53.417122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.398588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:24:53.417188Z","caller":"traceutil/trace.go:171","msg":"trace[1292746810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2143; }","duration":"114.548092ms","start":"2026-04-08T01:24:53.302618Z","end":"2026-04-08T01:24:53.417166Z","steps":["trace[1292746810] 'agreement among raft nodes before linearized reading' (duration: 114.312077ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:59.251531Z","caller":"traceutil/trace.go:171","msg":"trace[574893675] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"119.154402ms","start":"2026-04-08T01:24:59.132344Z","end":"2026-04-08T01:24:59.251499Z","steps":["trace[574893675] 'process raft request' (duration: 46.508897ms)","trace[574893675] 'compare' (duration: 72.455529ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:24:59.251913Z","caller":"traceutil/trace.go:171","msg":"trace[240740906] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"118.473173ms","start":"2026-04-08T01:24:59.133391Z","end":"2026-04-08T01:24:59.251864Z","steps":["trace[240740906] 'process raft request' (duration: 118.053513ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:24:59.252379Z","caller":"traceutil/trace.go:171","msg":"trace[1745192127] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"117.713074ms","start":"2026-04-08T01:24:59.134642Z","end":"2026-04-08T01:24:59.252356Z","steps":["trace[1745192127] 'process raft request' (duration: 117.107408ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:29:57.958679Z","caller":"traceutil/trace.go:171","msg":"trace[578162114] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"205.615928ms","start":"2026-04-08T01:29:57.753037Z","end":"2026-04-08T01:29:57.958652Z","steps":["trace[578162114] 'process raft request' (duration: 205.353711ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:29:58.31267Z","caller":"traceutil/trace.go:171","msg":"trace[1124394470] linearizableReadLoop","detail":"{readStateIndex:5056; appliedIndex:5053; }","duration":"345.650648ms","start":"2026-04-08T01:29:57.967Z","end":"2026-04-08T01:29:58.31265Z","steps":["trace[1124394470] 'read index received' (duration: 260.472541ms)","trace[1124394470] 'applied index is now lower than readState.Index' (duration: 85.177157ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:29:58.312723Z","caller":"traceutil/trace.go:171","msg":"trace[1511644306] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"345.549466ms","start":"2026-04-08T01:29:57.967159Z","end":"2026-04-08T01:29:58.312709Z","steps":["trace[1511644306] 'process raft request' (duration: 345.436903ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:29:58.31272Z","caller":"traceutil/trace.go:171","msg":"trace[984283669] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"350.560224ms","start":"2026-04-08T01:29:57.962109Z","end":"2026-04-08T01:29:58.312669Z","steps":["trace[984283669] 'process raft request' (duration: 350.442051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:29:58.31281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:29:57.967137Z","time spent":"345.613359ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3706,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:29:58.312903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:29:57.9621Z","time spent":"350.719909ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T01:29:58.312988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.949206ms","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-08T01:29:58.313077Z","caller":"traceutil/trace.go:171","msg":"trace[363636739] 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:4870; }","duration":"346.063609ms","start":"2026-04-08T01:29:57.966991Z","end":"2026-04-08T01:29:58.313054Z","steps":["trace[363636739] 'agreement among raft nodes before linearized reading' (duration: 345.783142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:29:58.313132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:29:57.966966Z","time spent":"346.155112ms","remote":"127.0.0.1:53028","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":"info","ts":"2026-04-08T01:29:58.313214Z","caller":"traceutil/trace.go:171","msg":"trace[1949768693] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"352.388211ms","start":"2026-04-08T01:29:57.96079Z","end":"2026-04-08T01:29:58.313178Z","steps":["trace[1949768693] 'process raft request' (duration: 351.615981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:29:58.313363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:29:57.960773Z","time spent":"352.507075ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:29:58.327979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.492032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:29:58.328138Z","caller":"traceutil/trace.go:171","msg":"trace[1227576366] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:4872; }","duration":"278.754069ms","start":"2026-04-08T01:29:58.049361Z","end":"2026-04-08T01:29:58.328115Z","steps":["trace[1227576366] 'agreement among raft nodes before linearized reading' (duration: 278.445901ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:06.613786Z","caller":"traceutil/trace.go:171","msg":"trace[1675448238] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"186.780764ms","start":"2026-04-08T01:30:06.426978Z","end":"2026-04-08T01:30:06.613759Z","steps":["trace[1675448238] 'process raft request' (duration: 110.721458ms)","trace[1675448238] 'compare' (duration: 75.80962ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:30:06.613842Z","caller":"traceutil/trace.go:171","msg":"trace[1930425323] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"157.123053ms","start":"2026-04-08T01:30:06.456692Z","end":"2026-04-08T01:30:06.613815Z","steps":["trace[1930425323] 'process raft request' (duration: 157.06842ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:06.61398Z","caller":"traceutil/trace.go:171","msg":"trace[1914245418] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"184.505327ms","start":"2026-04-08T01:30:06.429445Z","end":"2026-04-08T01:30:06.61395Z","steps":["trace[1914245418] 'process raft request' (duration: 184.220059ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:06.614097Z","caller":"traceutil/trace.go:171","msg":"trace[1181929366] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"161.858364ms","start":"2026-04-08T01:30:06.452183Z","end":"2026-04-08T01:30:06.614042Z","steps":["trace[1181929366] 'process raft request' (duration: 161.537425ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:06.619318Z","caller":"traceutil/trace.go:171","msg":"trace[1515600075] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"102.353063ms","start":"2026-04-08T01:30:06.516947Z","end":"2026-04-08T01:30:06.6193Z","steps":["trace[1515600075] 'process raft request' (duration: 102.21416ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:09.281548Z","caller":"traceutil/trace.go:171","msg":"trace[230891175] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"164.04967ms","start":"2026-04-08T01:30:09.117471Z","end":"2026-04-08T01:30:09.281521Z","steps":["trace[230891175] 'process raft request' (duration: 163.685132ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:17.015181Z","caller":"traceutil/trace.go:171","msg":"trace[1346593496] linearizableReadLoop","detail":"{readStateIndex:5445; appliedIndex:5444; }","duration":"223.950852ms","start":"2026-04-08T01:30:16.791201Z","end":"2026-04-08T01:30:17.015152Z","steps":["trace[1346593496] 'read index received' (duration: 223.682484ms)","trace[1346593496] 'applied index is now lower than readState.Index' (duration: 267.508µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:30:17.015572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.344471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-gateway-cf54cb88c-m5zsh\" ","response":"range_response_count:1 size:4760"} {"level":"info","ts":"2026-04-08T01:30:17.015615Z","caller":"traceutil/trace.go:171","msg":"trace[375644877] range","detail":"{range_begin:/registry/pods/monitoring/loki-gateway-cf54cb88c-m5zsh; range_end:; response_count:1; response_revision:5251; }","duration":"224.466655ms","start":"2026-04-08T01:30:16.791138Z","end":"2026-04-08T01:30:17.015605Z","steps":["trace[375644877] 'agreement among raft nodes before linearized reading' (duration: 224.223499ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:17.015324Z","caller":"traceutil/trace.go:171","msg":"trace[1729670722] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"307.039989ms","start":"2026-04-08T01:30:16.708272Z","end":"2026-04-08T01:30:17.015312Z","steps":["trace[1729670722] 'process raft request' (duration: 306.70967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:30:17.01592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:30:16.708238Z","time spent":"307.580774ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":707,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T01:30:17.444334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.220667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:30:17.444519Z","caller":"traceutil/trace.go:171","msg":"trace[990272018] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"309.582415ms","start":"2026-04-08T01:30:17.134917Z","end":"2026-04-08T01:30:17.444499Z","steps":["trace[990272018] 'process raft request' (duration: 108.100861ms)","trace[990272018] 'compare' (duration: 201.075703ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:30:17.444601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:30:17.134898Z","time spent":"309.664906ms","remote":"127.0.0.1:53120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:30:17.444922Z","caller":"traceutil/trace.go:171","msg":"trace[1562893913] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"309.525633ms","start":"2026-04-08T01:30:17.135355Z","end":"2026-04-08T01:30:17.44488Z","steps":["trace[1562893913] 'process raft request' (duration: 309.096223ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:17.445085Z","caller":"traceutil/trace.go:171","msg":"trace[1182634311] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"306.983129ms","start":"2026-04-08T01:30:17.138078Z","end":"2026-04-08T01:30:17.445061Z","steps":["trace[1182634311] 'process raft request' (duration: 306.737472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:30:17.445145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:30:17.135337Z","time spent":"309.682709ms","remote":"127.0.0.1:53120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:30:17.445274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:30:17.13807Z","time spent":"307.092221ms","remote":"127.0.0.1:53316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4665,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:30:17.445844Z","caller":"traceutil/trace.go:171","msg":"trace[1710169469] linearizableReadLoop","detail":"{readStateIndex:5456; appliedIndex:5452; }","duration":"306.964838ms","start":"2026-04-08T01:30:17.138863Z","end":"2026-04-08T01:30:17.445827Z","steps":["trace[1710169469] 'read index received' (duration: 104.342224ms)","trace[1710169469] 'applied index is now lower than readState.Index' (duration: 202.621424ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:30:17.445933Z","caller":"traceutil/trace.go:171","msg":"trace[1161814877] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"307.701567ms","start":"2026-04-08T01:30:17.138222Z","end":"2026-04-08T01:30:17.445924Z","steps":["trace[1161814877] 'process raft request' (duration: 306.699741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:30:17.445996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:30:17.138205Z","time spent":"307.753868ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4753,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:30:17.446312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.44957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-08T01:30:17.446334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.561034ms","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-08T01:30:17.446407Z","caller":"traceutil/trace.go:171","msg":"trace[1099456299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5262; }","duration":"300.644356ms","start":"2026-04-08T01:30:17.145747Z","end":"2026-04-08T01:30:17.446391Z","steps":["trace[1099456299] 'agreement among raft nodes before linearized reading' (duration: 300.481281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:30:17.446449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:30:17.14574Z","time spent":"300.701137ms","remote":"127.0.0.1:53120","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-08T01:30:17.446501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.98945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:30:17.446537Z","caller":"traceutil/trace.go:171","msg":"trace[1375929852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5262; }","duration":"145.047592ms","start":"2026-04-08T01:30:17.301482Z","end":"2026-04-08T01:30:17.446529Z","steps":["trace[1375929852] 'agreement among raft nodes before linearized reading' (duration: 144.986991ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:17.446356Z","caller":"traceutil/trace.go:171","msg":"trace[1144046707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5262; }","duration":"307.520703ms","start":"2026-04-08T01:30:17.138824Z","end":"2026-04-08T01:30:17.446345Z","steps":["trace[1144046707] 'agreement among raft nodes before linearized reading' (duration: 307.350448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:30:17.446627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:30:17.138807Z","time spent":"307.784379ms","remote":"127.0.0.1:53120","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-08T01:30:19.539621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.751256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:30:19.539726Z","caller":"traceutil/trace.go:171","msg":"trace[459669245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5294; }","duration":"237.914491ms","start":"2026-04-08T01:30:19.301789Z","end":"2026-04-08T01:30:19.539703Z","steps":["trace[459669245] 'range keys from in-memory index tree' (duration: 237.646843ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:29.701353Z","caller":"traceutil/trace.go:171","msg":"trace[1961926154] linearizableReadLoop","detail":"{readStateIndex:5643; appliedIndex:5642; }","duration":"107.975218ms","start":"2026-04-08T01:30:29.593348Z","end":"2026-04-08T01:30:29.701324Z","steps":["trace[1961926154] 'read index received' (duration: 107.791784ms)","trace[1961926154] 'applied index is now lower than readState.Index' (duration: 182.264µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:30:29.701444Z","caller":"traceutil/trace.go:171","msg":"trace[1856913534] transaction","detail":"{read_only:false; response_revision:5442; number_of_response:1; }","duration":"108.147652ms","start":"2026-04-08T01:30:29.593254Z","end":"2026-04-08T01:30:29.701402Z","steps":["trace[1856913534] 'process raft request' (duration: 107.878025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:30:29.701793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.406729ms","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-08T01:30:29.702035Z","caller":"traceutil/trace.go:171","msg":"trace[869573301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5442; }","duration":"108.730847ms","start":"2026-04-08T01:30:29.593279Z","end":"2026-04-08T01:30:29.70201Z","steps":["trace[869573301] 'agreement among raft nodes before linearized reading' (duration: 108.208444ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:29.837611Z","caller":"traceutil/trace.go:171","msg":"trace[1592889846] transaction","detail":"{read_only:false; response_revision:5443; number_of_response:1; }","duration":"130.949429ms","start":"2026-04-08T01:30:29.706627Z","end":"2026-04-08T01:30:29.837577Z","steps":["trace[1592889846] 'process raft request' (duration: 125.15086ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:29.853152Z","caller":"traceutil/trace.go:171","msg":"trace[449731459] transaction","detail":"{read_only:false; response_revision:5444; number_of_response:1; }","duration":"146.179241ms","start":"2026-04-08T01:30:29.706955Z","end":"2026-04-08T01:30:29.853134Z","steps":["trace[449731459] 'process raft request' (duration: 146.042608ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:29.853434Z","caller":"traceutil/trace.go:171","msg":"trace[1741126538] transaction","detail":"{read_only:false; response_revision:5445; number_of_response:1; }","duration":"146.461019ms","start":"2026-04-08T01:30:29.706955Z","end":"2026-04-08T01:30:29.853416Z","steps":["trace[1741126538] 'process raft request' (duration: 146.14958ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:29.853587Z","caller":"traceutil/trace.go:171","msg":"trace[1175073126] transaction","detail":"{read_only:false; response_revision:5446; number_of_response:1; }","duration":"142.990398ms","start":"2026-04-08T01:30:29.710573Z","end":"2026-04-08T01:30:29.853563Z","steps":["trace[1175073126] 'process raft request' (duration: 142.776692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:30:30.041697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.246336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19665"} {"level":"info","ts":"2026-04-08T01:30:30.041787Z","caller":"traceutil/trace.go:171","msg":"trace[2022573709] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5446; }","duration":"103.37981ms","start":"2026-04-08T01:30:29.938387Z","end":"2026-04-08T01:30:30.041767Z","steps":["trace[2022573709] 'range keys from in-memory index tree' (duration: 103.140573ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:31.199948Z","caller":"traceutil/trace.go:171","msg":"trace[2002491205] linearizableReadLoop","detail":"{readStateIndex:5653; appliedIndex:5652; }","duration":"297.547936ms","start":"2026-04-08T01:30:30.902372Z","end":"2026-04-08T01:30:31.19992Z","steps":["trace[2002491205] 'read index received' (duration: 297.32424ms)","trace[2002491205] 'applied index is now lower than readState.Index' (duration: 222.035µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:30:31.200074Z","caller":"traceutil/trace.go:171","msg":"trace[1031942885] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"298.424448ms","start":"2026-04-08T01:30:30.901611Z","end":"2026-04-08T01:30:31.200036Z","steps":["trace[1031942885] 'process raft request' (duration: 298.149622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:30:31.200263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.881894ms","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-08T01:30:31.20035Z","caller":"traceutil/trace.go:171","msg":"trace[2135830696] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5452; }","duration":"298.031798ms","start":"2026-04-08T01:30:30.9023Z","end":"2026-04-08T01:30:31.200332Z","steps":["trace[2135830696] 'agreement among raft nodes before linearized reading' (duration: 297.764561ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:31.996006Z","caller":"traceutil/trace.go:171","msg":"trace[1450681287] transaction","detail":"{read_only:false; response_revision:5459; number_of_response:1; }","duration":"121.065785ms","start":"2026-04-08T01:30:31.8749Z","end":"2026-04-08T01:30:31.995966Z","steps":["trace[1450681287] 'process raft request' (duration: 118.650042ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:30:58.251529Z","caller":"traceutil/trace.go:171","msg":"trace[979712990] transaction","detail":"{read_only:false; response_revision:5719; number_of_response:1; }","duration":"128.466056ms","start":"2026-04-08T01:30:58.122985Z","end":"2026-04-08T01:30:58.251451Z","steps":["trace[979712990] 'process raft request' (duration: 40.298577ms)","trace[979712990] 'compare' (duration: 87.06564ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:31:54.203389Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2995} {"level":"info","ts":"2026-04-08T01:31:54.271612Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2995,"took":"66.745918ms","hash":694884187,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":34377728,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-08T01:31:54.271667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":694884187,"revision":2995,"compact-revision":-1} {"level":"info","ts":"2026-04-08T01:36:09.35788Z","caller":"traceutil/trace.go:171","msg":"trace[1647784452] transaction","detail":"{read_only:false; response_revision:8036; number_of_response:1; }","duration":"353.321087ms","start":"2026-04-08T01:36:09.004524Z","end":"2026-04-08T01:36:09.357845Z","steps":["trace[1647784452] 'process raft request' (duration: 353.043279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:36:09.358064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:36:09.00451Z","time spent":"353.46616ms","remote":"127.0.0.1:53120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:36:19.908704Z","caller":"traceutil/trace.go:171","msg":"trace[1214814212] transaction","detail":"{read_only:false; response_revision:8090; number_of_response:1; }","duration":"130.279056ms","start":"2026-04-08T01:36:19.778383Z","end":"2026-04-08T01:36:19.908662Z","steps":["trace[1214814212] 'process raft request' (duration: 108.044231ms)","trace[1214814212] 'compare' (duration: 21.977158ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:36:21.939403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.720707ms","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-08T01:36:21.939514Z","caller":"traceutil/trace.go:171","msg":"trace[1343783310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8096; }","duration":"226.876881ms","start":"2026-04-08T01:36:21.712613Z","end":"2026-04-08T01:36:21.93949Z","steps":["trace[1343783310] 'range keys from in-memory index tree' (duration: 226.514641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:36:21.939533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.880715ms","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-08T01:36:21.939646Z","caller":"traceutil/trace.go:171","msg":"trace[221626647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8096; }","duration":"449.059059ms","start":"2026-04-08T01:36:21.49057Z","end":"2026-04-08T01:36:21.939629Z","steps":["trace[221626647] 'range keys from in-memory index tree' (duration: 448.691151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:36:21.939677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:36:21.490536Z","time spent":"449.135041ms","remote":"127.0.0.1:53120","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-08T01:36:21.9398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.399524ms","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-08T01:36:21.939862Z","caller":"traceutil/trace.go:171","msg":"trace[146360078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8096; }","duration":"239.514557ms","start":"2026-04-08T01:36:21.700333Z","end":"2026-04-08T01:36:21.939848Z","steps":["trace[146360078] 'range keys from in-memory index tree' (duration: 239.22355ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:36:22.02405Z","caller":"traceutil/trace.go:171","msg":"trace[1010971394] transaction","detail":"{read_only:false; response_revision:8097; number_of_response:1; }","duration":"110.572737ms","start":"2026-04-08T01:36:21.913451Z","end":"2026-04-08T01:36:22.024024Z","steps":["trace[1010971394] 'process raft request' (duration: 110.427962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:36:23.838568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.321821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:36:23.838678Z","caller":"traceutil/trace.go:171","msg":"trace[112909515] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:8107; }","duration":"107.505308ms","start":"2026-04-08T01:36:23.731147Z","end":"2026-04-08T01:36:23.838653Z","steps":["trace[112909515] 'count revisions from in-memory index tree' (duration: 107.163678ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:36:49.83249Z","caller":"traceutil/trace.go:171","msg":"trace[294215542] linearizableReadLoop","detail":"{readStateIndex:8596; appliedIndex:8595; }","duration":"134.253418ms","start":"2026-04-08T01:36:49.698212Z","end":"2026-04-08T01:36:49.832466Z","steps":["trace[294215542] 'read index received' (duration: 134.107394ms)","trace[294215542] 'applied index is now lower than readState.Index' (duration: 145.174µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:36:49.832699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.425072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T01:36:49.832655Z","caller":"traceutil/trace.go:171","msg":"trace[348958665] transaction","detail":"{read_only:false; response_revision:8303; number_of_response:1; }","duration":"255.08555ms","start":"2026-04-08T01:36:49.577527Z","end":"2026-04-08T01:36:49.832612Z","steps":["trace[348958665] 'process raft request' (duration: 254.798992ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:36:49.832734Z","caller":"traceutil/trace.go:171","msg":"trace[1706798780] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:8303; }","duration":"134.519095ms","start":"2026-04-08T01:36:49.698205Z","end":"2026-04-08T01:36:49.832724Z","steps":["trace[1706798780] 'agreement among raft nodes before linearized reading' (duration: 134.398622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:36:50.33244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.482296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T01:36:50.332524Z","caller":"traceutil/trace.go:171","msg":"trace[1038754358] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8303; }","duration":"340.63416ms","start":"2026-04-08T01:36:49.99187Z","end":"2026-04-08T01:36:50.332504Z","steps":["trace[1038754358] 'count revisions from in-memory index tree' (duration: 340.372933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:36:50.332554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:36:49.991844Z","time spent":"340.703812ms","remote":"127.0.0.1:44664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-04-08T01:36:50.339641Z","caller":"traceutil/trace.go:171","msg":"trace[133826907] transaction","detail":"{read_only:false; response_revision:8304; number_of_response:1; }","duration":"246.279652ms","start":"2026-04-08T01:36:50.093333Z","end":"2026-04-08T01:36:50.339613Z","steps":["trace[133826907] 'process raft request' (duration: 245.936164ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:36:50.340891Z","caller":"traceutil/trace.go:171","msg":"trace[809656518] linearizableReadLoop","detail":"{readStateIndex:8598; appliedIndex:8597; }","duration":"180.412271ms","start":"2026-04-08T01:36:50.160451Z","end":"2026-04-08T01:36:50.340863Z","steps":["trace[809656518] 'read index received' (duration: 179.23011ms)","trace[809656518] 'applied index is now lower than readState.Index' (duration: 1.181351ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:36:50.34107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.592785ms","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-08T01:36:50.341117Z","caller":"traceutil/trace.go:171","msg":"trace[1434752986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8308; }","duration":"180.698498ms","start":"2026-04-08T01:36:50.160409Z","end":"2026-04-08T01:36:50.341107Z","steps":["trace[1434752986] 'agreement among raft nodes before linearized reading' (duration: 180.519463ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:36:50.341146Z","caller":"traceutil/trace.go:171","msg":"trace[237441797] transaction","detail":"{read_only:false; response_revision:8305; number_of_response:1; }","duration":"217.445088ms","start":"2026-04-08T01:36:50.123419Z","end":"2026-04-08T01:36:50.340864Z","steps":["trace[237441797] 'process raft request' (duration: 217.214811ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:36:50.341093Z","caller":"traceutil/trace.go:171","msg":"trace[1575425516] transaction","detail":"{read_only:false; response_revision:8307; number_of_response:1; }","duration":"103.269989ms","start":"2026-04-08T01:36:50.237632Z","end":"2026-04-08T01:36:50.340902Z","steps":["trace[1575425516] 'process raft request' (duration: 103.184126ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:36:50.341266Z","caller":"traceutil/trace.go:171","msg":"trace[1335105398] transaction","detail":"{read_only:false; response_revision:8306; number_of_response:1; }","duration":"147.062399ms","start":"2026-04-08T01:36:50.193849Z","end":"2026-04-08T01:36:50.340912Z","steps":["trace[1335105398] 'process raft request' (duration: 146.932756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:36:50.342488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.238105ms","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-08T01:36:50.342535Z","caller":"traceutil/trace.go:171","msg":"trace[581124059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8308; }","duration":"180.323887ms","start":"2026-04-08T01:36:50.1622Z","end":"2026-04-08T01:36:50.342524Z","steps":["trace[581124059] 'agreement among raft nodes before linearized reading' (duration: 180.149223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:36:50.342984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.125342ms","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-08T01:36:50.343072Z","caller":"traceutil/trace.go:171","msg":"trace[1236814984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8308; }","duration":"178.238824ms","start":"2026-04-08T01:36:50.164815Z","end":"2026-04-08T01:36:50.343054Z","steps":["trace[1236814984] 'agreement among raft nodes before linearized reading' (duration: 177.614968ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:36:54.281086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6131} {"level":"info","ts":"2026-04-08T01:36:54.510264Z","caller":"traceutil/trace.go:171","msg":"trace[273175339] transaction","detail":"{read_only:false; response_revision:8327; number_of_response:1; }","duration":"152.050808ms","start":"2026-04-08T01:36:54.358178Z","end":"2026-04-08T01:36:54.510229Z","steps":["trace[273175339] 'process raft request' (duration: 103.517954ms)","trace[273175339] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:538; } (duration: 48.179165ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:36:54.510621Z","caller":"traceutil/trace.go:171","msg":"trace[1939301084] transaction","detail":"{read_only:false; response_revision:8328; number_of_response:1; }","duration":"152.208202ms","start":"2026-04-08T01:36:54.358406Z","end":"2026-04-08T01:36:54.510614Z","steps":["trace[1939301084] 'process raft request' (duration: 152.007677ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:36:54.51061Z","caller":"traceutil/trace.go:171","msg":"trace[1355833062] linearizableReadLoop","detail":"{readStateIndex:8624; appliedIndex:8620; }","duration":"148.63233ms","start":"2026-04-08T01:36:54.361947Z","end":"2026-04-08T01:36:54.510579Z","steps":["trace[1355833062] 'read index received' (duration: 84.300958ms)","trace[1355833062] 'applied index is now lower than readState.Index' (duration: 64.330162ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:36:54.51077Z","caller":"traceutil/trace.go:171","msg":"trace[1735115119] transaction","detail":"{read_only:false; response_revision:8329; number_of_response:1; }","duration":"152.113419ms","start":"2026-04-08T01:36:54.358641Z","end":"2026-04-08T01:36:54.510754Z","steps":["trace[1735115119] 'process raft request' (duration: 151.884494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:36:54.510846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.511956ms","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-08T01:36:54.510873Z","caller":"traceutil/trace.go:171","msg":"trace[515531831] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8329; }","duration":"148.587308ms","start":"2026-04-08T01:36:54.36228Z","end":"2026-04-08T01:36:54.510867Z","steps":["trace[515531831] 'agreement among raft nodes before linearized reading' (duration: 148.490956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:36:54.510943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.305658ms","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-08T01:36:54.510958Z","caller":"traceutil/trace.go:171","msg":"trace[1335439905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8329; }","duration":"144.374059ms","start":"2026-04-08T01:36:54.366581Z","end":"2026-04-08T01:36:54.510955Z","steps":["trace[1335439905] 'agreement among raft nodes before linearized reading' (duration: 144.331248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:36:54.511022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.412146ms","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-08T01:36:54.511036Z","caller":"traceutil/trace.go:171","msg":"trace[80536770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8329; }","duration":"145.446246ms","start":"2026-04-08T01:36:54.365586Z","end":"2026-04-08T01:36:54.511033Z","steps":["trace[80536770] 'agreement among raft nodes before linearized reading' (duration: 145.403925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:36:54.511136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.190555ms","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-08T01:36:54.511152Z","caller":"traceutil/trace.go:171","msg":"trace[606186964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8329; }","duration":"149.230216ms","start":"2026-04-08T01:36:54.361918Z","end":"2026-04-08T01:36:54.511148Z","steps":["trace[606186964] 'agreement among raft nodes before linearized reading' (duration: 149.192874ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:36:54.619414Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6131,"took":"336.162933ms","hash":62753717,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":23146496,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-08T01:36:54.619497Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":62753717,"revision":6131,"compact-revision":2995} {"level":"info","ts":"2026-04-08T01:36:54.61977Z","caller":"traceutil/trace.go:171","msg":"trace[81177556] transaction","detail":"{read_only:false; response_revision:8332; number_of_response:1; }","duration":"104.789256ms","start":"2026-04-08T01:36:54.514954Z","end":"2026-04-08T01:36:54.619743Z","steps":["trace[81177556] 'process raft request' (duration: 104.692004ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:36:54.619811Z","caller":"traceutil/trace.go:171","msg":"trace[1707896586] transaction","detail":"{read_only:false; response_revision:8333; number_of_response:1; }","duration":"104.739486ms","start":"2026-04-08T01:36:54.51505Z","end":"2026-04-08T01:36:54.619789Z","steps":["trace[1707896586] 'process raft request' (duration: 104.640603ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:36:54.619824Z","caller":"traceutil/trace.go:171","msg":"trace[147018780] transaction","detail":"{read_only:false; response_revision:8331; number_of_response:1; }","duration":"104.953711ms","start":"2026-04-08T01:36:54.514819Z","end":"2026-04-08T01:36:54.619773Z","steps":["trace[147018780] 'process raft request' (duration: 85.461898ms)","trace[147018780] 'compare' (duration: 19.212415ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:37:02.324316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.978566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-08T01:37:02.324426Z","caller":"traceutil/trace.go:171","msg":"trace[1751181430] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8408; }","duration":"121.14806ms","start":"2026-04-08T01:37:02.203258Z","end":"2026-04-08T01:37:02.324406Z","steps":["trace[1751181430] 'range keys from in-memory index tree' (duration: 120.738959ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:37:02.537606Z","caller":"traceutil/trace.go:171","msg":"trace[134930949] transaction","detail":"{read_only:false; response_revision:8409; number_of_response:1; }","duration":"129.778392ms","start":"2026-04-08T01:37:02.407801Z","end":"2026-04-08T01:37:02.53758Z","steps":["trace[134930949] 'process raft request' (duration: 129.559536ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:37:02.792441Z","caller":"traceutil/trace.go:171","msg":"trace[1443227642] transaction","detail":"{read_only:false; response_revision:8411; number_of_response:1; }","duration":"315.077268ms","start":"2026-04-08T01:37:02.477333Z","end":"2026-04-08T01:37:02.792411Z","steps":["trace[1443227642] 'process raft request' (duration: 315.021457ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:37:02.792456Z","caller":"traceutil/trace.go:171","msg":"trace[1375058645] transaction","detail":"{read_only:false; response_revision:8410; number_of_response:1; }","duration":"376.593648ms","start":"2026-04-08T01:37:02.415825Z","end":"2026-04-08T01:37:02.792419Z","steps":["trace[1375058645] 'process raft request' (duration: 376.402734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:37:02.792591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:37:02.477299Z","time spent":"315.216562ms","remote":"127.0.0.1:53120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:37:02.792642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:37:02.415808Z","time spent":"376.735122ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4638,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:37:02.792829Z","caller":"traceutil/trace.go:171","msg":"trace[742145823] transaction","detail":"{read_only:false; response_revision:8413; number_of_response:1; }","duration":"258.784645ms","start":"2026-04-08T01:37:02.534031Z","end":"2026-04-08T01:37:02.792815Z","steps":["trace[742145823] 'process raft request' (duration: 258.57276ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:37:02.792858Z","caller":"traceutil/trace.go:171","msg":"trace[693318558] linearizableReadLoop","detail":"{readStateIndex:8710; appliedIndex:8707; }","duration":"308.240472ms","start":"2026-04-08T01:37:02.484543Z","end":"2026-04-08T01:37:02.792783Z","steps":["trace[693318558] 'read index received' (duration: 52.851975ms)","trace[693318558] 'applied index is now lower than readState.Index' (duration: 255.387237ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:37:02.792895Z","caller":"traceutil/trace.go:171","msg":"trace[423357529] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"258.6178ms","start":"2026-04-08T01:37:02.534272Z","end":"2026-04-08T01:37:02.79289Z","steps":["trace[423357529] 'process raft request' (duration: 258.451256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:37:02.793005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.460218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:37:02.793033Z","caller":"traceutil/trace.go:171","msg":"trace[265114328] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:8414; }","duration":"308.532029ms","start":"2026-04-08T01:37:02.484492Z","end":"2026-04-08T01:37:02.793024Z","steps":["trace[265114328] 'agreement among raft nodes before linearized reading' (duration: 308.468528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:37:02.793056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:37:02.484471Z","time spent":"308.580611ms","remote":"127.0.0.1:44636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-08T01:37:02.792962Z","caller":"traceutil/trace.go:171","msg":"trace[368640915] transaction","detail":"{read_only:false; response_revision:8412; number_of_response:1; }","duration":"259.258867ms","start":"2026-04-08T01:37:02.533655Z","end":"2026-04-08T01:37:02.792914Z","steps":["trace[368640915] 'process raft request' (duration: 258.727833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:37:02.793182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.685012ms","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-08T01:37:02.793231Z","caller":"traceutil/trace.go:171","msg":"trace[323034290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8414; }","duration":"143.778114ms","start":"2026-04-08T01:37:02.649441Z","end":"2026-04-08T01:37:02.793219Z","steps":["trace[323034290] 'agreement among raft nodes before linearized reading' (duration: 143.61788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:37:02.793269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.178667ms","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-08T01:37:02.793291Z","caller":"traceutil/trace.go:171","msg":"trace[1108793683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8414; }","duration":"141.217349ms","start":"2026-04-08T01:37:02.652069Z","end":"2026-04-08T01:37:02.793286Z","steps":["trace[1108793683] 'agreement among raft nodes before linearized reading' (duration: 141.131196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:37:02.793375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.134733ms","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-08T01:37:02.793419Z","caller":"traceutil/trace.go:171","msg":"trace[350615672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8414; }","duration":"144.229766ms","start":"2026-04-08T01:37:02.649178Z","end":"2026-04-08T01:37:02.793407Z","steps":["trace[350615672] 'agreement among raft nodes before linearized reading' (duration: 144.049731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:37:02.793406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.294431ms","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-08T01:37:02.793565Z","caller":"traceutil/trace.go:171","msg":"trace[520390786] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8414; }","duration":"172.526187ms","start":"2026-04-08T01:37:02.620989Z","end":"2026-04-08T01:37:02.793516Z","steps":["trace[520390786] 'agreement among raft nodes before linearized reading' (duration: 172.063935ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:37:03.660439Z","caller":"traceutil/trace.go:171","msg":"trace[850168915] transaction","detail":"{read_only:false; response_revision:8422; number_of_response:1; }","duration":"224.368375ms","start":"2026-04-08T01:37:03.436032Z","end":"2026-04-08T01:37:03.6604Z","steps":["trace[850168915] 'process raft request' (duration: 224.022627ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:37:03.95693Z","caller":"traceutil/trace.go:171","msg":"trace[1358266419] transaction","detail":"{read_only:false; response_revision:8423; number_of_response:1; }","duration":"508.898365ms","start":"2026-04-08T01:37:03.44799Z","end":"2026-04-08T01:37:03.956888Z","steps":["trace[1358266419] 'process raft request' (duration: 508.454214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:37:03.957113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:37:03.447949Z","time spent":"509.07425ms","remote":"127.0.0.1:37292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:37:55.260864Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002408.wal"} {"level":"info","ts":"2026-04-08T01:38:05.190122Z","caller":"traceutil/trace.go:171","msg":"trace[1354252640] transaction","detail":"{read_only:false; response_revision:9076; number_of_response:1; }","duration":"100.566755ms","start":"2026-04-08T01:38:05.089486Z","end":"2026-04-08T01:38:05.190053Z","steps":["trace[1354252640] 'process raft request' (duration: 79.212524ms)","trace[1354252640] 'compare' (duration: 21.186897ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:38:24.73085Z","caller":"traceutil/trace.go:171","msg":"trace[27334917] transaction","detail":"{read_only:false; response_revision:9190; number_of_response:1; }","duration":"207.185366ms","start":"2026-04-08T01:38:24.523624Z","end":"2026-04-08T01:38:24.73081Z","steps":["trace[27334917] 'process raft request' (duration: 206.96699ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:39:33.450997Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T01:39:33.456916Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T01:39:33.456989Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-08T01:40:16.175422Z","caller":"traceutil/trace.go:171","msg":"trace[378879066] linearizableReadLoop","detail":"{readStateIndex:10340; appliedIndex:10339; }","duration":"109.321725ms","start":"2026-04-08T01:40:16.066068Z","end":"2026-04-08T01:40:16.175389Z","steps":["trace[378879066] 'read index received' (duration: 109.046018ms)","trace[378879066] 'applied index is now lower than readState.Index' (duration: 274.557µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:40:16.175479Z","caller":"traceutil/trace.go:171","msg":"trace[367321726] transaction","detail":"{read_only:false; response_revision:9993; number_of_response:1; }","duration":"178.339332ms","start":"2026-04-08T01:40:15.997106Z","end":"2026-04-08T01:40:16.175446Z","steps":["trace[367321726] 'process raft request' (duration: 178.115876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:40:16.175711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.051013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T01:40:16.175818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.800759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19220"} {"level":"info","ts":"2026-04-08T01:40:16.175892Z","caller":"traceutil/trace.go:171","msg":"trace[1070007207] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9993; }","duration":"107.950172ms","start":"2026-04-08T01:40:16.067922Z","end":"2026-04-08T01:40:16.175872Z","steps":["trace[1070007207] 'agreement among raft nodes before linearized reading' (duration: 107.696217ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:40:16.175827Z","caller":"traceutil/trace.go:171","msg":"trace[1039732039] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:9993; }","duration":"154.257868ms","start":"2026-04-08T01:40:16.021546Z","end":"2026-04-08T01:40:16.175803Z","steps":["trace[1039732039] 'agreement among raft nodes before linearized reading' (duration: 154.018653ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:41:54.287489Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8325} {"level":"info","ts":"2026-04-08T01:41:54.365756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8325,"took":"76.197437ms","hash":1716156203,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":24834048,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-08T01:41:54.365828Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1716156203,"revision":8325,"compact-revision":6131} {"level":"info","ts":"2026-04-08T01:44:47.751246Z","caller":"traceutil/trace.go:171","msg":"trace[1250207957] linearizableReadLoop","detail":"{readStateIndex:11971; appliedIndex:11970; }","duration":"195.059165ms","start":"2026-04-08T01:44:47.556164Z","end":"2026-04-08T01:44:47.751223Z","steps":["trace[1250207957] 'read index received' (duration: 194.897831ms)","trace[1250207957] 'applied index is now lower than readState.Index' (duration: 160.494µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:44:47.7513Z","caller":"traceutil/trace.go:171","msg":"trace[1999078434] transaction","detail":"{read_only:false; response_revision:11564; number_of_response:1; }","duration":"225.61317ms","start":"2026-04-08T01:44:47.525659Z","end":"2026-04-08T01:44:47.751272Z","steps":["trace[1999078434] 'process raft request' (duration: 225.418445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:44:47.751596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.421883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-04-08T01:44:47.751644Z","caller":"traceutil/trace.go:171","msg":"trace[1022076306] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:11564; }","duration":"195.520137ms","start":"2026-04-08T01:44:47.556111Z","end":"2026-04-08T01:44:47.751632Z","steps":["trace[1022076306] 'agreement among raft nodes before linearized reading' (duration: 195.241269ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:44:48.821096Z","caller":"traceutil/trace.go:171","msg":"trace[1255774433] transaction","detail":"{read_only:false; response_revision:11566; number_of_response:1; }","duration":"248.354855ms","start":"2026-04-08T01:44:48.572712Z","end":"2026-04-08T01:44:48.821067Z","steps":["trace[1255774433] 'process raft request' (duration: 248.193121ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:44:48.821768Z","caller":"traceutil/trace.go:171","msg":"trace[73405212] transaction","detail":"{read_only:false; response_revision:11567; number_of_response:1; }","duration":"247.089052ms","start":"2026-04-08T01:44:48.574669Z","end":"2026-04-08T01:44:48.821758Z","steps":["trace[73405212] 'process raft request' (duration: 247.001379ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:44:48.821987Z","caller":"traceutil/trace.go:171","msg":"trace[1304177770] transaction","detail":"{read_only:false; response_revision:11568; number_of_response:1; }","duration":"246.839265ms","start":"2026-04-08T01:44:48.575128Z","end":"2026-04-08T01:44:48.821967Z","steps":["trace[1304177770] 'process raft request' (duration: 246.593029ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:44:48.822186Z","caller":"traceutil/trace.go:171","msg":"trace[636252286] linearizableReadLoop","detail":"{readStateIndex:11977; appliedIndex:11973; }","duration":"187.261884ms","start":"2026-04-08T01:44:48.634915Z","end":"2026-04-08T01:44:48.822176Z","steps":["trace[636252286] 'read index received' (duration: 186.029282ms)","trace[636252286] 'applied index is now lower than readState.Index' (duration: 1.231572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:44:48.822327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.411038ms","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-08T01:44:48.822353Z","caller":"traceutil/trace.go:171","msg":"trace[309914898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11569; }","duration":"187.51345ms","start":"2026-04-08T01:44:48.634832Z","end":"2026-04-08T01:44:48.822345Z","steps":["trace[309914898] 'agreement among raft nodes before linearized reading' (duration: 187.373756ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:44:48.822354Z","caller":"traceutil/trace.go:171","msg":"trace[1377848323] transaction","detail":"{read_only:false; response_revision:11569; number_of_response:1; }","duration":"243.949222ms","start":"2026-04-08T01:44:48.578394Z","end":"2026-04-08T01:44:48.822343Z","steps":["trace[1377848323] 'process raft request' (duration: 243.51748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:44:53.582002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.024806ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-08T01:44:53.582951Z","caller":"traceutil/trace.go:171","msg":"trace[1795846264] linearizableReadLoop","detail":"{readStateIndex:11999; appliedIndex:11994; }","duration":"610.528515ms","start":"2026-04-08T01:44:52.972403Z","end":"2026-04-08T01:44:53.582932Z","steps":["trace[1795846264] 'read index received' (duration: 520.084µs)","trace[1795846264] 'applied index is now lower than readState.Index' (duration: 610.007651ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:44:53.583038Z","caller":"traceutil/trace.go:171","msg":"trace[236064744] transaction","detail":"{read_only:false; response_revision:11588; number_of_response:1; }","duration":"610.815043ms","start":"2026-04-08T01:44:52.972165Z","end":"2026-04-08T01:44:53.58298Z","steps":["trace[236064744] 'process raft request' (duration: 610.68204ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:44:53.583196Z","caller":"traceutil/trace.go:171","msg":"trace[2018430926] transaction","detail":"{read_only:false; response_revision:11587; number_of_response:1; }","duration":"611.234223ms","start":"2026-04-08T01:44:52.971949Z","end":"2026-04-08T01:44:53.583184Z","steps":["trace[2018430926] 'process raft request' (duration: 610.622467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:44:53.583217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:44:52.972157Z","time spent":"610.964036ms","remote":"127.0.0.1:53120","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-08T01:44:53.583251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:44:52.971926Z","time spent":"611.297495ms","remote":"127.0.0.1:53120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:44:53.583197Z","caller":"traceutil/trace.go:171","msg":"trace[492542652] transaction","detail":"{read_only:false; response_revision:11590; number_of_response:1; }","duration":"610.921305ms","start":"2026-04-08T01:44:52.97225Z","end":"2026-04-08T01:44:53.583171Z","steps":["trace[492542652] 'process raft request' (duration: 610.658968ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:44:53.583233Z","caller":"traceutil/trace.go:171","msg":"trace[1014919879] transaction","detail":"{read_only:false; response_revision:11589; number_of_response:1; }","duration":"610.911774ms","start":"2026-04-08T01:44:52.972277Z","end":"2026-04-08T01:44:53.583189Z","steps":["trace[1014919879] 'process raft request' (duration: 610.610337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:44:53.583349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.037238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-08T01:44:53.583333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.914704ms","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-08T01:44:53.583371Z","caller":"traceutil/trace.go:171","msg":"trace[608445909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11590; }","duration":"201.085849ms","start":"2026-04-08T01:44:53.382279Z","end":"2026-04-08T01:44:53.583365Z","steps":["trace[608445909] 'agreement among raft nodes before linearized reading' (duration: 201.032848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:44:53.583365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:44:52.972215Z","time spent":"611.070718ms","remote":"127.0.0.1:53120","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-08T01:44:53.583383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:44:52.97227Z","time spent":"611.028757ms","remote":"127.0.0.1:53120","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-08T01:44:53.583443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.939813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5127"} {"level":"warn","ts":"2026-04-08T01:44:53.583473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.114781ms","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":"warn","ts":"2026-04-08T01:44:53.58349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.401392ms","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-08T01:44:53.58353Z","caller":"traceutil/trace.go:171","msg":"trace[1709752744] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:11590; }","duration":"551.070506ms","start":"2026-04-08T01:44:53.032439Z","end":"2026-04-08T01:44:53.58351Z","steps":["trace[1709752744] 'agreement among raft nodes before linearized reading' (duration: 550.823979ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:44:53.583574Z","caller":"traceutil/trace.go:171","msg":"trace[501802590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11590; }","duration":"314.514096ms","start":"2026-04-08T01:44:53.269043Z","end":"2026-04-08T01:44:53.583557Z","steps":["trace[501802590] 'agreement among raft nodes before linearized reading' (duration: 314.32357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:44:53.58363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:44:53.269029Z","time spent":"314.588977ms","remote":"127.0.0.1:53120","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":"warn","ts":"2026-04-08T01:44:53.5834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.557237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T01:44:53.583715Z","caller":"traceutil/trace.go:171","msg":"trace[1374040958] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:11590; }","duration":"216.864475ms","start":"2026-04-08T01:44:53.366829Z","end":"2026-04-08T01:44:53.583693Z","steps":["trace[1374040958] 'agreement among raft nodes before linearized reading' (duration: 216.493115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:44:53.583583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:44:53.032425Z","time spent":"551.143548ms","remote":"127.0.0.1:53090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":5150,"request content":"key:\"/registry/jobs/openstack/staffeln-db-sync\" "} {"level":"info","ts":"2026-04-08T01:44:53.583535Z","caller":"traceutil/trace.go:171","msg":"trace[1569262234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11590; }","duration":"357.227004ms","start":"2026-04-08T01:44:53.226292Z","end":"2026-04-08T01:44:53.583519Z","steps":["trace[1569262234] 'agreement among raft nodes before linearized reading' (duration: 357.09122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:44:53.583907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:44:53.226268Z","time spent":"357.627144ms","remote":"127.0.0.1:53120","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-08T01:44:53.583402Z","caller":"traceutil/trace.go:171","msg":"trace[837523027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11590; }","duration":"611.007817ms","start":"2026-04-08T01:44:52.972372Z","end":"2026-04-08T01:44:53.58338Z","steps":["trace[837523027] 'agreement among raft nodes before linearized reading' (duration: 610.802602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:44:53.584004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:44:52.97236Z","time spent":"611.630342ms","remote":"127.0.0.1:53120","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-08T01:44:53.583342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.121812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:44:53.584121Z","caller":"traceutil/trace.go:171","msg":"trace[1208468175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11590; }","duration":"282.954215ms","start":"2026-04-08T01:44:53.301149Z","end":"2026-04-08T01:44:53.584103Z","steps":["trace[1208468175] 'agreement among raft nodes before linearized reading' (duration: 282.154344ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:46:54.292829Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10670} {"level":"info","ts":"2026-04-08T01:46:54.370969Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10670,"took":"74.634069ms","hash":3311156389,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":20180992,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-08T01:46:54.371027Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3311156389,"revision":10670,"compact-revision":8325} {"level":"warn","ts":"2026-04-08T01:47:09.601208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.379316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-08T01:47:09.601379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.386653ms","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":"warn","ts":"2026-04-08T01:47:09.601415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.901608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/barbican-barbican-ks-service\" ","response":"range_response_count:1 size:764"} {"level":"info","ts":"2026-04-08T01:47:09.601484Z","caller":"traceutil/trace.go:171","msg":"trace[1443810100] range","detail":"{range_begin:/registry/rolebindings/openstack/barbican-barbican-ks-service; range_end:; response_count:1; response_revision:12287; }","duration":"403.025031ms","start":"2026-04-08T01:47:09.198441Z","end":"2026-04-08T01:47:09.601466Z","steps":["trace[1443810100] 'range keys from in-memory index tree' (duration: 402.686192ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:47:09.60148Z","caller":"traceutil/trace.go:171","msg":"trace[1238044005] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:12287; }","duration":"295.523116ms","start":"2026-04-08T01:47:09.30593Z","end":"2026-04-08T01:47:09.601453Z","steps":["trace[1238044005] 'count revisions from in-memory index tree' (duration: 295.344052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:47:09.601519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:47:09.198418Z","time spent":"403.095533ms","remote":"127.0.0.1:53202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":787,"request content":"key:\"/registry/rolebindings/openstack/barbican-barbican-ks-service\" "} {"level":"info","ts":"2026-04-08T01:47:09.601426Z","caller":"traceutil/trace.go:171","msg":"trace[1554594035] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12287; }","duration":"364.617583ms","start":"2026-04-08T01:47:09.236777Z","end":"2026-04-08T01:47:09.601395Z","steps":["trace[1554594035] 'range keys from in-memory index tree' (duration: 364.201402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:47:09.601561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.98967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/barbican-barbican-ks-user\" ","response":"range_response_count:1 size:752"} {"level":"info","ts":"2026-04-08T01:47:09.601687Z","caller":"traceutil/trace.go:171","msg":"trace[743507306] range","detail":"{range_begin:/registry/rolebindings/openstack/barbican-barbican-ks-user; range_end:; response_count:1; response_revision:12287; }","duration":"403.158574ms","start":"2026-04-08T01:47:09.1985Z","end":"2026-04-08T01:47:09.601658Z","steps":["trace[743507306] 'range keys from in-memory index tree' (duration: 402.788124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:47:09.601655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.168064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tttx9\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-08T01:47:09.601756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:47:09.198481Z","time spent":"403.258876ms","remote":"127.0.0.1:53202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":775,"request content":"key:\"/registry/rolebindings/openstack/barbican-barbican-ks-user\" "} {"level":"warn","ts":"2026-04-08T01:47:09.601761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.245344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:47:09.601787Z","caller":"traceutil/trace.go:171","msg":"trace[750844260] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tttx9; range_end:; response_count:1; response_revision:12287; }","duration":"403.450961ms","start":"2026-04-08T01:47:09.198291Z","end":"2026-04-08T01:47:09.601742Z","steps":["trace[750844260] 'range keys from in-memory index tree' (duration: 402.965499ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:47:09.601834Z","caller":"traceutil/trace.go:171","msg":"trace[1354289479] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:12287; }","duration":"399.639263ms","start":"2026-04-08T01:47:09.202177Z","end":"2026-04-08T01:47:09.601816Z","steps":["trace[1354289479] 'count revisions from in-memory index tree' (duration: 399.12611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:47:09.601835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:47:09.198262Z","time spent":"403.561893ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-tttx9\" "} {"level":"warn","ts":"2026-04-08T01:47:09.601881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:47:09.202119Z","time spent":"399.749406ms","remote":"127.0.0.1:47152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-04-08T01:47:09.601704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:47:09.236771Z","time spent":"364.86132ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-08T01:47:10.550892Z","caller":"traceutil/trace.go:171","msg":"trace[1257706402] linearizableReadLoop","detail":"{readStateIndex:12734; appliedIndex:12733; }","duration":"133.997555ms","start":"2026-04-08T01:47:10.416875Z","end":"2026-04-08T01:47:10.550872Z","steps":["trace[1257706402] 'read index received' (duration: 133.829811ms)","trace[1257706402] 'applied index is now lower than readState.Index' (duration: 166.924µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:47:10.550951Z","caller":"traceutil/trace.go:171","msg":"trace[2065669269] transaction","detail":"{read_only:false; response_revision:12296; number_of_response:1; }","duration":"138.915921ms","start":"2026-04-08T01:47:10.411998Z","end":"2026-04-08T01:47:10.550914Z","steps":["trace[2065669269] 'process raft request' (duration: 138.745197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:47:10.551105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.358708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/glance-glance-storage-init\" ","response":"range_response_count:1 size:756"} {"level":"info","ts":"2026-04-08T01:47:10.551154Z","caller":"traceutil/trace.go:171","msg":"trace[1651531810] range","detail":"{range_begin:/registry/rolebindings/openstack/glance-glance-storage-init; range_end:; response_count:1; response_revision:12296; }","duration":"126.43828ms","start":"2026-04-08T01:47:10.424702Z","end":"2026-04-08T01:47:10.551141Z","steps":["trace[1651531810] 'agreement among raft nodes before linearized reading' (duration: 126.297137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:47:10.551235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.299822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/glance-glance-rabbit-init\" ","response":"range_response_count:1 size:752"} {"level":"info","ts":"2026-04-08T01:47:10.551329Z","caller":"traceutil/trace.go:171","msg":"trace[1414632277] range","detail":"{range_begin:/registry/rolebindings/openstack/glance-glance-rabbit-init; range_end:; response_count:1; response_revision:12296; }","duration":"134.441466ms","start":"2026-04-08T01:47:10.416866Z","end":"2026-04-08T01:47:10.551307Z","steps":["trace[1414632277] 'agreement among raft nodes before linearized reading' (duration: 134.136838ms)"],"step_count":1}