{"level":"warn","ts":"2026-07-14T23:02:14.629862Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T23:02:14.629952Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.57: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.19.213.57:2380","--initial-cluster=instance=https://199.19.213.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.57: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-07-14T23:02:14.630016Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T23:02:14.630036Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.57:2380"]} {"level":"info","ts":"2026-07-14T23:02:14.630056Z","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-07-14T23:02:14.630673Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"]} {"level":"info","ts":"2026-07-14T23:02:14.630751Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.57:2380"],"listen-peer-urls":["https://199.19.213.57:2380"],"advertise-client-urls":["https://199.19.213.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.57: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-07-14T23:02:14.636629Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.581949ms"} {"level":"info","ts":"2026-07-14T23:02:14.647776Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ef5ef2a9544a39ef","cluster-id":"58d35553a3d286c0"} {"level":"info","ts":"2026-07-14T23:02:14.648348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T23:02:14.648389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became follower at term 0"} {"level":"info","ts":"2026-07-14T23:02:14.648406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ef5ef2a9544a39ef [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T23:02:14.648446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became follower at term 1"} {"level":"info","ts":"2026-07-14T23:02:14.648517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=(17248490431953123823)"} {"level":"warn","ts":"2026-07-14T23:02:14.654368Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T23:02:14.656468Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T23:02:14.657483Z","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-07-14T23:02:14.659896Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ef5ef2a9544a39ef","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T23:02:14.665078Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ef5ef2a9544a39ef","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T23:02:14.665685Z","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-07-14T23:02:14.665861Z","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-07-14T23:02:14.665939Z","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-07-14T23:02:14.666059Z","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-07-14T23:02:14.666086Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.57:2380"} {"level":"info","ts":"2026-07-14T23:02:14.666165Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.57:2380"} {"level":"info","ts":"2026-07-14T23:02:14.666379Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ef5ef2a9544a39ef","initial-advertise-peer-urls":["https://199.19.213.57:2380"],"listen-peer-urls":["https://199.19.213.57:2380"],"advertise-client-urls":["https://199.19.213.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T23:02:14.666408Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T23:02:14.666924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=(17248490431953123823)"} {"level":"info","ts":"2026-07-14T23:02:14.667047Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"58d35553a3d286c0","local-member-id":"ef5ef2a9544a39ef","added-peer-id":"ef5ef2a9544a39ef","added-peer-peer-urls":["https://199.19.213.57:2380"]} {"level":"info","ts":"2026-07-14T23:02:15.349658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T23:02:15.349847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T23:02:15.349907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef received MsgPreVoteResp from ef5ef2a9544a39ef at term 1"} {"level":"info","ts":"2026-07-14T23:02:15.349957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became candidate at term 2"} {"level":"info","ts":"2026-07-14T23:02:15.350009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef received MsgVoteResp from ef5ef2a9544a39ef at term 2"} {"level":"info","ts":"2026-07-14T23:02:15.350054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became leader at term 2"} {"level":"info","ts":"2026-07-14T23:02:15.350101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ef5ef2a9544a39ef elected leader ef5ef2a9544a39ef at term 2"} {"level":"info","ts":"2026-07-14T23:02:15.351317Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:02:15.352123Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ef5ef2a9544a39ef","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.57:2379]}","request-path":"/0/members/ef5ef2a9544a39ef/attributes","cluster-id":"58d35553a3d286c0","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T23:02:15.352246Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T23:02:15.352324Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T23:02:15.3525Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"58d35553a3d286c0","local-member-id":"ef5ef2a9544a39ef","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:02:15.352606Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T23:02:15.352678Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T23:02:15.352655Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:02:15.352759Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:02:15.354611Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.57:2379"} {"level":"info","ts":"2026-07-14T23:02:15.356931Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-14T23:02:29.924837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.023101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:02:29.924986Z","caller":"traceutil/trace.go:171","msg":"trace[895031844] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"746.613256ms","start":"2026-07-14T23:02:29.178354Z","end":"2026-07-14T23:02:29.924968Z","steps":["trace[895031844] 'process raft request' (duration: 222.573234ms)","trace[895031844] 'compare' (duration: 521.880498ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:02:29.925063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:29.178327Z","time spent":"746.705948ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:02:30.60839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.00406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:02:30.608552Z","caller":"traceutil/trace.go:171","msg":"trace[1223025153] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"257.945716ms","start":"2026-07-14T23:02:30.350582Z","end":"2026-07-14T23:02:30.608528Z","steps":["trace[1223025153] 'read index received' (duration: 47.201µs)","trace[1223025153] 'applied index is now lower than readState.Index' (duration: 257.896955ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:02:30.60865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.059639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:02:30.608631Z","caller":"traceutil/trace.go:171","msg":"trace[789684533] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"282.515172ms","start":"2026-07-14T23:02:30.326069Z","end":"2026-07-14T23:02:30.608584Z","steps":["trace[789684533] 'process raft request' (duration: 13.192027ms)","trace[789684533] 'compare' (duration: 268.793785ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:02:30.608704Z","caller":"traceutil/trace.go:171","msg":"trace[606907429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"258.092449ms","start":"2026-07-14T23:02:30.350577Z","end":"2026-07-14T23:02:30.608669Z","steps":["trace[606907429] 'agreement among raft nodes before linearized reading' (duration: 258.012378ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:52.368521Z","caller":"traceutil/trace.go:171","msg":"trace[1724245760] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"681.089966ms","start":"2026-07-14T23:02:51.687399Z","end":"2026-07-14T23:02:52.368489Z","steps":["trace[1724245760] 'process raft request' (duration: 680.933265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:52.368677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:51.687373Z","time spent":"681.242237ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T23:02:52.378285Z","caller":"traceutil/trace.go:171","msg":"trace[81547011] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:543; }","duration":"332.312513ms","start":"2026-07-14T23:02:52.045954Z","end":"2026-07-14T23:02:52.378266Z","steps":["trace[81547011] 'read index received' (duration: 322.297545ms)","trace[81547011] 'applied index is now lower than readState.Index' (duration: 10.014208ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:02:52.378313Z","caller":"traceutil/trace.go:171","msg":"trace[402711628] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"668.244534ms","start":"2026-07-14T23:02:51.710052Z","end":"2026-07-14T23:02:52.378297Z","steps":["trace[402711628] 'process raft request' (duration: 667.997032ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:52.378364Z","caller":"traceutil/trace.go:171","msg":"trace[1246497448] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"667.118613ms","start":"2026-07-14T23:02:51.711239Z","end":"2026-07-14T23:02:52.378357Z","steps":["trace[1246497448] 'process raft request' (duration: 666.836482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:52.378406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:51.710035Z","time spent":"668.326405ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:02:52.378448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:51.711232Z","time spent":"667.164853ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T23:02:52.378519Z","caller":"traceutil/trace.go:171","msg":"trace[688730584] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"683.003208ms","start":"2026-07-14T23:02:51.695507Z","end":"2026-07-14T23:02:52.37851Z","steps":["trace[688730584] 'process raft request' (duration: 682.326104ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:52.378529Z","caller":"traceutil/trace.go:171","msg":"trace[2099431286] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"615.215578ms","start":"2026-07-14T23:02:51.763295Z","end":"2026-07-14T23:02:52.37851Z","steps":["trace[2099431286] 'process raft request' (duration: 614.883636ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:52.378548Z","caller":"traceutil/trace.go:171","msg":"trace[541075138] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"656.119132ms","start":"2026-07-14T23:02:51.722414Z","end":"2026-07-14T23:02:52.378533Z","steps":["trace[541075138] 'process raft request' (duration: 655.69084ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:52.378585Z","caller":"traceutil/trace.go:171","msg":"trace[1484743144] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"669.569254ms","start":"2026-07-14T23:02:51.709009Z","end":"2026-07-14T23:02:52.378578Z","steps":["trace[1484743144] 'process raft request' (duration: 669.001861ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:52.378595Z","caller":"traceutil/trace.go:171","msg":"trace[1468922408] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"674.627406ms","start":"2026-07-14T23:02:51.703949Z","end":"2026-07-14T23:02:52.378576Z","steps":["trace[1468922408] 'process raft request' (duration: 674.037513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:52.378619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:51.708975Z","time spent":"669.630585ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:02:52.378608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:51.76327Z","time spent":"615.301889ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:02:52.378631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:51.722399Z","time spent":"656.204833ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T23:02:52.378601Z","caller":"traceutil/trace.go:171","msg":"trace[1865897728] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"649.591566ms","start":"2026-07-14T23:02:51.728972Z","end":"2026-07-14T23:02:52.378563Z","steps":["trace[1865897728] 'process raft request' (duration: 649.171134ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:52.378663Z","caller":"traceutil/trace.go:171","msg":"trace[1560274453] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"683.101367ms","start":"2026-07-14T23:02:51.695556Z","end":"2026-07-14T23:02:52.378658Z","steps":["trace[1560274453] 'process raft request' (duration: 682.374064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:52.378688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:51.695552Z","time spent":"683.123807ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T23:02:52.378801Z","caller":"traceutil/trace.go:171","msg":"trace[334490238] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"675.069801ms","start":"2026-07-14T23:02:51.703726Z","end":"2026-07-14T23:02:52.378796Z","steps":["trace[334490238] 'process raft request' (duration: 674.231347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:52.378838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:51.703706Z","time spent":"675.115851ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:02:52.378559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:51.69549Z","time spent":"683.050608ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:02:52.378721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:51.728909Z","time spent":"649.758777ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T23:02:52.378778Z","caller":"traceutil/trace.go:171","msg":"trace[223441211] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"499.354374ms","start":"2026-07-14T23:02:51.879417Z","end":"2026-07-14T23:02:52.378772Z","steps":["trace[223441211] 'process raft request' (duration: 498.811221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:52.378923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:51.879387Z","time spent":"499.523336ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:02:52.378638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:51.703941Z","time spent":"674.677776ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:02:52.379217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.255318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-14T23:02:52.379392Z","caller":"traceutil/trace.go:171","msg":"trace[1688383760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"333.470529ms","start":"2026-07-14T23:02:52.045913Z","end":"2026-07-14T23:02:52.379383Z","steps":["trace[1688383760] 'agreement among raft nodes before linearized reading' (duration: 332.916366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:52.379425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:52.045897Z","time spent":"333.521489ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T23:02:57.394994Z","caller":"traceutil/trace.go:171","msg":"trace[1592168518] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"149.15001ms","start":"2026-07-14T23:02:57.245785Z","end":"2026-07-14T23:02:57.394935Z","steps":["trace[1592168518] 'process raft request' (duration: 142.282931ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:57.397355Z","caller":"traceutil/trace.go:171","msg":"trace[1491616026] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"121.785818ms","start":"2026-07-14T23:02:57.275555Z","end":"2026-07-14T23:02:57.397341Z","steps":["trace[1491616026] 'process raft request' (duration: 120.081379ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:58.730649Z","caller":"traceutil/trace.go:171","msg":"trace[1114817418] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"328.691725ms","start":"2026-07-14T23:02:58.401929Z","end":"2026-07-14T23:02:58.73062Z","steps":["trace[1114817418] 'process raft request' (duration: 322.309259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:58.730786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:58.401914Z","time spent":"328.809545ms","remote":"127.0.0.1:36292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:02:58.737227Z","caller":"traceutil/trace.go:171","msg":"trace[618800623] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"303.983594ms","start":"2026-07-14T23:02:58.433181Z","end":"2026-07-14T23:02:58.737165Z","steps":["trace[618800623] 'read index received' (duration: 289.817294ms)","trace[618800623] 'applied index is now lower than readState.Index' (duration: 14.16554ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:02:58.737463Z","caller":"traceutil/trace.go:171","msg":"trace[1185683716] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"327.881361ms","start":"2026-07-14T23:02:58.409556Z","end":"2026-07-14T23:02:58.737438Z","steps":["trace[1185683716] 'process raft request' (duration: 327.489839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:58.737692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.497097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-14T23:02:58.737768Z","caller":"traceutil/trace.go:171","msg":"trace[383024988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"304.612338ms","start":"2026-07-14T23:02:58.433142Z","end":"2026-07-14T23:02:58.737754Z","steps":["trace[383024988] 'agreement among raft nodes before linearized reading' (duration: 304.308736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:58.73782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:58.433124Z","time spent":"304.687618ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T23:02:58.737998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:58.409537Z","time spent":"328.026382ms","remote":"127.0.0.1:36234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:02:59.196303Z","caller":"traceutil/trace.go:171","msg":"trace[2035096098] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"412.106568ms","start":"2026-07-14T23:02:58.784153Z","end":"2026-07-14T23:02:59.19626Z","steps":["trace[2035096098] 'process raft request' (duration: 411.775616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:59.19657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:58.784138Z","time spent":"412.295889ms","remote":"127.0.0.1:36716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:02:59.787815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174730626573499772,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:03:00.288924Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174730626573499772,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:03:00.703941Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.444460098s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-14T23:03:00.829782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.189609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:03:00.830287Z","caller":"traceutil/trace.go:171","msg":"trace[443920502] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.570891503s","start":"2026-07-14T23:02:59.259327Z","end":"2026-07-14T23:03:00.830218Z","steps":["trace[443920502] 'process raft request' (duration: 1.444854749s)","trace[443920502] 'compare' (duration: 125.029468ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:00.830489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:59.259296Z","time spent":"1.571090815s","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:03:00.830606Z","caller":"traceutil/trace.go:171","msg":"trace[65656920] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"1.543552725s","start":"2026-07-14T23:02:59.287033Z","end":"2026-07-14T23:03:00.830585Z","steps":["trace[65656920] 'read index received' (duration: 1.417197929s)","trace[65656920] 'applied index is now lower than readState.Index' (duration: 126.353626ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:00.830736Z","caller":"traceutil/trace.go:171","msg":"trace[427891781] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.193206336s","start":"2026-07-14T23:02:59.637495Z","end":"2026-07-14T23:03:00.830701Z","steps":["trace[427891781] 'process raft request' (duration: 1.192594263s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:00.830898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:59.637467Z","time spent":"1.193348647s","remote":"127.0.0.1:36292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:03:00.830944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.543913717s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-14T23:03:00.830986Z","caller":"traceutil/trace.go:171","msg":"trace[1553437444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"1.544012587s","start":"2026-07-14T23:02:59.286959Z","end":"2026-07-14T23:03:00.830971Z","steps":["trace[1553437444] 'agreement among raft nodes before linearized reading' (duration: 1.543864146s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:00.831021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:59.286928Z","time spent":"1.544084727s","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T23:03:00.831186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430526373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-14T23:03:00.831217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3872463s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-14T23:03:00.831177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.013796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:03:00.831229Z","caller":"traceutil/trace.go:171","msg":"trace[139817781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"1.430585724s","start":"2026-07-14T23:02:59.400632Z","end":"2026-07-14T23:03:00.831218Z","steps":["trace[139817781] 'agreement among raft nodes before linearized reading' (duration: 1.430455144s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:00.831267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:59.400618Z","time spent":"1.430641815s","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-14T23:03:00.83128Z","caller":"traceutil/trace.go:171","msg":"trace[1528380769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"185.170367ms","start":"2026-07-14T23:03:00.646085Z","end":"2026-07-14T23:03:00.831256Z","steps":["trace[1528380769] 'agreement among raft nodes before linearized reading' (duration: 184.969545ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:00.831281Z","caller":"traceutil/trace.go:171","msg":"trace[1775870869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:636; }","duration":"1.387321912s","start":"2026-07-14T23:02:59.443922Z","end":"2026-07-14T23:03:00.831244Z","steps":["trace[1775870869] 'agreement among raft nodes before linearized reading' (duration: 1.38712822s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:00.831323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:59.443898Z","time spent":"1.387417302s","remote":"127.0.0.1:36478","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":"warn","ts":"2026-07-14T23:03:00.831022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.486804942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:03:00.831529Z","caller":"traceutil/trace.go:171","msg":"trace[1962241786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"1.487361865s","start":"2026-07-14T23:02:59.344153Z","end":"2026-07-14T23:03:00.831515Z","steps":["trace[1962241786] 'agreement among raft nodes before linearized reading' (duration: 1.486816142s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:00.831564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:59.34413Z","time spent":"1.487424306s","remote":"127.0.0.1:36190","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-07-14T23:03:02.701479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.54662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:03:02.701595Z","caller":"traceutil/trace.go:171","msg":"trace[223771814] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:678; }","duration":"137.826991ms","start":"2026-07-14T23:03:02.56374Z","end":"2026-07-14T23:03:02.701567Z","steps":["trace[223771814] 'range keys from in-memory index tree' (duration: 137.442619ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:05.623736Z","caller":"traceutil/trace.go:171","msg":"trace[1621139505] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"135.813161ms","start":"2026-07-14T23:03:05.487899Z","end":"2026-07-14T23:03:05.623712Z","steps":["trace[1621139505] 'process raft request' (duration: 135.297528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:16.406389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.941746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-14T23:03:16.40645Z","caller":"traceutil/trace.go:171","msg":"trace[638260050] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:797; }","duration":"178.036416ms","start":"2026-07-14T23:03:16.228403Z","end":"2026-07-14T23:03:16.406439Z","steps":["trace[638260050] 'range keys from in-memory index tree' (duration: 177.751574ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:16.812625Z","caller":"traceutil/trace.go:171","msg":"trace[1117423047] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"403.018684ms","start":"2026-07-14T23:03:16.409581Z","end":"2026-07-14T23:03:16.8126Z","steps":["trace[1117423047] 'process raft request' (duration: 402.882603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:16.812773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:16.409535Z","time spent":"403.165465ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:03:16.997834Z","caller":"traceutil/trace.go:171","msg":"trace[1367244931] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"584.258066ms","start":"2026-07-14T23:03:16.413548Z","end":"2026-07-14T23:03:16.997806Z","steps":["trace[1367244931] 'process raft request' (duration: 584.057564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:16.998043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:16.413522Z","time spent":"584.394077ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:03:48.59672Z","caller":"traceutil/trace.go:171","msg":"trace[662981972] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"251.109369ms","start":"2026-07-14T23:03:48.345586Z","end":"2026-07-14T23:03:48.596696Z","steps":["trace[662981972] 'read index received' (duration: 250.950778ms)","trace[662981972] 'applied index is now lower than readState.Index' (duration: 157.221µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:48.596864Z","caller":"traceutil/trace.go:171","msg":"trace[761124426] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"463.827263ms","start":"2026-07-14T23:03:48.132986Z","end":"2026-07-14T23:03:48.596813Z","steps":["trace[761124426] 'process raft request' (duration: 463.566351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:48.59692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.311881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:03:48.596976Z","caller":"traceutil/trace.go:171","msg":"trace[1151469069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1209; }","duration":"251.422532ms","start":"2026-07-14T23:03:48.345541Z","end":"2026-07-14T23:03:48.596963Z","steps":["trace[1151469069] 'agreement among raft nodes before linearized reading' (duration: 251.315941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:48.597074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:48.132773Z","time spent":"464.190907ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:03:48.617611Z","caller":"traceutil/trace.go:171","msg":"trace[1042695740] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"243.814642ms","start":"2026-07-14T23:03:48.373763Z","end":"2026-07-14T23:03:48.617578Z","steps":["trace[1042695740] 'process raft request' (duration: 243.480739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:48.6177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.743247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-14T23:03:48.617755Z","caller":"traceutil/trace.go:171","msg":"trace[164766757] range","detail":"{range_begin:/registry/masterleases/199.19.213.57; range_end:; response_count:1; response_revision:1210; }","duration":"216.830227ms","start":"2026-07-14T23:03:48.40091Z","end":"2026-07-14T23:03:48.61774Z","steps":["trace[164766757] 'agreement among raft nodes before linearized reading' (duration: 216.616545ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:04:01.739896Z","caller":"traceutil/trace.go:171","msg":"trace[1151726387] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"115.97063ms","start":"2026-07-14T23:04:01.623903Z","end":"2026-07-14T23:04:01.739874Z","steps":["trace[1151726387] 'process raft request' (duration: 113.460702ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:04:04.870691Z","caller":"traceutil/trace.go:171","msg":"trace[947496610] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"200.480525ms","start":"2026-07-14T23:04:04.670123Z","end":"2026-07-14T23:04:04.870603Z","steps":["trace[947496610] 'process raft request' (duration: 199.976109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:04.870684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.664929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-6vd29\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-07-14T23:04:04.871242Z","caller":"traceutil/trace.go:171","msg":"trace[1924497816] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-6vd29; range_end:; response_count:1; response_revision:1348; }","duration":"278.274766ms","start":"2026-07-14T23:04:04.592933Z","end":"2026-07-14T23:04:04.871208Z","steps":["trace[1924497816] 'range keys from in-memory index tree' (duration: 277.444516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:36.846173Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174730626573502133,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T23:04:36.927002Z","caller":"traceutil/trace.go:171","msg":"trace[1029117543] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"581.681104ms","start":"2026-07-14T23:04:36.345284Z","end":"2026-07-14T23:04:36.926965Z","steps":["trace[1029117543] 'read index received' (duration: 581.352189ms)","trace[1029117543] 'applied index is now lower than readState.Index' (duration: 327.455µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:04:36.927107Z","caller":"traceutil/trace.go:171","msg":"trace[1084807696] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"693.334481ms","start":"2026-07-14T23:04:36.233729Z","end":"2026-07-14T23:04:36.927063Z","steps":["trace[1084807696] 'process raft request' (duration: 693.031507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:36.927257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.932417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:04:36.927312Z","caller":"traceutil/trace.go:171","msg":"trace[1766727717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1867; }","duration":"582.073469ms","start":"2026-07-14T23:04:36.345223Z","end":"2026-07-14T23:04:36.927297Z","steps":["trace[1766727717] 'agreement among raft nodes before linearized reading' (duration: 581.939787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:36.927355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:04:36.345197Z","time spent":"582.148029ms","remote":"127.0.0.1:36190","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-07-14T23:04:36.927362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:04:36.233697Z","time spent":"693.535664ms","remote":"127.0.0.1:36294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:04:36.927478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.287289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-07-14T23:04:36.927726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.702437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4d6bc62e-9f5d-499c-9bf9-7f01982d342d\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-14T23:04:36.928377Z","caller":"traceutil/trace.go:171","msg":"trace[1520677865] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4d6bc62e-9f5d-499c-9bf9-7f01982d342d; range_end:; response_count:1; response_revision:1867; }","duration":"368.381766ms","start":"2026-07-14T23:04:36.559968Z","end":"2026-07-14T23:04:36.928349Z","steps":["trace[1520677865] 'agreement among raft nodes before linearized reading' (duration: 367.554466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:36.928467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:04:36.559952Z","time spent":"368.496778ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4d6bc62e-9f5d-499c-9bf9-7f01982d342d\" "} {"level":"info","ts":"2026-07-14T23:04:36.927862Z","caller":"traceutil/trace.go:171","msg":"trace[969159136] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1867; }","duration":"399.638363ms","start":"2026-07-14T23:04:36.528128Z","end":"2026-07-14T23:04:36.927767Z","steps":["trace[969159136] 'agreement among raft nodes before linearized reading' (duration: 399.124706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:36.928668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:04:36.528114Z","time spent":"400.530574ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-14T23:04:36.927688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.596044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"warn","ts":"2026-07-14T23:04:36.927539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.988847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4d6bc62e-9f5d-499c-9bf9-7f01982d342d\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-14T23:04:36.929698Z","caller":"traceutil/trace.go:171","msg":"trace[2070240413] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4d6bc62e-9f5d-499c-9bf9-7f01982d342d; range_end:; response_count:1; response_revision:1867; }","duration":"142.168635ms","start":"2026-07-14T23:04:36.787473Z","end":"2026-07-14T23:04:36.929642Z","steps":["trace[2070240413] 'agreement among raft nodes before linearized reading' (duration: 139.839425ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:04:36.928865Z","caller":"traceutil/trace.go:171","msg":"trace[1793645792] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1867; }","duration":"111.756528ms","start":"2026-07-14T23:04:36.817056Z","end":"2026-07-14T23:04:36.928813Z","steps":["trace[1793645792] 'agreement among raft nodes before linearized reading' (duration: 110.524492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:37.45373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.073897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:04:37.453839Z","caller":"traceutil/trace.go:171","msg":"trace[66371499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1867; }","duration":"525.28063ms","start":"2026-07-14T23:04:36.928534Z","end":"2026-07-14T23:04:37.453814Z","steps":["trace[66371499] 'range keys from in-memory index tree' (duration: 524.892145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:37.45389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:04:36.928521Z","time spent":"525.358702ms","remote":"127.0.0.1:36206","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-07-14T23:04:37.454032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.721584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-07-14T23:04:37.454111Z","caller":"traceutil/trace.go:171","msg":"trace[1707245062] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1867; }","duration":"517.813355ms","start":"2026-07-14T23:04:36.936278Z","end":"2026-07-14T23:04:37.454092Z","steps":["trace[1707245062] 'range keys from in-memory index tree' (duration: 517.539992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:37.454152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:04:36.936203Z","time spent":"517.940567ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":575,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-14T23:04:37.454309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.52142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:566"} {"level":"info","ts":"2026-07-14T23:04:37.454408Z","caller":"traceutil/trace.go:171","msg":"trace[308485785] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1867; }","duration":"520.685482ms","start":"2026-07-14T23:04:36.933695Z","end":"2026-07-14T23:04:37.454381Z","steps":["trace[308485785] 'range keys from in-memory index tree' (duration: 520.027434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:37.454466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:04:36.933679Z","time spent":"520.776363ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":589,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-07-14T23:04:37.464651Z","caller":"traceutil/trace.go:171","msg":"trace[27646167] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"527.160895ms","start":"2026-07-14T23:04:36.937473Z","end":"2026-07-14T23:04:37.464634Z","steps":["trace[27646167] 'process raft request' (duration: 527.014083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:37.46476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:04:36.937457Z","time spent":"527.234395ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:04:37.474544Z","caller":"traceutil/trace.go:171","msg":"trace[1980582878] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1926; }","duration":"170.085893ms","start":"2026-07-14T23:04:37.304436Z","end":"2026-07-14T23:04:37.474522Z","steps":["trace[1980582878] 'read index received' (duration: 160.068456ms)","trace[1980582878] 'applied index is now lower than readState.Index' (duration: 10.016297ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:04:37.475006Z","caller":"traceutil/trace.go:171","msg":"trace[670602712] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"438.826172ms","start":"2026-07-14T23:04:37.036147Z","end":"2026-07-14T23:04:37.474973Z","steps":["trace[670602712] 'process raft request' (duration: 438.142573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:37.475183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:04:37.036126Z","time spent":"438.941654ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:04:37.475366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.950145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-14T23:04:37.475457Z","caller":"traceutil/trace.go:171","msg":"trace[667922953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1871; }","duration":"171.094376ms","start":"2026-07-14T23:04:37.304347Z","end":"2026-07-14T23:04:37.475441Z","steps":["trace[667922953] 'agreement among raft nodes before linearized reading' (duration: 170.504349ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:04:37.873352Z","caller":"traceutil/trace.go:171","msg":"trace[1787145972] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"393.420685ms","start":"2026-07-14T23:04:37.479902Z","end":"2026-07-14T23:04:37.873322Z","steps":["trace[1787145972] 'process raft request' (duration: 393.232063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:37.873672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:04:37.479883Z","time spent":"393.624348ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:04:37.886698Z","caller":"traceutil/trace.go:171","msg":"trace[46644556] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1930; }","duration":"390.776862ms","start":"2026-07-14T23:04:37.495911Z","end":"2026-07-14T23:04:37.886688Z","steps":["trace[46644556] 'read index received' (duration: 377.32547ms)","trace[46644556] 'applied index is now lower than readState.Index' (duration: 13.450862ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:04:37.886886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.966064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-14T23:04:37.886917Z","caller":"traceutil/trace.go:171","msg":"trace[998373714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1876; }","duration":"391.021245ms","start":"2026-07-14T23:04:37.495887Z","end":"2026-07-14T23:04:37.886909Z","steps":["trace[998373714] 'agreement among raft nodes before linearized reading' (duration: 390.889474ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:04:37.886864Z","caller":"traceutil/trace.go:171","msg":"trace[751769378] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"405.783203ms","start":"2026-07-14T23:04:37.481001Z","end":"2026-07-14T23:04:37.886785Z","steps":["trace[751769378] 'process raft request' (duration: 405.601961ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:04:37.886967Z","caller":"traceutil/trace.go:171","msg":"trace[347597515] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"405.434209ms","start":"2026-07-14T23:04:37.481526Z","end":"2026-07-14T23:04:37.88696Z","steps":["trace[347597515] 'process raft request' (duration: 405.103885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:37.887013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:04:37.481484Z","time spent":"405.503699ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:04:37.887045Z","caller":"traceutil/trace.go:171","msg":"trace[1228657377] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"405.165655ms","start":"2026-07-14T23:04:37.481859Z","end":"2026-07-14T23:04:37.887025Z","steps":["trace[1228657377] 'process raft request' (duration: 404.79974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:37.887097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:04:37.480992Z","time spent":"405.988375ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:04:37.887163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:04:37.481848Z","time spent":"405.236797ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:04:37.886939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:04:37.495877Z","time spent":"391.056416ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T23:04:37.887306Z","caller":"traceutil/trace.go:171","msg":"trace[128469670] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"406.726015ms","start":"2026-07-14T23:04:37.480525Z","end":"2026-07-14T23:04:37.887251Z","steps":["trace[128469670] 'process raft request' (duration: 406.028276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:37.887572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:04:37.480514Z","time spent":"406.914917ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:04:39.999766Z","caller":"traceutil/trace.go:171","msg":"trace[1366382340] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1955; }","duration":"109.280917ms","start":"2026-07-14T23:04:39.890462Z","end":"2026-07-14T23:04:39.999743Z","steps":["trace[1366382340] 'read index received' (duration: 109.110195ms)","trace[1366382340] 'applied index is now lower than readState.Index' (duration: 169.602µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:04:40.000063Z","caller":"traceutil/trace.go:171","msg":"trace[891958668] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"117.40248ms","start":"2026-07-14T23:04:39.882615Z","end":"2026-07-14T23:04:40.000018Z","steps":["trace[891958668] 'process raft request' (duration: 117.011966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:40.000422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.929835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:04:40.000492Z","caller":"traceutil/trace.go:171","msg":"trace[1629047741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1896; }","duration":"110.018146ms","start":"2026-07-14T23:04:39.890456Z","end":"2026-07-14T23:04:40.000474Z","steps":["trace[1629047741] 'agreement among raft nodes before linearized reading' (duration: 109.809334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:40.000722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.125235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-07-14T23:04:40.000807Z","caller":"traceutil/trace.go:171","msg":"trace[1507625360] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1896; }","duration":"109.244126ms","start":"2026-07-14T23:04:39.891545Z","end":"2026-07-14T23:04:40.000789Z","steps":["trace[1507625360] 'agreement among raft nodes before linearized reading' (duration: 109.100255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:40.001037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.187878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-14T23:04:40.001074Z","caller":"traceutil/trace.go:171","msg":"trace[1795986540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1896; }","duration":"110.476452ms","start":"2026-07-14T23:04:39.890588Z","end":"2026-07-14T23:04:40.001064Z","steps":["trace[1795986540] 'agreement among raft nodes before linearized reading' (duration: 109.56569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:40.001189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.173098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-14T23:04:40.001215Z","caller":"traceutil/trace.go:171","msg":"trace[1363914082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1896; }","duration":"110.223449ms","start":"2026-07-14T23:04:39.890985Z","end":"2026-07-14T23:04:40.001209Z","steps":["trace[1363914082] 'agreement among raft nodes before linearized reading' (duration: 110.159528ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:04:45.65945Z","caller":"traceutil/trace.go:171","msg":"trace[170464957] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"150.312226ms","start":"2026-07-14T23:04:45.509104Z","end":"2026-07-14T23:04:45.659416Z","steps":["trace[170464957] 'process raft request' (duration: 149.999652ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:05:15.134831Z","caller":"traceutil/trace.go:171","msg":"trace[2056357774] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"171.219982ms","start":"2026-07-14T23:05:14.96358Z","end":"2026-07-14T23:05:15.1348Z","steps":["trace[2056357774] 'process raft request' (duration: 171.045429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:05:15.405104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.901505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-07-14T23:05:15.405194Z","caller":"traceutil/trace.go:171","msg":"trace[330623708] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2179; }","duration":"128.047526ms","start":"2026-07-14T23:05:15.277127Z","end":"2026-07-14T23:05:15.405175Z","steps":["trace[330623708] 'range keys from in-memory index tree' (duration: 127.646041ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:09.365747Z","caller":"traceutil/trace.go:171","msg":"trace[1630551241] transaction","detail":"{read_only:false; response_revision:2650; number_of_response:1; }","duration":"121.51301ms","start":"2026-07-14T23:06:09.244206Z","end":"2026-07-14T23:06:09.365719Z","steps":["trace[1630551241] 'process raft request' (duration: 118.814462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:11.080459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174730626573504063,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T23:06:11.490541Z","caller":"traceutil/trace.go:171","msg":"trace[370836386] linearizableReadLoop","detail":"{readStateIndex:2755; appliedIndex:2754; }","duration":"910.967353ms","start":"2026-07-14T23:06:10.579546Z","end":"2026-07-14T23:06:11.490513Z","steps":["trace[370836386] 'read index received' (duration: 910.781201ms)","trace[370836386] 'applied index is now lower than readState.Index' (duration: 184.842µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:06:11.490576Z","caller":"traceutil/trace.go:171","msg":"trace[1043718387] transaction","detail":"{read_only:false; response_revision:2655; number_of_response:1; }","duration":"913.927405ms","start":"2026-07-14T23:06:10.576626Z","end":"2026-07-14T23:06:11.490554Z","steps":["trace[1043718387] 'process raft request' (duration: 913.725732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:11.490739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:10.576611Z","time spent":"914.026836ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:06:11.490811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.257077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-14T23:06:11.490858Z","caller":"traceutil/trace.go:171","msg":"trace[754342444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2655; }","duration":"911.356499ms","start":"2026-07-14T23:06:10.579487Z","end":"2026-07-14T23:06:11.490844Z","steps":["trace[754342444] 'agreement among raft nodes before linearized reading' (duration: 911.173746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:11.4909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:10.579462Z","time spent":"911.42387ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T23:06:11.490879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.838698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T23:06:11.490893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.516407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:06:11.490968Z","caller":"traceutil/trace.go:171","msg":"trace[492943117] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2655; }","duration":"688.989419ms","start":"2026-07-14T23:06:10.801957Z","end":"2026-07-14T23:06:11.490946Z","steps":["trace[492943117] 'agreement among raft nodes before linearized reading' (duration: 688.816647ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:11.490982Z","caller":"traceutil/trace.go:171","msg":"trace[1530547915] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2655; }","duration":"228.67729ms","start":"2026-07-14T23:06:11.262285Z","end":"2026-07-14T23:06:11.490962Z","steps":["trace[1530547915] 'agreement among raft nodes before linearized reading' (duration: 228.498377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:11.49097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.233904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:06:11.491018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:10.801943Z","time spent":"689.0642ms","remote":"127.0.0.1:36394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:06:11.491045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.748161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:06:11.490925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.644966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-07-14T23:06:11.491071Z","caller":"traceutil/trace.go:171","msg":"trace[794957404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2655; }","duration":"843.801942ms","start":"2026-07-14T23:06:10.64726Z","end":"2026-07-14T23:06:11.491061Z","steps":["trace[794957404] 'agreement among raft nodes before linearized reading' (duration: 843.770972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:11.491093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:10.647248Z","time spent":"843.840122ms","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T23:06:11.491091Z","caller":"traceutil/trace.go:171","msg":"trace[1781938830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2655; }","duration":"149.367276ms","start":"2026-07-14T23:06:11.341674Z","end":"2026-07-14T23:06:11.491041Z","steps":["trace[1781938830] 'agreement among raft nodes before linearized reading' (duration: 149.177643ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:11.491105Z","caller":"traceutil/trace.go:171","msg":"trace[710008667] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2655; }","duration":"419.823778ms","start":"2026-07-14T23:06:11.071262Z","end":"2026-07-14T23:06:11.491085Z","steps":["trace[710008667] 'agreement among raft nodes before linearized reading' (duration: 419.539444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:11.491198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:11.07121Z","time spent":"419.97099ms","remote":"127.0.0.1:36294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-14T23:06:11.491017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.061624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-07-14T23:06:11.49141Z","caller":"traceutil/trace.go:171","msg":"trace[1501573371] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2655; }","duration":"870.47096ms","start":"2026-07-14T23:06:10.620924Z","end":"2026-07-14T23:06:11.491395Z","steps":["trace[1501573371] 'agreement among raft nodes before linearized reading' (duration: 870.051714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:11.491439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:10.62091Z","time spent":"870.52131ms","remote":"127.0.0.1:36294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":682,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-14T23:06:11.491439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.785101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-14T23:06:11.491479Z","caller":"traceutil/trace.go:171","msg":"trace[480886242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2655; }","duration":"903.824162ms","start":"2026-07-14T23:06:10.587643Z","end":"2026-07-14T23:06:11.491467Z","steps":["trace[480886242] 'agreement among raft nodes before linearized reading' (duration: 903.079181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:11.491486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.778991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-14T23:06:11.491507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:10.587625Z","time spent":"903.876052ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T23:06:11.491549Z","caller":"traceutil/trace.go:171","msg":"trace[160746706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2655; }","duration":"903.858313ms","start":"2026-07-14T23:06:10.587675Z","end":"2026-07-14T23:06:11.491534Z","steps":["trace[160746706] 'agreement among raft nodes before linearized reading' (duration: 903.707011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:11.491594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:10.587665Z","time spent":"903.917523ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T23:06:11.491672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.175853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-14T23:06:11.491733Z","caller":"traceutil/trace.go:171","msg":"trace[1950619461] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2655; }","duration":"896.278495ms","start":"2026-07-14T23:06:10.595438Z","end":"2026-07-14T23:06:11.491716Z","steps":["trace[1950619461] 'agreement among raft nodes before linearized reading' (duration: 895.866249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:11.491762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:10.595424Z","time spent":"896.332216ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-07-14T23:06:37.868081Z","caller":"traceutil/trace.go:171","msg":"trace[1431660983] linearizableReadLoop","detail":"{readStateIndex:2947; appliedIndex:2946; }","duration":"195.936223ms","start":"2026-07-14T23:06:37.672122Z","end":"2026-07-14T23:06:37.868058Z","steps":["trace[1431660983] 'read index received' (duration: 195.845402ms)","trace[1431660983] 'applied index is now lower than readState.Index' (duration: 89.571µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:06:37.868386Z","caller":"traceutil/trace.go:171","msg":"trace[2071967075] transaction","detail":"{read_only:false; response_revision:2836; number_of_response:1; }","duration":"221.651422ms","start":"2026-07-14T23:06:37.646721Z","end":"2026-07-14T23:06:37.868372Z","steps":["trace[2071967075] 'process raft request' (duration: 221.187355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:37.868507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.349019ms","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-07-14T23:06:37.868589Z","caller":"traceutil/trace.go:171","msg":"trace[2106695406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2836; }","duration":"196.504442ms","start":"2026-07-14T23:06:37.672066Z","end":"2026-07-14T23:06:37.86857Z","steps":["trace[2106695406] 'agreement among raft nodes before linearized reading' (duration: 196.202897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:37.868743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.35644ms","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-07-14T23:06:37.868807Z","caller":"traceutil/trace.go:171","msg":"trace[1961744598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2836; }","duration":"182.494882ms","start":"2026-07-14T23:06:37.686296Z","end":"2026-07-14T23:06:37.868791Z","steps":["trace[1961744598] 'agreement among raft nodes before linearized reading' (duration: 182.289929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:37.869266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.360064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-14T23:06:37.869343Z","caller":"traceutil/trace.go:171","msg":"trace[1175183401] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2836; }","duration":"183.463865ms","start":"2026-07-14T23:06:37.685857Z","end":"2026-07-14T23:06:37.869321Z","steps":["trace[1175183401] 'agreement among raft nodes before linearized reading' (duration: 182.481391ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:38.0498Z","caller":"traceutil/trace.go:171","msg":"trace[1804334001] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"176.336644ms","start":"2026-07-14T23:06:37.873438Z","end":"2026-07-14T23:06:38.049775Z","steps":["trace[1804334001] 'process raft request' (duration: 103.236048ms)","trace[1804334001] 'compare' (duration: 72.859533ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:06:38.115959Z","caller":"traceutil/trace.go:171","msg":"trace[637045543] linearizableReadLoop","detail":"{readStateIndex:2950; appliedIndex:2947; }","duration":"240.633184ms","start":"2026-07-14T23:06:37.875299Z","end":"2026-07-14T23:06:38.115933Z","steps":["trace[637045543] 'read index received' (duration: 101.382581ms)","trace[637045543] 'applied index is now lower than readState.Index' (duration: 139.249173ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:06:38.116037Z","caller":"traceutil/trace.go:171","msg":"trace[1098354562] transaction","detail":"{read_only:false; response_revision:2839; number_of_response:1; }","duration":"241.526427ms","start":"2026-07-14T23:06:37.874487Z","end":"2026-07-14T23:06:38.116013Z","steps":["trace[1098354562] 'process raft request' (duration: 241.396745ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:38.115976Z","caller":"traceutil/trace.go:171","msg":"trace[1191854719] transaction","detail":"{read_only:false; response_revision:2838; number_of_response:1; }","duration":"241.930372ms","start":"2026-07-14T23:06:37.874026Z","end":"2026-07-14T23:06:38.115957Z","steps":["trace[1191854719] 'process raft request' (duration: 241.702709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:38.11629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.962629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1249"} {"level":"info","ts":"2026-07-14T23:06:38.116333Z","caller":"traceutil/trace.go:171","msg":"trace[1159794696] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2839; }","duration":"241.05519ms","start":"2026-07-14T23:06:37.875266Z","end":"2026-07-14T23:06:38.116321Z","steps":["trace[1159794696] 'agreement among raft nodes before linearized reading' (duration: 240.831487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:38.116371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.441325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:06:38.116388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.729877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-07-14T23:06:38.116418Z","caller":"traceutil/trace.go:171","msg":"trace[878648287] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2839; }","duration":"125.525907ms","start":"2026-07-14T23:06:37.990878Z","end":"2026-07-14T23:06:38.116404Z","steps":["trace[878648287] 'agreement among raft nodes before linearized reading' (duration: 125.434416ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:38.116476Z","caller":"traceutil/trace.go:171","msg":"trace[744363459] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2839; }","duration":"187.852869ms","start":"2026-07-14T23:06:37.928596Z","end":"2026-07-14T23:06:38.116449Z","steps":["trace[744363459] 'agreement among raft nodes before linearized reading' (duration: 187.613165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:38.116528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.650261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-07-14T23:06:38.116572Z","caller":"traceutil/trace.go:171","msg":"trace[1233396309] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2839; }","duration":"186.738043ms","start":"2026-07-14T23:06:37.929817Z","end":"2026-07-14T23:06:38.116555Z","steps":["trace[1233396309] 'agreement among raft nodes before linearized reading' (duration: 186.602991ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:38.334664Z","caller":"traceutil/trace.go:171","msg":"trace[646419532] linearizableReadLoop","detail":"{readStateIndex:2951; appliedIndex:2950; }","duration":"214.672153ms","start":"2026-07-14T23:06:38.119967Z","end":"2026-07-14T23:06:38.334639Z","steps":["trace[646419532] 'read index received' (duration: 176.260263ms)","trace[646419532] 'applied index is now lower than readState.Index' (duration: 38.41104ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:06:38.334867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.880236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"warn","ts":"2026-07-14T23:06:38.33491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.674534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:5908"} {"level":"info","ts":"2026-07-14T23:06:38.334944Z","caller":"traceutil/trace.go:171","msg":"trace[345160555] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2840; }","duration":"214.976877ms","start":"2026-07-14T23:06:38.119944Z","end":"2026-07-14T23:06:38.33492Z","steps":["trace[345160555] 'agreement among raft nodes before linearized reading' (duration: 214.785674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:38.335057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.274613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c24a8fa0a1564c\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-14T23:06:38.334953Z","caller":"traceutil/trace.go:171","msg":"trace[2047882634] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2840; }","duration":"214.756244ms","start":"2026-07-14T23:06:38.120183Z","end":"2026-07-14T23:06:38.334939Z","steps":["trace[2047882634] 'agreement among raft nodes before linearized reading' (duration: 214.598332ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:38.335108Z","caller":"traceutil/trace.go:171","msg":"trace[1031151151] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c24a8fa0a1564c; range_end:; response_count:1; response_revision:2840; }","duration":"206.367445ms","start":"2026-07-14T23:06:38.12873Z","end":"2026-07-14T23:06:38.335097Z","steps":["trace[1031151151] 'agreement among raft nodes before linearized reading' (duration: 206.245153ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:38.335074Z","caller":"traceutil/trace.go:171","msg":"trace[113632136] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"215.300202ms","start":"2026-07-14T23:06:38.119386Z","end":"2026-07-14T23:06:38.334687Z","steps":["trace[113632136] 'process raft request' (duration: 176.897202ms)","trace[113632136] 'compare' (duration: 38.231577ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:06:38.335596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.179171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-07-14T23:06:38.335666Z","caller":"traceutil/trace.go:171","msg":"trace[1401510442] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2840; }","duration":"215.267902ms","start":"2026-07-14T23:06:38.120377Z","end":"2026-07-14T23:06:38.335645Z","steps":["trace[1401510442] 'agreement among raft nodes before linearized reading' (duration: 214.719814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:38.711551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.87713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:06:38.711665Z","caller":"traceutil/trace.go:171","msg":"trace[1322303674] transaction","detail":"{read_only:false; response_revision:2841; number_of_response:1; }","duration":"373.698499ms","start":"2026-07-14T23:06:38.337945Z","end":"2026-07-14T23:06:38.711644Z","steps":["trace[1322303674] 'process raft request' (duration: 171.598047ms)","trace[1322303674] 'compare' (duration: 201.766238ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:06:38.711736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:38.337917Z","time spent":"373.78844ms","remote":"127.0.0.1:36282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:06:38.739966Z","caller":"traceutil/trace.go:171","msg":"trace[1869138707] transaction","detail":"{read_only:false; response_revision:2842; number_of_response:1; }","duration":"399.506049ms","start":"2026-07-14T23:06:38.340431Z","end":"2026-07-14T23:06:38.739937Z","steps":["trace[1869138707] 'process raft request' (duration: 399.372787ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:38.740053Z","caller":"traceutil/trace.go:171","msg":"trace[1894539721] linearizableReadLoop","detail":"{readStateIndex:2954; appliedIndex:2951; }","duration":"329.03658ms","start":"2026-07-14T23:06:38.411005Z","end":"2026-07-14T23:06:38.740042Z","steps":["trace[1894539721] 'read index received' (duration: 98.548411ms)","trace[1894539721] 'applied index is now lower than readState.Index' (duration: 230.486309ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:06:38.740104Z","caller":"traceutil/trace.go:171","msg":"trace[924123128] transaction","detail":"{read_only:false; response_revision:2843; number_of_response:1; }","duration":"398.406713ms","start":"2026-07-14T23:06:38.341678Z","end":"2026-07-14T23:06:38.740085Z","steps":["trace[924123128] 'process raft request' (duration: 398.20426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:38.740197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.169252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-14T23:06:38.740222Z","caller":"traceutil/trace.go:171","msg":"trace[510787332] range","detail":"{range_begin:/registry/masterleases/199.19.213.57; range_end:; response_count:1; response_revision:2843; }","duration":"329.255224ms","start":"2026-07-14T23:06:38.410957Z","end":"2026-07-14T23:06:38.740212Z","steps":["trace[510787332] 'agreement among raft nodes before linearized reading' (duration: 329.133102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:38.740247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:38.410946Z","time spent":"329.295424ms","remote":"127.0.0.1:36234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.57\" "} {"level":"warn","ts":"2026-07-14T23:06:38.740241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:38.341656Z","time spent":"398.500884ms","remote":"127.0.0.1:36294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:06:38.740513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:38.340416Z","time spent":"399.5906ms","remote":"127.0.0.1:36294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:06:38.742856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.798116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-14T23:06:38.742868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.437821ms","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-07-14T23:06:38.742895Z","caller":"traceutil/trace.go:171","msg":"trace[1980523260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2844; }","duration":"124.864748ms","start":"2026-07-14T23:06:38.618022Z","end":"2026-07-14T23:06:38.742887Z","steps":["trace[1980523260] 'agreement among raft nodes before linearized reading' (duration: 124.743976ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:38.742918Z","caller":"traceutil/trace.go:171","msg":"trace[1174135249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2844; }","duration":"131.541253ms","start":"2026-07-14T23:06:38.611363Z","end":"2026-07-14T23:06:38.742904Z","steps":["trace[1174135249] 'agreement among raft nodes before linearized reading' (duration: 131.384111ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:39.081036Z","caller":"traceutil/trace.go:171","msg":"trace[1100685383] linearizableReadLoop","detail":"{readStateIndex:2961; appliedIndex:2960; }","duration":"295.980526ms","start":"2026-07-14T23:06:38.785029Z","end":"2026-07-14T23:06:39.081009Z","steps":["trace[1100685383] 'read index received' (duration: 295.855624ms)","trace[1100685383] 'applied index is now lower than readState.Index' (duration: 123.702µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:06:39.081141Z","caller":"traceutil/trace.go:171","msg":"trace[1482815220] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"297.459398ms","start":"2026-07-14T23:06:38.783649Z","end":"2026-07-14T23:06:39.081109Z","steps":["trace[1482815220] 'process raft request' (duration: 297.199114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:39.081176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.118498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-14T23:06:39.08122Z","caller":"traceutil/trace.go:171","msg":"trace[1960371596] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2849; }","duration":"296.198899ms","start":"2026-07-14T23:06:38.785008Z","end":"2026-07-14T23:06:39.081207Z","steps":["trace[1960371596] 'agreement among raft nodes before linearized reading' (duration: 296.102568ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:07.00302Z","caller":"traceutil/trace.go:171","msg":"trace[2138937498] transaction","detail":"{read_only:false; response_revision:3029; number_of_response:1; }","duration":"199.464745ms","start":"2026-07-14T23:07:06.803524Z","end":"2026-07-14T23:07:07.002989Z","steps":["trace[2138937498] 'process raft request' (duration: 199.282143ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:08.426169Z","caller":"traceutil/trace.go:171","msg":"trace[186843318] transaction","detail":"{read_only:false; response_revision:3038; number_of_response:1; }","duration":"148.133686ms","start":"2026-07-14T23:07:08.277994Z","end":"2026-07-14T23:07:08.426127Z","steps":["trace[186843318] 'process raft request' (duration: 124.027659ms)","trace[186843318] 'compare' (duration: 23.926025ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:07:08.427391Z","caller":"traceutil/trace.go:171","msg":"trace[1884603147] transaction","detail":"{read_only:false; response_revision:3039; number_of_response:1; }","duration":"148.719895ms","start":"2026-07-14T23:07:08.278639Z","end":"2026-07-14T23:07:08.427359Z","steps":["trace[1884603147] 'process raft request' (duration: 147.66676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:07:08.967845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.557524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:07:08.967966Z","caller":"traceutil/trace.go:171","msg":"trace[659698797] transaction","detail":"{read_only:false; response_revision:3050; number_of_response:1; }","duration":"198.228619ms","start":"2026-07-14T23:07:08.769711Z","end":"2026-07-14T23:07:08.96794Z","steps":["trace[659698797] 'process raft request' (duration: 26.479302ms)","trace[659698797] 'compare' (duration: 171.373421ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:07:09.320104Z","caller":"traceutil/trace.go:171","msg":"trace[1742585750] linearizableReadLoop","detail":"{readStateIndex:3176; appliedIndex:3174; }","duration":"231.916485ms","start":"2026-07-14T23:07:09.088165Z","end":"2026-07-14T23:07:09.320081Z","steps":["trace[1742585750] 'read index received' (duration: 229.472669ms)","trace[1742585750] 'applied index is now lower than readState.Index' (duration: 2.442956ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:07:09.320231Z","caller":"traceutil/trace.go:171","msg":"trace[866109309] transaction","detail":"{read_only:false; response_revision:3051; number_of_response:1; }","duration":"304.743375ms","start":"2026-07-14T23:07:09.015445Z","end":"2026-07-14T23:07:09.320188Z","steps":["trace[866109309] 'process raft request' (duration: 302.267269ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:09.320227Z","caller":"traceutil/trace.go:171","msg":"trace[1215849563] transaction","detail":"{read_only:false; response_revision:3052; number_of_response:1; }","duration":"303.128012ms","start":"2026-07-14T23:07:09.017079Z","end":"2026-07-14T23:07:09.320207Z","steps":["trace[1215849563] 'process raft request' (duration: 302.926599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:07:09.320422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:07:09.015428Z","time spent":"304.894757ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:07:09.320493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.31737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-07-14T23:07:09.320549Z","caller":"traceutil/trace.go:171","msg":"trace[445235863] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3052; }","duration":"232.424532ms","start":"2026-07-14T23:07:09.088109Z","end":"2026-07-14T23:07:09.320533Z","steps":["trace[445235863] 'agreement among raft nodes before linearized reading' (duration: 232.153278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:07:09.320631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:07:09.017067Z","time spent":"303.301175ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:07:10.296773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174730626573505218,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T23:07:10.35352Z","caller":"traceutil/trace.go:171","msg":"trace[76169631] linearizableReadLoop","detail":"{readStateIndex:3181; appliedIndex:3180; }","duration":"556.994685ms","start":"2026-07-14T23:07:09.7965Z","end":"2026-07-14T23:07:10.353494Z","steps":["trace[76169631] 'read index received' (duration: 556.834582ms)","trace[76169631] 'applied index is now lower than readState.Index' (duration: 159.053µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:07:10.353667Z","caller":"traceutil/trace.go:171","msg":"trace[2062177799] transaction","detail":"{read_only:false; response_revision:3057; number_of_response:1; }","duration":"596.128459ms","start":"2026-07-14T23:07:09.7575Z","end":"2026-07-14T23:07:10.353629Z","steps":["trace[2062177799] 'process raft request' (duration: 595.821024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:07:10.353874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:07:09.757453Z","time spent":"596.314082ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:07:10.35387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.35914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-07-14T23:07:10.353942Z","caller":"traceutil/trace.go:171","msg":"trace[283168154] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3057; }","duration":"557.496292ms","start":"2026-07-14T23:07:09.796428Z","end":"2026-07-14T23:07:10.353925Z","steps":["trace[283168154] 'agreement among raft nodes before linearized reading' (duration: 557.216208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:07:10.354003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:07:09.796398Z","time spent":"557.592173ms","remote":"127.0.0.1:36388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1319,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-07-14T23:07:10.354238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.634274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-07-14T23:07:10.354291Z","caller":"traceutil/trace.go:171","msg":"trace[1714998741] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3057; }","duration":"304.761076ms","start":"2026-07-14T23:07:10.049514Z","end":"2026-07-14T23:07:10.354275Z","steps":["trace[1714998741] 'agreement among raft nodes before linearized reading' (duration: 304.150837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:07:10.354335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:07:10.04949Z","time spent":"304.830017ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":547,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-07-14T23:07:10.861401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174730626573505223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:07:11.362142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174730626573505223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:07:11.414389Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.055514776s","expected-duration":"1s"} {"level":"info","ts":"2026-07-14T23:07:11.417582Z","caller":"traceutil/trace.go:171","msg":"trace[1454431168] transaction","detail":"{read_only:false; response_revision:3058; number_of_response:1; }","duration":"1.058779103s","start":"2026-07-14T23:07:10.358774Z","end":"2026-07-14T23:07:11.417553Z","steps":["trace[1454431168] 'process raft request' (duration: 1.055935962s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:07:11.417746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:07:10.358754Z","time spent":"1.058912024s","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:07:11.41942Z","caller":"traceutil/trace.go:171","msg":"trace[1223235738] linearizableReadLoop","detail":"{readStateIndex:3184; appliedIndex:3181; }","duration":"1.058267255s","start":"2026-07-14T23:07:10.361141Z","end":"2026-07-14T23:07:11.419408Z","steps":["trace[1223235738] 'read index received' (duration: 1.053590378s)","trace[1223235738] 'applied index is now lower than readState.Index' (duration: 4.675887ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:07:11.419514Z","caller":"traceutil/trace.go:171","msg":"trace[1297340721] transaction","detail":"{read_only:false; response_revision:3060; number_of_response:1; }","duration":"953.054418ms","start":"2026-07-14T23:07:10.466452Z","end":"2026-07-14T23:07:11.419507Z","steps":["trace[1297340721] 'process raft request' (duration: 952.915106ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:11.419502Z","caller":"traceutil/trace.go:171","msg":"trace[108484362] transaction","detail":"{read_only:false; response_revision:3059; number_of_response:1; }","duration":"1.060520348s","start":"2026-07-14T23:07:10.358954Z","end":"2026-07-14T23:07:11.419475Z","steps":["trace[108484362] 'process raft request' (duration: 1.060349186s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:07:11.419563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:07:10.466423Z","time spent":"953.105898ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:07:11.419665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:07:10.358934Z","time spent":"1.06064116s","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:07:11.419778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058632001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1249"} {"level":"info","ts":"2026-07-14T23:07:11.419813Z","caller":"traceutil/trace.go:171","msg":"trace[689220678] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3060; }","duration":"1.058680201s","start":"2026-07-14T23:07:10.361122Z","end":"2026-07-14T23:07:11.419803Z","steps":["trace[689220678] 'agreement among raft nodes before linearized reading' (duration: 1.058555439s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:07:11.419833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:07:10.361112Z","time spent":"1.058715932s","remote":"127.0.0.1:36388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1272,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"warn","ts":"2026-07-14T23:07:11.420041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.745137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:07:11.420088Z","caller":"traceutil/trace.go:171","msg":"trace[204900314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3060; }","duration":"989.842578ms","start":"2026-07-14T23:07:10.430234Z","end":"2026-07-14T23:07:11.420076Z","steps":["trace[204900314] 'agreement among raft nodes before linearized reading' (duration: 989.648215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:07:11.420111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:07:10.430206Z","time spent":"989.90037ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T23:07:11.420184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.081846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:07:11.420221Z","caller":"traceutil/trace.go:171","msg":"trace[760847921] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:3060; }","duration":"541.160196ms","start":"2026-07-14T23:07:10.879038Z","end":"2026-07-14T23:07:11.420198Z","steps":["trace[760847921] 'agreement among raft nodes before linearized reading' (duration: 541.123845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:07:11.420242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:07:10.879016Z","time spent":"541.220107ms","remote":"127.0.0.1:50482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:07:11.420408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.556173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-07-14T23:07:11.420441Z","caller":"traceutil/trace.go:171","msg":"trace[86820007] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3060; }","duration":"548.636654ms","start":"2026-07-14T23:07:10.871795Z","end":"2026-07-14T23:07:11.420432Z","steps":["trace[86820007] 'agreement among raft nodes before linearized reading' (duration: 548.542923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:07:11.420463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:07:10.87177Z","time spent":"548.687565ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11519,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-14T23:07:11.420517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.850998ms","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-07-14T23:07:11.420542Z","caller":"traceutil/trace.go:171","msg":"trace[847398075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3060; }","duration":"989.89354ms","start":"2026-07-14T23:07:10.430641Z","end":"2026-07-14T23:07:11.420535Z","steps":["trace[847398075] 'agreement among raft nodes before linearized reading' (duration: 989.816869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:07:11.420616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:07:10.430617Z","time spent":"989.93855ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T23:08:40.034621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.653527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-14T23:08:40.034709Z","caller":"traceutil/trace.go:171","msg":"trace[1358486955] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3815; }","duration":"182.77651ms","start":"2026-07-14T23:08:39.851916Z","end":"2026-07-14T23:08:40.034692Z","steps":["trace[1358486955] 'range keys from in-memory index tree' (duration: 182.554477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:08:40.034952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.741815ms","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-07-14T23:08:40.034989Z","caller":"traceutil/trace.go:171","msg":"trace[369747280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3815; }","duration":"179.801436ms","start":"2026-07-14T23:08:39.855179Z","end":"2026-07-14T23:08:40.03498Z","steps":["trace[369747280] 'range keys from in-memory index tree' (duration: 179.614974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:08:40.035299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.138727ms","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-07-14T23:08:40.03535Z","caller":"traceutil/trace.go:171","msg":"trace[1746457006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3815; }","duration":"177.223829ms","start":"2026-07-14T23:08:39.858117Z","end":"2026-07-14T23:08:40.03534Z","steps":["trace[1746457006] 'range keys from in-memory index tree' (duration: 177.011726ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:04.344698Z","caller":"traceutil/trace.go:171","msg":"trace[894126005] transaction","detail":"{read_only:false; response_revision:4429; number_of_response:1; }","duration":"185.478912ms","start":"2026-07-14T23:09:04.159199Z","end":"2026-07-14T23:09:04.344677Z","steps":["trace[894126005] 'process raft request' (duration: 155.900531ms)","trace[894126005] 'compare' (duration: 29.338347ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:04.360908Z","caller":"traceutil/trace.go:171","msg":"trace[2084583959] linearizableReadLoop","detail":"{readStateIndex:4623; appliedIndex:4621; }","duration":"199.7925ms","start":"2026-07-14T23:09:04.161096Z","end":"2026-07-14T23:09:04.360888Z","steps":["trace[2084583959] 'read index received' (duration: 153.999593ms)","trace[2084583959] 'applied index is now lower than readState.Index' (duration: 45.791807ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:04.361034Z","caller":"traceutil/trace.go:171","msg":"trace[979505632] transaction","detail":"{read_only:false; response_revision:4431; number_of_response:1; }","duration":"199.781059ms","start":"2026-07-14T23:09:04.161226Z","end":"2026-07-14T23:09:04.361007Z","steps":["trace[979505632] 'process raft request' (duration: 199.618137ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:04.361048Z","caller":"traceutil/trace.go:171","msg":"trace[2075381715] transaction","detail":"{read_only:false; response_revision:4430; number_of_response:1; }","duration":"201.276302ms","start":"2026-07-14T23:09:04.159748Z","end":"2026-07-14T23:09:04.361024Z","steps":["trace[2075381715] 'process raft request' (duration: 200.982147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:04.361421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.309467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-14T23:09:04.361455Z","caller":"traceutil/trace.go:171","msg":"trace[1099121197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4431; }","duration":"200.367729ms","start":"2026-07-14T23:09:04.161079Z","end":"2026-07-14T23:09:04.361447Z","steps":["trace[1099121197] 'agreement among raft nodes before linearized reading' (duration: 200.263917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:04.361465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.093043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-14T23:09:04.3615Z","caller":"traceutil/trace.go:171","msg":"trace[381820317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4431; }","duration":"193.155993ms","start":"2026-07-14T23:09:04.168336Z","end":"2026-07-14T23:09:04.361492Z","steps":["trace[381820317] 'agreement among raft nodes before linearized reading' (duration: 193.039352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:04.361632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.342988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-07-14T23:09:04.361677Z","caller":"traceutil/trace.go:171","msg":"trace[1753287315] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4431; }","duration":"130.40102ms","start":"2026-07-14T23:09:04.23125Z","end":"2026-07-14T23:09:04.361651Z","steps":["trace[1753287315] 'agreement among raft nodes before linearized reading' (duration: 130.336899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:04.361921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.819637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-14T23:09:04.361946Z","caller":"traceutil/trace.go:171","msg":"trace[848204718] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4431; }","duration":"196.856787ms","start":"2026-07-14T23:09:04.165084Z","end":"2026-07-14T23:09:04.361941Z","steps":["trace[848204718] 'agreement among raft nodes before linearized reading' (duration: 196.791986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:04.362004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.990989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-14T23:09:04.362189Z","caller":"traceutil/trace.go:171","msg":"trace[1151031432] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4431; }","duration":"198.028134ms","start":"2026-07-14T23:09:04.164146Z","end":"2026-07-14T23:09:04.362174Z","steps":["trace[1151031432] 'agreement among raft nodes before linearized reading' (duration: 196.912298ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:04.506562Z","caller":"traceutil/trace.go:171","msg":"trace[2033020336] transaction","detail":"{read_only:false; response_revision:4434; number_of_response:1; }","duration":"131.721568ms","start":"2026-07-14T23:09:04.374814Z","end":"2026-07-14T23:09:04.506535Z","steps":["trace[2033020336] 'process raft request' (duration: 131.596466ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:04.506963Z","caller":"traceutil/trace.go:171","msg":"trace[2131986166] transaction","detail":"{read_only:false; response_revision:4433; number_of_response:1; }","duration":"132.178825ms","start":"2026-07-14T23:09:04.37477Z","end":"2026-07-14T23:09:04.506949Z","steps":["trace[2131986166] 'process raft request' (duration: 131.518066ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:04.506589Z","caller":"traceutil/trace.go:171","msg":"trace[1978664364] linearizableReadLoop","detail":"{readStateIndex:4628; appliedIndex:4625; }","duration":"128.918138ms","start":"2026-07-14T23:09:04.377652Z","end":"2026-07-14T23:09:04.506571Z","steps":["trace[1978664364] 'read index received' (duration: 42.060243ms)","trace[1978664364] 'applied index is now lower than readState.Index' (duration: 86.857225ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:09:04.507406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.730699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2360528"} {"level":"info","ts":"2026-07-14T23:09:04.507454Z","caller":"traceutil/trace.go:171","msg":"trace[29339765] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4437; }","duration":"129.80597ms","start":"2026-07-14T23:09:04.37764Z","end":"2026-07-14T23:09:04.507446Z","steps":["trace[29339765] 'agreement among raft nodes before linearized reading' (duration: 128.988309ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:04.507623Z","caller":"traceutil/trace.go:171","msg":"trace[1907821505] transaction","detail":"{read_only:false; response_revision:4435; number_of_response:1; }","duration":"130.018464ms","start":"2026-07-14T23:09:04.377592Z","end":"2026-07-14T23:09:04.50761Z","steps":["trace[1907821505] 'process raft request' (duration: 128.846457ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:04.507691Z","caller":"traceutil/trace.go:171","msg":"trace[208371909] transaction","detail":"{read_only:false; response_revision:4436; number_of_response:1; }","duration":"128.571133ms","start":"2026-07-14T23:09:04.3791Z","end":"2026-07-14T23:09:04.507671Z","steps":["trace[208371909] 'process raft request' (duration: 127.363245ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:04.507936Z","caller":"traceutil/trace.go:171","msg":"trace[1361685712] transaction","detail":"{read_only:false; response_revision:4437; number_of_response:1; }","duration":"126.803967ms","start":"2026-07-14T23:09:04.381116Z","end":"2026-07-14T23:09:04.50792Z","steps":["trace[1361685712] 'process raft request' (duration: 125.413987ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:06.722298Z","caller":"traceutil/trace.go:171","msg":"trace[958327592] transaction","detail":"{read_only:false; response_revision:4456; number_of_response:1; }","duration":"152.977938ms","start":"2026-07-14T23:09:06.569294Z","end":"2026-07-14T23:09:06.722272Z","steps":["trace[958327592] 'process raft request' (duration: 152.808416ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:06.743685Z","caller":"traceutil/trace.go:171","msg":"trace[1031668230] transaction","detail":"{read_only:false; response_revision:4459; number_of_response:1; }","duration":"159.699247ms","start":"2026-07-14T23:09:06.583968Z","end":"2026-07-14T23:09:06.743667Z","steps":["trace[1031668230] 'process raft request' (duration: 159.669356ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:06.743746Z","caller":"traceutil/trace.go:171","msg":"trace[1558903549] transaction","detail":"{read_only:false; response_revision:4457; number_of_response:1; }","duration":"161.107627ms","start":"2026-07-14T23:09:06.582613Z","end":"2026-07-14T23:09:06.74372Z","steps":["trace[1558903549] 'process raft request' (duration: 160.892153ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:06.743782Z","caller":"traceutil/trace.go:171","msg":"trace[96356216] transaction","detail":"{read_only:false; response_revision:4458; number_of_response:1; }","duration":"160.231174ms","start":"2026-07-14T23:09:06.583536Z","end":"2026-07-14T23:09:06.743767Z","steps":["trace[96356216] 'process raft request' (duration: 160.070042ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:06.854022Z","caller":"traceutil/trace.go:171","msg":"trace[1174261033] linearizableReadLoop","detail":"{readStateIndex:4654; appliedIndex:4653; }","duration":"119.130176ms","start":"2026-07-14T23:09:06.734857Z","end":"2026-07-14T23:09:06.853988Z","steps":["trace[1174261033] 'read index received' (duration: 9.030282ms)","trace[1174261033] 'applied index is now lower than readState.Index' (duration: 110.098634ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:06.854103Z","caller":"traceutil/trace.go:171","msg":"trace[1354283144] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4460; }","duration":"118.126651ms","start":"2026-07-14T23:09:06.735878Z","end":"2026-07-14T23:09:06.854004Z","steps":["trace[1354283144] 'process raft request' (duration: 117.956348ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:06.854149Z","caller":"traceutil/trace.go:171","msg":"trace[1108581641] transaction","detail":"{read_only:false; response_revision:4460; number_of_response:1; }","duration":"119.480631ms","start":"2026-07-14T23:09:06.7346Z","end":"2026-07-14T23:09:06.85408Z","steps":["trace[1108581641] 'process raft request' (duration: 52.651278ms)","trace[1108581641] 'compare' (duration: 66.357876ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:06.854186Z","caller":"traceutil/trace.go:171","msg":"trace[42577961] transaction","detail":"{read_only:false; response_revision:4461; number_of_response:1; }","duration":"117.265098ms","start":"2026-07-14T23:09:06.736893Z","end":"2026-07-14T23:09:06.854158Z","steps":["trace[42577961] 'process raft request' (duration: 116.982354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:06.854573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.689964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-l4ts7-tls\" ","response":"range_response_count:1 size:1581"} {"level":"info","ts":"2026-07-14T23:09:06.855937Z","caller":"traceutil/trace.go:171","msg":"trace[1449033296] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-l4ts7-tls; range_end:; response_count:1; response_revision:4461; }","duration":"121.085484ms","start":"2026-07-14T23:09:06.734833Z","end":"2026-07-14T23:09:06.855918Z","steps":["trace[1449033296] 'agreement among raft nodes before linearized reading' (duration: 119.366159ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:07.033647Z","caller":"traceutil/trace.go:171","msg":"trace[1902060890] transaction","detail":"{read_only:false; response_revision:4462; number_of_response:1; }","duration":"171.62877ms","start":"2026-07-14T23:09:06.861985Z","end":"2026-07-14T23:09:07.033613Z","steps":["trace[1902060890] 'process raft request' (duration: 151.06203ms)","trace[1902060890] 'compare' (duration: 20.378127ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:07.034177Z","caller":"traceutil/trace.go:171","msg":"trace[579446922] transaction","detail":"{read_only:false; response_revision:4463; number_of_response:1; }","duration":"163.025414ms","start":"2026-07-14T23:09:06.871096Z","end":"2026-07-14T23:09:07.034121Z","steps":["trace[579446922] 'process raft request' (duration: 162.652499ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:12.69995Z","caller":"traceutil/trace.go:171","msg":"trace[1253654999] transaction","detail":"{read_only:false; response_revision:4614; number_of_response:1; }","duration":"246.895947ms","start":"2026-07-14T23:09:12.453018Z","end":"2026-07-14T23:09:12.699914Z","steps":["trace[1253654999] 'process raft request' (duration: 246.663144ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:12.975533Z","caller":"traceutil/trace.go:171","msg":"trace[836709403] linearizableReadLoop","detail":"{readStateIndex:4816; appliedIndex:4814; }","duration":"517.721831ms","start":"2026-07-14T23:09:12.457674Z","end":"2026-07-14T23:09:12.975396Z","steps":["trace[836709403] 'read index received' (duration: 241.296245ms)","trace[836709403] 'applied index is now lower than readState.Index' (duration: 276.423036ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:12.975739Z","caller":"traceutil/trace.go:171","msg":"trace[1369459402] transaction","detail":"{read_only:false; response_revision:4615; number_of_response:1; }","duration":"522.001833ms","start":"2026-07-14T23:09:12.453698Z","end":"2026-07-14T23:09:12.9757Z","steps":["trace[1369459402] 'process raft request' (duration: 520.021405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:12.975925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:12.453686Z","time spent":"522.142805ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:09:12.975903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.190888ms","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-07-14T23:09:12.976007Z","caller":"traceutil/trace.go:171","msg":"trace[907692076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4615; }","duration":"518.31857ms","start":"2026-07-14T23:09:12.457661Z","end":"2026-07-14T23:09:12.97598Z","steps":["trace[907692076] 'agreement among raft nodes before linearized reading' (duration: 518.046836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:12.976084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:12.457657Z","time spent":"518.413191ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T23:09:12.976249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.466294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-14T23:09:12.97634Z","caller":"traceutil/trace.go:171","msg":"trace[537658645] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4615; }","duration":"514.644817ms","start":"2026-07-14T23:09:12.461678Z","end":"2026-07-14T23:09:12.976323Z","steps":["trace[537658645] 'agreement among raft nodes before linearized reading' (duration: 514.21019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:12.97642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:12.461658Z","time spent":"514.746598ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-07-14T23:09:12.976448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.422088ms","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-07-14T23:09:12.976502Z","caller":"traceutil/trace.go:171","msg":"trace[1061783479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4615; }","duration":"202.519509ms","start":"2026-07-14T23:09:12.773965Z","end":"2026-07-14T23:09:12.976485Z","steps":["trace[1061783479] 'agreement among raft nodes before linearized reading' (duration: 202.301146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:12.976485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.498004ms","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-07-14T23:09:12.976573Z","caller":"traceutil/trace.go:171","msg":"trace[1517064377] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4615; }","duration":"203.734457ms","start":"2026-07-14T23:09:12.772819Z","end":"2026-07-14T23:09:12.976554Z","steps":["trace[1517064377] 'agreement among raft nodes before linearized reading' (duration: 203.517884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:12.976201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.993707ms","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-07-14T23:09:12.976746Z","caller":"traceutil/trace.go:171","msg":"trace[1319716770] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4615; }","duration":"201.580646ms","start":"2026-07-14T23:09:12.77515Z","end":"2026-07-14T23:09:12.97673Z","steps":["trace[1319716770] 'agreement among raft nodes before linearized reading' (duration: 200.780434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:13.327952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.683008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:09:13.328098Z","caller":"traceutil/trace.go:171","msg":"trace[1752700242] transaction","detail":"{read_only:false; response_revision:4623; number_of_response:1; }","duration":"178.728404ms","start":"2026-07-14T23:09:13.149336Z","end":"2026-07-14T23:09:13.328064Z","steps":["trace[1752700242] 'process raft request' (duration: 44.836034ms)","trace[1752700242] 'compare' (duration: 133.578665ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:13.520131Z","caller":"traceutil/trace.go:171","msg":"trace[1319612129] linearizableReadLoop","detail":"{readStateIndex:4826; appliedIndex:4825; }","duration":"110.067024ms","start":"2026-07-14T23:09:13.410041Z","end":"2026-07-14T23:09:13.520108Z","steps":["trace[1319612129] 'read index received' (duration: 52.8294ms)","trace[1319612129] 'applied index is now lower than readState.Index' (duration: 57.236704ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:13.520213Z","caller":"traceutil/trace.go:171","msg":"trace[582239475] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"111.717928ms","start":"2026-07-14T23:09:13.408459Z","end":"2026-07-14T23:09:13.520177Z","steps":["trace[582239475] 'process raft request' (duration: 54.458884ms)","trace[582239475] 'compare' (duration: 57.112092ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:09:13.520405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.338008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-07-14T23:09:13.520454Z","caller":"traceutil/trace.go:171","msg":"trace[1163081382] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4625; }","duration":"110.434959ms","start":"2026-07-14T23:09:13.410004Z","end":"2026-07-14T23:09:13.520439Z","steps":["trace[1163081382] 'agreement among raft nodes before linearized reading' (duration: 110.195675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:15.481526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.994691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:09:15.481635Z","caller":"traceutil/trace.go:171","msg":"trace[73708987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4649; }","duration":"136.149604ms","start":"2026-07-14T23:09:15.345466Z","end":"2026-07-14T23:09:15.481616Z","steps":["trace[73708987] 'range keys from in-memory index tree' (duration: 135.932691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:15.481864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.73175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5958370d-069a-4918-a5c8-8a3d80e2a74c\" ","response":"range_response_count:1 size:3951"} {"level":"info","ts":"2026-07-14T23:09:15.481955Z","caller":"traceutil/trace.go:171","msg":"trace[1409657174] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5958370d-069a-4918-a5c8-8a3d80e2a74c; range_end:; response_count:1; response_revision:4649; }","duration":"142.895052ms","start":"2026-07-14T23:09:15.339037Z","end":"2026-07-14T23:09:15.481932Z","steps":["trace[1409657174] 'range keys from in-memory index tree' (duration: 142.545936ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:17.889644Z","caller":"traceutil/trace.go:171","msg":"trace[769445225] linearizableReadLoop","detail":"{readStateIndex:4891; appliedIndex:4890; }","duration":"378.168695ms","start":"2026-07-14T23:09:17.51145Z","end":"2026-07-14T23:09:17.889619Z","steps":["trace[769445225] 'read index received' (duration: 377.932046ms)","trace[769445225] 'applied index is now lower than readState.Index' (duration: 233.479µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:17.889854Z","caller":"traceutil/trace.go:171","msg":"trace[313239261] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"388.523596ms","start":"2026-07-14T23:09:17.501296Z","end":"2026-07-14T23:09:17.889819Z","steps":["trace[313239261] 'process raft request' (duration: 388.180968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:17.890107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.16474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-l4ts7.18c24ab5e0996a64\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-07-14T23:09:17.890165Z","caller":"traceutil/trace.go:171","msg":"trace[1406095044] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-l4ts7.18c24ab5e0996a64; range_end:; response_count:1; response_revision:4688; }","duration":"114.260789ms","start":"2026-07-14T23:09:17.77589Z","end":"2026-07-14T23:09:17.890151Z","steps":["trace[1406095044] 'agreement among raft nodes before linearized reading' (duration: 114.099225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:17.890126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:17.501285Z","time spent":"388.742084ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:09:17.88996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.493773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-07-14T23:09:17.890435Z","caller":"traceutil/trace.go:171","msg":"trace[1542798711] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4688; }","duration":"379.005865ms","start":"2026-07-14T23:09:17.511416Z","end":"2026-07-14T23:09:17.890422Z","steps":["trace[1542798711] 'agreement among raft nodes before linearized reading' (duration: 378.33919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:17.890483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:17.511405Z","time spent":"379.06168ms","remote":"127.0.0.1:36294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-14T23:09:23.216843Z","caller":"traceutil/trace.go:171","msg":"trace[635247482] linearizableReadLoop","detail":"{readStateIndex:4995; appliedIndex:4994; }","duration":"163.183509ms","start":"2026-07-14T23:09:23.053641Z","end":"2026-07-14T23:09:23.216825Z","steps":["trace[635247482] 'read index received' (duration: 163.008045ms)","trace[635247482] 'applied index is now lower than readState.Index' (duration: 174.734µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:23.217067Z","caller":"traceutil/trace.go:171","msg":"trace[1597702331] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"191.466825ms","start":"2026-07-14T23:09:23.025588Z","end":"2026-07-14T23:09:23.217054Z","steps":["trace[1597702331] 'process raft request' (duration: 191.116587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:23.217357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.440853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-14T23:09:23.217343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.388305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-14T23:09:23.217403Z","caller":"traceutil/trace.go:171","msg":"trace[347066190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4787; }","duration":"119.504098ms","start":"2026-07-14T23:09:23.097888Z","end":"2026-07-14T23:09:23.217392Z","steps":["trace[347066190] 'agreement among raft nodes before linearized reading' (duration: 119.346626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:23.21739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.287302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-14T23:09:23.217343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.671776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-14T23:09:23.217453Z","caller":"traceutil/trace.go:171","msg":"trace[751873215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4787; }","duration":"114.375469ms","start":"2026-07-14T23:09:23.103056Z","end":"2026-07-14T23:09:23.217431Z","steps":["trace[751873215] 'agreement among raft nodes before linearized reading' (duration: 114.211426ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:23.21744Z","caller":"traceutil/trace.go:171","msg":"trace[670782100] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4787; }","duration":"133.542737ms","start":"2026-07-14T23:09:23.083865Z","end":"2026-07-14T23:09:23.217408Z","steps":["trace[670782100] 'agreement among raft nodes before linearized reading' (duration: 133.306849ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:23.217654Z","caller":"traceutil/trace.go:171","msg":"trace[981065543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4787; }","duration":"163.861061ms","start":"2026-07-14T23:09:23.053622Z","end":"2026-07-14T23:09:23.217483Z","steps":["trace[981065543] 'agreement among raft nodes before linearized reading' (duration: 163.541026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:23.217579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.353958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-14T23:09:23.217986Z","caller":"traceutil/trace.go:171","msg":"trace[816138312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4787; }","duration":"131.701285ms","start":"2026-07-14T23:09:23.086155Z","end":"2026-07-14T23:09:23.217856Z","steps":["trace[816138312] 'agreement among raft nodes before linearized reading' (duration: 131.098388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:27.124583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174730626573509428,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T23:09:27.316036Z","caller":"traceutil/trace.go:171","msg":"trace[1054558567] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"737.077737ms","start":"2026-07-14T23:09:26.578904Z","end":"2026-07-14T23:09:27.315981Z","steps":["trace[1054558567] 'process raft request' (duration: 736.804167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:27.316206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.282634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-14T23:09:27.31627Z","caller":"traceutil/trace.go:171","msg":"trace[53849399] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4817; }","duration":"692.37004ms","start":"2026-07-14T23:09:26.623884Z","end":"2026-07-14T23:09:27.316254Z","steps":["trace[53849399] 'agreement among raft nodes before linearized reading' (duration: 692.167305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:27.316278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:26.578881Z","time spent":"737.26506ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10793,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:09:27.316309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:26.623871Z","time spent":"692.429535ms","remote":"127.0.0.1:36348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-07-14T23:09:27.315945Z","caller":"traceutil/trace.go:171","msg":"trace[2056082015] linearizableReadLoop","detail":"{readStateIndex:5027; appliedIndex:5026; }","duration":"692.010143ms","start":"2026-07-14T23:09:26.623904Z","end":"2026-07-14T23:09:27.315914Z","steps":["trace[2056082015] 'read index received' (duration: 691.710641ms)","trace[2056082015] 'applied index is now lower than readState.Index' (duration: 298.192µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:09:27.317071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.825534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-14T23:09:27.317116Z","caller":"traceutil/trace.go:171","msg":"trace[100384109] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4817; }","duration":"637.894989ms","start":"2026-07-14T23:09:26.67921Z","end":"2026-07-14T23:09:27.317105Z","steps":["trace[100384109] 'agreement among raft nodes before linearized reading' (duration: 637.731257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:27.317148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:26.679189Z","time spent":"637.952124ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-07-14T23:09:27.317535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.960586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:09:27.317668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.118636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:09:27.317773Z","caller":"traceutil/trace.go:171","msg":"trace[1774329691] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4817; }","duration":"569.286317ms","start":"2026-07-14T23:09:26.748466Z","end":"2026-07-14T23:09:27.317753Z","steps":["trace[1774329691] 'agreement among raft nodes before linearized reading' (duration: 569.110724ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:27.317627Z","caller":"traceutil/trace.go:171","msg":"trace[642388856] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4817; }","duration":"469.124908ms","start":"2026-07-14T23:09:26.848479Z","end":"2026-07-14T23:09:27.317604Z","steps":["trace[642388856] 'agreement among raft nodes before linearized reading' (duration: 468.993909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:27.318191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:26.848451Z","time spent":"469.719573ms","remote":"127.0.0.1:53252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:09:27.322552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:26.748456Z","time spent":"574.064813ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-14T23:09:27.540745Z","caller":"traceutil/trace.go:171","msg":"trace[543612345] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"213.752431ms","start":"2026-07-14T23:09:27.326956Z","end":"2026-07-14T23:09:27.540708Z","steps":["trace[543612345] 'process raft request' (duration: 203.126442ms)","trace[543612345] 'compare' (duration: 10.363779ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:27.557294Z","caller":"traceutil/trace.go:171","msg":"trace[124016455] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"229.960294ms","start":"2026-07-14T23:09:27.327293Z","end":"2026-07-14T23:09:27.557254Z","steps":["trace[124016455] 'process raft request' (duration: 229.726027ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:27.557322Z","caller":"traceutil/trace.go:171","msg":"trace[144637033] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"229.078029ms","start":"2026-07-14T23:09:27.328221Z","end":"2026-07-14T23:09:27.557299Z","steps":["trace[144637033] 'process raft request' (duration: 229.004633ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:27.557402Z","caller":"traceutil/trace.go:171","msg":"trace[1633037006] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"229.956934ms","start":"2026-07-14T23:09:27.327432Z","end":"2026-07-14T23:09:27.557389Z","steps":["trace[1633037006] 'process raft request' (duration: 229.735178ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:27.55779Z","caller":"traceutil/trace.go:171","msg":"trace[1596201904] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"221.237597ms","start":"2026-07-14T23:09:27.33653Z","end":"2026-07-14T23:09:27.557767Z","steps":["trace[1596201904] 'process raft request' (duration: 221.14482ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:27.557862Z","caller":"traceutil/trace.go:171","msg":"trace[847328100] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"221.632626ms","start":"2026-07-14T23:09:27.336211Z","end":"2026-07-14T23:09:27.557844Z","steps":["trace[847328100] 'process raft request' (duration: 221.41908ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:27.557866Z","caller":"traceutil/trace.go:171","msg":"trace[17706745] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"229.341378ms","start":"2026-07-14T23:09:27.328515Z","end":"2026-07-14T23:09:27.557856Z","steps":["trace[17706745] 'process raft request' (duration: 228.752934ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:27.557825Z","caller":"traceutil/trace.go:171","msg":"trace[844619976] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"228.316572ms","start":"2026-07-14T23:09:27.32949Z","end":"2026-07-14T23:09:27.557806Z","steps":["trace[844619976] 'process raft request' (duration: 227.919443ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:27.558099Z","caller":"traceutil/trace.go:171","msg":"trace[1175881311] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"220.549546ms","start":"2026-07-14T23:09:27.337536Z","end":"2026-07-14T23:09:27.558085Z","steps":["trace[1175881311] 'process raft request' (duration: 220.190089ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:27.558299Z","caller":"traceutil/trace.go:171","msg":"trace[1920118433] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"220.640533ms","start":"2026-07-14T23:09:27.337648Z","end":"2026-07-14T23:09:27.558288Z","steps":["trace[1920118433] 'process raft request' (duration: 220.342331ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:27.558445Z","caller":"traceutil/trace.go:171","msg":"trace[92593402] linearizableReadLoop","detail":"{readStateIndex:5033; appliedIndex:5027; }","duration":"222.477789ms","start":"2026-07-14T23:09:27.335956Z","end":"2026-07-14T23:09:27.558434Z","steps":["trace[92593402] 'read index received' (duration: 194.138384ms)","trace[92593402] 'applied index is now lower than readState.Index' (duration: 28.338415ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:27.558504Z","caller":"traceutil/trace.go:171","msg":"trace[1010670086] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"220.87766ms","start":"2026-07-14T23:09:27.337611Z","end":"2026-07-14T23:09:27.558489Z","steps":["trace[1010670086] 'process raft request' (duration: 220.631141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:27.558616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.63821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-07-14T23:09:27.55866Z","caller":"traceutil/trace.go:171","msg":"trace[570603446] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4828; }","duration":"222.706545ms","start":"2026-07-14T23:09:27.335939Z","end":"2026-07-14T23:09:27.558646Z","steps":["trace[570603446] 'agreement among raft nodes before linearized reading' (duration: 222.536273ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.117505Z","caller":"traceutil/trace.go:171","msg":"trace[1137684516] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"237.369712ms","start":"2026-07-14T23:09:27.880086Z","end":"2026-07-14T23:09:28.117456Z","steps":["trace[1137684516] 'process raft request' (duration: 231.414685ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.195946Z","caller":"traceutil/trace.go:171","msg":"trace[1025227164] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"313.563516ms","start":"2026-07-14T23:09:27.882322Z","end":"2026-07-14T23:09:28.195886Z","steps":["trace[1025227164] 'process raft request' (duration: 313.277915ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.196062Z","caller":"traceutil/trace.go:171","msg":"trace[1448112421] linearizableReadLoop","detail":"{readStateIndex:5045; appliedIndex:5041; }","duration":"202.381481ms","start":"2026-07-14T23:09:27.993651Z","end":"2026-07-14T23:09:28.196033Z","steps":["trace[1448112421] 'read index received' (duration: 117.995003ms)","trace[1448112421] 'applied index is now lower than readState.Index' (duration: 84.385007ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:09:28.196237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:27.882308Z","time spent":"313.769241ms","remote":"127.0.0.1:36488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1563,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:09:28.196333Z","caller":"traceutil/trace.go:171","msg":"trace[1768576853] transaction","detail":"{read_only:false; response_revision:4834; number_of_response:1; }","duration":"311.951886ms","start":"2026-07-14T23:09:27.88437Z","end":"2026-07-14T23:09:28.196322Z","steps":["trace[1768576853] 'process raft request' (duration: 311.385344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:28.196292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.608797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-07-14T23:09:28.196449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:27.884364Z","time spent":"312.002309ms","remote":"127.0.0.1:36686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:09:28.196501Z","caller":"traceutil/trace.go:171","msg":"trace[1154273041] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4835; }","duration":"202.820192ms","start":"2026-07-14T23:09:27.993644Z","end":"2026-07-14T23:09:28.196464Z","steps":["trace[1154273041] 'agreement among raft nodes before linearized reading' (duration: 202.485298ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:09:28.196609Z","caller":"traceutil/trace.go:171","msg":"trace[894929869] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"242.425973ms","start":"2026-07-14T23:09:27.954018Z","end":"2026-07-14T23:09:28.196444Z","steps":["trace[894929869] 'process raft request' (duration: 241.943717ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:01.736256Z","caller":"traceutil/trace.go:171","msg":"trace[628886303] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"108.640522ms","start":"2026-07-14T23:10:01.627579Z","end":"2026-07-14T23:10:01.736219Z","steps":["trace[628886303] 'process raft request' (duration: 108.374858ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:10.233686Z","caller":"traceutil/trace.go:171","msg":"trace[116650404] linearizableReadLoop","detail":"{readStateIndex:5481; appliedIndex:5480; }","duration":"177.535346ms","start":"2026-07-14T23:10:10.056132Z","end":"2026-07-14T23:10:10.233668Z","steps":["trace[116650404] 'read index received' (duration: 177.385599ms)","trace[116650404] 'applied index is now lower than readState.Index' (duration: 149.197µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:10:10.233885Z","caller":"traceutil/trace.go:171","msg":"trace[353923198] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"179.304031ms","start":"2026-07-14T23:10:10.054513Z","end":"2026-07-14T23:10:10.233817Z","steps":["trace[353923198] 'process raft request' (duration: 179.048339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:10.23393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.766767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:10:10.234843Z","caller":"traceutil/trace.go:171","msg":"trace[1918552071] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5253; }","duration":"178.716333ms","start":"2026-07-14T23:10:10.056099Z","end":"2026-07-14T23:10:10.234815Z","steps":["trace[1918552071] 'agreement among raft nodes before linearized reading' (duration: 177.688103ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:10.445338Z","caller":"traceutil/trace.go:171","msg":"trace[2020960122] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"102.839283ms","start":"2026-07-14T23:10:10.342472Z","end":"2026-07-14T23:10:10.445311Z","steps":["trace[2020960122] 'process raft request' (duration: 79.622904ms)","trace[2020960122] 'compare' (duration: 23.0246ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:10:10.445511Z","caller":"traceutil/trace.go:171","msg":"trace[2030058794] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"102.230174ms","start":"2026-07-14T23:10:10.343255Z","end":"2026-07-14T23:10:10.445485Z","steps":["trace[2030058794] 'process raft request' (duration: 102.017123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:10.94652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.513468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:10:10.946615Z","caller":"traceutil/trace.go:171","msg":"trace[148591157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5258; }","duration":"301.653134ms","start":"2026-07-14T23:10:10.64494Z","end":"2026-07-14T23:10:10.946594Z","steps":["trace[148591157] 'range keys from in-memory index tree' (duration: 301.34931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:10:10.946672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:10:10.644927Z","time spent":"301.735419ms","remote":"127.0.0.1:36190","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-07-14T23:10:10.94695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.621221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-07-14T23:10:10.947028Z","caller":"traceutil/trace.go:171","msg":"trace[870500809] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5258; }","duration":"206.752847ms","start":"2026-07-14T23:10:10.740251Z","end":"2026-07-14T23:10:10.947004Z","steps":["trace[870500809] 'range keys from in-memory index tree' (duration: 206.37968ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:12:15.431191Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3154} {"level":"info","ts":"2026-07-14T23:12:15.531489Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3154,"took":"98.639214ms","hash":1913354952,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":30879744,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-14T23:12:15.531585Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1913354952,"revision":3154,"compact-revision":-1} {"level":"info","ts":"2026-07-14T23:12:25.255312Z","caller":"traceutil/trace.go:171","msg":"trace[1950781332] transaction","detail":"{read_only:false; response_revision:6285; number_of_response:1; }","duration":"103.808573ms","start":"2026-07-14T23:12:25.151467Z","end":"2026-07-14T23:12:25.255275Z","steps":["trace[1950781332] 'process raft request' (duration: 88.178497ms)","trace[1950781332] 'compare' (duration: 15.363049ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:12:25.25982Z","caller":"traceutil/trace.go:171","msg":"trace[2068376186] transaction","detail":"{read_only:false; response_revision:6287; number_of_response:1; }","duration":"108.281897ms","start":"2026-07-14T23:12:25.151524Z","end":"2026-07-14T23:12:25.259806Z","steps":["trace[2068376186] 'process raft request' (duration: 108.229746ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:12:25.259862Z","caller":"traceutil/trace.go:171","msg":"trace[1491840496] transaction","detail":"{read_only:false; response_revision:6286; number_of_response:1; }","duration":"108.354859ms","start":"2026-07-14T23:12:25.151486Z","end":"2026-07-14T23:12:25.25984Z","steps":["trace[1491840496] 'process raft request' (duration: 108.100342ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:12:25.909886Z","caller":"traceutil/trace.go:171","msg":"trace[1605231150] transaction","detail":"{read_only:false; response_revision:6293; number_of_response:1; }","duration":"151.166194ms","start":"2026-07-14T23:12:25.758669Z","end":"2026-07-14T23:12:25.909835Z","steps":["trace[1605231150] 'process raft request' (duration: 113.15846ms)","trace[1605231150] 'compare' (duration: 37.727207ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:13:23.213327Z","caller":"traceutil/trace.go:171","msg":"trace[282390517] transaction","detail":"{read_only:false; response_revision:6888; number_of_response:1; }","duration":"149.946339ms","start":"2026-07-14T23:13:23.06336Z","end":"2026-07-14T23:13:23.213306Z","steps":["trace[282390517] 'process raft request' (duration: 134.931907ms)","trace[282390517] 'compare' (duration: 14.614483ms)"],"step_count":2}