{"level":"warn","ts":"2026-09-08T15:39:52.143507Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T15:39:52.143649Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.5: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://162.253.55.5:2380","--initial-cluster=instance=https://162.253.55.5:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.5:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.5: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-09-08T15:39:52.14374Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T15:39:52.143749Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.5:2380"]} {"level":"info","ts":"2026-09-08T15:39:52.143768Z","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-09-08T15:39:52.144633Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"]} {"level":"info","ts":"2026-09-08T15:39:52.144884Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.5:2380"],"listen-peer-urls":["https://162.253.55.5:2380"],"advertise-client-urls":["https://162.253.55.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.5: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-09-08T15:39:52.152902Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.912793ms"} {"level":"info","ts":"2026-09-08T15:39:52.164798Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b512f7d8ac93be25","cluster-id":"e1953d9a3e7368e5"} {"level":"info","ts":"2026-09-08T15:39:52.16489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T15:39:52.164914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became follower at term 0"} {"level":"info","ts":"2026-09-08T15:39:52.164929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b512f7d8ac93be25 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T15:39:52.164938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became follower at term 1"} {"level":"info","ts":"2026-09-08T15:39:52.164981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=(13047763580426108453)"} {"level":"warn","ts":"2026-09-08T15:39:52.327799Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T15:39:52.329943Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T15:39:52.331864Z","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-09-08T15:39:52.334442Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b512f7d8ac93be25","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T15:39:52.335145Z","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-09-08T15:39:52.335215Z","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-09-08T15:39:52.335228Z","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-09-08T15:39:52.335344Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b512f7d8ac93be25","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T15:39:52.338945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=(13047763580426108453)"} {"level":"info","ts":"2026-09-08T15:39:52.339511Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e1953d9a3e7368e5","local-member-id":"b512f7d8ac93be25","added-peer-id":"b512f7d8ac93be25","added-peer-peer-urls":["https://162.253.55.5:2380"]} {"level":"info","ts":"2026-09-08T15:39:52.340392Z","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-09-08T15:39:52.340638Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b512f7d8ac93be25","initial-advertise-peer-urls":["https://162.253.55.5:2380"],"listen-peer-urls":["https://162.253.55.5:2380"],"advertise-client-urls":["https://162.253.55.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T15:39:52.34067Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T15:39:52.340821Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.5:2380"} {"level":"info","ts":"2026-09-08T15:39:52.340838Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.5:2380"} {"level":"info","ts":"2026-09-08T15:39:52.567086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T15:39:52.567234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T15:39:52.567269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 received MsgPreVoteResp from b512f7d8ac93be25 at term 1"} {"level":"info","ts":"2026-09-08T15:39:52.567301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became candidate at term 2"} {"level":"info","ts":"2026-09-08T15:39:52.567317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 received MsgVoteResp from b512f7d8ac93be25 at term 2"} {"level":"info","ts":"2026-09-08T15:39:52.567336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became leader at term 2"} {"level":"info","ts":"2026-09-08T15:39:52.567355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b512f7d8ac93be25 elected leader b512f7d8ac93be25 at term 2"} {"level":"info","ts":"2026-09-08T15:39:52.568985Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b512f7d8ac93be25","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.5:2379]}","request-path":"/0/members/b512f7d8ac93be25/attributes","cluster-id":"e1953d9a3e7368e5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T15:39:52.568994Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:39:52.569048Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:39:52.568987Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:39:52.570322Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e1953d9a3e7368e5","local-member-id":"b512f7d8ac93be25","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:39:52.570431Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:39:52.570462Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:39:52.570543Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T15:39:52.570631Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T15:39:52.572294Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.5:2379"} {"level":"info","ts":"2026-09-08T15:39:52.573123Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-08T15:39:57.568202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.369977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:39:57.568407Z","caller":"traceutil/trace.go:171","msg":"trace[1426943647] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"217.779402ms","start":"2026-09-08T15:39:57.350612Z","end":"2026-09-08T15:39:57.568391Z","steps":["trace[1426943647] 'process raft request' (duration: 12.42965ms)","trace[1426943647] 'compare' (duration: 204.20464ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:39:57.568661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.091674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-09-08T15:39:57.568758Z","caller":"traceutil/trace.go:171","msg":"trace[399845231] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:276; }","duration":"182.240501ms","start":"2026-09-08T15:39:57.386501Z","end":"2026-09-08T15:39:57.568742Z","steps":["trace[399845231] 'agreement among raft nodes before linearized reading' (duration: 181.9958ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:39:57.56834Z","caller":"traceutil/trace.go:171","msg":"trace[861472242] linearizableReadLoop","detail":"{readStateIndex:282; appliedIndex:281; }","duration":"181.769919ms","start":"2026-09-08T15:39:57.386552Z","end":"2026-09-08T15:39:57.568322Z","steps":["trace[861472242] 'read index received' (duration: 30.281µs)","trace[861472242] 'applied index is now lower than readState.Index' (duration: 181.738418ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:39:57.969218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.238567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:39:57.969582Z","caller":"traceutil/trace.go:171","msg":"trace[1187112878] linearizableReadLoop","detail":"{readStateIndex:283; appliedIndex:282; }","duration":"393.588843ms","start":"2026-09-08T15:39:57.575969Z","end":"2026-09-08T15:39:57.969557Z","steps":["trace[1187112878] 'read index received' (duration: 177.798241ms)","trace[1187112878] 'applied index is now lower than readState.Index' (duration: 215.789092ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:39:57.969656Z","caller":"traceutil/trace.go:171","msg":"trace[781911278] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"396.081144ms","start":"2026-09-08T15:39:57.573525Z","end":"2026-09-08T15:39:57.969606Z","steps":["trace[781911278] 'process raft request' (duration: 180.361186ms)","trace[781911278] 'compare' (duration: 215.034197ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:39:57.969866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:39:57.573506Z","time spent":"396.244332ms","remote":"127.0.0.1:35058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-08T15:39:57.969904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.908127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-08T15:39:57.970024Z","caller":"traceutil/trace.go:171","msg":"trace[605625336] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:277; }","duration":"394.064244ms","start":"2026-09-08T15:39:57.575938Z","end":"2026-09-08T15:39:57.970002Z","steps":["trace[605625336] 'agreement among raft nodes before linearized reading' (duration: 393.721019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:39:57.970133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:39:57.575927Z","time spent":"394.190919ms","remote":"127.0.0.1:34866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-09-08T15:39:58.173031Z","caller":"traceutil/trace.go:171","msg":"trace[1826356022] linearizableReadLoop","detail":"{readStateIndex:284; appliedIndex:283; }","duration":"197.064833ms","start":"2026-09-08T15:39:57.975927Z","end":"2026-09-08T15:39:58.172992Z","steps":["trace[1826356022] 'read index received' (duration: 136.104881ms)","trace[1826356022] 'applied index is now lower than readState.Index' (duration: 60.958682ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:39:58.173227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.269772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:39:58.173283Z","caller":"traceutil/trace.go:171","msg":"trace[1711710670] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:278; }","duration":"197.380277ms","start":"2026-09-08T15:39:57.975886Z","end":"2026-09-08T15:39:58.173266Z","steps":["trace[1711710670] 'agreement among raft nodes before linearized reading' (duration: 197.240431ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:39:58.173492Z","caller":"traceutil/trace.go:171","msg":"trace[447787933] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"199.24607ms","start":"2026-09-08T15:39:57.974197Z","end":"2026-09-08T15:39:58.173443Z","steps":["trace[447787933] 'process raft request' (duration: 137.924532ms)","trace[447787933] 'compare' (duration: 60.677539ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:40:32.93772Z","caller":"traceutil/trace.go:171","msg":"trace[2030068049] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"131.807369ms","start":"2026-09-08T15:40:32.805884Z","end":"2026-09-08T15:40:32.937692Z","steps":["trace[2030068049] 'process raft request' (duration: 131.684303ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.040419Z","caller":"traceutil/trace.go:171","msg":"trace[488199739] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"195.58139ms","start":"2026-09-08T15:40:32.844797Z","end":"2026-09-08T15:40:33.040378Z","steps":["trace[488199739] 'process raft request' (duration: 190.489344ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.040832Z","caller":"traceutil/trace.go:171","msg":"trace[756857958] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:557; }","duration":"182.518071ms","start":"2026-09-08T15:40:32.858251Z","end":"2026-09-08T15:40:33.040769Z","steps":["trace[756857958] 'read index received' (duration: 79.428128ms)","trace[756857958] 'applied index is now lower than readState.Index' (duration: 103.088843ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:40:33.041253Z","caller":"traceutil/trace.go:171","msg":"trace[1519421301] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"153.821591ms","start":"2026-09-08T15:40:32.887418Z","end":"2026-09-08T15:40:33.041239Z","steps":["trace[1519421301] 'process raft request' (duration: 153.03574ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.041354Z","caller":"traceutil/trace.go:171","msg":"trace[323975423] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"190.272224ms","start":"2026-09-08T15:40:32.851072Z","end":"2026-09-08T15:40:33.041344Z","steps":["trace[323975423] 'process raft request' (duration: 189.216459ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.041438Z","caller":"traceutil/trace.go:171","msg":"trace[236376667] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"185.823713ms","start":"2026-09-08T15:40:32.855606Z","end":"2026-09-08T15:40:33.04143Z","steps":["trace[236376667] 'process raft request' (duration: 184.723786ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.041589Z","caller":"traceutil/trace.go:171","msg":"trace[1695292282] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"164.460462ms","start":"2026-09-08T15:40:32.877116Z","end":"2026-09-08T15:40:33.041577Z","steps":["trace[1695292282] 'process raft request' (duration: 163.25662ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.041695Z","caller":"traceutil/trace.go:171","msg":"trace[1496353744] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"154.327017ms","start":"2026-09-08T15:40:32.88736Z","end":"2026-09-08T15:40:33.041687Z","steps":["trace[1496353744] 'process raft request' (duration: 153.057971ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.041902Z","caller":"traceutil/trace.go:171","msg":"trace[50084032] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"126.407256ms","start":"2026-09-08T15:40:32.915486Z","end":"2026-09-08T15:40:33.041893Z","steps":["trace[50084032] 'process raft request' (duration: 125.176622ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.04194Z","caller":"traceutil/trace.go:171","msg":"trace[1156763470] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"122.017637ms","start":"2026-09-08T15:40:32.9199Z","end":"2026-09-08T15:40:33.041918Z","steps":["trace[1156763470] 'process raft request' (duration: 120.817765ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.042013Z","caller":"traceutil/trace.go:171","msg":"trace[950187988] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"146.431286ms","start":"2026-09-08T15:40:32.895573Z","end":"2026-09-08T15:40:33.042004Z","steps":["trace[950187988] 'process raft request' (duration: 144.917228ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.042087Z","caller":"traceutil/trace.go:171","msg":"trace[185568394] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"142.355913ms","start":"2026-09-08T15:40:32.899723Z","end":"2026-09-08T15:40:33.042079Z","steps":["trace[185568394] 'process raft request' (duration: 140.807703ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:33.042219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.946125ms","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-09-08T15:40:33.042273Z","caller":"traceutil/trace.go:171","msg":"trace[1337275876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:543; }","duration":"184.075262ms","start":"2026-09-08T15:40:32.858183Z","end":"2026-09-08T15:40:33.042258Z","steps":["trace[1337275876] 'agreement among raft nodes before linearized reading' (duration: 183.938774ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:33.112255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.397916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-08T15:40:33.112385Z","caller":"traceutil/trace.go:171","msg":"trace[1631820440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:544; }","duration":"157.572165ms","start":"2026-09-08T15:40:32.95478Z","end":"2026-09-08T15:40:33.112352Z","steps":["trace[1631820440] 'agreement among raft nodes before linearized reading' (duration: 157.257729ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.11279Z","caller":"traceutil/trace.go:171","msg":"trace[1674602943] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"171.985945ms","start":"2026-09-08T15:40:32.940755Z","end":"2026-09-08T15:40:33.112741Z","steps":["trace[1674602943] 'process raft request' (duration: 171.073967ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:33.112849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.592356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-08T15:40:33.112916Z","caller":"traceutil/trace.go:171","msg":"trace[1256819666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:544; }","duration":"116.717063ms","start":"2026-09-08T15:40:32.996185Z","end":"2026-09-08T15:40:33.112902Z","steps":["trace[1256819666] 'agreement among raft nodes before linearized reading' (duration: 116.593936ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.163464Z","caller":"traceutil/trace.go:171","msg":"trace[1287052795] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"113.602541ms","start":"2026-09-08T15:40:33.049824Z","end":"2026-09-08T15:40:33.163427Z","steps":["trace[1287052795] 'process raft request' (duration: 113.360319ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.16396Z","caller":"traceutil/trace.go:171","msg":"trace[1701924015] transaction","detail":"{read_only:false; number_of_response:1; response_revision:545; }","duration":"113.95909ms","start":"2026-09-08T15:40:33.049983Z","end":"2026-09-08T15:40:33.163942Z","steps":["trace[1701924015] 'process raft request' (duration: 113.39164ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.164327Z","caller":"traceutil/trace.go:171","msg":"trace[732705783] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"101.427076ms","start":"2026-09-08T15:40:33.062886Z","end":"2026-09-08T15:40:33.164313Z","steps":["trace[732705783] 'process raft request' (duration: 101.141321ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.164413Z","caller":"traceutil/trace.go:171","msg":"trace[896455892] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"112.110143ms","start":"2026-09-08T15:40:33.052283Z","end":"2026-09-08T15:40:33.164393Z","steps":["trace[896455892] 'process raft request' (duration: 111.328473ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.164518Z","caller":"traceutil/trace.go:171","msg":"trace[1880219816] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"104.529438ms","start":"2026-09-08T15:40:33.059976Z","end":"2026-09-08T15:40:33.164505Z","steps":["trace[1880219816] 'process raft request' (duration: 103.879714ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.164652Z","caller":"traceutil/trace.go:171","msg":"trace[422275414] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"109.692176ms","start":"2026-09-08T15:40:33.054939Z","end":"2026-09-08T15:40:33.164632Z","steps":["trace[422275414] 'process raft request' (duration: 108.808111ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.164844Z","caller":"traceutil/trace.go:171","msg":"trace[193985112] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"109.75833ms","start":"2026-09-08T15:40:33.055072Z","end":"2026-09-08T15:40:33.16483Z","steps":["trace[193985112] 'process raft request' (duration: 108.709935ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:33.164877Z","caller":"traceutil/trace.go:171","msg":"trace[661639996] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"109.694476ms","start":"2026-09-08T15:40:33.055169Z","end":"2026-09-08T15:40:33.164864Z","steps":["trace[661639996] 'process raft request' (duration: 108.642642ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:40.441492Z","caller":"traceutil/trace.go:171","msg":"trace[466764152] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"421.125165ms","start":"2026-09-08T15:40:40.020334Z","end":"2026-09-08T15:40:40.441459Z","steps":["trace[466764152] 'read index received' (duration: 421.004059ms)","trace[466764152] 'applied index is now lower than readState.Index' (duration: 120.196µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:40:40.441684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.332485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:40:40.441764Z","caller":"traceutil/trace.go:171","msg":"trace[1181178357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"421.476623ms","start":"2026-09-08T15:40:40.020244Z","end":"2026-09-08T15:40:40.441721Z","steps":["trace[1181178357] 'agreement among raft nodes before linearized reading' (duration: 421.372698ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:40.441829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:40.020216Z","time spent":"421.59619ms","remote":"127.0.0.1:34652","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-09-08T15:40:40.442163Z","caller":"traceutil/trace.go:171","msg":"trace[1980389708] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"484.923094ms","start":"2026-09-08T15:40:39.957127Z","end":"2026-09-08T15:40:40.44205Z","steps":["trace[1980389708] 'process raft request' (duration: 484.146504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:40.443534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:39.957097Z","time spent":"485.221501ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:40:40.78214Z","caller":"traceutil/trace.go:171","msg":"trace[1257755793] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"337.724806ms","start":"2026-09-08T15:40:40.444331Z","end":"2026-09-08T15:40:40.782055Z","steps":["trace[1257755793] 'read index received' (duration: 335.474117ms)","trace[1257755793] 'applied index is now lower than readState.Index' (duration: 2.247259ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:40:40.782425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.065325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:40:40.782497Z","caller":"traceutil/trace.go:171","msg":"trace[1272440531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"338.208832ms","start":"2026-09-08T15:40:40.444271Z","end":"2026-09-08T15:40:40.78248Z","steps":["trace[1272440531] 'agreement among raft nodes before linearized reading' (duration: 337.9762ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:40.782553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:40.444247Z","time spent":"338.292027ms","remote":"127.0.0.1:34646","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-09-08T15:40:40.782977Z","caller":"traceutil/trace.go:171","msg":"trace[1039707503] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"728.961565ms","start":"2026-09-08T15:40:40.053985Z","end":"2026-09-08T15:40:40.782947Z","steps":["trace[1039707503] 'process raft request' (duration: 727.155149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:40.783137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:40.053939Z","time spent":"729.113354ms","remote":"127.0.0.1:34764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:40:41.413841Z","caller":"traceutil/trace.go:171","msg":"trace[940165460] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"431.794954ms","start":"2026-09-08T15:40:40.982014Z","end":"2026-09-08T15:40:41.413809Z","steps":["trace[940165460] 'read index received' (duration: 431.584803ms)","trace[940165460] 'applied index is now lower than readState.Index' (duration: 208.731µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:40:41.41398Z","caller":"traceutil/trace.go:171","msg":"trace[2065052878] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"531.860966ms","start":"2026-09-08T15:40:40.882062Z","end":"2026-09-08T15:40:41.413923Z","steps":["trace[2065052878] 'process raft request' (duration: 531.55549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:41.414136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:40.882028Z","time spent":"532.041145ms","remote":"127.0.0.1:34914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-08T15:40:41.414286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.246127ms","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-09-08T15:40:41.414538Z","caller":"traceutil/trace.go:171","msg":"trace[1946602838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"432.522372ms","start":"2026-09-08T15:40:40.981961Z","end":"2026-09-08T15:40:41.414483Z","steps":["trace[1946602838] 'agreement among raft nodes before linearized reading' (duration: 431.973943ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:41.414627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:40.981936Z","time spent":"432.677761ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-08T15:40:41.45527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.517032ms","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-09-08T15:40:41.455359Z","caller":"traceutil/trace.go:171","msg":"trace[183812073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"216.625057ms","start":"2026-09-08T15:40:41.23871Z","end":"2026-09-08T15:40:41.455335Z","steps":["trace[183812073] 'agreement among raft nodes before linearized reading' (duration: 216.408855ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:41.45528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.907669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:40:41.45552Z","caller":"traceutil/trace.go:171","msg":"trace[1462449881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"434.196654ms","start":"2026-09-08T15:40:41.021313Z","end":"2026-09-08T15:40:41.45551Z","steps":["trace[1462449881] 'agreement among raft nodes before linearized reading' (duration: 433.819063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:41.455549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:41.02128Z","time spent":"434.259638ms","remote":"127.0.0.1:34652","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-09-08T15:40:41.45527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.621441ms","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-09-08T15:40:41.455717Z","caller":"traceutil/trace.go:171","msg":"trace[1317113306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"246.162349ms","start":"2026-09-08T15:40:41.209546Z","end":"2026-09-08T15:40:41.455708Z","steps":["trace[1317113306] 'agreement among raft nodes before linearized reading' (duration: 245.526664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:41.455731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.158398ms","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-09-08T15:40:41.455794Z","caller":"traceutil/trace.go:171","msg":"trace[151084151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"246.231602ms","start":"2026-09-08T15:40:41.209523Z","end":"2026-09-08T15:40:41.455754Z","steps":["trace[151084151] 'agreement among raft nodes before linearized reading' (duration: 245.588747ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:41:00.055165Z","caller":"traceutil/trace.go:171","msg":"trace[442387029] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"311.237193ms","start":"2026-09-08T15:40:59.743897Z","end":"2026-09-08T15:41:00.055134Z","steps":["trace[442387029] 'process raft request' (duration: 311.00389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:41:00.05547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:59.743867Z","time spent":"311.433954ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:41:00.060867Z","caller":"traceutil/trace.go:171","msg":"trace[1748565018] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"274.345947ms","start":"2026-09-08T15:40:59.786499Z","end":"2026-09-08T15:41:00.060845Z","steps":["trace[1748565018] 'process raft request' (duration: 274.21589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:42:24.612264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.331605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5f914692-3fed-4bee-9294-739758d2d955\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-09-08T15:42:24.61235Z","caller":"traceutil/trace.go:171","msg":"trace[1419901844] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5f914692-3fed-4bee-9294-739758d2d955; range_end:; response_count:1; response_revision:1904; }","duration":"180.485643ms","start":"2026-09-08T15:42:24.431846Z","end":"2026-09-08T15:42:24.612332Z","steps":["trace[1419901844] 'range keys from in-memory index tree' (duration: 180.158487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:42:24.612601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.205626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-08T15:42:24.612737Z","caller":"traceutil/trace.go:171","msg":"trace[1578710798] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1904; }","duration":"336.380235ms","start":"2026-09-08T15:42:24.276337Z","end":"2026-09-08T15:42:24.612717Z","steps":["trace[1578710798] 'range keys from in-memory index tree' (duration: 336.079668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:42:24.612868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:42:24.276316Z","time spent":"336.531913ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-08T15:42:24.725222Z","caller":"traceutil/trace.go:171","msg":"trace[2107641129] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"107.968984ms","start":"2026-09-08T15:42:24.617224Z","end":"2026-09-08T15:42:24.725193Z","steps":["trace[2107641129] 'process raft request' (duration: 107.795254ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:42:24.865776Z","caller":"traceutil/trace.go:171","msg":"trace[172569556] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"247.025884ms","start":"2026-09-08T15:42:24.618718Z","end":"2026-09-08T15:42:24.865744Z","steps":["trace[172569556] 'process raft request' (duration: 195.180175ms)","trace[172569556] 'compare' (duration: 51.387344ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:42:24.866265Z","caller":"traceutil/trace.go:171","msg":"trace[529515562] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1981; }","duration":"223.388482ms","start":"2026-09-08T15:42:24.64283Z","end":"2026-09-08T15:42:24.866219Z","steps":["trace[529515562] 'read index received' (duration: 82.093383ms)","trace[529515562] 'applied index is now lower than readState.Index' (duration: 141.292489ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:42:24.866404Z","caller":"traceutil/trace.go:171","msg":"trace[136982174] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"244.688077ms","start":"2026-09-08T15:42:24.621698Z","end":"2026-09-08T15:42:24.866386Z","steps":["trace[136982174] 'process raft request' (duration: 244.007194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:42:24.86664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.759978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5f914692-3fed-4bee-9294-739758d2d955\" ","response":"range_response_count:1 size:3745"} {"level":"info","ts":"2026-09-08T15:42:24.866698Z","caller":"traceutil/trace.go:171","msg":"trace[317258907] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5f914692-3fed-4bee-9294-739758d2d955; range_end:; response_count:1; response_revision:1907; }","duration":"223.901036ms","start":"2026-09-08T15:42:24.642779Z","end":"2026-09-08T15:42:24.86668Z","steps":["trace[317258907] 'agreement among raft nodes before linearized reading' (duration: 223.53742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:42:25.098892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.509985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-08T15:42:25.09899Z","caller":"traceutil/trace.go:171","msg":"trace[2076785342] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1907; }","duration":"116.651544ms","start":"2026-09-08T15:42:24.982318Z","end":"2026-09-08T15:42:25.09897Z","steps":["trace[2076785342] 'range keys from in-memory index tree' (duration: 116.292447ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:42:52.821087Z","caller":"traceutil/trace.go:171","msg":"trace[824471713] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"107.873577ms","start":"2026-09-08T15:42:52.713187Z","end":"2026-09-08T15:42:52.821061Z","steps":["trace[824471713] 'process raft request' (duration: 107.661236ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:42:58.26357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.886501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:42:58.264009Z","caller":"traceutil/trace.go:171","msg":"trace[1757009741] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"194.723626ms","start":"2026-09-08T15:42:58.069264Z","end":"2026-09-08T15:42:58.263988Z","steps":["trace[1757009741] 'process raft request' (duration: 60.342893ms)","trace[1757009741] 'compare' (duration: 133.63772ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:42:58.931273Z","caller":"traceutil/trace.go:171","msg":"trace[1128367028] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"151.694854ms","start":"2026-09-08T15:42:58.779554Z","end":"2026-09-08T15:42:58.931249Z","steps":["trace[1128367028] 'process raft request' (duration: 151.391753ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:42:59.328391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.251914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:42:59.328498Z","caller":"traceutil/trace.go:171","msg":"trace[1113184079] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"383.94603ms","start":"2026-09-08T15:42:58.944532Z","end":"2026-09-08T15:42:59.328478Z","steps":["trace[1113184079] 'process raft request' (duration: 176.492215ms)","trace[1113184079] 'compare' (duration: 207.012663ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:42:59.32856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:42:58.944496Z","time spent":"384.032941ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:42:59.411251Z","caller":"traceutil/trace.go:171","msg":"trace[1741843910] linearizableReadLoop","detail":"{readStateIndex:2236; appliedIndex:2234; }","duration":"347.007465ms","start":"2026-09-08T15:42:59.064222Z","end":"2026-09-08T15:42:59.411229Z","steps":["trace[1741843910] 'read index received' (duration: 56.800209ms)","trace[1741843910] 'applied index is now lower than readState.Index' (duration: 290.206116ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:42:59.411419Z","caller":"traceutil/trace.go:171","msg":"trace[210451002] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"423.330187ms","start":"2026-09-08T15:42:58.98805Z","end":"2026-09-08T15:42:59.41138Z","steps":["trace[210451002] 'process raft request' (duration: 423.005926ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:42:59.411603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:42:58.988022Z","time spent":"423.489408ms","remote":"127.0.0.1:34734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-08T15:42:59.411652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.436527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-08T15:42:59.411802Z","caller":"traceutil/trace.go:171","msg":"trace[557820040] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2152; }","duration":"347.630998ms","start":"2026-09-08T15:42:59.064144Z","end":"2026-09-08T15:42:59.411775Z","steps":["trace[557820040] 'agreement among raft nodes before linearized reading' (duration: 347.278676ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:42:59.411904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:42:59.064114Z","time spent":"347.763199ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-08T15:42:59.61491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.913105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:42:59.615247Z","caller":"traceutil/trace.go:171","msg":"trace[1610527444] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"197.350143ms","start":"2026-09-08T15:42:59.417867Z","end":"2026-09-08T15:42:59.615218Z","steps":["trace[1610527444] 'process raft request' (duration: 19.049197ms)","trace[1610527444] 'compare' (duration: 177.809824ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:42:59.615246Z","caller":"traceutil/trace.go:171","msg":"trace[365544296] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"113.585317ms","start":"2026-09-08T15:42:59.501566Z","end":"2026-09-08T15:42:59.615152Z","steps":["trace[365544296] 'process raft request' (duration: 113.490757ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:44:09.687927Z","caller":"traceutil/trace.go:171","msg":"trace[574437757] transaction","detail":"{read_only:false; response_revision:2626; number_of_response:1; }","duration":"124.043894ms","start":"2026-09-08T15:44:09.563857Z","end":"2026-09-08T15:44:09.687901Z","steps":["trace[574437757] 'process raft request' (duration: 123.8949ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:44:42.913269Z","caller":"traceutil/trace.go:171","msg":"trace[1221455496] transaction","detail":"{read_only:false; response_revision:2809; number_of_response:1; }","duration":"341.761861ms","start":"2026-09-08T15:44:42.571481Z","end":"2026-09-08T15:44:42.913243Z","steps":["trace[1221455496] 'process raft request' (duration: 341.550545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:44:42.913503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:44:42.571444Z","time spent":"341.922636ms","remote":"127.0.0.1:34734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:44:42.959817Z","caller":"traceutil/trace.go:171","msg":"trace[28167665] linearizableReadLoop","detail":"{readStateIndex:2918; appliedIndex:2916; }","duration":"124.171921ms","start":"2026-09-08T15:44:42.8356Z","end":"2026-09-08T15:44:42.959772Z","steps":["trace[28167665] 'read index received' (duration: 77.37865ms)","trace[28167665] 'applied index is now lower than readState.Index' (duration: 46.791411ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:44:42.959891Z","caller":"traceutil/trace.go:171","msg":"trace[469074923] transaction","detail":"{read_only:false; response_revision:2810; number_of_response:1; }","duration":"292.996147ms","start":"2026-09-08T15:44:42.666864Z","end":"2026-09-08T15:44:42.959861Z","steps":["trace[469074923] 'process raft request' (duration: 292.691928ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:44:42.960151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.512373ms","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-09-08T15:44:42.96071Z","caller":"traceutil/trace.go:171","msg":"trace[1649450412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2810; }","duration":"125.114002ms","start":"2026-09-08T15:44:42.835554Z","end":"2026-09-08T15:44:42.960668Z","steps":["trace[1649450412] 'agreement among raft nodes before linearized reading' (duration: 124.407679ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:31.771264Z","caller":"traceutil/trace.go:171","msg":"trace[2080373720] transaction","detail":"{read_only:false; response_revision:3376; number_of_response:1; }","duration":"139.334364ms","start":"2026-09-08T15:46:31.631903Z","end":"2026-09-08T15:46:31.771237Z","steps":["trace[2080373720] 'process raft request' (duration: 139.191538ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:31.794244Z","caller":"traceutil/trace.go:171","msg":"trace[1145681083] linearizableReadLoop","detail":"{readStateIndex:3509; appliedIndex:3506; }","duration":"154.592505ms","start":"2026-09-08T15:46:31.639636Z","end":"2026-09-08T15:46:31.794228Z","steps":["trace[1145681083] 'read index received' (duration: 131.522441ms)","trace[1145681083] 'applied index is now lower than readState.Index' (duration: 23.069064ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:31.794348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.692168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:31.794386Z","caller":"traceutil/trace.go:171","msg":"trace[1775293836] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:3378; }","duration":"154.781783ms","start":"2026-09-08T15:46:31.639593Z","end":"2026-09-08T15:46:31.794375Z","steps":["trace[1775293836] 'agreement among raft nodes before linearized reading' (duration: 154.69769ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:31.794406Z","caller":"traceutil/trace.go:171","msg":"trace[651389778] transaction","detail":"{read_only:false; response_revision:3377; number_of_response:1; }","duration":"162.44267ms","start":"2026-09-08T15:46:31.631945Z","end":"2026-09-08T15:46:31.794388Z","steps":["trace[651389778] 'process raft request' (duration: 162.157187ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:31.794503Z","caller":"traceutil/trace.go:171","msg":"trace[293661512] transaction","detail":"{read_only:false; response_revision:3378; number_of_response:1; }","duration":"161.486398ms","start":"2026-09-08T15:46:31.632973Z","end":"2026-09-08T15:46:31.79446Z","steps":["trace[293661512] 'process raft request' (duration: 161.209036ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:32.771539Z","caller":"traceutil/trace.go:171","msg":"trace[922143340] transaction","detail":"{read_only:false; response_revision:3381; number_of_response:1; }","duration":"159.00616ms","start":"2026-09-08T15:46:32.612504Z","end":"2026-09-08T15:46:32.77151Z","steps":["trace[922143340] 'process raft request' (duration: 158.854404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:33.376943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.96214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-09-08T15:46:33.377035Z","caller":"traceutil/trace.go:171","msg":"trace[1603543101] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3384; }","duration":"103.095196ms","start":"2026-09-08T15:46:33.273917Z","end":"2026-09-08T15:46:33.377012Z","steps":["trace[1603543101] 'range keys from in-memory index tree' (duration: 102.767231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:37.172799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.116424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:46:37.172945Z","caller":"traceutil/trace.go:171","msg":"trace[617181049] linearizableReadLoop","detail":"{readStateIndex:3535; appliedIndex:3534; }","duration":"152.985908ms","start":"2026-09-08T15:46:37.019935Z","end":"2026-09-08T15:46:37.172921Z","steps":["trace[617181049] 'read index received' (duration: 15.434925ms)","trace[617181049] 'applied index is now lower than readState.Index' (duration: 137.549493ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:37.173033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.095513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:37.173068Z","caller":"traceutil/trace.go:171","msg":"trace[7544554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3403; }","duration":"153.171276ms","start":"2026-09-08T15:46:37.019885Z","end":"2026-09-08T15:46:37.173057Z","steps":["trace[7544554] 'agreement among raft nodes before linearized reading' (duration: 153.092983ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:37.173324Z","caller":"traceutil/trace.go:171","msg":"trace[1594196703] transaction","detail":"{read_only:false; response_revision:3403; number_of_response:1; }","duration":"201.334171ms","start":"2026-09-08T15:46:36.97167Z","end":"2026-09-08T15:46:37.173004Z","steps":["trace[1594196703] 'process raft request' (duration: 63.794758ms)","trace[1594196703] 'compare' (duration: 136.942866ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:39.082181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.649555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T15:46:39.082326Z","caller":"traceutil/trace.go:171","msg":"trace[1224088008] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3411; }","duration":"122.840434ms","start":"2026-09-08T15:46:38.959467Z","end":"2026-09-08T15:46:39.082308Z","steps":["trace[1224088008] 'range keys from in-memory index tree' (duration: 122.452176ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:39.84928Z","caller":"traceutil/trace.go:171","msg":"trace[1964981414] transaction","detail":"{read_only:false; response_revision:3415; number_of_response:1; }","duration":"101.162042ms","start":"2026-09-08T15:46:39.748094Z","end":"2026-09-08T15:46:39.849256Z","steps":["trace[1964981414] 'process raft request' (duration: 101.036657ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:40.038247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.116465ms","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-09-08T15:46:40.038314Z","caller":"traceutil/trace.go:171","msg":"trace[1872300981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3415; }","duration":"104.23162ms","start":"2026-09-08T15:46:39.934066Z","end":"2026-09-08T15:46:40.038298Z","steps":["trace[1872300981] 'range keys from in-memory index tree' (duration: 103.98014ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:40.038373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.121265ms","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-09-08T15:46:40.038462Z","caller":"traceutil/trace.go:171","msg":"trace[592695643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3415; }","duration":"104.243931ms","start":"2026-09-08T15:46:39.9342Z","end":"2026-09-08T15:46:40.038444Z","steps":["trace[592695643] 'range keys from in-memory index tree' (duration: 103.961528ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:40.038773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.598556ms","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-09-08T15:46:40.039532Z","caller":"traceutil/trace.go:171","msg":"trace[708325133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3415; }","duration":"117.796434ms","start":"2026-09-08T15:46:39.92107Z","end":"2026-09-08T15:46:40.038866Z","steps":["trace[708325133] 'range keys from in-memory index tree' (duration: 117.229779ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:41.183581Z","caller":"traceutil/trace.go:171","msg":"trace[243772370] linearizableReadLoop","detail":"{readStateIndex:3553; appliedIndex:3552; }","duration":"162.970835ms","start":"2026-09-08T15:46:41.020587Z","end":"2026-09-08T15:46:41.183557Z","steps":["trace[243772370] 'read index received' (duration: 162.743785ms)","trace[243772370] 'applied index is now lower than readState.Index' (duration: 226.21µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:41.183635Z","caller":"traceutil/trace.go:171","msg":"trace[1591856245] transaction","detail":"{read_only:false; response_revision:3421; number_of_response:1; }","duration":"237.660045ms","start":"2026-09-08T15:46:40.94595Z","end":"2026-09-08T15:46:41.18361Z","steps":["trace[1591856245] 'process raft request' (duration: 237.459077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:41.183804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.184595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:41.183891Z","caller":"traceutil/trace.go:171","msg":"trace[568175514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3421; }","duration":"163.333321ms","start":"2026-09-08T15:46:41.020538Z","end":"2026-09-08T15:46:41.183871Z","steps":["trace[568175514] 'agreement among raft nodes before linearized reading' (duration: 163.124342ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:43.320347Z","caller":"traceutil/trace.go:171","msg":"trace[1506708094] linearizableReadLoop","detail":"{readStateIndex:3565; appliedIndex:3564; }","duration":"114.740376ms","start":"2026-09-08T15:46:43.205585Z","end":"2026-09-08T15:46:43.320325Z","steps":["trace[1506708094] 'read index received' (duration: 114.576549ms)","trace[1506708094] 'applied index is now lower than readState.Index' (duration: 162.927µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:43.320391Z","caller":"traceutil/trace.go:171","msg":"trace[1439224950] transaction","detail":"{read_only:false; response_revision:3432; number_of_response:1; }","duration":"128.255211ms","start":"2026-09-08T15:46:43.192118Z","end":"2026-09-08T15:46:43.320373Z","steps":["trace[1439224950] 'process raft request' (duration: 128.047291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:43.320564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.954506ms","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-09-08T15:46:43.320644Z","caller":"traceutil/trace.go:171","msg":"trace[161302724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3432; }","duration":"115.071701ms","start":"2026-09-08T15:46:43.205557Z","end":"2026-09-08T15:46:43.320629Z","steps":["trace[161302724] 'agreement among raft nodes before linearized reading' (duration: 114.859542ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:43.483254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.119785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:46:43.483375Z","caller":"traceutil/trace.go:171","msg":"trace[1424707527] linearizableReadLoop","detail":"{readStateIndex:3567; appliedIndex:3566; }","duration":"142.401375ms","start":"2026-09-08T15:46:43.340952Z","end":"2026-09-08T15:46:43.483353Z","steps":["trace[1424707527] 'read index received' (duration: 35.076189ms)","trace[1424707527] 'applied index is now lower than readState.Index' (duration: 107.323235ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:43.483451Z","caller":"traceutil/trace.go:171","msg":"trace[1952027997] transaction","detail":"{read_only:false; response_revision:3434; number_of_response:1; }","duration":"156.099789ms","start":"2026-09-08T15:46:43.327325Z","end":"2026-09-08T15:46:43.483425Z","steps":["trace[1952027997] 'process raft request' (duration: 48.69902ms)","trace[1952027997] 'compare' (duration: 106.951648ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:43.483558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.528203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:46:43.483599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.641586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-09-08T15:46:43.483612Z","caller":"traceutil/trace.go:171","msg":"trace[1036683930] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:3434; }","duration":"134.613536ms","start":"2026-09-08T15:46:43.348981Z","end":"2026-09-08T15:46:43.483595Z","steps":["trace[1036683930] 'agreement among raft nodes before linearized reading' (duration: 134.528253ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:43.483644Z","caller":"traceutil/trace.go:171","msg":"trace[1803924665] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3434; }","duration":"142.705499ms","start":"2026-09-08T15:46:43.340922Z","end":"2026-09-08T15:46:43.483628Z","steps":["trace[1803924665] 'agreement among raft nodes before linearized reading' (duration: 142.540061ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:44.293148Z","caller":"traceutil/trace.go:171","msg":"trace[1015072221] transaction","detail":"{read_only:false; response_revision:3440; number_of_response:1; }","duration":"148.132881ms","start":"2026-09-08T15:46:44.144982Z","end":"2026-09-08T15:46:44.293114Z","steps":["trace[1015072221] 'process raft request' (duration: 148.063548ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:44.293227Z","caller":"traceutil/trace.go:171","msg":"trace[500752961] transaction","detail":"{read_only:false; response_revision:3439; number_of_response:1; }","duration":"148.281458ms","start":"2026-09-08T15:46:44.144905Z","end":"2026-09-08T15:46:44.293187Z","steps":["trace[500752961] 'process raft request' (duration: 58.634079ms)","trace[500752961] 'compare' (duration: 89.380618ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:45.573091Z","caller":"traceutil/trace.go:171","msg":"trace[1836819920] transaction","detail":"{read_only:false; response_revision:3443; number_of_response:1; }","duration":"225.530435ms","start":"2026-09-08T15:46:45.347514Z","end":"2026-09-08T15:46:45.573044Z","steps":["trace[1836819920] 'process raft request' (duration: 225.169789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:45.753839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.675619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:46:45.754005Z","caller":"traceutil/trace.go:171","msg":"trace[618530527] transaction","detail":"{read_only:false; response_revision:3445; number_of_response:1; }","duration":"176.598678ms","start":"2026-09-08T15:46:45.577357Z","end":"2026-09-08T15:46:45.753956Z","steps":["trace[618530527] 'process raft request' (duration: 67.699619ms)","trace[618530527] 'compare' (duration: 108.487541ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:47.753565Z","caller":"traceutil/trace.go:171","msg":"trace[520602527] transaction","detail":"{read_only:false; response_revision:3454; number_of_response:1; }","duration":"169.715311ms","start":"2026-09-08T15:46:47.583782Z","end":"2026-09-08T15:46:47.753497Z","steps":["trace[520602527] 'process raft request' (duration: 169.506602ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:47.773047Z","caller":"traceutil/trace.go:171","msg":"trace[2062891743] transaction","detail":"{read_only:false; response_revision:3455; number_of_response:1; }","duration":"156.697755ms","start":"2026-09-08T15:46:47.616311Z","end":"2026-09-08T15:46:47.773008Z","steps":["trace[2062891743] 'process raft request' (duration: 156.469985ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:47.886418Z","caller":"traceutil/trace.go:171","msg":"trace[1550513400] transaction","detail":"{read_only:false; response_revision:3456; number_of_response:1; }","duration":"107.705949ms","start":"2026-09-08T15:46:47.77866Z","end":"2026-09-08T15:46:47.886366Z","steps":["trace[1550513400] 'process raft request' (duration: 48.8523ms)","trace[1550513400] 'compare' (duration: 58.523184ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:48.383664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.081091ms","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-09-08T15:46:48.383749Z","caller":"traceutil/trace.go:171","msg":"trace[482858809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3458; }","duration":"139.183125ms","start":"2026-09-08T15:46:48.244548Z","end":"2026-09-08T15:46:48.383731Z","steps":["trace[482858809] 'range keys from in-memory index tree' (duration: 138.85663ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:48.588688Z","caller":"traceutil/trace.go:171","msg":"trace[985012824] transaction","detail":"{read_only:false; response_revision:3460; number_of_response:1; }","duration":"199.439992ms","start":"2026-09-08T15:46:48.389203Z","end":"2026-09-08T15:46:48.588643Z","steps":["trace[985012824] 'process raft request' (duration: 102.519342ms)","trace[985012824] 'compare' (duration: 96.573094ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:48.588961Z","caller":"traceutil/trace.go:171","msg":"trace[1989325404] transaction","detail":"{read_only:false; response_revision:3461; number_of_response:1; }","duration":"199.658401ms","start":"2026-09-08T15:46:48.389242Z","end":"2026-09-08T15:46:48.5889Z","steps":["trace[1989325404] 'process raft request' (duration: 199.337347ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:51.012305Z","caller":"traceutil/trace.go:171","msg":"trace[864459779] transaction","detail":"{read_only:false; response_revision:3471; number_of_response:1; }","duration":"193.607393ms","start":"2026-09-08T15:46:50.81867Z","end":"2026-09-08T15:46:51.012278Z","steps":["trace[864459779] 'process raft request' (duration: 193.429775ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:53.918018Z","caller":"traceutil/trace.go:171","msg":"trace[1318978555] transaction","detail":"{read_only:false; response_revision:3483; number_of_response:1; }","duration":"102.507402ms","start":"2026-09-08T15:46:53.815485Z","end":"2026-09-08T15:46:53.917992Z","steps":["trace[1318978555] 'process raft request' (duration: 102.297863ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:54.432573Z","caller":"traceutil/trace.go:171","msg":"trace[526215600] transaction","detail":"{read_only:false; response_revision:3487; number_of_response:1; }","duration":"166.307718ms","start":"2026-09-08T15:46:54.266223Z","end":"2026-09-08T15:46:54.432531Z","steps":["trace[526215600] 'process raft request' (duration: 151.769558ms)","trace[526215600] 'compare' (duration: 14.390674ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:37.60045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.684939ms","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-09-08T15:49:37.600545Z","caller":"traceutil/trace.go:171","msg":"trace[256655280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4502; }","duration":"253.831927ms","start":"2026-09-08T15:49:37.346693Z","end":"2026-09-08T15:49:37.600524Z","steps":["trace[256655280] 'range keys from in-memory index tree' (duration: 253.49683ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:40.094167Z","caller":"traceutil/trace.go:171","msg":"trace[2087162930] linearizableReadLoop","detail":"{readStateIndex:4703; appliedIndex:4702; }","duration":"132.218169ms","start":"2026-09-08T15:49:39.961926Z","end":"2026-09-08T15:49:40.094144Z","steps":["trace[2087162930] 'read index received' (duration: 131.801118ms)","trace[2087162930] 'applied index is now lower than readState.Index' (duration: 416.101µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:40.094218Z","caller":"traceutil/trace.go:171","msg":"trace[1478151121] transaction","detail":"{read_only:false; response_revision:4516; number_of_response:1; }","duration":"227.444323ms","start":"2026-09-08T15:49:39.866747Z","end":"2026-09-08T15:49:40.094192Z","steps":["trace[1478151121] 'process raft request' (duration: 227.137737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:40.094649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.698884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:49:40.094702Z","caller":"traceutil/trace.go:171","msg":"trace[952861512] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:4516; }","duration":"132.803159ms","start":"2026-09-08T15:49:39.961886Z","end":"2026-09-08T15:49:40.09469Z","steps":["trace[952861512] 'agreement among raft nodes before linearized reading' (duration: 132.373427ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:40.408051Z","caller":"traceutil/trace.go:171","msg":"trace[1727915291] transaction","detail":"{read_only:false; response_revision:4517; number_of_response:1; }","duration":"167.342651ms","start":"2026-09-08T15:49:40.240668Z","end":"2026-09-08T15:49:40.40801Z","steps":["trace[1727915291] 'process raft request' (duration: 112.179029ms)","trace[1727915291] 'compare' (duration: 54.990533ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:40.492215Z","caller":"traceutil/trace.go:171","msg":"trace[1796798605] transaction","detail":"{read_only:false; response_revision:4518; number_of_response:1; }","duration":"192.237939ms","start":"2026-09-08T15:49:40.299942Z","end":"2026-09-08T15:49:40.49218Z","steps":["trace[1796798605] 'process raft request' (duration: 191.772986ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:40.757486Z","caller":"traceutil/trace.go:171","msg":"trace[1643103433] linearizableReadLoop","detail":"{readStateIndex:4706; appliedIndex:4705; }","duration":"205.490215ms","start":"2026-09-08T15:49:40.551974Z","end":"2026-09-08T15:49:40.757464Z","steps":["trace[1643103433] 'read index received' (duration: 205.261564ms)","trace[1643103433] 'applied index is now lower than readState.Index' (duration: 227.751µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:40.757605Z","caller":"traceutil/trace.go:171","msg":"trace[617346904] transaction","detail":"{read_only:false; response_revision:4519; number_of_response:1; }","duration":"213.184518ms","start":"2026-09-08T15:49:40.544391Z","end":"2026-09-08T15:49:40.757576Z","steps":["trace[617346904] 'process raft request' (duration: 212.82962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:40.757675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.689515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-09-08T15:49:40.757707Z","caller":"traceutil/trace.go:171","msg":"trace[1218304769] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4519; }","duration":"205.755849ms","start":"2026-09-08T15:49:40.551941Z","end":"2026-09-08T15:49:40.757697Z","steps":["trace[1218304769] 'agreement among raft nodes before linearized reading' (duration: 205.606161ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:40.79876Z","caller":"traceutil/trace.go:171","msg":"trace[269669284] transaction","detail":"{read_only:false; response_revision:4520; number_of_response:1; }","duration":"110.62019ms","start":"2026-09-08T15:49:40.688119Z","end":"2026-09-08T15:49:40.798739Z","steps":["trace[269669284] 'process raft request' (duration: 110.499064ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:41.139809Z","caller":"traceutil/trace.go:171","msg":"trace[708080666] linearizableReadLoop","detail":"{readStateIndex:4710; appliedIndex:4709; }","duration":"118.118944ms","start":"2026-09-08T15:49:41.021654Z","end":"2026-09-08T15:49:41.139773Z","steps":["trace[708080666] 'read index received' (duration: 114.897919ms)","trace[708080666] 'applied index is now lower than readState.Index' (duration: 3.219695ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:41.139869Z","caller":"traceutil/trace.go:171","msg":"trace[516628695] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"131.555358ms","start":"2026-09-08T15:49:41.008275Z","end":"2026-09-08T15:49:41.139831Z","steps":["trace[516628695] 'process raft request' (duration: 128.27115ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:41.14009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.42773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:41.148559Z","caller":"traceutil/trace.go:171","msg":"trace[1388505834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4523; }","duration":"126.949155ms","start":"2026-09-08T15:49:41.02158Z","end":"2026-09-08T15:49:41.148529Z","steps":["trace[1388505834] 'agreement among raft nodes before linearized reading' (duration: 118.42572ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:41.341913Z","caller":"traceutil/trace.go:171","msg":"trace[1684710851] transaction","detail":"{read_only:false; response_revision:4526; number_of_response:1; }","duration":"167.435958ms","start":"2026-09-08T15:49:41.174457Z","end":"2026-09-08T15:49:41.341893Z","steps":["trace[1684710851] 'process raft request' (duration: 115.006884ms)","trace[1684710851] 'compare' (duration: 51.686646ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:41.341909Z","caller":"traceutil/trace.go:171","msg":"trace[1416278037] transaction","detail":"{read_only:false; response_revision:4527; number_of_response:1; }","duration":"149.566908ms","start":"2026-09-08T15:49:41.192319Z","end":"2026-09-08T15:49:41.341886Z","steps":["trace[1416278037] 'process raft request' (duration: 149.155807ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:41.737778Z","caller":"traceutil/trace.go:171","msg":"trace[1619339636] transaction","detail":"{read_only:false; response_revision:4528; number_of_response:1; }","duration":"207.199776ms","start":"2026-09-08T15:49:41.530547Z","end":"2026-09-08T15:49:41.737747Z","steps":["trace[1619339636] 'process raft request' (duration: 206.749403ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:41.940762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.187983ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-08T15:49:41.941056Z","caller":"traceutil/trace.go:171","msg":"trace[245866249] linearizableReadLoop","detail":"{readStateIndex:4716; appliedIndex:4714; }","duration":"236.625037ms","start":"2026-09-08T15:49:41.704388Z","end":"2026-09-08T15:49:41.941013Z","steps":["trace[245866249] 'read index received' (duration: 32.784532ms)","trace[245866249] 'applied index is now lower than readState.Index' (duration: 203.836415ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:41.941425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.006787ms","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-09-08T15:49:41.941493Z","caller":"traceutil/trace.go:171","msg":"trace[575265896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4528; }","duration":"237.157054ms","start":"2026-09-08T15:49:41.704316Z","end":"2026-09-08T15:49:41.941473Z","steps":["trace[575265896] 'agreement among raft nodes before linearized reading' (duration: 236.830418ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:41.941535Z","caller":"traceutil/trace.go:171","msg":"trace[1851854729] transaction","detail":"{read_only:false; response_revision:4529; number_of_response:1; }","duration":"145.879499ms","start":"2026-09-08T15:49:41.795629Z","end":"2026-09-08T15:49:41.941508Z","steps":["trace[1851854729] 'process raft request' (duration: 145.29738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:41.941756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.058249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:41.941807Z","caller":"traceutil/trace.go:171","msg":"trace[658463520] transaction","detail":"{read_only:false; response_revision:4530; number_of_response:1; }","duration":"143.040265ms","start":"2026-09-08T15:49:41.798658Z","end":"2026-09-08T15:49:41.941698Z","steps":["trace[658463520] 'process raft request' (duration: 142.764731ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:41.94183Z","caller":"traceutil/trace.go:171","msg":"trace[2044424378] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4530; }","duration":"137.169255ms","start":"2026-09-08T15:49:41.804641Z","end":"2026-09-08T15:49:41.94181Z","steps":["trace[2044424378] 'agreement among raft nodes before linearized reading' (duration: 137.018038ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:41.941897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.390441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-08T15:49:41.941983Z","caller":"traceutil/trace.go:171","msg":"trace[446683250] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4530; }","duration":"152.555589ms","start":"2026-09-08T15:49:41.789394Z","end":"2026-09-08T15:49:41.94195Z","steps":["trace[446683250] 'agreement among raft nodes before linearized reading' (duration: 152.277245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:41.943264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.927877ms","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-09-08T15:49:41.943336Z","caller":"traceutil/trace.go:171","msg":"trace[2120257653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4530; }","duration":"132.030553ms","start":"2026-09-08T15:49:41.811287Z","end":"2026-09-08T15:49:41.943318Z","steps":["trace[2120257653] 'agreement among raft nodes before linearized reading' (duration: 130.306585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:42.251311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.18974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:49:42.251627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.492041ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:42.251647Z","caller":"traceutil/trace.go:171","msg":"trace[1014951064] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:4534; }","duration":"148.429301ms","start":"2026-09-08T15:49:42.10301Z","end":"2026-09-08T15:49:42.251439Z","steps":["trace[1014951064] 'count revisions from in-memory index tree' (duration: 148.110385ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:42.251778Z","caller":"traceutil/trace.go:171","msg":"trace[436772644] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4534; }","duration":"195.705752ms","start":"2026-09-08T15:49:42.056047Z","end":"2026-09-08T15:49:42.251752Z","steps":["trace[436772644] 'range keys from in-memory index tree' (duration: 195.318153ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:42.763752Z","caller":"traceutil/trace.go:171","msg":"trace[1196507307] transaction","detail":"{read_only:false; response_revision:4537; number_of_response:1; }","duration":"115.125773ms","start":"2026-09-08T15:49:42.648571Z","end":"2026-09-08T15:49:42.763696Z","steps":["trace[1196507307] 'process raft request' (duration: 114.429587ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:43.115454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.771119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:49:43.115594Z","caller":"traceutil/trace.go:171","msg":"trace[1446925318] transaction","detail":"{read_only:false; response_revision:4538; number_of_response:1; }","duration":"309.875079ms","start":"2026-09-08T15:49:42.80568Z","end":"2026-09-08T15:49:43.115555Z","steps":["trace[1446925318] 'process raft request' (duration: 43.886048ms)","trace[1446925318] 'compare' (duration: 265.663764ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:43.115638Z","caller":"traceutil/trace.go:171","msg":"trace[445416334] transaction","detail":"{read_only:false; response_revision:4539; number_of_response:1; }","duration":"276.291846ms","start":"2026-09-08T15:49:42.839325Z","end":"2026-09-08T15:49:43.115617Z","steps":["trace[445416334] 'process raft request' (duration: 276.221952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:43.115772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:42.805669Z","time spent":"310.003266ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:49:43.115857Z","caller":"traceutil/trace.go:171","msg":"trace[1492677066] linearizableReadLoop","detail":"{readStateIndex:4726; appliedIndex:4725; }","duration":"284.630211ms","start":"2026-09-08T15:49:42.831216Z","end":"2026-09-08T15:49:43.115846Z","steps":["trace[1492677066] 'read index received' (duration: 18.375438ms)","trace[1492677066] 'applied index is now lower than readState.Index' (duration: 266.253573ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:43.116037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.813381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-09-08T15:49:43.116068Z","caller":"traceutil/trace.go:171","msg":"trace[316046833] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4539; }","duration":"284.867194ms","start":"2026-09-08T15:49:42.831188Z","end":"2026-09-08T15:49:43.116055Z","steps":["trace[316046833] 'agreement among raft nodes before linearized reading' (duration: 284.731317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:43.116195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.981384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T15:49:43.116218Z","caller":"traceutil/trace.go:171","msg":"trace[893837421] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4539; }","duration":"121.040466ms","start":"2026-09-08T15:49:42.995171Z","end":"2026-09-08T15:49:43.116211Z","steps":["trace[893837421] 'agreement among raft nodes before linearized reading' (duration: 120.970282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:43.574028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.14298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:49:43.574144Z","caller":"traceutil/trace.go:171","msg":"trace[491471442] linearizableReadLoop","detail":"{readStateIndex:4729; appliedIndex:4727; }","duration":"446.678642ms","start":"2026-09-08T15:49:43.127444Z","end":"2026-09-08T15:49:43.574123Z","steps":["trace[491471442] 'read index received' (duration: 38.497545ms)","trace[491471442] 'applied index is now lower than readState.Index' (duration: 408.179167ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:43.574451Z","caller":"traceutil/trace.go:171","msg":"trace[987666333] transaction","detail":"{read_only:false; response_revision:4541; number_of_response:1; }","duration":"453.0894ms","start":"2026-09-08T15:49:43.121311Z","end":"2026-09-08T15:49:43.5744Z","steps":["trace[987666333] 'process raft request' (duration: 322.476845ms)","trace[987666333] 'compare' (duration: 129.916549ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:43.574552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:43.121286Z","time spent":"453.215526ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:49:43.574583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.626171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-08T15:49:43.574686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.255382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/monitoring/kube-prometheus-stack-admission-create\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:49:43.57468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.559222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-08T15:49:43.574745Z","caller":"traceutil/trace.go:171","msg":"trace[1564782833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4541; }","duration":"227.672298ms","start":"2026-09-08T15:49:43.347056Z","end":"2026-09-08T15:49:43.574728Z","steps":["trace[1564782833] 'agreement among raft nodes before linearized reading' (duration: 227.476988ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:43.574759Z","caller":"traceutil/trace.go:171","msg":"trace[1997178041] range","detail":"{range_begin:/registry/jobs/monitoring/kube-prometheus-stack-admission-create; range_end:; response_count:0; response_revision:4541; }","duration":"447.333026ms","start":"2026-09-08T15:49:43.127376Z","end":"2026-09-08T15:49:43.574709Z","steps":["trace[1997178041] 'agreement among raft nodes before linearized reading' (duration: 447.22108ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:43.574763Z","caller":"traceutil/trace.go:171","msg":"trace[607178243] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:4541; }","duration":"407.81468ms","start":"2026-09-08T15:49:43.166888Z","end":"2026-09-08T15:49:43.574703Z","steps":["trace[607178243] 'agreement among raft nodes before linearized reading' (duration: 407.634821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:43.574907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:43.166875Z","time spent":"408.01101ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-08T15:49:43.574954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.757446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-09-08T15:49:43.574787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:43.127362Z","time spent":"447.418091ms","remote":"127.0.0.1:34914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/monitoring/kube-prometheus-stack-admission-create\" "} {"level":"info","ts":"2026-09-08T15:49:43.575056Z","caller":"traceutil/trace.go:171","msg":"trace[1078859172] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4541; }","duration":"377.842051ms","start":"2026-09-08T15:49:43.197154Z","end":"2026-09-08T15:49:43.574996Z","steps":["trace[1078859172] 'agreement among raft nodes before linearized reading' (duration: 377.670422ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:43.575117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:43.197138Z","time spent":"377.961697ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-08T15:49:43.825541Z","caller":"traceutil/trace.go:171","msg":"trace[638689079] transaction","detail":"{read_only:false; response_revision:4544; number_of_response:1; }","duration":"122.526612ms","start":"2026-09-08T15:49:43.701947Z","end":"2026-09-08T15:49:43.824473Z","steps":["trace[638689079] 'process raft request' (duration: 121.719011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:44.441998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.960431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:49:44.44212Z","caller":"traceutil/trace.go:171","msg":"trace[1895142675] transaction","detail":"{read_only:false; response_revision:4548; number_of_response:1; }","duration":"463.901488ms","start":"2026-09-08T15:49:43.978195Z","end":"2026-09-08T15:49:44.442097Z","steps":["trace[1895142675] 'process raft request' (duration: 95.736656ms)","trace[1895142675] 'compare' (duration: 367.656196ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:44.442224Z","caller":"traceutil/trace.go:171","msg":"trace[2004175961] linearizableReadLoop","detail":"{readStateIndex:4736; appliedIndex:4734; }","duration":"436.931352ms","start":"2026-09-08T15:49:44.005266Z","end":"2026-09-08T15:49:44.442198Z","steps":["trace[2004175961] 'read index received' (duration: 37.323706ms)","trace[2004175961] 'applied index is now lower than readState.Index' (duration: 399.604506ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:44.442333Z","caller":"traceutil/trace.go:171","msg":"trace[16104006] transaction","detail":"{read_only:false; response_revision:4551; number_of_response:1; }","duration":"393.483664ms","start":"2026-09-08T15:49:44.048832Z","end":"2026-09-08T15:49:44.442316Z","steps":["trace[16104006] 'process raft request' (duration: 393.418271ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:44.442423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:44.048818Z","time spent":"393.558998ms","remote":"127.0.0.1:34914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3849,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:49:44.44249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.218877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-08T15:49:44.442542Z","caller":"traceutil/trace.go:171","msg":"trace[755089029] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4551; }","duration":"437.29811ms","start":"2026-09-08T15:49:44.005227Z","end":"2026-09-08T15:49:44.442525Z","steps":["trace[755089029] 'agreement among raft nodes before linearized reading' (duration: 437.0902ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:44.442586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:44.005208Z","time spent":"437.364614ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-08T15:49:44.442566Z","caller":"traceutil/trace.go:171","msg":"trace[1757835867] transaction","detail":"{read_only:false; response_revision:4549; number_of_response:1; }","duration":"395.052475ms","start":"2026-09-08T15:49:44.047493Z","end":"2026-09-08T15:49:44.442545Z","steps":["trace[1757835867] 'process raft request' (duration: 394.609482ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:44.442629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:43.978183Z","time spent":"464.083057ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:49:44.44273Z","caller":"traceutil/trace.go:171","msg":"trace[1568233484] transaction","detail":"{read_only:false; response_revision:4550; number_of_response:1; }","duration":"394.839424ms","start":"2026-09-08T15:49:44.04787Z","end":"2026-09-08T15:49:44.442709Z","steps":["trace[1568233484] 'process raft request' (duration: 394.315846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:44.442907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:44.047857Z","time spent":"394.97515ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3385,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:49:44.442988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.323385ms","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-09-08T15:49:44.44307Z","caller":"traceutil/trace.go:171","msg":"trace[1957842461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4551; }","duration":"411.406109ms","start":"2026-09-08T15:49:44.031645Z","end":"2026-09-08T15:49:44.443052Z","steps":["trace[1957842461] 'agreement among raft nodes before linearized reading' (duration: 411.121355ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:44.443114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:44.03161Z","time spent":"411.490984ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-08T15:49:44.443163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.488485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:44.443217Z","caller":"traceutil/trace.go:171","msg":"trace[1310875483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4551; }","duration":"422.566969ms","start":"2026-09-08T15:49:44.020636Z","end":"2026-09-08T15:49:44.443203Z","steps":["trace[1310875483] 'agreement among raft nodes before linearized reading' (duration: 422.120256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:44.443254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:44.020621Z","time spent":"422.623702ms","remote":"127.0.0.1:34652","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-09-08T15:49:44.443883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.233771ms","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-09-08T15:49:44.443906Z","caller":"traceutil/trace.go:171","msg":"trace[2006505662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4551; }","duration":"412.285544ms","start":"2026-09-08T15:49:44.031615Z","end":"2026-09-08T15:49:44.4439Z","steps":["trace[2006505662] 'agreement among raft nodes before linearized reading' (duration: 411.470413ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:44.443935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:44.031601Z","time spent":"412.330976ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-08T15:49:44.442679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:44.047469Z","time spent":"395.166691ms","remote":"127.0.0.1:34734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":762,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T15:49:44.661888Z","caller":"traceutil/trace.go:171","msg":"trace[1977009041] transaction","detail":"{read_only:false; response_revision:4553; number_of_response:1; }","duration":"209.517704ms","start":"2026-09-08T15:49:44.452297Z","end":"2026-09-08T15:49:44.661815Z","steps":["trace[1977009041] 'process raft request' (duration: 209.322554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:44.662112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.452498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-create-k9tfq\" ","response":"range_response_count:1 size:3400"} {"level":"info","ts":"2026-09-08T15:49:44.662184Z","caller":"traceutil/trace.go:171","msg":"trace[1293145266] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-create-k9tfq; range_end:; response_count:1; response_revision:4553; }","duration":"206.562604ms","start":"2026-09-08T15:49:44.455598Z","end":"2026-09-08T15:49:44.662161Z","steps":["trace[1293145266] 'agreement among raft nodes before linearized reading' (duration: 206.308251ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:44.661853Z","caller":"traceutil/trace.go:171","msg":"trace[2031340361] linearizableReadLoop","detail":"{readStateIndex:4741; appliedIndex:4740; }","duration":"206.153383ms","start":"2026-09-08T15:49:44.455636Z","end":"2026-09-08T15:49:44.66179Z","steps":["trace[2031340361] 'read index received' (duration: 206.018366ms)","trace[2031340361] 'applied index is now lower than readState.Index' (duration: 133.667µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:44.688391Z","caller":"traceutil/trace.go:171","msg":"trace[1566961686] transaction","detail":"{read_only:false; response_revision:4554; number_of_response:1; }","duration":"232.611403ms","start":"2026-09-08T15:49:44.455763Z","end":"2026-09-08T15:49:44.688374Z","steps":["trace[1566961686] 'process raft request' (duration: 232.491367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:44.688633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.497465ms","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-09-08T15:49:44.688636Z","caller":"traceutil/trace.go:171","msg":"trace[1437371986] transaction","detail":"{read_only:false; response_revision:4555; number_of_response:1; }","duration":"232.820264ms","start":"2026-09-08T15:49:44.455758Z","end":"2026-09-08T15:49:44.688578Z","steps":["trace[1437371986] 'process raft request' (duration: 232.576351ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:44.6887Z","caller":"traceutil/trace.go:171","msg":"trace[1975162050] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4555; }","duration":"163.60619ms","start":"2026-09-08T15:49:44.525077Z","end":"2026-09-08T15:49:44.688683Z","steps":["trace[1975162050] 'agreement among raft nodes before linearized reading' (duration: 163.435981ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:44.80698Z","caller":"traceutil/trace.go:171","msg":"trace[118611027] linearizableReadLoop","detail":"{readStateIndex:4744; appliedIndex:4743; }","duration":"114.99555ms","start":"2026-09-08T15:49:44.691959Z","end":"2026-09-08T15:49:44.806954Z","steps":["trace[118611027] 'read index received' (duration: 46.859381ms)","trace[118611027] 'applied index is now lower than readState.Index' (duration: 68.134589ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:44.807039Z","caller":"traceutil/trace.go:171","msg":"trace[903816413] transaction","detail":"{read_only:false; response_revision:4556; number_of_response:1; }","duration":"137.791664ms","start":"2026-09-08T15:49:44.669202Z","end":"2026-09-08T15:49:44.806994Z","steps":["trace[903816413] 'process raft request' (duration: 69.680536ms)","trace[903816413] 'compare' (duration: 67.943079ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:44.807149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.170018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:44.807199Z","caller":"traceutil/trace.go:171","msg":"trace[69215136] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4556; }","duration":"115.253463ms","start":"2026-09-08T15:49:44.691933Z","end":"2026-09-08T15:49:44.807186Z","steps":["trace[69215136] 'agreement among raft nodes before linearized reading' (duration: 115.155158ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:45.277544Z","caller":"traceutil/trace.go:171","msg":"trace[1402529287] transaction","detail":"{read_only:false; response_revision:4558; number_of_response:1; }","duration":"104.656415ms","start":"2026-09-08T15:49:45.172849Z","end":"2026-09-08T15:49:45.277505Z","steps":["trace[1402529287] 'process raft request' (duration: 88.163782ms)","trace[1402529287] 'compare' (duration: 16.26066ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:45.636616Z","caller":"traceutil/trace.go:171","msg":"trace[664332567] transaction","detail":"{read_only:false; response_revision:4560; number_of_response:1; }","duration":"112.661153ms","start":"2026-09-08T15:49:45.523934Z","end":"2026-09-08T15:49:45.636595Z","steps":["trace[664332567] 'process raft request' (duration: 112.288614ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:45.82992Z","caller":"traceutil/trace.go:171","msg":"trace[965906097] transaction","detail":"{read_only:false; response_revision:4562; number_of_response:1; }","duration":"188.805611ms","start":"2026-09-08T15:49:45.641083Z","end":"2026-09-08T15:49:45.829889Z","steps":["trace[965906097] 'process raft request' (duration: 166.895072ms)","trace[965906097] 'compare' (duration: 21.73712ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:45.830143Z","caller":"traceutil/trace.go:171","msg":"trace[1735615969] transaction","detail":"{read_only:false; response_revision:4563; number_of_response:1; }","duration":"188.927837ms","start":"2026-09-08T15:49:45.641195Z","end":"2026-09-08T15:49:45.830123Z","steps":["trace[1735615969] 'process raft request' (duration: 188.643772ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:46.672926Z","caller":"traceutil/trace.go:171","msg":"trace[871753466] transaction","detail":"{read_only:false; response_revision:4567; number_of_response:1; }","duration":"108.687031ms","start":"2026-09-08T15:49:46.564212Z","end":"2026-09-08T15:49:46.672899Z","steps":["trace[871753466] 'process raft request' (duration: 108.526173ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:46.89496Z","caller":"traceutil/trace.go:171","msg":"trace[759418498] transaction","detail":"{read_only:false; response_revision:4570; number_of_response:1; }","duration":"176.276663ms","start":"2026-09-08T15:49:46.718657Z","end":"2026-09-08T15:49:46.894934Z","steps":["trace[759418498] 'process raft request' (duration: 176.22661ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:46.895092Z","caller":"traceutil/trace.go:171","msg":"trace[1873627769] transaction","detail":"{read_only:false; response_revision:4569; number_of_response:1; }","duration":"176.544776ms","start":"2026-09-08T15:49:46.718438Z","end":"2026-09-08T15:49:46.894982Z","steps":["trace[1873627769] 'process raft request' (duration: 125.3153ms)","trace[1873627769] 'compare' (duration: 50.976584ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:49.01561Z","caller":"traceutil/trace.go:171","msg":"trace[1725720481] transaction","detail":"{read_only:false; response_revision:4580; number_of_response:1; }","duration":"111.267686ms","start":"2026-09-08T15:49:48.904302Z","end":"2026-09-08T15:49:49.015569Z","steps":["trace[1725720481] 'process raft request' (duration: 52.920684ms)","trace[1725720481] 'compare' (duration: 57.938371ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:49.602612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.624684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-09-08T15:49:49.602706Z","caller":"traceutil/trace.go:171","msg":"trace[535122896] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4581; }","duration":"243.767361ms","start":"2026-09-08T15:49:49.358916Z","end":"2026-09-08T15:49:49.602683Z","steps":["trace[535122896] 'range keys from in-memory index tree' (duration: 243.362001ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:50.004986Z","caller":"traceutil/trace.go:171","msg":"trace[1386510714] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"100.630964ms","start":"2026-09-08T15:49:49.904319Z","end":"2026-09-08T15:49:50.00495Z","steps":["trace[1386510714] 'process raft request' (duration: 74.313759ms)","trace[1386510714] 'compare' (duration: 25.717715ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:52.226863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.941527ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:52.227005Z","caller":"traceutil/trace.go:171","msg":"trace[1623150757] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4596; }","duration":"171.090064ms","start":"2026-09-08T15:49:52.055886Z","end":"2026-09-08T15:49:52.226976Z","steps":["trace[1623150757] 'range keys from in-memory index tree' (duration: 170.924425ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:52.227346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.707973ms","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-09-08T15:49:52.227431Z","caller":"traceutil/trace.go:171","msg":"trace[937454353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4596; }","duration":"334.891392ms","start":"2026-09-08T15:49:51.892519Z","end":"2026-09-08T15:49:52.227411Z","steps":["trace[937454353] 'range keys from in-memory index tree' (duration: 334.471471ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:52.227479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:51.892495Z","time spent":"334.975516ms","remote":"127.0.0.1:34940","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-09-08T15:49:52.227899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.058737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-08T15:49:52.228044Z","caller":"traceutil/trace.go:171","msg":"trace[1222845734] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4596; }","duration":"218.735102ms","start":"2026-09-08T15:49:52.009289Z","end":"2026-09-08T15:49:52.228024Z","steps":["trace[1222845734] 'range keys from in-memory index tree' (duration: 217.869066ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:52.227923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.307284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T15:49:52.228259Z","caller":"traceutil/trace.go:171","msg":"trace[29469262] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4596; }","duration":"180.486254ms","start":"2026-09-08T15:49:52.047756Z","end":"2026-09-08T15:49:52.228242Z","steps":["trace[29469262] 'range keys from in-memory index tree' (duration: 178.735545ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:52.253981Z","caller":"traceutil/trace.go:171","msg":"trace[1470707874] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"188.684144ms","start":"2026-09-08T15:49:52.065275Z","end":"2026-09-08T15:49:52.253959Z","steps":["trace[1470707874] 'process raft request' (duration: 188.535056ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:52.726499Z","caller":"traceutil/trace.go:171","msg":"trace[1589976649] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"105.481966ms","start":"2026-09-08T15:49:52.620974Z","end":"2026-09-08T15:49:52.726456Z","steps":["trace[1589976649] 'process raft request' (duration: 92.706523ms)","trace[1589976649] 'compare' (duration: 12.645156ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:52.770715Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2862} {"level":"info","ts":"2026-09-08T15:49:53.083173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2862,"took":"310.912069ms","hash":3547870645,"current-db-size-bytes":43307008,"current-db-size":"43 MB","current-db-size-in-use-bytes":20127744,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-08T15:49:53.083935Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3547870645,"revision":2862,"compact-revision":-1} {"level":"info","ts":"2026-09-08T15:50:06.90893Z","caller":"traceutil/trace.go:171","msg":"trace[753241161] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"169.97388ms","start":"2026-09-08T15:50:06.73893Z","end":"2026-09-08T15:50:06.908904Z","steps":["trace[753241161] 'process raft request' (duration: 169.816452ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:06.925894Z","caller":"traceutil/trace.go:171","msg":"trace[2026200515] linearizableReadLoop","detail":"{readStateIndex:5380; appliedIndex:5378; }","duration":"179.067007ms","start":"2026-09-08T15:50:06.7468Z","end":"2026-09-08T15:50:06.925867Z","steps":["trace[2026200515] 'read index received' (duration: 161.99767ms)","trace[2026200515] 'applied index is now lower than readState.Index' (duration: 17.068057ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:50:06.926009Z","caller":"traceutil/trace.go:171","msg":"trace[525636373] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"181.112502ms","start":"2026-09-08T15:50:06.744877Z","end":"2026-09-08T15:50:06.92599Z","steps":["trace[525636373] 'process raft request' (duration: 178.641835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:06.926335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.51404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-09-08T15:50:06.926392Z","caller":"traceutil/trace.go:171","msg":"trace[845756736] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5177; }","duration":"179.609165ms","start":"2026-09-08T15:50:06.746765Z","end":"2026-09-08T15:50:06.926374Z","steps":["trace[845756736] 'agreement among raft nodes before linearized reading' (duration: 179.473598ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:12.961776Z","caller":"traceutil/trace.go:171","msg":"trace[1884466458] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"162.994545ms","start":"2026-09-08T15:50:12.798751Z","end":"2026-09-08T15:50:12.961745Z","steps":["trace[1884466458] 'process raft request' (duration: 162.718281ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:13.028129Z","caller":"traceutil/trace.go:171","msg":"trace[902660371] linearizableReadLoop","detail":"{readStateIndex:5434; appliedIndex:5432; }","duration":"207.852751ms","start":"2026-09-08T15:50:12.820218Z","end":"2026-09-08T15:50:13.02807Z","steps":["trace[902660371] 'read index received' (duration: 141.441865ms)","trace[902660371] 'applied index is now lower than readState.Index' (duration: 66.409436ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:50:13.028301Z","caller":"traceutil/trace.go:171","msg":"trace[1714739428] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"214.26615ms","start":"2026-09-08T15:50:12.814009Z","end":"2026-09-08T15:50:13.028275Z","steps":["trace[1714739428] 'process raft request' (duration: 213.768275ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:13.02857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.277053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-08T15:50:13.028792Z","caller":"traceutil/trace.go:171","msg":"trace[1698450537] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5229; }","duration":"208.528776ms","start":"2026-09-08T15:50:12.82018Z","end":"2026-09-08T15:50:13.028709Z","steps":["trace[1698450537] 'agreement among raft nodes before linearized reading' (duration: 208.159377ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:13.370285Z","caller":"traceutil/trace.go:171","msg":"trace[573027231] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"111.662594ms","start":"2026-09-08T15:50:13.258594Z","end":"2026-09-08T15:50:13.370257Z","steps":["trace[573027231] 'process raft request' (duration: 111.459594ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:13.389756Z","caller":"traceutil/trace.go:171","msg":"trace[687915332] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"129.978107ms","start":"2026-09-08T15:50:13.259754Z","end":"2026-09-08T15:50:13.389732Z","steps":["trace[687915332] 'process raft request' (duration: 129.856571ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:16.14765Z","caller":"traceutil/trace.go:171","msg":"trace[266492728] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"143.295468ms","start":"2026-09-08T15:50:16.004335Z","end":"2026-09-08T15:50:16.14763Z","steps":["trace[266492728] 'process raft request' (duration: 142.969191ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:20.623392Z","caller":"traceutil/trace.go:171","msg":"trace[1684641132] linearizableReadLoop","detail":"{readStateIndex:5603; appliedIndex:5602; }","duration":"128.868321ms","start":"2026-09-08T15:50:20.494498Z","end":"2026-09-08T15:50:20.623367Z","steps":["trace[1684641132] 'read index received' (duration: 128.577816ms)","trace[1684641132] 'applied index is now lower than readState.Index' (duration: 288.975µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:50:20.623462Z","caller":"traceutil/trace.go:171","msg":"trace[742781793] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"198.469328ms","start":"2026-09-08T15:50:20.424956Z","end":"2026-09-08T15:50:20.623426Z","steps":["trace[742781793] 'process raft request' (duration: 198.207294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:20.623589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.053881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:50:20.623687Z","caller":"traceutil/trace.go:171","msg":"trace[626185684] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:5395; }","duration":"129.177768ms","start":"2026-09-08T15:50:20.49449Z","end":"2026-09-08T15:50:20.623668Z","steps":["trace[626185684] 'agreement among raft nodes before linearized reading' (duration: 129.006029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:20.623767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.783681ms","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-09-08T15:50:20.623901Z","caller":"traceutil/trace.go:171","msg":"trace[1536011105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5395; }","duration":"104.9763ms","start":"2026-09-08T15:50:20.518901Z","end":"2026-09-08T15:50:20.623878Z","steps":["trace[1536011105] 'agreement among raft nodes before linearized reading' (duration: 104.671535ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:20.853074Z","caller":"traceutil/trace.go:171","msg":"trace[1522618191] linearizableReadLoop","detail":"{readStateIndex:5605; appliedIndex:5603; }","duration":"222.453614ms","start":"2026-09-08T15:50:20.630596Z","end":"2026-09-08T15:50:20.85305Z","steps":["trace[1522618191] 'read index received' (duration: 134.000726ms)","trace[1522618191] 'applied index is now lower than readState.Index' (duration: 88.451948ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:50:20.853207Z","caller":"traceutil/trace.go:171","msg":"trace[278053376] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"222.522068ms","start":"2026-09-08T15:50:20.630674Z","end":"2026-09-08T15:50:20.853196Z","steps":["trace[278053376] 'process raft request' (duration: 222.339528ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:20.853231Z","caller":"traceutil/trace.go:171","msg":"trace[1820577372] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"222.711238ms","start":"2026-09-08T15:50:20.63048Z","end":"2026-09-08T15:50:20.853191Z","steps":["trace[1820577372] 'process raft request' (duration: 222.486065ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:20.853591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.978091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-08T15:50:20.853637Z","caller":"traceutil/trace.go:171","msg":"trace[1172435235] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5398; }","duration":"223.060305ms","start":"2026-09-08T15:50:20.630567Z","end":"2026-09-08T15:50:20.853627Z","steps":["trace[1172435235] 'agreement among raft nodes before linearized reading' (duration: 222.900117ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:20.853602Z","caller":"traceutil/trace.go:171","msg":"trace[791551269] transaction","detail":"{read_only:false; response_revision:5396; number_of_response:1; }","duration":"224.52799ms","start":"2026-09-08T15:50:20.629059Z","end":"2026-09-08T15:50:20.853587Z","steps":["trace[791551269] 'process raft request' (duration: 135.521863ms)","trace[791551269] 'compare' (duration: 88.234317ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:50:20.853879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.742733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:50:20.853927Z","caller":"traceutil/trace.go:171","msg":"trace[809900814] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:5398; }","duration":"153.856819ms","start":"2026-09-08T15:50:20.70006Z","end":"2026-09-08T15:50:20.853917Z","steps":["trace[809900814] 'agreement among raft nodes before linearized reading' (duration: 153.753494ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:20.999179Z","caller":"traceutil/trace.go:171","msg":"trace[1483614390] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"134.644019ms","start":"2026-09-08T15:50:20.864482Z","end":"2026-09-08T15:50:20.999126Z","steps":["trace[1483614390] 'process raft request' (duration: 134.29044ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:21.945596Z","caller":"traceutil/trace.go:171","msg":"trace[915919151] transaction","detail":"{read_only:false; response_revision:5913; number_of_response:1; }","duration":"144.411887ms","start":"2026-09-08T15:51:21.801167Z","end":"2026-09-08T15:51:21.945578Z","steps":["trace[915919151] 'process raft request' (duration: 132.955695ms)","trace[915919151] 'compare' (duration: 11.22881ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:54:52.778197Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4603} {"level":"info","ts":"2026-09-08T15:54:52.831612Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4603,"took":"52.006108ms","hash":3958794998,"current-db-size-bytes":43307008,"current-db-size":"43 MB","current-db-size-in-use-bytes":34308096,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-08T15:54:52.831686Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3958794998,"revision":4603,"compact-revision":2862}