{"level":"warn","ts":"2026-05-25T14:25:17.145828Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T14:25:17.145961Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-25T14:25:17.146053Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T14:25:17.146068Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-25T14:25:17.146092Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-25T14:25:17.146803Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-05-25T14:25:17.146893Z","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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-25T14:25:17.150807Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.641811ms"} {"level":"info","ts":"2026-05-25T14:25:17.156262Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-05-25T14:25:17.156389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-05-25T14:25:17.156429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-05-25T14:25:17.156458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-25T14:25:17.156478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-05-25T14:25:17.156547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-05-25T14:25:17.16243Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-25T14:25:17.163951Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-25T14:25:17.165381Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-25T14:25:17.16828Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-25T14:25:17.168782Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-25T14:25:17.168926Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-25T14:25:17.168996Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-25T14:25:17.169012Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-25T14:25:17.169401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-05-25T14:25:17.16957Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-25T14:25:17.173957Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-25T14:25:17.174111Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-25T14:25:17.174184Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-25T14:25:17.174305Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-25T14:25:17.174356Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-25T14:25:17.757616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-05-25T14:25:17.757679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-25T14:25:17.757718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-05-25T14:25:17.757741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-05-25T14:25:17.757763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-25T14:25:17.757779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-05-25T14:25:17.757799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-25T14:25:17.759117Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-25T14:25:17.759135Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T14:25:17.75915Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T14:25:17.759233Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T14:25:17.759605Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-25T14:25:17.759656Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-25T14:25:17.760677Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T14:25:17.760804Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T14:25:17.760845Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T14:25:17.762369Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-05-25T14:25:17.763138Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-25T14:25:45.415871Z","caller":"traceutil/trace.go:171","msg":"trace[1271045834] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"137.686732ms","start":"2026-05-25T14:25:45.278152Z","end":"2026-05-25T14:25:45.415839Z","steps":["trace[1271045834] 'process raft request' (duration: 137.439337ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:25:45.438Z","caller":"traceutil/trace.go:171","msg":"trace[1238364746] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"157.739912ms","start":"2026-05-25T14:25:45.280226Z","end":"2026-05-25T14:25:45.437966Z","steps":["trace[1238364746] 'process raft request' (duration: 157.534368ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:25:45.438124Z","caller":"traceutil/trace.go:171","msg":"trace[806997261] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"157.551778ms","start":"2026-05-25T14:25:45.280551Z","end":"2026-05-25T14:25:45.438103Z","steps":["trace[806997261] 'process raft request' (duration: 157.352294ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:25:58.428693Z","caller":"traceutil/trace.go:171","msg":"trace[1622991930] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"141.422055ms","start":"2026-05-25T14:25:58.287234Z","end":"2026-05-25T14:25:58.428656Z","steps":["trace[1622991930] 'process raft request' (duration: 140.777673ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:25:58.733093Z","caller":"traceutil/trace.go:171","msg":"trace[1694193487] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"249.291008ms","start":"2026-05-25T14:25:58.483772Z","end":"2026-05-25T14:25:58.733063Z","steps":["trace[1694193487] 'read index received' (duration: 249.074033ms)","trace[1694193487] 'applied index is now lower than readState.Index' (duration: 215.785µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:25:58.733246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.4374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:25:58.73332Z","caller":"traceutil/trace.go:171","msg":"trace[1153617799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"249.540662ms","start":"2026-05-25T14:25:58.483766Z","end":"2026-05-25T14:25:58.733307Z","steps":["trace[1153617799] 'agreement among raft nodes before linearized reading' (duration: 249.41009ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:25:58.733524Z","caller":"traceutil/trace.go:171","msg":"trace[170024392] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"260.742909ms","start":"2026-05-25T14:25:58.472737Z","end":"2026-05-25T14:25:58.73348Z","steps":["trace[170024392] 'process raft request' (duration: 260.059926ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:25:58.772097Z","caller":"traceutil/trace.go:171","msg":"trace[968848380] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"287.697322ms","start":"2026-05-25T14:25:58.484376Z","end":"2026-05-25T14:25:58.772074Z","steps":["trace[968848380] 'process raft request' (duration: 287.437476ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:26:44.440229Z","caller":"traceutil/trace.go:171","msg":"trace[1118320308] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"201.692308ms","start":"2026-05-25T14:26:44.238503Z","end":"2026-05-25T14:26:44.440195Z","steps":["trace[1118320308] 'process raft request' (duration: 201.499124ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:06.01744Z","caller":"traceutil/trace.go:171","msg":"trace[1979000127] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"102.335243ms","start":"2026-05-25T14:27:05.915072Z","end":"2026-05-25T14:27:06.017407Z","steps":["trace[1979000127] 'process raft request' (duration: 102.245552ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:06.017625Z","caller":"traceutil/trace.go:171","msg":"trace[348156002] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"102.500777ms","start":"2026-05-25T14:27:05.915082Z","end":"2026-05-25T14:27:06.017582Z","steps":["trace[348156002] 'process raft request' (duration: 102.277632ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:06.017495Z","caller":"traceutil/trace.go:171","msg":"trace[637742887] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"102.426745ms","start":"2026-05-25T14:27:05.915023Z","end":"2026-05-25T14:27:06.01745Z","steps":["trace[637742887] 'process raft request' (duration: 102.117559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:28.900932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.087658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T14:27:28.901061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.064608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e3a62d84-e551-4876-8c8b-271baac9a6df\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-25T14:27:28.901152Z","caller":"traceutil/trace.go:171","msg":"trace[1299029049] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1868; }","duration":"109.365754ms","start":"2026-05-25T14:27:28.791739Z","end":"2026-05-25T14:27:28.901105Z","steps":["trace[1299029049] 'count revisions from in-memory index tree' (duration: 108.929365ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:28.901169Z","caller":"traceutil/trace.go:171","msg":"trace[131578459] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e3a62d84-e551-4876-8c8b-271baac9a6df; range_end:; response_count:1; response_revision:1868; }","duration":"196.247322ms","start":"2026-05-25T14:27:28.704896Z","end":"2026-05-25T14:27:28.901143Z","steps":["trace[131578459] 'range keys from in-memory index tree' (duration: 195.788023ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:42.191397Z","caller":"traceutil/trace.go:171","msg":"trace[1192424705] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"144.049873ms","start":"2026-05-25T14:27:42.047319Z","end":"2026-05-25T14:27:42.191368Z","steps":["trace[1192424705] 'process raft request' (duration: 143.875579ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:42.191665Z","caller":"traceutil/trace.go:171","msg":"trace[1582335032] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"130.45399ms","start":"2026-05-25T14:27:42.061183Z","end":"2026-05-25T14:27:42.191637Z","steps":["trace[1582335032] 'process raft request' (duration: 130.143024ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:42.321166Z","caller":"traceutil/trace.go:171","msg":"trace[485814943] linearizableReadLoop","detail":"{readStateIndex:2051; appliedIndex:2050; }","duration":"124.042167ms","start":"2026-05-25T14:27:42.197094Z","end":"2026-05-25T14:27:42.321136Z","steps":["trace[485814943] 'read index received' (duration: 114.90927ms)","trace[485814943] 'applied index is now lower than readState.Index' (duration: 9.131727ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:27:42.32121Z","caller":"traceutil/trace.go:171","msg":"trace[1973584954] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"139.821582ms","start":"2026-05-25T14:27:42.181361Z","end":"2026-05-25T14:27:42.321183Z","steps":["trace[1973584954] 'process raft request' (duration: 130.637274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:42.321464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.911908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-25T14:27:42.32151Z","caller":"traceutil/trace.go:171","msg":"trace[974541107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1977; }","duration":"118.022171ms","start":"2026-05-25T14:27:42.203475Z","end":"2026-05-25T14:27:42.321497Z","steps":["trace[974541107] 'agreement among raft nodes before linearized reading' (duration: 117.834107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:42.321542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.484424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-05-25T14:27:42.321565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.540831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-25T14:27:42.321582Z","caller":"traceutil/trace.go:171","msg":"trace[1920774161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1977; }","duration":"112.581085ms","start":"2026-05-25T14:27:42.208989Z","end":"2026-05-25T14:27:42.321571Z","steps":["trace[1920774161] 'agreement among raft nodes before linearized reading' (duration: 112.375651ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:42.321614Z","caller":"traceutil/trace.go:171","msg":"trace[961517048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1977; }","duration":"117.593882ms","start":"2026-05-25T14:27:42.204006Z","end":"2026-05-25T14:27:42.3216Z","steps":["trace[961517048] 'agreement among raft nodes before linearized reading' (duration: 117.421248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:42.321481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.356582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-25T14:27:42.322118Z","caller":"traceutil/trace.go:171","msg":"trace[686092846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1977; }","duration":"124.886623ms","start":"2026-05-25T14:27:42.197013Z","end":"2026-05-25T14:27:42.321899Z","steps":["trace[686092846] 'agreement among raft nodes before linearized reading' (duration: 124.22789ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:45.290026Z","caller":"traceutil/trace.go:171","msg":"trace[400327703] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"123.977035ms","start":"2026-05-25T14:27:45.166025Z","end":"2026-05-25T14:27:45.290002Z","steps":["trace[400327703] 'process raft request' (duration: 123.436805ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:28:18.429974Z","caller":"traceutil/trace.go:171","msg":"trace[1845607931] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"114.362223ms","start":"2026-05-25T14:28:18.315576Z","end":"2026-05-25T14:28:18.429938Z","steps":["trace[1845607931] 'process raft request' (duration: 114.142629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:24.612463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.228712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T14:28:24.612615Z","caller":"traceutil/trace.go:171","msg":"trace[1726922521] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"195.980515ms","start":"2026-05-25T14:28:24.416616Z","end":"2026-05-25T14:28:24.612596Z","steps":["trace[1726922521] 'process raft request' (duration: 82.889117ms)","trace[1726922521] 'compare' (duration: 112.089829ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:28:24.690456Z","caller":"traceutil/trace.go:171","msg":"trace[1424649958] linearizableReadLoop","detail":"{readStateIndex:2301; appliedIndex:2299; }","duration":"140.905333ms","start":"2026-05-25T14:28:24.549523Z","end":"2026-05-25T14:28:24.690429Z","steps":["trace[1424649958] 'read index received' (duration: 140.802241ms)","trace[1424649958] 'applied index is now lower than readState.Index' (duration: 101.252µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:28:24.690514Z","caller":"traceutil/trace.go:171","msg":"trace[1180046510] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"195.449695ms","start":"2026-05-25T14:28:24.495032Z","end":"2026-05-25T14:28:24.690482Z","steps":["trace[1180046510] 'process raft request' (duration: 195.342383ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:28:24.690546Z","caller":"traceutil/trace.go:171","msg":"trace[256625246] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"243.158404ms","start":"2026-05-25T14:28:24.447331Z","end":"2026-05-25T14:28:24.690489Z","steps":["trace[256625246] 'process raft request' (duration: 242.891518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:24.690735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.96835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-25T14:28:24.690736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.198579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-25T14:28:24.690791Z","caller":"traceutil/trace.go:171","msg":"trace[1063167397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2218; }","duration":"122.065151ms","start":"2026-05-25T14:28:24.568713Z","end":"2026-05-25T14:28:24.690779Z","steps":["trace[1063167397] 'agreement among raft nodes before linearized reading' (duration: 121.887168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:24.690802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.99924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-25T14:28:24.690808Z","caller":"traceutil/trace.go:171","msg":"trace[723258153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2218; }","duration":"141.313871ms","start":"2026-05-25T14:28:24.549475Z","end":"2026-05-25T14:28:24.690789Z","steps":["trace[723258153] 'agreement among raft nodes before linearized reading' (duration: 141.103837ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:28:24.690832Z","caller":"traceutil/trace.go:171","msg":"trace[2104474159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2218; }","duration":"122.068452ms","start":"2026-05-25T14:28:24.568755Z","end":"2026-05-25T14:28:24.690823Z","steps":["trace[2104474159] 'agreement among raft nodes before linearized reading' (duration: 121.934039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:24.690839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.851417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-25T14:28:24.690915Z","caller":"traceutil/trace.go:171","msg":"trace[1366111292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2218; }","duration":"121.958279ms","start":"2026-05-25T14:28:24.568939Z","end":"2026-05-25T14:28:24.690897Z","steps":["trace[1366111292] 'agreement among raft nodes before linearized reading' (duration: 121.736645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:29:33.349668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.534324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T14:29:33.349768Z","caller":"traceutil/trace.go:171","msg":"trace[705079482] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"250.561187ms","start":"2026-05-25T14:29:33.099192Z","end":"2026-05-25T14:29:33.349753Z","steps":["trace[705079482] 'process raft request' (duration: 86.86544ms)","trace[705079482] 'compare' (duration: 163.31581ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:29:33.362246Z","caller":"traceutil/trace.go:171","msg":"trace[683039414] linearizableReadLoop","detail":"{readStateIndex:2896; appliedIndex:2894; }","duration":"259.407887ms","start":"2026-05-25T14:29:33.10282Z","end":"2026-05-25T14:29:33.362228Z","steps":["trace[683039414] 'read index received' (duration: 83.378203ms)","trace[683039414] 'applied index is now lower than readState.Index' (duration: 176.028674ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:29:33.36266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.783414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-25T14:29:33.362665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.98253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-25T14:29:33.362719Z","caller":"traceutil/trace.go:171","msg":"trace[1193923380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2790; }","duration":"259.907327ms","start":"2026-05-25T14:29:33.102796Z","end":"2026-05-25T14:29:33.362703Z","steps":["trace[1193923380] 'agreement among raft nodes before linearized reading' (duration: 259.608021ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:29:33.362745Z","caller":"traceutil/trace.go:171","msg":"trace[944595438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2790; }","duration":"259.089521ms","start":"2026-05-25T14:29:33.103631Z","end":"2026-05-25T14:29:33.362721Z","steps":["trace[944595438] 'agreement among raft nodes before linearized reading' (duration: 258.835967ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:29:33.362785Z","caller":"traceutil/trace.go:171","msg":"trace[847558262] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"262.348503ms","start":"2026-05-25T14:29:33.100357Z","end":"2026-05-25T14:29:33.362705Z","steps":["trace[847558262] 'process raft request' (duration: 261.756482ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:18.990629Z","caller":"traceutil/trace.go:171","msg":"trace[251249] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"115.746672ms","start":"2026-05-25T14:33:18.874859Z","end":"2026-05-25T14:33:18.990605Z","steps":["trace[251249] 'process raft request' (duration: 112.726044ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:18.991138Z","caller":"traceutil/trace.go:171","msg":"trace[38318644] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"113.397307ms","start":"2026-05-25T14:33:18.877729Z","end":"2026-05-25T14:33:18.991126Z","steps":["trace[38318644] 'process raft request' (duration: 112.824696ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:19.142754Z","caller":"traceutil/trace.go:171","msg":"trace[832540932] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"105.640629ms","start":"2026-05-25T14:33:19.037077Z","end":"2026-05-25T14:33:19.142717Z","steps":["trace[832540932] 'process raft request' (duration: 84.917471ms)","trace[832540932] 'compare' (duration: 20.485263ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:33:19.142836Z","caller":"traceutil/trace.go:171","msg":"trace[1305682043] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"105.556206ms","start":"2026-05-25T14:33:19.037239Z","end":"2026-05-25T14:33:19.142795Z","steps":["trace[1305682043] 'process raft request' (duration: 105.394243ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:19.142865Z","caller":"traceutil/trace.go:171","msg":"trace[268718900] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"105.508466ms","start":"2026-05-25T14:33:19.037329Z","end":"2026-05-25T14:33:19.142837Z","steps":["trace[268718900] 'process raft request' (duration: 105.391184ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:19.142861Z","caller":"traceutil/trace.go:171","msg":"trace[1079121431] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"104.910165ms","start":"2026-05-25T14:33:19.037924Z","end":"2026-05-25T14:33:19.142834Z","steps":["trace[1079121431] 'process raft request' (duration: 104.864784ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:19.142927Z","caller":"traceutil/trace.go:171","msg":"trace[2035085303] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"105.558456ms","start":"2026-05-25T14:33:19.037359Z","end":"2026-05-25T14:33:19.142917Z","steps":["trace[2035085303] 'process raft request' (duration: 105.396453ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:19.142836Z","caller":"traceutil/trace.go:171","msg":"trace[967954631] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"105.527876ms","start":"2026-05-25T14:33:19.037282Z","end":"2026-05-25T14:33:19.142809Z","steps":["trace[967954631] 'process raft request' (duration: 105.398544ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:22.720626Z","caller":"traceutil/trace.go:171","msg":"trace[1005860023] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"141.354184ms","start":"2026-05-25T14:33:22.57924Z","end":"2026-05-25T14:33:22.720594Z","steps":["trace[1005860023] 'process raft request' (duration: 127.172252ms)","trace[1005860023] 'compare' (duration: 14.06998ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:33:22.748527Z","caller":"traceutil/trace.go:171","msg":"trace[781675776] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"169.213119ms","start":"2026-05-25T14:33:22.579291Z","end":"2026-05-25T14:33:22.748504Z","steps":["trace[781675776] 'process raft request' (duration: 169.118977ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:22.748528Z","caller":"traceutil/trace.go:171","msg":"trace[1204072476] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"169.25559ms","start":"2026-05-25T14:33:22.579237Z","end":"2026-05-25T14:33:22.748493Z","steps":["trace[1204072476] 'process raft request' (duration: 169.049656ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:38.330996Z","caller":"traceutil/trace.go:171","msg":"trace[990251968] linearizableReadLoop","detail":"{readStateIndex:5394; appliedIndex:5393; }","duration":"496.477846ms","start":"2026-05-25T14:33:37.834499Z","end":"2026-05-25T14:33:38.330977Z","steps":["trace[990251968] 'read index received' (duration: 496.402805ms)","trace[990251968] 'applied index is now lower than readState.Index' (duration: 74.121µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:33:38.331104Z","caller":"traceutil/trace.go:171","msg":"trace[1059445770] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"577.806124ms","start":"2026-05-25T14:33:37.753263Z","end":"2026-05-25T14:33:38.331069Z","steps":["trace[1059445770] 'process raft request' (duration: 577.564649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:33:38.331189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.67472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-msc4f\" ","response":"range_response_count:1 size:9859"} {"level":"info","ts":"2026-05-25T14:33:38.331262Z","caller":"traceutil/trace.go:171","msg":"trace[308146877] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-msc4f; range_end:; response_count:1; response_revision:5200; }","duration":"496.760992ms","start":"2026-05-25T14:33:37.834459Z","end":"2026-05-25T14:33:38.33122Z","steps":["trace[308146877] 'agreement among raft nodes before linearized reading' (duration: 496.626299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:33:38.331267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:33:37.753239Z","time spent":"577.950596ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T14:33:38.331336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:33:37.834441Z","time spent":"496.858553ms","remote":"127.0.0.1:39530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9882,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-msc4f\" "} {"level":"info","ts":"2026-05-25T14:33:38.365036Z","caller":"traceutil/trace.go:171","msg":"trace[719555131] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"499.031906ms","start":"2026-05-25T14:33:37.865941Z","end":"2026-05-25T14:33:38.364972Z","steps":["trace[719555131] 'process raft request' (duration: 499.002955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:33:38.365159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:33:37.865914Z","time spent":"499.177538ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:33:38.365298Z","caller":"traceutil/trace.go:171","msg":"trace[1346723388] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"499.438993ms","start":"2026-05-25T14:33:37.865822Z","end":"2026-05-25T14:33:38.365261Z","steps":["trace[1346723388] 'process raft request' (duration: 499.029246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:33:38.365479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:33:37.865801Z","time spent":"499.584546ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:33:44.497063Z","caller":"traceutil/trace.go:171","msg":"trace[1893943078] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"148.995301ms","start":"2026-05-25T14:33:44.348037Z","end":"2026-05-25T14:33:44.497032Z","steps":["trace[1893943078] 'process raft request' (duration: 90.604296ms)","trace[1893943078] 'compare' (duration: 57.852685ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:33:44.523853Z","caller":"traceutil/trace.go:171","msg":"trace[858682804] transaction","detail":"{read_only:false; response_revision:5378; number_of_response:1; }","duration":"126.378995ms","start":"2026-05-25T14:33:44.397443Z","end":"2026-05-25T14:33:44.523822Z","steps":["trace[858682804] 'process raft request' (duration: 126.155541ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:44.523891Z","caller":"traceutil/trace.go:171","msg":"trace[934659269] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"122.888948ms","start":"2026-05-25T14:33:44.400972Z","end":"2026-05-25T14:33:44.523861Z","steps":["trace[934659269] 'process raft request' (duration: 122.766705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:33:44.74021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.51157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-25a50f9f-2db2-4fbe-8ede-b8a133370d9f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-25T14:33:44.740325Z","caller":"traceutil/trace.go:171","msg":"trace[1020552848] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-25a50f9f-2db2-4fbe-8ede-b8a133370d9f; range_end:; response_count:1; response_revision:5379; }","duration":"136.675424ms","start":"2026-05-25T14:33:44.603622Z","end":"2026-05-25T14:33:44.740297Z","steps":["trace[1020552848] 'range keys from in-memory index tree' (duration: 136.314957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:33:56.509868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.40153ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-25T14:35:17.784767Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3035} {"level":"info","ts":"2026-05-25T14:35:17.866664Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3035,"took":"80.656549ms","hash":370909565,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":32391168,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-25T14:35:17.866779Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":370909565,"revision":3035,"compact-revision":-1} {"level":"info","ts":"2026-05-25T14:39:49.958848Z","caller":"traceutil/trace.go:171","msg":"trace[1539123919] transaction","detail":"{read_only:false; response_revision:8181; number_of_response:1; }","duration":"116.688543ms","start":"2026-05-25T14:39:49.84213Z","end":"2026-05-25T14:39:49.958818Z","steps":["trace[1539123919] 'process raft request' (duration: 115.690974ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:39:49.959019Z","caller":"traceutil/trace.go:171","msg":"trace[446449670] transaction","detail":"{read_only:false; response_revision:8182; number_of_response:1; }","duration":"116.601442ms","start":"2026-05-25T14:39:49.842381Z","end":"2026-05-25T14:39:49.958982Z","steps":["trace[446449670] 'process raft request' (duration: 116.371597ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:00.984968Z","caller":"traceutil/trace.go:171","msg":"trace[730168905] transaction","detail":"{read_only:false; response_revision:8237; number_of_response:1; }","duration":"193.809084ms","start":"2026-05-25T14:40:00.791132Z","end":"2026-05-25T14:40:00.984941Z","steps":["trace[730168905] 'process raft request' (duration: 193.60829ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:01.014773Z","caller":"traceutil/trace.go:171","msg":"trace[1654241470] linearizableReadLoop","detail":"{readStateIndex:8528; appliedIndex:8526; }","duration":"165.782159ms","start":"2026-05-25T14:40:00.848967Z","end":"2026-05-25T14:40:01.014749Z","steps":["trace[1654241470] 'read index received' (duration: 135.824358ms)","trace[1654241470] 'applied index is now lower than readState.Index' (duration: 29.956931ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:40:01.01495Z","caller":"traceutil/trace.go:171","msg":"trace[1008394936] transaction","detail":"{read_only:false; response_revision:8238; number_of_response:1; }","duration":"216.129228ms","start":"2026-05-25T14:40:00.798792Z","end":"2026-05-25T14:40:01.014921Z","steps":["trace[1008394936] 'process raft request' (duration: 215.820772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:40:01.014982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.995954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-25T14:40:01.015222Z","caller":"traceutil/trace.go:171","msg":"trace[1462953867] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:8238; }","duration":"166.281798ms","start":"2026-05-25T14:40:00.848909Z","end":"2026-05-25T14:40:01.015191Z","steps":["trace[1462953867] 'agreement among raft nodes before linearized reading' (duration: 165.980063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:40:01.265965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.187336ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-25T14:40:01.266227Z","caller":"traceutil/trace.go:171","msg":"trace[1466882868] linearizableReadLoop","detail":"{readStateIndex:8530; appliedIndex:8528; }","duration":"106.268546ms","start":"2026-05-25T14:40:01.159941Z","end":"2026-05-25T14:40:01.26621Z","steps":["trace[1466882868] 'read index received' (duration: 98.359775ms)","trace[1466882868] 'applied index is now lower than readState.Index' (duration: 7.907711ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:40:01.266378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.436909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mlfcq\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-05-25T14:40:01.266365Z","caller":"traceutil/trace.go:171","msg":"trace[570420923] transaction","detail":"{read_only:false; response_revision:8239; number_of_response:1; }","duration":"247.094179ms","start":"2026-05-25T14:40:01.019234Z","end":"2026-05-25T14:40:01.266328Z","steps":["trace[570420923] 'process raft request' (duration: 246.862555ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:01.266421Z","caller":"traceutil/trace.go:171","msg":"trace[1991057081] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mlfcq; range_end:; response_count:1; response_revision:8239; }","duration":"106.52021ms","start":"2026-05-25T14:40:01.159885Z","end":"2026-05-25T14:40:01.266406Z","steps":["trace[1991057081] 'agreement among raft nodes before linearized reading' (duration: 106.382228ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:01.280957Z","caller":"traceutil/trace.go:171","msg":"trace[812088264] transaction","detail":"{read_only:false; response_revision:8240; number_of_response:1; }","duration":"115.538952ms","start":"2026-05-25T14:40:01.165388Z","end":"2026-05-25T14:40:01.280927Z","steps":["trace[812088264] 'process raft request' (duration: 115.343928ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:02.116707Z","caller":"traceutil/trace.go:171","msg":"trace[959970010] transaction","detail":"{read_only:false; response_revision:8243; number_of_response:1; }","duration":"167.345718ms","start":"2026-05-25T14:40:01.949322Z","end":"2026-05-25T14:40:02.116668Z","steps":["trace[959970010] 'process raft request' (duration: 138.220164ms)","trace[959970010] 'compare' (duration: 28.91971ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:40:02.116913Z","caller":"traceutil/trace.go:171","msg":"trace[1873272092] transaction","detail":"{read_only:false; response_revision:8244; number_of_response:1; }","duration":"124.278949ms","start":"2026-05-25T14:40:01.992609Z","end":"2026-05-25T14:40:02.116888Z","steps":["trace[1873272092] 'process raft request' (duration: 124.009633ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:02.116932Z","caller":"traceutil/trace.go:171","msg":"trace[376908703] transaction","detail":"{read_only:false; response_revision:8245; number_of_response:1; }","duration":"112.553156ms","start":"2026-05-25T14:40:02.004365Z","end":"2026-05-25T14:40:02.116919Z","steps":["trace[376908703] 'process raft request' (duration: 112.459344ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:02.662809Z","caller":"traceutil/trace.go:171","msg":"trace[1807068381] linearizableReadLoop","detail":"{readStateIndex:8537; appliedIndex:8536; }","duration":"175.359342ms","start":"2026-05-25T14:40:02.4874Z","end":"2026-05-25T14:40:02.662759Z","steps":["trace[1807068381] 'read index received' (duration: 175.204489ms)","trace[1807068381] 'applied index is now lower than readState.Index' (duration: 153.923µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:40:02.662869Z","caller":"traceutil/trace.go:171","msg":"trace[1868251838] transaction","detail":"{read_only:false; response_revision:8246; number_of_response:1; }","duration":"176.195577ms","start":"2026-05-25T14:40:02.486646Z","end":"2026-05-25T14:40:02.662842Z","steps":["trace[1868251838] 'process raft request' (duration: 175.965283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:40:02.662968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.547775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:40:02.663001Z","caller":"traceutil/trace.go:171","msg":"trace[464252497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8246; }","duration":"175.618586ms","start":"2026-05-25T14:40:02.487373Z","end":"2026-05-25T14:40:02.662992Z","steps":["trace[464252497] 'agreement among raft nodes before linearized reading' (duration: 175.546825ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:02.86993Z","caller":"traceutil/trace.go:171","msg":"trace[163190180] linearizableReadLoop","detail":"{readStateIndex:8540; appliedIndex:8539; }","duration":"127.578721ms","start":"2026-05-25T14:40:02.742319Z","end":"2026-05-25T14:40:02.869898Z","steps":["trace[163190180] 'read index received' (duration: 112.849651ms)","trace[163190180] 'applied index is now lower than readState.Index' (duration: 14.727ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:40:02.870108Z","caller":"traceutil/trace.go:171","msg":"trace[1154463261] transaction","detail":"{read_only:false; response_revision:8249; number_of_response:1; }","duration":"132.640208ms","start":"2026-05-25T14:40:02.73745Z","end":"2026-05-25T14:40:02.87009Z","steps":["trace[1154463261] 'process raft request' (duration: 117.618742ms)","trace[1154463261] 'compare' (duration: 14.488466ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:40:02.870534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.697399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:40:02.870732Z","caller":"traceutil/trace.go:171","msg":"trace[1347912929] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8249; }","duration":"110.962635ms","start":"2026-05-25T14:40:02.759732Z","end":"2026-05-25T14:40:02.870695Z","steps":["trace[1347912929] 'agreement among raft nodes before linearized reading' (duration: 110.65345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:40:02.87152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.195472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-25T14:40:02.871552Z","caller":"traceutil/trace.go:171","msg":"trace[1727953404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8249; }","duration":"129.236493ms","start":"2026-05-25T14:40:02.742302Z","end":"2026-05-25T14:40:02.871539Z","steps":["trace[1727953404] 'agreement among raft nodes before linearized reading' (duration: 129.07355ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:17.820559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6190} {"level":"info","ts":"2026-05-25T14:40:17.948511Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6190,"took":"126.594823ms","hash":467739003,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":23113728,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-25T14:40:17.948635Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":467739003,"revision":6190,"compact-revision":3035} {"level":"info","ts":"2026-05-25T14:40:29.607153Z","caller":"traceutil/trace.go:171","msg":"trace[625500169] linearizableReadLoop","detail":"{readStateIndex:8746; appliedIndex:8745; }","duration":"152.947265ms","start":"2026-05-25T14:40:29.454184Z","end":"2026-05-25T14:40:29.607132Z","steps":["trace[625500169] 'read index received' (duration: 152.753121ms)","trace[625500169] 'applied index is now lower than readState.Index' (duration: 193.294µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:40:29.607549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.341862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mlfcq\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-05-25T14:40:29.607633Z","caller":"traceutil/trace.go:171","msg":"trace[2021076835] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mlfcq; range_end:; response_count:1; response_revision:8447; }","duration":"153.406473ms","start":"2026-05-25T14:40:29.454178Z","end":"2026-05-25T14:40:29.607584Z","steps":["trace[2021076835] 'agreement among raft nodes before linearized reading' (duration: 153.141098ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:29.607931Z","caller":"traceutil/trace.go:171","msg":"trace[1695534689] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"200.295218ms","start":"2026-05-25T14:40:29.407624Z","end":"2026-05-25T14:40:29.607919Z","steps":["trace[1695534689] 'process raft request' (duration: 199.37668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:40:29.608423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.605872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:40:29.608489Z","caller":"traceutil/trace.go:171","msg":"trace[814093728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8447; }","duration":"116.680703ms","start":"2026-05-25T14:40:29.491772Z","end":"2026-05-25T14:40:29.608453Z","steps":["trace[814093728] 'agreement among raft nodes before linearized reading' (duration: 116.620981ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:29.753104Z","caller":"traceutil/trace.go:171","msg":"trace[726543327] transaction","detail":"{read_only:false; response_revision:8448; number_of_response:1; }","duration":"109.585368ms","start":"2026-05-25T14:40:29.643373Z","end":"2026-05-25T14:40:29.752958Z","steps":["trace[726543327] 'process raft request' (duration: 63.478219ms)","trace[726543327] 'compare' (duration: 45.819024ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:40:42.389812Z","caller":"traceutil/trace.go:171","msg":"trace[75242901] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"165.788899ms","start":"2026-05-25T14:40:42.224001Z","end":"2026-05-25T14:40:42.38979Z","steps":["trace[75242901] 'process raft request' (duration: 165.635066ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:42.421381Z","caller":"traceutil/trace.go:171","msg":"trace[1066514151] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"192.624981ms","start":"2026-05-25T14:40:42.228725Z","end":"2026-05-25T14:40:42.42135Z","steps":["trace[1066514151] 'process raft request' (duration: 192.414377ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:42.421538Z","caller":"traceutil/trace.go:171","msg":"trace[1040750204] transaction","detail":"{read_only:false; response_revision:8558; number_of_response:1; }","duration":"170.338266ms","start":"2026-05-25T14:40:42.251174Z","end":"2026-05-25T14:40:42.421512Z","steps":["trace[1040750204] 'process raft request' (duration: 170.104652ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:42.42179Z","caller":"traceutil/trace.go:171","msg":"trace[1849320242] transaction","detail":"{read_only:false; response_revision:8559; number_of_response:1; }","duration":"170.495929ms","start":"2026-05-25T14:40:42.25128Z","end":"2026-05-25T14:40:42.421776Z","steps":["trace[1849320242] 'process raft request' (duration: 170.190834ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:42.421862Z","caller":"traceutil/trace.go:171","msg":"trace[308297370] transaction","detail":"{read_only:false; response_revision:8560; number_of_response:1; }","duration":"155.150397ms","start":"2026-05-25T14:40:42.266673Z","end":"2026-05-25T14:40:42.421823Z","steps":["trace[308297370] 'process raft request' (duration: 155.004224ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:43.055525Z","caller":"traceutil/trace.go:171","msg":"trace[1330974169] transaction","detail":"{read_only:false; response_revision:8562; number_of_response:1; }","duration":"137.835917ms","start":"2026-05-25T14:40:42.917665Z","end":"2026-05-25T14:40:43.055501Z","steps":["trace[1330974169] 'process raft request' (duration: 137.48125ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:41:26.033689Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002432.wal"} {"level":"info","ts":"2026-05-25T14:41:59.0175Z","caller":"traceutil/trace.go:171","msg":"trace[228095775] transaction","detail":"{read_only:false; response_revision:9306; number_of_response:1; }","duration":"239.104521ms","start":"2026-05-25T14:41:58.77837Z","end":"2026-05-25T14:41:59.017474Z","steps":["trace[228095775] 'process raft request' (duration: 238.947078ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:42:49.730471Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T14:42:49.736296Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-25T14:42:49.736393Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-25T14:45:17.827855Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8334} {"level":"info","ts":"2026-05-25T14:45:17.904174Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8334,"took":"73.956049ms","hash":953711341,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":26681344,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-25T14:45:17.90427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":953711341,"revision":8334,"compact-revision":6190} {"level":"info","ts":"2026-05-25T14:45:45.777408Z","caller":"traceutil/trace.go:171","msg":"trace[1840269727] transaction","detail":"{read_only:false; response_revision:11167; number_of_response:1; }","duration":"293.755402ms","start":"2026-05-25T14:45:45.48363Z","end":"2026-05-25T14:45:45.777386Z","steps":["trace[1840269727] 'process raft request' (duration: 281.688972ms)","trace[1840269727] 'compare' (duration: 11.597732ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:45:46.343666Z","caller":"traceutil/trace.go:171","msg":"trace[429838906] transaction","detail":"{read_only:false; response_revision:11169; number_of_response:1; }","duration":"120.174048ms","start":"2026-05-25T14:45:46.223459Z","end":"2026-05-25T14:45:46.343633Z","steps":["trace[429838906] 'process raft request' (duration: 119.941474ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:50:17.835078Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11009} {"level":"info","ts":"2026-05-25T14:50:17.912036Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11009,"took":"74.772881ms","hash":2805440801,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":28422144,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-25T14:50:17.912088Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2805440801,"revision":11009,"compact-revision":8334} {"level":"warn","ts":"2026-05-25T14:52:07.305883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.833667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-25T14:52:07.306007Z","caller":"traceutil/trace.go:171","msg":"trace[360798874] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13742; }","duration":"251.997239ms","start":"2026-05-25T14:52:07.053986Z","end":"2026-05-25T14:52:07.305983Z","steps":["trace[360798874] 'range keys from in-memory index tree' (duration: 251.665473ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:52:07.307065Z","caller":"traceutil/trace.go:171","msg":"trace[463325324] transaction","detail":"{read_only:false; response_revision:13743; number_of_response:1; }","duration":"205.877793ms","start":"2026-05-25T14:52:07.101149Z","end":"2026-05-25T14:52:07.307027Z","steps":["trace[463325324] 'process raft request' (duration: 205.74143ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:52:07.310158Z","caller":"traceutil/trace.go:171","msg":"trace[1564985642] transaction","detail":"{read_only:false; response_revision:13744; number_of_response:1; }","duration":"153.446466ms","start":"2026-05-25T14:52:07.156682Z","end":"2026-05-25T14:52:07.310128Z","steps":["trace[1564985642] 'process raft request' (duration: 153.274853ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:52:16.147147Z","caller":"traceutil/trace.go:171","msg":"trace[1940407949] transaction","detail":"{read_only:false; response_revision:13908; number_of_response:1; }","duration":"213.363734ms","start":"2026-05-25T14:52:15.933737Z","end":"2026-05-25T14:52:16.147101Z","steps":["trace[1940407949] 'process raft request' (duration: 208.872599ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:52:16.384598Z","caller":"traceutil/trace.go:171","msg":"trace[315804939] transaction","detail":"{read_only:false; response_revision:13909; number_of_response:1; }","duration":"226.577915ms","start":"2026-05-25T14:52:16.157993Z","end":"2026-05-25T14:52:16.384571Z","steps":["trace[315804939] 'process raft request' (duration: 212.67727ms)","trace[315804939] 'compare' (duration: 13.64755ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:53:43.752639Z","caller":"traceutil/trace.go:171","msg":"trace[1924991402] transaction","detail":"{read_only:false; response_revision:14932; number_of_response:1; }","duration":"116.163907ms","start":"2026-05-25T14:53:43.636455Z","end":"2026-05-25T14:53:43.752619Z","steps":["trace[1924991402] 'process raft request' (duration: 116.032775ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:53:43.765015Z","caller":"traceutil/trace.go:171","msg":"trace[1204004128] transaction","detail":"{read_only:false; response_revision:14933; number_of_response:1; }","duration":"128.123595ms","start":"2026-05-25T14:53:43.636875Z","end":"2026-05-25T14:53:43.764998Z","steps":["trace[1204004128] 'process raft request' (duration: 127.954661ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:53:45.074798Z","caller":"traceutil/trace.go:171","msg":"trace[1368101824] linearizableReadLoop","detail":"{readStateIndex:15441; appliedIndex:15440; }","duration":"249.709385ms","start":"2026-05-25T14:53:44.825064Z","end":"2026-05-25T14:53:45.074774Z","steps":["trace[1368101824] 'read index received' (duration: 249.456771ms)","trace[1368101824] 'applied index is now lower than readState.Index' (duration: 251.724µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:53:45.074848Z","caller":"traceutil/trace.go:171","msg":"trace[592080767] transaction","detail":"{read_only:false; response_revision:14936; number_of_response:1; }","duration":"406.334762ms","start":"2026-05-25T14:53:44.668485Z","end":"2026-05-25T14:53:45.07482Z","steps":["trace[592080767] 'process raft request' (duration: 406.101468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:53:45.074985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:53:44.668459Z","time spent":"406.451624ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T14:53:45.075062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.98249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"warn","ts":"2026-05-25T14:53:45.075064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.850317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-25T14:53:45.075106Z","caller":"traceutil/trace.go:171","msg":"trace[1760475828] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14936; }","duration":"250.043031ms","start":"2026-05-25T14:53:44.825052Z","end":"2026-05-25T14:53:45.075095Z","steps":["trace[1760475828] 'agreement among raft nodes before linearized reading' (duration: 249.867568ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:53:45.075127Z","caller":"traceutil/trace.go:171","msg":"trace[1791509700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14936; }","duration":"220.958ms","start":"2026-05-25T14:53:44.85415Z","end":"2026-05-25T14:53:45.075108Z","steps":["trace[1791509700] 'agreement among raft nodes before linearized reading' (duration: 220.806617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:53:45.075202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.816791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:53:45.075266Z","caller":"traceutil/trace.go:171","msg":"trace[1950256917] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14936; }","duration":"205.922953ms","start":"2026-05-25T14:53:44.869326Z","end":"2026-05-25T14:53:45.075249Z","steps":["trace[1950256917] 'agreement among raft nodes before linearized reading' (duration: 205.819411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:53:45.075403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.048745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:53:45.07547Z","caller":"traceutil/trace.go:171","msg":"trace[1725492384] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14936; }","duration":"206.126247ms","start":"2026-05-25T14:53:44.869335Z","end":"2026-05-25T14:53:45.075461Z","steps":["trace[1725492384] 'agreement among raft nodes before linearized reading' (duration: 206.043226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:53:45.075656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.974936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"warn","ts":"2026-05-25T14:53:45.075688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.440712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:53:45.075721Z","caller":"traceutil/trace.go:171","msg":"trace[825875144] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14936; }","duration":"205.067397ms","start":"2026-05-25T14:53:44.870636Z","end":"2026-05-25T14:53:45.075704Z","steps":["trace[825875144] 'agreement among raft nodes before linearized reading' (duration: 204.923984ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:53:45.075734Z","caller":"traceutil/trace.go:171","msg":"trace[989000409] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14936; }","duration":"206.502194ms","start":"2026-05-25T14:53:44.869218Z","end":"2026-05-25T14:53:45.07572Z","steps":["trace[989000409] 'agreement among raft nodes before linearized reading' (duration: 206.435643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:53:45.076047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.839884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T14:53:45.076158Z","caller":"traceutil/trace.go:171","msg":"trace[350951397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14936; }","duration":"114.024597ms","start":"2026-05-25T14:53:44.96211Z","end":"2026-05-25T14:53:45.076135Z","steps":["trace[350951397] 'agreement among raft nodes before linearized reading' (duration: 113.393804ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:55:17.841295Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13048} {"level":"info","ts":"2026-05-25T14:55:17.915397Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13048,"took":"71.0309ms","hash":1446973865,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":31506432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-25T14:55:17.915467Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1446973865,"revision":13048,"compact-revision":11009} {"level":"info","ts":"2026-05-25T14:56:09.985781Z","caller":"traceutil/trace.go:171","msg":"trace[246350608] linearizableReadLoop","detail":"{readStateIndex:16549; appliedIndex:16548; }","duration":"152.221354ms","start":"2026-05-25T14:56:09.833529Z","end":"2026-05-25T14:56:09.985751Z","steps":["trace[246350608] 'read index received' (duration: 151.99123ms)","trace[246350608] 'applied index is now lower than readState.Index' (duration: 228.564µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:56:09.98587Z","caller":"traceutil/trace.go:171","msg":"trace[1107292661] transaction","detail":"{read_only:false; response_revision:16005; number_of_response:1; }","duration":"183.370037ms","start":"2026-05-25T14:56:09.802473Z","end":"2026-05-25T14:56:09.985843Z","steps":["trace[1107292661] 'process raft request' (duration: 183.090682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:56:09.986099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.55791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-25T14:56:09.986163Z","caller":"traceutil/trace.go:171","msg":"trace[583730783] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16005; }","duration":"152.674324ms","start":"2026-05-25T14:56:09.833472Z","end":"2026-05-25T14:56:09.986146Z","steps":["trace[583730783] 'agreement among raft nodes before linearized reading' (duration: 152.47536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:56:09.986184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.264478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-25T14:56:09.986228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.487515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T14:56:09.986289Z","caller":"traceutil/trace.go:171","msg":"trace[1599000648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16005; }","duration":"150.43768ms","start":"2026-05-25T14:56:09.835831Z","end":"2026-05-25T14:56:09.986269Z","steps":["trace[1599000648] 'agreement among raft nodes before linearized reading' (duration: 150.150785ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:56:09.986312Z","caller":"traceutil/trace.go:171","msg":"trace[1180826023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16005; }","duration":"147.618888ms","start":"2026-05-25T14:56:09.838673Z","end":"2026-05-25T14:56:09.986292Z","steps":["trace[1180826023] 'agreement among raft nodes before linearized reading' (duration: 147.396263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:56:09.986268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.493315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-25T14:56:09.986416Z","caller":"traceutil/trace.go:171","msg":"trace[2077081195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16005; }","duration":"147.674678ms","start":"2026-05-25T14:56:09.838721Z","end":"2026-05-25T14:56:09.986395Z","steps":["trace[2077081195] 'agreement among raft nodes before linearized reading' (duration: 147.279271ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:59:33.03374Z","caller":"traceutil/trace.go:171","msg":"trace[946827775] transaction","detail":"{read_only:false; response_revision:17577; number_of_response:1; }","duration":"106.271318ms","start":"2026-05-25T14:59:32.927409Z","end":"2026-05-25T14:59:33.03368Z","steps":["trace[946827775] 'process raft request' (duration: 105.987572ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:59:39.05293Z","caller":"traceutil/trace.go:171","msg":"trace[271584900] linearizableReadLoop","detail":"{readStateIndex:18230; appliedIndex:18229; }","duration":"116.521591ms","start":"2026-05-25T14:59:38.936392Z","end":"2026-05-25T14:59:39.052914Z","steps":["trace[271584900] 'read index received' (duration: 116.314937ms)","trace[271584900] 'applied index is now lower than readState.Index' (duration: 206.054µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:59:39.053023Z","caller":"traceutil/trace.go:171","msg":"trace[81783691] transaction","detail":"{read_only:false; response_revision:17637; number_of_response:1; }","duration":"120.986176ms","start":"2026-05-25T14:59:38.932006Z","end":"2026-05-25T14:59:39.052993Z","steps":["trace[81783691] 'process raft request' (duration: 120.763532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:59:39.053837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.433329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:946112"} {"level":"info","ts":"2026-05-25T14:59:39.053889Z","caller":"traceutil/trace.go:171","msg":"trace[1484337952] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:17637; }","duration":"117.52043ms","start":"2026-05-25T14:59:38.936357Z","end":"2026-05-25T14:59:39.053878Z","steps":["trace[1484337952] 'agreement among raft nodes before linearized reading' (duration: 116.665873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:59:39.504113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.867099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T14:59:39.504202Z","caller":"traceutil/trace.go:171","msg":"trace[556746530] transaction","detail":"{read_only:false; response_revision:17638; number_of_response:1; }","duration":"447.771227ms","start":"2026-05-25T14:59:39.056415Z","end":"2026-05-25T14:59:39.504186Z","steps":["trace[556746530] 'process raft request' (duration: 253.744575ms)","trace[556746530] 'compare' (duration: 193.663595ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:59:39.504244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:59:39.056394Z","time spent":"447.830158ms","remote":"127.0.0.1:39614","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-05-25T14:59:39.540666Z","caller":"traceutil/trace.go:171","msg":"trace[2747959] transaction","detail":"{read_only:false; response_revision:17639; number_of_response:1; }","duration":"482.211931ms","start":"2026-05-25T14:59:39.058419Z","end":"2026-05-25T14:59:39.540631Z","steps":["trace[2747959] 'process raft request' (duration: 481.997697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:59:39.540856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:59:39.058404Z","time spent":"482.360525ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:59:39.540866Z","caller":"traceutil/trace.go:171","msg":"trace[1046663804] transaction","detail":"{read_only:false; response_revision:17640; number_of_response:1; }","duration":"481.525908ms","start":"2026-05-25T14:59:39.059321Z","end":"2026-05-25T14:59:39.540847Z","steps":["trace[1046663804] 'process raft request' (duration: 481.253032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:59:39.541026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:59:39.059309Z","time spent":"481.659761ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:59:39.541224Z","caller":"traceutil/trace.go:171","msg":"trace[441116007] transaction","detail":"{read_only:false; response_revision:17641; number_of_response:1; }","duration":"479.374067ms","start":"2026-05-25T14:59:39.061829Z","end":"2026-05-25T14:59:39.541203Z","steps":["trace[441116007] 'process raft request' (duration: 478.938939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:59:39.541336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:59:39.06181Z","time spent":"479.473539ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3280,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:59:39.541347Z","caller":"traceutil/trace.go:171","msg":"trace[1821386575] transaction","detail":"{read_only:false; response_revision:17642; number_of_response:1; }","duration":"312.976179ms","start":"2026-05-25T14:59:39.228352Z","end":"2026-05-25T14:59:39.541328Z","steps":["trace[1821386575] 'process raft request' (duration: 312.739235ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:59:39.541308Z","caller":"traceutil/trace.go:171","msg":"trace[1701700602] linearizableReadLoop","detail":"{readStateIndex:18234; appliedIndex:18230; }","duration":"475.157628ms","start":"2026-05-25T14:59:39.066113Z","end":"2026-05-25T14:59:39.54127Z","steps":["trace[1701700602] 'read index received' (duration: 244.094723ms)","trace[1701700602] 'applied index is now lower than readState.Index' (duration: 231.061295ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:59:39.541439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:59:39.228329Z","time spent":"313.063471ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T14:59:39.541556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.404813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-25T14:59:39.541648Z","caller":"traceutil/trace.go:171","msg":"trace[1749170780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17642; }","duration":"475.541585ms","start":"2026-05-25T14:59:39.066085Z","end":"2026-05-25T14:59:39.541626Z","steps":["trace[1749170780] 'agreement among raft nodes before linearized reading' (duration: 475.29367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:59:39.541702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.560068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-n9sd9\" ","response":"range_response_count:1 size:20771"} {"level":"warn","ts":"2026-05-25T14:59:39.541728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:59:39.066076Z","time spent":"475.634636ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T14:59:39.541762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.338656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rcr5z\" ","response":"range_response_count:1 size:9713"} {"level":"info","ts":"2026-05-25T14:59:39.541809Z","caller":"traceutil/trace.go:171","msg":"trace[595965118] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rcr5z; range_end:; response_count:1; response_revision:17642; }","duration":"342.424248ms","start":"2026-05-25T14:59:39.199373Z","end":"2026-05-25T14:59:39.541797Z","steps":["trace[595965118] 'agreement among raft nodes before linearized reading' (duration: 342.298356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:59:39.541814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.430985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-25T14:59:39.541846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:59:39.19935Z","time spent":"342.48892ms","remote":"127.0.0.1:39530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9736,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-rcr5z\" "} {"level":"info","ts":"2026-05-25T14:59:39.541864Z","caller":"traceutil/trace.go:171","msg":"trace[2005901578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17642; }","duration":"473.489486ms","start":"2026-05-25T14:59:39.06836Z","end":"2026-05-25T14:59:39.54185Z","steps":["trace[2005901578] 'agreement among raft nodes before linearized reading' (duration: 473.371724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:59:39.541903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:59:39.068354Z","time spent":"473.538957ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-25T14:59:39.541768Z","caller":"traceutil/trace.go:171","msg":"trace[8472739] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-n9sd9; range_end:; response_count:1; response_revision:17642; }","duration":"316.658079ms","start":"2026-05-25T14:59:39.225093Z","end":"2026-05-25T14:59:39.541751Z","steps":["trace[8472739] 'agreement among raft nodes before linearized reading' (duration: 316.459755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:59:39.542026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:59:39.225072Z","time spent":"316.945025ms","remote":"127.0.0.1:39530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20794,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-n9sd9\" "} {"level":"warn","ts":"2026-05-25T14:59:39.541697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.531445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-25T14:59:39.542122Z","caller":"traceutil/trace.go:171","msg":"trace[778644946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17642; }","duration":"475.971253ms","start":"2026-05-25T14:59:39.06614Z","end":"2026-05-25T14:59:39.542112Z","steps":["trace[778644946] 'agreement among raft nodes before linearized reading' (duration: 475.393222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:59:39.542192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:59:39.066137Z","time spent":"476.046534ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-25T14:59:52.594369Z","caller":"traceutil/trace.go:171","msg":"trace[1869052746] transaction","detail":"{read_only:false; response_revision:17934; number_of_response:1; }","duration":"121.133098ms","start":"2026-05-25T14:59:52.473207Z","end":"2026-05-25T14:59:52.59434Z","steps":["trace[1869052746] 'process raft request' (duration: 120.994226ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:00:17.849474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15546} {"level":"info","ts":"2026-05-25T15:00:17.939797Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15546,"took":"87.418789ms","hash":1651994997,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":34029568,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-25T15:00:17.93987Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1651994997,"revision":15546,"compact-revision":13048} {"level":"info","ts":"2026-05-25T15:03:04.540119Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T15:03:04.544938Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-25T15:03:04.545068Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-25T15:04:50.765205Z","caller":"traceutil/trace.go:171","msg":"trace[570480530] transaction","detail":"{read_only:false; response_revision:20095; number_of_response:1; }","duration":"150.229566ms","start":"2026-05-25T15:04:50.614942Z","end":"2026-05-25T15:04:50.765172Z","steps":["trace[570480530] 'process raft request' (duration: 150.041482ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:04:50.916237Z","caller":"traceutil/trace.go:171","msg":"trace[545690002] transaction","detail":"{read_only:false; response_revision:20096; number_of_response:1; }","duration":"299.983803ms","start":"2026-05-25T15:04:50.616206Z","end":"2026-05-25T15:04:50.91619Z","steps":["trace[545690002] 'process raft request' (duration: 299.765978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:04:50.916458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:04:50.61618Z","time spent":"300.173356ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:04:50.916483Z","caller":"traceutil/trace.go:171","msg":"trace[1738385467] transaction","detail":"{read_only:false; response_revision:20097; number_of_response:1; }","duration":"259.132308ms","start":"2026-05-25T15:04:50.65732Z","end":"2026-05-25T15:04:50.916452Z","steps":["trace[1738385467] 'process raft request' (duration: 258.832633ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:04:50.91685Z","caller":"traceutil/trace.go:171","msg":"trace[542402586] linearizableReadLoop","detail":"{readStateIndex:20771; appliedIndex:20767; }","duration":"205.146985ms","start":"2026-05-25T15:04:50.711671Z","end":"2026-05-25T15:04:50.916818Z","steps":["trace[542402586] 'read index received' (duration: 53.268798ms)","trace[542402586] 'applied index is now lower than readState.Index' (duration: 151.876827ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:04:50.916987Z","caller":"traceutil/trace.go:171","msg":"trace[1610286512] transaction","detail":"{read_only:false; response_revision:20098; number_of_response:1; }","duration":"251.255199ms","start":"2026-05-25T15:04:50.665713Z","end":"2026-05-25T15:04:50.916968Z","steps":["trace[1610286512] 'process raft request' (duration: 250.944803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:04:50.917065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.37862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:04:50.917129Z","caller":"traceutil/trace.go:171","msg":"trace[273824831] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:20098; }","duration":"205.497143ms","start":"2026-05-25T15:04:50.711613Z","end":"2026-05-25T15:04:50.91711Z","steps":["trace[273824831] 'agreement among raft nodes before linearized reading' (duration: 205.38086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:04:51.218677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.635316ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-25T15:04:51.218784Z","caller":"traceutil/trace.go:171","msg":"trace[719784488] linearizableReadLoop","detail":"{readStateIndex:20772; appliedIndex:20771; }","duration":"230.798331ms","start":"2026-05-25T15:04:50.987968Z","end":"2026-05-25T15:04:51.218767Z","steps":["trace[719784488] 'read index received' (duration: 35.008112ms)","trace[719784488] 'applied index is now lower than readState.Index' (duration: 195.788759ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:04:51.219027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.059897ms","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-05-25T15:04:51.219075Z","caller":"traceutil/trace.go:171","msg":"trace[1325781412] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20098; }","duration":"231.153048ms","start":"2026-05-25T15:04:50.987911Z","end":"2026-05-25T15:04:51.219064Z","steps":["trace[1325781412] 'agreement among raft nodes before linearized reading' (duration: 231.067136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:04:51.221812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.925597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-25T15:04:51.221842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.032532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-25T15:04:51.221895Z","caller":"traceutil/trace.go:171","msg":"trace[1875031465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20099; }","duration":"182.049819ms","start":"2026-05-25T15:04:51.039828Z","end":"2026-05-25T15:04:51.221878Z","steps":["trace[1875031465] 'agreement among raft nodes before linearized reading' (duration: 181.910427ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:04:51.221926Z","caller":"traceutil/trace.go:171","msg":"trace[1528349175] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20099; }","duration":"178.161856ms","start":"2026-05-25T15:04:51.043743Z","end":"2026-05-25T15:04:51.221904Z","steps":["trace[1528349175] 'agreement among raft nodes before linearized reading' (duration: 177.974302ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:04:51.540511Z","caller":"traceutil/trace.go:171","msg":"trace[1918249464] transaction","detail":"{read_only:false; response_revision:20102; number_of_response:1; }","duration":"314.453867ms","start":"2026-05-25T15:04:51.226026Z","end":"2026-05-25T15:04:51.540479Z","steps":["trace[1918249464] 'process raft request' (duration: 314.260963ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:04:51.540516Z","caller":"traceutil/trace.go:171","msg":"trace[393093751] transaction","detail":"{read_only:false; response_revision:20101; number_of_response:1; }","duration":"315.10531ms","start":"2026-05-25T15:04:51.225371Z","end":"2026-05-25T15:04:51.540476Z","steps":["trace[393093751] 'process raft request' (duration: 279.640548ms)","trace[393093751] 'compare' (duration: 34.136456ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:04:51.54067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:04:51.226011Z","time spent":"314.576069ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:04:51.540695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.630706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T15:04:51.54072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:04:51.225356Z","time spent":"315.251542ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:04:51.540773Z","caller":"traceutil/trace.go:171","msg":"trace[956790993] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20103; }","duration":"318.760319ms","start":"2026-05-25T15:04:51.221995Z","end":"2026-05-25T15:04:51.540755Z","steps":["trace[956790993] 'agreement among raft nodes before linearized reading' (duration: 318.593166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:04:51.540821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:04:51.221977Z","time spent":"318.83112ms","remote":"127.0.0.1:39536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-25T15:04:51.540517Z","caller":"traceutil/trace.go:171","msg":"trace[415492886] linearizableReadLoop","detail":"{readStateIndex:20777; appliedIndex:20774; }","duration":"306.809713ms","start":"2026-05-25T15:04:51.233671Z","end":"2026-05-25T15:04:51.540481Z","steps":["trace[415492886] 'read index received' (duration: 271.33466ms)","trace[415492886] 'applied index is now lower than readState.Index' (duration: 35.473733ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:04:51.540947Z","caller":"traceutil/trace.go:171","msg":"trace[1262125651] transaction","detail":"{read_only:false; response_revision:20103; number_of_response:1; }","duration":"314.361325ms","start":"2026-05-25T15:04:51.226548Z","end":"2026-05-25T15:04:51.54091Z","steps":["trace[1262125651] 'process raft request' (duration: 313.867276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:04:51.541156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:04:51.226534Z","time spent":"314.544458ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:04:51.548187Z","caller":"traceutil/trace.go:171","msg":"trace[68859515] transaction","detail":"{read_only:false; response_revision:20105; number_of_response:1; }","duration":"311.056663ms","start":"2026-05-25T15:04:51.237111Z","end":"2026-05-25T15:04:51.548168Z","steps":["trace[68859515] 'process raft request' (duration: 310.995752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:04:51.548252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.444298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T15:04:51.548287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:04:51.237088Z","time spent":"311.142214ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:04:51.548342Z","caller":"traceutil/trace.go:171","msg":"trace[1324295677] transaction","detail":"{read_only:false; response_revision:20104; number_of_response:1; }","duration":"311.307448ms","start":"2026-05-25T15:04:51.237002Z","end":"2026-05-25T15:04:51.54831Z","steps":["trace[1324295677] 'process raft request' (duration: 310.969871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:04:51.548386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.203613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-05-25T15:04:51.548444Z","caller":"traceutil/trace.go:171","msg":"trace[655034405] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:20105; }","duration":"313.292755ms","start":"2026-05-25T15:04:51.235136Z","end":"2026-05-25T15:04:51.548429Z","steps":["trace[655034405] 'agreement among raft nodes before linearized reading' (duration: 313.049871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:04:51.548489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:04:51.235127Z","time spent":"313.354996ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-25T15:04:51.548487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:04:51.236979Z","time spent":"311.43916ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:04:51.548295Z","caller":"traceutil/trace.go:171","msg":"trace[1429276992] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:20105; }","duration":"143.53727ms","start":"2026-05-25T15:04:51.404746Z","end":"2026-05-25T15:04:51.548283Z","steps":["trace[1429276992] 'agreement among raft nodes before linearized reading' (duration: 143.429548ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:04:51.957579Z","caller":"traceutil/trace.go:171","msg":"trace[1137646524] transaction","detail":"{read_only:false; response_revision:20106; number_of_response:1; }","duration":"100.534765ms","start":"2026-05-25T15:04:51.857019Z","end":"2026-05-25T15:04:51.957553Z","steps":["trace[1137646524] 'process raft request' (duration: 100.363241ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:05:17.855885Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18192} {"level":"info","ts":"2026-05-25T15:05:17.966641Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18192,"took":"108.041896ms","hash":1022521623,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":30523392,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-25T15:05:17.966705Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1022521623,"revision":18192,"compact-revision":15546} {"level":"info","ts":"2026-05-25T15:08:42.175143Z","caller":"traceutil/trace.go:171","msg":"trace[1098639815] transaction","detail":"{read_only:false; response_revision:22551; number_of_response:1; }","duration":"106.285014ms","start":"2026-05-25T15:08:42.068837Z","end":"2026-05-25T15:08:42.175122Z","steps":["trace[1098639815] 'process raft request' (duration: 105.896636ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:10:17.862911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20304} {"level":"info","ts":"2026-05-25T15:10:17.96997Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20304,"took":"103.233466ms","hash":3188470559,"current-db-size-bytes":57577472,"current-db-size":"58 MB","current-db-size-in-use-bytes":42549248,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-25T15:10:17.970027Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3188470559,"revision":20304,"compact-revision":18192} {"level":"info","ts":"2026-05-25T15:12:20.368486Z","caller":"traceutil/trace.go:171","msg":"trace[1534581449] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"150.999224ms","start":"2026-05-25T15:12:20.217449Z","end":"2026-05-25T15:12:20.368448Z","steps":["trace[1534581449] 'process raft request' (duration: 129.545937ms)","trace[1534581449] 'compare' (duration: 20.922406ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:20.537652Z","caller":"traceutil/trace.go:171","msg":"trace[1386112844] linearizableReadLoop","detail":"{readStateIndex:25545; appliedIndex:25544; }","duration":"122.409922ms","start":"2026-05-25T15:12:20.415215Z","end":"2026-05-25T15:12:20.537625Z","steps":["trace[1386112844] 'read index received' (duration: 75.876929ms)","trace[1386112844] 'applied index is now lower than readState.Index' (duration: 46.531503ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:20.537764Z","caller":"traceutil/trace.go:171","msg":"trace[501491178] transaction","detail":"{read_only:false; response_revision:24742; number_of_response:1; }","duration":"155.122351ms","start":"2026-05-25T15:12:20.38259Z","end":"2026-05-25T15:12:20.537712Z","steps":["trace[501491178] 'process raft request' (duration: 108.503117ms)","trace[501491178] 'compare' (duration: 46.41032ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:20.537852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.739942ms","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-05-25T15:12:20.537928Z","caller":"traceutil/trace.go:171","msg":"trace[599177366] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24742; }","duration":"107.861085ms","start":"2026-05-25T15:12:20.430048Z","end":"2026-05-25T15:12:20.537909Z","steps":["trace[599177366] 'agreement among raft nodes before linearized reading' (duration: 107.735993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:20.537795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.556504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:12:20.538213Z","caller":"traceutil/trace.go:171","msg":"trace[1849881132] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:24742; }","duration":"123.029353ms","start":"2026-05-25T15:12:20.415161Z","end":"2026-05-25T15:12:20.53819Z","steps":["trace[1849881132] 'agreement among raft nodes before linearized reading' (duration: 122.557084ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:20.692116Z","caller":"traceutil/trace.go:171","msg":"trace[1198039389] transaction","detail":"{read_only:false; response_revision:24744; number_of_response:1; }","duration":"104.573323ms","start":"2026-05-25T15:12:20.587514Z","end":"2026-05-25T15:12:20.692087Z","steps":["trace[1198039389] 'process raft request' (duration: 104.41126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:21.411925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.432119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:12:21.412035Z","caller":"traceutil/trace.go:171","msg":"trace[1668338122] transaction","detail":"{read_only:false; response_revision:24746; number_of_response:1; }","duration":"282.66228ms","start":"2026-05-25T15:12:21.129352Z","end":"2026-05-25T15:12:21.412014Z","steps":["trace[1668338122] 'process raft request' (duration: 128.052879ms)","trace[1668338122] 'compare' (duration: 153.781636ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:22.211859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.65379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:12:22.212089Z","caller":"traceutil/trace.go:171","msg":"trace[1655249634] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"294.71754ms","start":"2026-05-25T15:12:21.917339Z","end":"2026-05-25T15:12:22.212057Z","steps":["trace[1655249634] 'process raft request' (duration: 136.732744ms)","trace[1655249634] 'compare' (duration: 157.511058ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:22.34818Z","caller":"traceutil/trace.go:171","msg":"trace[842009595] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"429.572056ms","start":"2026-05-25T15:12:21.918586Z","end":"2026-05-25T15:12:22.348158Z","steps":["trace[842009595] 'process raft request' (duration: 411.637276ms)","trace[842009595] 'compare' (duration: 17.790417ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:22.348321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:21.918565Z","time spent":"429.695139ms","remote":"127.0.0.1:39614","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-05-25T15:12:22.348491Z","caller":"traceutil/trace.go:171","msg":"trace[1408027462] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"429.7944ms","start":"2026-05-25T15:12:21.918688Z","end":"2026-05-25T15:12:22.348483Z","steps":["trace[1408027462] 'process raft request' (duration: 429.436993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:22.348526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:21.918681Z","time spent":"429.825641ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:12:22.348767Z","caller":"traceutil/trace.go:171","msg":"trace[2073852688] transaction","detail":"{read_only:false; response_revision:24753; number_of_response:1; }","duration":"393.194257ms","start":"2026-05-25T15:12:21.955563Z","end":"2026-05-25T15:12:22.348757Z","steps":["trace[2073852688] 'process raft request' (duration: 393.075475ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:22.348745Z","caller":"traceutil/trace.go:171","msg":"trace[2130158522] transaction","detail":"{read_only:false; response_revision:24752; number_of_response:1; }","duration":"429.734679ms","start":"2026-05-25T15:12:21.918973Z","end":"2026-05-25T15:12:22.348708Z","steps":["trace[2130158522] 'process raft request' (duration: 429.448094ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:22.348763Z","caller":"traceutil/trace.go:171","msg":"trace[1742064243] linearizableReadLoop","detail":"{readStateIndex:25558; appliedIndex:25553; }","duration":"146.940897ms","start":"2026-05-25T15:12:22.201797Z","end":"2026-05-25T15:12:22.348738Z","steps":["trace[1742064243] 'read index received' (duration: 128.422016ms)","trace[1742064243] 'applied index is now lower than readState.Index' (duration: 18.518011ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:22.348818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:21.955548Z","time spent":"393.246208ms","remote":"127.0.0.1:39614","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-05-25T15:12:22.348895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:21.918955Z","time spent":"429.860901ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:12:22.348941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.131321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-25T15:12:22.348977Z","caller":"traceutil/trace.go:171","msg":"trace[2034906549] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24754; }","duration":"147.187292ms","start":"2026-05-25T15:12:22.201779Z","end":"2026-05-25T15:12:22.348967Z","steps":["trace[2034906549] 'agreement among raft nodes before linearized reading' (duration: 147.057689ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:22.349069Z","caller":"traceutil/trace.go:171","msg":"trace[1511823859] transaction","detail":"{read_only:false; response_revision:24754; number_of_response:1; }","duration":"155.54505ms","start":"2026-05-25T15:12:22.193497Z","end":"2026-05-25T15:12:22.349042Z","steps":["trace[1511823859] 'process raft request' (duration: 155.205223ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:22.54181Z","caller":"traceutil/trace.go:171","msg":"trace[789751893] transaction","detail":"{read_only:false; response_revision:24755; number_of_response:1; }","duration":"168.952554ms","start":"2026-05-25T15:12:22.372823Z","end":"2026-05-25T15:12:22.541776Z","steps":["trace[789751893] 'process raft request' (duration: 126.832245ms)","trace[789751893] 'compare' (duration: 41.961886ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:22.847902Z","caller":"traceutil/trace.go:171","msg":"trace[1201757673] linearizableReadLoop","detail":"{readStateIndex:25560; appliedIndex:25559; }","duration":"277.766039ms","start":"2026-05-25T15:12:22.570109Z","end":"2026-05-25T15:12:22.847875Z","steps":["trace[1201757673] 'read index received' (duration: 277.574615ms)","trace[1201757673] 'applied index is now lower than readState.Index' (duration: 190.283µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:22.84794Z","caller":"traceutil/trace.go:171","msg":"trace[1017075884] transaction","detail":"{read_only:false; response_revision:24756; number_of_response:1; }","duration":"304.865272ms","start":"2026-05-25T15:12:22.543046Z","end":"2026-05-25T15:12:22.847911Z","steps":["trace[1017075884] 'process raft request' (duration: 304.667398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:22.848066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:22.543034Z","time spent":"304.956204ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:12:22.848063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.951452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-25T15:12:22.848247Z","caller":"traceutil/trace.go:171","msg":"trace[1249068897] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:24756; }","duration":"278.142446ms","start":"2026-05-25T15:12:22.570064Z","end":"2026-05-25T15:12:22.848207Z","steps":["trace[1249068897] 'agreement among raft nodes before linearized reading' (duration: 277.924492ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:22.879759Z","caller":"traceutil/trace.go:171","msg":"trace[1697097399] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"221.674443ms","start":"2026-05-25T15:12:22.658057Z","end":"2026-05-25T15:12:22.879732Z","steps":["trace[1697097399] 'process raft request' (duration: 221.258956ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:22.879834Z","caller":"traceutil/trace.go:171","msg":"trace[1481795734] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"182.323318ms","start":"2026-05-25T15:12:22.697487Z","end":"2026-05-25T15:12:22.87981Z","steps":["trace[1481795734] 'process raft request' (duration: 182.038463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:22.879662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.424743ms","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-05-25T15:12:22.880106Z","caller":"traceutil/trace.go:171","msg":"trace[207652038] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24758; }","duration":"122.904381ms","start":"2026-05-25T15:12:22.757171Z","end":"2026-05-25T15:12:22.880075Z","steps":["trace[207652038] 'agreement among raft nodes before linearized reading' (duration: 122.406612ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:23.524502Z","caller":"traceutil/trace.go:171","msg":"trace[308411802] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"107.178132ms","start":"2026-05-25T15:12:23.41729Z","end":"2026-05-25T15:12:23.524468Z","steps":["trace[308411802] 'process raft request' (duration: 106.992089ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:23.66137Z","caller":"traceutil/trace.go:171","msg":"trace[1059889448] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"102.866271ms","start":"2026-05-25T15:12:23.558457Z","end":"2026-05-25T15:12:23.661323Z","steps":["trace[1059889448] 'process raft request' (duration: 94.025354ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:24.230543Z","caller":"traceutil/trace.go:171","msg":"trace[2014056218] transaction","detail":"{read_only:false; response_revision:24761; number_of_response:1; }","duration":"267.785668ms","start":"2026-05-25T15:12:23.962721Z","end":"2026-05-25T15:12:24.230506Z","steps":["trace[2014056218] 'process raft request' (duration: 267.587085ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:24.251784Z","caller":"traceutil/trace.go:171","msg":"trace[1019667365] transaction","detail":"{read_only:false; response_revision:24762; number_of_response:1; }","duration":"130.073438ms","start":"2026-05-25T15:12:24.121679Z","end":"2026-05-25T15:12:24.251752Z","steps":["trace[1019667365] 'process raft request' (duration: 129.254551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:24.613008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.102626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:12:24.613111Z","caller":"traceutil/trace.go:171","msg":"trace[1222465758] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"258.803679ms","start":"2026-05-25T15:12:24.35429Z","end":"2026-05-25T15:12:24.613094Z","steps":["trace[1222465758] 'process raft request' (duration: 117.543279ms)","trace[1222465758] 'compare' (duration: 141.000485ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:24.616597Z","caller":"traceutil/trace.go:171","msg":"trace[326751594] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"261.935687ms","start":"2026-05-25T15:12:24.354634Z","end":"2026-05-25T15:12:24.61657Z","steps":["trace[326751594] 'process raft request' (duration: 261.673932ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:24.617142Z","caller":"traceutil/trace.go:171","msg":"trace[966539786] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"259.871048ms","start":"2026-05-25T15:12:24.357249Z","end":"2026-05-25T15:12:24.61712Z","steps":["trace[966539786] 'process raft request' (duration: 259.258637ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:24.617474Z","caller":"traceutil/trace.go:171","msg":"trace[1549774900] transaction","detail":"{read_only:false; response_revision:24767; number_of_response:1; }","duration":"260.124224ms","start":"2026-05-25T15:12:24.357331Z","end":"2026-05-25T15:12:24.617455Z","steps":["trace[1549774900] 'process raft request' (duration: 259.727996ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:24.617649Z","caller":"traceutil/trace.go:171","msg":"trace[2046194254] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"258.541713ms","start":"2026-05-25T15:12:24.35909Z","end":"2026-05-25T15:12:24.617632Z","steps":["trace[2046194254] 'process raft request' (duration: 258.121375ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:24.617772Z","caller":"traceutil/trace.go:171","msg":"trace[947814637] linearizableReadLoop","detail":"{readStateIndex:25572; appliedIndex:25567; }","duration":"157.525797ms","start":"2026-05-25T15:12:24.460217Z","end":"2026-05-25T15:12:24.617743Z","steps":["trace[947814637] 'read index received' (duration: 11.62888ms)","trace[947814637] 'applied index is now lower than readState.Index' (duration: 145.895497ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:24.617911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.66661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:12:24.617986Z","caller":"traceutil/trace.go:171","msg":"trace[105545361] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:24768; }","duration":"157.794733ms","start":"2026-05-25T15:12:24.460171Z","end":"2026-05-25T15:12:24.617966Z","steps":["trace[105545361] 'agreement among raft nodes before linearized reading' (duration: 157.66291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:24.628841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.720695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:12:24.629076Z","caller":"traceutil/trace.go:171","msg":"trace[1496270689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24769; }","duration":"143.97124ms","start":"2026-05-25T15:12:24.485047Z","end":"2026-05-25T15:12:24.629018Z","steps":["trace[1496270689] 'agreement among raft nodes before linearized reading' (duration: 143.690744ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:25.132236Z","caller":"traceutil/trace.go:171","msg":"trace[640677753] transaction","detail":"{read_only:false; response_revision:24772; number_of_response:1; }","duration":"246.76265ms","start":"2026-05-25T15:12:24.885448Z","end":"2026-05-25T15:12:25.13221Z","steps":["trace[640677753] 'process raft request' (duration: 246.701459ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:25.132318Z","caller":"traceutil/trace.go:171","msg":"trace[797430948] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"246.849451ms","start":"2026-05-25T15:12:24.88542Z","end":"2026-05-25T15:12:25.13227Z","steps":["trace[797430948] 'process raft request' (duration: 227.240009ms)","trace[797430948] 'compare' (duration: 19.331437ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:26.379726Z","caller":"traceutil/trace.go:171","msg":"trace[1638518279] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"141.714507ms","start":"2026-05-25T15:12:26.237991Z","end":"2026-05-25T15:12:26.379706Z","steps":["trace[1638518279] 'process raft request' (duration: 141.533744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:26.763715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.946943ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-25T15:12:26.763874Z","caller":"traceutil/trace.go:171","msg":"trace[2027026749] linearizableReadLoop","detail":"{readStateIndex:25580; appliedIndex:25579; }","duration":"448.19394ms","start":"2026-05-25T15:12:26.315639Z","end":"2026-05-25T15:12:26.763833Z","steps":["trace[2027026749] 'read index received' (duration: 64.071445ms)","trace[2027026749] 'applied index is now lower than readState.Index' (duration: 384.119335ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:26.764228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.569537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-25T15:12:26.764293Z","caller":"traceutil/trace.go:171","msg":"trace[563167333] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24775; }","duration":"448.687539ms","start":"2026-05-25T15:12:26.315588Z","end":"2026-05-25T15:12:26.764276Z","steps":["trace[563167333] 'agreement among raft nodes before linearized reading' (duration: 448.479916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:26.764322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:26.315568Z","time spent":"448.7485ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-25T15:12:26.764448Z","caller":"traceutil/trace.go:171","msg":"trace[976534483] transaction","detail":"{read_only:false; response_revision:24776; number_of_response:1; }","duration":"146.335836ms","start":"2026-05-25T15:12:26.618083Z","end":"2026-05-25T15:12:26.764419Z","steps":["trace[976534483] 'process raft request' (duration: 145.812276ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:27.000341Z","caller":"traceutil/trace.go:171","msg":"trace[398396168] linearizableReadLoop","detail":"{readStateIndex:25583; appliedIndex:25581; }","duration":"236.423014ms","start":"2026-05-25T15:12:26.763901Z","end":"2026-05-25T15:12:27.000324Z","steps":["trace[398396168] 'read index received' (duration: 193.098831ms)","trace[398396168] 'applied index is now lower than readState.Index' (duration: 43.323443ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:27.000486Z","caller":"traceutil/trace.go:171","msg":"trace[201815021] transaction","detail":"{read_only:false; response_revision:24778; number_of_response:1; }","duration":"367.814356ms","start":"2026-05-25T15:12:26.63266Z","end":"2026-05-25T15:12:27.000475Z","steps":["trace[201815021] 'process raft request' (duration: 367.629902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:27.001032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:26.632651Z","time spent":"368.335385ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:12:27.0006Z","caller":"traceutil/trace.go:171","msg":"trace[1557259071] transaction","detail":"{read_only:false; response_revision:24777; number_of_response:1; }","duration":"378.543579ms","start":"2026-05-25T15:12:26.622046Z","end":"2026-05-25T15:12:27.000589Z","steps":["trace[1557259071] 'process raft request' (duration: 334.920831ms)","trace[1557259071] 'compare' (duration: 43.232241ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:27.001231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:26.622036Z","time spent":"379.171461ms","remote":"127.0.0.1:39614","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-05-25T15:12:27.000728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.290133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-25T15:12:27.001378Z","caller":"traceutil/trace.go:171","msg":"trace[1787712249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24778; }","duration":"380.949495ms","start":"2026-05-25T15:12:26.62042Z","end":"2026-05-25T15:12:27.001369Z","steps":["trace[1787712249] 'agreement among raft nodes before linearized reading' (duration: 380.220232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:27.001419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:26.620406Z","time spent":"381.004376ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T15:12:27.000778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.350484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-25T15:12:27.001575Z","caller":"traceutil/trace.go:171","msg":"trace[1623126205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24778; }","duration":"380.17496ms","start":"2026-05-25T15:12:26.621393Z","end":"2026-05-25T15:12:27.001568Z","steps":["trace[1623126205] 'agreement among raft nodes before linearized reading' (duration: 379.350055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:27.001615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:26.621379Z","time spent":"380.227661ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T15:12:27.000834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.863313ms","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-05-25T15:12:27.001788Z","caller":"traceutil/trace.go:171","msg":"trace[436344345] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24778; }","duration":"381.840462ms","start":"2026-05-25T15:12:26.619938Z","end":"2026-05-25T15:12:27.001779Z","steps":["trace[436344345] 'agreement among raft nodes before linearized reading' (duration: 380.857404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:27.001828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:26.619921Z","time spent":"381.898353ms","remote":"127.0.0.1:39614","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":"warn","ts":"2026-05-25T15:12:27.000916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.280039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-25T15:12:27.001927Z","caller":"traceutil/trace.go:171","msg":"trace[1821423754] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:24778; }","duration":"529.330979ms","start":"2026-05-25T15:12:26.47259Z","end":"2026-05-25T15:12:27.001921Z","steps":["trace[1821423754] 'agreement among raft nodes before linearized reading' (duration: 528.262308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:27.001951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:26.472569Z","time spent":"529.37253ms","remote":"127.0.0.1:39536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":90,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-05-25T15:12:27.002248Z","caller":"traceutil/trace.go:171","msg":"trace[1088940952] transaction","detail":"{read_only:false; response_revision:24779; number_of_response:1; }","duration":"234.322054ms","start":"2026-05-25T15:12:26.767918Z","end":"2026-05-25T15:12:27.00224Z","steps":["trace[1088940952] 'process raft request' (duration: 233.993888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:27.295313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.01563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:12:27.295501Z","caller":"traceutil/trace.go:171","msg":"trace[1358526417] transaction","detail":"{read_only:false; response_revision:24781; number_of_response:1; }","duration":"284.485905ms","start":"2026-05-25T15:12:27.010995Z","end":"2026-05-25T15:12:27.295481Z","steps":["trace[1358526417] 'process raft request' (duration: 139.21914ms)","trace[1358526417] 'compare' (duration: 144.863527ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:27.5881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.439436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:12:27.588342Z","caller":"traceutil/trace.go:171","msg":"trace[697351807] linearizableReadLoop","detail":"{readStateIndex:25589; appliedIndex:25585; }","duration":"536.623017ms","start":"2026-05-25T15:12:27.051705Z","end":"2026-05-25T15:12:27.588328Z","steps":["trace[697351807] 'read index received' (duration: 98.427456ms)","trace[697351807] 'applied index is now lower than readState.Index' (duration: 438.194171ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:27.588518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.80188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:12:27.58857Z","caller":"traceutil/trace.go:171","msg":"trace[1047775010] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:24786; }","duration":"536.878792ms","start":"2026-05-25T15:12:27.051674Z","end":"2026-05-25T15:12:27.588553Z","steps":["trace[1047775010] 'agreement among raft nodes before linearized reading' (duration: 536.787501ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:27.588533Z","caller":"traceutil/trace.go:171","msg":"trace[428072468] transaction","detail":"{read_only:false; response_revision:24786; number_of_response:1; }","duration":"449.963663ms","start":"2026-05-25T15:12:27.138536Z","end":"2026-05-25T15:12:27.5885Z","steps":["trace[428072468] 'process raft request' (duration: 449.76169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:27.58861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:27.051664Z","time spent":"536.935623ms","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true "} {"level":"info","ts":"2026-05-25T15:12:27.588617Z","caller":"traceutil/trace.go:171","msg":"trace[1916474430] transaction","detail":"{read_only:false; response_revision:24782; number_of_response:1; }","duration":"575.347221ms","start":"2026-05-25T15:12:27.013256Z","end":"2026-05-25T15:12:27.588603Z","steps":["trace[1916474430] 'process raft request' (duration: 397.331805ms)","trace[1916474430] 'compare' (duration: 177.324583ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:27.588657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.638954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:12:27.588681Z","caller":"traceutil/trace.go:171","msg":"trace[1001310595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24786; }","duration":"105.699535ms","start":"2026-05-25T15:12:27.482973Z","end":"2026-05-25T15:12:27.588673Z","steps":["trace[1001310595] 'agreement among raft nodes before linearized reading' (duration: 105.665524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:27.588723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:27.013228Z","time spent":"575.423992ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:12:27.588741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:27.138517Z","time spent":"450.120027ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:12:27.588804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.497605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:12:27.588824Z","caller":"traceutil/trace.go:171","msg":"trace[238955520] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:24786; }","duration":"150.559195ms","start":"2026-05-25T15:12:27.438259Z","end":"2026-05-25T15:12:27.588818Z","steps":["trace[238955520] 'agreement among raft nodes before linearized reading' (duration: 150.526945ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:27.588514Z","caller":"traceutil/trace.go:171","msg":"trace[621414217] transaction","detail":"{read_only:false; response_revision:24785; number_of_response:1; }","duration":"451.34397ms","start":"2026-05-25T15:12:27.137133Z","end":"2026-05-25T15:12:27.588477Z","steps":["trace[621414217] 'process raft request' (duration: 451.135246ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:27.588547Z","caller":"traceutil/trace.go:171","msg":"trace[344637459] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"574.272031ms","start":"2026-05-25T15:12:27.014239Z","end":"2026-05-25T15:12:27.588511Z","steps":["trace[344637459] 'process raft request' (duration: 573.936294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:27.58893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.831596ms","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":"warn","ts":"2026-05-25T15:12:27.588936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:27.014233Z","time spent":"574.664678ms","remote":"127.0.0.1:39614","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-05-25T15:12:27.588952Z","caller":"traceutil/trace.go:171","msg":"trace[1390571725] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24786; }","duration":"193.902518ms","start":"2026-05-25T15:12:27.395043Z","end":"2026-05-25T15:12:27.588946Z","steps":["trace[1390571725] 'agreement among raft nodes before linearized reading' (duration: 193.868647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:27.588973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:27.137108Z","time spent":"451.789668ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:12:27.588589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.303999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:12:27.589092Z","caller":"traceutil/trace.go:171","msg":"trace[640247212] transaction","detail":"{read_only:false; response_revision:24784; number_of_response:1; }","duration":"550.473269ms","start":"2026-05-25T15:12:27.03861Z","end":"2026-05-25T15:12:27.589083Z","steps":["trace[640247212] 'process raft request' (duration: 549.607663ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:27.589111Z","caller":"traceutil/trace.go:171","msg":"trace[1056509259] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24786; }","duration":"500.851228ms","start":"2026-05-25T15:12:27.08824Z","end":"2026-05-25T15:12:27.589092Z","steps":["trace[1056509259] 'agreement among raft nodes before linearized reading' (duration: 500.318108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:27.589127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:27.038592Z","time spent":"550.515301ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:12:27.58916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:27.088222Z","time spent":"500.91949ms","remote":"127.0.0.1:33446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"info","ts":"2026-05-25T15:12:27.73011Z","caller":"traceutil/trace.go:171","msg":"trace[1208775878] transaction","detail":"{read_only:false; response_revision:24787; number_of_response:1; }","duration":"115.209035ms","start":"2026-05-25T15:12:27.614879Z","end":"2026-05-25T15:12:27.730088Z","steps":["trace[1208775878] 'process raft request' (duration: 115.065492ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:27.96955Z","caller":"traceutil/trace.go:171","msg":"trace[1640409960] linearizableReadLoop","detail":"{readStateIndex:25593; appliedIndex:25591; }","duration":"246.634647ms","start":"2026-05-25T15:12:27.722888Z","end":"2026-05-25T15:12:27.969523Z","steps":["trace[1640409960] 'read index received' (duration: 7.137255ms)","trace[1640409960] 'applied index is now lower than readState.Index' (duration: 239.496162ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:27.969762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.853511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:12:27.969825Z","caller":"traceutil/trace.go:171","msg":"trace[98037779] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:24788; }","duration":"246.977324ms","start":"2026-05-25T15:12:27.72283Z","end":"2026-05-25T15:12:27.969807Z","steps":["trace[98037779] 'agreement among raft nodes before linearized reading' (duration: 246.855841ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:27.969971Z","caller":"traceutil/trace.go:171","msg":"trace[817634223] transaction","detail":"{read_only:false; response_revision:24788; number_of_response:1; }","duration":"251.670423ms","start":"2026-05-25T15:12:27.717992Z","end":"2026-05-25T15:12:27.969663Z","steps":["trace[817634223] 'process raft request' (duration: 251.336887ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:31.015603Z","caller":"traceutil/trace.go:171","msg":"trace[264121272] linearizableReadLoop","detail":"{readStateIndex:25613; appliedIndex:25612; }","duration":"208.618556ms","start":"2026-05-25T15:12:30.806958Z","end":"2026-05-25T15:12:31.015577Z","steps":["trace[264121272] 'read index received' (duration: 208.430352ms)","trace[264121272] 'applied index is now lower than readState.Index' (duration: 180.454µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:31.01574Z","caller":"traceutil/trace.go:171","msg":"trace[1147302810] transaction","detail":"{read_only:false; response_revision:24808; number_of_response:1; }","duration":"241.197884ms","start":"2026-05-25T15:12:30.774503Z","end":"2026-05-25T15:12:31.0157Z","steps":["trace[1147302810] 'process raft request' (duration: 240.885138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:31.015891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.917341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-25T15:12:31.015932Z","caller":"traceutil/trace.go:171","msg":"trace[453887343] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24808; }","duration":"209.019923ms","start":"2026-05-25T15:12:30.806902Z","end":"2026-05-25T15:12:31.015922Z","steps":["trace[453887343] 'agreement among raft nodes before linearized reading' (duration: 208.83337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:31.805798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.424029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:12:31.806153Z","caller":"traceutil/trace.go:171","msg":"trace[267157566] linearizableReadLoop","detail":"{readStateIndex:25616; appliedIndex:25613; }","duration":"786.049316ms","start":"2026-05-25T15:12:31.02008Z","end":"2026-05-25T15:12:31.806129Z","steps":["trace[267157566] 'read index received' (duration: 237.774839ms)","trace[267157566] 'applied index is now lower than readState.Index' (duration: 548.273097ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:31.806226Z","caller":"traceutil/trace.go:171","msg":"trace[1853321641] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"785.406504ms","start":"2026-05-25T15:12:31.02078Z","end":"2026-05-25T15:12:31.806186Z","steps":["trace[1853321641] 'process raft request' (duration: 785.281962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:31.806376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.285092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-25T15:12:31.806414Z","caller":"traceutil/trace.go:171","msg":"trace[1953409488] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24811; }","duration":"786.350253ms","start":"2026-05-25T15:12:31.020051Z","end":"2026-05-25T15:12:31.806402Z","steps":["trace[1953409488] 'agreement among raft nodes before linearized reading' (duration: 786.181299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:31.806415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:31.020754Z","time spent":"785.558979ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:12:31.806448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:31.020041Z","time spent":"786.398184ms","remote":"127.0.0.1:39530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-25T15:12:31.80672Z","caller":"traceutil/trace.go:171","msg":"trace[4876674] transaction","detail":"{read_only:false; response_revision:24809; number_of_response:1; }","duration":"787.826082ms","start":"2026-05-25T15:12:31.018872Z","end":"2026-05-25T15:12:31.806698Z","steps":["trace[4876674] 'process raft request' (duration: 613.395943ms)","trace[4876674] 'compare' (duration: 173.255516ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:31.80682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:31.018852Z","time spent":"787.919593ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:12:31.806189Z","caller":"traceutil/trace.go:171","msg":"trace[1785700108] transaction","detail":"{read_only:false; response_revision:24810; number_of_response:1; }","duration":"786.923363ms","start":"2026-05-25T15:12:31.01922Z","end":"2026-05-25T15:12:31.806143Z","steps":["trace[1785700108] 'process raft request' (duration: 786.739339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:31.807374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:31.01921Z","time spent":"788.086735ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:12:31.80942Z","caller":"traceutil/trace.go:171","msg":"trace[87580377] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"194.723593ms","start":"2026-05-25T15:12:31.614676Z","end":"2026-05-25T15:12:31.809399Z","steps":["trace[87580377] 'process raft request' (duration: 194.670422ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:31.809486Z","caller":"traceutil/trace.go:171","msg":"trace[862922021] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"207.800982ms","start":"2026-05-25T15:12:31.601673Z","end":"2026-05-25T15:12:31.809474Z","steps":["trace[862922021] 'process raft request' (duration: 207.600538ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:31.809416Z","caller":"traceutil/trace.go:171","msg":"trace[1475532531] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"515.733601ms","start":"2026-05-25T15:12:31.293656Z","end":"2026-05-25T15:12:31.80939Z","steps":["trace[1475532531] 'process raft request' (duration: 515.486976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:31.809594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:31.293626Z","time spent":"515.934394ms","remote":"127.0.0.1:39362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:12:31.810129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.630945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T15:12:31.810178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.27428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:12:31.810214Z","caller":"traceutil/trace.go:171","msg":"trace[1726659671] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:24815; }","duration":"287.757318ms","start":"2026-05-25T15:12:31.522436Z","end":"2026-05-25T15:12:31.810193Z","steps":["trace[1726659671] 'agreement among raft nodes before linearized reading' (duration: 287.588865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:31.810217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.51994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-25T15:12:31.810257Z","caller":"traceutil/trace.go:171","msg":"trace[24108015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24815; }","duration":"324.387042ms","start":"2026-05-25T15:12:31.485851Z","end":"2026-05-25T15:12:31.810238Z","steps":["trace[24108015] 'agreement among raft nodes before linearized reading' (duration: 324.26397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:31.81023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.522888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"warn","ts":"2026-05-25T15:12:31.810303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:31.485839Z","time spent":"324.456264ms","remote":"127.0.0.1:39340","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-05-25T15:12:31.810351Z","caller":"traceutil/trace.go:171","msg":"trace[887336963] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:24815; }","duration":"351.69401ms","start":"2026-05-25T15:12:31.458628Z","end":"2026-05-25T15:12:31.810322Z","steps":["trace[887336963] 'agreement among raft nodes before linearized reading' (duration: 351.481026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:31.810939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:31.45861Z","time spent":"352.314762ms","remote":"127.0.0.1:39514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1875,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "} {"level":"info","ts":"2026-05-25T15:12:31.810288Z","caller":"traceutil/trace.go:171","msg":"trace[2129649903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24815; }","duration":"502.638102ms","start":"2026-05-25T15:12:31.307632Z","end":"2026-05-25T15:12:31.81027Z","steps":["trace[2129649903] 'agreement among raft nodes before linearized reading' (duration: 502.452629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:31.811079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:31.307615Z","time spent":"503.443927ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T15:12:31.810477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.359032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-25T15:12:31.810834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.975244ms","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-05-25T15:12:31.811412Z","caller":"traceutil/trace.go:171","msg":"trace[1252677332] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24815; }","duration":"208.555994ms","start":"2026-05-25T15:12:31.60284Z","end":"2026-05-25T15:12:31.811396Z","steps":["trace[1252677332] 'agreement among raft nodes before linearized reading' (duration: 207.151568ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:31.811325Z","caller":"traceutil/trace.go:171","msg":"trace[1088042616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24815; }","duration":"788.197578ms","start":"2026-05-25T15:12:31.023069Z","end":"2026-05-25T15:12:31.811266Z","steps":["trace[1088042616] 'agreement among raft nodes before linearized reading' (duration: 787.25051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:31.811911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:31.023054Z","time spent":"788.80412ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-25T15:12:31.812082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.838623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-25T15:12:31.812149Z","caller":"traceutil/trace.go:171","msg":"trace[383513785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24815; }","duration":"198.554876ms","start":"2026-05-25T15:12:31.613576Z","end":"2026-05-25T15:12:31.812131Z","steps":["trace[383513785] 'agreement among raft nodes before linearized reading' (duration: 196.643769ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:42.055594Z","caller":"traceutil/trace.go:171","msg":"trace[2112907375] transaction","detail":"{read_only:false; response_revision:24942; number_of_response:1; }","duration":"207.913493ms","start":"2026-05-25T15:12:41.847653Z","end":"2026-05-25T15:12:42.055566Z","steps":["trace[2112907375] 'process raft request' (duration: 207.71184ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:42.284618Z","caller":"traceutil/trace.go:171","msg":"trace[1922415933] transaction","detail":"{read_only:false; response_revision:24944; number_of_response:1; }","duration":"429.629287ms","start":"2026-05-25T15:12:41.854979Z","end":"2026-05-25T15:12:42.284608Z","steps":["trace[1922415933] 'process raft request' (duration: 429.501735ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:42.284596Z","caller":"traceutil/trace.go:171","msg":"trace[1485491317] transaction","detail":"{read_only:false; response_revision:24943; number_of_response:1; }","duration":"431.35467ms","start":"2026-05-25T15:12:41.853201Z","end":"2026-05-25T15:12:42.284556Z","steps":["trace[1485491317] 'process raft request' (duration: 337.401888ms)","trace[1485491317] 'compare' (duration: 93.726508ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:42.284724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:41.854974Z","time spent":"429.698398ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:12:42.28474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:41.853182Z","time spent":"431.500913ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:12:42.284873Z","caller":"traceutil/trace.go:171","msg":"trace[1154582547] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"428.809362ms","start":"2026-05-25T15:12:41.856057Z","end":"2026-05-25T15:12:42.284867Z","steps":["trace[1154582547] 'process raft request' (duration: 428.533897ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:42.285008Z","caller":"traceutil/trace.go:171","msg":"trace[1396962098] linearizableReadLoop","detail":"{readStateIndex:25756; appliedIndex:25750; }","duration":"420.647557ms","start":"2026-05-25T15:12:41.864332Z","end":"2026-05-25T15:12:42.284979Z","steps":["trace[1396962098] 'read index received' (duration: 190.986012ms)","trace[1396962098] 'applied index is now lower than readState.Index' (duration: 229.660495ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:42.285088Z","caller":"traceutil/trace.go:171","msg":"trace[1779811113] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"426.986017ms","start":"2026-05-25T15:12:41.858068Z","end":"2026-05-25T15:12:42.285054Z","steps":["trace[1779811113] 'process raft request' (duration: 426.723502ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:42.285233Z","caller":"traceutil/trace.go:171","msg":"trace[660583396] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"272.768282ms","start":"2026-05-25T15:12:42.012401Z","end":"2026-05-25T15:12:42.28517Z","steps":["trace[660583396] 'process raft request' (duration: 272.522288ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:42.28528Z","caller":"traceutil/trace.go:171","msg":"trace[2036879007] transaction","detail":"{read_only:false; response_revision:24945; number_of_response:1; }","duration":"429.583676ms","start":"2026-05-25T15:12:41.855051Z","end":"2026-05-25T15:12:42.284635Z","steps":["trace[2036879007] 'process raft request' (duration: 429.493665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:42.285377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:41.858062Z","time spent":"427.253632ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:12:42.285392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:41.855047Z","time spent":"430.29348ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:12:42.285441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.686778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-25T15:12:42.285461Z","caller":"traceutil/trace.go:171","msg":"trace[1230266430] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24948; }","duration":"285.736909ms","start":"2026-05-25T15:12:41.999718Z","end":"2026-05-25T15:12:42.285455Z","steps":["trace[1230266430] 'agreement among raft nodes before linearized reading' (duration: 285.645927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:42.285446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.092475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-25T15:12:42.285274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:41.856051Z","time spent":"429.204589ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:12:42.285496Z","caller":"traceutil/trace.go:171","msg":"trace[1385016106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24948; }","duration":"421.182177ms","start":"2026-05-25T15:12:41.864299Z","end":"2026-05-25T15:12:42.285481Z","steps":["trace[1385016106] 'agreement among raft nodes before linearized reading' (duration: 421.014354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:42.285532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:41.864288Z","time spent":"421.236338ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T15:12:42.285505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.27035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T15:12:42.285573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.538132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-25T15:12:42.285492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.946167ms","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-05-25T15:12:42.285589Z","caller":"traceutil/trace.go:171","msg":"trace[768558426] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:24948; }","duration":"130.410733ms","start":"2026-05-25T15:12:42.155157Z","end":"2026-05-25T15:12:42.285568Z","steps":["trace[768558426] 'agreement among raft nodes before linearized reading' (duration: 130.27627ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:42.285607Z","caller":"traceutil/trace.go:171","msg":"trace[934251867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24948; }","duration":"414.585862ms","start":"2026-05-25T15:12:41.871011Z","end":"2026-05-25T15:12:42.285597Z","steps":["trace[934251867] 'agreement among raft nodes before linearized reading' (duration: 414.506501ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:42.285615Z","caller":"traceutil/trace.go:171","msg":"trace[2052194910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24948; }","duration":"417.08512ms","start":"2026-05-25T15:12:41.868521Z","end":"2026-05-25T15:12:42.285606Z","steps":["trace[2052194910] 'agreement among raft nodes before linearized reading' (duration: 416.829475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:42.285631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:41.871002Z","time spent":"414.623913ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T15:12:42.285624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.190637ms","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-05-25T15:12:42.285642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.969338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f3c9ba2b-0d02-4ff5-b388-a2ef94f1adc6\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-05-25T15:12:42.285643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:41.868512Z","time spent":"417.124451ms","remote":"127.0.0.1:39614","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-05-25T15:12:42.285679Z","caller":"traceutil/trace.go:171","msg":"trace[1024831321] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f3c9ba2b-0d02-4ff5-b388-a2ef94f1adc6; range_end:; response_count:1; response_revision:24948; }","duration":"368.02271ms","start":"2026-05-25T15:12:41.917643Z","end":"2026-05-25T15:12:42.285666Z","steps":["trace[1024831321] 'agreement among raft nodes before linearized reading' (duration: 367.913778ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:42.285678Z","caller":"traceutil/trace.go:171","msg":"trace[324205035] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24948; }","duration":"412.264819ms","start":"2026-05-25T15:12:41.873396Z","end":"2026-05-25T15:12:42.285661Z","steps":["trace[324205035] 'agreement among raft nodes before linearized reading' (duration: 412.120186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:42.285717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:41.917635Z","time spent":"368.07384ms","remote":"127.0.0.1:39530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f3c9ba2b-0d02-4ff5-b388-a2ef94f1adc6\" "} {"level":"warn","ts":"2026-05-25T15:12:42.285726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:41.873386Z","time spent":"412.32973ms","remote":"127.0.0.1:39614","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":"warn","ts":"2026-05-25T15:12:42.553022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.963613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:12:42.553244Z","caller":"traceutil/trace.go:171","msg":"trace[797026913] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"258.762928ms","start":"2026-05-25T15:12:42.29445Z","end":"2026-05-25T15:12:42.553213Z","steps":["trace[797026913] 'process raft request' (duration: 116.52221ms)","trace[797026913] 'compare' (duration: 141.848851ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:42.553289Z","caller":"traceutil/trace.go:171","msg":"trace[37107240] linearizableReadLoop","detail":"{readStateIndex:25761; appliedIndex:25757; }","duration":"250.269725ms","start":"2026-05-25T15:12:42.303002Z","end":"2026-05-25T15:12:42.553272Z","steps":["trace[37107240] 'read index received' (duration: 107.985107ms)","trace[37107240] 'applied index is now lower than readState.Index' (duration: 142.283728ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:42.553341Z","caller":"traceutil/trace.go:171","msg":"trace[605734241] transaction","detail":"{read_only:false; response_revision:24951; number_of_response:1; }","duration":"257.355071ms","start":"2026-05-25T15:12:42.295956Z","end":"2026-05-25T15:12:42.553311Z","steps":["trace[605734241] 'process raft request' (duration: 257.246829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:42.553527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.517961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f3c9ba2b-0d02-4ff5-b388-a2ef94f1adc6\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-25T15:12:42.553571Z","caller":"traceutil/trace.go:171","msg":"trace[710647643] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f3c9ba2b-0d02-4ff5-b388-a2ef94f1adc6; range_end:; response_count:1; response_revision:24952; }","duration":"250.563052ms","start":"2026-05-25T15:12:42.302997Z","end":"2026-05-25T15:12:42.55356Z","steps":["trace[710647643] 'agreement among raft nodes before linearized reading' (duration: 250.399239ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:42.55363Z","caller":"traceutil/trace.go:171","msg":"trace[973306487] transaction","detail":"{read_only:false; response_revision:24950; number_of_response:1; }","duration":"258.022003ms","start":"2026-05-25T15:12:42.295597Z","end":"2026-05-25T15:12:42.553619Z","steps":["trace[973306487] 'process raft request' (duration: 257.538164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:42.553724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.724694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:12:42.553814Z","caller":"traceutil/trace.go:171","msg":"trace[916385839] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:24952; }","duration":"232.860116ms","start":"2026-05-25T15:12:42.320935Z","end":"2026-05-25T15:12:42.553795Z","steps":["trace[916385839] 'agreement among raft nodes before linearized reading' (duration: 232.686893ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:42.553379Z","caller":"traceutil/trace.go:171","msg":"trace[1064740007] transaction","detail":"{read_only:false; response_revision:24952; number_of_response:1; }","duration":"256.542055ms","start":"2026-05-25T15:12:42.296827Z","end":"2026-05-25T15:12:42.553369Z","steps":["trace[1064740007] 'process raft request' (duration: 256.410413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:42.553946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.304035ms","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-05-25T15:12:42.553982Z","caller":"traceutil/trace.go:171","msg":"trace[636938150] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24952; }","duration":"194.364095ms","start":"2026-05-25T15:12:42.359609Z","end":"2026-05-25T15:12:42.553973Z","steps":["trace[636938150] 'agreement among raft nodes before linearized reading' (duration: 194.305694ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:42.79678Z","caller":"traceutil/trace.go:171","msg":"trace[374648729] transaction","detail":"{read_only:false; response_revision:24953; number_of_response:1; }","duration":"236.890143ms","start":"2026-05-25T15:12:42.559867Z","end":"2026-05-25T15:12:42.796757Z","steps":["trace[374648729] 'process raft request' (duration: 212.782165ms)","trace[374648729] 'compare' (duration: 24.012426ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:42.970355Z","caller":"traceutil/trace.go:171","msg":"trace[1586641763] transaction","detail":"{read_only:false; response_revision:24954; number_of_response:1; }","duration":"408.893914ms","start":"2026-05-25T15:12:42.561407Z","end":"2026-05-25T15:12:42.970301Z","steps":["trace[1586641763] 'process raft request' (duration: 408.612679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:42.970577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:42.561382Z","time spent":"409.079828ms","remote":"127.0.0.1:39530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:12:42.971095Z","caller":"traceutil/trace.go:171","msg":"trace[1219598701] linearizableReadLoop","detail":"{readStateIndex:25763; appliedIndex:25761; }","duration":"408.228342ms","start":"2026-05-25T15:12:42.562852Z","end":"2026-05-25T15:12:42.971081Z","steps":["trace[1219598701] 'read index received' (duration: 209.930561ms)","trace[1219598701] 'applied index is now lower than readState.Index' (duration: 198.293701ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:42.971258Z","caller":"traceutil/trace.go:171","msg":"trace[907351333] transaction","detail":"{read_only:false; response_revision:24955; number_of_response:1; }","duration":"368.653372ms","start":"2026-05-25T15:12:42.60257Z","end":"2026-05-25T15:12:42.971223Z","steps":["trace[907351333] 'process raft request' (duration: 367.623162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:42.971355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.492597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-25T15:12:42.971401Z","caller":"traceutil/trace.go:171","msg":"trace[1695421388] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24955; }","duration":"408.552379ms","start":"2026-05-25T15:12:42.562836Z","end":"2026-05-25T15:12:42.971389Z","steps":["trace[1695421388] 'agreement among raft nodes before linearized reading' (duration: 408.296234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:42.971482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:42.562828Z","time spent":"408.64505ms","remote":"127.0.0.1:39530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-25T15:12:42.971477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:42.602547Z","time spent":"368.797545ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:12:43.515055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.295571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:12:43.515188Z","caller":"traceutil/trace.go:171","msg":"trace[117186412] linearizableReadLoop","detail":"{readStateIndex:25765; appliedIndex:25764; }","duration":"413.810857ms","start":"2026-05-25T15:12:43.101357Z","end":"2026-05-25T15:12:43.515168Z","steps":["trace[117186412] 'read index received' (duration: 176.334293ms)","trace[117186412] 'applied index is now lower than readState.Index' (duration: 237.474774ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:43.515196Z","caller":"traceutil/trace.go:171","msg":"trace[2078436745] transaction","detail":"{read_only:false; response_revision:24956; number_of_response:1; }","duration":"454.243694ms","start":"2026-05-25T15:12:43.060931Z","end":"2026-05-25T15:12:43.515175Z","steps":["trace[2078436745] 'process raft request' (duration: 216.73266ms)","trace[2078436745] 'compare' (duration: 237.172308ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:43.515291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:43.060866Z","time spent":"454.378507ms","remote":"127.0.0.1:39614","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-05-25T15:12:43.515356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.99663ms","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":"info","ts":"2026-05-25T15:12:43.51541Z","caller":"traceutil/trace.go:171","msg":"trace[266647869] 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:24956; }","duration":"414.087053ms","start":"2026-05-25T15:12:43.101307Z","end":"2026-05-25T15:12:43.515394Z","steps":["trace[266647869] 'agreement among raft nodes before linearized reading' (duration: 413.978521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:43.515494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:43.101256Z","time spent":"414.221196ms","remote":"127.0.0.1:33550","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-05-25T15:12:43.515738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.866629ms","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-05-25T15:12:43.515814Z","caller":"traceutil/trace.go:171","msg":"trace[1632561435] 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:24956; }","duration":"365.009052ms","start":"2026-05-25T15:12:43.150786Z","end":"2026-05-25T15:12:43.515795Z","steps":["trace[1632561435] 'agreement among raft nodes before linearized reading' (duration: 364.866109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:43.515819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.411864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f3c9ba2b-0d02-4ff5-b388-a2ef94f1adc6\" ","response":"range_response_count:1 size:3764"} {"level":"warn","ts":"2026-05-25T15:12:43.515865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:43.150758Z","time spent":"365.086804ms","remote":"127.0.0.1:33502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true "} {"level":"info","ts":"2026-05-25T15:12:43.515889Z","caller":"traceutil/trace.go:171","msg":"trace[1027987905] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f3c9ba2b-0d02-4ff5-b388-a2ef94f1adc6; range_end:; response_count:1; response_revision:24956; }","duration":"225.527076ms","start":"2026-05-25T15:12:43.290344Z","end":"2026-05-25T15:12:43.515871Z","steps":["trace[1027987905] 'agreement among raft nodes before linearized reading' (duration: 225.306542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:43.823218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.745352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-25T15:12:43.823307Z","caller":"traceutil/trace.go:171","msg":"trace[2141232838] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:24956; }","duration":"118.896935ms","start":"2026-05-25T15:12:43.704388Z","end":"2026-05-25T15:12:43.823285Z","steps":["trace[2141232838] 'count revisions from in-memory index tree' (duration: 118.63342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:45.894223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.282036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-25T15:12:45.894324Z","caller":"traceutil/trace.go:171","msg":"trace[667987786] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24971; }","duration":"183.42705ms","start":"2026-05-25T15:12:45.710872Z","end":"2026-05-25T15:12:45.894299Z","steps":["trace[667987786] 'range keys from in-memory index tree' (duration: 183.084922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:49.778735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.98897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:12:49.778872Z","caller":"traceutil/trace.go:171","msg":"trace[1317607449] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"496.963555ms","start":"2026-05-25T15:12:49.281865Z","end":"2026-05-25T15:12:49.778828Z","steps":["trace[1317607449] 'process raft request' (duration: 217.795701ms)","trace[1317607449] 'compare' (duration: 278.619393ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:49.778914Z","caller":"traceutil/trace.go:171","msg":"trace[2137617542] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"192.655894ms","start":"2026-05-25T15:12:49.586242Z","end":"2026-05-25T15:12:49.778898Z","steps":["trace[2137617542] 'process raft request' (duration: 192.586343ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:49.778971Z","caller":"traceutil/trace.go:171","msg":"trace[877247318] linearizableReadLoop","detail":"{readStateIndex:25831; appliedIndex:25830; }","duration":"377.47929ms","start":"2026-05-25T15:12:49.401461Z","end":"2026-05-25T15:12:49.778941Z","steps":["trace[877247318] 'read index received' (duration: 98.154132ms)","trace[877247318] 'applied index is now lower than readState.Index' (duration: 279.323028ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:49.77903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:49.281849Z","time spent":"497.096837ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:12:49.779103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.637632ms","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":"warn","ts":"2026-05-25T15:12:49.779155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.523134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:12:49.779156Z","caller":"traceutil/trace.go:171","msg":"trace[257529288] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:25022; }","duration":"377.725393ms","start":"2026-05-25T15:12:49.401416Z","end":"2026-05-25T15:12:49.779142Z","steps":["trace[257529288] 'agreement among raft nodes before linearized reading' (duration: 377.635992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:49.779168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.24816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T15:12:49.779173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.310379ms","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-05-25T15:12:49.77918Z","caller":"traceutil/trace.go:171","msg":"trace[1059192919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25022; }","duration":"295.597736ms","start":"2026-05-25T15:12:49.483575Z","end":"2026-05-25T15:12:49.779173Z","steps":["trace[1059192919] 'agreement among raft nodes before linearized reading' (duration: 295.552665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:49.779193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:49.401394Z","time spent":"377.790125ms","remote":"127.0.0.1:39554","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":"info","ts":"2026-05-25T15:12:49.779212Z","caller":"traceutil/trace.go:171","msg":"trace[1809267869] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25022; }","duration":"336.379979ms","start":"2026-05-25T15:12:49.442822Z","end":"2026-05-25T15:12:49.779202Z","steps":["trace[1809267869] 'agreement among raft nodes before linearized reading' (duration: 336.269847ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:49.77921Z","caller":"traceutil/trace.go:171","msg":"trace[1656397805] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:25022; }","duration":"158.342712ms","start":"2026-05-25T15:12:49.620853Z","end":"2026-05-25T15:12:49.779196Z","steps":["trace[1656397805] 'agreement among raft nodes before linearized reading' (duration: 158.251881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:49.779236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:49.442804Z","time spent":"336.426041ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-25T15:13:00.374667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.057449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:13:00.374849Z","caller":"traceutil/trace.go:171","msg":"trace[118355961] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"135.557281ms","start":"2026-05-25T15:13:00.239276Z","end":"2026-05-25T15:13:00.374833Z","steps":["trace[118355961] 'process raft request' (duration: 135.48419ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:00.374924Z","caller":"traceutil/trace.go:171","msg":"trace[1304275333] linearizableReadLoop","detail":"{readStateIndex:25915; appliedIndex:25914; }","duration":"381.105557ms","start":"2026-05-25T15:12:59.993791Z","end":"2026-05-25T15:13:00.374897Z","steps":["trace[1304275333] 'read index received' (duration: 56.211µs)","trace[1304275333] 'applied index is now lower than readState.Index' (duration: 381.047146ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:00.375078Z","caller":"traceutil/trace.go:171","msg":"trace[1351063746] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"557.135266ms","start":"2026-05-25T15:12:59.817927Z","end":"2026-05-25T15:13:00.375062Z","steps":["trace[1351063746] 'process raft request' (duration: 148.597018ms)","trace[1351063746] 'compare' (duration: 407.662761ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:00.375215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.425813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:00.375262Z","caller":"traceutil/trace.go:171","msg":"trace[235619704] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:25104; }","duration":"381.516685ms","start":"2026-05-25T15:12:59.993733Z","end":"2026-05-25T15:13:00.375249Z","steps":["trace[235619704] 'agreement among raft nodes before linearized reading' (duration: 381.445054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:00.375295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:59.993709Z","time spent":"381.576127ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true "} {"level":"warn","ts":"2026-05-25T15:13:00.375335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:59.817918Z","time spent":"557.239838ms","remote":"127.0.0.1:39614","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-05-25T15:13:00.375523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.45491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:00.375597Z","caller":"traceutil/trace.go:171","msg":"trace[1234475911] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25105; }","duration":"134.579092ms","start":"2026-05-25T15:13:00.241Z","end":"2026-05-25T15:13:00.375579Z","steps":["trace[1234475911] 'agreement among raft nodes before linearized reading' (duration: 134.411519ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:03.117964Z","caller":"traceutil/trace.go:171","msg":"trace[1940288636] linearizableReadLoop","detail":"{readStateIndex:25943; appliedIndex:25942; }","duration":"216.20577ms","start":"2026-05-25T15:13:02.901736Z","end":"2026-05-25T15:13:03.117942Z","steps":["trace[1940288636] 'read index received' (duration: 216.048647ms)","trace[1940288636] 'applied index is now lower than readState.Index' (duration: 156.083µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:03.118051Z","caller":"traceutil/trace.go:171","msg":"trace[1002025858] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"221.941628ms","start":"2026-05-25T15:13:02.89606Z","end":"2026-05-25T15:13:03.118002Z","steps":["trace[1002025858] 'process raft request' (duration: 221.744215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:03.118207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.393644ms","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-05-25T15:13:03.118645Z","caller":"traceutil/trace.go:171","msg":"trace[1810466310] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25130; }","duration":"216.929644ms","start":"2026-05-25T15:13:02.901693Z","end":"2026-05-25T15:13:03.118623Z","steps":["trace[1810466310] 'agreement among raft nodes before linearized reading' (duration: 216.374194ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:05.618484Z","caller":"traceutil/trace.go:171","msg":"trace[940995101] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"105.647683ms","start":"2026-05-25T15:13:05.512794Z","end":"2026-05-25T15:13:05.618441Z","steps":["trace[940995101] 'process raft request' (duration: 105.44247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:06.053212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.216337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-25T15:13:06.053222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.667956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-25T15:13:06.053297Z","caller":"traceutil/trace.go:171","msg":"trace[1369446023] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:25147; }","duration":"211.339939ms","start":"2026-05-25T15:13:05.841938Z","end":"2026-05-25T15:13:06.053278Z","steps":["trace[1369446023] 'count revisions from in-memory index tree' (duration: 211.162205ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:06.05332Z","caller":"traceutil/trace.go:171","msg":"trace[1449560751] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25147; }","duration":"123.814429ms","start":"2026-05-25T15:13:05.929482Z","end":"2026-05-25T15:13:06.053297Z","steps":["trace[1449560751] 'range keys from in-memory index tree' (duration: 123.493313ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:07.338875Z","caller":"traceutil/trace.go:171","msg":"trace[1023687348] linearizableReadLoop","detail":"{readStateIndex:25974; appliedIndex:25973; }","duration":"127.495368ms","start":"2026-05-25T15:13:07.211355Z","end":"2026-05-25T15:13:07.33885Z","steps":["trace[1023687348] 'read index received' (duration: 127.309984ms)","trace[1023687348] 'applied index is now lower than readState.Index' (duration: 184.494µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:07.338954Z","caller":"traceutil/trace.go:171","msg":"trace[995675866] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"191.276228ms","start":"2026-05-25T15:13:07.147646Z","end":"2026-05-25T15:13:07.338922Z","steps":["trace[995675866] 'process raft request' (duration: 191.043412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:07.339177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.754283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10851"} {"level":"info","ts":"2026-05-25T15:13:07.339246Z","caller":"traceutil/trace.go:171","msg":"trace[1307269601] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25160; }","duration":"127.934426ms","start":"2026-05-25T15:13:07.211295Z","end":"2026-05-25T15:13:07.339229Z","steps":["trace[1307269601] 'agreement among raft nodes before linearized reading' (duration: 127.685172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:07.593446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.184743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:13:07.59368Z","caller":"traceutil/trace.go:171","msg":"trace[485560887] transaction","detail":"{read_only:false; response_revision:25162; number_of_response:1; }","duration":"241.416409ms","start":"2026-05-25T15:13:07.352246Z","end":"2026-05-25T15:13:07.593662Z","steps":["trace[485560887] 'process raft request' (duration: 241.348217ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:07.593707Z","caller":"traceutil/trace.go:171","msg":"trace[1232167480] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"251.253725ms","start":"2026-05-25T15:13:07.342426Z","end":"2026-05-25T15:13:07.593679Z","steps":["trace[1232167480] 'process raft request' (duration: 125.752375ms)","trace[1232167480] 'compare' (duration: 125.071901ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:07.594143Z","caller":"traceutil/trace.go:171","msg":"trace[576254473] linearizableReadLoop","detail":"{readStateIndex:25975; appliedIndex:25974; }","duration":"250.473391ms","start":"2026-05-25T15:13:07.343338Z","end":"2026-05-25T15:13:07.593812Z","steps":["trace[576254473] 'read index received' (duration: 124.836318ms)","trace[576254473] 'applied index is now lower than readState.Index' (duration: 125.580262ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:07.594241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.871078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f3c9ba2b-0d02-4ff5-b388-a2ef94f1adc6\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-25T15:13:07.594304Z","caller":"traceutil/trace.go:171","msg":"trace[1071985576] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f3c9ba2b-0d02-4ff5-b388-a2ef94f1adc6; range_end:; response_count:1; response_revision:25162; }","duration":"251.008321ms","start":"2026-05-25T15:13:07.343281Z","end":"2026-05-25T15:13:07.594289Z","steps":["trace[1071985576] 'agreement among raft nodes before linearized reading' (duration: 250.775766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:07.59452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.612454ms","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-05-25T15:13:07.59464Z","caller":"traceutil/trace.go:171","msg":"trace[78479569] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25162; }","duration":"134.736656ms","start":"2026-05-25T15:13:07.459882Z","end":"2026-05-25T15:13:07.594618Z","steps":["trace[78479569] 'agreement among raft nodes before linearized reading' (duration: 134.573653ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:10.013131Z","caller":"traceutil/trace.go:171","msg":"trace[1619524943] transaction","detail":"{read_only:false; response_revision:25186; number_of_response:1; }","duration":"191.628073ms","start":"2026-05-25T15:13:09.821472Z","end":"2026-05-25T15:13:10.0131Z","steps":["trace[1619524943] 'process raft request' (duration: 191.344078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:10.408441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.537392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:13:10.408707Z","caller":"traceutil/trace.go:171","msg":"trace[1568513616] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"506.998425ms","start":"2026-05-25T15:13:09.901664Z","end":"2026-05-25T15:13:10.408663Z","steps":["trace[1568513616] 'process raft request' (duration: 237.118167ms)","trace[1568513616] 'compare' (duration: 269.41653ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:10.408875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:09.901637Z","time spent":"507.140148ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:10.720971Z","caller":"traceutil/trace.go:171","msg":"trace[2104216716] transaction","detail":"{read_only:false; response_revision:25188; number_of_response:1; }","duration":"301.873705ms","start":"2026-05-25T15:13:10.419075Z","end":"2026-05-25T15:13:10.720949Z","steps":["trace[2104216716] 'process raft request' (duration: 301.730802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:10.721115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:10.419056Z","time spent":"301.986868ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:10.732131Z","caller":"traceutil/trace.go:171","msg":"trace[1613494644] linearizableReadLoop","detail":"{readStateIndex:26009; appliedIndex:26002; }","duration":"244.122989ms","start":"2026-05-25T15:13:10.487947Z","end":"2026-05-25T15:13:10.73207Z","steps":["trace[1613494644] 'read index received' (duration: 233.11907ms)","trace[1613494644] 'applied index is now lower than readState.Index' (duration: 11.002239ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:10.732316Z","caller":"traceutil/trace.go:171","msg":"trace[46241297] transaction","detail":"{read_only:false; response_revision:25192; number_of_response:1; }","duration":"276.26685ms","start":"2026-05-25T15:13:10.456021Z","end":"2026-05-25T15:13:10.732288Z","steps":["trace[46241297] 'process raft request' (duration: 275.869192ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:10.732478Z","caller":"traceutil/trace.go:171","msg":"trace[1608974916] transaction","detail":"{read_only:false; response_revision:25189; number_of_response:1; }","duration":"296.687437ms","start":"2026-05-25T15:13:10.435781Z","end":"2026-05-25T15:13:10.732468Z","steps":["trace[1608974916] 'process raft request' (duration: 295.830501ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:10.732573Z","caller":"traceutil/trace.go:171","msg":"trace[635864054] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"266.648057ms","start":"2026-05-25T15:13:10.465903Z","end":"2026-05-25T15:13:10.732551Z","steps":["trace[635864054] 'process raft request' (duration: 266.031735ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:10.732643Z","caller":"traceutil/trace.go:171","msg":"trace[378039974] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"296.586225ms","start":"2026-05-25T15:13:10.436041Z","end":"2026-05-25T15:13:10.732627Z","steps":["trace[378039974] 'process raft request' (duration: 295.75873ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:10.732762Z","caller":"traceutil/trace.go:171","msg":"trace[1811008266] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"264.707999ms","start":"2026-05-25T15:13:10.468022Z","end":"2026-05-25T15:13:10.73273Z","steps":["trace[1811008266] 'process raft request' (duration: 263.953385ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:10.7328Z","caller":"traceutil/trace.go:171","msg":"trace[1993299710] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"279.795976ms","start":"2026-05-25T15:13:10.452989Z","end":"2026-05-25T15:13:10.732785Z","steps":["trace[1993299710] 'process raft request' (duration: 278.863409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:10.732893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.941195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:10.732929Z","caller":"traceutil/trace.go:171","msg":"trace[1806485069] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"264.889393ms","start":"2026-05-25T15:13:10.468028Z","end":"2026-05-25T15:13:10.732917Z","steps":["trace[1806485069] 'process raft request' (duration: 264.002336ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:10.732955Z","caller":"traceutil/trace.go:171","msg":"trace[1684957319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25195; }","duration":"245.041217ms","start":"2026-05-25T15:13:10.487886Z","end":"2026-05-25T15:13:10.732927Z","steps":["trace[1684957319] 'agreement among raft nodes before linearized reading' (duration: 244.934675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:11.297696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.889117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:11.298119Z","caller":"traceutil/trace.go:171","msg":"trace[1863832588] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25201; }","duration":"165.339015ms","start":"2026-05-25T15:13:11.132763Z","end":"2026-05-25T15:13:11.298102Z","steps":["trace[1863832588] 'count revisions from in-memory index tree' (duration: 164.742975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:11.298042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.256643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-05-25T15:13:11.298264Z","caller":"traceutil/trace.go:171","msg":"trace[874458117] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25201; }","duration":"233.522278ms","start":"2026-05-25T15:13:11.064732Z","end":"2026-05-25T15:13:11.298254Z","steps":["trace[874458117] 'range keys from in-memory index tree' (duration: 233.08627ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:23.95262Z","caller":"traceutil/trace.go:171","msg":"trace[1201388873] linearizableReadLoop","detail":"{readStateIndex:26106; appliedIndex:26105; }","duration":"468.601816ms","start":"2026-05-25T15:13:23.483992Z","end":"2026-05-25T15:13:23.952594Z","steps":["trace[1201388873] 'read index received' (duration: 468.383692ms)","trace[1201388873] 'applied index is now lower than readState.Index' (duration: 216.944µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:23.952848Z","caller":"traceutil/trace.go:171","msg":"trace[666289790] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"505.642729ms","start":"2026-05-25T15:13:23.44719Z","end":"2026-05-25T15:13:23.952833Z","steps":["trace[666289790] 'process raft request' (duration: 505.224711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:23.952934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.090956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:23.953026Z","caller":"traceutil/trace.go:171","msg":"trace[1852666961] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25289; }","duration":"286.236608ms","start":"2026-05-25T15:13:23.666767Z","end":"2026-05-25T15:13:23.953004Z","steps":["trace[1852666961] 'agreement among raft nodes before linearized reading' (duration: 286.091905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:23.953104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.090316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:23.953198Z","caller":"traceutil/trace.go:171","msg":"trace[22440056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25289; }","duration":"469.243008ms","start":"2026-05-25T15:13:23.483933Z","end":"2026-05-25T15:13:23.953176Z","steps":["trace[22440056] 'agreement among raft nodes before linearized reading' (duration: 469.059235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:23.953252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:23.483909Z","time spent":"469.33418ms","remote":"127.0.0.1:39340","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-05-25T15:13:23.952992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:23.447157Z","time spent":"505.750161ms","remote":"127.0.0.1:39614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:23.953529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.177687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-25T15:13:23.953625Z","caller":"traceutil/trace.go:171","msg":"trace[1704538657] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25289; }","duration":"238.326681ms","start":"2026-05-25T15:13:23.715273Z","end":"2026-05-25T15:13:23.9536Z","steps":["trace[1704538657] 'agreement among raft nodes before linearized reading' (duration: 237.751479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.245213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.881679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:24.245332Z","caller":"traceutil/trace.go:171","msg":"trace[2020857271] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25289; }","duration":"105.081603ms","start":"2026-05-25T15:13:24.140221Z","end":"2026-05-25T15:13:24.245303Z","steps":["trace[2020857271] 'count revisions from in-memory index tree' (duration: 104.761987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.245571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.618209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:24.245666Z","caller":"traceutil/trace.go:171","msg":"trace[1279464282] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:25289; }","duration":"225.774482ms","start":"2026-05-25T15:13:24.01987Z","end":"2026-05-25T15:13:24.245644Z","steps":["trace[1279464282] 'count revisions from in-memory index tree' (duration: 225.541847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.245706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.767964ms","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-05-25T15:13:24.245759Z","caller":"traceutil/trace.go:171","msg":"trace[570722116] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25289; }","duration":"223.860345ms","start":"2026-05-25T15:13:24.021883Z","end":"2026-05-25T15:13:24.245743Z","steps":["trace[570722116] 'range keys from in-memory index tree' (duration: 223.656472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.246307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.535336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:24.246389Z","caller":"traceutil/trace.go:171","msg":"trace[1430219485] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25289; }","duration":"265.635518ms","start":"2026-05-25T15:13:23.980733Z","end":"2026-05-25T15:13:24.246368Z","steps":["trace[1430219485] 'count revisions from in-memory index tree' (duration: 265.403064ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:25.631715Z","caller":"traceutil/trace.go:171","msg":"trace[362183735] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"537.412703ms","start":"2026-05-25T15:13:25.094272Z","end":"2026-05-25T15:13:25.631684Z","steps":["trace[362183735] 'process raft request' (duration: 524.248343ms)","trace[362183735] 'compare' (duration: 12.984535ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:25.631859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:25.094249Z","time spent":"537.538595ms","remote":"127.0.0.1:39530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3699,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:25.647045Z","caller":"traceutil/trace.go:171","msg":"trace[1102573084] linearizableReadLoop","detail":"{readStateIndex:26123; appliedIndex:26121; }","duration":"162.295428ms","start":"2026-05-25T15:13:25.484723Z","end":"2026-05-25T15:13:25.647018Z","steps":["trace[1102573084] 'read index received' (duration: 133.752147ms)","trace[1102573084] 'applied index is now lower than readState.Index' (duration: 28.542291ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:25.647331Z","caller":"traceutil/trace.go:171","msg":"trace[1038926620] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"217.79432ms","start":"2026-05-25T15:13:25.429524Z","end":"2026-05-25T15:13:25.647319Z","steps":["trace[1038926620] 'process raft request' (duration: 217.364442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:25.647494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.748566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:25.647562Z","caller":"traceutil/trace.go:171","msg":"trace[1271866307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25306; }","duration":"162.871719ms","start":"2026-05-25T15:13:25.484674Z","end":"2026-05-25T15:13:25.647546Z","steps":["trace[1271866307] 'agreement among raft nodes before linearized reading' (duration: 162.729936ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:26.976961Z","caller":"traceutil/trace.go:171","msg":"trace[1817425701] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"104.220286ms","start":"2026-05-25T15:13:26.872701Z","end":"2026-05-25T15:13:26.976921Z","steps":["trace[1817425701] 'process raft request' (duration: 56.602783ms)","trace[1817425701] 'compare' (duration: 47.44857ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:26.978252Z","caller":"traceutil/trace.go:171","msg":"trace[2118178958] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"105.351037ms","start":"2026-05-25T15:13:26.872886Z","end":"2026-05-25T15:13:26.978237Z","steps":["trace[2118178958] 'process raft request' (duration: 105.201554ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:29.70523Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068a7.wal"} {"level":"info","ts":"2026-05-25T15:15:17.869242Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23552} {"level":"info","ts":"2026-05-25T15:15:18.002748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23552,"took":"129.390015ms","hash":755878537,"current-db-size-bytes":59695104,"current-db-size":"60 MB","current-db-size-in-use-bytes":36708352,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-25T15:15:18.002825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":755878537,"revision":23552,"compact-revision":20304} {"level":"info","ts":"2026-05-25T15:20:17.875248Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26407} {"level":"info","ts":"2026-05-25T15:20:18.014282Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26407,"took":"135.803535ms","hash":3156739098,"current-db-size-bytes":59695104,"current-db-size":"60 MB","current-db-size-in-use-bytes":34287616,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-25T15:20:18.014359Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3156739098,"revision":26407,"compact-revision":23552} {"level":"info","ts":"2026-05-25T15:20:23.306659Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T15:20:23.310983Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-25T15:20:23.311081Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-25T15:25:17.894948Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29037} {"level":"info","ts":"2026-05-25T15:25:18.029875Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29037,"took":"131.942523ms","hash":791321181,"current-db-size-bytes":59695104,"current-db-size":"60 MB","current-db-size-in-use-bytes":30482432,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-25T15:25:18.029949Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":791321181,"revision":29037,"compact-revision":26407} {"level":"info","ts":"2026-05-25T15:30:17.899927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31263} {"level":"info","ts":"2026-05-25T15:30:18.025255Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31263,"took":"120.900997ms","hash":3428163701,"current-db-size-bytes":59695104,"current-db-size":"60 MB","current-db-size-in-use-bytes":30416896,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-25T15:30:18.025342Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3428163701,"revision":31263,"compact-revision":29037} {"level":"info","ts":"2026-05-25T15:35:17.903963Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33523} {"level":"info","ts":"2026-05-25T15:35:18.043695Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33523,"took":"135.956969ms","hash":3734692290,"current-db-size-bytes":59695104,"current-db-size":"60 MB","current-db-size-in-use-bytes":31932416,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-25T15:35:18.043767Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3734692290,"revision":33523,"compact-revision":31263} {"level":"info","ts":"2026-05-25T15:40:17.910196Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35826} {"level":"info","ts":"2026-05-25T15:40:18.055619Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35826,"took":"140.872216ms","hash":1139092086,"current-db-size-bytes":61448192,"current-db-size":"61 MB","current-db-size-in-use-bytes":31260672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-25T15:40:18.055723Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1139092086,"revision":35826,"compact-revision":33523} {"level":"info","ts":"2026-05-25T15:41:53.243584Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T15:41:53.248225Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-25T15:41:53.248296Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-25T15:45:17.917331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38048} {"level":"info","ts":"2026-05-25T15:45:18.033309Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38048,"took":"111.942041ms","hash":1632377964,"current-db-size-bytes":61448192,"current-db-size":"61 MB","current-db-size-in-use-bytes":31678464,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-25T15:45:18.033378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1632377964,"revision":38048,"compact-revision":35826}