{"level":"warn","ts":"2026-04-07T00:07:36.031457Z","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-07T00:07:36.031563Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-07T00:07:36.031643Z","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-07T00:07:36.031656Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-04-07T00:07:36.031676Z","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-07T00:07:36.03231Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-04-07T00:07:36.032383Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-07T00:07:36.038614Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.986329ms"} {"level":"info","ts":"2026-04-07T00:07:36.047505Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-04-07T00:07:36.047599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:07:36.047618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-04-07T00:07:36.04764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:07:36.047653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-04-07T00:07:36.047702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-04-07T00:07:36.053021Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:07:36.054382Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:07:36.055578Z","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-07T00:07:36.057768Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:07:36.057996Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:07:36.058102Z","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-07T00:07:36.058421Z","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-07T00:07:36.058471Z","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-07T00:07:36.062025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-04-07T00:07:36.062176Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-04-07T00:07:36.062698Z","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-07T00:07:36.062911Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:07:36.062948Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:07:36.063068Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-04-07T00:07:36.063087Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-04-07T00:07:36.648659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:07:36.648777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:07:36.648819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-04-07T00:07:36.64885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:07:36.648872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-04-07T00:07:36.648897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-04-07T00:07:36.648928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-04-07T00:07:36.650326Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:07:36.650388Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:07:36.650424Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:07:36.650724Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:07:36.650904Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:07:36.653909Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:07:36.654349Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:07:36.655638Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:07:36.655829Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:07:36.655888Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:07:36.660828Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-04-07T00:07:38.447249Z","caller":"traceutil/trace.go:171","msg":"trace[978446876] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"142.226806ms","start":"2026-04-07T00:07:38.304999Z","end":"2026-04-07T00:07:38.447225Z","steps":["trace[978446876] 'read index received' (duration: 131.308369ms)","trace[978446876] 'applied index is now lower than readState.Index' (duration: 10.916997ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:07:38.447865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.581584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T00:07:38.448011Z","caller":"traceutil/trace.go:171","msg":"trace[499512323] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"161.209317ms","start":"2026-04-07T00:07:38.286763Z","end":"2026-04-07T00:07:38.447972Z","steps":["trace[499512323] 'agreement among raft nodes before linearized reading' (duration: 160.602194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:38.448828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.964863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T00:07:38.448994Z","caller":"traceutil/trace.go:171","msg":"trace[1624653934] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"162.149399ms","start":"2026-04-07T00:07:38.286798Z","end":"2026-04-07T00:07:38.448948Z","steps":["trace[1624653934] 'agreement among raft nodes before linearized reading' (duration: 160.449491ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:38.44936Z","caller":"traceutil/trace.go:171","msg":"trace[1774287276] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"128.54394ms","start":"2026-04-07T00:07:38.320636Z","end":"2026-04-07T00:07:38.44918Z","steps":["trace[1774287276] 'process raft request' (duration: 127.939596ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:38.449417Z","caller":"traceutil/trace.go:171","msg":"trace[2116905834] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"128.364556ms","start":"2026-04-07T00:07:38.32083Z","end":"2026-04-07T00:07:38.449195Z","steps":["trace[2116905834] 'process raft request' (duration: 128.144531ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:38.449863Z","caller":"traceutil/trace.go:171","msg":"trace[152900174] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"128.799024ms","start":"2026-04-07T00:07:38.320692Z","end":"2026-04-07T00:07:38.449491Z","steps":["trace[152900174] 'process raft request' (duration: 128.092008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:38.450225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.431658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T00:07:38.450265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.496256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T00:07:38.450287Z","caller":"traceutil/trace.go:171","msg":"trace[1465990263] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"164.537131ms","start":"2026-04-07T00:07:38.285729Z","end":"2026-04-07T00:07:38.450267Z","steps":["trace[1465990263] 'agreement among raft nodes before linearized reading' (duration: 161.640128ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:38.447644Z","caller":"traceutil/trace.go:171","msg":"trace[497483920] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"213.134644ms","start":"2026-04-07T00:07:38.234181Z","end":"2026-04-07T00:07:38.447315Z","steps":["trace[497483920] 'process raft request' (duration: 202.157926ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:38.450361Z","caller":"traceutil/trace.go:171","msg":"trace[1145488868] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:8; }","duration":"215.582458ms","start":"2026-04-07T00:07:38.234732Z","end":"2026-04-07T00:07:38.450314Z","steps":["trace[1145488868] 'agreement among raft nodes before linearized reading' (duration: 212.623924ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:38.449969Z","caller":"traceutil/trace.go:171","msg":"trace[58197776] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"129.169122ms","start":"2026-04-07T00:07:38.320181Z","end":"2026-04-07T00:07:38.44935Z","steps":["trace[58197776] 'process raft request' (duration: 128.116849ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:38.450815Z","caller":"traceutil/trace.go:171","msg":"trace[883703127] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"129.704324ms","start":"2026-04-07T00:07:38.320762Z","end":"2026-04-07T00:07:38.450466Z","steps":["trace[883703127] 'process raft request' (duration: 128.08711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:38.45168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.919214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3173"} {"level":"info","ts":"2026-04-07T00:07:38.451714Z","caller":"traceutil/trace.go:171","msg":"trace[1540486480] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"118.800088ms","start":"2026-04-07T00:07:38.332864Z","end":"2026-04-07T00:07:38.451664Z","steps":["trace[1540486480] 'process raft request' (duration: 117.796375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:38.451742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.444626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T00:07:38.451939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.957382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T00:07:38.451978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.894642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T00:07:38.451969Z","caller":"traceutil/trace.go:171","msg":"trace[1202913203] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:0; response_revision:11; }","duration":"133.666481ms","start":"2026-04-07T00:07:38.318252Z","end":"2026-04-07T00:07:38.451918Z","steps":["trace[1202913203] 'agreement among raft nodes before linearized reading' (duration: 133.420496ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:38.452001Z","caller":"traceutil/trace.go:171","msg":"trace[1679418683] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"129.666233ms","start":"2026-04-07T00:07:38.320984Z","end":"2026-04-07T00:07:38.45065Z","steps":["trace[1679418683] 'process raft request' (duration: 128.085719ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:38.452043Z","caller":"traceutil/trace.go:171","msg":"trace[717950214] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"131.074835ms","start":"2026-04-07T00:07:38.320911Z","end":"2026-04-07T00:07:38.451986Z","steps":["trace[717950214] 'agreement among raft nodes before linearized reading' (duration: 130.899931ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:38.45209Z","caller":"traceutil/trace.go:171","msg":"trace[838517796] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"131.985564ms","start":"2026-04-07T00:07:38.320032Z","end":"2026-04-07T00:07:38.452017Z","steps":["trace[838517796] 'agreement among raft nodes before linearized reading' (duration: 131.277588ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:38.45189Z","caller":"traceutil/trace.go:171","msg":"trace[1554267079] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"121.64025ms","start":"2026-04-07T00:07:38.330214Z","end":"2026-04-07T00:07:38.451855Z","steps":["trace[1554267079] 'process raft request' (duration: 120.30088ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:38.451768Z","caller":"traceutil/trace.go:171","msg":"trace[22448535] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"144.013105ms","start":"2026-04-07T00:07:38.307721Z","end":"2026-04-07T00:07:38.451734Z","steps":["trace[22448535] 'agreement among raft nodes before linearized reading' (duration: 143.258258ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:42.636535Z","caller":"traceutil/trace.go:171","msg":"trace[889299550] transaction","detail":"{read_only:false; number_of_response:0; response_revision:260; }","duration":"105.196532ms","start":"2026-04-07T00:07:42.531303Z","end":"2026-04-07T00:07:42.6365Z","steps":["trace[889299550] 'process raft request' (duration: 104.925736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:42.636599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.734804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:07:42.63668Z","caller":"traceutil/trace.go:171","msg":"trace[1958864157] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:261; }","duration":"105.849396ms","start":"2026-04-07T00:07:42.530811Z","end":"2026-04-07T00:07:42.63666Z","steps":["trace[1958864157] 'agreement among raft nodes before linearized reading' (duration: 105.725304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:48.138319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.665972ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-07T00:07:48.138568Z","caller":"traceutil/trace.go:171","msg":"trace[1857567345] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:362; }","duration":"194.04595ms","start":"2026-04-07T00:07:47.944498Z","end":"2026-04-07T00:07:48.138544Z","steps":["trace[1857567345] 'read index received' (duration: 5.830846ms)","trace[1857567345] 'applied index is now lower than readState.Index' (duration: 188.213094ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:07:48.138614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:47.831588Z","time spent":"307.019091ms","remote":"127.0.0.1:42498","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T00:07:48.138706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.190713ms","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-07T00:07:48.138776Z","caller":"traceutil/trace.go:171","msg":"trace[1044554283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:350; }","duration":"194.286475ms","start":"2026-04-07T00:07:47.94447Z","end":"2026-04-07T00:07:48.138756Z","steps":["trace[1044554283] 'agreement among raft nodes before linearized reading' (duration: 194.137952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:48.379852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.76263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:07:48.379961Z","caller":"traceutil/trace.go:171","msg":"trace[1478813476] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:363; }","duration":"200.857639ms","start":"2026-04-07T00:07:48.179087Z","end":"2026-04-07T00:07:48.379945Z","steps":["trace[1478813476] 'read index received' (duration: 32.882964ms)","trace[1478813476] 'applied index is now lower than readState.Index' (duration: 167.973325ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:48.379982Z","caller":"traceutil/trace.go:171","msg":"trace[825818896] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"236.495532ms","start":"2026-04-07T00:07:48.143465Z","end":"2026-04-07T00:07:48.37996Z","steps":["trace[825818896] 'process raft request' (duration: 103.182149ms)","trace[825818896] 'compare' (duration: 132.657988ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:07:48.380019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.93646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:07:48.380058Z","caller":"traceutil/trace.go:171","msg":"trace[837106275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:352; }","duration":"201.045703ms","start":"2026-04-07T00:07:48.179004Z","end":"2026-04-07T00:07:48.38005Z","steps":["trace[837106275] 'agreement among raft nodes before linearized reading' (duration: 200.985422ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:06.348612Z","caller":"traceutil/trace.go:171","msg":"trace[1630636145] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"165.497441ms","start":"2026-04-07T00:08:06.183074Z","end":"2026-04-07T00:08:06.348571Z","steps":["trace[1630636145] 'process raft request' (duration: 165.269496ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:06.348672Z","caller":"traceutil/trace.go:171","msg":"trace[711124222] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"156.639478ms","start":"2026-04-07T00:08:06.192006Z","end":"2026-04-07T00:08:06.348645Z","steps":["trace[711124222] 'process raft request' (duration: 156.494185ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:06.348614Z","caller":"traceutil/trace.go:171","msg":"trace[1374904780] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"164.690924ms","start":"2026-04-07T00:08:06.183898Z","end":"2026-04-07T00:08:06.348589Z","steps":["trace[1374904780] 'process raft request' (duration: 164.552991ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:06.348752Z","caller":"traceutil/trace.go:171","msg":"trace[1546791972] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"156.248661ms","start":"2026-04-07T00:08:06.192456Z","end":"2026-04-07T00:08:06.348705Z","steps":["trace[1546791972] 'process raft request' (duration: 156.102108ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:06.348865Z","caller":"traceutil/trace.go:171","msg":"trace[227651209] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"155.631448ms","start":"2026-04-07T00:08:06.193216Z","end":"2026-04-07T00:08:06.348848Z","steps":["trace[227651209] 'process raft request' (duration: 155.397243ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:06.348923Z","caller":"traceutil/trace.go:171","msg":"trace[1214801671] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"155.367002ms","start":"2026-04-07T00:08:06.193543Z","end":"2026-04-07T00:08:06.34891Z","steps":["trace[1214801671] 'process raft request' (duration: 155.118897ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:06.349054Z","caller":"traceutil/trace.go:171","msg":"trace[33192268] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"152.56139ms","start":"2026-04-07T00:08:06.196478Z","end":"2026-04-07T00:08:06.34904Z","steps":["trace[33192268] 'process raft request' (duration: 152.362366ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:06.34914Z","caller":"traceutil/trace.go:171","msg":"trace[1834604261] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:483; }","duration":"102.884803ms","start":"2026-04-07T00:08:06.246237Z","end":"2026-04-07T00:08:06.349122Z","steps":["trace[1834604261] 'read index received' (duration: 102.178448ms)","trace[1834604261] 'applied index is now lower than readState.Index' (duration: 705.315µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:06.349165Z","caller":"traceutil/trace.go:171","msg":"trace[1509085486] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"139.037077ms","start":"2026-04-07T00:08:06.210106Z","end":"2026-04-07T00:08:06.349143Z","steps":["trace[1509085486] 'process raft request' (duration: 138.835963ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:06.34919Z","caller":"traceutil/trace.go:171","msg":"trace[812362554] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"134.353325ms","start":"2026-04-07T00:08:06.214805Z","end":"2026-04-07T00:08:06.349158Z","steps":["trace[812362554] 'process raft request' (duration: 134.261813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:06.349286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.037697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:06.349329Z","caller":"traceutil/trace.go:171","msg":"trace[1541668284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"103.129098ms","start":"2026-04-07T00:08:06.24619Z","end":"2026-04-07T00:08:06.349319Z","steps":["trace[1541668284] 'agreement among raft nodes before linearized reading' (duration: 103.009705ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:06.356372Z","caller":"traceutil/trace.go:171","msg":"trace[1964536762] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"105.744255ms","start":"2026-04-07T00:08:06.250608Z","end":"2026-04-07T00:08:06.356352Z","steps":["trace[1964536762] 'process raft request' (duration: 105.433757ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:06.35643Z","caller":"traceutil/trace.go:171","msg":"trace[2079266820] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"102.599687ms","start":"2026-04-07T00:08:06.253803Z","end":"2026-04-07T00:08:06.356403Z","steps":["trace[2079266820] 'process raft request' (duration: 102.345552ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:06.356735Z","caller":"traceutil/trace.go:171","msg":"trace[774313450] transaction","detail":"{read_only:false; number_of_response:1; response_revision:476; }","duration":"102.402703ms","start":"2026-04-07T00:08:06.254319Z","end":"2026-04-07T00:08:06.356722Z","steps":["trace[774313450] 'process raft request' (duration: 101.913042ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:12.80044Z","caller":"traceutil/trace.go:171","msg":"trace[1756730163] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"199.665622ms","start":"2026-04-07T00:08:12.600747Z","end":"2026-04-07T00:08:12.800413Z","steps":["trace[1756730163] 'read index received' (duration: 191.202029ms)","trace[1756730163] 'applied index is now lower than readState.Index' (duration: 8.462433ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:12.800639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.870407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-d46c8\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-04-07T00:08:12.8006Z","caller":"traceutil/trace.go:171","msg":"trace[293706413] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"207.115603ms","start":"2026-04-07T00:08:12.59345Z","end":"2026-04-07T00:08:12.800566Z","steps":["trace[293706413] 'process raft request' (duration: 198.331723ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:12.800684Z","caller":"traceutil/trace.go:171","msg":"trace[107076878] range","detail":"{range_begin:/registry/pods/kube-system/cilium-d46c8; range_end:; response_count:1; response_revision:576; }","duration":"199.958888ms","start":"2026-04-07T00:08:12.600712Z","end":"2026-04-07T00:08:12.800671Z","steps":["trace[107076878] 'agreement among raft nodes before linearized reading' (duration: 199.804274ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:12.980996Z","caller":"traceutil/trace.go:171","msg":"trace[507329449] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"274.364554ms","start":"2026-04-07T00:08:12.706595Z","end":"2026-04-07T00:08:12.98096Z","steps":["trace[507329449] 'process raft request' (duration: 221.733672ms)","trace[507329449] 'compare' (duration: 52.223973ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:13.06092Z","caller":"traceutil/trace.go:171","msg":"trace[1311363246] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:598; }","duration":"158.675624ms","start":"2026-04-07T00:08:12.902188Z","end":"2026-04-07T00:08:13.060864Z","steps":["trace[1311363246] 'read index received' (duration: 22.10315ms)","trace[1311363246] 'applied index is now lower than readState.Index' (duration: 136.571644ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:13.061106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.903568ms","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-07T00:08:13.061139Z","caller":"traceutil/trace.go:171","msg":"trace[554574804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"158.98962ms","start":"2026-04-07T00:08:12.90214Z","end":"2026-04-07T00:08:13.061129Z","steps":["trace[554574804] 'agreement among raft nodes before linearized reading' (duration: 158.842997ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:13.061048Z","caller":"traceutil/trace.go:171","msg":"trace[950655222] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"244.154968ms","start":"2026-04-07T00:08:12.816828Z","end":"2026-04-07T00:08:13.060983Z","steps":["trace[950655222] 'process raft request' (duration: 243.901163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:13.52562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.995163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:08:13.525792Z","caller":"traceutil/trace.go:171","msg":"trace[798633533] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"487.456548ms","start":"2026-04-07T00:08:13.038311Z","end":"2026-04-07T00:08:13.525768Z","steps":["trace[798633533] 'process raft request' (duration: 191.203539ms)","trace[798633533] 'compare' (duration: 295.764928ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:13.525863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:13.038286Z","time spent":"487.552389ms","remote":"127.0.0.1:42200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:08:13.526866Z","caller":"traceutil/trace.go:171","msg":"trace[45807135] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"348.87023ms","start":"2026-04-07T00:08:13.177977Z","end":"2026-04-07T00:08:13.526847Z","steps":["trace[45807135] 'read index received' (duration: 51.570079ms)","trace[45807135] 'applied index is now lower than readState.Index' (duration: 297.298851ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:13.527138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.006613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:13.527195Z","caller":"traceutil/trace.go:171","msg":"trace[1836008441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"349.253148ms","start":"2026-04-07T00:08:13.177925Z","end":"2026-04-07T00:08:13.527178Z","steps":["trace[1836008441] 'agreement among raft nodes before linearized reading' (duration: 348.993772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:13.527229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:13.177903Z","time spent":"349.318789ms","remote":"127.0.0.1:41984","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-07T00:08:13.527237Z","caller":"traceutil/trace.go:171","msg":"trace[1702770603] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"461.717068ms","start":"2026-04-07T00:08:13.065459Z","end":"2026-04-07T00:08:13.527176Z","steps":["trace[1702770603] 'process raft request' (duration: 461.266608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:13.527573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:13.065443Z","time spent":"461.975644ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:08:55.105897Z","caller":"traceutil/trace.go:171","msg":"trace[927864966] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"209.643863ms","start":"2026-04-07T00:08:54.896217Z","end":"2026-04-07T00:08:55.105861Z","steps":["trace[927864966] 'process raft request' (duration: 208.637932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:40.029863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.722056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-935fd20c-9cc8-4a28-a54c-bc4cbbb61e4f\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-04-07T00:09:40.030048Z","caller":"traceutil/trace.go:171","msg":"trace[350746809] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-935fd20c-9cc8-4a28-a54c-bc4cbbb61e4f; range_end:; response_count:1; response_revision:1781; }","duration":"249.950861ms","start":"2026-04-07T00:09:39.780076Z","end":"2026-04-07T00:09:40.030027Z","steps":["trace[350746809] 'range keys from in-memory index tree' (duration: 249.553343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:40.400868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.919597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:09:40.401238Z","caller":"traceutil/trace.go:171","msg":"trace[841339786] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"280.283384ms","start":"2026-04-07T00:09:40.120895Z","end":"2026-04-07T00:09:40.401178Z","steps":["trace[841339786] 'process raft request' (duration: 55.940058ms)","trace[841339786] 'compare' (duration: 223.764373ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:09:40.412755Z","caller":"traceutil/trace.go:171","msg":"trace[1354094808] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"291.421462ms","start":"2026-04-07T00:09:40.121294Z","end":"2026-04-07T00:09:40.412716Z","steps":["trace[1354094808] 'process raft request' (duration: 291.198928ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:40.412796Z","caller":"traceutil/trace.go:171","msg":"trace[227961181] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"291.205258ms","start":"2026-04-07T00:09:40.121561Z","end":"2026-04-07T00:09:40.412766Z","steps":["trace[227961181] 'process raft request' (duration: 291.094216ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:40.413144Z","caller":"traceutil/trace.go:171","msg":"trace[1826098199] linearizableReadLoop","detail":"{readStateIndex:1855; appliedIndex:1851; }","duration":"235.74434ms","start":"2026-04-07T00:09:40.17738Z","end":"2026-04-07T00:09:40.413125Z","steps":["trace[1826098199] 'read index received' (duration: 235.105177ms)","trace[1826098199] 'applied index is now lower than readState.Index' (duration: 638.183µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:09:40.413242Z","caller":"traceutil/trace.go:171","msg":"trace[525657741] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"285.844078ms","start":"2026-04-07T00:09:40.12736Z","end":"2026-04-07T00:09:40.413204Z","steps":["trace[525657741] 'process raft request' (duration: 285.650114ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:40.413435Z","caller":"traceutil/trace.go:171","msg":"trace[2072921647] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"291.142287ms","start":"2026-04-07T00:09:40.122279Z","end":"2026-04-07T00:09:40.413422Z","steps":["trace[2072921647] 'process raft request' (duration: 290.420752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:40.413459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.081116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:09:40.413542Z","caller":"traceutil/trace.go:171","msg":"trace[558815051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1789; }","duration":"236.227049ms","start":"2026-04-07T00:09:40.177306Z","end":"2026-04-07T00:09:40.413533Z","steps":["trace[558815051] 'agreement among raft nodes before linearized reading' (duration: 235.941643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:40.41379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.968289ms","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-07T00:09:40.413834Z","caller":"traceutil/trace.go:171","msg":"trace[800254912] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1790; }","duration":"189.079881ms","start":"2026-04-07T00:09:40.224743Z","end":"2026-04-07T00:09:40.413823Z","steps":["trace[800254912] 'agreement among raft nodes before linearized reading' (duration: 188.926768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:40.414131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.901374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-07T00:09:40.414308Z","caller":"traceutil/trace.go:171","msg":"trace[811109601] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1790; }","duration":"182.078328ms","start":"2026-04-07T00:09:40.232156Z","end":"2026-04-07T00:09:40.414234Z","steps":["trace[811109601] 'agreement among raft nodes before linearized reading' (duration: 181.741701ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:29.348849Z","caller":"traceutil/trace.go:171","msg":"trace[1077203901] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"120.758466ms","start":"2026-04-07T00:10:29.22806Z","end":"2026-04-07T00:10:29.348819Z","steps":["trace[1077203901] 'process raft request' (duration: 120.563233ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:29.494952Z","caller":"traceutil/trace.go:171","msg":"trace[401558677] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"123.067452ms","start":"2026-04-07T00:10:29.371567Z","end":"2026-04-07T00:10:29.494634Z","steps":["trace[401558677] 'process raft request' (duration: 118.96192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:35.70006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.149975ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T00:10:35.700329Z","caller":"traceutil/trace.go:171","msg":"trace[1450359127] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"187.593154ms","start":"2026-04-07T00:10:35.512706Z","end":"2026-04-07T00:10:35.700299Z","steps":["trace[1450359127] 'process raft request' (duration: 187.457681ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:35.700391Z","caller":"traceutil/trace.go:171","msg":"trace[1633196892] linearizableReadLoop","detail":"{readStateIndex:2213; appliedIndex:2212; }","duration":"326.874491ms","start":"2026-04-07T00:10:35.37349Z","end":"2026-04-07T00:10:35.700364Z","steps":["trace[1633196892] 'read index received' (duration: 69.337278ms)","trace[1633196892] 'applied index is now lower than readState.Index' (duration: 257.531443ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:10:35.700693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.184748ms","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-07T00:10:35.700753Z","caller":"traceutil/trace.go:171","msg":"trace[705990808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2135; }","duration":"327.293509ms","start":"2026-04-07T00:10:35.373442Z","end":"2026-04-07T00:10:35.700735Z","steps":["trace[705990808] 'agreement among raft nodes before linearized reading' (duration: 327.043344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:35.700796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:35.373421Z","time spent":"327.363841ms","remote":"127.0.0.1:42234","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-07T00:10:35.715795Z","caller":"traceutil/trace.go:171","msg":"trace[1260764147] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"201.525193ms","start":"2026-04-07T00:10:35.514232Z","end":"2026-04-07T00:10:35.715758Z","steps":["trace[1260764147] 'process raft request' (duration: 201.240627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:35.715897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.171357ms","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-07T00:10:35.716001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.050472ms","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-07T00:10:35.716015Z","caller":"traceutil/trace.go:171","msg":"trace[199114508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2136; }","duration":"322.34675ms","start":"2026-04-07T00:10:35.393637Z","end":"2026-04-07T00:10:35.715984Z","steps":["trace[199114508] 'agreement among raft nodes before linearized reading' (duration: 322.017464ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:35.716062Z","caller":"traceutil/trace.go:171","msg":"trace[1646010226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2136; }","duration":"127.162964ms","start":"2026-04-07T00:10:35.588886Z","end":"2026-04-07T00:10:35.716049Z","steps":["trace[1646010226] 'agreement among raft nodes before linearized reading' (duration: 127.004991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:35.716077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:35.393616Z","time spent":"322.448163ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T00:10:35.716114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.092004ms","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-07T00:10:35.716281Z","caller":"traceutil/trace.go:171","msg":"trace[408483264] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2136; }","duration":"120.285388ms","start":"2026-04-07T00:10:35.595926Z","end":"2026-04-07T00:10:35.716211Z","steps":["trace[408483264] 'agreement among raft nodes before linearized reading' (duration: 119.89319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:36.020217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.038183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:10:36.020573Z","caller":"traceutil/trace.go:171","msg":"trace[1767628696] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"300.195257ms","start":"2026-04-07T00:10:35.720358Z","end":"2026-04-07T00:10:36.020553Z","steps":["trace[1767628696] 'process raft request' (duration: 300.043264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:36.020658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:35.720328Z","time spent":"300.296859ms","remote":"127.0.0.1:42234","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-07T00:10:36.020895Z","caller":"traceutil/trace.go:171","msg":"trace[494657597] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"300.487902ms","start":"2026-04-07T00:10:35.720366Z","end":"2026-04-07T00:10:36.020854Z","steps":["trace[494657597] 'process raft request' (duration: 300.145966ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:36.021002Z","caller":"traceutil/trace.go:171","msg":"trace[2043402981] linearizableReadLoop","detail":"{readStateIndex:2219; appliedIndex:2215; }","duration":"159.068023ms","start":"2026-04-07T00:10:35.861893Z","end":"2026-04-07T00:10:36.020961Z","steps":["trace[2043402981] 'read index received' (duration: 147.667235ms)","trace[2043402981] 'applied index is now lower than readState.Index' (duration: 11.399878ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:10:36.02114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:35.720342Z","time spent":"300.684336ms","remote":"127.0.0.1:42234","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-07T00:10:36.021218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.320538ms","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-07T00:10:36.021256Z","caller":"traceutil/trace.go:171","msg":"trace[1143938151] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2140; }","duration":"159.42501ms","start":"2026-04-07T00:10:35.861818Z","end":"2026-04-07T00:10:36.021243Z","steps":["trace[1143938151] 'agreement among raft nodes before linearized reading' (duration: 159.226676ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:36.021228Z","caller":"traceutil/trace.go:171","msg":"trace[1465117884] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"300.779448ms","start":"2026-04-07T00:10:35.720375Z","end":"2026-04-07T00:10:36.021154Z","steps":["trace[1465117884] 'process raft request' (duration: 300.325129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:36.021447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:35.720351Z","time spent":"300.973222ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:10:36.020443Z","caller":"traceutil/trace.go:171","msg":"trace[951381832] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"315.492294ms","start":"2026-04-07T00:10:35.704917Z","end":"2026-04-07T00:10:36.020409Z","steps":["trace[951381832] 'process raft request' (duration: 121.155726ms)","trace[951381832] 'compare' (duration: 193.701435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:10:36.021674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:35.704892Z","time spent":"316.714387ms","remote":"127.0.0.1:42234","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-07T00:11:52.155875Z","caller":"traceutil/trace.go:171","msg":"trace[2127841909] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"109.931742ms","start":"2026-04-07T00:11:52.045913Z","end":"2026-04-07T00:11:52.155844Z","steps":["trace[2127841909] 'process raft request' (duration: 109.764349ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:52.309337Z","caller":"traceutil/trace.go:171","msg":"trace[376135267] linearizableReadLoop","detail":"{readStateIndex:2848; appliedIndex:2847; }","duration":"131.554197ms","start":"2026-04-07T00:11:52.177755Z","end":"2026-04-07T00:11:52.309309Z","steps":["trace[376135267] 'read index received' (duration: 84.812297ms)","trace[376135267] 'applied index is now lower than readState.Index' (duration: 46.74084ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:52.30942Z","caller":"traceutil/trace.go:171","msg":"trace[1444202558] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"261.557274ms","start":"2026-04-07T00:11:52.047836Z","end":"2026-04-07T00:11:52.309394Z","steps":["trace[1444202558] 'process raft request' (duration: 214.706361ms)","trace[1444202558] 'compare' (duration: 46.618398ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:52.309471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.69084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:11:52.309506Z","caller":"traceutil/trace.go:171","msg":"trace[1775363857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2744; }","duration":"131.782992ms","start":"2026-04-07T00:11:52.17771Z","end":"2026-04-07T00:11:52.309493Z","steps":["trace[1775363857] 'agreement among raft nodes before linearized reading' (duration: 131.70473ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:18.066584Z","caller":"traceutil/trace.go:171","msg":"trace[1205270186] transaction","detail":"{read_only:false; response_revision:3712; number_of_response:1; }","duration":"211.646768ms","start":"2026-04-07T00:14:17.854897Z","end":"2026-04-07T00:14:18.066544Z","steps":["trace[1205270186] 'process raft request' (duration: 211.405252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:15:45.133831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.538835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:15:45.134014Z","caller":"traceutil/trace.go:171","msg":"trace[1046742921] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"255.001464ms","start":"2026-04-07T00:15:44.879Z","end":"2026-04-07T00:15:45.134002Z","steps":["trace[1046742921] 'process raft request' (duration: 80.229934ms)","trace[1046742921] 'compare' (duration: 174.348022ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:15:45.134207Z","caller":"traceutil/trace.go:171","msg":"trace[1006625432] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"178.945854ms","start":"2026-04-07T00:15:44.95523Z","end":"2026-04-07T00:15:45.134176Z","steps":["trace[1006625432] 'process raft request' (duration: 178.679338ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:47.291473Z","caller":"traceutil/trace.go:171","msg":"trace[158395545] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"105.944193ms","start":"2026-04-07T00:15:47.185499Z","end":"2026-04-07T00:15:47.291443Z","steps":["trace[158395545] 'process raft request' (duration: 95.165516ms)","trace[158395545] 'compare' (duration: 10.642685ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:15:47.291538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.292721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:15:47.291616Z","caller":"traceutil/trace.go:171","msg":"trace[1726632053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4947; }","duration":"114.438924ms","start":"2026-04-07T00:15:47.177159Z","end":"2026-04-07T00:15:47.291597Z","steps":["trace[1726632053] 'agreement among raft nodes before linearized reading' (duration: 114.271601ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:16.962743Z","caller":"traceutil/trace.go:171","msg":"trace[356118201] transaction","detail":"{read_only:false; response_revision:5449; number_of_response:1; }","duration":"208.949521ms","start":"2026-04-07T00:16:16.753765Z","end":"2026-04-07T00:16:16.962715Z","steps":["trace[356118201] 'process raft request' (duration: 153.796857ms)","trace[356118201] 'compare' (duration: 55.051412ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:17:37.030645Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2992} {"level":"info","ts":"2026-04-07T00:17:37.110954Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2992,"took":"78.620781ms","hash":666068737,"current-db-size-bytes":58281984,"current-db-size":"58 MB","current-db-size-in-use-bytes":33656832,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T00:17:37.111008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":666068737,"revision":2992,"compact-revision":-1} {"level":"info","ts":"2026-04-07T00:22:19.414654Z","caller":"traceutil/trace.go:171","msg":"trace[2117424780] transaction","detail":"{read_only:false; response_revision:8177; number_of_response:1; }","duration":"109.276138ms","start":"2026-04-07T00:22:19.30535Z","end":"2026-04-07T00:22:19.414626Z","steps":["trace[2117424780] 'process raft request' (duration: 109.092735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:22:19.568719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.033963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:22:19.568824Z","caller":"traceutil/trace.go:171","msg":"trace[1501624982] linearizableReadLoop","detail":"{readStateIndex:8465; appliedIndex:8464; }","duration":"119.36354ms","start":"2026-04-07T00:22:19.449444Z","end":"2026-04-07T00:22:19.568807Z","steps":["trace[1501624982] 'read index received' (duration: 15.199594ms)","trace[1501624982] 'applied index is now lower than readState.Index' (duration: 104.162576ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:22:19.568919Z","caller":"traceutil/trace.go:171","msg":"trace[1094282356] transaction","detail":"{read_only:false; response_revision:8178; number_of_response:1; }","duration":"149.872188ms","start":"2026-04-07T00:22:19.41903Z","end":"2026-04-07T00:22:19.568902Z","steps":["trace[1094282356] 'process raft request' (duration: 45.578009ms)","trace[1094282356] 'compare' (duration: 103.916281ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:22:19.56905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.552193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-07T00:22:19.569099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.651015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T00:22:19.569136Z","caller":"traceutil/trace.go:171","msg":"trace[814104170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8178; }","duration":"119.683966ms","start":"2026-04-07T00:22:19.449433Z","end":"2026-04-07T00:22:19.569117Z","steps":["trace[814104170] 'agreement among raft nodes before linearized reading' (duration: 119.488592ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:19.569167Z","caller":"traceutil/trace.go:171","msg":"trace[739672680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8178; }","duration":"119.759298ms","start":"2026-04-07T00:22:19.449391Z","end":"2026-04-07T00:22:19.56915Z","steps":["trace[739672680] 'agreement among raft nodes before linearized reading' (duration: 119.569124ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:19.702868Z","caller":"traceutil/trace.go:171","msg":"trace[1858904766] transaction","detail":"{read_only:false; response_revision:8179; number_of_response:1; }","duration":"129.784458ms","start":"2026-04-07T00:22:19.573057Z","end":"2026-04-07T00:22:19.702841Z","steps":["trace[1858904766] 'process raft request' (duration: 129.642085ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:19.759346Z","caller":"traceutil/trace.go:171","msg":"trace[1613745639] transaction","detail":"{read_only:false; response_revision:8180; number_of_response:1; }","duration":"185.847495ms","start":"2026-04-07T00:22:19.573477Z","end":"2026-04-07T00:22:19.759325Z","steps":["trace[1613745639] 'process raft request' (duration: 185.731303ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:19.760553Z","caller":"traceutil/trace.go:171","msg":"trace[1476322818] transaction","detail":"{read_only:false; response_revision:8181; number_of_response:1; }","duration":"186.363304ms","start":"2026-04-07T00:22:19.57416Z","end":"2026-04-07T00:22:19.760523Z","steps":["trace[1476322818] 'process raft request' (duration: 185.13236ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:20.291298Z","caller":"traceutil/trace.go:171","msg":"trace[1629642823] linearizableReadLoop","detail":"{readStateIndex:8472; appliedIndex:8471; }","duration":"114.027993ms","start":"2026-04-07T00:22:20.177245Z","end":"2026-04-07T00:22:20.291273Z","steps":["trace[1629642823] 'read index received' (duration: 54.584167ms)","trace[1629642823] 'applied index is now lower than readState.Index' (duration: 59.442956ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:22:20.291325Z","caller":"traceutil/trace.go:171","msg":"trace[1103488333] transaction","detail":"{read_only:false; response_revision:8184; number_of_response:1; }","duration":"130.848998ms","start":"2026-04-07T00:22:20.160442Z","end":"2026-04-07T00:22:20.291291Z","steps":["trace[1103488333] 'process raft request' (duration: 71.456114ms)","trace[1103488333] 'compare' (duration: 59.233021ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:22:20.291468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.208397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:22:20.291502Z","caller":"traceutil/trace.go:171","msg":"trace[644538058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8184; }","duration":"114.280218ms","start":"2026-04-07T00:22:20.177212Z","end":"2026-04-07T00:22:20.291492Z","steps":["trace[644538058] 'agreement among raft nodes before linearized reading' (duration: 114.150856ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:21.730926Z","caller":"traceutil/trace.go:171","msg":"trace[335730277] linearizableReadLoop","detail":"{readStateIndex:8477; appliedIndex:8476; }","duration":"137.550193ms","start":"2026-04-07T00:22:21.593353Z","end":"2026-04-07T00:22:21.730903Z","steps":["trace[335730277] 'read index received' (duration: 137.464381ms)","trace[335730277] 'applied index is now lower than readState.Index' (duration: 84.062µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:22:21.731055Z","caller":"traceutil/trace.go:171","msg":"trace[562973187] transaction","detail":"{read_only:false; response_revision:8189; number_of_response:1; }","duration":"138.293457ms","start":"2026-04-07T00:22:21.592714Z","end":"2026-04-07T00:22:21.731008Z","steps":["trace[562973187] 'process raft request' (duration: 138.073842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:22:21.731409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.044172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223793"} {"level":"info","ts":"2026-04-07T00:22:21.731497Z","caller":"traceutil/trace.go:171","msg":"trace[837401448] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8189; }","duration":"138.169665ms","start":"2026-04-07T00:22:21.593315Z","end":"2026-04-07T00:22:21.731484Z","steps":["trace[837401448] 'agreement among raft nodes before linearized reading' (duration: 137.738657ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:21.908672Z","caller":"traceutil/trace.go:171","msg":"trace[897995153] transaction","detail":"{read_only:false; response_revision:8191; number_of_response:1; }","duration":"124.947051ms","start":"2026-04-07T00:22:21.783685Z","end":"2026-04-07T00:22:21.908632Z","steps":["trace[897995153] 'process raft request' (duration: 44.253622ms)","trace[897995153] 'compare' (duration: 80.527046ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:22:21.944698Z","caller":"traceutil/trace.go:171","msg":"trace[1168761013] transaction","detail":"{read_only:false; response_revision:8193; number_of_response:1; }","duration":"160.460159ms","start":"2026-04-07T00:22:21.784212Z","end":"2026-04-07T00:22:21.944672Z","steps":["trace[1168761013] 'process raft request' (duration: 160.409338ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:21.944722Z","caller":"traceutil/trace.go:171","msg":"trace[250419828] transaction","detail":"{read_only:false; response_revision:8192; number_of_response:1; }","duration":"160.795725ms","start":"2026-04-07T00:22:21.783891Z","end":"2026-04-07T00:22:21.944687Z","steps":["trace[250419828] 'process raft request' (duration: 160.604011ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:37.037878Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6102} {"level":"info","ts":"2026-04-07T00:22:37.131753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6102,"took":"91.75549ms","hash":3777626368,"current-db-size-bytes":58281984,"current-db-size":"58 MB","current-db-size-in-use-bytes":23465984,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-07T00:22:37.13181Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3777626368,"revision":6102,"compact-revision":2992} {"level":"info","ts":"2026-04-07T00:22:51.689148Z","caller":"traceutil/trace.go:171","msg":"trace[56001123] transaction","detail":"{read_only:false; response_revision:8405; number_of_response:1; }","duration":"100.232328ms","start":"2026-04-07T00:22:51.588891Z","end":"2026-04-07T00:22:51.689123Z","steps":["trace[56001123] 'process raft request' (duration: 100.074255ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:01.559909Z","caller":"traceutil/trace.go:171","msg":"trace[347875591] transaction","detail":"{read_only:false; response_revision:8499; number_of_response:1; }","duration":"211.839972ms","start":"2026-04-07T00:23:01.348046Z","end":"2026-04-07T00:23:01.559886Z","steps":["trace[347875591] 'process raft request' (duration: 211.253841ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:51.358323Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002420.wal"} {"level":"info","ts":"2026-04-07T00:24:03.069962Z","caller":"traceutil/trace.go:171","msg":"trace[347258843] transaction","detail":"{read_only:false; response_revision:9158; number_of_response:1; }","duration":"127.820937ms","start":"2026-04-07T00:24:02.942113Z","end":"2026-04-07T00:24:03.069934Z","steps":["trace[347258843] 'process raft request' (duration: 127.281787ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:24:03.071968Z","caller":"traceutil/trace.go:171","msg":"trace[1218281864] transaction","detail":"{read_only:false; response_revision:9159; number_of_response:1; }","duration":"105.639725ms","start":"2026-04-07T00:24:02.9663Z","end":"2026-04-07T00:24:03.071882Z","steps":["trace[1218281864] 'process raft request' (duration: 105.335029ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:24:15.347587Z","caller":"traceutil/trace.go:171","msg":"trace[26188018] linearizableReadLoop","detail":"{readStateIndex:9551; appliedIndex:9550; }","duration":"169.11555ms","start":"2026-04-07T00:24:15.178445Z","end":"2026-04-07T00:24:15.347561Z","steps":["trace[26188018] 'read index received' (duration: 169.007068ms)","trace[26188018] 'applied index is now lower than readState.Index' (duration: 107.272µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:24:15.347691Z","caller":"traceutil/trace.go:171","msg":"trace[818700266] transaction","detail":"{read_only:false; response_revision:9231; number_of_response:1; }","duration":"234.887002ms","start":"2026-04-07T00:24:15.112784Z","end":"2026-04-07T00:24:15.347671Z","steps":["trace[818700266] 'process raft request' (duration: 234.635627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:24:15.347736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.290694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:24:15.347777Z","caller":"traceutil/trace.go:171","msg":"trace[1981597709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9231; }","duration":"169.383456ms","start":"2026-04-07T00:24:15.178382Z","end":"2026-04-07T00:24:15.347766Z","steps":["trace[1981597709] 'agreement among raft nodes before linearized reading' (duration: 169.277374ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:24:15.355447Z","caller":"traceutil/trace.go:171","msg":"trace[526944740] transaction","detail":"{read_only:false; response_revision:9232; number_of_response:1; }","duration":"172.044669ms","start":"2026-04-07T00:24:15.183373Z","end":"2026-04-07T00:24:15.355418Z","steps":["trace[526944740] 'process raft request' (duration: 171.806354ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:25:16.213643Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T00:25:16.218248Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T00:25:16.218359Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T00:27:37.044738Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8277} {"level":"info","ts":"2026-04-07T00:27:37.232816Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8277,"took":"185.959698ms","hash":3367338255,"current-db-size-bytes":58281984,"current-db-size":"58 MB","current-db-size-in-use-bytes":26525696,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-07T00:27:37.232866Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3367338255,"revision":8277,"compact-revision":6102} {"level":"info","ts":"2026-04-07T00:27:37.392926Z","caller":"traceutil/trace.go:171","msg":"trace[581458646] transaction","detail":"{read_only:false; response_revision:10958; number_of_response:1; }","duration":"110.887335ms","start":"2026-04-07T00:27:37.282012Z","end":"2026-04-07T00:27:37.392899Z","steps":["trace[581458646] 'process raft request' (duration: 110.680361ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:27:37.406854Z","caller":"traceutil/trace.go:171","msg":"trace[1726943156] transaction","detail":"{read_only:false; response_revision:10959; number_of_response:1; }","duration":"123.078837ms","start":"2026-04-07T00:27:37.28374Z","end":"2026-04-07T00:27:37.406818Z","steps":["trace[1726943156] 'process raft request' (duration: 122.859273ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:32:37.05025Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10957} {"level":"info","ts":"2026-04-07T00:32:37.12975Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10957,"took":"77.548862ms","hash":3629316259,"current-db-size-bytes":58281984,"current-db-size":"58 MB","current-db-size-in-use-bytes":28270592,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-07T00:32:37.129823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3629316259,"revision":10957,"compact-revision":8277} {"level":"info","ts":"2026-04-07T00:33:39.516599Z","caller":"traceutil/trace.go:171","msg":"trace[1876693449] transaction","detail":"{read_only:false; response_revision:13423; number_of_response:1; }","duration":"104.839735ms","start":"2026-04-07T00:33:39.411735Z","end":"2026-04-07T00:33:39.516574Z","steps":["trace[1876693449] 'process raft request' (duration: 104.687632ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:33:39.521598Z","caller":"traceutil/trace.go:171","msg":"trace[1396011271] transaction","detail":"{read_only:false; response_revision:13424; number_of_response:1; }","duration":"106.915167ms","start":"2026-04-07T00:33:39.414651Z","end":"2026-04-07T00:33:39.521566Z","steps":["trace[1396011271] 'process raft request' (duration: 106.669312ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:34:16.746828Z","caller":"traceutil/trace.go:171","msg":"trace[510554037] linearizableReadLoop","detail":"{readStateIndex:14097; appliedIndex:14096; }","duration":"147.493616ms","start":"2026-04-07T00:34:16.599311Z","end":"2026-04-07T00:34:16.746805Z","steps":["trace[510554037] 'read index received' (duration: 147.398764ms)","trace[510554037] 'applied index is now lower than readState.Index' (duration: 93.882µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:34:16.746899Z","caller":"traceutil/trace.go:171","msg":"trace[891078588] transaction","detail":"{read_only:false; response_revision:13632; number_of_response:1; }","duration":"200.736015ms","start":"2026-04-07T00:34:16.546124Z","end":"2026-04-07T00:34:16.74686Z","steps":["trace[891078588] 'process raft request' (duration: 200.545511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:34:16.747013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.691389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-4qnjj\" ","response":"range_response_count:1 size:12356"} {"level":"info","ts":"2026-04-07T00:34:16.747044Z","caller":"traceutil/trace.go:171","msg":"trace[457581813] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-4qnjj; range_end:; response_count:1; response_revision:13632; }","duration":"147.768271ms","start":"2026-04-07T00:34:16.599266Z","end":"2026-04-07T00:34:16.747034Z","steps":["trace[457581813] 'agreement among raft nodes before linearized reading' (duration: 147.639339ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:34:16.913689Z","caller":"traceutil/trace.go:171","msg":"trace[202016145] transaction","detail":"{read_only:false; response_revision:13633; number_of_response:1; }","duration":"154.975325ms","start":"2026-04-07T00:34:16.758692Z","end":"2026-04-07T00:34:16.913668Z","steps":["trace[202016145] 'process raft request' (duration: 154.526315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:34:37.196148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.362814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6z866\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-04-07T00:34:37.196248Z","caller":"traceutil/trace.go:171","msg":"trace[2075381717] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6z866; range_end:; response_count:1; response_revision:13934; }","duration":"126.500907ms","start":"2026-04-07T00:34:37.069725Z","end":"2026-04-07T00:34:37.196226Z","steps":["trace[2075381717] 'range keys from in-memory index tree' (duration: 126.192541ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:02.775811Z","caller":"traceutil/trace.go:171","msg":"trace[1676510877] transaction","detail":"{read_only:false; response_revision:14743; number_of_response:1; }","duration":"232.547888ms","start":"2026-04-07T00:36:02.543242Z","end":"2026-04-07T00:36:02.77579Z","steps":["trace[1676510877] 'process raft request' (duration: 142.972195ms)","trace[1676510877] 'compare' (duration: 89.436ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:36:02.775805Z","caller":"traceutil/trace.go:171","msg":"trace[650545573] linearizableReadLoop","detail":"{readStateIndex:15244; appliedIndex:15243; }","duration":"153.895113ms","start":"2026-04-07T00:36:02.621887Z","end":"2026-04-07T00:36:02.775782Z","steps":["trace[650545573] 'read index received' (duration: 64.219298ms)","trace[650545573] 'applied index is now lower than readState.Index' (duration: 89.674695ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:36:02.775982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.087156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-04-07T00:36:02.776022Z","caller":"traceutil/trace.go:171","msg":"trace[873103602] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14743; }","duration":"154.162207ms","start":"2026-04-07T00:36:02.621849Z","end":"2026-04-07T00:36:02.776011Z","steps":["trace[873103602] 'agreement among raft nodes before linearized reading' (duration: 154.008064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:36:02.776211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.457266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-04-07T00:36:02.776286Z","caller":"traceutil/trace.go:171","msg":"trace[1511294487] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14743; }","duration":"136.578618ms","start":"2026-04-07T00:36:02.639689Z","end":"2026-04-07T00:36:02.776267Z","steps":["trace[1511294487] 'agreement among raft nodes before linearized reading' (duration: 136.332614ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:03.379313Z","caller":"traceutil/trace.go:171","msg":"trace[122898637] transaction","detail":"{read_only:false; response_revision:14744; number_of_response:1; }","duration":"113.804344ms","start":"2026-04-07T00:36:03.265477Z","end":"2026-04-07T00:36:03.379281Z","steps":["trace[122898637] 'process raft request' (duration: 113.62681ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:37:37.072486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12999} {"level":"info","ts":"2026-04-07T00:37:37.14547Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12999,"took":"70.293821ms","hash":2467601636,"current-db-size-bytes":58281984,"current-db-size":"58 MB","current-db-size-in-use-bytes":30941184,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T00:37:37.145526Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2467601636,"revision":12999,"compact-revision":10957} {"level":"info","ts":"2026-04-07T00:41:25.338362Z","caller":"traceutil/trace.go:171","msg":"trace[967480997] transaction","detail":"{read_only:false; response_revision:17193; number_of_response:1; }","duration":"155.758075ms","start":"2026-04-07T00:41:25.182579Z","end":"2026-04-07T00:41:25.338337Z","steps":["trace[967480997] 'process raft request' (duration: 155.596612ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:41:25.340703Z","caller":"traceutil/trace.go:171","msg":"trace[771558849] transaction","detail":"{read_only:false; response_revision:17195; number_of_response:1; }","duration":"156.290325ms","start":"2026-04-07T00:41:25.184399Z","end":"2026-04-07T00:41:25.340689Z","steps":["trace[771558849] 'process raft request' (duration: 156.247514ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:41:25.341093Z","caller":"traceutil/trace.go:171","msg":"trace[1540079175] transaction","detail":"{read_only:false; response_revision:17194; number_of_response:1; }","duration":"156.903247ms","start":"2026-04-07T00:41:25.184175Z","end":"2026-04-07T00:41:25.341078Z","steps":["trace[1540079175] 'process raft request' (duration: 156.404907ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:41:31.070176Z","caller":"traceutil/trace.go:171","msg":"trace[119169764] transaction","detail":"{read_only:false; response_revision:17226; number_of_response:1; }","duration":"161.471528ms","start":"2026-04-07T00:41:30.908671Z","end":"2026-04-07T00:41:31.070143Z","steps":["trace[119169764] 'process raft request' (duration: 142.335567ms)","trace[119169764] 'compare' (duration: 18.980918ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:41:31.580282Z","caller":"traceutil/trace.go:171","msg":"trace[1504714668] transaction","detail":"{read_only:false; response_revision:17228; number_of_response:1; }","duration":"217.455264ms","start":"2026-04-07T00:41:31.362802Z","end":"2026-04-07T00:41:31.580257Z","steps":["trace[1504714668] 'process raft request' (duration: 217.316991ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:41:31.586058Z","caller":"traceutil/trace.go:171","msg":"trace[1834984806] linearizableReadLoop","detail":"{readStateIndex:17811; appliedIndex:17809; }","duration":"218.826941ms","start":"2026-04-07T00:41:31.367211Z","end":"2026-04-07T00:41:31.586038Z","steps":["trace[1834984806] 'read index received' (duration: 212.915583ms)","trace[1834984806] 'applied index is now lower than readState.Index' (duration: 5.910728ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:41:31.586106Z","caller":"traceutil/trace.go:171","msg":"trace[1570197575] transaction","detail":"{read_only:false; response_revision:17229; number_of_response:1; }","duration":"223.012755ms","start":"2026-04-07T00:41:31.363072Z","end":"2026-04-07T00:41:31.586085Z","steps":["trace[1570197575] 'process raft request' (duration: 222.869612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:41:31.586246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.018435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-07T00:41:31.586281Z","caller":"traceutil/trace.go:171","msg":"trace[2138381449] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17229; }","duration":"219.062746ms","start":"2026-04-07T00:41:31.367208Z","end":"2026-04-07T00:41:31.58627Z","steps":["trace[2138381449] 'agreement among raft nodes before linearized reading' (duration: 218.939653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:41:32.031822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.616461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:41:32.031944Z","caller":"traceutil/trace.go:171","msg":"trace[1338182208] transaction","detail":"{read_only:false; response_revision:17233; number_of_response:1; }","duration":"314.058619ms","start":"2026-04-07T00:41:31.717875Z","end":"2026-04-07T00:41:32.031934Z","steps":["trace[1338182208] 'process raft request' (duration: 314.037409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:41:32.031977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:41:31.717843Z","time spent":"314.11915ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:41:32.03217Z","caller":"traceutil/trace.go:171","msg":"trace[1315954669] transaction","detail":"{read_only:false; response_revision:17231; number_of_response:1; }","duration":"441.500188ms","start":"2026-04-07T00:41:31.590663Z","end":"2026-04-07T00:41:32.032163Z","steps":["trace[1315954669] 'process raft request' (duration: 272.48908ms)","trace[1315954669] 'compare' (duration: 168.304894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:41:32.032198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:41:31.590649Z","time spent":"441.532959ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:41:32.032257Z","caller":"traceutil/trace.go:171","msg":"trace[1051739577] transaction","detail":"{read_only:false; response_revision:17232; number_of_response:1; }","duration":"441.491127ms","start":"2026-04-07T00:41:31.590762Z","end":"2026-04-07T00:41:32.032253Z","steps":["trace[1051739577] 'process raft request' (duration: 441.11941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:41:32.03228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:41:31.590758Z","time spent":"441.509518ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:41:32.032334Z","caller":"traceutil/trace.go:171","msg":"trace[620948786] linearizableReadLoop","detail":"{readStateIndex:17814; appliedIndex:17811; }","duration":"431.879097ms","start":"2026-04-07T00:41:31.60045Z","end":"2026-04-07T00:41:32.032329Z","steps":["trace[620948786] 'read index received' (duration: 8.51005ms)","trace[620948786] 'applied index is now lower than readState.Index' (duration: 423.368367ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:41:32.03248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.02943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-04-07T00:41:32.032494Z","caller":"traceutil/trace.go:171","msg":"trace[13555651] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:17233; }","duration":"432.06623ms","start":"2026-04-07T00:41:31.600423Z","end":"2026-04-07T00:41:32.032489Z","steps":["trace[13555651] 'agreement among raft nodes before linearized reading' (duration: 431.998349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:41:32.032507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:41:31.600415Z","time spent":"432.089701ms","remote":"127.0.0.1:42106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"warn","ts":"2026-04-07T00:41:32.032606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.7056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:41:32.032619Z","caller":"traceutil/trace.go:171","msg":"trace[1696285858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17233; }","duration":"321.754311ms","start":"2026-04-07T00:41:31.71086Z","end":"2026-04-07T00:41:32.032615Z","steps":["trace[1696285858] 'agreement among raft nodes before linearized reading' (duration: 321.715381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:41:32.03263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:41:31.710838Z","time spent":"321.789482ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T00:41:32.032767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.836599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-07T00:41:32.03278Z","caller":"traceutil/trace.go:171","msg":"trace[1447786169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17233; }","duration":"245.890511ms","start":"2026-04-07T00:41:31.786886Z","end":"2026-04-07T00:41:32.032777Z","steps":["trace[1447786169] 'agreement among raft nodes before linearized reading' (duration: 245.84634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:41:32.032838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.30455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-07T00:41:32.032892Z","caller":"traceutil/trace.go:171","msg":"trace[580353030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17233; }","duration":"236.377111ms","start":"2026-04-07T00:41:31.796497Z","end":"2026-04-07T00:41:32.032874Z","steps":["trace[580353030] 'agreement among raft nodes before linearized reading' (duration: 236.231329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:41:32.033091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.184562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-04-07T00:41:32.033107Z","caller":"traceutil/trace.go:171","msg":"trace[599874745] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:17233; }","duration":"175.226893ms","start":"2026-04-07T00:41:31.857874Z","end":"2026-04-07T00:41:32.033101Z","steps":["trace[599874745] 'agreement among raft nodes before linearized reading' (duration: 175.180792ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:41:32.166259Z","caller":"traceutil/trace.go:171","msg":"trace[208061209] transaction","detail":"{read_only:false; response_revision:17235; number_of_response:1; }","duration":"126.516891ms","start":"2026-04-07T00:41:32.039708Z","end":"2026-04-07T00:41:32.166225Z","steps":["trace[208061209] 'process raft request' (duration: 101.442562ms)","trace[208061209] 'compare' (duration: 24.839045ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:41:32.166413Z","caller":"traceutil/trace.go:171","msg":"trace[230422101] transaction","detail":"{read_only:false; response_revision:17236; number_of_response:1; }","duration":"126.302557ms","start":"2026-04-07T00:41:32.040053Z","end":"2026-04-07T00:41:32.166355Z","steps":["trace[230422101] 'process raft request' (duration: 126.079102ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:42:37.081345Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15358} {"level":"info","ts":"2026-04-07T00:42:37.176644Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15358,"took":"92.33189ms","hash":3918668508,"current-db-size-bytes":58281984,"current-db-size":"58 MB","current-db-size-in-use-bytes":33480704,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T00:42:37.17672Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3918668508,"revision":15358,"compact-revision":12999} {"level":"info","ts":"2026-04-07T00:45:40.33351Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T00:45:40.340243Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-07T00:45:40.34036Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-07T00:47:03.034553Z","caller":"traceutil/trace.go:171","msg":"trace[1233081060] transaction","detail":"{read_only:false; response_revision:19824; number_of_response:1; }","duration":"210.185477ms","start":"2026-04-07T00:47:02.82434Z","end":"2026-04-07T00:47:03.034526Z","steps":["trace[1233081060] 'process raft request' (duration: 143.287735ms)","trace[1233081060] 'compare' (duration: 66.526355ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:47:03.34367Z","caller":"traceutil/trace.go:171","msg":"trace[437772653] transaction","detail":"{read_only:false; response_revision:19825; number_of_response:1; }","duration":"107.93349ms","start":"2026-04-07T00:47:03.235696Z","end":"2026-04-07T00:47:03.343629Z","steps":["trace[437772653] 'process raft request' (duration: 107.693105ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:47:03.49064Z","caller":"traceutil/trace.go:171","msg":"trace[1176158944] transaction","detail":"{read_only:false; response_revision:19827; number_of_response:1; }","duration":"142.607661ms","start":"2026-04-07T00:47:03.347998Z","end":"2026-04-07T00:47:03.490606Z","steps":["trace[1176158944] 'process raft request' (duration: 120.883968ms)","trace[1176158944] 'compare' (duration: 21.533689ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:47:03.831215Z","caller":"traceutil/trace.go:171","msg":"trace[1199201285] transaction","detail":"{read_only:false; response_revision:19828; number_of_response:1; }","duration":"123.596712ms","start":"2026-04-07T00:47:03.707589Z","end":"2026-04-07T00:47:03.831186Z","steps":["trace[1199201285] 'process raft request' (duration: 123.443479ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:47:03.84524Z","caller":"traceutil/trace.go:171","msg":"trace[290661382] linearizableReadLoop","detail":"{readStateIndex:20496; appliedIndex:20494; }","duration":"108.040441ms","start":"2026-04-07T00:47:03.737179Z","end":"2026-04-07T00:47:03.845219Z","steps":["trace[290661382] 'read index received' (duration: 93.91857ms)","trace[290661382] 'applied index is now lower than readState.Index' (duration: 14.121251ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:47:03.845328Z","caller":"traceutil/trace.go:171","msg":"trace[1581478026] transaction","detail":"{read_only:false; response_revision:19829; number_of_response:1; }","duration":"137.661602ms","start":"2026-04-07T00:47:03.707641Z","end":"2026-04-07T00:47:03.845303Z","steps":["trace[1581478026] 'process raft request' (duration: 137.493459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:47:03.845552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.354577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-07T00:47:03.845564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.083943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-07T00:47:03.84561Z","caller":"traceutil/trace.go:171","msg":"trace[951029966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19829; }","duration":"108.45781ms","start":"2026-04-07T00:47:03.737137Z","end":"2026-04-07T00:47:03.845595Z","steps":["trace[951029966] 'agreement among raft nodes before linearized reading' (duration: 108.191935ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:47:03.84562Z","caller":"traceutil/trace.go:171","msg":"trace[900835039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19829; }","duration":"103.145034ms","start":"2026-04-07T00:47:03.74246Z","end":"2026-04-07T00:47:03.845605Z","steps":["trace[900835039] 'agreement among raft nodes before linearized reading' (duration: 102.93654ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:47:03.998332Z","caller":"traceutil/trace.go:171","msg":"trace[313503531] transaction","detail":"{read_only:false; response_revision:19830; number_of_response:1; }","duration":"148.154672ms","start":"2026-04-07T00:47:03.850152Z","end":"2026-04-07T00:47:03.998307Z","steps":["trace[313503531] 'process raft request' (duration: 126.368627ms)","trace[313503531] 'compare' (duration: 21.505289ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:47:04.076497Z","caller":"traceutil/trace.go:171","msg":"trace[565776831] linearizableReadLoop","detail":"{readStateIndex:20499; appliedIndex:20496; }","duration":"178.822053ms","start":"2026-04-07T00:47:03.897651Z","end":"2026-04-07T00:47:04.076473Z","steps":["trace[565776831] 'read index received' (duration: 78.949643ms)","trace[565776831] 'applied index is now lower than readState.Index' (duration: 99.87105ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:47:04.076533Z","caller":"traceutil/trace.go:171","msg":"trace[357786248] transaction","detail":"{read_only:false; response_revision:19831; number_of_response:1; }","duration":"226.260577ms","start":"2026-04-07T00:47:03.850247Z","end":"2026-04-07T00:47:04.076508Z","steps":["trace[357786248] 'process raft request' (duration: 226.019103ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:47:04.076569Z","caller":"traceutil/trace.go:171","msg":"trace[71593901] transaction","detail":"{read_only:false; response_revision:19832; number_of_response:1; }","duration":"226.217767ms","start":"2026-04-07T00:47:03.850316Z","end":"2026-04-07T00:47:04.076534Z","steps":["trace[71593901] 'process raft request' (duration: 226.111935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:47:04.076656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.982546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:47:04.076744Z","caller":"traceutil/trace.go:171","msg":"trace[943545812] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:19832; }","duration":"179.086498ms","start":"2026-04-07T00:47:03.897642Z","end":"2026-04-07T00:47:04.076729Z","steps":["trace[943545812] 'agreement among raft nodes before linearized reading' (duration: 178.924145ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:47:37.086844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17971} {"level":"info","ts":"2026-04-07T00:47:37.19795Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17971,"took":"107.307587ms","hash":727883333,"current-db-size-bytes":58281984,"current-db-size":"58 MB","current-db-size-in-use-bytes":29257728,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T00:47:37.198008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":727883333,"revision":17971,"compact-revision":15358} {"level":"info","ts":"2026-04-07T00:50:57.903437Z","caller":"traceutil/trace.go:171","msg":"trace[923471297] transaction","detail":"{read_only:false; response_revision:22250; number_of_response:1; }","duration":"199.669838ms","start":"2026-04-07T00:50:57.703732Z","end":"2026-04-07T00:50:57.903402Z","steps":["trace[923471297] 'process raft request' (duration: 199.437813ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:50:58.013597Z","caller":"traceutil/trace.go:171","msg":"trace[163789496] transaction","detail":"{read_only:false; response_revision:22251; number_of_response:1; }","duration":"122.063551ms","start":"2026-04-07T00:50:57.891514Z","end":"2026-04-07T00:50:58.013578Z","steps":["trace[163789496] 'process raft request' (duration: 118.111153ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:37.093519Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20083} {"level":"info","ts":"2026-04-07T00:52:37.199058Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20083,"took":"100.647345ms","hash":4020548426,"current-db-size-bytes":58281984,"current-db-size":"58 MB","current-db-size-in-use-bytes":41549824,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-04-07T00:52:37.199146Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4020548426,"revision":20083,"compact-revision":17971} {"level":"info","ts":"2026-04-07T00:54:20.166863Z","caller":"traceutil/trace.go:171","msg":"trace[628254551] transaction","detail":"{read_only:false; response_revision:24302; number_of_response:1; }","duration":"131.51703ms","start":"2026-04-07T00:54:20.035329Z","end":"2026-04-07T00:54:20.166846Z","steps":["trace[628254551] 'process raft request' (duration: 131.413918ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:20.167212Z","caller":"traceutil/trace.go:171","msg":"trace[1604430272] linearizableReadLoop","detail":"{readStateIndex:25095; appliedIndex:25095; }","duration":"128.851377ms","start":"2026-04-07T00:54:20.038353Z","end":"2026-04-07T00:54:20.167204Z","steps":["trace[1604430272] 'read index received' (duration: 128.848877ms)","trace[1604430272] 'applied index is now lower than readState.Index' (duration: 1.97µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:20.167306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.943739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-07T00:54:20.167327Z","caller":"traceutil/trace.go:171","msg":"trace[1825132140] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24302; }","duration":"128.989809ms","start":"2026-04-07T00:54:20.038332Z","end":"2026-04-07T00:54:20.167322Z","steps":["trace[1825132140] 'agreement among raft nodes before linearized reading' (duration: 128.913388ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:20.444824Z","caller":"traceutil/trace.go:171","msg":"trace[1153528403] linearizableReadLoop","detail":"{readStateIndex:25096; appliedIndex:25095; }","duration":"277.567849ms","start":"2026-04-07T00:54:20.167234Z","end":"2026-04-07T00:54:20.444801Z","steps":["trace[1153528403] 'read index received' (duration: 239.263276ms)","trace[1153528403] 'applied index is now lower than readState.Index' (duration: 38.303633ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:20.445061Z","caller":"traceutil/trace.go:171","msg":"trace[422934033] transaction","detail":"{read_only:false; response_revision:24303; number_of_response:1; }","duration":"394.256085ms","start":"2026-04-07T00:54:20.050792Z","end":"2026-04-07T00:54:20.445048Z","steps":["trace[422934033] 'process raft request' (duration: 355.755598ms)","trace[422934033] 'compare' (duration: 38.15785ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:20.445163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:20.050781Z","time spent":"394.314776ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:20.445415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.195189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T00:54:20.445454Z","caller":"traceutil/trace.go:171","msg":"trace[92038003] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:24303; }","duration":"327.234179ms","start":"2026-04-07T00:54:20.118211Z","end":"2026-04-07T00:54:20.445445Z","steps":["trace[92038003] 'agreement among raft nodes before linearized reading' (duration: 327.114597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:20.445483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:20.118168Z","time spent":"327.308501ms","remote":"127.0.0.1:41994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-04-07T00:54:20.445673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.794306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:54:20.44571Z","caller":"traceutil/trace.go:171","msg":"trace[1621353407] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:24303; }","duration":"381.846716ms","start":"2026-04-07T00:54:20.063855Z","end":"2026-04-07T00:54:20.445701Z","steps":["trace[1621353407] 'agreement among raft nodes before linearized reading' (duration: 381.787955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:20.445739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:20.063848Z","time spent":"381.883547ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":31,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:54:20.47649Z","caller":"traceutil/trace.go:171","msg":"trace[2141328563] transaction","detail":"{read_only:false; response_revision:24304; number_of_response:1; }","duration":"305.455745ms","start":"2026-04-07T00:54:20.171019Z","end":"2026-04-07T00:54:20.476474Z","steps":["trace[2141328563] 'process raft request' (duration: 305.376383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:20.476647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.18147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:54:20.476702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.41092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:54:20.476764Z","caller":"traceutil/trace.go:171","msg":"trace[1884820757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24304; }","duration":"300.337763ms","start":"2026-04-07T00:54:20.176401Z","end":"2026-04-07T00:54:20.476738Z","steps":["trace[1884820757] 'agreement among raft nodes before linearized reading' (duration: 300.095648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:20.47678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.306324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-04-07T00:54:20.476796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.369664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"warn","ts":"2026-04-07T00:54:20.476825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:20.176385Z","time spent":"300.427474ms","remote":"127.0.0.1:41982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T00:54:20.47682Z","caller":"traceutil/trace.go:171","msg":"trace[147701069] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24304; }","duration":"149.357015ms","start":"2026-04-07T00:54:20.32745Z","end":"2026-04-07T00:54:20.476807Z","steps":["trace[147701069] 'agreement among raft nodes before linearized reading' (duration: 149.280474ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:20.476818Z","caller":"traceutil/trace.go:171","msg":"trace[1887431937] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:24304; }","duration":"227.586054ms","start":"2026-04-07T00:54:20.249204Z","end":"2026-04-07T00:54:20.47679Z","steps":["trace[1887431937] 'agreement among raft nodes before linearized reading' (duration: 227.3813ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:20.47687Z","caller":"traceutil/trace.go:171","msg":"trace[1200465318] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24304; }","duration":"160.472857ms","start":"2026-04-07T00:54:20.316381Z","end":"2026-04-07T00:54:20.476854Z","steps":["trace[1200465318] 'agreement among raft nodes before linearized reading' (duration: 160.170531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:20.476896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.304834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:54:20.476977Z","caller":"traceutil/trace.go:171","msg":"trace[1342243213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24304; }","duration":"150.412647ms","start":"2026-04-07T00:54:20.326545Z","end":"2026-04-07T00:54:20.476958Z","steps":["trace[1342243213] 'agreement among raft nodes before linearized reading' (duration: 150.209503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:20.476689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.476428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-07T00:54:20.47714Z","caller":"traceutil/trace.go:171","msg":"trace[592988478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24304; }","duration":"284.957516ms","start":"2026-04-07T00:54:20.192173Z","end":"2026-04-07T00:54:20.477131Z","steps":["trace[592988478] 'agreement among raft nodes before linearized reading' (duration: 284.434676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:20.477234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.556047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-07T00:54:20.477354Z","caller":"traceutil/trace.go:171","msg":"trace[895182497] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:24304; }","duration":"185.723899ms","start":"2026-04-07T00:54:20.291609Z","end":"2026-04-07T00:54:20.477332Z","steps":["trace[895182497] 'agreement among raft nodes before linearized reading' (duration: 185.072797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:20.477362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:20.170993Z","time spent":"305.543827ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:20.651497Z","caller":"traceutil/trace.go:171","msg":"trace[812143194] transaction","detail":"{read_only:false; response_revision:24305; number_of_response:1; }","duration":"171.20282ms","start":"2026-04-07T00:54:20.480272Z","end":"2026-04-07T00:54:20.651475Z","steps":["trace[812143194] 'process raft request' (duration: 169.608219ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:20.651954Z","caller":"traceutil/trace.go:171","msg":"trace[1172371494] transaction","detail":"{read_only:false; response_revision:24307; number_of_response:1; }","duration":"170.001317ms","start":"2026-04-07T00:54:20.481937Z","end":"2026-04-07T00:54:20.651938Z","steps":["trace[1172371494] 'process raft request' (duration: 169.833844ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:20.65197Z","caller":"traceutil/trace.go:171","msg":"trace[1074571618] transaction","detail":"{read_only:false; response_revision:24306; number_of_response:1; }","duration":"170.115829ms","start":"2026-04-07T00:54:20.481827Z","end":"2026-04-07T00:54:20.651943Z","steps":["trace[1074571618] 'process raft request' (duration: 169.577168ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:20.65203Z","caller":"traceutil/trace.go:171","msg":"trace[1466468613] linearizableReadLoop","detail":"{readStateIndex:25101; appliedIndex:25098; }","duration":"128.177934ms","start":"2026-04-07T00:54:20.523838Z","end":"2026-04-07T00:54:20.652016Z","steps":["trace[1466468613] 'read index received' (duration: 126.027921ms)","trace[1466468613] 'applied index is now lower than readState.Index' (duration: 2.148633ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:20.652239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.397365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"warn","ts":"2026-04-07T00:54:20.652265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.134584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-04-07T00:54:20.65228Z","caller":"traceutil/trace.go:171","msg":"trace[1656260787] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24308; }","duration":"167.473647ms","start":"2026-04-07T00:54:20.484797Z","end":"2026-04-07T00:54:20.65227Z","steps":["trace[1656260787] 'agreement among raft nodes before linearized reading' (duration: 167.314854ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:20.6523Z","caller":"traceutil/trace.go:171","msg":"trace[1332294083] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24308; }","duration":"119.220636ms","start":"2026-04-07T00:54:20.533067Z","end":"2026-04-07T00:54:20.652288Z","steps":["trace[1332294083] 'agreement among raft nodes before linearized reading' (duration: 119.102844ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:20.651985Z","caller":"traceutil/trace.go:171","msg":"trace[764092466] transaction","detail":"{read_only:false; response_revision:24308; number_of_response:1; }","duration":"127.669994ms","start":"2026-04-07T00:54:20.524292Z","end":"2026-04-07T00:54:20.651962Z","steps":["trace[764092466] 'process raft request' (duration: 127.593552ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:25.095062Z","caller":"traceutil/trace.go:171","msg":"trace[902278323] transaction","detail":"{read_only:false; response_revision:24340; number_of_response:1; }","duration":"115.191465ms","start":"2026-04-07T00:54:24.979846Z","end":"2026-04-07T00:54:25.095038Z","steps":["trace[902278323] 'process raft request' (duration: 115.035382ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:25.332301Z","caller":"traceutil/trace.go:171","msg":"trace[1361047211] transaction","detail":"{read_only:false; response_revision:24341; number_of_response:1; }","duration":"262.756853ms","start":"2026-04-07T00:54:25.069522Z","end":"2026-04-07T00:54:25.332278Z","steps":["trace[1361047211] 'process raft request' (duration: 262.188372ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:25.332347Z","caller":"traceutil/trace.go:171","msg":"trace[416994750] linearizableReadLoop","detail":"{readStateIndex:25136; appliedIndex:25134; }","duration":"117.176043ms","start":"2026-04-07T00:54:25.215141Z","end":"2026-04-07T00:54:25.332317Z","steps":["trace[416994750] 'read index received' (duration: 116.490189ms)","trace[416994750] 'applied index is now lower than readState.Index' (duration: 684.694µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:25.332493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.336737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:54:25.332537Z","caller":"traceutil/trace.go:171","msg":"trace[468591985] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:24342; }","duration":"117.424648ms","start":"2026-04-07T00:54:25.215099Z","end":"2026-04-07T00:54:25.332523Z","steps":["trace[468591985] 'agreement among raft nodes before linearized reading' (duration: 117.334826ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:25.332565Z","caller":"traceutil/trace.go:171","msg":"trace[1565783081] transaction","detail":"{read_only:false; response_revision:24342; number_of_response:1; }","duration":"250.743284ms","start":"2026-04-07T00:54:25.08179Z","end":"2026-04-07T00:54:25.332534Z","steps":["trace[1565783081] 'process raft request' (duration: 250.426668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:25.728432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.710404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:25.728734Z","caller":"traceutil/trace.go:171","msg":"trace[1556248920] transaction","detail":"{read_only:false; response_revision:24343; number_of_response:1; }","duration":"278.319884ms","start":"2026-04-07T00:54:25.450393Z","end":"2026-04-07T00:54:25.728713Z","steps":["trace[1556248920] 'process raft request' (duration: 141.249093ms)","trace[1556248920] 'compare' (duration: 136.092732ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:25.728789Z","caller":"traceutil/trace.go:171","msg":"trace[1772075942] linearizableReadLoop","detail":"{readStateIndex:25137; appliedIndex:25136; }","duration":"191.990695ms","start":"2026-04-07T00:54:25.536764Z","end":"2026-04-07T00:54:25.728755Z","steps":["trace[1772075942] 'read index received' (duration: 54.933034ms)","trace[1772075942] 'applied index is now lower than readState.Index' (duration: 137.052161ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:25.862733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.200954ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-07T00:54:25.863232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.459224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-07T00:54:25.863289Z","caller":"traceutil/trace.go:171","msg":"trace[1551929493] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24343; }","duration":"326.545515ms","start":"2026-04-07T00:54:25.536723Z","end":"2026-04-07T00:54:25.863269Z","steps":["trace[1551929493] 'agreement among raft nodes before linearized reading' (duration: 192.133428ms)","trace[1551929493] 'range keys from in-memory index tree' (duration: 134.272395ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:25.863346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:25.536705Z","time spent":"326.629488ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T00:54:25.864352Z","caller":"traceutil/trace.go:171","msg":"trace[712170267] linearizableReadLoop","detail":"{readStateIndex:25139; appliedIndex:25137; }","duration":"135.467368ms","start":"2026-04-07T00:54:25.728868Z","end":"2026-04-07T00:54:25.864335Z","steps":["trace[712170267] 'read index received' (duration: 119.44111ms)","trace[712170267] 'applied index is now lower than readState.Index' (duration: 16.024678ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:25.864454Z","caller":"traceutil/trace.go:171","msg":"trace[1260126369] transaction","detail":"{read_only:false; response_revision:24344; number_of_response:1; }","duration":"223.938761ms","start":"2026-04-07T00:54:25.640455Z","end":"2026-04-07T00:54:25.864393Z","steps":["trace[1260126369] 'process raft request' (duration: 222.369001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:25.866318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.169477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:25.866373Z","caller":"traceutil/trace.go:171","msg":"trace[1672641806] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:24344; }","duration":"216.258008ms","start":"2026-04-07T00:54:25.650102Z","end":"2026-04-07T00:54:25.86636Z","steps":["trace[1672641806] 'agreement among raft nodes before linearized reading' (duration: 214.287688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:25.866753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.941824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-04-07T00:54:25.866802Z","caller":"traceutil/trace.go:171","msg":"trace[673284915] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:24344; }","duration":"307.989515ms","start":"2026-04-07T00:54:25.558799Z","end":"2026-04-07T00:54:25.866788Z","steps":["trace[673284915] 'agreement among raft nodes before linearized reading' (duration: 305.644988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:25.866849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:25.558779Z","time spent":"308.058837ms","remote":"127.0.0.1:42106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" "} {"level":"warn","ts":"2026-04-07T00:54:25.867041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.701491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:25.867083Z","caller":"traceutil/trace.go:171","msg":"trace[1141297842] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:24344; }","duration":"278.772223ms","start":"2026-04-07T00:54:25.588301Z","end":"2026-04-07T00:54:25.867073Z","steps":["trace[1141297842] 'agreement among raft nodes before linearized reading' (duration: 276.13325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:25.915178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.651193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:25.915295Z","caller":"traceutil/trace.go:171","msg":"trace[1759528743] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24345; }","duration":"131.815035ms","start":"2026-04-07T00:54:25.78345Z","end":"2026-04-07T00:54:25.915265Z","steps":["trace[1759528743] 'agreement among raft nodes before linearized reading' (duration: 131.607851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:26.093722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.956769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:26.093856Z","caller":"traceutil/trace.go:171","msg":"trace[2026272966] transaction","detail":"{read_only:false; response_revision:24347; number_of_response:1; }","duration":"153.237953ms","start":"2026-04-07T00:54:25.940593Z","end":"2026-04-07T00:54:26.093831Z","steps":["trace[2026272966] 'process raft request' (duration: 27.07345ms)","trace[2026272966] 'compare' (duration: 125.455169ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:26.403129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.396922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-07T00:54:26.403218Z","caller":"traceutil/trace.go:171","msg":"trace[1583554433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24347; }","duration":"200.521995ms","start":"2026-04-07T00:54:26.202676Z","end":"2026-04-07T00:54:26.403198Z","steps":["trace[1583554433] 'range keys from in-memory index tree' (duration: 200.193288ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:26.511668Z","caller":"traceutil/trace.go:171","msg":"trace[1730947139] transaction","detail":"{read_only:false; response_revision:24348; number_of_response:1; }","duration":"104.323108ms","start":"2026-04-07T00:54:26.407318Z","end":"2026-04-07T00:54:26.511641Z","steps":["trace[1730947139] 'process raft request' (duration: 103.671485ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:26.829741Z","caller":"traceutil/trace.go:171","msg":"trace[1899648605] transaction","detail":"{read_only:false; response_revision:24350; number_of_response:1; }","duration":"130.063182ms","start":"2026-04-07T00:54:26.699641Z","end":"2026-04-07T00:54:26.829704Z","steps":["trace[1899648605] 'process raft request' (duration: 129.857947ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:27.02685Z","caller":"traceutil/trace.go:171","msg":"trace[1157845346] transaction","detail":"{read_only:false; response_revision:24353; number_of_response:1; }","duration":"260.0042ms","start":"2026-04-07T00:54:26.766821Z","end":"2026-04-07T00:54:27.026825Z","steps":["trace[1157845346] 'process raft request' (duration: 259.953859ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:27.027057Z","caller":"traceutil/trace.go:171","msg":"trace[618143118] transaction","detail":"{read_only:false; response_revision:24351; number_of_response:1; }","duration":"327.171477ms","start":"2026-04-07T00:54:26.699854Z","end":"2026-04-07T00:54:27.027025Z","steps":["trace[618143118] 'process raft request' (duration: 326.366611ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:27.027109Z","caller":"traceutil/trace.go:171","msg":"trace[749710062] transaction","detail":"{read_only:false; response_revision:24352; number_of_response:1; }","duration":"326.513244ms","start":"2026-04-07T00:54:26.70056Z","end":"2026-04-07T00:54:27.027073Z","steps":["trace[749710062] 'process raft request' (duration: 326.108146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:27.02721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:26.699839Z","time spent":"327.296759ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:27.027322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:26.700553Z","time spent":"326.670667ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:27.076757Z","caller":"traceutil/trace.go:171","msg":"trace[1215448036] transaction","detail":"{read_only:false; response_revision:24355; number_of_response:1; }","duration":"210.361501ms","start":"2026-04-07T00:54:26.866372Z","end":"2026-04-07T00:54:27.076734Z","steps":["trace[1215448036] 'process raft request' (duration: 210.238478ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:27.077181Z","caller":"traceutil/trace.go:171","msg":"trace[1911096654] transaction","detail":"{read_only:false; response_revision:24354; number_of_response:1; }","duration":"210.952793ms","start":"2026-04-07T00:54:26.865728Z","end":"2026-04-07T00:54:27.076681Z","steps":["trace[1911096654] 'process raft request' (duration: 210.726598ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:27.411653Z","caller":"traceutil/trace.go:171","msg":"trace[1746169532] transaction","detail":"{read_only:false; response_revision:24356; number_of_response:1; }","duration":"311.038086ms","start":"2026-04-07T00:54:27.100593Z","end":"2026-04-07T00:54:27.411631Z","steps":["trace[1746169532] 'process raft request' (duration: 309.504855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:27.411793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:27.100579Z","time spent":"311.140378ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:27.445409Z","caller":"traceutil/trace.go:171","msg":"trace[1931300290] transaction","detail":"{read_only:false; response_revision:24357; number_of_response:1; }","duration":"102.988692ms","start":"2026-04-07T00:54:27.342391Z","end":"2026-04-07T00:54:27.44538Z","steps":["trace[1931300290] 'process raft request' (duration: 102.787278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:27.713791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.96116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:27.713849Z","caller":"traceutil/trace.go:171","msg":"trace[769317186] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24357; }","duration":"112.070242ms","start":"2026-04-07T00:54:27.601765Z","end":"2026-04-07T00:54:27.713836Z","steps":["trace[769317186] 'count revisions from in-memory index tree' (duration: 111.861868ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:28.050833Z","caller":"traceutil/trace.go:171","msg":"trace[1889775978] linearizableReadLoop","detail":"{readStateIndex:25154; appliedIndex:25153; }","duration":"126.52502ms","start":"2026-04-07T00:54:27.924282Z","end":"2026-04-07T00:54:28.050807Z","steps":["trace[1889775978] 'read index received' (duration: 126.283296ms)","trace[1889775978] 'applied index is now lower than readState.Index' (duration: 240.894µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:28.051152Z","caller":"traceutil/trace.go:171","msg":"trace[512520878] transaction","detail":"{read_only:false; response_revision:24359; number_of_response:1; }","duration":"130.213064ms","start":"2026-04-07T00:54:27.920925Z","end":"2026-04-07T00:54:28.051138Z","steps":["trace[512520878] 'process raft request' (duration: 129.711704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:28.05141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.119942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:28.051455Z","caller":"traceutil/trace.go:171","msg":"trace[1371667691] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:24359; }","duration":"127.197783ms","start":"2026-04-07T00:54:27.924246Z","end":"2026-04-07T00:54:28.051444Z","steps":["trace[1371667691] 'agreement among raft nodes before linearized reading' (duration: 127.110531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:28.051634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.936769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:54:28.05167Z","caller":"traceutil/trace.go:171","msg":"trace[234266702] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:24359; }","duration":"112.97632ms","start":"2026-04-07T00:54:27.938686Z","end":"2026-04-07T00:54:28.051662Z","steps":["trace[234266702] 'agreement among raft nodes before linearized reading' (duration: 112.914189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:28.052054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.193345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-07T00:54:28.052097Z","caller":"traceutil/trace.go:171","msg":"trace[1429388839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24359; }","duration":"101.257586ms","start":"2026-04-07T00:54:27.950827Z","end":"2026-04-07T00:54:28.052085Z","steps":["trace[1429388839] 'agreement among raft nodes before linearized reading' (duration: 101.127314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:28.286895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.191796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:28.286985Z","caller":"traceutil/trace.go:171","msg":"trace[717620265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24360; }","duration":"111.357839ms","start":"2026-04-07T00:54:28.175607Z","end":"2026-04-07T00:54:28.286965Z","steps":["trace[717620265] 'range keys from in-memory index tree' (duration: 111.112424ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:28.887902Z","caller":"traceutil/trace.go:171","msg":"trace[1388379736] linearizableReadLoop","detail":"{readStateIndex:25157; appliedIndex:25156; }","duration":"313.889913ms","start":"2026-04-07T00:54:28.573969Z","end":"2026-04-07T00:54:28.887859Z","steps":["trace[1388379736] 'read index received' (duration: 313.72608ms)","trace[1388379736] 'applied index is now lower than readState.Index' (duration: 162.723µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:28.888019Z","caller":"traceutil/trace.go:171","msg":"trace[1796170075] transaction","detail":"{read_only:false; response_revision:24362; number_of_response:1; }","duration":"335.83995ms","start":"2026-04-07T00:54:28.552143Z","end":"2026-04-07T00:54:28.887983Z","steps":["trace[1796170075] 'process raft request' (duration: 335.547645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:28.888052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.054446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:28.888097Z","caller":"traceutil/trace.go:171","msg":"trace[682935223] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:24362; }","duration":"314.151667ms","start":"2026-04-07T00:54:28.573932Z","end":"2026-04-07T00:54:28.888084Z","steps":["trace[682935223] 'agreement among raft nodes before linearized reading' (duration: 314.058286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:28.88814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:28.573915Z","time spent":"314.216129ms","remote":"127.0.0.1:37638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:54:28.888157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:28.552116Z","time spent":"335.974453ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:28.908713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.303001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:54:28.908781Z","caller":"traceutil/trace.go:171","msg":"trace[29412518] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:24363; }","duration":"197.429902ms","start":"2026-04-07T00:54:28.711338Z","end":"2026-04-07T00:54:28.908768Z","steps":["trace[29412518] 'agreement among raft nodes before linearized reading' (duration: 197.28774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:29.154968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.021932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:29.15504Z","caller":"traceutil/trace.go:171","msg":"trace[782077121] linearizableReadLoop","detail":"{readStateIndex:25159; appliedIndex:25158; }","duration":"122.603163ms","start":"2026-04-07T00:54:29.032425Z","end":"2026-04-07T00:54:29.155028Z","steps":["trace[782077121] 'read index received' (duration: 14.578441ms)","trace[782077121] 'applied index is now lower than readState.Index' (duration: 108.023882ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:29.155163Z","caller":"traceutil/trace.go:171","msg":"trace[560330839] transaction","detail":"{read_only:false; response_revision:24364; number_of_response:1; }","duration":"242.129082ms","start":"2026-04-07T00:54:28.913027Z","end":"2026-04-07T00:54:29.155156Z","steps":["trace[560330839] 'process raft request' (duration: 133.850885ms)","trace[560330839] 'compare' (duration: 107.669106ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:29.155338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.920599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:54:29.155352Z","caller":"traceutil/trace.go:171","msg":"trace[1293980247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24364; }","duration":"122.96355ms","start":"2026-04-07T00:54:29.032384Z","end":"2026-04-07T00:54:29.155347Z","steps":["trace[1293980247] 'agreement among raft nodes before linearized reading' (duration: 122.883229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:29.155443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.027121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-07T00:54:29.155458Z","caller":"traceutil/trace.go:171","msg":"trace[160827696] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24364; }","duration":"123.069892ms","start":"2026-04-07T00:54:29.032384Z","end":"2026-04-07T00:54:29.155453Z","steps":["trace[160827696] 'agreement among raft nodes before linearized reading' (duration: 123.029132ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:29.194645Z","caller":"traceutil/trace.go:171","msg":"trace[1993453442] transaction","detail":"{read_only:false; response_revision:24365; number_of_response:1; }","duration":"161.17809ms","start":"2026-04-07T00:54:29.033437Z","end":"2026-04-07T00:54:29.194615Z","steps":["trace[1993453442] 'process raft request' (duration: 160.977256ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:29.528895Z","caller":"traceutil/trace.go:171","msg":"trace[349389780] transaction","detail":"{read_only:false; response_revision:24366; number_of_response:1; }","duration":"445.30381ms","start":"2026-04-07T00:54:29.083553Z","end":"2026-04-07T00:54:29.528856Z","steps":["trace[349389780] 'process raft request' (duration: 436.491305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:29.529086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:29.083535Z","time spent":"445.453514ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:29.529202Z","caller":"traceutil/trace.go:171","msg":"trace[555774939] transaction","detail":"{read_only:false; response_revision:24367; number_of_response:1; }","duration":"443.680268ms","start":"2026-04-07T00:54:29.085495Z","end":"2026-04-07T00:54:29.529176Z","steps":["trace[555774939] 'process raft request' (duration: 443.318831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:29.52938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:29.085393Z","time spent":"443.884923ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:29.529595Z","caller":"traceutil/trace.go:171","msg":"trace[1522955254] linearizableReadLoop","detail":"{readStateIndex:25162; appliedIndex:25159; }","duration":"374.526391ms","start":"2026-04-07T00:54:29.155054Z","end":"2026-04-07T00:54:29.52958Z","steps":["trace[1522955254] 'read index received' (duration: 39.482806ms)","trace[1522955254] 'applied index is now lower than readState.Index' (duration: 335.042615ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:29.529614Z","caller":"traceutil/trace.go:171","msg":"trace[776697512] transaction","detail":"{read_only:false; response_revision:24368; number_of_response:1; }","duration":"369.97639ms","start":"2026-04-07T00:54:29.159613Z","end":"2026-04-07T00:54:29.529589Z","steps":["trace[776697512] 'process raft request' (duration: 369.463909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:29.529772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:29.159585Z","time spent":"370.123663ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:29.529938Z","caller":"traceutil/trace.go:171","msg":"trace[1818131523] transaction","detail":"{read_only:false; response_revision:24369; number_of_response:1; }","duration":"368.631804ms","start":"2026-04-07T00:54:29.161288Z","end":"2026-04-07T00:54:29.529919Z","steps":["trace[1818131523] 'process raft request' (duration: 368.230136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:29.530015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:29.16128Z","time spent":"368.698905ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:29.530078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.445079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274786"} {"level":"info","ts":"2026-04-07T00:54:29.530119Z","caller":"traceutil/trace.go:171","msg":"trace[1959349030] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:24369; }","duration":"388.53026ms","start":"2026-04-07T00:54:29.141577Z","end":"2026-04-07T00:54:29.530107Z","steps":["trace[1959349030] 'agreement among raft nodes before linearized reading' (duration: 388.082611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:29.530148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:29.141552Z","time spent":"388.591312ms","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":32,"response size":274809,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"warn","ts":"2026-04-07T00:54:29.812545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.152652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:29.812762Z","caller":"traceutil/trace.go:171","msg":"trace[948496611] transaction","detail":"{read_only:false; response_revision:24370; number_of_response:1; }","duration":"393.942698ms","start":"2026-04-07T00:54:29.418799Z","end":"2026-04-07T00:54:29.812742Z","steps":["trace[948496611] 'process raft request' (duration: 244.49279ms)","trace[948496611] 'compare' (duration: 148.604391ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:29.812825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:29.418764Z","time spent":"394.040551ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:29.813161Z","caller":"traceutil/trace.go:171","msg":"trace[1624854219] linearizableReadLoop","detail":"{readStateIndex:25166; appliedIndex:25164; }","duration":"283.486177ms","start":"2026-04-07T00:54:29.529629Z","end":"2026-04-07T00:54:29.813115Z","steps":["trace[1624854219] 'read index received' (duration: 133.516128ms)","trace[1624854219] 'applied index is now lower than readState.Index' (duration: 149.968789ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:29.813325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.33039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:29.813353Z","caller":"traceutil/trace.go:171","msg":"trace[1346248211] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:24371; }","duration":"596.389611ms","start":"2026-04-07T00:54:29.216958Z","end":"2026-04-07T00:54:29.813347Z","steps":["trace[1346248211] 'agreement among raft nodes before linearized reading' (duration: 596.34235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:29.813379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:29.21694Z","time spent":"596.433312ms","remote":"127.0.0.1:42166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:54:29.813604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.057661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:54:29.813628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.952879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:29.813701Z","caller":"traceutil/trace.go:171","msg":"trace[650328419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24371; }","duration":"635.084602ms","start":"2026-04-07T00:54:29.178598Z","end":"2026-04-07T00:54:29.813683Z","steps":["trace[650328419] 'agreement among raft nodes before linearized reading' (duration: 634.854267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:29.813773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:29.178578Z","time spent":"635.182074ms","remote":"127.0.0.1:41982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T00:54:29.81364Z","caller":"traceutil/trace.go:171","msg":"trace[1156784980] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:24371; }","duration":"129.145823ms","start":"2026-04-07T00:54:29.684484Z","end":"2026-04-07T00:54:29.81363Z","steps":["trace[1156784980] 'agreement among raft nodes before linearized reading' (duration: 129.091622ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:29.813278Z","caller":"traceutil/trace.go:171","msg":"trace[662087550] transaction","detail":"{read_only:false; response_revision:24371; number_of_response:1; }","duration":"345.332739ms","start":"2026-04-07T00:54:29.467937Z","end":"2026-04-07T00:54:29.81327Z","steps":["trace[662087550] 'process raft request' (duration: 344.733717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:29.814747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:29.467909Z","time spent":"346.744347ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:29.813249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.226825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:29.814876Z","caller":"traceutil/trace.go:171","msg":"trace[572902209] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:24371; }","duration":"492.876348ms","start":"2026-04-07T00:54:29.321988Z","end":"2026-04-07T00:54:29.814864Z","steps":["trace[572902209] 'agreement among raft nodes before linearized reading' (duration: 491.221595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:29.814916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:29.321974Z","time spent":"492.931539ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:54:30.106168Z","caller":"traceutil/trace.go:171","msg":"trace[994778933] linearizableReadLoop","detail":"{readStateIndex:25167; appliedIndex:25166; }","duration":"189.089947ms","start":"2026-04-07T00:54:29.917049Z","end":"2026-04-07T00:54:30.106139Z","steps":["trace[994778933] 'read index received' (duration: 135.865276ms)","trace[994778933] 'applied index is now lower than readState.Index' (duration: 53.22321ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:30.106516Z","caller":"traceutil/trace.go:171","msg":"trace[1403862215] transaction","detail":"{read_only:false; response_revision:24372; number_of_response:1; }","duration":"197.644608ms","start":"2026-04-07T00:54:29.908857Z","end":"2026-04-07T00:54:30.106502Z","steps":["trace[1403862215] 'process raft request' (duration: 144.06557ms)","trace[1403862215] 'compare' (duration: 52.801561ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:30.106668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.597497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-04-07T00:54:30.106745Z","caller":"traceutil/trace.go:171","msg":"trace[555154926] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24372; }","duration":"189.705499ms","start":"2026-04-07T00:54:29.917022Z","end":"2026-04-07T00:54:30.106727Z","steps":["trace[555154926] 'agreement among raft nodes before linearized reading' (duration: 189.500695ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:30.258756Z","caller":"traceutil/trace.go:171","msg":"trace[292954797] transaction","detail":"{read_only:false; response_revision:24373; number_of_response:1; }","duration":"195.890202ms","start":"2026-04-07T00:54:30.062813Z","end":"2026-04-07T00:54:30.258703Z","steps":["trace[292954797] 'process raft request' (duration: 153.287054ms)","trace[292954797] 'compare' (duration: 42.120929ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:30.258594Z","caller":"traceutil/trace.go:171","msg":"trace[398350027] linearizableReadLoop","detail":"{readStateIndex:25168; appliedIndex:25167; }","duration":"152.319816ms","start":"2026-04-07T00:54:30.106236Z","end":"2026-04-07T00:54:30.258556Z","steps":["trace[398350027] 'read index received' (duration: 109.705527ms)","trace[398350027] 'applied index is now lower than readState.Index' (duration: 42.611749ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:30.259088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.635793ms","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:6"} {"level":"warn","ts":"2026-04-07T00:54:30.259115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.894963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-07T00:54:30.259168Z","caller":"traceutil/trace.go:171","msg":"trace[1051168029] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:24373; }","duration":"254.769355ms","start":"2026-04-07T00:54:30.004377Z","end":"2026-04-07T00:54:30.259146Z","steps":["trace[1051168029] 'agreement among raft nodes before linearized reading' (duration: 254.423288ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:30.259174Z","caller":"traceutil/trace.go:171","msg":"trace[1925781121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24373; }","duration":"190.989104ms","start":"2026-04-07T00:54:30.068171Z","end":"2026-04-07T00:54:30.25916Z","steps":["trace[1925781121] 'agreement among raft nodes before linearized reading' (duration: 190.583096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:30.260196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.531749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T00:54:30.260257Z","caller":"traceutil/trace.go:171","msg":"trace[368601977] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:24373; }","duration":"141.641411ms","start":"2026-04-07T00:54:30.118597Z","end":"2026-04-07T00:54:30.260238Z","steps":["trace[368601977] 'agreement among raft nodes before linearized reading' (duration: 141.422596ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:41.430249Z","caller":"traceutil/trace.go:171","msg":"trace[1050396900] linearizableReadLoop","detail":"{readStateIndex:25308; appliedIndex:25307; }","duration":"129.699955ms","start":"2026-04-07T00:54:41.300515Z","end":"2026-04-07T00:54:41.430215Z","steps":["trace[1050396900] 'read index received' (duration: 129.380579ms)","trace[1050396900] 'applied index is now lower than readState.Index' (duration: 317.706µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:41.430393Z","caller":"traceutil/trace.go:171","msg":"trace[1867657743] transaction","detail":"{read_only:false; response_revision:24510; number_of_response:1; }","duration":"201.66821ms","start":"2026-04-07T00:54:41.228681Z","end":"2026-04-07T00:54:41.430349Z","steps":["trace[1867657743] 'process raft request' (duration: 201.310333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:41.430583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.037373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-84406942-7c6a-4e37-a008-b2aa2400ed99\" ","response":"range_response_count:1 size:3642"} {"level":"info","ts":"2026-04-07T00:54:41.430695Z","caller":"traceutil/trace.go:171","msg":"trace[2084600831] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-84406942-7c6a-4e37-a008-b2aa2400ed99; range_end:; response_count:1; response_revision:24510; }","duration":"130.203436ms","start":"2026-04-07T00:54:41.300472Z","end":"2026-04-07T00:54:41.430675Z","steps":["trace[2084600831] 'agreement among raft nodes before linearized reading' (duration: 129.87052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:41.794181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.677032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:41.794365Z","caller":"traceutil/trace.go:171","msg":"trace[16874095] transaction","detail":"{read_only:false; response_revision:24511; number_of_response:1; }","duration":"217.344382ms","start":"2026-04-07T00:54:41.577002Z","end":"2026-04-07T00:54:41.794346Z","steps":["trace[16874095] 'process raft request' (duration: 39.414785ms)","trace[16874095] 'compare' (duration: 177.522139ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:41.794546Z","caller":"traceutil/trace.go:171","msg":"trace[741436869] linearizableReadLoop","detail":"{readStateIndex:25310; appliedIndex:25308; }","duration":"199.959167ms","start":"2026-04-07T00:54:41.594553Z","end":"2026-04-07T00:54:41.794512Z","steps":["trace[741436869] 'read index received' (duration: 21.830525ms)","trace[741436869] 'applied index is now lower than readState.Index' (duration: 178.125622ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:41.794697Z","caller":"traceutil/trace.go:171","msg":"trace[375403526] transaction","detail":"{read_only:false; response_revision:24512; number_of_response:1; }","duration":"217.717421ms","start":"2026-04-07T00:54:41.576945Z","end":"2026-04-07T00:54:41.794662Z","steps":["trace[375403526] 'process raft request' (duration: 217.340493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:41.794825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.246092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-07T00:54:41.794874Z","caller":"traceutil/trace.go:171","msg":"trace[1618402266] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24512; }","duration":"200.336263ms","start":"2026-04-07T00:54:41.594522Z","end":"2026-04-07T00:54:41.794858Z","steps":["trace[1618402266] 'agreement among raft nodes before linearized reading' (duration: 200.138749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:41.794931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.732281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:54:41.795007Z","caller":"traceutil/trace.go:171","msg":"trace[565420505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24512; }","duration":"196.812253ms","start":"2026-04-07T00:54:41.598173Z","end":"2026-04-07T00:54:41.794985Z","steps":["trace[565420505] 'agreement among raft nodes before linearized reading' (duration: 196.65021ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:41.944815Z","caller":"traceutil/trace.go:171","msg":"trace[687522135] linearizableReadLoop","detail":"{readStateIndex:25312; appliedIndex:25310; }","duration":"118.847529ms","start":"2026-04-07T00:54:41.825917Z","end":"2026-04-07T00:54:41.944765Z","steps":["trace[687522135] 'read index received' (duration: 10.892937ms)","trace[687522135] 'applied index is now lower than readState.Index' (duration: 107.953062ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:41.944831Z","caller":"traceutil/trace.go:171","msg":"trace[828512273] transaction","detail":"{read_only:false; response_revision:24514; number_of_response:1; }","duration":"145.650173ms","start":"2026-04-07T00:54:41.799147Z","end":"2026-04-07T00:54:41.944798Z","steps":["trace[828512273] 'process raft request' (duration: 142.491941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:41.945048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.101935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-84406942-7c6a-4e37-a008-b2aa2400ed99\" ","response":"range_response_count:1 size:3642"} {"level":"info","ts":"2026-04-07T00:54:41.945133Z","caller":"traceutil/trace.go:171","msg":"trace[1939784776] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-84406942-7c6a-4e37-a008-b2aa2400ed99; range_end:; response_count:1; response_revision:24514; }","duration":"119.223318ms","start":"2026-04-07T00:54:41.825889Z","end":"2026-04-07T00:54:41.945112Z","steps":["trace[1939784776] 'agreement among raft nodes before linearized reading' (duration: 119.007343ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:42.131537Z","caller":"traceutil/trace.go:171","msg":"trace[1483232747] transaction","detail":"{read_only:false; response_revision:24516; number_of_response:1; }","duration":"263.641774ms","start":"2026-04-07T00:54:41.867872Z","end":"2026-04-07T00:54:42.131513Z","steps":["trace[1483232747] 'process raft request' (duration: 263.590643ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:42.131803Z","caller":"traceutil/trace.go:171","msg":"trace[1728302233] transaction","detail":"{read_only:false; response_revision:24515; number_of_response:1; }","duration":"265.112304ms","start":"2026-04-07T00:54:41.866659Z","end":"2026-04-07T00:54:42.131771Z","steps":["trace[1728302233] 'process raft request' (duration: 264.659355ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:42.354985Z","caller":"traceutil/trace.go:171","msg":"trace[1276851452] transaction","detail":"{read_only:false; response_revision:24517; number_of_response:1; }","duration":"402.264739ms","start":"2026-04-07T00:54:41.952698Z","end":"2026-04-07T00:54:42.354963Z","steps":["trace[1276851452] 'process raft request' (duration: 313.123682ms)","trace[1276851452] 'compare' (duration: 88.934623ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:42.355123Z","caller":"traceutil/trace.go:171","msg":"trace[645156600] linearizableReadLoop","detail":"{readStateIndex:25316; appliedIndex:25314; }","duration":"178.001578ms","start":"2026-04-07T00:54:42.177103Z","end":"2026-04-07T00:54:42.355104Z","steps":["trace[645156600] 'read index received' (duration: 88.730079ms)","trace[645156600] 'applied index is now lower than readState.Index' (duration: 89.270069ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:42.355206Z","caller":"traceutil/trace.go:171","msg":"trace[1189078461] transaction","detail":"{read_only:false; response_revision:24518; number_of_response:1; }","duration":"200.816873ms","start":"2026-04-07T00:54:42.154379Z","end":"2026-04-07T00:54:42.355196Z","steps":["trace[1189078461] 'process raft request' (duration: 200.520057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:42.355324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.198452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:42.355437Z","caller":"traceutil/trace.go:171","msg":"trace[18980458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24518; }","duration":"178.353985ms","start":"2026-04-07T00:54:42.177057Z","end":"2026-04-07T00:54:42.355411Z","steps":["trace[18980458] 'agreement among raft nodes before linearized reading' (duration: 178.157522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:42.35534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:41.952674Z","time spent":"402.461353ms","remote":"127.0.0.1:42138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3744,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:42.682196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.264924ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-04-07T00:54:42.682268Z","caller":"traceutil/trace.go:171","msg":"trace[301461899] linearizableReadLoop","detail":"{readStateIndex:25319; appliedIndex:25318; }","duration":"291.239586ms","start":"2026-04-07T00:54:42.391016Z","end":"2026-04-07T00:54:42.682255Z","steps":["trace[301461899] 'read index received' (duration: 131.835289ms)","trace[301461899] 'applied index is now lower than readState.Index' (duration: 159.403027ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:42.682545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.908172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-84406942-7c6a-4e37-a008-b2aa2400ed99\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-04-07T00:54:42.68262Z","caller":"traceutil/trace.go:171","msg":"trace[93294918] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-84406942-7c6a-4e37-a008-b2aa2400ed99; range_end:; response_count:1; response_revision:24520; }","duration":"245.992954ms","start":"2026-04-07T00:54:42.436609Z","end":"2026-04-07T00:54:42.682602Z","steps":["trace[93294918] 'agreement among raft nodes before linearized reading' (duration: 245.854251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:42.682808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.792256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"warn","ts":"2026-04-07T00:54:42.682805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.3273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-04-07T00:54:42.68284Z","caller":"traceutil/trace.go:171","msg":"trace[1805261577] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24520; }","duration":"291.850097ms","start":"2026-04-07T00:54:42.390982Z","end":"2026-04-07T00:54:42.682832Z","steps":["trace[1805261577] 'agreement among raft nodes before linearized reading' (duration: 291.344157ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:42.682919Z","caller":"traceutil/trace.go:171","msg":"trace[1953081927] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:24520; }","duration":"102.487993ms","start":"2026-04-07T00:54:42.580408Z","end":"2026-04-07T00:54:42.682896Z","steps":["trace[1953081927] 'agreement among raft nodes before linearized reading' (duration: 102.266649ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:43.06168Z","caller":"traceutil/trace.go:171","msg":"trace[1504417308] transaction","detail":"{read_only:false; response_revision:24521; number_of_response:1; }","duration":"378.470964ms","start":"2026-04-07T00:54:42.683191Z","end":"2026-04-07T00:54:43.061662Z","steps":["trace[1504417308] 'process raft request' (duration: 378.208879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:43.061799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:42.683176Z","time spent":"378.561646ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":724,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:43.061861Z","caller":"traceutil/trace.go:171","msg":"trace[1598308268] linearizableReadLoop","detail":"{readStateIndex:25320; appliedIndex:25319; }","duration":"374.915323ms","start":"2026-04-07T00:54:42.68668Z","end":"2026-04-07T00:54:43.061596Z","steps":["trace[1598308268] 'read index received' (duration: 374.685648ms)","trace[1598308268] 'applied index is now lower than readState.Index' (duration: 228.705µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:43.062031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.342021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f57b5985-2100-4873-9d69-c09e696ebed5\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-04-07T00:54:43.062202Z","caller":"traceutil/trace.go:171","msg":"trace[108589784] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f57b5985-2100-4873-9d69-c09e696ebed5; range_end:; response_count:1; response_revision:24521; }","duration":"375.506404ms","start":"2026-04-07T00:54:42.686635Z","end":"2026-04-07T00:54:43.062141Z","steps":["trace[108589784] 'agreement among raft nodes before linearized reading' (duration: 375.28351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:43.062259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:42.686614Z","time spent":"375.635178ms","remote":"127.0.0.1:42094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1343,"request content":"key:\"/registry/persistentvolumes/pvc-f57b5985-2100-4873-9d69-c09e696ebed5\" "} {"level":"warn","ts":"2026-04-07T00:54:43.120054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.168385ms","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:6"} {"level":"info","ts":"2026-04-07T00:54:43.120117Z","caller":"traceutil/trace.go:171","msg":"trace[2137956318] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24522; }","duration":"431.261066ms","start":"2026-04-07T00:54:42.68884Z","end":"2026-04-07T00:54:43.120101Z","steps":["trace[2137956318] 'agreement among raft nodes before linearized reading' (duration: 431.166344ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:43.120107Z","caller":"traceutil/trace.go:171","msg":"trace[1195643410] transaction","detail":"{read_only:false; response_revision:24522; number_of_response:1; }","duration":"433.134964ms","start":"2026-04-07T00:54:42.686952Z","end":"2026-04-07T00:54:43.120087Z","steps":["trace[1195643410] 'process raft request' (duration: 432.872869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:43.120153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:42.688823Z","time spent":"431.320897ms","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:54:43.120155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.52186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-07T00:54:43.120223Z","caller":"traceutil/trace.go:171","msg":"trace[1476700255] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24522; }","duration":"157.637992ms","start":"2026-04-07T00:54:42.962567Z","end":"2026-04-07T00:54:43.120205Z","steps":["trace[1476700255] 'agreement among raft nodes before linearized reading' (duration: 157.403477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:43.120221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:42.686943Z","time spent":"433.228165ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:43.120404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.778435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-04-07T00:54:43.12101Z","caller":"traceutil/trace.go:171","msg":"trace[984312204] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24522; }","duration":"433.401478ms","start":"2026-04-07T00:54:42.687584Z","end":"2026-04-07T00:54:43.120986Z","steps":["trace[984312204] 'agreement among raft nodes before linearized reading' (duration: 432.696045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:43.12111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:42.687573Z","time spent":"433.52103ms","remote":"127.0.0.1:42106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1469,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"warn","ts":"2026-04-07T00:54:43.313662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.441498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:43.313767Z","caller":"traceutil/trace.go:171","msg":"trace[1784848932] linearizableReadLoop","detail":"{readStateIndex:25323; appliedIndex:25322; }","duration":"187.14198ms","start":"2026-04-07T00:54:43.126608Z","end":"2026-04-07T00:54:43.31375Z","steps":["trace[1784848932] 'read index received' (duration: 29.555159ms)","trace[1784848932] 'applied index is now lower than readState.Index' (duration: 157.585501ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:43.313843Z","caller":"traceutil/trace.go:171","msg":"trace[824977055] transaction","detail":"{read_only:false; response_revision:24524; number_of_response:1; }","duration":"189.244362ms","start":"2026-04-07T00:54:43.124558Z","end":"2026-04-07T00:54:43.313803Z","steps":["trace[824977055] 'process raft request' (duration: 31.566869ms)","trace[824977055] 'compare' (duration: 157.283795ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:43.313971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.315774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:43.314103Z","caller":"traceutil/trace.go:171","msg":"trace[1182048070] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:24524; }","duration":"187.512747ms","start":"2026-04-07T00:54:43.126547Z","end":"2026-04-07T00:54:43.314059Z","steps":["trace[1182048070] 'agreement among raft nodes before linearized reading' (duration: 187.281123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:43.314217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.11816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-04-07T00:54:43.314256Z","caller":"traceutil/trace.go:171","msg":"trace[2000192999] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24524; }","duration":"182.176282ms","start":"2026-04-07T00:54:43.132069Z","end":"2026-04-07T00:54:43.314245Z","steps":["trace[2000192999] 'agreement among raft nodes before linearized reading' (duration: 181.770774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:43.578323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.077264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:43.578439Z","caller":"traceutil/trace.go:171","msg":"trace[2022313996] linearizableReadLoop","detail":"{readStateIndex:25324; appliedIndex:25323; }","duration":"259.265728ms","start":"2026-04-07T00:54:43.319157Z","end":"2026-04-07T00:54:43.578422Z","steps":["trace[2022313996] 'read index received' (duration: 147.033151ms)","trace[2022313996] 'applied index is now lower than readState.Index' (duration: 112.231477ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:43.578517Z","caller":"traceutil/trace.go:171","msg":"trace[215419689] transaction","detail":"{read_only:false; response_revision:24525; number_of_response:1; }","duration":"260.563734ms","start":"2026-04-07T00:54:43.317942Z","end":"2026-04-07T00:54:43.578506Z","steps":["trace[215419689] 'process raft request' (duration: 148.227965ms)","trace[215419689] 'compare' (duration: 111.746976ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:43.578676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.499012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18a3ed043109776f\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-04-07T00:54:43.578762Z","caller":"traceutil/trace.go:171","msg":"trace[1625171927] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18a3ed043109776f; range_end:; response_count:1; response_revision:24525; }","duration":"259.612965ms","start":"2026-04-07T00:54:43.319126Z","end":"2026-04-07T00:54:43.578739Z","steps":["trace[1625171927] 'agreement among raft nodes before linearized reading' (duration: 259.403071ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:43.655274Z","caller":"traceutil/trace.go:171","msg":"trace[1430505075] transaction","detail":"{read_only:false; response_revision:24526; number_of_response:1; }","duration":"219.867762ms","start":"2026-04-07T00:54:43.435346Z","end":"2026-04-07T00:54:43.655214Z","steps":["trace[1430505075] 'process raft request' (duration: 219.347692ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:43.82924Z","caller":"traceutil/trace.go:171","msg":"trace[1448474851] linearizableReadLoop","detail":"{readStateIndex:25326; appliedIndex:25325; }","duration":"141.579262ms","start":"2026-04-07T00:54:43.687634Z","end":"2026-04-07T00:54:43.829214Z","steps":["trace[1448474851] 'read index received' (duration: 140.326867ms)","trace[1448474851] 'applied index is now lower than readState.Index' (duration: 1.251095ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:43.829472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.829456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-84406942-7c6a-4e37-a008-b2aa2400ed99\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-04-07T00:54:43.829525Z","caller":"traceutil/trace.go:171","msg":"trace[1602031286] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-84406942-7c6a-4e37-a008-b2aa2400ed99; range_end:; response_count:1; response_revision:24529; }","duration":"141.932368ms","start":"2026-04-07T00:54:43.687567Z","end":"2026-04-07T00:54:43.829499Z","steps":["trace[1602031286] 'agreement among raft nodes before linearized reading' (duration: 141.746865ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:43.829579Z","caller":"traceutil/trace.go:171","msg":"trace[418038846] transaction","detail":"{read_only:false; response_revision:24527; number_of_response:1; }","duration":"248.539033ms","start":"2026-04-07T00:54:43.581008Z","end":"2026-04-07T00:54:43.829547Z","steps":["trace[418038846] 'process raft request' (duration: 246.938602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:44.125051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.058622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:44.125127Z","caller":"traceutil/trace.go:171","msg":"trace[1875381773] transaction","detail":"{read_only:false; response_revision:24530; number_of_response:1; }","duration":"280.195405ms","start":"2026-04-07T00:54:43.844919Z","end":"2026-04-07T00:54:44.125114Z","steps":["trace[1875381773] 'process raft request' (duration: 135.004111ms)","trace[1875381773] 'compare' (duration: 144.820097ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:44.513803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.454222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:44.514068Z","caller":"traceutil/trace.go:171","msg":"trace[1200768303] linearizableReadLoop","detail":"{readStateIndex:25330; appliedIndex:25328; }","duration":"565.033783ms","start":"2026-04-07T00:54:43.949002Z","end":"2026-04-07T00:54:44.514036Z","steps":["trace[1200768303] 'read index received' (duration: 30.904566ms)","trace[1200768303] 'applied index is now lower than readState.Index' (duration: 534.127557ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:44.514231Z","caller":"traceutil/trace.go:171","msg":"trace[1301115841] transaction","detail":"{read_only:false; response_revision:24531; number_of_response:1; }","duration":"591.129743ms","start":"2026-04-07T00:54:43.923078Z","end":"2026-04-07T00:54:44.514208Z","steps":["trace[1301115841] 'process raft request' (duration: 339.181991ms)","trace[1301115841] 'compare' (duration: 251.296638ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:44.514304Z","caller":"traceutil/trace.go:171","msg":"trace[1580494176] transaction","detail":"{read_only:false; response_revision:24532; number_of_response:1; }","duration":"377.601727ms","start":"2026-04-07T00:54:44.136692Z","end":"2026-04-07T00:54:44.514293Z","steps":["trace[1580494176] 'process raft request' (duration: 377.223869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:44.514406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:44.136671Z","time spent":"377.674798ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:44.514603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.605545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-07T00:54:44.514665Z","caller":"traceutil/trace.go:171","msg":"trace[1157227037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24535; }","duration":"565.703176ms","start":"2026-04-07T00:54:43.948943Z","end":"2026-04-07T00:54:44.514646Z","steps":["trace[1157227037] 'agreement among raft nodes before linearized reading' (duration: 565.495851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:44.514703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:43.948921Z","time spent":"565.773718ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T00:54:44.514746Z","caller":"traceutil/trace.go:171","msg":"trace[415975196] transaction","detail":"{read_only:false; response_revision:24533; number_of_response:1; }","duration":"377.897902ms","start":"2026-04-07T00:54:44.136833Z","end":"2026-04-07T00:54:44.514731Z","steps":["trace[415975196] 'process raft request' (duration: 377.142047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:44.514838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:44.136818Z","time spent":"377.983974ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:44.5141Z","caller":"traceutil/trace.go:171","msg":"trace[1748207250] transaction","detail":"{read_only:false; response_revision:24535; number_of_response:1; }","duration":"153.202234ms","start":"2026-04-07T00:54:44.360881Z","end":"2026-04-07T00:54:44.514083Z","steps":["trace[1748207250] 'process raft request' (duration: 153.160833ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:44.514983Z","caller":"traceutil/trace.go:171","msg":"trace[1045273901] transaction","detail":"{read_only:false; response_revision:24534; number_of_response:1; }","duration":"154.181193ms","start":"2026-04-07T00:54:44.360792Z","end":"2026-04-07T00:54:44.514973Z","steps":["trace[1045273901] 'process raft request' (duration: 153.218134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:44.515095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.979206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:54:44.514377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:43.92305Z","time spent":"591.227084ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:44.515145Z","caller":"traceutil/trace.go:171","msg":"trace[288610145] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24535; }","duration":"348.030447ms","start":"2026-04-07T00:54:44.167099Z","end":"2026-04-07T00:54:44.515129Z","steps":["trace[288610145] 'agreement among raft nodes before linearized reading' (duration: 347.945735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:44.515187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:44.167076Z","time spent":"348.100068ms","remote":"127.0.0.1:37936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:54:44.515496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.234076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-07T00:54:44.515542Z","caller":"traceutil/trace.go:171","msg":"trace[2052776101] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24535; }","duration":"131.280657ms","start":"2026-04-07T00:54:44.38425Z","end":"2026-04-07T00:54:44.515531Z","steps":["trace[2052776101] 'agreement among raft nodes before linearized reading' (duration: 131.152084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:44.515571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.764222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:44.515615Z","caller":"traceutil/trace.go:171","msg":"trace[1911836259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24535; }","duration":"340.840673ms","start":"2026-04-07T00:54:44.174761Z","end":"2026-04-07T00:54:44.515602Z","steps":["trace[1911836259] 'agreement among raft nodes before linearized reading' (duration: 340.778722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:44.51565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:44.174743Z","time spent":"340.901294ms","remote":"127.0.0.1:41982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:54:44.515797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.095939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:44.515833Z","caller":"traceutil/trace.go:171","msg":"trace[1182120902] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24535; }","duration":"183.13643ms","start":"2026-04-07T00:54:44.332687Z","end":"2026-04-07T00:54:44.515824Z","steps":["trace[1182120902] 'agreement among raft nodes before linearized reading' (duration: 183.081499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:44.516014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.711226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:44.516063Z","caller":"traceutil/trace.go:171","msg":"trace[457425191] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:24535; }","duration":"259.792578ms","start":"2026-04-07T00:54:44.256258Z","end":"2026-04-07T00:54:44.516051Z","steps":["trace[457425191] 'agreement among raft nodes before linearized reading' (duration: 259.714847ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:49.547344Z","caller":"traceutil/trace.go:171","msg":"trace[1933569320] transaction","detail":"{read_only:false; response_revision:24590; number_of_response:1; }","duration":"202.772362ms","start":"2026-04-07T00:54:49.344546Z","end":"2026-04-07T00:54:49.547319Z","steps":["trace[1933569320] 'process raft request' (duration: 202.519087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:49.892709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.588519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:49.892902Z","caller":"traceutil/trace.go:171","msg":"trace[430246485] transaction","detail":"{read_only:false; response_revision:24591; number_of_response:1; }","duration":"287.161264ms","start":"2026-04-07T00:54:49.605722Z","end":"2026-04-07T00:54:49.892883Z","steps":["trace[430246485] 'process raft request' (duration: 89.31097ms)","trace[430246485] 'compare' (duration: 197.436776ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:49.893426Z","caller":"traceutil/trace.go:171","msg":"trace[1355104739] transaction","detail":"{read_only:false; response_revision:24592; number_of_response:1; }","duration":"220.676399ms","start":"2026-04-07T00:54:49.67272Z","end":"2026-04-07T00:54:49.893397Z","steps":["trace[1355104739] 'process raft request' (duration: 220.094978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:49.893578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.558364ms","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:8"} {"level":"info","ts":"2026-04-07T00:54:49.893635Z","caller":"traceutil/trace.go:171","msg":"trace[1441156801] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24592; }","duration":"124.695646ms","start":"2026-04-07T00:54:49.768922Z","end":"2026-04-07T00:54:49.893618Z","steps":["trace[1441156801] 'agreement among raft nodes before linearized reading' (duration: 124.553513ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:49.893202Z","caller":"traceutil/trace.go:171","msg":"trace[1411942970] linearizableReadLoop","detail":"{readStateIndex:25393; appliedIndex:25391; }","duration":"124.212726ms","start":"2026-04-07T00:54:49.76897Z","end":"2026-04-07T00:54:49.893182Z","steps":["trace[1411942970] 'read index received' (duration: 44.18126ms)","trace[1411942970] 'applied index is now lower than readState.Index' (duration: 80.029556ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:49.904864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.487381ms","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:8"} {"level":"info","ts":"2026-04-07T00:54:49.904947Z","caller":"traceutil/trace.go:171","msg":"trace[2080220055] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:24594; }","duration":"120.625274ms","start":"2026-04-07T00:54:49.784304Z","end":"2026-04-07T00:54:49.90493Z","steps":["trace[2080220055] 'agreement among raft nodes before linearized reading' (duration: 120.41117ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:03.741751Z","caller":"traceutil/trace.go:171","msg":"trace[2102851486] transaction","detail":"{read_only:false; response_revision:24719; number_of_response:1; }","duration":"124.265297ms","start":"2026-04-07T00:55:03.617459Z","end":"2026-04-07T00:55:03.741725Z","steps":["trace[2102851486] 'process raft request' (duration: 124.068223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:04.031191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.945978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-04-07T00:55:04.031288Z","caller":"traceutil/trace.go:171","msg":"trace[2043526173] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24719; }","duration":"177.099561ms","start":"2026-04-07T00:55:03.854165Z","end":"2026-04-07T00:55:04.031265Z","steps":["trace[2043526173] 'range keys from in-memory index tree' (duration: 176.762594ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:04.306912Z","caller":"traceutil/trace.go:171","msg":"trace[258093291] transaction","detail":"{read_only:false; response_revision:24723; number_of_response:1; }","duration":"270.43648ms","start":"2026-04-07T00:55:04.036457Z","end":"2026-04-07T00:55:04.306893Z","steps":["trace[258093291] 'process raft request' (duration: 270.4118ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:04.306944Z","caller":"traceutil/trace.go:171","msg":"trace[1291212754] linearizableReadLoop","detail":"{readStateIndex:25526; appliedIndex:25523; }","duration":"354.18778ms","start":"2026-04-07T00:55:03.952735Z","end":"2026-04-07T00:55:04.306923Z","steps":["trace[1291212754] 'read index received' (duration: 74.070066ms)","trace[1291212754] 'applied index is now lower than readState.Index' (duration: 280.107904ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:04.30696Z","caller":"traceutil/trace.go:171","msg":"trace[1634106342] transaction","detail":"{read_only:false; response_revision:24722; number_of_response:1; }","duration":"357.66813ms","start":"2026-04-07T00:55:03.949255Z","end":"2026-04-07T00:55:04.306923Z","steps":["trace[1634106342] 'process raft request' (duration: 357.544027ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:04.30702Z","caller":"traceutil/trace.go:171","msg":"trace[1750816022] transaction","detail":"{read_only:false; response_revision:24721; number_of_response:1; }","duration":"358.509117ms","start":"2026-04-07T00:55:03.948505Z","end":"2026-04-07T00:55:04.307014Z","steps":["trace[1750816022] 'process raft request' (duration: 335.649421ms)","trace[1750816022] 'compare' (duration: 22.328575ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:04.307082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:03.948478Z","time spent":"358.554407ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:04.307136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.401334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-04-07T00:55:04.307124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:03.94923Z","time spent":"357.805113ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:04.307202Z","caller":"traceutil/trace.go:171","msg":"trace[1810365821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24723; }","duration":"354.500616ms","start":"2026-04-07T00:55:03.952692Z","end":"2026-04-07T00:55:04.307193Z","steps":["trace[1810365821] 'agreement among raft nodes before linearized reading' (duration: 354.336953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:04.307234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:03.952677Z","time spent":"354.549177ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T00:55:04.307396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.371539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-04-07T00:55:04.307395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.297937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:04.307447Z","caller":"traceutil/trace.go:171","msg":"trace[1600372008] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:24723; }","duration":"137.476491ms","start":"2026-04-07T00:55:04.169954Z","end":"2026-04-07T00:55:04.307431Z","steps":["trace[1600372008] 'agreement among raft nodes before linearized reading' (duration: 137.310538ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:04.307455Z","caller":"traceutil/trace.go:171","msg":"trace[49667386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24723; }","duration":"130.376318ms","start":"2026-04-07T00:55:04.177062Z","end":"2026-04-07T00:55:04.307439Z","steps":["trace[49667386] 'agreement among raft nodes before linearized reading' (duration: 130.267446ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:04.504759Z","caller":"traceutil/trace.go:171","msg":"trace[1990207792] transaction","detail":"{read_only:false; response_revision:24724; number_of_response:1; }","duration":"194.488607ms","start":"2026-04-07T00:55:04.310237Z","end":"2026-04-07T00:55:04.504726Z","steps":["trace[1990207792] 'process raft request' (duration: 129.122383ms)","trace[1990207792] 'compare' (duration: 65.214141ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:04.504881Z","caller":"traceutil/trace.go:171","msg":"trace[1225188427] transaction","detail":"{read_only:false; response_revision:24725; number_of_response:1; }","duration":"193.0996ms","start":"2026-04-07T00:55:04.311762Z","end":"2026-04-07T00:55:04.504861Z","steps":["trace[1225188427] 'process raft request' (duration: 192.933127ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:04.852163Z","caller":"traceutil/trace.go:171","msg":"trace[1059767732] transaction","detail":"{read_only:false; response_revision:24726; number_of_response:1; }","duration":"259.35774ms","start":"2026-04-07T00:55:04.592777Z","end":"2026-04-07T00:55:04.852134Z","steps":["trace[1059767732] 'process raft request' (duration: 259.193537ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:04.862641Z","caller":"traceutil/trace.go:171","msg":"trace[438301210] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"268.014501ms","start":"2026-04-07T00:55:04.594613Z","end":"2026-04-07T00:55:04.862628Z","steps":["trace[438301210] 'process raft request' (duration: 267.901089ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:04.862877Z","caller":"traceutil/trace.go:171","msg":"trace[1160988782] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"267.86474ms","start":"2026-04-07T00:55:04.59499Z","end":"2026-04-07T00:55:04.862855Z","steps":["trace[1160988782] 'process raft request' (duration: 267.598853ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:04.863013Z","caller":"traceutil/trace.go:171","msg":"trace[1712045305] linearizableReadLoop","detail":"{readStateIndex:25532; appliedIndex:25529; }","duration":"267.801449ms","start":"2026-04-07T00:55:04.595199Z","end":"2026-04-07T00:55:04.863001Z","steps":["trace[1712045305] 'read index received' (duration: 256.773379ms)","trace[1712045305] 'applied index is now lower than readState.Index' (duration: 11.02695ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:04.863109Z","caller":"traceutil/trace.go:171","msg":"trace[1894513710] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"267.431621ms","start":"2026-04-07T00:55:04.595653Z","end":"2026-04-07T00:55:04.863085Z","steps":["trace[1894513710] 'process raft request' (duration: 267.137104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:04.863172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.954071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3d50543f-4a9b-49ce-a8dc-e2e131b0dc2e\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-07T00:55:04.863205Z","caller":"traceutil/trace.go:171","msg":"trace[482533642] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3d50543f-4a9b-49ce-a8dc-e2e131b0dc2e; range_end:; response_count:1; response_revision:24729; }","duration":"268.017232ms","start":"2026-04-07T00:55:04.59518Z","end":"2026-04-07T00:55:04.863197Z","steps":["trace[482533642] 'agreement among raft nodes before linearized reading' (duration: 267.89066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:04.863253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.195382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-07T00:55:04.863293Z","caller":"traceutil/trace.go:171","msg":"trace[1783762594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24729; }","duration":"173.271434ms","start":"2026-04-07T00:55:04.690011Z","end":"2026-04-07T00:55:04.863282Z","steps":["trace[1783762594] 'agreement among raft nodes before linearized reading' (duration: 173.145362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:04.863354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.186065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-07T00:55:04.863384Z","caller":"traceutil/trace.go:171","msg":"trace[723031678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24729; }","duration":"141.291367ms","start":"2026-04-07T00:55:04.722082Z","end":"2026-04-07T00:55:04.863373Z","steps":["trace[723031678] 'agreement among raft nodes before linearized reading' (duration: 141.161604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:05.163464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.4899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:05.163568Z","caller":"traceutil/trace.go:171","msg":"trace[207682918] linearizableReadLoop","detail":"{readStateIndex:25535; appliedIndex:25534; }","duration":"225.2308ms","start":"2026-04-07T00:55:04.938321Z","end":"2026-04-07T00:55:05.163552Z","steps":["trace[207682918] 'read index received' (duration: 73.545177ms)","trace[207682918] 'applied index is now lower than readState.Index' (duration: 151.684513ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:05.163604Z","caller":"traceutil/trace.go:171","msg":"trace[1167457937] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"295.843297ms","start":"2026-04-07T00:55:04.867741Z","end":"2026-04-07T00:55:05.163584Z","steps":["trace[1167457937] 'process raft request' (duration: 144.156623ms)","trace[1167457937] 'compare' (duration: 151.373008ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:05.163693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.376283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:05.163736Z","caller":"traceutil/trace.go:171","msg":"trace[700661959] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:24731; }","duration":"225.461605ms","start":"2026-04-07T00:55:04.938263Z","end":"2026-04-07T00:55:05.163725Z","steps":["trace[700661959] 'agreement among raft nodes before linearized reading' (duration: 225.390433ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:05.473637Z","caller":"traceutil/trace.go:171","msg":"trace[139113734] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"136.464281ms","start":"2026-04-07T00:55:05.337146Z","end":"2026-04-07T00:55:05.47361Z","steps":["trace[139113734] 'process raft request' (duration: 136.267246ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:05.986386Z","caller":"traceutil/trace.go:171","msg":"trace[243776816] linearizableReadLoop","detail":"{readStateIndex:25540; appliedIndex:25538; }","duration":"121.628295ms","start":"2026-04-07T00:55:05.864734Z","end":"2026-04-07T00:55:05.986362Z","steps":["trace[243776816] 'read index received' (duration: 119.868739ms)","trace[243776816] 'applied index is now lower than readState.Index' (duration: 1.758606ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:05.986589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.84364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:05.986628Z","caller":"traceutil/trace.go:171","msg":"trace[45767972] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24735; }","duration":"121.935461ms","start":"2026-04-07T00:55:05.864683Z","end":"2026-04-07T00:55:05.986618Z","steps":["trace[45767972] 'agreement among raft nodes before linearized reading' (duration: 121.793698ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:05.986775Z","caller":"traceutil/trace.go:171","msg":"trace[2006412935] transaction","detail":"{read_only:false; response_revision:24735; number_of_response:1; }","duration":"181.113781ms","start":"2026-04-07T00:55:05.805624Z","end":"2026-04-07T00:55:05.986738Z","steps":["trace[2006412935] 'process raft request' (duration: 180.643832ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:05.986771Z","caller":"traceutil/trace.go:171","msg":"trace[558229818] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"181.350315ms","start":"2026-04-07T00:55:05.805324Z","end":"2026-04-07T00:55:05.986674Z","steps":["trace[558229818] 'process raft request' (duration: 179.256633ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:10.000804Z","caller":"traceutil/trace.go:171","msg":"trace[1881291300] transaction","detail":"{read_only:false; response_revision:24781; number_of_response:1; }","duration":"138.388899ms","start":"2026-04-07T00:55:09.862389Z","end":"2026-04-07T00:55:10.000778Z","steps":["trace[1881291300] 'process raft request' (duration: 138.221425ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:10.265828Z","caller":"traceutil/trace.go:171","msg":"trace[592121714] linearizableReadLoop","detail":"{readStateIndex:25587; appliedIndex:25586; }","duration":"146.751065ms","start":"2026-04-07T00:55:10.119059Z","end":"2026-04-07T00:55:10.26581Z","steps":["trace[592121714] 'read index received' (duration: 146.600032ms)","trace[592121714] 'applied index is now lower than readState.Index' (duration: 150.443µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:10.265887Z","caller":"traceutil/trace.go:171","msg":"trace[2013512636] transaction","detail":"{read_only:false; response_revision:24782; number_of_response:1; }","duration":"218.206649ms","start":"2026-04-07T00:55:10.047658Z","end":"2026-04-07T00:55:10.265865Z","steps":["trace[2013512636] 'process raft request' (duration: 218.040766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:10.265941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.878707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T00:55:10.265964Z","caller":"traceutil/trace.go:171","msg":"trace[1105712133] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:24782; }","duration":"146.954539ms","start":"2026-04-07T00:55:10.119002Z","end":"2026-04-07T00:55:10.265957Z","steps":["trace[1105712133] 'agreement among raft nodes before linearized reading' (duration: 146.893528ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:10.479684Z","caller":"traceutil/trace.go:171","msg":"trace[1902202611] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"153.585231ms","start":"2026-04-07T00:55:10.326064Z","end":"2026-04-07T00:55:10.479649Z","steps":["trace[1902202611] 'process raft request' (duration: 83.31958ms)","trace[1902202611] 'compare' (duration: 69.959964ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:10.488052Z","caller":"traceutil/trace.go:171","msg":"trace[615466184] transaction","detail":"{read_only:false; response_revision:24785; number_of_response:1; }","duration":"160.353067ms","start":"2026-04-07T00:55:10.327679Z","end":"2026-04-07T00:55:10.488032Z","steps":["trace[615466184] 'process raft request' (duration: 160.302336ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:10.488164Z","caller":"traceutil/trace.go:171","msg":"trace[1407915242] transaction","detail":"{read_only:false; response_revision:24784; number_of_response:1; }","duration":"161.105302ms","start":"2026-04-07T00:55:10.327016Z","end":"2026-04-07T00:55:10.488122Z","steps":["trace[1407915242] 'process raft request' (duration: 160.835656ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:10.633932Z","caller":"traceutil/trace.go:171","msg":"trace[520246912] transaction","detail":"{read_only:false; response_revision:24786; number_of_response:1; }","duration":"100.665977ms","start":"2026-04-07T00:55:10.53323Z","end":"2026-04-07T00:55:10.633896Z","steps":["trace[520246912] 'process raft request' (duration: 78.059856ms)","trace[520246912] 'compare' (duration: 22.395627ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:12.619771Z","caller":"traceutil/trace.go:171","msg":"trace[1697004902] transaction","detail":"{read_only:false; response_revision:24800; number_of_response:1; }","duration":"135.679404ms","start":"2026-04-07T00:55:12.484061Z","end":"2026-04-07T00:55:12.61974Z","steps":["trace[1697004902] 'process raft request' (duration: 127.243096ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:12.673964Z","caller":"traceutil/trace.go:171","msg":"trace[2099329195] transaction","detail":"{read_only:false; response_revision:24801; number_of_response:1; }","duration":"180.629271ms","start":"2026-04-07T00:55:12.493304Z","end":"2026-04-07T00:55:12.673933Z","steps":["trace[2099329195] 'process raft request' (duration: 180.439697ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:12.799695Z","caller":"traceutil/trace.go:171","msg":"trace[577779082] transaction","detail":"{read_only:false; response_revision:24803; number_of_response:1; }","duration":"121.678946ms","start":"2026-04-07T00:55:12.677989Z","end":"2026-04-07T00:55:12.799668Z","steps":["trace[577779082] 'process raft request' (duration: 121.625655ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:12.799985Z","caller":"traceutil/trace.go:171","msg":"trace[1465908231] transaction","detail":"{read_only:false; response_revision:24802; number_of_response:1; }","duration":"122.218356ms","start":"2026-04-07T00:55:12.677726Z","end":"2026-04-07T00:55:12.799944Z","steps":["trace[1465908231] 'process raft request' (duration: 70.472334ms)","trace[1465908231] 'compare' (duration: 51.281433ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:16.201617Z","caller":"traceutil/trace.go:171","msg":"trace[21075107] linearizableReadLoop","detail":"{readStateIndex:25642; appliedIndex:25641; }","duration":"105.245657ms","start":"2026-04-07T00:55:16.096343Z","end":"2026-04-07T00:55:16.201588Z","steps":["trace[21075107] 'read index received' (duration: 103.320878ms)","trace[21075107] 'applied index is now lower than readState.Index' (duration: 1.923449ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:16.201759Z","caller":"traceutil/trace.go:171","msg":"trace[135473076] transaction","detail":"{read_only:false; response_revision:24835; number_of_response:1; }","duration":"139.058041ms","start":"2026-04-07T00:55:16.062687Z","end":"2026-04-07T00:55:16.201745Z","steps":["trace[135473076] 'process raft request' (duration: 137.087391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:16.202302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.666635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-07T00:55:16.202692Z","caller":"traceutil/trace.go:171","msg":"trace[1916982507] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24835; }","duration":"106.380429ms","start":"2026-04-07T00:55:16.096295Z","end":"2026-04-07T00:55:16.202676Z","steps":["trace[1916982507] 'agreement among raft nodes before linearized reading' (duration: 105.577043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:17.05224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.532812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:17.052536Z","caller":"traceutil/trace.go:171","msg":"trace[2014174660] transaction","detail":"{read_only:false; response_revision:24844; number_of_response:1; }","duration":"139.320228ms","start":"2026-04-07T00:55:16.913198Z","end":"2026-04-07T00:55:17.052518Z","steps":["trace[2014174660] 'process raft request' (duration: 139.255127ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:17.052619Z","caller":"traceutil/trace.go:171","msg":"trace[343554969] transaction","detail":"{read_only:false; response_revision:24842; number_of_response:1; }","duration":"145.716184ms","start":"2026-04-07T00:55:16.906883Z","end":"2026-04-07T00:55:17.052599Z","steps":["trace[343554969] 'process raft request' (duration: 145.46942ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:17.052635Z","caller":"traceutil/trace.go:171","msg":"trace[1379992156] linearizableReadLoop","detail":"{readStateIndex:25648; appliedIndex:25646; }","duration":"216.466864ms","start":"2026-04-07T00:55:16.836128Z","end":"2026-04-07T00:55:17.052595Z","steps":["trace[1379992156] 'read index received' (duration: 64.117857ms)","trace[1379992156] 'applied index is now lower than readState.Index' (duration: 152.342977ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:17.052643Z","caller":"traceutil/trace.go:171","msg":"trace[1611948316] transaction","detail":"{read_only:false; response_revision:24845; number_of_response:1; }","duration":"135.249096ms","start":"2026-04-07T00:55:16.91736Z","end":"2026-04-07T00:55:17.052609Z","steps":["trace[1611948316] 'process raft request' (duration: 135.127103ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:17.052727Z","caller":"traceutil/trace.go:171","msg":"trace[577024830] transaction","detail":"{read_only:false; response_revision:24843; number_of_response:1; }","duration":"140.291007ms","start":"2026-04-07T00:55:16.912426Z","end":"2026-04-07T00:55:17.052717Z","steps":["trace[577024830] 'process raft request' (duration: 139.982101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:17.05288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.754001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:55:17.052885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.200136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-07T00:55:17.052912Z","caller":"traceutil/trace.go:171","msg":"trace[1450679742] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:24845; }","duration":"216.817571ms","start":"2026-04-07T00:55:16.836082Z","end":"2026-04-07T00:55:17.052899Z","steps":["trace[1450679742] 'agreement among raft nodes before linearized reading' (duration: 216.75563ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:17.052923Z","caller":"traceutil/trace.go:171","msg":"trace[536909791] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24845; }","duration":"115.298949ms","start":"2026-04-07T00:55:16.937613Z","end":"2026-04-07T00:55:17.052912Z","steps":["trace[536909791] 'agreement among raft nodes before linearized reading' (duration: 115.167456ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:17.053172Z","caller":"traceutil/trace.go:171","msg":"trace[1095134970] transaction","detail":"{read_only:false; response_revision:24841; number_of_response:1; }","duration":"232.728699ms","start":"2026-04-07T00:55:16.82037Z","end":"2026-04-07T00:55:17.053099Z","steps":["trace[1095134970] 'process raft request' (duration: 106.228578ms)","trace[1095134970] 'compare' (duration: 125.42272ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:19.660824Z","caller":"traceutil/trace.go:171","msg":"trace[1670529103] transaction","detail":"{read_only:false; response_revision:24863; number_of_response:1; }","duration":"116.973662ms","start":"2026-04-07T00:55:19.543821Z","end":"2026-04-07T00:55:19.660795Z","steps":["trace[1670529103] 'process raft request' (duration: 116.791909ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:23.312237Z","caller":"traceutil/trace.go:171","msg":"trace[408082883] linearizableReadLoop","detail":"{readStateIndex:25699; appliedIndex:25698; }","duration":"121.892569ms","start":"2026-04-07T00:55:23.190323Z","end":"2026-04-07T00:55:23.312216Z","steps":["trace[408082883] 'read index received' (duration: 121.662264ms)","trace[408082883] 'applied index is now lower than readState.Index' (duration: 229.445µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:23.312511Z","caller":"traceutil/trace.go:171","msg":"trace[2074835238] transaction","detail":"{read_only:false; response_revision:24891; number_of_response:1; }","duration":"152.02788ms","start":"2026-04-07T00:55:23.16047Z","end":"2026-04-07T00:55:23.312498Z","steps":["trace[2074835238] 'process raft request' (duration: 151.575311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:23.312749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.404309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:23.312792Z","caller":"traceutil/trace.go:171","msg":"trace[2054024556] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:24891; }","duration":"122.484462ms","start":"2026-04-07T00:55:23.190296Z","end":"2026-04-07T00:55:23.31278Z","steps":["trace[2054024556] 'agreement among raft nodes before linearized reading' (duration: 122.40317ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:40.545352Z","caller":"traceutil/trace.go:171","msg":"trace[1799312102] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"153.547591ms","start":"2026-04-07T00:55:40.391774Z","end":"2026-04-07T00:55:40.545322Z","steps":["trace[1799312102] 'process raft request' (duration: 80.844962ms)","trace[1799312102] 'compare' (duration: 72.562277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:40.935601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.620235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:40.935746Z","caller":"traceutil/trace.go:171","msg":"trace[1704885870] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"152.239084ms","start":"2026-04-07T00:55:40.783482Z","end":"2026-04-07T00:55:40.935721Z","steps":["trace[1704885870] 'process raft request' (duration: 42.390454ms)","trace[1704885870] 'compare' (duration: 109.466022ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:41.163123Z","caller":"traceutil/trace.go:171","msg":"trace[210660621] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"128.42073ms","start":"2026-04-07T00:55:41.034667Z","end":"2026-04-07T00:55:41.163088Z","steps":["trace[210660621] 'process raft request' (duration: 128.159194ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:41.224844Z","caller":"traceutil/trace.go:171","msg":"trace[597070917] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"131.639884ms","start":"2026-04-07T00:55:41.093158Z","end":"2026-04-07T00:55:41.224798Z","steps":["trace[597070917] 'process raft request' (duration: 131.390479ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:41.413272Z","caller":"traceutil/trace.go:171","msg":"trace[231014836] linearizableReadLoop","detail":"{readStateIndex:25844; appliedIndex:25839; }","duration":"244.626696ms","start":"2026-04-07T00:55:41.168618Z","end":"2026-04-07T00:55:41.413244Z","steps":["trace[231014836] 'read index received' (duration: 56.047017ms)","trace[231014836] 'applied index is now lower than readState.Index' (duration: 188.578169ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:41.413402Z","caller":"traceutil/trace.go:171","msg":"trace[108582543] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"250.411471ms","start":"2026-04-07T00:55:41.162952Z","end":"2026-04-07T00:55:41.413363Z","steps":["trace[108582543] 'process raft request' (duration: 250.121865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:41.413546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.88186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-07T00:55:41.41363Z","caller":"traceutil/trace.go:171","msg":"trace[722140226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25032; }","duration":"244.972912ms","start":"2026-04-07T00:55:41.168608Z","end":"2026-04-07T00:55:41.413581Z","steps":["trace[722140226] 'agreement among raft nodes before linearized reading' (duration: 244.775978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:41.413693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.534569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:41.4138Z","caller":"traceutil/trace.go:171","msg":"trace[1061726026] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25032; }","duration":"173.704822ms","start":"2026-04-07T00:55:41.240073Z","end":"2026-04-07T00:55:41.413777Z","steps":["trace[1061726026] 'agreement among raft nodes before linearized reading' (duration: 173.492018ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:41.62159Z","caller":"traceutil/trace.go:171","msg":"trace[606322088] linearizableReadLoop","detail":"{readStateIndex:25846; appliedIndex:25845; }","duration":"143.042241ms","start":"2026-04-07T00:55:41.478524Z","end":"2026-04-07T00:55:41.621566Z","steps":["trace[606322088] 'read index received' (duration: 67.74846ms)","trace[606322088] 'applied index is now lower than readState.Index' (duration: 75.292841ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:41.621668Z","caller":"traceutil/trace.go:171","msg":"trace[256462345] transaction","detail":"{read_only:false; response_revision:25034; number_of_response:1; }","duration":"202.643769ms","start":"2026-04-07T00:55:41.41899Z","end":"2026-04-07T00:55:41.621634Z","steps":["trace[256462345] 'process raft request' (duration: 127.281757ms)","trace[256462345] 'compare' (duration: 75.092117ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:41.62183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.289006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-07T00:55:41.62188Z","caller":"traceutil/trace.go:171","msg":"trace[1710963015] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25034; }","duration":"143.376058ms","start":"2026-04-07T00:55:41.478488Z","end":"2026-04-07T00:55:41.621864Z","steps":["trace[1710963015] 'agreement among raft nodes before linearized reading' (duration: 143.204744ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:00.935782Z","caller":"traceutil/trace.go:171","msg":"trace[78266289] linearizableReadLoop","detail":"{readStateIndex:26134; appliedIndex:26133; }","duration":"143.544991ms","start":"2026-04-07T00:56:00.79221Z","end":"2026-04-07T00:56:00.935755Z","steps":["trace[78266289] 'read index received' (duration: 75.488494ms)","trace[78266289] 'applied index is now lower than readState.Index' (duration: 68.054927ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:56:00.935839Z","caller":"traceutil/trace.go:171","msg":"trace[2130020292] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"152.038321ms","start":"2026-04-07T00:56:00.78376Z","end":"2026-04-07T00:56:00.935798Z","steps":["trace[2130020292] 'process raft request' (duration: 83.975614ms)","trace[2130020292] 'compare' (duration: 67.852062ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:56:00.935952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.733385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:56:00.935993Z","caller":"traceutil/trace.go:171","msg":"trace[1536130465] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:25316; }","duration":"143.846257ms","start":"2026-04-07T00:56:00.792136Z","end":"2026-04-07T00:56:00.935982Z","steps":["trace[1536130465] 'agreement among raft nodes before linearized reading' (duration: 143.759355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:00.936224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.948389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:56:00.936266Z","caller":"traceutil/trace.go:171","msg":"trace[687927073] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:25316; }","duration":"144.066611ms","start":"2026-04-07T00:56:00.792188Z","end":"2026-04-07T00:56:00.936255Z","steps":["trace[687927073] 'agreement among raft nodes before linearized reading' (duration: 143.946609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:00.936632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.387118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:56:00.93669Z","caller":"traceutil/trace.go:171","msg":"trace[1959318676] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:25316; }","duration":"144.47838ms","start":"2026-04-07T00:56:00.792197Z","end":"2026-04-07T00:56:00.936676Z","steps":["trace[1959318676] 'agreement among raft nodes before linearized reading' (duration: 144.386448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:00.936348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.065611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:56:00.93696Z","caller":"traceutil/trace.go:171","msg":"trace[349112484] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:25316; }","duration":"144.693604ms","start":"2026-04-07T00:56:00.792257Z","end":"2026-04-07T00:56:00.93695Z","steps":["trace[349112484] 'agreement among raft nodes before linearized reading' (duration: 144.062932ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:01.21884Z","caller":"traceutil/trace.go:171","msg":"trace[367916367] linearizableReadLoop","detail":"{readStateIndex:26135; appliedIndex:26134; }","duration":"185.881516ms","start":"2026-04-07T00:56:01.032932Z","end":"2026-04-07T00:56:01.218813Z","steps":["trace[367916367] 'read index received' (duration: 185.670021ms)","trace[367916367] 'applied index is now lower than readState.Index' (duration: 210.055µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:56:01.218971Z","caller":"traceutil/trace.go:171","msg":"trace[1189422893] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"210.75702ms","start":"2026-04-07T00:56:01.008182Z","end":"2026-04-07T00:56:01.218939Z","steps":["trace[1189422893] 'process raft request' (duration: 210.404693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:01.219124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.13779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:56:01.219275Z","caller":"traceutil/trace.go:171","msg":"trace[1492846183] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:25317; }","duration":"186.373155ms","start":"2026-04-07T00:56:01.032879Z","end":"2026-04-07T00:56:01.219252Z","steps":["trace[1492846183] 'agreement among raft nodes before linearized reading' (duration: 186.08912ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:32.242695Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000695b.wal"} {"level":"info","ts":"2026-04-07T00:57:37.099269Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23287} {"level":"info","ts":"2026-04-07T00:57:37.232438Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23287,"took":"127.133575ms","hash":3759575500,"current-db-size-bytes":58703872,"current-db-size":"59 MB","current-db-size-in-use-bytes":36687872,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-07T00:57:37.232492Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3759575500,"revision":23287,"compact-revision":20083} {"level":"info","ts":"2026-04-07T01:02:37.106608Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26178} {"level":"info","ts":"2026-04-07T01:02:37.247726Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26178,"took":"136.972661ms","hash":1424370621,"current-db-size-bytes":58703872,"current-db-size":"59 MB","current-db-size-in-use-bytes":33198080,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-07T01:02:37.2478Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1424370621,"revision":26178,"compact-revision":23287} {"level":"info","ts":"2026-04-07T01:03:15.06742Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:03:15.074546Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-07T01:03:15.074707Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-07T01:07:37.112938Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28811} {"level":"info","ts":"2026-04-07T01:07:37.252961Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28811,"took":"135.727592ms","hash":882253655,"current-db-size-bytes":58703872,"current-db-size":"59 MB","current-db-size-in-use-bytes":30216192,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-07T01:07:37.253037Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":882253655,"revision":28811,"compact-revision":26178} {"level":"info","ts":"2026-04-07T01:12:37.119184Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31059} {"level":"info","ts":"2026-04-07T01:12:37.233062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31059,"took":"109.17894ms","hash":3904358609,"current-db-size-bytes":58703872,"current-db-size":"59 MB","current-db-size-in-use-bytes":31420416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T01:12:37.233162Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3904358609,"revision":31059,"compact-revision":28811} {"level":"info","ts":"2026-04-07T01:17:37.125473Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33392} {"level":"info","ts":"2026-04-07T01:17:37.271975Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33392,"took":"141.952ms","hash":3868009177,"current-db-size-bytes":59744256,"current-db-size":"60 MB","current-db-size-in-use-bytes":32481280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T01:17:37.272038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3868009177,"revision":33392,"compact-revision":31059} {"level":"info","ts":"2026-04-07T01:22:37.132862Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35617} {"level":"info","ts":"2026-04-07T01:22:37.266609Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35617,"took":"129.273534ms","hash":3107205674,"current-db-size-bytes":61014016,"current-db-size":"61 MB","current-db-size-in-use-bytes":31465472,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T01:22:37.266694Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3107205674,"revision":35617,"compact-revision":33392} {"level":"info","ts":"2026-04-07T01:24:40.011302Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:24:40.015903Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-07T01:24:40.016029Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}