{"level":"warn","ts":"2026-08-12T00:45:03.49076Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T00:45:03.49109Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-08-12T00:45:03.491194Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T00:45:03.491215Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-08-12T00:45:03.491248Z","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-08-12T00:45:03.49193Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-08-12T00:45:03.492046Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-08-12T00:45:03.494681Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.311534ms"} {"level":"info","ts":"2026-08-12T00:45:03.506785Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-08-12T00:45:03.506945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T00:45:03.506977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-08-12T00:45:03.506992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T00:45:03.507011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-08-12T00:45:03.507071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-08-12T00:45:03.512706Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T00:45:03.51427Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T00:45:03.5154Z","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-08-12T00:45:03.517648Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T00:45:03.517813Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T00:45:03.518032Z","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-08-12T00:45:03.518159Z","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-08-12T00:45:03.518366Z","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-08-12T00:45:03.520836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-08-12T00:45:03.522177Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-08-12T00:45:03.523718Z","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-08-12T00:45:03.523861Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-08-12T00:45:03.523991Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-08-12T00:45:03.524063Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T00:45:03.524116Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T00:45:03.807941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T00:45:03.808088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T00:45:03.808222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-08-12T00:45:03.808284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-08-12T00:45:03.808318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-08-12T00:45:03.808378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-08-12T00:45:03.808415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-08-12T00:45:03.823715Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T00:45:03.824692Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T00:45:03.824702Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T00:45:03.824836Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T00:45:03.825083Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T00:45:03.825125Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T00:45:03.828161Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-12T00:45:03.828651Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-08-12T00:45:03.82991Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T00:45:03.830049Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T00:45:03.830128Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T00:45:08.55123Z","caller":"traceutil/trace.go:171","msg":"trace[1401264847] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"188.627451ms","start":"2026-08-12T00:45:08.362572Z","end":"2026-08-12T00:45:08.5512Z","steps":["trace[1401264847] 'process raft request' (duration: 188.57407ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:45:08.551261Z","caller":"traceutil/trace.go:171","msg":"trace[761794967] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"189.974475ms","start":"2026-08-12T00:45:08.361252Z","end":"2026-08-12T00:45:08.551227Z","steps":["trace[761794967] 'process raft request' (duration: 155.494868ms)","trace[761794967] 'compare' (duration: 34.256035ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:45:18.127279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.500325ms","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-08-12T00:45:18.127977Z","caller":"traceutil/trace.go:171","msg":"trace[55986009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:364; }","duration":"116.224601ms","start":"2026-08-12T00:45:18.011719Z","end":"2026-08-12T00:45:18.127943Z","steps":["trace[55986009] 'range keys from in-memory index tree' (duration: 115.401303ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:45:18.322438Z","caller":"traceutil/trace.go:171","msg":"trace[1360399068] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"190.765524ms","start":"2026-08-12T00:45:18.131637Z","end":"2026-08-12T00:45:18.322403Z","steps":["trace[1360399068] 'process raft request' (duration: 190.537659ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:45:18.487144Z","caller":"traceutil/trace.go:171","msg":"trace[101072627] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"350.631451ms","start":"2026-08-12T00:45:18.136483Z","end":"2026-08-12T00:45:18.487115Z","steps":["trace[101072627] 'process raft request' (duration: 350.434477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:45:18.487888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:45:18.136468Z","time spent":"350.770364ms","remote":"127.0.0.1:58848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:45:47.895471Z","caller":"traceutil/trace.go:171","msg":"trace[1609106879] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"210.724004ms","start":"2026-08-12T00:45:47.684714Z","end":"2026-08-12T00:45:47.895438Z","steps":["trace[1609106879] 'read index received' (duration: 209.864356ms)","trace[1609106879] 'applied index is now lower than readState.Index' (duration: 858.398µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:45:47.895562Z","caller":"traceutil/trace.go:171","msg":"trace[1602632804] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"383.535128ms","start":"2026-08-12T00:45:47.511965Z","end":"2026-08-12T00:45:47.8955Z","steps":["trace[1602632804] 'process raft request' (duration: 383.075789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:45:47.895803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:45:47.511933Z","time spent":"383.748352ms","remote":"127.0.0.1:58732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T00:45:47.895906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.018861ms","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-08-12T00:45:47.895957Z","caller":"traceutil/trace.go:171","msg":"trace[750644295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"211.306826ms","start":"2026-08-12T00:45:47.684639Z","end":"2026-08-12T00:45:47.895946Z","steps":["trace[750644295] 'agreement among raft nodes before linearized reading' (duration: 210.919729ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:46:32.863556Z","caller":"traceutil/trace.go:171","msg":"trace[520803549] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"109.661005ms","start":"2026-08-12T00:46:32.753865Z","end":"2026-08-12T00:46:32.863526Z","steps":["trace[520803549] 'process raft request' (duration: 109.476092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:47:20.418744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.157728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:47:20.418899Z","caller":"traceutil/trace.go:171","msg":"trace[1507494184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1841; }","duration":"154.403283ms","start":"2026-08-12T00:47:20.264466Z","end":"2026-08-12T00:47:20.418869Z","steps":["trace[1507494184] 'range keys from in-memory index tree' (duration: 154.029384ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:47:35.827873Z","caller":"traceutil/trace.go:171","msg":"trace[1802469783] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"179.627589ms","start":"2026-08-12T00:47:35.648203Z","end":"2026-08-12T00:47:35.827831Z","steps":["trace[1802469783] 'process raft request' (duration: 157.45634ms)","trace[1802469783] 'compare' (duration: 21.868023ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:48:09.466955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.105318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T00:48:09.467204Z","caller":"traceutil/trace.go:171","msg":"trace[1846198694] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"113.35291ms","start":"2026-08-12T00:48:09.353788Z","end":"2026-08-12T00:48:09.467141Z","steps":["trace[1846198694] 'compare' (duration: 104.911733ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:48:15.984695Z","caller":"traceutil/trace.go:171","msg":"trace[352455552] linearizableReadLoop","detail":"{readStateIndex:2273; appliedIndex:2272; }","duration":"229.725962ms","start":"2026-08-12T00:48:15.75494Z","end":"2026-08-12T00:48:15.984666Z","steps":["trace[352455552] 'read index received' (duration: 229.471075ms)","trace[352455552] 'applied index is now lower than readState.Index' (duration: 253.907µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:48:15.984804Z","caller":"traceutil/trace.go:171","msg":"trace[1175516164] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"267.806869ms","start":"2026-08-12T00:48:15.716962Z","end":"2026-08-12T00:48:15.984769Z","steps":["trace[1175516164] 'process raft request' (duration: 267.449359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:48:15.984975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.00646ms","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-08-12T00:48:15.98523Z","caller":"traceutil/trace.go:171","msg":"trace[1871559954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2189; }","duration":"230.335429ms","start":"2026-08-12T00:48:15.754878Z","end":"2026-08-12T00:48:15.985213Z","steps":["trace[1871559954] 'agreement among raft nodes before linearized reading' (duration: 229.921677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:48:15.98571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.976331ms","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-08-12T00:48:15.985783Z","caller":"traceutil/trace.go:171","msg":"trace[15953762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2189; }","duration":"229.048473ms","start":"2026-08-12T00:48:15.7567Z","end":"2026-08-12T00:48:15.985748Z","steps":["trace[15953762] 'agreement among raft nodes before linearized reading' (duration: 228.92529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:48:16.147373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.919038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T00:48:16.147624Z","caller":"traceutil/trace.go:171","msg":"trace[241588404] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"156.262571ms","start":"2026-08-12T00:48:15.99132Z","end":"2026-08-12T00:48:16.147582Z","steps":["trace[241588404] 'process raft request' (duration: 44.047225ms)","trace[241588404] 'compare' (duration: 111.787344ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:48:16.147672Z","caller":"traceutil/trace.go:171","msg":"trace[1572481785] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"155.825099ms","start":"2026-08-12T00:48:15.991826Z","end":"2026-08-12T00:48:16.147651Z","steps":["trace[1572481785] 'process raft request' (duration: 155.644784ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:48:16.148018Z","caller":"traceutil/trace.go:171","msg":"trace[541114388] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"156.065177ms","start":"2026-08-12T00:48:15.991936Z","end":"2026-08-12T00:48:16.148001Z","steps":["trace[541114388] 'process raft request' (duration: 155.624133ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:48:16.148022Z","caller":"traceutil/trace.go:171","msg":"trace[1587848453] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"150.157341ms","start":"2026-08-12T00:48:15.997829Z","end":"2026-08-12T00:48:16.147987Z","steps":["trace[1587848453] 'process raft request' (duration: 150.029928ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:51:56.55342Z","caller":"traceutil/trace.go:171","msg":"trace[1218634136] linearizableReadLoop","detail":"{readStateIndex:3902; appliedIndex:3901; }","duration":"111.816555ms","start":"2026-08-12T00:51:56.441571Z","end":"2026-08-12T00:51:56.553387Z","steps":["trace[1218634136] 'read index received' (duration: 111.64111ms)","trace[1218634136] 'applied index is now lower than readState.Index' (duration: 173.705µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:51:56.553542Z","caller":"traceutil/trace.go:171","msg":"trace[611822785] transaction","detail":"{read_only:false; response_revision:3759; number_of_response:1; }","duration":"133.194082ms","start":"2026-08-12T00:51:56.420303Z","end":"2026-08-12T00:51:56.553497Z","steps":["trace[611822785] 'process raft request' (duration: 132.886053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:51:56.553603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.9989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:51:56.553659Z","caller":"traceutil/trace.go:171","msg":"trace[1417322592] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:3759; }","duration":"112.117203ms","start":"2026-08-12T00:51:56.441522Z","end":"2026-08-12T00:51:56.553639Z","steps":["trace[1417322592] 'agreement among raft nodes before linearized reading' (duration: 112.00494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:53:27.968086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.568652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-12T00:53:27.968197Z","caller":"traceutil/trace.go:171","msg":"trace[1376572826] linearizableReadLoop","detail":"{readStateIndex:5177; appliedIndex:5176; }","duration":"272.62318ms","start":"2026-08-12T00:53:27.695557Z","end":"2026-08-12T00:53:27.96818Z","steps":["trace[1376572826] 'read index received' (duration: 125.928935ms)","trace[1376572826] 'applied index is now lower than readState.Index' (duration: 146.693195ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:53:27.968515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.947732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-736b5be7-9673-4ea2-8740-74d1c6c3a6b6\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-12T00:53:27.968577Z","caller":"traceutil/trace.go:171","msg":"trace[1831159324] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-736b5be7-9673-4ea2-8740-74d1c6c3a6b6; range_end:; response_count:1; response_revision:4987; }","duration":"257.053055ms","start":"2026-08-12T00:53:27.711509Z","end":"2026-08-12T00:53:27.968562Z","steps":["trace[1831159324] 'agreement among raft nodes before linearized reading' (duration: 256.831199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:53:27.968766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.681871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-12T00:53:27.968804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.935459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-736b5be7-9673-4ea2-8740-74d1c6c3a6b6\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-12T00:53:27.968855Z","caller":"traceutil/trace.go:171","msg":"trace[1437052101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4987; }","duration":"104.816095ms","start":"2026-08-12T00:53:27.864029Z","end":"2026-08-12T00:53:27.968845Z","steps":["trace[1437052101] 'agreement among raft nodes before linearized reading' (duration: 104.509236ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:53:27.968885Z","caller":"traceutil/trace.go:171","msg":"trace[1913950097] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-736b5be7-9673-4ea2-8740-74d1c6c3a6b6; range_end:; response_count:1; response_revision:4987; }","duration":"106.094734ms","start":"2026-08-12T00:53:27.862771Z","end":"2026-08-12T00:53:27.968866Z","steps":["trace[1913950097] 'agreement among raft nodes before linearized reading' (duration: 105.808336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:53:27.968971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.410543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-c8mm4-tls\" ","response":"range_response_count:1 size:1967"} {"level":"info","ts":"2026-08-12T00:53:27.969004Z","caller":"traceutil/trace.go:171","msg":"trace[1568535963] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-c8mm4-tls; range_end:; response_count:1; response_revision:4987; }","duration":"273.485075ms","start":"2026-08-12T00:53:27.695509Z","end":"2026-08-12T00:53:27.968994Z","steps":["trace[1568535963] 'agreement among raft nodes before linearized reading' (duration: 272.854986ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:53:27.969093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.97986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T00:53:27.969157Z","caller":"traceutil/trace.go:171","msg":"trace[1364217741] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:4987; }","duration":"105.066452ms","start":"2026-08-12T00:53:27.864077Z","end":"2026-08-12T00:53:27.969143Z","steps":["trace[1364217741] 'agreement among raft nodes before linearized reading' (duration: 104.395403ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:53:27.968505Z","caller":"traceutil/trace.go:171","msg":"trace[603650357] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"305.021305ms","start":"2026-08-12T00:53:27.663437Z","end":"2026-08-12T00:53:27.968458Z","steps":["trace[603650357] 'process raft request' (duration: 158.008241ms)","trace[603650357] 'compare' (duration: 146.444937ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:53:27.969323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:53:27.663418Z","time spent":"305.814209ms","remote":"127.0.0.1:41594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2501,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T00:53:40.092194Z","caller":"traceutil/trace.go:171","msg":"trace[176232366] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"140.133581ms","start":"2026-08-12T00:53:39.952015Z","end":"2026-08-12T00:53:40.092148Z","steps":["trace[176232366] 'process raft request' (duration: 125.503881ms)","trace[176232366] 'compare' (duration: 14.405724ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:55:03.998021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2974} {"level":"info","ts":"2026-08-12T00:55:04.071811Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2974,"took":"71.858905ms","hash":2103796649,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":34238464,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-12T00:55:04.071895Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2103796649,"revision":2974,"compact-revision":-1} {"level":"info","ts":"2026-08-12T01:00:03.014Z","caller":"traceutil/trace.go:171","msg":"trace[1749205775] transaction","detail":"{read_only:false; response_revision:8268; number_of_response:1; }","duration":"172.813737ms","start":"2026-08-12T01:00:02.841151Z","end":"2026-08-12T01:00:03.013965Z","steps":["trace[1749205775] 'process raft request' (duration: 172.540619ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:04.005943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6081} {"level":"info","ts":"2026-08-12T01:00:04.09698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6081,"took":"88.549717ms","hash":790547567,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":23846912,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-12T01:00:04.097145Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":790547567,"revision":6081,"compact-revision":2974} {"level":"info","ts":"2026-08-12T01:00:14.756256Z","caller":"traceutil/trace.go:171","msg":"trace[1319081422] linearizableReadLoop","detail":"{readStateIndex:8621; appliedIndex:8620; }","duration":"238.328974ms","start":"2026-08-12T01:00:14.517887Z","end":"2026-08-12T01:00:14.756216Z","steps":["trace[1319081422] 'read index received' (duration: 237.958364ms)","trace[1319081422] 'applied index is now lower than readState.Index' (duration: 368.82µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:00:14.756367Z","caller":"traceutil/trace.go:171","msg":"trace[1933474384] transaction","detail":"{read_only:false; response_revision:8326; number_of_response:1; }","duration":"251.436997ms","start":"2026-08-12T01:00:14.504887Z","end":"2026-08-12T01:00:14.756324Z","steps":["trace[1933474384] 'process raft request' (duration: 251.010285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:00:14.756492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.561361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:00:14.756549Z","caller":"traceutil/trace.go:171","msg":"trace[1533738010] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8326; }","duration":"238.678154ms","start":"2026-08-12T01:00:14.517857Z","end":"2026-08-12T01:00:14.756535Z","steps":["trace[1533738010] 'agreement among raft nodes before linearized reading' (duration: 238.54573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:00:14.778736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.765397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T01:00:14.778792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.543808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T01:00:14.778864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.079153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:00:14.778905Z","caller":"traceutil/trace.go:171","msg":"trace[1304963400] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8327; }","duration":"164.139665ms","start":"2026-08-12T01:00:14.614751Z","end":"2026-08-12T01:00:14.778891Z","steps":["trace[1304963400] 'agreement among raft nodes before linearized reading' (duration: 164.088284ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:14.778903Z","caller":"traceutil/trace.go:171","msg":"trace[1004640305] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8327; }","duration":"163.694933ms","start":"2026-08-12T01:00:14.615186Z","end":"2026-08-12T01:00:14.778881Z","steps":["trace[1004640305] 'agreement among raft nodes before linearized reading' (duration: 163.516908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:00:14.778737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.845752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T01:00:14.779004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.586666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T01:00:14.778974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.090699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:00:14.778863Z","caller":"traceutil/trace.go:171","msg":"trace[1712789736] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8327; }","duration":"260.938702ms","start":"2026-08-12T01:00:14.517907Z","end":"2026-08-12T01:00:14.778845Z","steps":["trace[1712789736] 'agreement among raft nodes before linearized reading' (duration: 260.719396ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:14.779089Z","caller":"traceutil/trace.go:171","msg":"trace[1474073823] transaction","detail":"{read_only:false; response_revision:8327; number_of_response:1; }","duration":"200.642668ms","start":"2026-08-12T01:00:14.578135Z","end":"2026-08-12T01:00:14.778778Z","steps":["trace[1474073823] 'process raft request' (duration: 200.312959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:00:14.779173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.087802ms","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-08-12T01:00:14.779203Z","caller":"traceutil/trace.go:171","msg":"trace[185154962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8327; }","duration":"165.133913ms","start":"2026-08-12T01:00:14.614061Z","end":"2026-08-12T01:00:14.779195Z","steps":["trace[185154962] 'agreement among raft nodes before linearized reading' (duration: 165.006169ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:00:14.779207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.728238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:00:14.779266Z","caller":"traceutil/trace.go:171","msg":"trace[781952521] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8327; }","duration":"193.80222ms","start":"2026-08-12T01:00:14.585437Z","end":"2026-08-12T01:00:14.77924Z","steps":["trace[781952521] 'agreement among raft nodes before linearized reading' (duration: 193.725578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:00:14.779281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.607303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T01:00:14.779324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.300602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-12T01:00:14.779325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.703087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T01:00:14.779377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.607734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:00:14.7794Z","caller":"traceutil/trace.go:171","msg":"trace[2113465437] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8327; }","duration":"238.775937ms","start":"2026-08-12T01:00:14.54059Z","end":"2026-08-12T01:00:14.779366Z","steps":["trace[2113465437] 'agreement among raft nodes before linearized reading' (duration: 238.50819ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:14.779416Z","caller":"traceutil/trace.go:171","msg":"trace[507372973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8327; }","duration":"154.396695ms","start":"2026-08-12T01:00:14.624988Z","end":"2026-08-12T01:00:14.779385Z","steps":["trace[507372973] 'agreement among raft nodes before linearized reading' (duration: 154.025785ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:14.779446Z","caller":"traceutil/trace.go:171","msg":"trace[1731876925] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8327; }","duration":"237.740879ms","start":"2026-08-12T01:00:14.541681Z","end":"2026-08-12T01:00:14.779421Z","steps":["trace[1731876925] 'agreement among raft nodes before linearized reading' (duration: 237.519163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:00:14.779477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.632952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T01:00:14.779467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.500972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T01:00:14.778819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.93884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T01:00:14.779501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.522883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-12T01:00:14.779564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.52514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:00:14.779585Z","caller":"traceutil/trace.go:171","msg":"trace[1451991626] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8327; }","duration":"179.653746ms","start":"2026-08-12T01:00:14.599899Z","end":"2026-08-12T01:00:14.779552Z","steps":["trace[1451991626] 'agreement among raft nodes before linearized reading' (duration: 179.422439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:00:14.77959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.243564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T01:00:14.779631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.162869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:00:14.77912Z","caller":"traceutil/trace.go:171","msg":"trace[1858753515] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8327; }","duration":"215.293616ms","start":"2026-08-12T01:00:14.563796Z","end":"2026-08-12T01:00:14.77909Z","steps":["trace[1858753515] 'agreement among raft nodes before linearized reading' (duration: 215.020597ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:14.779632Z","caller":"traceutil/trace.go:171","msg":"trace[946593684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8327; }","duration":"157.698617ms","start":"2026-08-12T01:00:14.621907Z","end":"2026-08-12T01:00:14.779606Z","steps":["trace[946593684] 'agreement among raft nodes before linearized reading' (duration: 157.318587ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:14.779663Z","caller":"traceutil/trace.go:171","msg":"trace[473386325] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8327; }","duration":"215.328206ms","start":"2026-08-12T01:00:14.564314Z","end":"2026-08-12T01:00:14.779642Z","steps":["trace[473386325] 'agreement among raft nodes before linearized reading' (duration: 215.234253ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:14.779671Z","caller":"traceutil/trace.go:171","msg":"trace[1064111195] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8327; }","duration":"261.237211ms","start":"2026-08-12T01:00:14.51842Z","end":"2026-08-12T01:00:14.779658Z","steps":["trace[1064111195] 'agreement among raft nodes before linearized reading' (duration: 261.17966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:00:14.779728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.085647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:00:14.779044Z","caller":"traceutil/trace.go:171","msg":"trace[537638678] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8327; }","duration":"178.644748ms","start":"2026-08-12T01:00:14.600388Z","end":"2026-08-12T01:00:14.779033Z","steps":["trace[537638678] 'agreement among raft nodes before linearized reading' (duration: 178.572905ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:14.779619Z","caller":"traceutil/trace.go:171","msg":"trace[1941272780] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8327; }","duration":"194.597851ms","start":"2026-08-12T01:00:14.585012Z","end":"2026-08-12T01:00:14.77961Z","steps":["trace[1941272780] 'agreement among raft nodes before linearized reading' (duration: 194.527589ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:14.779519Z","caller":"traceutil/trace.go:171","msg":"trace[2062565256] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8327; }","duration":"194.695304ms","start":"2026-08-12T01:00:14.584811Z","end":"2026-08-12T01:00:14.779507Z","steps":["trace[2062565256] 'agreement among raft nodes before linearized reading' (duration: 194.637783ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:14.779835Z","caller":"traceutil/trace.go:171","msg":"trace[1652290133] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8327; }","duration":"239.268321ms","start":"2026-08-12T01:00:14.540522Z","end":"2026-08-12T01:00:14.779791Z","steps":["trace[1652290133] 'agreement among raft nodes before linearized reading' (duration: 239.007124ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:14.77942Z","caller":"traceutil/trace.go:171","msg":"trace[1120441513] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8327; }","duration":"215.83619ms","start":"2026-08-12T01:00:14.56356Z","end":"2026-08-12T01:00:14.779396Z","steps":["trace[1120441513] 'agreement among raft nodes before linearized reading' (duration: 215.642255ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:14.779017Z","caller":"traceutil/trace.go:171","msg":"trace[1249633595] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8327; }","duration":"179.162092ms","start":"2026-08-12T01:00:14.599844Z","end":"2026-08-12T01:00:14.779006Z","steps":["trace[1249633595] 'agreement among raft nodes before linearized reading' (duration: 178.830302ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:14.77957Z","caller":"traceutil/trace.go:171","msg":"trace[108406364] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8327; }","duration":"164.702162ms","start":"2026-08-12T01:00:14.614856Z","end":"2026-08-12T01:00:14.779558Z","steps":["trace[108406364] 'agreement among raft nodes before linearized reading' (duration: 163.921859ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:15.600481Z","caller":"traceutil/trace.go:171","msg":"trace[409278360] linearizableReadLoop","detail":"{readStateIndex:8628; appliedIndex:8626; }","duration":"349.080059ms","start":"2026-08-12T01:00:15.251368Z","end":"2026-08-12T01:00:15.600448Z","steps":["trace[409278360] 'read index received' (duration: 76.065611ms)","trace[409278360] 'applied index is now lower than readState.Index' (duration: 273.012788ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:00:15.600549Z","caller":"traceutil/trace.go:171","msg":"trace[1428318019] transaction","detail":"{read_only:false; response_revision:8333; number_of_response:1; }","duration":"355.761134ms","start":"2026-08-12T01:00:15.244754Z","end":"2026-08-12T01:00:15.600515Z","steps":["trace[1428318019] 'process raft request' (duration: 343.180344ms)","trace[1428318019] 'compare' (duration: 12.205029ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:00:15.600715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:00:15.244731Z","time spent":"355.897168ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:00:15.600736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.343656ms","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-08-12T01:00:15.600813Z","caller":"traceutil/trace.go:171","msg":"trace[607016120] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8333; }","duration":"349.462819ms","start":"2026-08-12T01:00:15.251329Z","end":"2026-08-12T01:00:15.600792Z","steps":["trace[607016120] 'agreement among raft nodes before linearized reading' (duration: 349.223053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:00:15.600858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:00:15.251307Z","time spent":"349.540352ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-12T01:00:39.871719Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002266.wal"} {"level":"info","ts":"2026-08-12T01:00:45.052609Z","caller":"traceutil/trace.go:171","msg":"trace[985719303] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"118.653633ms","start":"2026-08-12T01:00:44.933916Z","end":"2026-08-12T01:00:45.05257Z","steps":["trace[985719303] 'process raft request' (duration: 118.427957ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:47.942794Z","caller":"traceutil/trace.go:171","msg":"trace[295619205] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"125.497573ms","start":"2026-08-12T01:00:47.817251Z","end":"2026-08-12T01:00:47.942748Z","steps":["trace[295619205] 'process raft request' (duration: 60.134498ms)","trace[295619205] 'compare' (duration: 64.792859ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:00:58.014552Z","caller":"traceutil/trace.go:171","msg":"trace[1222958508] transaction","detail":"{read_only:false; response_revision:8656; number_of_response:1; }","duration":"109.707545ms","start":"2026-08-12T01:00:57.904812Z","end":"2026-08-12T01:00:58.014519Z","steps":["trace[1222958508] 'process raft request' (duration: 104.47641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:00:58.015151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.76136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-08-12T01:00:58.015271Z","caller":"traceutil/trace.go:171","msg":"trace[84203407] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8656; }","duration":"103.924414ms","start":"2026-08-12T01:00:57.911319Z","end":"2026-08-12T01:00:58.015244Z","steps":["trace[84203407] 'agreement among raft nodes before linearized reading' (duration: 103.39843ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:58.068109Z","caller":"traceutil/trace.go:171","msg":"trace[177233777] transaction","detail":"{read_only:false; response_revision:8657; number_of_response:1; }","duration":"137.447495ms","start":"2026-08-12T01:00:57.930605Z","end":"2026-08-12T01:00:58.068053Z","steps":["trace[177233777] 'process raft request' (duration: 137.076604ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:59.079383Z","caller":"traceutil/trace.go:171","msg":"trace[1651097676] transaction","detail":"{read_only:false; response_revision:8663; number_of_response:1; }","duration":"224.847731ms","start":"2026-08-12T01:00:58.854506Z","end":"2026-08-12T01:00:59.079354Z","steps":["trace[1651097676] 'process raft request' (duration: 224.673006ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:00:59.122012Z","caller":"traceutil/trace.go:171","msg":"trace[345959963] transaction","detail":"{read_only:false; response_revision:8664; number_of_response:1; }","duration":"189.271812ms","start":"2026-08-12T01:00:58.932697Z","end":"2026-08-12T01:00:59.121968Z","steps":["trace[345959963] 'process raft request' (duration: 189.009605ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:02:51.207585Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T01:02:51.217659Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-12T01:02:51.217803Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-12T01:05:04.013445Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8272} {"level":"info","ts":"2026-08-12T01:05:04.09494Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8272,"took":"79.051782ms","hash":2165672088,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":26484736,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-12T01:05:04.09504Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2165672088,"revision":8272,"compact-revision":6081} {"level":"info","ts":"2026-08-12T01:10:04.023689Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10757} {"level":"info","ts":"2026-08-12T01:10:04.107834Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10757,"took":"80.444669ms","hash":3175006316,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":26943488,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-12T01:10:04.1079Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3175006316,"revision":10757,"compact-revision":8272} {"level":"info","ts":"2026-08-12T01:12:01.108778Z","caller":"traceutil/trace.go:171","msg":"trace[838400462] transaction","detail":"{read_only:false; response_revision:13706; number_of_response:1; }","duration":"132.138221ms","start":"2026-08-12T01:12:00.976608Z","end":"2026-08-12T01:12:01.108746Z","steps":["trace[838400462] 'process raft request' (duration: 131.895395ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:12:01.109289Z","caller":"traceutil/trace.go:171","msg":"trace[749736646] linearizableReadLoop","detail":"{readStateIndex:14176; appliedIndex:14174; }","duration":"131.36649ms","start":"2026-08-12T01:12:00.977902Z","end":"2026-08-12T01:12:01.109269Z","steps":["trace[749736646] 'read index received' (duration: 130.668ms)","trace[749736646] 'applied index is now lower than readState.Index' (duration: 697.19µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:12:01.109378Z","caller":"traceutil/trace.go:171","msg":"trace[619059164] transaction","detail":"{read_only:false; response_revision:13707; number_of_response:1; }","duration":"132.334637ms","start":"2026-08-12T01:12:00.977003Z","end":"2026-08-12T01:12:01.109337Z","steps":["trace[619059164] 'process raft request' (duration: 132.187643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:12:01.109487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.657603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-12T01:12:01.109486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.561475ms","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-08-12T01:12:01.109539Z","caller":"traceutil/trace.go:171","msg":"trace[382207998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13707; }","duration":"129.719566ms","start":"2026-08-12T01:12:00.979803Z","end":"2026-08-12T01:12:01.109523Z","steps":["trace[382207998] 'agreement among raft nodes before linearized reading' (duration: 129.582131ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:12:01.109557Z","caller":"traceutil/trace.go:171","msg":"trace[1382355963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13707; }","duration":"131.645599ms","start":"2026-08-12T01:12:00.977883Z","end":"2026-08-12T01:12:01.109529Z","steps":["trace[1382355963] 'agreement among raft nodes before linearized reading' (duration: 131.476403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:12:42.897279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.079585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:12:42.897423Z","caller":"traceutil/trace.go:171","msg":"trace[20057026] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:13940; }","duration":"129.27815ms","start":"2026-08-12T01:12:42.768114Z","end":"2026-08-12T01:12:42.897392Z","steps":["trace[20057026] 'count revisions from in-memory index tree' (duration: 128.989592ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:12:43.705444Z","caller":"traceutil/trace.go:171","msg":"trace[1355050716] linearizableReadLoop","detail":"{readStateIndex:14425; appliedIndex:14424; }","duration":"256.976875ms","start":"2026-08-12T01:12:43.448426Z","end":"2026-08-12T01:12:43.705403Z","steps":["trace[1355050716] 'read index received' (duration: 256.625016ms)","trace[1355050716] 'applied index is now lower than readState.Index' (duration: 350.729µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:12:43.705847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.327345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:12:43.706009Z","caller":"traceutil/trace.go:171","msg":"trace[1527545492] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:13946; }","duration":"257.570312ms","start":"2026-08-12T01:12:43.448406Z","end":"2026-08-12T01:12:43.705976Z","steps":["trace[1527545492] 'agreement among raft nodes before linearized reading' (duration: 257.271844ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:12:43.706036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.236367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-08-12T01:12:43.706125Z","caller":"traceutil/trace.go:171","msg":"trace[1381599036] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:13946; }","duration":"224.36768ms","start":"2026-08-12T01:12:43.481736Z","end":"2026-08-12T01:12:43.706104Z","steps":["trace[1381599036] 'agreement among raft nodes before linearized reading' (duration: 224.107243ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:12:43.706136Z","caller":"traceutil/trace.go:171","msg":"trace[431633983] transaction","detail":"{read_only:false; response_revision:13946; number_of_response:1; }","duration":"269.893194ms","start":"2026-08-12T01:12:43.436225Z","end":"2026-08-12T01:12:43.706118Z","steps":["trace[431633983] 'process raft request' (duration: 268.955948ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:12:53.610423Z","caller":"traceutil/trace.go:171","msg":"trace[2004742261] transaction","detail":"{read_only:false; response_revision:14114; number_of_response:1; }","duration":"158.490881ms","start":"2026-08-12T01:12:53.451885Z","end":"2026-08-12T01:12:53.610376Z","steps":["trace[2004742261] 'process raft request' (duration: 158.297295ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:12:53.612529Z","caller":"traceutil/trace.go:171","msg":"trace[1782221527] transaction","detail":"{read_only:false; response_revision:14115; number_of_response:1; }","duration":"160.392814ms","start":"2026-08-12T01:12:53.452104Z","end":"2026-08-12T01:12:53.612497Z","steps":["trace[1782221527] 'process raft request' (duration: 160.169177ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:12:53.612952Z","caller":"traceutil/trace.go:171","msg":"trace[1880772725] transaction","detail":"{read_only:false; response_revision:14116; number_of_response:1; }","duration":"160.820065ms","start":"2026-08-12T01:12:53.452106Z","end":"2026-08-12T01:12:53.612927Z","steps":["trace[1880772725] 'process raft request' (duration: 160.331692ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:12:53.613581Z","caller":"traceutil/trace.go:171","msg":"trace[1256833963] transaction","detail":"{read_only:false; response_revision:14117; number_of_response:1; }","duration":"159.599462ms","start":"2026-08-12T01:12:53.453925Z","end":"2026-08-12T01:12:53.613524Z","steps":["trace[1256833963] 'process raft request' (duration: 158.817409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:13:01.568282Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972456123211672,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-12T01:13:01.877755Z","caller":"traceutil/trace.go:171","msg":"trace[106147898] linearizableReadLoop","detail":"{readStateIndex:14757; appliedIndex:14756; }","duration":"809.567041ms","start":"2026-08-12T01:13:01.068158Z","end":"2026-08-12T01:13:01.877725Z","steps":["trace[106147898] 'read index received' (duration: 809.294173ms)","trace[106147898] 'applied index is now lower than readState.Index' (duration: 270.868µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:13:01.878032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.202788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T01:13:01.878073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.879999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:13:01.878178Z","caller":"traceutil/trace.go:171","msg":"trace[258281460] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:14266; }","duration":"810.066525ms","start":"2026-08-12T01:13:01.068086Z","end":"2026-08-12T01:13:01.878152Z","steps":["trace[258281460] 'agreement among raft nodes before linearized reading' (duration: 809.857719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:13:01.878196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.364499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-12T01:13:01.878195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.252428ms","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-08-12T01:13:01.878274Z","caller":"traceutil/trace.go:171","msg":"trace[268558008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14266; }","duration":"225.445811ms","start":"2026-08-12T01:13:01.652807Z","end":"2026-08-12T01:13:01.878253Z","steps":["trace[268558008] 'agreement among raft nodes before linearized reading' (duration: 225.293027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:13:01.878271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:13:01.068059Z","time spent":"810.193317ms","remote":"127.0.0.1:48864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"info","ts":"2026-08-12T01:13:01.878278Z","caller":"traceutil/trace.go:171","msg":"trace[400367075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14266; }","duration":"224.377621ms","start":"2026-08-12T01:13:01.653884Z","end":"2026-08-12T01:13:01.878261Z","steps":["trace[400367075] 'agreement among raft nodes before linearized reading' (duration: 224.143444ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:13:01.878101Z","caller":"traceutil/trace.go:171","msg":"trace[290164067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14266; }","duration":"613.338332ms","start":"2026-08-12T01:13:01.264745Z","end":"2026-08-12T01:13:01.878083Z","steps":["trace[290164067] 'agreement among raft nodes before linearized reading' (duration: 613.217678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:13:01.878358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.661066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1565"} {"level":"warn","ts":"2026-08-12T01:13:01.878429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.305977ms","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-08-12T01:13:01.878501Z","caller":"traceutil/trace.go:171","msg":"trace[1285646652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14266; }","duration":"225.420151ms","start":"2026-08-12T01:13:01.65306Z","end":"2026-08-12T01:13:01.878481Z","steps":["trace[1285646652] 'agreement among raft nodes before linearized reading' (duration: 225.159753ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:13:01.878503Z","caller":"traceutil/trace.go:171","msg":"trace[134164711] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:14266; }","duration":"167.823411ms","start":"2026-08-12T01:13:01.71062Z","end":"2026-08-12T01:13:01.878443Z","steps":["trace[134164711] 'agreement among raft nodes before linearized reading' (duration: 167.44536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:13:01.878529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:13:01.264722Z","time spent":"613.724383ms","remote":"127.0.0.1:58660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T01:13:01.87856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.277745ms","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-08-12T01:13:01.878656Z","caller":"traceutil/trace.go:171","msg":"trace[1284688387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14266; }","duration":"221.395689ms","start":"2026-08-12T01:13:01.657229Z","end":"2026-08-12T01:13:01.878625Z","steps":["trace[1284688387] 'agreement among raft nodes before linearized reading' (duration: 220.941786ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:13:01.877926Z","caller":"traceutil/trace.go:171","msg":"trace[2091536213] transaction","detail":"{read_only:false; response_revision:14266; number_of_response:1; }","duration":"892.049882ms","start":"2026-08-12T01:13:00.985821Z","end":"2026-08-12T01:13:01.877871Z","steps":["trace[2091536213] 'process raft request' (duration: 891.680852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:13:01.87897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:13:00.985806Z","time spent":"893.047119ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:13:01.879605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.653764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:13:01.879696Z","caller":"traceutil/trace.go:171","msg":"trace[2099932975] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:14266; }","duration":"499.793548ms","start":"2026-08-12T01:13:01.379886Z","end":"2026-08-12T01:13:01.879679Z","steps":["trace[2099932975] 'agreement among raft nodes before linearized reading' (duration: 498.243816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:13:01.879912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:13:01.379864Z","time spent":"500.024914ms","remote":"127.0.0.1:59104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-08-12T01:14:36.01984Z","caller":"traceutil/trace.go:171","msg":"trace[511101317] linearizableReadLoop","detail":"{readStateIndex:15729; appliedIndex:15728; }","duration":"195.786768ms","start":"2026-08-12T01:14:35.824009Z","end":"2026-08-12T01:14:36.019795Z","steps":["trace[511101317] 'read index received' (duration: 195.588733ms)","trace[511101317] 'applied index is now lower than readState.Index' (duration: 196.265µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:14:36.01995Z","caller":"traceutil/trace.go:171","msg":"trace[863123780] transaction","detail":"{read_only:false; response_revision:15212; number_of_response:1; }","duration":"222.399307ms","start":"2026-08-12T01:14:35.797497Z","end":"2026-08-12T01:14:36.019896Z","steps":["trace[863123780] 'process raft request' (duration: 222.046657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:14:36.020271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.875848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-zgv5s\" ","response":"range_response_count:1 size:22014"} {"level":"info","ts":"2026-08-12T01:14:36.020331Z","caller":"traceutil/trace.go:171","msg":"trace[1994592904] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-zgv5s; range_end:; response_count:1; response_revision:15212; }","duration":"169.993421ms","start":"2026-08-12T01:14:35.85032Z","end":"2026-08-12T01:14:36.020314Z","steps":["trace[1994592904] 'agreement among raft nodes before linearized reading' (duration: 169.735814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:14:36.020294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.22981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-zzlhn\" ","response":"range_response_count:1 size:20558"} {"level":"warn","ts":"2026-08-12T01:14:36.020378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.279901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-zgv5s\" ","response":"range_response_count:1 size:22014"} {"level":"info","ts":"2026-08-12T01:14:36.020426Z","caller":"traceutil/trace.go:171","msg":"trace[497249455] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-zzlhn; range_end:; response_count:1; response_revision:15212; }","duration":"196.392104ms","start":"2026-08-12T01:14:35.824004Z","end":"2026-08-12T01:14:36.020396Z","steps":["trace[497249455] 'agreement among raft nodes before linearized reading' (duration: 195.971983ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:14:36.020478Z","caller":"traceutil/trace.go:171","msg":"trace[956431731] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-zgv5s; range_end:; response_count:1; response_revision:15212; }","duration":"169.415295ms","start":"2026-08-12T01:14:35.851041Z","end":"2026-08-12T01:14:36.020457Z","steps":["trace[956431731] 'agreement among raft nodes before linearized reading' (duration: 169.142808ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:15:04.031028Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12829} {"level":"info","ts":"2026-08-12T01:15:04.11201Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12829,"took":"77.372289ms","hash":3769204346,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":30220288,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-12T01:15:04.112081Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3769204346,"revision":12829,"compact-revision":10757} {"level":"info","ts":"2026-08-12T01:20:04.040134Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15356} {"level":"info","ts":"2026-08-12T01:20:04.138286Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15356,"took":"93.844481ms","hash":48450245,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":31698944,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-12T01:20:04.138362Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":48450245,"revision":15356,"compact-revision":12829} {"level":"warn","ts":"2026-08-12T01:20:17.204386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.239699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:20:17.204626Z","caller":"traceutil/trace.go:171","msg":"trace[809654115] transaction","detail":"{read_only:false; response_revision:17755; number_of_response:1; }","duration":"263.056471ms","start":"2026-08-12T01:20:16.941547Z","end":"2026-08-12T01:20:17.204603Z","steps":["trace[809654115] 'process raft request' (duration: 262.971909ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:20:17.204736Z","caller":"traceutil/trace.go:171","msg":"trace[669002495] transaction","detail":"{read_only:false; response_revision:17754; number_of_response:1; }","duration":"339.884721ms","start":"2026-08-12T01:20:16.864812Z","end":"2026-08-12T01:20:17.204697Z","steps":["trace[669002495] 'process raft request' (duration: 152.22562ms)","trace[669002495] 'compare' (duration: 186.823837ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:20:17.204798Z","caller":"traceutil/trace.go:171","msg":"trace[62821552] linearizableReadLoop","detail":"{readStateIndex:18355; appliedIndex:18354; }","duration":"287.821198ms","start":"2026-08-12T01:20:16.916866Z","end":"2026-08-12T01:20:17.204688Z","steps":["trace[62821552] 'read index received' (duration: 100.199457ms)","trace[62821552] 'applied index is now lower than readState.Index' (duration: 187.61666ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:20:17.204971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:20:16.864779Z","time spent":"340.058786ms","remote":"127.0.0.1:58848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20778,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:20:17.205112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.24637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:1647"} {"level":"info","ts":"2026-08-12T01:20:17.205162Z","caller":"traceutil/trace.go:171","msg":"trace[1185018853] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:17755; }","duration":"288.350542ms","start":"2026-08-12T01:20:16.916793Z","end":"2026-08-12T01:20:17.205144Z","steps":["trace[1185018853] 'agreement among raft nodes before linearized reading' (duration: 288.136766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:20:17.205187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.606768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:1647"} {"level":"info","ts":"2026-08-12T01:20:17.205298Z","caller":"traceutil/trace.go:171","msg":"trace[1160980976] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:17755; }","duration":"284.771943ms","start":"2026-08-12T01:20:16.9205Z","end":"2026-08-12T01:20:17.205272Z","steps":["trace[1160980976] 'agreement among raft nodes before linearized reading' (duration: 284.493336ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:20:17.475418Z","caller":"traceutil/trace.go:171","msg":"trace[1362000259] linearizableReadLoop","detail":"{readStateIndex:18358; appliedIndex:18356; }","duration":"254.667409ms","start":"2026-08-12T01:20:17.220723Z","end":"2026-08-12T01:20:17.47539Z","steps":["trace[1362000259] 'read index received' (duration: 185.990686ms)","trace[1362000259] 'applied index is now lower than readState.Index' (duration: 68.675653ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:20:17.47574Z","caller":"traceutil/trace.go:171","msg":"trace[1493795317] transaction","detail":"{read_only:false; response_revision:17756; number_of_response:1; }","duration":"261.040825ms","start":"2026-08-12T01:20:17.214684Z","end":"2026-08-12T01:20:17.475725Z","steps":["trace[1493795317] 'process raft request' (duration: 260.49578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:20:17.47581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.046269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:20:17.476029Z","caller":"traceutil/trace.go:171","msg":"trace[935794816] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:17756; }","duration":"255.309516ms","start":"2026-08-12T01:20:17.220687Z","end":"2026-08-12T01:20:17.475996Z","steps":["trace[935794816] 'agreement among raft nodes before linearized reading' (duration: 254.981548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:20:17.494285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.538972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18943"} {"level":"info","ts":"2026-08-12T01:20:17.494358Z","caller":"traceutil/trace.go:171","msg":"trace[985415515] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:17757; }","duration":"111.659715ms","start":"2026-08-12T01:20:17.382682Z","end":"2026-08-12T01:20:17.494342Z","steps":["trace[985415515] 'agreement among raft nodes before linearized reading' (duration: 111.377808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:20:17.49426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.070828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:20:17.494696Z","caller":"traceutil/trace.go:171","msg":"trace[1541498463] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:17757; }","duration":"107.592152ms","start":"2026-08-12T01:20:17.387071Z","end":"2026-08-12T01:20:17.494663Z","steps":["trace[1541498463] 'agreement among raft nodes before linearized reading' (duration: 107.081218ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:23:30.621995Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T01:23:30.628722Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-12T01:23:30.628907Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-12T01:25:04.048291Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17638} {"level":"info","ts":"2026-08-12T01:25:04.147476Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17638,"took":"94.718438ms","hash":665375431,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":34484224,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-12T01:25:04.147576Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":665375431,"revision":17638,"compact-revision":15356} {"level":"info","ts":"2026-08-12T01:25:55.393002Z","caller":"traceutil/trace.go:171","msg":"trace[1222946096] transaction","detail":"{read_only:false; response_revision:20387; number_of_response:1; }","duration":"306.915656ms","start":"2026-08-12T01:25:55.086042Z","end":"2026-08-12T01:25:55.392958Z","steps":["trace[1222946096] 'process raft request' (duration: 239.638129ms)","trace[1222946096] 'compare' (duration: 67.074091ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:25:55.393132Z","caller":"traceutil/trace.go:171","msg":"trace[1584682289] transaction","detail":"{read_only:false; response_revision:20389; number_of_response:1; }","duration":"299.451678ms","start":"2026-08-12T01:25:55.09367Z","end":"2026-08-12T01:25:55.393121Z","steps":["trace[1584682289] 'process raft request' (duration: 299.272183ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:25:55.39314Z","caller":"traceutil/trace.go:171","msg":"trace[1572405243] linearizableReadLoop","detail":"{readStateIndex:21074; appliedIndex:21071; }","duration":"128.680031ms","start":"2026-08-12T01:25:55.264429Z","end":"2026-08-12T01:25:55.393109Z","steps":["trace[1572405243] 'read index received' (duration: 61.26293ms)","trace[1572405243] 'applied index is now lower than readState.Index' (duration: 67.415051ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:25:55.39303Z","caller":"traceutil/trace.go:171","msg":"trace[1572511666] transaction","detail":"{read_only:false; response_revision:20388; number_of_response:1; }","duration":"306.626988ms","start":"2026-08-12T01:25:55.086369Z","end":"2026-08-12T01:25:55.392996Z","steps":["trace[1572511666] 'process raft request' (duration: 306.519835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:25:55.393397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.920158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T01:25:55.393398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:25:55.085974Z","time spent":"307.193823ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:25:55.393473Z","caller":"traceutil/trace.go:171","msg":"trace[1441087179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20389; }","duration":"129.068203ms","start":"2026-08-12T01:25:55.264364Z","end":"2026-08-12T01:25:55.393432Z","steps":["trace[1441087179] 'agreement among raft nodes before linearized reading' (duration: 128.878126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:25:55.393839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:25:55.086318Z","time spent":"307.019219ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:26:11.396924Z","caller":"traceutil/trace.go:171","msg":"trace[1357550209] transaction","detail":"{read_only:false; response_revision:20470; number_of_response:1; }","duration":"157.537421ms","start":"2026-08-12T01:26:11.239355Z","end":"2026-08-12T01:26:11.396893Z","steps":["trace[1357550209] 'process raft request' (duration: 157.308394ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:26:11.57496Z","caller":"traceutil/trace.go:171","msg":"trace[707159023] transaction","detail":"{read_only:false; response_revision:20471; number_of_response:1; }","duration":"119.357532ms","start":"2026-08-12T01:26:11.45557Z","end":"2026-08-12T01:26:11.574928Z","steps":["trace[707159023] 'process raft request' (duration: 119.179168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:26:11.813487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.066826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:26:11.813798Z","caller":"traceutil/trace.go:171","msg":"trace[379128416] transaction","detail":"{read_only:false; response_revision:20473; number_of_response:1; }","duration":"223.556083ms","start":"2026-08-12T01:26:11.590171Z","end":"2026-08-12T01:26:11.813728Z","steps":["trace[379128416] 'process raft request' (duration: 223.464551ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:26:11.813755Z","caller":"traceutil/trace.go:171","msg":"trace[1168514284] transaction","detail":"{read_only:false; response_revision:20472; number_of_response:1; }","duration":"354.939569ms","start":"2026-08-12T01:26:11.458771Z","end":"2026-08-12T01:26:11.813711Z","steps":["trace[1168514284] 'process raft request' (duration: 218.518643ms)","trace[1168514284] 'compare' (duration: 135.84918ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:26:11.813979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:26:11.458733Z","time spent":"355.138734ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:30:03.622101Z","caller":"traceutil/trace.go:171","msg":"trace[1156921331] transaction","detail":"{read_only:false; response_revision:22775; number_of_response:1; }","duration":"137.384769ms","start":"2026-08-12T01:30:03.484686Z","end":"2026-08-12T01:30:03.622071Z","steps":["trace[1156921331] 'process raft request' (duration: 137.104441ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:30:03.719458Z","caller":"traceutil/trace.go:171","msg":"trace[959613009] linearizableReadLoop","detail":"{readStateIndex:23533; appliedIndex:23531; }","duration":"156.034847ms","start":"2026-08-12T01:30:03.563396Z","end":"2026-08-12T01:30:03.719431Z","steps":["trace[959613009] 'read index received' (duration: 58.273161ms)","trace[959613009] 'applied index is now lower than readState.Index' (duration: 97.759976ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:30:03.719484Z","caller":"traceutil/trace.go:171","msg":"trace[1070398849] transaction","detail":"{read_only:false; response_revision:22776; number_of_response:1; }","duration":"226.851616ms","start":"2026-08-12T01:30:03.492593Z","end":"2026-08-12T01:30:03.719444Z","steps":["trace[1070398849] 'process raft request' (duration: 146.843582ms)","trace[1070398849] 'compare' (duration: 79.836329ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:30:03.71972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.310455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-12T01:30:03.719787Z","caller":"traceutil/trace.go:171","msg":"trace[441105988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22776; }","duration":"156.435699ms","start":"2026-08-12T01:30:03.563336Z","end":"2026-08-12T01:30:03.719772Z","steps":["trace[441105988] 'agreement among raft nodes before linearized reading' (duration: 156.228153ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:30:04.056572Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20108} {"level":"info","ts":"2026-08-12T01:30:04.172206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20108,"took":"110.019728ms","hash":1119794964,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":40882176,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-08-12T01:30:04.17227Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1119794964,"revision":20108,"compact-revision":17638} {"level":"info","ts":"2026-08-12T01:33:49.815609Z","caller":"traceutil/trace.go:171","msg":"trace[1965142881] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"136.626438ms","start":"2026-08-12T01:33:49.678949Z","end":"2026-08-12T01:33:49.815575Z","steps":["trace[1965142881] 'process raft request' (duration: 136.390401ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:49.820162Z","caller":"traceutil/trace.go:171","msg":"trace[855783898] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"127.249818ms","start":"2026-08-12T01:33:49.692865Z","end":"2026-08-12T01:33:49.820115Z","steps":["trace[855783898] 'process raft request' (duration: 127.074543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:50.108323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.12347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:33:50.108539Z","caller":"traceutil/trace.go:171","msg":"trace[378868415] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"211.719686ms","start":"2026-08-12T01:33:49.896788Z","end":"2026-08-12T01:33:50.108507Z","steps":["trace[378868415] 'process raft request' (duration: 82.296198ms)","trace[378868415] 'compare' (duration: 128.993966ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:33:54.386763Z","caller":"traceutil/trace.go:171","msg":"trace[9168082] linearizableReadLoop","detail":"{readStateIndex:25966; appliedIndex:25965; }","duration":"179.17656ms","start":"2026-08-12T01:33:54.207565Z","end":"2026-08-12T01:33:54.386741Z","steps":["trace[9168082] 'read index received' (duration: 179.062597ms)","trace[9168082] 'applied index is now lower than readState.Index' (duration: 113.023µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:33:54.386849Z","caller":"traceutil/trace.go:171","msg":"trace[956137787] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"262.486046ms","start":"2026-08-12T01:33:54.124324Z","end":"2026-08-12T01:33:54.38681Z","steps":["trace[956137787] 'process raft request' (duration: 262.26281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:54.386959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.377437ms","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-08-12T01:33:54.386989Z","caller":"traceutil/trace.go:171","msg":"trace[560602069] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25148; }","duration":"179.459519ms","start":"2026-08-12T01:33:54.207522Z","end":"2026-08-12T01:33:54.386982Z","steps":["trace[560602069] 'agreement among raft nodes before linearized reading' (duration: 179.319995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:54.411088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.518869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:33:54.411214Z","caller":"traceutil/trace.go:171","msg":"trace[1576190582] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25149; }","duration":"184.670574ms","start":"2026-08-12T01:33:54.226502Z","end":"2026-08-12T01:33:54.411172Z","steps":["trace[1576190582] 'agreement among raft nodes before linearized reading' (duration: 184.467678ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:55.452588Z","caller":"traceutil/trace.go:171","msg":"trace[1215564786] transaction","detail":"{read_only:false; response_revision:25151; number_of_response:1; }","duration":"249.203337ms","start":"2026-08-12T01:33:55.203357Z","end":"2026-08-12T01:33:55.452561Z","steps":["trace[1215564786] 'process raft request' (duration: 248.983341ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:55.694817Z","caller":"traceutil/trace.go:171","msg":"trace[994561302] transaction","detail":"{read_only:false; response_revision:25152; number_of_response:1; }","duration":"489.993081ms","start":"2026-08-12T01:33:55.204786Z","end":"2026-08-12T01:33:55.694779Z","steps":["trace[994561302] 'process raft request' (duration: 429.589451ms)","trace[994561302] 'compare' (duration: 60.097801ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:33:55.695062Z","caller":"traceutil/trace.go:171","msg":"trace[628581985] transaction","detail":"{read_only:false; response_revision:25153; number_of_response:1; }","duration":"490.044682ms","start":"2026-08-12T01:33:55.204981Z","end":"2026-08-12T01:33:55.695026Z","steps":["trace[628581985] 'process raft request' (duration: 489.739584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:55.695211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:55.204973Z","time spent":"490.159565ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:33:55.695203Z","caller":"traceutil/trace.go:171","msg":"trace[1350985352] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"192.047769ms","start":"2026-08-12T01:33:55.503138Z","end":"2026-08-12T01:33:55.695185Z","steps":["trace[1350985352] 'process raft request' (duration: 191.970917ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:55.695291Z","caller":"traceutil/trace.go:171","msg":"trace[445785309] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"188.488369ms","start":"2026-08-12T01:33:55.506788Z","end":"2026-08-12T01:33:55.695277Z","steps":["trace[445785309] 'process raft request' (duration: 188.364256ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:55.695371Z","caller":"traceutil/trace.go:171","msg":"trace[767185470] transaction","detail":"{read_only:false; response_revision:25154; number_of_response:1; }","duration":"489.384034ms","start":"2026-08-12T01:33:55.205975Z","end":"2026-08-12T01:33:55.695359Z","steps":["trace[767185470] 'process raft request' (duration: 488.970913ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:55.695433Z","caller":"traceutil/trace.go:171","msg":"trace[1575573131] transaction","detail":"{read_only:false; response_revision:25156; number_of_response:1; }","duration":"268.268827ms","start":"2026-08-12T01:33:55.427153Z","end":"2026-08-12T01:33:55.695421Z","steps":["trace[1575573131] 'process raft request' (duration: 267.908727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:55.695476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:55.20594Z","time spent":"489.453976ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:33:55.695502Z","caller":"traceutil/trace.go:171","msg":"trace[235975742] transaction","detail":"{read_only:false; response_revision:25155; number_of_response:1; }","duration":"314.749139ms","start":"2026-08-12T01:33:55.380747Z","end":"2026-08-12T01:33:55.695496Z","steps":["trace[235975742] 'process raft request' (duration: 314.275376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:55.695532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:55.380721Z","time spent":"314.79326ms","remote":"127.0.0.1:58944","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-08-12T01:33:55.695499Z","caller":"traceutil/trace.go:171","msg":"trace[765649786] linearizableReadLoop","detail":"{readStateIndex:25972; appliedIndex:25968; }","duration":"429.440697ms","start":"2026-08-12T01:33:55.266031Z","end":"2026-08-12T01:33:55.695472Z","steps":["trace[765649786] 'read index received' (duration: 186.36548ms)","trace[765649786] 'applied index is now lower than readState.Index' (duration: 243.072447ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:33:55.695681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.632493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:33:55.695715Z","caller":"traceutil/trace.go:171","msg":"trace[1059315319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25158; }","duration":"429.719205ms","start":"2026-08-12T01:33:55.265985Z","end":"2026-08-12T01:33:55.695704Z","steps":["trace[1059315319] 'agreement among raft nodes before linearized reading' (duration: 429.630092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:55.695748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:55.265964Z","time spent":"429.774966ms","remote":"127.0.0.1:58660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T01:33:55.696054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:55.204777Z","time spent":"490.119465ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:33:55.696191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.277005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-08-12T01:33:55.6963Z","caller":"traceutil/trace.go:171","msg":"trace[1682297148] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25158; }","duration":"219.426659ms","start":"2026-08-12T01:33:55.476842Z","end":"2026-08-12T01:33:55.696269Z","steps":["trace[1682297148] 'agreement among raft nodes before linearized reading' (duration: 219.10081ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:56.032973Z","caller":"traceutil/trace.go:171","msg":"trace[620285075] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"194.136287ms","start":"2026-08-12T01:33:55.838802Z","end":"2026-08-12T01:33:56.032938Z","steps":["trace[620285075] 'process raft request' (duration: 178.112421ms)","trace[620285075] 'compare' (duration: 15.770148ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:33:56.308746Z","caller":"traceutil/trace.go:171","msg":"trace[555740476] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"146.548454ms","start":"2026-08-12T01:33:56.162163Z","end":"2026-08-12T01:33:56.308712Z","steps":["trace[555740476] 'process raft request' (duration: 134.123249ms)","trace[555740476] 'compare' (duration: 12.269971ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:33:56.715531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.737102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:33:56.715771Z","caller":"traceutil/trace.go:171","msg":"trace[1763982623] linearizableReadLoop","detail":"{readStateIndex:25981; appliedIndex:25979; }","duration":"256.688214ms","start":"2026-08-12T01:33:56.459065Z","end":"2026-08-12T01:33:56.715753Z","steps":["trace[1763982623] 'read index received' (duration: 120.735426ms)","trace[1763982623] 'applied index is now lower than readState.Index' (duration: 135.951638ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:33:56.715857Z","caller":"traceutil/trace.go:171","msg":"trace[8809218] transaction","detail":"{read_only:false; response_revision:25163; number_of_response:1; }","duration":"304.297968ms","start":"2026-08-12T01:33:56.411551Z","end":"2026-08-12T01:33:56.715849Z","steps":["trace[8809218] 'process raft request' (duration: 304.096862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:56.715905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:56.411522Z","time spent":"304.3583ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:33:56.715892Z","caller":"traceutil/trace.go:171","msg":"trace[1339972485] transaction","detail":"{read_only:false; response_revision:25162; number_of_response:1; }","duration":"319.288585ms","start":"2026-08-12T01:33:56.396578Z","end":"2026-08-12T01:33:56.715867Z","steps":["trace[1339972485] 'process raft request' (duration: 183.114151ms)","trace[1339972485] 'compare' (duration: 135.370162ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:33:56.716004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.08215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T01:33:56.716047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.953991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:33:56.716094Z","caller":"traceutil/trace.go:171","msg":"trace[226802919] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25163; }","duration":"257.020473ms","start":"2026-08-12T01:33:56.459056Z","end":"2026-08-12T01:33:56.716076Z","steps":["trace[226802919] 'agreement among raft nodes before linearized reading' (duration: 256.857929ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:56.716091Z","caller":"traceutil/trace.go:171","msg":"trace[1282315141] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25163; }","duration":"128.247824ms","start":"2026-08-12T01:33:56.587823Z","end":"2026-08-12T01:33:56.716071Z","steps":["trace[1282315141] 'agreement among raft nodes before linearized reading' (duration: 128.076639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:56.71603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:56.396558Z","time spent":"319.393658ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:33:57.410925Z","caller":"traceutil/trace.go:171","msg":"trace[393401386] linearizableReadLoop","detail":"{readStateIndex:25982; appliedIndex:25981; }","duration":"185.659491ms","start":"2026-08-12T01:33:57.225238Z","end":"2026-08-12T01:33:57.410897Z","steps":["trace[393401386] 'read index received' (duration: 185.448185ms)","trace[393401386] 'applied index is now lower than readState.Index' (duration: 209.676µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:33:57.411022Z","caller":"traceutil/trace.go:171","msg":"trace[1221861860] transaction","detail":"{read_only:false; response_revision:25164; number_of_response:1; }","duration":"287.562803ms","start":"2026-08-12T01:33:57.123399Z","end":"2026-08-12T01:33:57.410961Z","steps":["trace[1221861860] 'process raft request' (duration: 287.295696ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:57.411195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.938359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-08-12T01:33:57.411234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.714058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:33:57.411259Z","caller":"traceutil/trace.go:171","msg":"trace[641028755] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:25164; }","duration":"186.073393ms","start":"2026-08-12T01:33:57.225168Z","end":"2026-08-12T01:33:57.411242Z","steps":["trace[641028755] 'agreement among raft nodes before linearized reading' (duration: 185.838866ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:57.41127Z","caller":"traceutil/trace.go:171","msg":"trace[909966479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25164; }","duration":"146.816221ms","start":"2026-08-12T01:33:57.264442Z","end":"2026-08-12T01:33:57.411258Z","steps":["trace[909966479] 'agreement among raft nodes before linearized reading' (duration: 146.732708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:57.600975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.519642ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-12T01:33:57.601098Z","caller":"traceutil/trace.go:171","msg":"trace[1461995147] linearizableReadLoop","detail":"{readStateIndex:25983; appliedIndex:25982; }","duration":"188.641733ms","start":"2026-08-12T01:33:57.412435Z","end":"2026-08-12T01:33:57.601076Z","steps":["trace[1461995147] 'read index received' (duration: 45.916846ms)","trace[1461995147] 'applied index is now lower than readState.Index' (duration: 142.723497ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:33:57.601682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.23704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:33:57.601796Z","caller":"traceutil/trace.go:171","msg":"trace[424931387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25164; }","duration":"189.334574ms","start":"2026-08-12T01:33:57.412425Z","end":"2026-08-12T01:33:57.60176Z","steps":["trace[424931387] 'agreement among raft nodes before linearized reading' (duration: 188.989063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:57.601943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.169125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-12T01:33:57.602028Z","caller":"traceutil/trace.go:171","msg":"trace[293380522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25164; }","duration":"145.33727ms","start":"2026-08-12T01:33:57.456671Z","end":"2026-08-12T01:33:57.602008Z","steps":["trace[293380522] 'agreement among raft nodes before linearized reading' (duration: 144.978639ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:57.761353Z","caller":"traceutil/trace.go:171","msg":"trace[837892666] transaction","detail":"{read_only:false; response_revision:25165; number_of_response:1; }","duration":"158.63317ms","start":"2026-08-12T01:33:57.602696Z","end":"2026-08-12T01:33:57.761329Z","steps":["trace[837892666] 'process raft request' (duration: 112.636221ms)","trace[837892666] 'compare' (duration: 45.838414ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:33:58.081893Z","caller":"traceutil/trace.go:171","msg":"trace[294483661] transaction","detail":"{read_only:false; response_revision:25166; number_of_response:1; }","duration":"475.047366ms","start":"2026-08-12T01:33:57.606808Z","end":"2026-08-12T01:33:58.081856Z","steps":["trace[294483661] 'process raft request' (duration: 420.306964ms)","trace[294483661] 'compare' (duration: 53.907349ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:33:58.082064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:57.606784Z","time spent":"475.189739ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:33:58.082507Z","caller":"traceutil/trace.go:171","msg":"trace[1206098480] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"380.830626ms","start":"2026-08-12T01:33:57.701581Z","end":"2026-08-12T01:33:58.082411Z","steps":["trace[1206098480] 'process raft request' (duration: 380.228049ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:58.082724Z","caller":"traceutil/trace.go:171","msg":"trace[2042341874] linearizableReadLoop","detail":"{readStateIndex:25985; appliedIndex:25983; }","duration":"382.138822ms","start":"2026-08-12T01:33:57.700571Z","end":"2026-08-12T01:33:58.08271Z","steps":["trace[2042341874] 'read index received' (duration: 14.718609ms)","trace[2042341874] 'applied index is now lower than readState.Index' (duration: 367.419463ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:33:58.08275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:57.701563Z","time spent":"381.047811ms","remote":"127.0.0.1:58944","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-08-12T01:33:58.082927Z","caller":"traceutil/trace.go:171","msg":"trace[1843106938] transaction","detail":"{read_only:false; response_revision:25168; number_of_response:1; }","duration":"380.946499ms","start":"2026-08-12T01:33:57.701968Z","end":"2026-08-12T01:33:58.082914Z","steps":["trace[1843106938] 'process raft request' (duration: 380.318142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:58.082993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:57.701953Z","time spent":"380.999701ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:33:58.083131Z","caller":"traceutil/trace.go:171","msg":"trace[262344295] transaction","detail":"{read_only:false; response_revision:25170; number_of_response:1; }","duration":"379.757175ms","start":"2026-08-12T01:33:57.703359Z","end":"2026-08-12T01:33:58.083116Z","steps":["trace[262344295] 'process raft request' (duration: 379.302933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:58.083307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.731088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-12T01:33:58.083354Z","caller":"traceutil/trace.go:171","msg":"trace[1244183951] transaction","detail":"{read_only:false; response_revision:25169; number_of_response:1; }","duration":"380.080184ms","start":"2026-08-12T01:33:57.703245Z","end":"2026-08-12T01:33:58.083326Z","steps":["trace[1244183951] 'process raft request' (duration: 379.378585ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:58.083343Z","caller":"traceutil/trace.go:171","msg":"trace[189161377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25170; }","duration":"382.791499ms","start":"2026-08-12T01:33:57.700541Z","end":"2026-08-12T01:33:58.083333Z","steps":["trace[189161377] 'agreement among raft nodes before linearized reading' (duration: 382.650275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:58.083459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:57.703229Z","time spent":"380.166167ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:33:58.083498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:57.700523Z","time spent":"382.964064ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T01:33:58.083612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:57.703349Z","time spent":"379.811798ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:33:58.089358Z","caller":"traceutil/trace.go:171","msg":"trace[1430799513] transaction","detail":"{read_only:false; response_revision:25172; number_of_response:1; }","duration":"169.537113ms","start":"2026-08-12T01:33:57.919799Z","end":"2026-08-12T01:33:58.089336Z","steps":["trace[1430799513] 'process raft request' (duration: 169.493902ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:58.089525Z","caller":"traceutil/trace.go:171","msg":"trace[752179064] transaction","detail":"{read_only:false; response_revision:25171; number_of_response:1; }","duration":"228.06845ms","start":"2026-08-12T01:33:57.861449Z","end":"2026-08-12T01:33:58.089518Z","steps":["trace[752179064] 'process raft request' (duration: 227.722021ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:58.090025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.756006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:33:58.090084Z","caller":"traceutil/trace.go:171","msg":"trace[952753603] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25173; }","duration":"157.821858ms","start":"2026-08-12T01:33:57.932246Z","end":"2026-08-12T01:33:58.090068Z","steps":["trace[952753603] 'agreement among raft nodes before linearized reading' (duration: 157.724656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:58.090062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.937301ms","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-08-12T01:33:58.090166Z","caller":"traceutil/trace.go:171","msg":"trace[1779140704] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25173; }","duration":"389.083155ms","start":"2026-08-12T01:33:57.701058Z","end":"2026-08-12T01:33:58.090141Z","steps":["trace[1779140704] 'agreement among raft nodes before linearized reading' (duration: 388.804598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:58.09021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:57.70104Z","time spent":"389.159138ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-12T01:33:58.090368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.504097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T01:33:58.090391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.297077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1987"} {"level":"info","ts":"2026-08-12T01:33:58.090412Z","caller":"traceutil/trace.go:171","msg":"trace[145184060] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25173; }","duration":"103.602919ms","start":"2026-08-12T01:33:57.986796Z","end":"2026-08-12T01:33:58.090399Z","steps":["trace[145184060] 'agreement among raft nodes before linearized reading' (duration: 103.533928ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:58.090433Z","caller":"traceutil/trace.go:171","msg":"trace[1066189083] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25173; }","duration":"178.335618ms","start":"2026-08-12T01:33:57.912084Z","end":"2026-08-12T01:33:58.090419Z","steps":["trace[1066189083] 'agreement among raft nodes before linearized reading' (duration: 178.222905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:58.090712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.195696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"warn","ts":"2026-08-12T01:33:58.090721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.237473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-12T01:33:58.090744Z","caller":"traceutil/trace.go:171","msg":"trace[712427551] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25173; }","duration":"327.263938ms","start":"2026-08-12T01:33:57.763472Z","end":"2026-08-12T01:33:58.090736Z","steps":["trace[712427551] 'agreement among raft nodes before linearized reading' (duration: 327.146874ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:58.090761Z","caller":"traceutil/trace.go:171","msg":"trace[1406362320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25173; }","duration":"388.284704ms","start":"2026-08-12T01:33:57.702465Z","end":"2026-08-12T01:33:58.09075Z","steps":["trace[1406362320] 'agreement among raft nodes before linearized reading' (duration: 388.166291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:58.090778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:57.763456Z","time spent":"327.312599ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-08-12T01:33:58.090795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:33:57.702461Z","time spent":"388.326565ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-12T01:33:58.371713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.07508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:33:58.371826Z","caller":"traceutil/trace.go:171","msg":"trace[1226329758] transaction","detail":"{read_only:false; response_revision:25175; number_of_response:1; }","duration":"277.177535ms","start":"2026-08-12T01:33:58.094625Z","end":"2026-08-12T01:33:58.371803Z","steps":["trace[1226329758] 'process raft request' (duration: 89.921041ms)","trace[1226329758] 'compare' (duration: 186.777321ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:33:58.373139Z","caller":"traceutil/trace.go:171","msg":"trace[1035143510] linearizableReadLoop","detail":"{readStateIndex:25997; appliedIndex:25994; }","duration":"107.394925ms","start":"2026-08-12T01:33:58.265691Z","end":"2026-08-12T01:33:58.373086Z","steps":["trace[1035143510] 'read index received' (duration: 107.133768ms)","trace[1035143510] 'applied index is now lower than readState.Index' (duration: 259.717µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:33:58.37338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.656892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:33:58.373431Z","caller":"traceutil/trace.go:171","msg":"trace[258956678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25178; }","duration":"107.772815ms","start":"2026-08-12T01:33:58.265644Z","end":"2026-08-12T01:33:58.373417Z","steps":["trace[258956678] 'agreement among raft nodes before linearized reading' (duration: 107.59057ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:58.373413Z","caller":"traceutil/trace.go:171","msg":"trace[1823465038] transaction","detail":"{read_only:false; response_revision:25176; number_of_response:1; }","duration":"277.505463ms","start":"2026-08-12T01:33:58.095878Z","end":"2026-08-12T01:33:58.373383Z","steps":["trace[1823465038] 'process raft request' (duration: 276.886706ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:58.373704Z","caller":"traceutil/trace.go:171","msg":"trace[1762523951] transaction","detail":"{read_only:false; response_revision:25178; number_of_response:1; }","duration":"275.377615ms","start":"2026-08-12T01:33:58.098309Z","end":"2026-08-12T01:33:58.373687Z","steps":["trace[1762523951] 'process raft request' (duration: 274.698766ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:58.373871Z","caller":"traceutil/trace.go:171","msg":"trace[1987129151] transaction","detail":"{read_only:false; response_revision:25177; number_of_response:1; }","duration":"276.211457ms","start":"2026-08-12T01:33:58.097638Z","end":"2026-08-12T01:33:58.373849Z","steps":["trace[1987129151] 'process raft request' (duration: 275.297292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:58.670587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.630867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:33:58.671215Z","caller":"traceutil/trace.go:171","msg":"trace[512586285] transaction","detail":"{read_only:false; response_revision:25180; number_of_response:1; }","duration":"290.901177ms","start":"2026-08-12T01:33:58.380271Z","end":"2026-08-12T01:33:58.671172Z","steps":["trace[512586285] 'process raft request' (duration: 184.581712ms)","trace[512586285] 'compare' (duration: 105.275356ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:33:59.661094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.868193ms","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-08-12T01:33:59.661249Z","caller":"traceutil/trace.go:171","msg":"trace[2061980336] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25184; }","duration":"115.065768ms","start":"2026-08-12T01:33:59.546146Z","end":"2026-08-12T01:33:59.661212Z","steps":["trace[2061980336] 'range keys from in-memory index tree' (duration: 114.653407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:33:59.661233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.346488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T01:33:59.661115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.136018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:33:59.661327Z","caller":"traceutil/trace.go:171","msg":"trace[1493438103] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:25184; }","duration":"110.515122ms","start":"2026-08-12T01:33:59.550789Z","end":"2026-08-12T01:33:59.661304Z","steps":["trace[1493438103] 'count revisions from in-memory index tree' (duration: 110.171623ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:33:59.661379Z","caller":"traceutil/trace.go:171","msg":"trace[1315944173] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25184; }","duration":"157.468717ms","start":"2026-08-12T01:33:59.503888Z","end":"2026-08-12T01:33:59.661357Z","steps":["trace[1315944173] 'count revisions from in-memory index tree' (duration: 156.985794ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:00.334895Z","caller":"traceutil/trace.go:171","msg":"trace[771054455] transaction","detail":"{read_only:false; response_revision:25185; number_of_response:1; }","duration":"246.717568ms","start":"2026-08-12T01:34:00.088139Z","end":"2026-08-12T01:34:00.334857Z","steps":["trace[771054455] 'process raft request' (duration: 246.507602ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:00.498881Z","caller":"traceutil/trace.go:171","msg":"trace[1272400368] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"408.157676ms","start":"2026-08-12T01:34:00.090691Z","end":"2026-08-12T01:34:00.498849Z","steps":["trace[1272400368] 'process raft request' (duration: 408.062264ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:00.498889Z","caller":"traceutil/trace.go:171","msg":"trace[1927805085] transaction","detail":"{read_only:false; response_revision:25186; number_of_response:1; }","duration":"408.231788ms","start":"2026-08-12T01:34:00.090617Z","end":"2026-08-12T01:34:00.498849Z","steps":["trace[1927805085] 'process raft request' (duration: 407.958001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:00.499072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:00.090601Z","time spent":"408.371652ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:34:00.499182Z","caller":"traceutil/trace.go:171","msg":"trace[76380611] transaction","detail":"{read_only:false; response_revision:25188; number_of_response:1; }","duration":"408.456735ms","start":"2026-08-12T01:34:00.090707Z","end":"2026-08-12T01:34:00.499164Z","steps":["trace[76380611] 'process raft request' (duration: 408.091745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:00.499276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:00.090691Z","time spent":"408.533696ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:34:00.49927Z","caller":"traceutil/trace.go:171","msg":"trace[1198939959] transaction","detail":"{read_only:false; response_revision:25189; number_of_response:1; }","duration":"408.315181ms","start":"2026-08-12T01:34:00.090927Z","end":"2026-08-12T01:34:00.499242Z","steps":["trace[1198939959] 'process raft request' (duration: 408.130986ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:00.499426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:00.090917Z","time spent":"408.426184ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:34:00.499445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:00.090667Z","time spent":"408.29687ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:34:00.499529Z","caller":"traceutil/trace.go:171","msg":"trace[517000998] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"404.403471ms","start":"2026-08-12T01:34:00.095111Z","end":"2026-08-12T01:34:00.499514Z","steps":["trace[517000998] 'process raft request' (duration: 404.083863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:00.499584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:00.095095Z","time spent":"404.457892ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:34:00.499628Z","caller":"traceutil/trace.go:171","msg":"trace[129026590] transaction","detail":"{read_only:false; response_revision:25192; number_of_response:1; }","duration":"120.831889ms","start":"2026-08-12T01:34:00.378777Z","end":"2026-08-12T01:34:00.499609Z","steps":["trace[129026590] 'process raft request' (duration: 120.779508ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:00.499684Z","caller":"traceutil/trace.go:171","msg":"trace[1980113722] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"403.423854ms","start":"2026-08-12T01:34:00.09625Z","end":"2026-08-12T01:34:00.499674Z","steps":["trace[1980113722] 'process raft request' (duration: 403.173407ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:00.499701Z","caller":"traceutil/trace.go:171","msg":"trace[1779972606] linearizableReadLoop","detail":"{readStateIndex:26009; appliedIndex:26003; }","duration":"404.082502ms","start":"2026-08-12T01:34:00.095602Z","end":"2026-08-12T01:34:00.499685Z","steps":["trace[1779972606] 'read index received' (duration: 238.952242ms)","trace[1779972606] 'applied index is now lower than readState.Index' (duration: 165.12793ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:34:00.499736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:00.096237Z","time spent":"403.472806ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:34:00.499846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.249777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-12T01:34:00.499882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.922554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-12T01:34:00.499892Z","caller":"traceutil/trace.go:171","msg":"trace[1978247518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25192; }","duration":"404.316958ms","start":"2026-08-12T01:34:00.09556Z","end":"2026-08-12T01:34:00.499877Z","steps":["trace[1978247518] 'agreement among raft nodes before linearized reading' (duration: 404.181825ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:00.499913Z","caller":"traceutil/trace.go:171","msg":"trace[1550506129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25192; }","duration":"124.012177ms","start":"2026-08-12T01:34:00.37589Z","end":"2026-08-12T01:34:00.499902Z","steps":["trace[1550506129] 'agreement among raft nodes before linearized reading' (duration: 123.871963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:00.499912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.25644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-12T01:34:00.499925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:00.095547Z","time spent":"404.37102ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-12T01:34:00.499948Z","caller":"traceutil/trace.go:171","msg":"trace[77830154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25192; }","duration":"121.351713ms","start":"2026-08-12T01:34:00.378586Z","end":"2026-08-12T01:34:00.499938Z","steps":["trace[77830154] 'agreement among raft nodes before linearized reading' (duration: 121.23552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:06.924114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.880596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:34:06.924302Z","caller":"traceutil/trace.go:171","msg":"trace[159776151] transaction","detail":"{read_only:false; response_revision:25227; number_of_response:1; }","duration":"404.848103ms","start":"2026-08-12T01:34:06.519424Z","end":"2026-08-12T01:34:06.924272Z","steps":["trace[159776151] 'process raft request' (duration: 195.621508ms)","trace[159776151] 'compare' (duration: 208.649629ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:34:06.9244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:06.519377Z","time spent":"404.975917ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:34:07.278162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.147515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:34:07.278341Z","caller":"traceutil/trace.go:171","msg":"trace[1444690417] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"757.456865ms","start":"2026-08-12T01:34:06.520857Z","end":"2026-08-12T01:34:07.278314Z","steps":["trace[1444690417] 'process raft request' (duration: 517.031962ms)","trace[1444690417] 'compare' (duration: 239.958101ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:34:07.278427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:06.520814Z","time spent":"757.575619ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:34:07.278742Z","caller":"traceutil/trace.go:171","msg":"trace[1694889563] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"753.922296ms","start":"2026-08-12T01:34:06.524793Z","end":"2026-08-12T01:34:07.278715Z","steps":["trace[1694889563] 'process raft request' (duration: 753.806073ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:07.278753Z","caller":"traceutil/trace.go:171","msg":"trace[327411682] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"756.54861ms","start":"2026-08-12T01:34:06.522145Z","end":"2026-08-12T01:34:07.278694Z","steps":["trace[327411682] 'process raft request' (duration: 756.141399ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:07.278938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:06.524777Z","time spent":"754.05136ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:34:07.279047Z","caller":"traceutil/trace.go:171","msg":"trace[924912298] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"752.270772ms","start":"2026-08-12T01:34:06.526758Z","end":"2026-08-12T01:34:07.279029Z","steps":["trace[924912298] 'process raft request' (duration: 752.135788ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:07.279048Z","caller":"traceutil/trace.go:171","msg":"trace[380054505] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"753.338331ms","start":"2026-08-12T01:34:06.525661Z","end":"2026-08-12T01:34:07.278999Z","steps":["trace[380054505] 'process raft request' (duration: 752.990682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:07.27914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:06.526721Z","time spent":"752.376665ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:34:07.27915Z","caller":"traceutil/trace.go:171","msg":"trace[486664604] linearizableReadLoop","detail":"{readStateIndex:26052; appliedIndex:26046; }","duration":"749.783762ms","start":"2026-08-12T01:34:06.529332Z","end":"2026-08-12T01:34:07.279116Z","steps":["trace[486664604] 'read index received' (duration: 185.733734ms)","trace[486664604] 'applied index is now lower than readState.Index' (duration: 564.048548ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:34:07.279252Z","caller":"traceutil/trace.go:171","msg":"trace[1414518250] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"642.786308ms","start":"2026-08-12T01:34:06.636454Z","end":"2026-08-12T01:34:07.27924Z","steps":["trace[1414518250] 'process raft request' (duration: 642.52397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:07.27926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:06.525645Z","time spent":"753.507615ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:34:07.279308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:06.636417Z","time spent":"642.8619ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:34:07.279405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:06.522128Z","time spent":"756.833348ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:34:07.279466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.121711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-12T01:34:07.279541Z","caller":"traceutil/trace.go:171","msg":"trace[825731523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25233; }","duration":"750.240964ms","start":"2026-08-12T01:34:06.529278Z","end":"2026-08-12T01:34:07.279519Z","steps":["trace[825731523] 'agreement among raft nodes before linearized reading' (duration: 750.035409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:07.279604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:06.529266Z","time spent":"750.318957ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-12T01:34:07.484854Z","caller":"traceutil/trace.go:171","msg":"trace[1511218071] transaction","detail":"{read_only:false; response_revision:25234; number_of_response:1; }","duration":"709.575794ms","start":"2026-08-12T01:34:06.775224Z","end":"2026-08-12T01:34:07.4848Z","steps":["trace[1511218071] 'process raft request' (duration: 705.956314ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:07.484943Z","caller":"traceutil/trace.go:171","msg":"trace[1210801479] transaction","detail":"{read_only:false; response_revision:25236; number_of_response:1; }","duration":"200.818011ms","start":"2026-08-12T01:34:07.284092Z","end":"2026-08-12T01:34:07.48491Z","steps":["trace[1210801479] 'process raft request' (duration: 200.699918ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:07.484946Z","caller":"traceutil/trace.go:171","msg":"trace[1791007391] transaction","detail":"{read_only:false; response_revision:25235; number_of_response:1; }","duration":"417.68233ms","start":"2026-08-12T01:34:07.067225Z","end":"2026-08-12T01:34:07.484908Z","steps":["trace[1791007391] 'process raft request' (duration: 417.404923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:07.485089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:06.775185Z","time spent":"709.77378ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:34:07.485143Z","caller":"traceutil/trace.go:171","msg":"trace[1803204692] linearizableReadLoop","detail":"{readStateIndex:26055; appliedIndex:26053; }","duration":"205.843163ms","start":"2026-08-12T01:34:07.279266Z","end":"2026-08-12T01:34:07.485109Z","steps":["trace[1803204692] 'read index received' (duration: 202.014096ms)","trace[1803204692] 'applied index is now lower than readState.Index' (duration: 3.825957ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:34:07.485302Z","caller":"traceutil/trace.go:171","msg":"trace[1212661259] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"115.82865ms","start":"2026-08-12T01:34:07.369443Z","end":"2026-08-12T01:34:07.485272Z","steps":["trace[1212661259] 'process raft request' (duration: 115.409688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:07.485297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.615477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T01:34:07.485383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.16307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T01:34:07.48542Z","caller":"traceutil/trace.go:171","msg":"trace[1469309242] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:25237; }","duration":"300.804902ms","start":"2026-08-12T01:34:07.184587Z","end":"2026-08-12T01:34:07.485392Z","steps":["trace[1469309242] 'agreement among raft nodes before linearized reading' (duration: 300.581735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:07.485456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.773174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-12T01:34:07.485484Z","caller":"traceutil/trace.go:171","msg":"trace[1537851489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25237; }","duration":"952.818796ms","start":"2026-08-12T01:34:06.532657Z","end":"2026-08-12T01:34:07.485475Z","steps":["trace[1537851489] 'agreement among raft nodes before linearized reading' (duration: 952.754694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:07.485483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.934814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T01:34:07.485489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:07.184559Z","time spent":"300.915565ms","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true "} {"level":"info","ts":"2026-08-12T01:34:07.485515Z","caller":"traceutil/trace.go:171","msg":"trace[1472367080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25237; }","duration":"219.990116ms","start":"2026-08-12T01:34:07.265518Z","end":"2026-08-12T01:34:07.485508Z","steps":["trace[1472367080] 'agreement among raft nodes before linearized reading' (duration: 219.942384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:07.485521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:06.532646Z","time spent":"952.868137ms","remote":"127.0.0.1:58944","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-08-12T01:34:07.485388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.068974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-12T01:34:07.485586Z","caller":"traceutil/trace.go:171","msg":"trace[1258970880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25237; }","duration":"953.298019ms","start":"2026-08-12T01:34:06.532271Z","end":"2026-08-12T01:34:07.485569Z","steps":["trace[1258970880] 'agreement among raft nodes before linearized reading' (duration: 952.93786ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:07.485634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:06.532264Z","time spent":"953.358682ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T01:34:07.485313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.472394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:34:07.485721Z","caller":"traceutil/trace.go:171","msg":"trace[1599975824] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25237; }","duration":"740.950076ms","start":"2026-08-12T01:34:06.744758Z","end":"2026-08-12T01:34:07.485708Z","steps":["trace[1599975824] 'agreement among raft nodes before linearized reading' (duration: 740.443512ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:07.485433Z","caller":"traceutil/trace.go:171","msg":"trace[2139959101] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25237; }","duration":"456.252762ms","start":"2026-08-12T01:34:07.029164Z","end":"2026-08-12T01:34:07.485417Z","steps":["trace[2139959101] 'agreement among raft nodes before linearized reading' (duration: 456.18056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:07.485748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:06.74473Z","time spent":"741.007288ms","remote":"127.0.0.1:44058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-08-12T01:34:07.485794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:07.02914Z","time spent":"456.640893ms","remote":"127.0.0.1:59050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-12T01:34:07.485512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.504167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-08-12T01:34:07.485883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.073759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-08-12T01:34:07.486156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:07.067195Z","time spent":"417.858676ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:34:07.48622Z","caller":"traceutil/trace.go:171","msg":"trace[1535377958] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:25237; }","duration":"260.488781ms","start":"2026-08-12T01:34:07.225714Z","end":"2026-08-12T01:34:07.486203Z","steps":["trace[1535377958] 'agreement among raft nodes before linearized reading' (duration: 259.866484ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:07.486159Z","caller":"traceutil/trace.go:171","msg":"trace[1297933375] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25237; }","duration":"953.155945ms","start":"2026-08-12T01:34:06.532985Z","end":"2026-08-12T01:34:07.486141Z","steps":["trace[1297933375] 'agreement among raft nodes before linearized reading' (duration: 952.383214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:07.486339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:06.532978Z","time spent":"953.34222ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-12T01:34:17.420338Z","caller":"traceutil/trace.go:171","msg":"trace[858372683] transaction","detail":"{read_only:false; response_revision:25360; number_of_response:1; }","duration":"143.096728ms","start":"2026-08-12T01:34:17.277205Z","end":"2026-08-12T01:34:17.420301Z","steps":["trace[858372683] 'process raft request' (duration: 141.422671ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:17.709531Z","caller":"traceutil/trace.go:171","msg":"trace[842881996] transaction","detail":"{read_only:false; response_revision:25367; number_of_response:1; }","duration":"122.128234ms","start":"2026-08-12T01:34:17.587373Z","end":"2026-08-12T01:34:17.709501Z","steps":["trace[842881996] 'process raft request' (duration: 122.068783ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:17.709645Z","caller":"traceutil/trace.go:171","msg":"trace[1925642122] transaction","detail":"{read_only:false; response_revision:25363; number_of_response:1; }","duration":"383.46482ms","start":"2026-08-12T01:34:17.326149Z","end":"2026-08-12T01:34:17.709614Z","steps":["trace[1925642122] 'process raft request' (duration: 383.0987ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:17.709976Z","caller":"traceutil/trace.go:171","msg":"trace[1012699065] transaction","detail":"{read_only:false; response_revision:25364; number_of_response:1; }","duration":"383.697396ms","start":"2026-08-12T01:34:17.32624Z","end":"2026-08-12T01:34:17.709938Z","steps":["trace[1012699065] 'process raft request' (duration: 383.066589ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:17.709975Z","caller":"traceutil/trace.go:171","msg":"trace[100871574] transaction","detail":"{read_only:false; response_revision:25365; number_of_response:1; }","duration":"383.584191ms","start":"2026-08-12T01:34:17.326352Z","end":"2026-08-12T01:34:17.709937Z","steps":["trace[100871574] 'process raft request' (duration: 382.992285ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:17.710029Z","caller":"traceutil/trace.go:171","msg":"trace[1327331805] transaction","detail":"{read_only:false; response_revision:25362; number_of_response:1; }","duration":"384.20408ms","start":"2026-08-12T01:34:17.32579Z","end":"2026-08-12T01:34:17.709994Z","steps":["trace[1327331805] 'process raft request' (duration: 383.405648ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:17.710037Z","caller":"traceutil/trace.go:171","msg":"trace[693680492] linearizableReadLoop","detail":"{readStateIndex:26189; appliedIndex:26183; }","duration":"305.710138ms","start":"2026-08-12T01:34:17.404277Z","end":"2026-08-12T01:34:17.709987Z","steps":["trace[693680492] 'read index received' (duration: 14.370809ms)","trace[693680492] 'applied index is now lower than readState.Index' (duration: 291.334749ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:34:17.710038Z","caller":"traceutil/trace.go:171","msg":"trace[917805821] transaction","detail":"{read_only:false; response_revision:25361; number_of_response:1; }","duration":"385.212118ms","start":"2026-08-12T01:34:17.324775Z","end":"2026-08-12T01:34:17.709987Z","steps":["trace[917805821] 'process raft request' (duration: 333.757528ms)","trace[917805821] 'compare' (duration: 50.339059ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:34:17.710095Z","caller":"traceutil/trace.go:171","msg":"trace[1475452183] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"183.84412ms","start":"2026-08-12T01:34:17.526196Z","end":"2026-08-12T01:34:17.71004Z","steps":["trace[1475452183] 'process raft request' (duration: 183.202803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:17.710126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:17.326229Z","time spent":"383.797219ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:34:17.710159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:17.325743Z","time spent":"384.330734ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:34:17.710235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:17.324759Z","time spent":"385.369833ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:34:17.71013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:17.32633Z","time spent":"383.711405ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:34:17.710317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.250983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"warn","ts":"2026-08-12T01:34:17.710316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.038948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cebca698-7934-4beb-a45a-54715877bc8d\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-12T01:34:17.710342Z","caller":"traceutil/trace.go:171","msg":"trace[214635318] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25367; }","duration":"288.311454ms","start":"2026-08-12T01:34:17.422025Z","end":"2026-08-12T01:34:17.710336Z","steps":["trace[214635318] 'agreement among raft nodes before linearized reading' (duration: 288.16138ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:17.710362Z","caller":"traceutil/trace.go:171","msg":"trace[1397045731] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cebca698-7934-4beb-a45a-54715877bc8d; range_end:; response_count:1; response_revision:25367; }","duration":"306.129811ms","start":"2026-08-12T01:34:17.404217Z","end":"2026-08-12T01:34:17.710347Z","steps":["trace[1397045731] 'agreement among raft nodes before linearized reading' (duration: 305.926055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:17.7104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:17.404203Z","time spent":"306.188192ms","remote":"127.0.0.1:58848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cebca698-7934-4beb-a45a-54715877bc8d\" "} {"level":"warn","ts":"2026-08-12T01:34:17.710413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.884433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-12T01:34:17.710445Z","caller":"traceutil/trace.go:171","msg":"trace[602245852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25367; }","duration":"178.956165ms","start":"2026-08-12T01:34:17.531479Z","end":"2026-08-12T01:34:17.710435Z","steps":["trace[602245852] 'agreement among raft nodes before linearized reading' (duration: 178.852552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:17.710482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.858822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-12T01:34:17.710486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.812528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-08-12T01:34:17.710526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.788709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:34:17.710525Z","caller":"traceutil/trace.go:171","msg":"trace[1205356481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25367; }","duration":"165.947534ms","start":"2026-08-12T01:34:17.544563Z","end":"2026-08-12T01:34:17.710511Z","steps":["trace[1205356481] 'agreement among raft nodes before linearized reading' (duration: 165.746639ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:17.710531Z","caller":"traceutil/trace.go:171","msg":"trace[1159318702] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25367; }","duration":"170.868679ms","start":"2026-08-12T01:34:17.539648Z","end":"2026-08-12T01:34:17.710517Z","steps":["trace[1159318702] 'agreement among raft nodes before linearized reading' (duration: 170.726155ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:17.710548Z","caller":"traceutil/trace.go:171","msg":"trace[352790001] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25367; }","duration":"192.854871ms","start":"2026-08-12T01:34:17.517686Z","end":"2026-08-12T01:34:17.71054Z","steps":["trace[352790001] 'agreement among raft nodes before linearized reading' (duration: 192.80574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:17.710569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.482703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-12T01:34:17.710607Z","caller":"traceutil/trace.go:171","msg":"trace[1676914734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25367; }","duration":"164.560794ms","start":"2026-08-12T01:34:17.546035Z","end":"2026-08-12T01:34:17.710596Z","steps":["trace[1676914734] 'agreement among raft nodes before linearized reading' (duration: 164.417281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:17.710735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.85224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-12T01:34:17.710775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:17.326141Z","time spent":"383.568372ms","remote":"127.0.0.1:58944","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-08-12T01:34:17.710839Z","caller":"traceutil/trace.go:171","msg":"trace[578900431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25367; }","duration":"139.044915ms","start":"2026-08-12T01:34:17.571769Z","end":"2026-08-12T01:34:17.710813Z","steps":["trace[578900431] 'agreement among raft nodes before linearized reading' (duration: 138.676385ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:17.96832Z","caller":"traceutil/trace.go:171","msg":"trace[844909177] transaction","detail":"{read_only:false; response_revision:25368; number_of_response:1; }","duration":"251.799649ms","start":"2026-08-12T01:34:17.716475Z","end":"2026-08-12T01:34:17.968275Z","steps":["trace[844909177] 'process raft request' (duration: 176.780454ms)","trace[844909177] 'compare' (duration: 74.843291ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:34:18.257098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.610341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:34:18.257444Z","caller":"traceutil/trace.go:171","msg":"trace[134814879] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"540.872805ms","start":"2026-08-12T01:34:17.716532Z","end":"2026-08-12T01:34:18.257404Z","steps":["trace[134814879] 'process raft request' (duration: 540.721311ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:18.257425Z","caller":"traceutil/trace.go:171","msg":"trace[1489594071] transaction","detail":"{read_only:false; response_revision:25369; number_of_response:1; }","duration":"540.877355ms","start":"2026-08-12T01:34:17.716486Z","end":"2026-08-12T01:34:18.257363Z","steps":["trace[1489594071] 'process raft request' (duration: 418.854893ms)","trace[1489594071] 'compare' (duration: 121.385324ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:34:18.257502Z","caller":"traceutil/trace.go:171","msg":"trace[1042539726] transaction","detail":"{read_only:false; response_revision:25371; number_of_response:1; }","duration":"540.33782ms","start":"2026-08-12T01:34:17.717117Z","end":"2026-08-12T01:34:18.257455Z","steps":["trace[1042539726] 'process raft request' (duration: 540.209357ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:18.257649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:17.716525Z","time spent":"541.010129ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:34:18.257686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.412696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T01:34:18.257696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:17.716464Z","time spent":"541.098751ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:34:18.257718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:17.717101Z","time spent":"540.505534ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:34:18.257743Z","caller":"traceutil/trace.go:171","msg":"trace[1576109312] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25373; }","duration":"538.481958ms","start":"2026-08-12T01:34:17.719245Z","end":"2026-08-12T01:34:18.257727Z","steps":["trace[1576109312] 'agreement among raft nodes before linearized reading' (duration: 538.342874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:18.257819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:17.719239Z","time spent":"538.5672ms","remote":"127.0.0.1:58862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-08-12T01:34:18.257843Z","caller":"traceutil/trace.go:171","msg":"trace[1808139642] transaction","detail":"{read_only:false; response_revision:25373; number_of_response:1; }","duration":"539.105606ms","start":"2026-08-12T01:34:17.718725Z","end":"2026-08-12T01:34:18.25783Z","steps":["trace[1808139642] 'process raft request' (duration: 538.715125ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:18.257548Z","caller":"traceutil/trace.go:171","msg":"trace[1056745217] linearizableReadLoop","detail":"{readStateIndex:26197; appliedIndex:26191; }","duration":"538.260462ms","start":"2026-08-12T01:34:17.71926Z","end":"2026-08-12T01:34:18.25752Z","steps":["trace[1056745217] 'read index received' (duration: 173.996917ms)","trace[1056745217] 'applied index is now lower than readState.Index' (duration: 364.262015ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:34:18.257914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:17.718715Z","time spent":"539.159297ms","remote":"127.0.0.1:58848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:34:18.257817Z","caller":"traceutil/trace.go:171","msg":"trace[413142711] transaction","detail":"{read_only:false; response_revision:25372; number_of_response:1; }","duration":"540.416702ms","start":"2026-08-12T01:34:17.717368Z","end":"2026-08-12T01:34:18.257785Z","steps":["trace[413142711] 'process raft request' (duration: 540.021712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:18.258148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.506726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:34:18.258201Z","caller":"traceutil/trace.go:171","msg":"trace[131851164] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25373; }","duration":"108.597028ms","start":"2026-08-12T01:34:18.14959Z","end":"2026-08-12T01:34:18.258187Z","steps":["trace[131851164] 'agreement among raft nodes before linearized reading' (duration: 108.517166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:18.2581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:17.717343Z","time spent":"540.646659ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:34:18.258208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.298394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-08-12T01:34:18.258261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.855501ms","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:1936"} {"level":"info","ts":"2026-08-12T01:34:18.258285Z","caller":"traceutil/trace.go:171","msg":"trace[1553166441] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25373; }","duration":"461.417587ms","start":"2026-08-12T01:34:17.796846Z","end":"2026-08-12T01:34:18.258263Z","steps":["trace[1553166441] 'agreement among raft nodes before linearized reading' (duration: 461.16728ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:18.25852Z","caller":"traceutil/trace.go:171","msg":"trace[889865593] 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:25373; }","duration":"137.951654ms","start":"2026-08-12T01:34:18.12036Z","end":"2026-08-12T01:34:18.258312Z","steps":["trace[889865593] 'agreement among raft nodes before linearized reading' (duration: 137.681496ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:18.258524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:17.796819Z","time spent":"461.506749ms","remote":"127.0.0.1:55622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3331,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-08-12T01:34:18.258552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.779043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cebca698-7934-4beb-a45a-54715877bc8d\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-08-12T01:34:18.25861Z","caller":"traceutil/trace.go:171","msg":"trace[729811292] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cebca698-7934-4beb-a45a-54715877bc8d; range_end:; response_count:1; response_revision:25373; }","duration":"408.901536ms","start":"2026-08-12T01:34:17.849693Z","end":"2026-08-12T01:34:18.258594Z","steps":["trace[729811292] 'agreement among raft nodes before linearized reading' (duration: 408.579927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:18.258651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:17.849659Z","time spent":"408.984428ms","remote":"127.0.0.1:58848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cebca698-7934-4beb-a45a-54715877bc8d\" "} {"level":"info","ts":"2026-08-12T01:34:18.397019Z","caller":"traceutil/trace.go:171","msg":"trace[1331836572] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"124.243124ms","start":"2026-08-12T01:34:18.27274Z","end":"2026-08-12T01:34:18.396983Z","steps":["trace[1331836572] 'process raft request' (duration: 122.31152ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:18.633291Z","caller":"traceutil/trace.go:171","msg":"trace[1344963558] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"228.429719ms","start":"2026-08-12T01:34:18.40485Z","end":"2026-08-12T01:34:18.63328Z","steps":["trace[1344963558] 'process raft request' (duration: 227.774371ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:18.633141Z","caller":"traceutil/trace.go:171","msg":"trace[2027517421] linearizableReadLoop","detail":"{readStateIndex:26199; appliedIndex:26198; }","duration":"179.710345ms","start":"2026-08-12T01:34:18.453411Z","end":"2026-08-12T01:34:18.633121Z","steps":["trace[2027517421] 'read index received' (duration: 179.52831ms)","trace[2027517421] 'applied index is now lower than readState.Index' (duration: 181.095µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:34:18.634055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.637061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-08-12T01:34:18.634145Z","caller":"traceutil/trace.go:171","msg":"trace[611028803] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25375; }","duration":"180.795376ms","start":"2026-08-12T01:34:18.453331Z","end":"2026-08-12T01:34:18.634126Z","steps":["trace[611028803] 'agreement among raft nodes before linearized reading' (duration: 180.566899ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:18.759847Z","caller":"traceutil/trace.go:171","msg":"trace[1572451146] linearizableReadLoop","detail":"{readStateIndex:26200; appliedIndex:26199; }","duration":"121.131537ms","start":"2026-08-12T01:34:18.638682Z","end":"2026-08-12T01:34:18.759813Z","steps":["trace[1572451146] 'read index received' (duration: 113.594177ms)","trace[1572451146] 'applied index is now lower than readState.Index' (duration: 7.53433ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:34:18.759849Z","caller":"traceutil/trace.go:171","msg":"trace[2097371134] transaction","detail":"{read_only:false; response_revision:25377; number_of_response:1; }","duration":"112.448326ms","start":"2026-08-12T01:34:18.647366Z","end":"2026-08-12T01:34:18.759814Z","steps":["trace[2097371134] 'process raft request' (duration: 112.377984ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:18.759848Z","caller":"traceutil/trace.go:171","msg":"trace[1008334254] transaction","detail":"{read_only:false; response_revision:25376; number_of_response:1; }","duration":"290.491624ms","start":"2026-08-12T01:34:18.469316Z","end":"2026-08-12T01:34:18.759808Z","steps":["trace[1008334254] 'process raft request' (duration: 282.934385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:18.760139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.440736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18caeae15e6b0cd8\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-08-12T01:34:18.760194Z","caller":"traceutil/trace.go:171","msg":"trace[1937755662] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18caeae15e6b0cd8; range_end:; response_count:1; response_revision:25377; }","duration":"121.540879ms","start":"2026-08-12T01:34:18.638634Z","end":"2026-08-12T01:34:18.760175Z","steps":["trace[1937755662] 'agreement among raft nodes before linearized reading' (duration: 121.298512ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:20.897857Z","caller":"traceutil/trace.go:171","msg":"trace[1386096556] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"131.464815ms","start":"2026-08-12T01:34:20.766367Z","end":"2026-08-12T01:34:20.897832Z","steps":["trace[1386096556] 'process raft request' (duration: 131.261519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:25.400044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.625001ms","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-08-12T01:34:25.40013Z","caller":"traceutil/trace.go:171","msg":"trace[1556623067] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25446; }","duration":"144.780275ms","start":"2026-08-12T01:34:25.255329Z","end":"2026-08-12T01:34:25.400109Z","steps":["trace[1556623067] 'range keys from in-memory index tree' (duration: 144.291301ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:25.889808Z","caller":"traceutil/trace.go:171","msg":"trace[1329804075] transaction","detail":"{read_only:false; response_revision:25448; number_of_response:1; }","duration":"145.911886ms","start":"2026-08-12T01:34:25.743871Z","end":"2026-08-12T01:34:25.889782Z","steps":["trace[1329804075] 'process raft request' (duration: 145.621368ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:25.890291Z","caller":"traceutil/trace.go:171","msg":"trace[1862894082] transaction","detail":"{read_only:false; response_revision:25450; number_of_response:1; }","duration":"144.071706ms","start":"2026-08-12T01:34:25.746189Z","end":"2026-08-12T01:34:25.890261Z","steps":["trace[1862894082] 'process raft request' (duration: 143.940432ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:25.890291Z","caller":"traceutil/trace.go:171","msg":"trace[681767977] transaction","detail":"{read_only:false; response_revision:25449; number_of_response:1; }","duration":"144.212809ms","start":"2026-08-12T01:34:25.746022Z","end":"2026-08-12T01:34:25.890235Z","steps":["trace[681767977] 'process raft request' (duration: 143.688814ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:26.024549Z","caller":"traceutil/trace.go:171","msg":"trace[1673046809] transaction","detail":"{read_only:false; response_revision:25451; number_of_response:1; }","duration":"275.790646ms","start":"2026-08-12T01:34:25.74872Z","end":"2026-08-12T01:34:26.02451Z","steps":["trace[1673046809] 'process raft request' (duration: 244.872636ms)","trace[1673046809] 'compare' (duration: 30.623482ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:34:26.024727Z","caller":"traceutil/trace.go:171","msg":"trace[1896383808] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"275.683054ms","start":"2026-08-12T01:34:25.749008Z","end":"2026-08-12T01:34:26.024691Z","steps":["trace[1896383808] 'process raft request' (duration: 275.425716ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:26.699814Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066ae.wal"} {"level":"info","ts":"2026-08-12T01:34:30.233636Z","caller":"traceutil/trace.go:171","msg":"trace[1342926315] transaction","detail":"{read_only:false; response_revision:25489; number_of_response:1; }","duration":"160.150092ms","start":"2026-08-12T01:34:30.073446Z","end":"2026-08-12T01:34:30.233596Z","steps":["trace[1342926315] 'process raft request' (duration: 64.300388ms)","trace[1342926315] 'compare' (duration: 95.70611ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:34:30.655645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.308845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:34:30.65598Z","caller":"traceutil/trace.go:171","msg":"trace[621663836] linearizableReadLoop","detail":"{readStateIndex:26319; appliedIndex:26315; }","duration":"271.61119ms","start":"2026-08-12T01:34:30.384347Z","end":"2026-08-12T01:34:30.655958Z","steps":["trace[621663836] 'read index received' (duration: 93.119339ms)","trace[621663836] 'applied index is now lower than readState.Index' (duration: 178.490431ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:34:30.65596Z","caller":"traceutil/trace.go:171","msg":"trace[1495859416] transaction","detail":"{read_only:false; response_revision:25490; number_of_response:1; }","duration":"335.053044ms","start":"2026-08-12T01:34:30.320843Z","end":"2026-08-12T01:34:30.655896Z","steps":["trace[1495859416] 'process raft request' (duration: 33.334887ms)","trace[1495859416] 'compare' (duration: 301.11468ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:34:30.656186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:30.320826Z","time spent":"335.26307ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:34:30.656265Z","caller":"traceutil/trace.go:171","msg":"trace[1115940802] transaction","detail":"{read_only:false; response_revision:25491; number_of_response:1; }","duration":"335.27235ms","start":"2026-08-12T01:34:30.320979Z","end":"2026-08-12T01:34:30.656252Z","steps":["trace[1115940802] 'process raft request' (duration: 334.787856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:30.656344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:30.32097Z","time spent":"335.334932ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:34:30.656691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.00958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-08-12T01:34:30.656828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.473414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T01:34:30.656891Z","caller":"traceutil/trace.go:171","msg":"trace[905073664] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:25493; }","duration":"272.573046ms","start":"2026-08-12T01:34:30.38429Z","end":"2026-08-12T01:34:30.656863Z","steps":["trace[905073664] 'agreement among raft nodes before linearized reading' (duration: 272.479544ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:30.657046Z","caller":"traceutil/trace.go:171","msg":"trace[775170269] transaction","detail":"{read_only:false; response_revision:25492; number_of_response:1; }","duration":"335.026292ms","start":"2026-08-12T01:34:30.322009Z","end":"2026-08-12T01:34:30.657036Z","steps":["trace[775170269] 'process raft request' (duration: 333.86333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:30.657113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:30.321981Z","time spent":"335.098734ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:34:30.65722Z","caller":"traceutil/trace.go:171","msg":"trace[1144879354] transaction","detail":"{read_only:false; response_revision:25493; number_of_response:1; }","duration":"334.054346ms","start":"2026-08-12T01:34:30.323156Z","end":"2026-08-12T01:34:30.65721Z","steps":["trace[1144879354] 'process raft request' (duration: 332.76299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:30.657273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:34:30.323145Z","time spent":"334.100647ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:34:30.658231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.866138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T01:34:30.658323Z","caller":"traceutil/trace.go:171","msg":"trace[84541024] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25493; }","duration":"113.019862ms","start":"2026-08-12T01:34:30.54528Z","end":"2026-08-12T01:34:30.6583Z","steps":["trace[84541024] 'agreement among raft nodes before linearized reading' (duration: 112.839747ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:30.659478Z","caller":"traceutil/trace.go:171","msg":"trace[1951984556] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25493; }","duration":"150.126664ms","start":"2026-08-12T01:34:30.506602Z","end":"2026-08-12T01:34:30.656728Z","steps":["trace[1951984556] 'agreement among raft nodes before linearized reading' (duration: 149.948989ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:30.892457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.537954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:34:30.892549Z","caller":"traceutil/trace.go:171","msg":"trace[169641537] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25493; }","duration":"114.686948ms","start":"2026-08-12T01:34:30.777838Z","end":"2026-08-12T01:34:30.892525Z","steps":["trace[169641537] 'range keys from in-memory index tree' (duration: 114.473952ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:46.067768Z","caller":"traceutil/trace.go:171","msg":"trace[320376009] transaction","detail":"{read_only:false; response_revision:25613; number_of_response:1; }","duration":"104.550476ms","start":"2026-08-12T01:34:45.963186Z","end":"2026-08-12T01:34:46.067736Z","steps":["trace[320376009] 'process raft request' (duration: 104.375351ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:46.130641Z","caller":"traceutil/trace.go:171","msg":"trace[1531702118] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"156.591023ms","start":"2026-08-12T01:34:45.973996Z","end":"2026-08-12T01:34:46.130587Z","steps":["trace[1531702118] 'process raft request' (duration: 156.414238ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:46.130689Z","caller":"traceutil/trace.go:171","msg":"trace[1343033656] transaction","detail":"{read_only:false; response_revision:25614; number_of_response:1; }","duration":"160.503582ms","start":"2026-08-12T01:34:45.970139Z","end":"2026-08-12T01:34:46.130643Z","steps":["trace[1343033656] 'process raft request' (duration: 160.041689ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:46.916047Z","caller":"traceutil/trace.go:171","msg":"trace[893278086] linearizableReadLoop","detail":"{readStateIndex:26450; appliedIndex:26449; }","duration":"120.15647ms","start":"2026-08-12T01:34:46.795853Z","end":"2026-08-12T01:34:46.916009Z","steps":["trace[893278086] 'read index received' (duration: 25.092837ms)","trace[893278086] 'applied index is now lower than readState.Index' (duration: 95.061503ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:34:46.916573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.699814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-12T01:34:46.916748Z","caller":"traceutil/trace.go:171","msg":"trace[1167410751] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25621; }","duration":"120.810618ms","start":"2026-08-12T01:34:46.795828Z","end":"2026-08-12T01:34:46.916639Z","steps":["trace[1167410751] 'agreement among raft nodes before linearized reading' (duration: 120.584352ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:46.916928Z","caller":"traceutil/trace.go:171","msg":"trace[156680746] transaction","detail":"{read_only:false; response_revision:25621; number_of_response:1; }","duration":"123.637907ms","start":"2026-08-12T01:34:46.793276Z","end":"2026-08-12T01:34:46.916914Z","steps":["trace[156680746] 'process raft request' (duration: 48.601542ms)","trace[156680746] 'compare' (duration: 73.964385ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:34:46.917715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.758602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:276059"} {"level":"info","ts":"2026-08-12T01:34:46.917762Z","caller":"traceutil/trace.go:171","msg":"trace[844249438] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25621; }","duration":"109.822043ms","start":"2026-08-12T01:34:46.807927Z","end":"2026-08-12T01:34:46.917749Z","steps":["trace[844249438] 'agreement among raft nodes before linearized reading' (duration: 109.37252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:46.918324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.760956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-12T01:34:46.918389Z","caller":"traceutil/trace.go:171","msg":"trace[120168759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25621; }","duration":"120.86685ms","start":"2026-08-12T01:34:46.797507Z","end":"2026-08-12T01:34:46.918374Z","steps":["trace[120168759] 'agreement among raft nodes before linearized reading' (duration: 119.295685ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:47.095117Z","caller":"traceutil/trace.go:171","msg":"trace[1045261446] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"173.40535ms","start":"2026-08-12T01:34:46.92165Z","end":"2026-08-12T01:34:47.095055Z","steps":["trace[1045261446] 'process raft request' (duration: 135.116405ms)","trace[1045261446] 'compare' (duration: 38.163942ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:34:47.165558Z","caller":"traceutil/trace.go:171","msg":"trace[1007009469] linearizableReadLoop","detail":"{readStateIndex:26452; appliedIndex:26450; }","duration":"168.74427ms","start":"2026-08-12T01:34:46.996775Z","end":"2026-08-12T01:34:47.165519Z","steps":["trace[1007009469] 'read index received' (duration: 60.013678ms)","trace[1007009469] 'applied index is now lower than readState.Index' (duration: 108.728952ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:34:47.165615Z","caller":"traceutil/trace.go:171","msg":"trace[1814666699] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"240.534386ms","start":"2026-08-12T01:34:46.925038Z","end":"2026-08-12T01:34:47.165573Z","steps":["trace[1814666699] 'process raft request' (duration: 240.234588ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:47.16564Z","caller":"traceutil/trace.go:171","msg":"trace[567230225] transaction","detail":"{read_only:false; response_revision:25624; number_of_response:1; }","duration":"166.500988ms","start":"2026-08-12T01:34:46.999097Z","end":"2026-08-12T01:34:47.165598Z","steps":["trace[567230225] 'process raft request' (duration: 166.381804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:47.165922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.588601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"warn","ts":"2026-08-12T01:34:47.165962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.689316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:34:47.165989Z","caller":"traceutil/trace.go:171","msg":"trace[1425750737] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25624; }","duration":"125.695524ms","start":"2026-08-12T01:34:47.040278Z","end":"2026-08-12T01:34:47.165974Z","steps":["trace[1425750737] 'agreement among raft nodes before linearized reading' (duration: 125.525639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:47.165994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.983637ms","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:6"} {"level":"info","ts":"2026-08-12T01:34:47.166009Z","caller":"traceutil/trace.go:171","msg":"trace[421719671] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:25624; }","duration":"119.80247ms","start":"2026-08-12T01:34:47.046193Z","end":"2026-08-12T01:34:47.165995Z","steps":["trace[421719671] 'agreement among raft nodes before linearized reading' (duration: 119.683987ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:47.166046Z","caller":"traceutil/trace.go:171","msg":"trace[385024351] 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:25624; }","duration":"169.293856ms","start":"2026-08-12T01:34:46.99674Z","end":"2026-08-12T01:34:47.166034Z","steps":["trace[385024351] 'agreement among raft nodes before linearized reading' (duration: 168.970497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:47.33278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.560447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-12T01:34:47.332848Z","caller":"traceutil/trace.go:171","msg":"trace[1136454607] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:25624; }","duration":"104.764312ms","start":"2026-08-12T01:34:47.228068Z","end":"2026-08-12T01:34:47.332832Z","steps":["trace[1136454607] 'range keys from in-memory index tree' (duration: 104.319869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:47.556567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.417844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:34:47.556667Z","caller":"traceutil/trace.go:171","msg":"trace[1414099659] linearizableReadLoop","detail":"{readStateIndex:26456; appliedIndex:26455; }","duration":"143.777307ms","start":"2026-08-12T01:34:47.412874Z","end":"2026-08-12T01:34:47.556651Z","steps":["trace[1414099659] 'read index received' (duration: 31.105505ms)","trace[1414099659] 'applied index is now lower than readState.Index' (duration: 112.670832ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:34:47.556839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.964262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-08-12T01:34:47.556866Z","caller":"traceutil/trace.go:171","msg":"trace[81363526] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25626; }","duration":"144.022304ms","start":"2026-08-12T01:34:47.412837Z","end":"2026-08-12T01:34:47.556859Z","steps":["trace[81363526] 'agreement among raft nodes before linearized reading' (duration: 143.858329ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:47.557056Z","caller":"traceutil/trace.go:171","msg":"trace[2007783033] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"191.897395ms","start":"2026-08-12T01:34:47.365146Z","end":"2026-08-12T01:34:47.557044Z","steps":["trace[2007783033] 'process raft request' (duration: 78.877924ms)","trace[2007783033] 'compare' (duration: 111.94156ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:34:47.829614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.394576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:34:47.829705Z","caller":"traceutil/trace.go:171","msg":"trace[2124452251] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25626; }","duration":"138.54643ms","start":"2026-08-12T01:34:47.691137Z","end":"2026-08-12T01:34:47.829684Z","steps":["trace[2124452251] 'count revisions from in-memory index tree' (duration: 138.311614ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:50.984485Z","caller":"traceutil/trace.go:171","msg":"trace[1061369282] transaction","detail":"{read_only:false; response_revision:25650; number_of_response:1; }","duration":"141.429851ms","start":"2026-08-12T01:34:50.84301Z","end":"2026-08-12T01:34:50.98444Z","steps":["trace[1061369282] 'process raft request' (duration: 140.959068ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:51.17046Z","caller":"traceutil/trace.go:171","msg":"trace[762519488] transaction","detail":"{read_only:false; response_revision:25651; number_of_response:1; }","duration":"180.965581ms","start":"2026-08-12T01:34:50.989471Z","end":"2026-08-12T01:34:51.170436Z","steps":["trace[762519488] 'process raft request' (duration: 180.643472ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:51.332018Z","caller":"traceutil/trace.go:171","msg":"trace[1639030118] linearizableReadLoop","detail":"{readStateIndex:26483; appliedIndex:26481; }","duration":"153.97724ms","start":"2026-08-12T01:34:51.178014Z","end":"2026-08-12T01:34:51.331991Z","steps":["trace[1639030118] 'read index received' (duration: 72.709801ms)","trace[1639030118] 'applied index is now lower than readState.Index' (duration: 81.266359ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:34:51.332223Z","caller":"traceutil/trace.go:171","msg":"trace[807989396] transaction","detail":"{read_only:false; response_revision:25653; number_of_response:1; }","duration":"154.390351ms","start":"2026-08-12T01:34:51.177822Z","end":"2026-08-12T01:34:51.332212Z","steps":["trace[807989396] 'process raft request' (duration: 97.093459ms)","trace[807989396] 'compare' (duration: 56.85497ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:34:51.332473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.445084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-12T01:34:51.332495Z","caller":"traceutil/trace.go:171","msg":"trace[1206504969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25653; }","duration":"154.535517ms","start":"2026-08-12T01:34:51.177952Z","end":"2026-08-12T01:34:51.332488Z","steps":["trace[1206504969] 'agreement among raft nodes before linearized reading' (duration: 154.413864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:53.27786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.918621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-12T01:34:53.277989Z","caller":"traceutil/trace.go:171","msg":"trace[988051602] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25665; }","duration":"103.116866ms","start":"2026-08-12T01:34:53.174854Z","end":"2026-08-12T01:34:53.277971Z","steps":["trace[988051602] 'range keys from in-memory index tree' (duration: 102.606232ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:56.089048Z","caller":"traceutil/trace.go:171","msg":"trace[1862279485] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"140.444494ms","start":"2026-08-12T01:34:55.948574Z","end":"2026-08-12T01:34:56.089019Z","steps":["trace[1862279485] 'process raft request' (duration: 140.245608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:56.350486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.727565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:34:56.350612Z","caller":"traceutil/trace.go:171","msg":"trace[2063667188] linearizableReadLoop","detail":"{readStateIndex:26518; appliedIndex:26517; }","duration":"231.153686ms","start":"2026-08-12T01:34:56.119436Z","end":"2026-08-12T01:34:56.35059Z","steps":["trace[2063667188] 'read index received' (duration: 105.122252ms)","trace[2063667188] 'applied index is now lower than readState.Index' (duration: 126.029664ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:34:56.350752Z","caller":"traceutil/trace.go:171","msg":"trace[634735058] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"238.742076ms","start":"2026-08-12T01:34:56.11186Z","end":"2026-08-12T01:34:56.350602Z","steps":["trace[634735058] 'process raft request' (duration: 112.674782ms)","trace[634735058] 'compare' (duration: 125.56016ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:34:56.350939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.462945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T01:34:56.351057Z","caller":"traceutil/trace.go:171","msg":"trace[1284657125] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:25687; }","duration":"231.65659ms","start":"2026-08-12T01:34:56.119375Z","end":"2026-08-12T01:34:56.351031Z","steps":["trace[1284657125] 'agreement among raft nodes before linearized reading' (duration: 231.3126ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:56.386375Z","caller":"traceutil/trace.go:171","msg":"trace[223620986] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"224.995665ms","start":"2026-08-12T01:34:56.161352Z","end":"2026-08-12T01:34:56.386348Z","steps":["trace[223620986] 'process raft request' (duration: 224.742118ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:56.386405Z","caller":"traceutil/trace.go:171","msg":"trace[126038762] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"219.621986ms","start":"2026-08-12T01:34:56.166753Z","end":"2026-08-12T01:34:56.386375Z","steps":["trace[126038762] 'process raft request' (duration: 219.527623ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:56.386568Z","caller":"traceutil/trace.go:171","msg":"trace[1126815458] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"220.259043ms","start":"2026-08-12T01:34:56.166295Z","end":"2026-08-12T01:34:56.386555Z","steps":["trace[1126815458] 'process raft request' (duration: 219.940714ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:56.386742Z","caller":"traceutil/trace.go:171","msg":"trace[1047076420] transaction","detail":"{read_only:false; response_revision:25691; number_of_response:1; }","duration":"216.21788ms","start":"2026-08-12T01:34:56.17047Z","end":"2026-08-12T01:34:56.386687Z","steps":["trace[1047076420] 'process raft request' (duration: 215.85056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:34:56.386783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.800254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:34:56.386872Z","caller":"traceutil/trace.go:171","msg":"trace[436885116] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:25691; }","duration":"254.965638ms","start":"2026-08-12T01:34:56.131884Z","end":"2026-08-12T01:34:56.386849Z","steps":["trace[436885116] 'agreement among raft nodes before linearized reading' (duration: 254.774903ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:56.582884Z","caller":"traceutil/trace.go:171","msg":"trace[1571705543] linearizableReadLoop","detail":"{readStateIndex:26523; appliedIndex:26522; }","duration":"146.141052ms","start":"2026-08-12T01:34:56.436703Z","end":"2026-08-12T01:34:56.582844Z","steps":["trace[1571705543] 'read index received' (duration: 78.018988ms)","trace[1571705543] 'applied index is now lower than readState.Index' (duration: 68.120784ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:34:56.583154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.43708ms","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-08-12T01:34:56.583177Z","caller":"traceutil/trace.go:171","msg":"trace[809882781] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25692; }","duration":"146.523343ms","start":"2026-08-12T01:34:56.436646Z","end":"2026-08-12T01:34:56.583169Z","steps":["trace[809882781] 'agreement among raft nodes before linearized reading' (duration: 146.314827ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:34:56.583471Z","caller":"traceutil/trace.go:171","msg":"trace[31826282] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"157.231921ms","start":"2026-08-12T01:34:56.426228Z","end":"2026-08-12T01:34:56.58346Z","steps":["trace[31826282] 'process raft request' (duration: 88.749468ms)","trace[31826282] 'compare' (duration: 67.546487ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:35:03.674337Z","caller":"traceutil/trace.go:171","msg":"trace[1195474245] transaction","detail":"{read_only:false; response_revision:25765; number_of_response:1; }","duration":"220.842088ms","start":"2026-08-12T01:35:03.45347Z","end":"2026-08-12T01:35:03.674312Z","steps":["trace[1195474245] 'process raft request' (duration: 220.644183ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:35:03.674737Z","caller":"traceutil/trace.go:171","msg":"trace[464648302] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"217.720401ms","start":"2026-08-12T01:35:03.456755Z","end":"2026-08-12T01:35:03.674476Z","steps":["trace[464648302] 'process raft request' (duration: 217.512375ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:35:03.882846Z","caller":"traceutil/trace.go:171","msg":"trace[1162986869] linearizableReadLoop","detail":"{readStateIndex:26600; appliedIndex:26597; }","duration":"212.59109ms","start":"2026-08-12T01:35:03.67023Z","end":"2026-08-12T01:35:03.882821Z","steps":["trace[1162986869] 'read index received' (duration: 3.877998ms)","trace[1162986869] 'applied index is now lower than readState.Index' (duration: 208.711862ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:35:03.883103Z","caller":"traceutil/trace.go:171","msg":"trace[1777074637] transaction","detail":"{read_only:false; response_revision:25768; number_of_response:1; }","duration":"196.463711ms","start":"2026-08-12T01:35:03.686593Z","end":"2026-08-12T01:35:03.883057Z","steps":["trace[1777074637] 'process raft request' (duration: 196.180773ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:35:03.883569Z","caller":"traceutil/trace.go:171","msg":"trace[1758261735] transaction","detail":"{read_only:false; response_revision:25767; number_of_response:1; }","duration":"416.138548ms","start":"2026-08-12T01:35:03.467402Z","end":"2026-08-12T01:35:03.883541Z","steps":["trace[1758261735] 'process raft request' (duration: 323.243796ms)","trace[1758261735] 'compare' (duration: 91.935866ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:35:03.884647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:35:03.467382Z","time spent":"417.150786ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:35:03.883649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.577557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:35:03.885002Z","caller":"traceutil/trace.go:171","msg":"trace[1434514543] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:25768; }","duration":"214.992606ms","start":"2026-08-12T01:35:03.669987Z","end":"2026-08-12T01:35:03.884979Z","steps":["trace[1434514543] 'agreement among raft nodes before linearized reading' (duration: 213.564636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:35:04.163193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.021955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:35:04.163318Z","caller":"traceutil/trace.go:171","msg":"trace[2108868877] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:25768; }","duration":"125.20335ms","start":"2026-08-12T01:35:04.038085Z","end":"2026-08-12T01:35:04.163288Z","steps":["trace[2108868877] 'count revisions from in-memory index tree' (duration: 124.946734ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:35:04.163401Z","caller":"traceutil/trace.go:171","msg":"trace[682096629] transaction","detail":"{read_only:false; response_revision:25769; number_of_response:1; }","duration":"105.447062ms","start":"2026-08-12T01:35:04.057929Z","end":"2026-08-12T01:35:04.163376Z","steps":["trace[682096629] 'process raft request' (duration: 59.322479ms)","trace[682096629] 'compare' (duration: 45.506166ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:35:04.343018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.946236ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:6"} {"level":"info","ts":"2026-08-12T01:35:04.34313Z","caller":"traceutil/trace.go:171","msg":"trace[1406510318] linearizableReadLoop","detail":"{readStateIndex:26604; appliedIndex:26603; }","duration":"142.550893ms","start":"2026-08-12T01:35:04.200559Z","end":"2026-08-12T01:35:04.34311Z","steps":["trace[1406510318] 'read index received' (duration: 13.242639ms)","trace[1406510318] 'applied index is now lower than readState.Index' (duration: 129.306684ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:35:04.343273Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22797} {"level":"info","ts":"2026-08-12T01:35:04.343708Z","caller":"traceutil/trace.go:171","msg":"trace[159155722] compact","detail":"{revision:22797; response_revision:25770; }","duration":"178.677717ms","start":"2026-08-12T01:35:04.165001Z","end":"2026-08-12T01:35:04.343679Z","steps":["trace[159155722] 'process raft request' (duration: 48.760287ms)","trace[159155722] 'check and update compact revision' (duration: 127.844343ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:35:04.343778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.221842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-hftlq\" ","response":"range_response_count:1 size:5642"} {"level":"info","ts":"2026-08-12T01:35:04.343851Z","caller":"traceutil/trace.go:171","msg":"trace[1885193684] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-hftlq; range_end:; response_count:1; response_revision:25770; }","duration":"143.300114ms","start":"2026-08-12T01:35:04.200533Z","end":"2026-08-12T01:35:04.343833Z","steps":["trace[1885193684] 'agreement among raft nodes before linearized reading' (duration: 143.061097ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:35:04.500993Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22797,"took":"152.051206ms","hash":3201193350,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":37437440,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-08-12T01:35:04.501078Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3201193350,"revision":22797,"compact-revision":20108} {"level":"info","ts":"2026-08-12T01:40:04.351655Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25769} {"level":"info","ts":"2026-08-12T01:40:04.498973Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25769,"took":"141.990092ms","hash":3063427408,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":35446784,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-12T01:40:04.499062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3063427408,"revision":25769,"compact-revision":22797} {"level":"info","ts":"2026-08-12T01:41:01.878194Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T01:41:01.884919Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-12T01:41:01.885052Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-12T01:45:04.358671Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28643} {"level":"info","ts":"2026-08-12T01:45:04.502947Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28643,"took":"138.350891ms","hash":1619907981,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":30580736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T01:45:04.503031Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1619907981,"revision":28643,"compact-revision":25769} {"level":"info","ts":"2026-08-12T01:50:04.368048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30864} {"level":"info","ts":"2026-08-12T01:50:04.48626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30864,"took":"113.261154ms","hash":1892840240,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":31379456,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T01:50:04.486333Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1892840240,"revision":30864,"compact-revision":28643} {"level":"info","ts":"2026-08-12T01:55:04.374615Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33123} {"level":"info","ts":"2026-08-12T01:55:04.529045Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33123,"took":"150.288987ms","hash":2639807831,"current-db-size-bytes":60289024,"current-db-size":"60 MB","current-db-size-in-use-bytes":30695424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T01:55:04.52913Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2639807831,"revision":33123,"compact-revision":30864} {"level":"info","ts":"2026-08-12T02:00:04.391908Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35410} {"level":"info","ts":"2026-08-12T02:00:04.565059Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35410,"took":"169.310567ms","hash":3488400336,"current-db-size-bytes":60289024,"current-db-size":"60 MB","current-db-size-in-use-bytes":32092160,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-12T02:00:04.565132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3488400336,"revision":35410,"compact-revision":33123} {"level":"info","ts":"2026-08-12T02:02:25.013042Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T02:02:25.017907Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-12T02:02:25.018037Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-12T02:05:04.400528Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37671} {"level":"info","ts":"2026-08-12T02:05:04.530021Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37671,"took":"125.782207ms","hash":3922562887,"current-db-size-bytes":61976576,"current-db-size":"62 MB","current-db-size-in-use-bytes":32952320,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-12T02:05:04.530119Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3922562887,"revision":37671,"compact-revision":35410}