{"level":"warn","ts":"2026-07-23T23:44:08.842552Z","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-23T23:44:08.842825Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-23T23:44:08.843036Z","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-23T23:44:08.843063Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-07-23T23:44:08.843118Z","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-23T23:44:08.844315Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-07-23T23:44:08.84447Z","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.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-23T23:44:08.849763Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.784003ms"} {"level":"info","ts":"2026-07-23T23:44:08.859057Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-07-23T23:44:08.859171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-07-23T23:44:08.859208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-07-23T23:44:08.859221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-23T23:44:08.859242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-07-23T23:44:08.859305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-07-23T23:44:08.86777Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-23T23:44:08.870941Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-23T23:44:08.874066Z","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-23T23:44:08.87643Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-23T23:44:08.876662Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-23T23:44:08.876846Z","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-23T23:44:08.876954Z","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-23T23:44:08.876971Z","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-23T23:44:08.881001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-07-23T23:44:08.881417Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-07-23T23:44:08.884753Z","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-23T23:44:08.885025Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-07-23T23:44:08.885074Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-07-23T23:44:08.885924Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-23T23:44:08.886009Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-23T23:44:09.562168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-07-23T23:44:09.562248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-23T23:44:09.562285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-07-23T23:44:09.562304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-07-23T23:44:09.562314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-07-23T23:44:09.562331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-07-23T23:44:09.562343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-07-23T23:44:09.56369Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:44:09.564671Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:44:09.564669Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-07-23T23:44:09.564691Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:44:09.564795Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:44:09.564903Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:44:09.564935Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:44:09.565649Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-23T23:44:09.56574Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-23T23:44:09.567814Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-23T23:44:09.567818Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"warn","ts":"2026-07-23T23:44:12.333384Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972034937986007,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:44:12.532006Z","caller":"traceutil/trace.go:171","msg":"trace[148962118] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"701.350873ms","start":"2026-07-23T23:44:11.83062Z","end":"2026-07-23T23:44:12.531971Z","steps":["trace[148962118] 'process raft request' (duration: 701.124979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:12.532463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.643307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-23T23:44:12.532531Z","caller":"traceutil/trace.go:171","msg":"trace[1818518458] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"699.760689ms","start":"2026-07-23T23:44:11.832758Z","end":"2026-07-23T23:44:12.532519Z","steps":["trace[1818518458] 'agreement among raft nodes before linearized reading' (duration: 699.410361ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:12.532119Z","caller":"traceutil/trace.go:171","msg":"trace[706983855] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"699.095055ms","start":"2026-07-23T23:44:11.832798Z","end":"2026-07-23T23:44:12.531893Z","steps":["trace[706983855] 'read index received' (duration: 698.87165ms)","trace[706983855] 'applied index is now lower than readState.Index' (duration: 222.495µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:44:12.532654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:11.832721Z","time spent":"699.906022ms","remote":"127.0.0.1:46390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-07-23T23:44:12.532556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:11.830601Z","time spent":"701.486777ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:44:12.532813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.561194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-23T23:44:12.532892Z","caller":"traceutil/trace.go:171","msg":"trace[1646946370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"593.695566ms","start":"2026-07-23T23:44:11.939183Z","end":"2026-07-23T23:44:12.532878Z","steps":["trace[1646946370] 'agreement among raft nodes before linearized reading' (duration: 593.551364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:12.532961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:11.939156Z","time spent":"593.759738ms","remote":"127.0.0.1:46012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:44:13.157374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.55078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-23T23:44:13.157492Z","caller":"traceutil/trace.go:171","msg":"trace[1537257550] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"619.168241ms","start":"2026-07-23T23:44:12.538312Z","end":"2026-07-23T23:44:13.15748Z","steps":["trace[1537257550] 'read index received' (duration: 347.305884ms)","trace[1537257550] 'applied index is now lower than readState.Index' (duration: 271.861487ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:13.157507Z","caller":"traceutil/trace.go:171","msg":"trace[1571157196] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"619.882106ms","start":"2026-07-23T23:44:12.53761Z","end":"2026-07-23T23:44:13.157492Z","steps":["trace[1571157196] 'process raft request' (duration: 348.06506ms)","trace[1571157196] 'compare' (duration: 271.449668ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:44:13.157564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:12.537583Z","time spent":"619.958027ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:44:13.157646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.326954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-23T23:44:13.157694Z","caller":"traceutil/trace.go:171","msg":"trace[1395652743] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:73; }","duration":"619.400845ms","start":"2026-07-23T23:44:12.538286Z","end":"2026-07-23T23:44:13.157687Z","steps":["trace[1395652743] 'agreement among raft nodes before linearized reading' (duration: 619.285263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:13.157743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:12.538275Z","time spent":"619.460866ms","remote":"127.0.0.1:46390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-07-23T23:44:13.157795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.564301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-23T23:44:13.157821Z","caller":"traceutil/trace.go:171","msg":"trace[1761327119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"217.652063ms","start":"2026-07-23T23:44:12.940162Z","end":"2026-07-23T23:44:13.157814Z","steps":["trace[1761327119] 'agreement among raft nodes before linearized reading' (duration: 217.588341ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:15.720901Z","caller":"traceutil/trace.go:171","msg":"trace[294410197] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"101.31853ms","start":"2026-07-23T23:44:15.61956Z","end":"2026-07-23T23:44:15.720878Z","steps":["trace[294410197] 'process raft request' (duration: 93.401838ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:47.96227Z","caller":"traceutil/trace.go:171","msg":"trace[1859762480] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"412.27093ms","start":"2026-07-23T23:44:47.549953Z","end":"2026-07-23T23:44:47.962224Z","steps":["trace[1859762480] 'process raft request' (duration: 411.256837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:47.96268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:47.549939Z","time spent":"412.411812ms","remote":"127.0.0.1:46076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:44:47.974804Z","caller":"traceutil/trace.go:171","msg":"trace[927851791] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:566; }","duration":"414.932551ms","start":"2026-07-23T23:44:47.559801Z","end":"2026-07-23T23:44:47.974733Z","steps":["trace[927851791] 'read index received' (duration: 401.42512ms)","trace[927851791] 'applied index is now lower than readState.Index' (duration: 13.506691ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:47.974853Z","caller":"traceutil/trace.go:171","msg":"trace[1854689182] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"417.388608ms","start":"2026-07-23T23:44:47.557429Z","end":"2026-07-23T23:44:47.974817Z","steps":["trace[1854689182] 'process raft request' (duration: 417.086461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:47.974955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:47.557424Z","time spent":"417.48422ms","remote":"127.0.0.1:46076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:44:47.974967Z","caller":"traceutil/trace.go:171","msg":"trace[836661690] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"401.531782ms","start":"2026-07-23T23:44:47.573411Z","end":"2026-07-23T23:44:47.974942Z","steps":["trace[836661690] 'process raft request' (duration: 401.205695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:47.974992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.170346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10078"} {"level":"info","ts":"2026-07-23T23:44:47.975028Z","caller":"traceutil/trace.go:171","msg":"trace[128548539] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:555; }","duration":"415.241168ms","start":"2026-07-23T23:44:47.559775Z","end":"2026-07-23T23:44:47.975016Z","steps":["trace[128548539] 'agreement among raft nodes before linearized reading' (duration: 415.088085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:47.975066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:47.559769Z","time spent":"415.287089ms","remote":"127.0.0.1:46076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10100,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-07-23T23:44:47.975032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:47.5734Z","time spent":"401.605303ms","remote":"127.0.0.1:46076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:44:47.975197Z","caller":"traceutil/trace.go:171","msg":"trace[466749497] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"417.814607ms","start":"2026-07-23T23:44:47.557344Z","end":"2026-07-23T23:44:47.975158Z","steps":["trace[466749497] 'process raft request' (duration: 417.117121ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:47.975155Z","caller":"traceutil/trace.go:171","msg":"trace[2098380046] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"415.088525ms","start":"2026-07-23T23:44:47.560041Z","end":"2026-07-23T23:44:47.97513Z","steps":["trace[2098380046] 'process raft request' (duration: 414.519431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:47.975353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:47.557339Z","time spent":"417.94304ms","remote":"127.0.0.1:46076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:44:47.974965Z","caller":"traceutil/trace.go:171","msg":"trace[765014056] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"417.684264ms","start":"2026-07-23T23:44:47.557233Z","end":"2026-07-23T23:44:47.974917Z","steps":["trace[765014056] 'process raft request' (duration: 417.128431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:47.975418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:47.560034Z","time spent":"415.32159ms","remote":"127.0.0.1:46076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:44:47.975484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:47.557215Z","time spent":"418.229286ms","remote":"127.0.0.1:46076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:44:47.975183Z","caller":"traceutil/trace.go:171","msg":"trace[1766693585] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"312.074281ms","start":"2026-07-23T23:44:47.663088Z","end":"2026-07-23T23:44:47.975163Z","steps":["trace[1766693585] 'process raft request' (duration: 311.57609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:47.975548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:47.663063Z","time spent":"312.462629ms","remote":"127.0.0.1:46076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:44:52.175949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972034937987150,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:44:52.215998Z","caller":"traceutil/trace.go:171","msg":"trace[1452969039] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"540.549443ms","start":"2026-07-23T23:44:51.675419Z","end":"2026-07-23T23:44:52.215968Z","steps":["trace[1452969039] 'read index received' (duration: 539.650252ms)","trace[1452969039] 'applied index is now lower than readState.Index' (duration: 897.351µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:52.216117Z","caller":"traceutil/trace.go:171","msg":"trace[725615641] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"551.65547ms","start":"2026-07-23T23:44:51.664425Z","end":"2026-07-23T23:44:52.21608Z","steps":["trace[725615641] 'process raft request' (duration: 550.75762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:52.216281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.497211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T23:44:52.216313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:51.664393Z","time spent":"551.805354ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:44:52.216339Z","caller":"traceutil/trace.go:171","msg":"trace[666944855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"486.587584ms","start":"2026-07-23T23:44:51.729735Z","end":"2026-07-23T23:44:52.216322Z","steps":["trace[666944855] 'agreement among raft nodes before linearized reading' (duration: 486.46009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:52.216374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:51.72972Z","time spent":"486.646665ms","remote":"127.0.0.1:46012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:44:52.217024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.584537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-07-23T23:44:52.217098Z","caller":"traceutil/trace.go:171","msg":"trace[2096380280] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:613; }","duration":"541.70295ms","start":"2026-07-23T23:44:51.675377Z","end":"2026-07-23T23:44:52.21708Z","steps":["trace[2096380280] 'agreement among raft nodes before linearized reading' (duration: 540.712127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:52.217145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:51.675364Z","time spent":"541.771711ms","remote":"127.0.0.1:46076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":20888,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-07-23T23:44:54.018057Z","caller":"traceutil/trace.go:171","msg":"trace[2140240948] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"264.459819ms","start":"2026-07-23T23:44:53.753572Z","end":"2026-07-23T23:44:54.018031Z","steps":["trace[2140240948] 'process raft request' (duration: 264.267885ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:54.107157Z","caller":"traceutil/trace.go:171","msg":"trace[1427672416] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"164.729656ms","start":"2026-07-23T23:44:53.942397Z","end":"2026-07-23T23:44:54.107127Z","steps":["trace[1427672416] 'read index received' (duration: 75.496373ms)","trace[1427672416] 'applied index is now lower than readState.Index' (duration: 89.232353ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:44:54.107344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.925591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-23T23:44:54.107384Z","caller":"traceutil/trace.go:171","msg":"trace[818519259] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:625; }","duration":"165.010382ms","start":"2026-07-23T23:44:53.942362Z","end":"2026-07-23T23:44:54.107373Z","steps":["trace[818519259] 'agreement among raft nodes before linearized reading' (duration: 164.862429ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:54.107886Z","caller":"traceutil/trace.go:171","msg":"trace[1857253007] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"263.547199ms","start":"2026-07-23T23:44:53.844328Z","end":"2026-07-23T23:44:54.107875Z","steps":["trace[1857253007] 'process raft request' (duration: 260.95745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:54.671431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972034937987189,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:44:55.569712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.81216ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-07-23T23:44:55.569844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:54.109551Z","time spent":"1.46028231s","remote":"127.0.0.1:46054","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-23T23:44:55.570037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.399422535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:44:55.570129Z","caller":"traceutil/trace.go:171","msg":"trace[630725723] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:625; }","duration":"1.399569479s","start":"2026-07-23T23:44:54.170537Z","end":"2026-07-23T23:44:55.570106Z","steps":["trace[630725723] 'agreement among raft nodes before linearized reading' (duration: 1.399400565s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:55.570178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:54.170512Z","time spent":"1.39965655s","remote":"127.0.0.1:46226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-07-23T23:44:55.569827Z","caller":"traceutil/trace.go:171","msg":"trace[661347624] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"1.399222321s","start":"2026-07-23T23:44:54.170586Z","end":"2026-07-23T23:44:55.569809Z","steps":["trace[661347624] 'read index received' (duration: 895.217056ms)","trace[661347624] 'applied index is now lower than readState.Index' (duration: 504.003755ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:44:55.570634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.481263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-23T23:44:55.570664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097177652s","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-23T23:44:55.570679Z","caller":"traceutil/trace.go:171","msg":"trace[1382318057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"172.562235ms","start":"2026-07-23T23:44:55.398105Z","end":"2026-07-23T23:44:55.570667Z","steps":["trace[1382318057] 'agreement among raft nodes before linearized reading' (duration: 172.471483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:55.570798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016363174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T23:44:55.570757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.328046ms","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-23T23:44:55.570865Z","caller":"traceutil/trace.go:171","msg":"trace[600785081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"1.016475546s","start":"2026-07-23T23:44:54.554367Z","end":"2026-07-23T23:44:55.570843Z","steps":["trace[600785081] 'agreement among raft nodes before linearized reading' (duration: 1.016356503s)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:55.570905Z","caller":"traceutil/trace.go:171","msg":"trace[2003252472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"168.513091ms","start":"2026-07-23T23:44:55.402338Z","end":"2026-07-23T23:44:55.570851Z","steps":["trace[2003252472] 'agreement among raft nodes before linearized reading' (duration: 168.305577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:55.57091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:54.554348Z","time spent":"1.016554368s","remote":"127.0.0.1:46012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:44:55.571201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.024042ms","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-23T23:44:55.571298Z","caller":"traceutil/trace.go:171","msg":"trace[1964218040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"953.335569ms","start":"2026-07-23T23:44:54.617924Z","end":"2026-07-23T23:44:55.571259Z","steps":["trace[1964218040] 'agreement among raft nodes before linearized reading' (duration: 952.899628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:55.571348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:54.617905Z","time spent":"953.432142ms","remote":"127.0.0.1:46298","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":"info","ts":"2026-07-23T23:44:55.571479Z","caller":"traceutil/trace.go:171","msg":"trace[1838276369] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"1.097267694s","start":"2026-07-23T23:44:54.473429Z","end":"2026-07-23T23:44:55.570697Z","steps":["trace[1838276369] 'agreement among raft nodes before linearized reading' (duration: 1.09709659s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:55.57154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:54.473407Z","time spent":"1.098118363s","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-23T23:44:55.816303Z","caller":"traceutil/trace.go:171","msg":"trace[1384932108] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"229.080269ms","start":"2026-07-23T23:44:55.587204Z","end":"2026-07-23T23:44:55.816284Z","steps":["trace[1384932108] 'process raft request' (duration: 229.018578ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:55.816324Z","caller":"traceutil/trace.go:171","msg":"trace[723509635] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"240.73909ms","start":"2026-07-23T23:44:55.575552Z","end":"2026-07-23T23:44:55.816291Z","steps":["trace[723509635] 'process raft request' (duration: 165.804907ms)","trace[723509635] 'compare' (duration: 74.735988ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:55.816361Z","caller":"traceutil/trace.go:171","msg":"trace[879514176] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:646; }","duration":"197.000154ms","start":"2026-07-23T23:44:55.619347Z","end":"2026-07-23T23:44:55.816347Z","steps":["trace[879514176] 'read index received' (duration: 122.020409ms)","trace[879514176] 'applied index is now lower than readState.Index' (duration: 74.979055ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:44:55.816489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.130666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-23T23:44:55.81652Z","caller":"traceutil/trace.go:171","msg":"trace[590017287] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:630; }","duration":"197.212648ms","start":"2026-07-23T23:44:55.619298Z","end":"2026-07-23T23:44:55.816511Z","steps":["trace[590017287] 'agreement among raft nodes before linearized reading' (duration: 197.131247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:55.816524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.395406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:44:55.816552Z","caller":"traceutil/trace.go:171","msg":"trace[65060796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"192.459278ms","start":"2026-07-23T23:44:55.624085Z","end":"2026-07-23T23:44:55.816545Z","steps":["trace[65060796] 'agreement among raft nodes before linearized reading' (duration: 192.390707ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:55.816672Z","caller":"traceutil/trace.go:171","msg":"trace[522901384] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"229.458448ms","start":"2026-07-23T23:44:55.587201Z","end":"2026-07-23T23:44:55.81666Z","steps":["trace[522901384] 'process raft request' (duration: 229.050299ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:55.81669Z","caller":"traceutil/trace.go:171","msg":"trace[1180754484] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"229.330295ms","start":"2026-07-23T23:44:55.58735Z","end":"2026-07-23T23:44:55.816681Z","steps":["trace[1180754484] 'process raft request' (duration: 228.955557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:11.422015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.182344ms","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-23T23:45:11.422115Z","caller":"traceutil/trace.go:171","msg":"trace[886021292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:801; }","duration":"165.354508ms","start":"2026-07-23T23:45:11.256743Z","end":"2026-07-23T23:45:11.422097Z","steps":["trace[886021292] 'range keys from in-memory index tree' (duration: 164.966038ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:11.943715Z","caller":"traceutil/trace.go:171","msg":"trace[1922903578] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"494.587939ms","start":"2026-07-23T23:45:11.449101Z","end":"2026-07-23T23:45:11.943689Z","steps":["trace[1922903578] 'read index received' (duration: 494.373905ms)","trace[1922903578] 'applied index is now lower than readState.Index' (duration: 212.844µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:45:11.943813Z","caller":"traceutil/trace.go:171","msg":"trace[219976154] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"517.017239ms","start":"2026-07-23T23:45:11.426762Z","end":"2026-07-23T23:45:11.943779Z","steps":["trace[219976154] 'process raft request' (duration: 516.727693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:11.943973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:11.426738Z","time spent":"517.149542ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:45:11.943995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.838165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:45:11.944041Z","caller":"traceutil/trace.go:171","msg":"trace[697991981] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:802; }","duration":"494.972498ms","start":"2026-07-23T23:45:11.449055Z","end":"2026-07-23T23:45:11.944027Z","steps":["trace[697991981] 'agreement among raft nodes before linearized reading' (duration: 494.842765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:11.94412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:11.449037Z","time spent":"495.0688ms","remote":"127.0.0.1:46320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:45:11.973786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.766401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:45:11.973967Z","caller":"traceutil/trace.go:171","msg":"trace[982259747] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"118.536411ms","start":"2026-07-23T23:45:11.855411Z","end":"2026-07-23T23:45:11.973947Z","steps":["trace[982259747] 'process raft request' (duration: 118.145683ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:11.973877Z","caller":"traceutil/trace.go:171","msg":"trace[991685275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"419.893953ms","start":"2026-07-23T23:45:11.553959Z","end":"2026-07-23T23:45:11.973853Z","steps":["trace[991685275] 'agreement among raft nodes before linearized reading' (duration: 419.73712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:11.974338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:11.553941Z","time spent":"420.371455ms","remote":"127.0.0.1:46012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:45:11.97407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.166351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:45:11.974412Z","caller":"traceutil/trace.go:171","msg":"trace[1910959118] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:803; }","duration":"371.549851ms","start":"2026-07-23T23:45:11.602835Z","end":"2026-07-23T23:45:11.974385Z","steps":["trace[1910959118] 'agreement among raft nodes before linearized reading' (duration: 371.186002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:11.974471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:11.602808Z","time spent":"371.647153ms","remote":"127.0.0.1:46258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-07-23T23:46:08.344056Z","caller":"traceutil/trace.go:171","msg":"trace[186312700] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"118.0378ms","start":"2026-07-23T23:46:08.225974Z","end":"2026-07-23T23:46:08.344012Z","steps":["trace[186312700] 'process raft request' (duration: 117.578388ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:46:16.57013Z","caller":"traceutil/trace.go:171","msg":"trace[658032598] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"118.132039ms","start":"2026-07-23T23:46:16.451971Z","end":"2026-07-23T23:46:16.570103Z","steps":["trace[658032598] 'process raft request' (duration: 117.925864ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:46:16.570429Z","caller":"traceutil/trace.go:171","msg":"trace[1279753763] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"118.349234ms","start":"2026-07-23T23:46:16.452066Z","end":"2026-07-23T23:46:16.570415Z","steps":["trace[1279753763] 'process raft request' (duration: 117.984206ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:46:16.570614Z","caller":"traceutil/trace.go:171","msg":"trace[1765374050] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"118.441117ms","start":"2026-07-23T23:46:16.452101Z","end":"2026-07-23T23:46:16.570542Z","steps":["trace[1765374050] 'process raft request' (duration: 118.205531ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:46:16.570649Z","caller":"traceutil/trace.go:171","msg":"trace[453305247] transaction","detail":"{read_only:false; response_revision:1505; number_of_response:1; }","duration":"118.450688ms","start":"2026-07-23T23:46:16.452109Z","end":"2026-07-23T23:46:16.570559Z","steps":["trace[453305247] 'process raft request' (duration: 118.307643ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:46:40.091857Z","caller":"traceutil/trace.go:171","msg":"trace[1520494392] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"112.508298ms","start":"2026-07-23T23:46:39.979318Z","end":"2026-07-23T23:46:40.091827Z","steps":["trace[1520494392] 'process raft request' (duration: 99.082538ms)","trace[1520494392] 'compare' (duration: 13.307927ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:46:40.377235Z","caller":"traceutil/trace.go:171","msg":"trace[893651784] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"103.272951ms","start":"2026-07-23T23:46:40.273934Z","end":"2026-07-23T23:46:40.377207Z","steps":["trace[893651784] 'process raft request' (duration: 103.094346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:40.792231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.618276ms","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-23T23:46:40.792339Z","caller":"traceutil/trace.go:171","msg":"trace[846601221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1898; }","duration":"122.762621ms","start":"2026-07-23T23:46:40.669551Z","end":"2026-07-23T23:46:40.792314Z","steps":["trace[846601221] 'range keys from in-memory index tree' (duration: 122.494514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:40.792517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.105647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-23T23:46:40.792586Z","caller":"traceutil/trace.go:171","msg":"trace[1671780507] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1898; }","duration":"188.485817ms","start":"2026-07-23T23:46:40.604081Z","end":"2026-07-23T23:46:40.792566Z","steps":["trace[1671780507] 'range keys from in-memory index tree' (duration: 187.972744ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:47:09.132794Z","caller":"traceutil/trace.go:171","msg":"trace[372161246] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"145.759492ms","start":"2026-07-23T23:47:08.986974Z","end":"2026-07-23T23:47:09.132734Z","steps":["trace[372161246] 'process raft request' (duration: 142.284697ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:47:09.137184Z","caller":"traceutil/trace.go:171","msg":"trace[1424535525] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2186; }","duration":"148.610983ms","start":"2026-07-23T23:47:08.988554Z","end":"2026-07-23T23:47:09.137165Z","steps":["trace[1424535525] 'read index received' (duration: 140.805451ms)","trace[1424535525] 'applied index is now lower than readState.Index' (duration: 7.804302ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:47:09.137291Z","caller":"traceutil/trace.go:171","msg":"trace[1742161930] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"148.890431ms","start":"2026-07-23T23:47:08.988373Z","end":"2026-07-23T23:47:09.137264Z","steps":["trace[1742161930] 'process raft request' (duration: 148.696146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:09.137405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.829838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-23T23:47:09.137404Z","caller":"traceutil/trace.go:171","msg":"trace[1503081757] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"149.465094ms","start":"2026-07-23T23:47:08.987903Z","end":"2026-07-23T23:47:09.137368Z","steps":["trace[1503081757] 'process raft request' (duration: 149.129236ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:47:09.137492Z","caller":"traceutil/trace.go:171","msg":"trace[1167491732] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"139.609751ms","start":"2026-07-23T23:47:08.997872Z","end":"2026-07-23T23:47:09.137482Z","steps":["trace[1167491732] 'process raft request' (duration: 139.233162ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:47:09.137446Z","caller":"traceutil/trace.go:171","msg":"trace[657431786] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2114; }","duration":"148.912431ms","start":"2026-07-23T23:47:08.988519Z","end":"2026-07-23T23:47:09.137431Z","steps":["trace[657431786] 'agreement among raft nodes before linearized reading' (duration: 148.725456ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:47:09.137499Z","caller":"traceutil/trace.go:171","msg":"trace[1595400659] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"150.017238ms","start":"2026-07-23T23:47:08.987162Z","end":"2026-07-23T23:47:09.137179Z","steps":["trace[1595400659] 'process raft request' (duration: 149.74862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:15.466559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.639631ms","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-23T23:47:15.46666Z","caller":"traceutil/trace.go:171","msg":"trace[938076953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2144; }","duration":"307.792464ms","start":"2026-07-23T23:47:15.158845Z","end":"2026-07-23T23:47:15.466638Z","steps":["trace[938076953] 'range keys from in-memory index tree' (duration: 307.285531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:15.466715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:15.158823Z","time spent":"307.877746ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T23:47:15.466947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.19422ms","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-23T23:47:15.467023Z","caller":"traceutil/trace.go:171","msg":"trace[1133122948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2144; }","duration":"301.269582ms","start":"2026-07-23T23:47:15.165735Z","end":"2026-07-23T23:47:15.467005Z","steps":["trace[1133122948] 'range keys from in-memory index tree' (duration: 301.051738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:15.467085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:15.165673Z","time spent":"301.390395ms","remote":"127.0.0.1:46298","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":"warn","ts":"2026-07-23T23:47:15.467136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.000274ms","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-23T23:47:15.467194Z","caller":"traceutil/trace.go:171","msg":"trace[548207179] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2144; }","duration":"305.098836ms","start":"2026-07-23T23:47:15.16208Z","end":"2026-07-23T23:47:15.467179Z","steps":["trace[548207179] 'range keys from in-memory index tree' (duration: 304.862091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:15.467246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:15.162059Z","time spent":"305.174069ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T23:47:15.467222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.981307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-23T23:47:15.467411Z","caller":"traceutil/trace.go:171","msg":"trace[1561277276] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2144; }","duration":"291.231664ms","start":"2026-07-23T23:47:15.176135Z","end":"2026-07-23T23:47:15.467367Z","steps":["trace[1561277276] 'range keys from in-memory index tree' (duration: 290.733681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:15.467434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.889016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-07-23T23:47:15.467502Z","caller":"traceutil/trace.go:171","msg":"trace[1998272990] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2144; }","duration":"102.984417ms","start":"2026-07-23T23:47:15.364501Z","end":"2026-07-23T23:47:15.467485Z","steps":["trace[1998272990] 'range keys from in-memory index tree' (duration: 102.725971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:15.467881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.212959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-23T23:47:15.468003Z","caller":"traceutil/trace.go:171","msg":"trace[1075616040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2144; }","duration":"307.366304ms","start":"2026-07-23T23:47:15.160616Z","end":"2026-07-23T23:47:15.467983Z","steps":["trace[1075616040] 'range keys from in-memory index tree' (duration: 307.045735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:15.468217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:15.160605Z","time spent":"307.587209ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-23T23:47:15.719502Z","caller":"traceutil/trace.go:171","msg":"trace[1718028147] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"246.087488ms","start":"2026-07-23T23:47:15.473396Z","end":"2026-07-23T23:47:15.719484Z","steps":["trace[1718028147] 'process raft request' (duration: 245.964275ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:47:15.726554Z","caller":"traceutil/trace.go:171","msg":"trace[1211313531] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"252.923456ms","start":"2026-07-23T23:47:15.473597Z","end":"2026-07-23T23:47:15.726521Z","steps":["trace[1211313531] 'process raft request' (duration: 252.836094ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:47:15.726756Z","caller":"traceutil/trace.go:171","msg":"trace[1867476311] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"252.973778ms","start":"2026-07-23T23:47:15.473753Z","end":"2026-07-23T23:47:15.726727Z","steps":["trace[1867476311] 'process raft request' (duration: 252.745533ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:47:15.72696Z","caller":"traceutil/trace.go:171","msg":"trace[1446652480] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"253.101632ms","start":"2026-07-23T23:47:15.473848Z","end":"2026-07-23T23:47:15.72695Z","steps":["trace[1446652480] 'process raft request' (duration: 252.858275ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:47:15.726986Z","caller":"traceutil/trace.go:171","msg":"trace[664626409] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"253.145723ms","start":"2026-07-23T23:47:15.47383Z","end":"2026-07-23T23:47:15.726976Z","steps":["trace[664626409] 'process raft request' (duration: 253.030579ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:47:15.726997Z","caller":"traceutil/trace.go:171","msg":"trace[52943840] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"139.690267ms","start":"2026-07-23T23:47:15.587282Z","end":"2026-07-23T23:47:15.726972Z","steps":["trace[52943840] 'process raft request' (duration: 139.634904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:11.4065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.898011ms","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-23T23:48:11.406596Z","caller":"traceutil/trace.go:171","msg":"trace[492743510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2492; }","duration":"104.042114ms","start":"2026-07-23T23:48:11.302532Z","end":"2026-07-23T23:48:11.406574Z","steps":["trace[492743510] 'range keys from in-memory index tree' (duration: 103.680956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:57.704877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.697852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:48:57.705028Z","caller":"traceutil/trace.go:171","msg":"trace[727165994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2802; }","duration":"150.914948ms","start":"2026-07-23T23:48:57.554084Z","end":"2026-07-23T23:48:57.704999Z","steps":["trace[727165994] 'range keys from in-memory index tree' (duration: 150.599599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:57.705042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.804502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-23T23:48:57.70517Z","caller":"traceutil/trace.go:171","msg":"trace[340806985] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2802; }","duration":"148.977297ms","start":"2026-07-23T23:48:57.556168Z","end":"2026-07-23T23:48:57.705146Z","steps":["trace[340806985] 'range keys from in-memory index tree' (duration: 148.615228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:58.123564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.054484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:48:58.123693Z","caller":"traceutil/trace.go:171","msg":"trace[1578453142] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2803; }","duration":"296.26885ms","start":"2026-07-23T23:48:57.827398Z","end":"2026-07-23T23:48:58.123666Z","steps":["trace[1578453142] 'range keys from in-memory index tree' (duration: 295.951282ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:48:58.285823Z","caller":"traceutil/trace.go:171","msg":"trace[216089493] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"132.598394ms","start":"2026-07-23T23:48:58.153202Z","end":"2026-07-23T23:48:58.2858Z","steps":["trace[216089493] 'process raft request' (duration: 131.841533ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:48:58.45918Z","caller":"traceutil/trace.go:171","msg":"trace[1507484709] transaction","detail":"{read_only:false; response_revision:2805; number_of_response:1; }","duration":"168.204852ms","start":"2026-07-23T23:48:58.290851Z","end":"2026-07-23T23:48:58.459056Z","steps":["trace[1507484709] 'process raft request' (duration: 167.968536ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:48:58.465917Z","caller":"traceutil/trace.go:171","msg":"trace[1292159169] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"174.476014ms","start":"2026-07-23T23:48:58.291429Z","end":"2026-07-23T23:48:58.465905Z","steps":["trace[1292159169] 'process raft request' (duration: 174.347141ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:48:58.466337Z","caller":"traceutil/trace.go:171","msg":"trace[1827996128] transaction","detail":"{read_only:false; response_revision:2807; number_of_response:1; }","duration":"174.256739ms","start":"2026-07-23T23:48:58.29207Z","end":"2026-07-23T23:48:58.466326Z","steps":["trace[1827996128] 'process raft request' (duration: 173.794018ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:48:58.466993Z","caller":"traceutil/trace.go:171","msg":"trace[1757259298] linearizableReadLoop","detail":"{readStateIndex:2911; appliedIndex:2906; }","duration":"173.978761ms","start":"2026-07-23T23:48:58.292975Z","end":"2026-07-23T23:48:58.466953Z","steps":["trace[1757259298] 'read index received' (duration: 165.658757ms)","trace[1757259298] 'applied index is now lower than readState.Index' (duration: 8.318204ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:48:58.467062Z","caller":"traceutil/trace.go:171","msg":"trace[676282043] transaction","detail":"{read_only:false; response_revision:2808; number_of_response:1; }","duration":"174.530436ms","start":"2026-07-23T23:48:58.292521Z","end":"2026-07-23T23:48:58.467051Z","steps":["trace[676282043] 'process raft request' (duration: 173.694214ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:48:58.467177Z","caller":"traceutil/trace.go:171","msg":"trace[1681976047] transaction","detail":"{read_only:false; response_revision:2809; number_of_response:1; }","duration":"174.414624ms","start":"2026-07-23T23:48:58.292754Z","end":"2026-07-23T23:48:58.467169Z","steps":["trace[1681976047] 'process raft request' (duration: 174.097185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:58.4676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.581447ms","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-23T23:48:58.467711Z","caller":"traceutil/trace.go:171","msg":"trace[1779244524] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2809; }","duration":"174.72877ms","start":"2026-07-23T23:48:58.292959Z","end":"2026-07-23T23:48:58.467688Z","steps":["trace[1779244524] 'agreement among raft nodes before linearized reading' (duration: 174.372072ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:49:46.834501Z","caller":"traceutil/trace.go:171","msg":"trace[864882725] transaction","detail":"{read_only:false; response_revision:3059; number_of_response:1; }","duration":"107.212192ms","start":"2026-07-23T23:49:46.727258Z","end":"2026-07-23T23:49:46.834471Z","steps":["trace[864882725] 'process raft request' (duration: 107.023928ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:49:46.865439Z","caller":"traceutil/trace.go:171","msg":"trace[1905301119] transaction","detail":"{read_only:false; response_revision:3060; number_of_response:1; }","duration":"138.042862ms","start":"2026-07-23T23:49:46.727354Z","end":"2026-07-23T23:49:46.865396Z","steps":["trace[1905301119] 'process raft request' (duration: 137.765244ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:49:46.865735Z","caller":"traceutil/trace.go:171","msg":"trace[648670173] transaction","detail":"{read_only:false; response_revision:3061; number_of_response:1; }","duration":"134.723926ms","start":"2026-07-23T23:49:46.730988Z","end":"2026-07-23T23:49:46.865712Z","steps":["trace[648670173] 'process raft request' (duration: 134.322005ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:49:46.865471Z","caller":"traceutil/trace.go:171","msg":"trace[1009374151] linearizableReadLoop","detail":"{readStateIndex:3173; appliedIndex:3171; }","duration":"137.845036ms","start":"2026-07-23T23:49:46.72758Z","end":"2026-07-23T23:49:46.865425Z","steps":["trace[1009374151] 'read index received' (duration: 106.782931ms)","trace[1009374151] 'applied index is now lower than readState.Index' (duration: 31.060005ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:49:46.866294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.676256ms","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-23T23:49:46.86639Z","caller":"traceutil/trace.go:171","msg":"trace[1332513559] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3062; }","duration":"138.869172ms","start":"2026-07-23T23:49:46.727507Z","end":"2026-07-23T23:49:46.866376Z","steps":["trace[1332513559] 'agreement among raft nodes before linearized reading' (duration: 138.046931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:49:46.866495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.230099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-23T23:49:46.866606Z","caller":"traceutil/trace.go:171","msg":"trace[2026117226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3062; }","duration":"135.555716ms","start":"2026-07-23T23:49:46.731027Z","end":"2026-07-23T23:49:46.866583Z","steps":["trace[2026117226] 'agreement among raft nodes before linearized reading' (duration: 135.072245ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:52:48.979369Z","caller":"traceutil/trace.go:171","msg":"trace[1954514359] transaction","detail":"{read_only:false; response_revision:4551; number_of_response:1; }","duration":"132.718313ms","start":"2026-07-23T23:52:48.846625Z","end":"2026-07-23T23:52:48.979343Z","steps":["trace[1954514359] 'process raft request' (duration: 131.259355ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:52:48.983355Z","caller":"traceutil/trace.go:171","msg":"trace[151511519] transaction","detail":"{read_only:false; response_revision:4552; number_of_response:1; }","duration":"135.782599ms","start":"2026-07-23T23:52:48.847543Z","end":"2026-07-23T23:52:48.983326Z","steps":["trace[151511519] 'process raft request' (duration: 135.647686ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:52:49.119287Z","caller":"traceutil/trace.go:171","msg":"trace[1373206208] transaction","detail":"{read_only:false; response_revision:4553; number_of_response:1; }","duration":"113.830515ms","start":"2026-07-23T23:52:49.005435Z","end":"2026-07-23T23:52:49.119266Z","steps":["trace[1373206208] 'process raft request' (duration: 113.690212ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:52:52.14708Z","caller":"traceutil/trace.go:171","msg":"trace[1391894133] linearizableReadLoop","detail":"{readStateIndex:4802; appliedIndex:4801; }","duration":"126.489783ms","start":"2026-07-23T23:52:52.020568Z","end":"2026-07-23T23:52:52.147057Z","steps":["trace[1391894133] 'read index received' (duration: 117.873657ms)","trace[1391894133] 'applied index is now lower than readState.Index' (duration: 8.615056ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:52:52.147366Z","caller":"traceutil/trace.go:171","msg":"trace[653464870] transaction","detail":"{read_only:false; response_revision:4626; number_of_response:1; }","duration":"127.883689ms","start":"2026-07-23T23:52:52.019471Z","end":"2026-07-23T23:52:52.147355Z","steps":["trace[653464870] 'process raft request' (duration: 118.948554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:52.147649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.059638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-07-23T23:52:52.147705Z","caller":"traceutil/trace.go:171","msg":"trace[1928046062] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4626; }","duration":"127.149901ms","start":"2026-07-23T23:52:52.020541Z","end":"2026-07-23T23:52:52.14769Z","steps":["trace[1928046062] 'agreement among raft nodes before linearized reading' (duration: 126.985097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:52.148946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.2238ms","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-23T23:52:52.149029Z","caller":"traceutil/trace.go:171","msg":"trace[434959024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4627; }","duration":"113.337133ms","start":"2026-07-23T23:52:52.035673Z","end":"2026-07-23T23:52:52.14901Z","steps":["trace[434959024] 'agreement among raft nodes before linearized reading' (duration: 113.122358ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:52:52.149349Z","caller":"traceutil/trace.go:171","msg":"trace[1493549351] transaction","detail":"{read_only:false; response_revision:4627; number_of_response:1; }","duration":"115.773304ms","start":"2026-07-23T23:52:52.033562Z","end":"2026-07-23T23:52:52.149335Z","steps":["trace[1493549351] 'process raft request' (duration: 115.080887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:52.149531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.269917ms","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-23T23:52:52.14957Z","caller":"traceutil/trace.go:171","msg":"trace[1356345541] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4627; }","duration":"112.329358ms","start":"2026-07-23T23:52:52.037231Z","end":"2026-07-23T23:52:52.14956Z","steps":["trace[1356345541] 'agreement among raft nodes before linearized reading' (duration: 112.226006ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:52:53.412587Z","caller":"traceutil/trace.go:171","msg":"trace[592245774] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"180.846252ms","start":"2026-07-23T23:52:53.231723Z","end":"2026-07-23T23:52:53.412569Z","steps":["trace[592245774] 'process raft request' (duration: 180.559505ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:01.078246Z","caller":"traceutil/trace.go:171","msg":"trace[889240120] linearizableReadLoop","detail":"{readStateIndex:5025; appliedIndex:5024; }","duration":"101.770431ms","start":"2026-07-23T23:53:00.976458Z","end":"2026-07-23T23:53:01.078228Z","steps":["trace[889240120] 'read index received' (duration: 101.621277ms)","trace[889240120] 'applied index is now lower than readState.Index' (duration: 148.514µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:53:01.07831Z","caller":"traceutil/trace.go:171","msg":"trace[1065096955] transaction","detail":"{read_only:false; response_revision:4841; number_of_response:1; }","duration":"198.042473ms","start":"2026-07-23T23:53:00.880234Z","end":"2026-07-23T23:53:01.078277Z","steps":["trace[1065096955] 'process raft request' (duration: 197.887429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:01.078507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.034098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5f800ed0-49f8-4c0f-a6e5-5ec0eaa5b004\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-23T23:53:01.078553Z","caller":"traceutil/trace.go:171","msg":"trace[741653047] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5f800ed0-49f8-4c0f-a6e5-5ec0eaa5b004; range_end:; response_count:1; response_revision:4841; }","duration":"102.12104ms","start":"2026-07-23T23:53:00.97642Z","end":"2026-07-23T23:53:01.078541Z","steps":["trace[741653047] 'agreement among raft nodes before linearized reading' (duration: 101.901354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:01.353814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.374547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:53:01.353943Z","caller":"traceutil/trace.go:171","msg":"trace[931737604] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"270.894807ms","start":"2026-07-23T23:53:01.083029Z","end":"2026-07-23T23:53:01.353924Z","steps":["trace[931737604] 'process raft request' (duration: 133.321515ms)","trace[931737604] 'compare' (duration: 136.905865ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:53:01.366042Z","caller":"traceutil/trace.go:171","msg":"trace[1303117444] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"210.824196ms","start":"2026-07-23T23:53:01.155198Z","end":"2026-07-23T23:53:01.366022Z","steps":["trace[1303117444] 'process raft request' (duration: 210.719224ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:01.366172Z","caller":"traceutil/trace.go:171","msg":"trace[1369606383] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"210.835596ms","start":"2026-07-23T23:53:01.155326Z","end":"2026-07-23T23:53:01.366162Z","steps":["trace[1369606383] 'process raft request' (duration: 210.62661ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:01.366246Z","caller":"traceutil/trace.go:171","msg":"trace[288185735] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"282.591252ms","start":"2026-07-23T23:53:01.083584Z","end":"2026-07-23T23:53:01.366175Z","steps":["trace[288185735] 'process raft request' (duration: 282.279623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:01.930233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.857944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:53:01.930327Z","caller":"traceutil/trace.go:171","msg":"trace[1094192170] linearizableReadLoop","detail":"{readStateIndex:5036; appliedIndex:5035; }","duration":"182.852591ms","start":"2026-07-23T23:53:01.747456Z","end":"2026-07-23T23:53:01.930309Z","steps":["trace[1094192170] 'read index received' (duration: 10.831823ms)","trace[1094192170] 'applied index is now lower than readState.Index' (duration: 172.019848ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:53:01.930338Z","caller":"traceutil/trace.go:171","msg":"trace[1499035035] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"287.931775ms","start":"2026-07-23T23:53:01.642384Z","end":"2026-07-23T23:53:01.930316Z","steps":["trace[1499035035] 'process raft request' (duration: 115.909057ms)","trace[1499035035] 'compare' (duration: 171.584718ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:53:01.930445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.983285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5f800ed0-49f8-4c0f-a6e5-5ec0eaa5b004\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-07-23T23:53:01.930495Z","caller":"traceutil/trace.go:171","msg":"trace[1625520968] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5f800ed0-49f8-4c0f-a6e5-5ec0eaa5b004; range_end:; response_count:1; response_revision:4852; }","duration":"183.022856ms","start":"2026-07-23T23:53:01.747451Z","end":"2026-07-23T23:53:01.930474Z","steps":["trace[1625520968] 'agreement among raft nodes before linearized reading' (duration: 182.901912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:02.135291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.164856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-07-23T23:53:02.135392Z","caller":"traceutil/trace.go:171","msg":"trace[1540911204] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4854; }","duration":"103.32499ms","start":"2026-07-23T23:53:02.032046Z","end":"2026-07-23T23:53:02.135371Z","steps":["trace[1540911204] 'range keys from in-memory index tree' (duration: 102.993792ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:09.210492Z","caller":"traceutil/trace.go:171","msg":"trace[531789403] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4945; }","duration":"274.363983ms","start":"2026-07-23T23:53:08.936092Z","end":"2026-07-23T23:53:09.210455Z","steps":["trace[531789403] 'process raft request' (duration: 243.584268ms)","trace[531789403] 'compare' (duration: 30.698623ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:53:09.212315Z","caller":"traceutil/trace.go:171","msg":"trace[1282256392] linearizableReadLoop","detail":"{readStateIndex:5134; appliedIndex:5132; }","duration":"269.048138ms","start":"2026-07-23T23:53:08.943241Z","end":"2026-07-23T23:53:09.212289Z","steps":["trace[1282256392] 'read index received' (duration: 236.53521ms)","trace[1282256392] 'applied index is now lower than readState.Index' (duration: 32.511068ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:53:09.212344Z","caller":"traceutil/trace.go:171","msg":"trace[760309241] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"271.260485ms","start":"2026-07-23T23:53:08.941061Z","end":"2026-07-23T23:53:09.212321Z","steps":["trace[760309241] 'process raft request' (duration: 271.020589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:09.212599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.300514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-07-23T23:53:09.212662Z","caller":"traceutil/trace.go:171","msg":"trace[1032422617] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4946; }","duration":"269.444699ms","start":"2026-07-23T23:53:08.943201Z","end":"2026-07-23T23:53:09.212645Z","steps":["trace[1032422617] 'agreement among raft nodes before linearized reading' (duration: 269.188502ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:10.384927Z","caller":"traceutil/trace.go:171","msg":"trace[1604937864] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"100.317254ms","start":"2026-07-23T23:53:10.28458Z","end":"2026-07-23T23:53:10.384897Z","steps":["trace[1604937864] 'process raft request' (duration: 95.211035ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:16.54079Z","caller":"traceutil/trace.go:171","msg":"trace[1246925111] linearizableReadLoop","detail":"{readStateIndex:5229; appliedIndex:5228; }","duration":"468.481393ms","start":"2026-07-23T23:53:16.072285Z","end":"2026-07-23T23:53:16.540766Z","steps":["trace[1246925111] 'read index received' (duration: 468.316628ms)","trace[1246925111] 'applied index is now lower than readState.Index' (duration: 163.805µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:53:16.540827Z","caller":"traceutil/trace.go:171","msg":"trace[957263426] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"486.102087ms","start":"2026-07-23T23:53:16.054703Z","end":"2026-07-23T23:53:16.540806Z","steps":["trace[957263426] 'process raft request' (duration: 485.922612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.540967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:16.054656Z","time spent":"486.22346ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:53:16.541032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.601301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"warn","ts":"2026-07-23T23:53:16.54106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.77167ms","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-23T23:53:16.541079Z","caller":"traceutil/trace.go:171","msg":"trace[1095018391] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5038; }","duration":"438.677203ms","start":"2026-07-23T23:53:16.10239Z","end":"2026-07-23T23:53:16.541068Z","steps":["trace[1095018391] 'agreement among raft nodes before linearized reading' (duration: 438.563271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.541114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:16.102373Z","time spent":"438.732994ms","remote":"127.0.0.1:46212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10414,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-07-23T23:53:16.541092Z","caller":"traceutil/trace.go:171","msg":"trace[1740076484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5038; }","duration":"468.833443ms","start":"2026-07-23T23:53:16.072248Z","end":"2026-07-23T23:53:16.541081Z","steps":["trace[1740076484] 'agreement among raft nodes before linearized reading' (duration: 468.639848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.541179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:16.07223Z","time spent":"468.935985ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T23:53:16.541304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.560119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-23T23:53:16.541352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.32405ms","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-23T23:53:16.541375Z","caller":"traceutil/trace.go:171","msg":"trace[17663161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5038; }","duration":"127.655622ms","start":"2026-07-23T23:53:16.413699Z","end":"2026-07-23T23:53:16.541355Z","steps":["trace[17663161] 'agreement among raft nodes before linearized reading' (duration: 127.532128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.541184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.515879ms","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-23T23:53:16.5414Z","caller":"traceutil/trace.go:171","msg":"trace[585743442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5038; }","duration":"239.382341ms","start":"2026-07-23T23:53:16.302006Z","end":"2026-07-23T23:53:16.541389Z","steps":["trace[585743442] 'agreement among raft nodes before linearized reading' (duration: 239.267127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.541427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.673667ms","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-23T23:53:16.541458Z","caller":"traceutil/trace.go:171","msg":"trace[452068687] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5038; }","duration":"240.843608ms","start":"2026-07-23T23:53:16.300603Z","end":"2026-07-23T23:53:16.541446Z","steps":["trace[452068687] 'agreement among raft nodes before linearized reading' (duration: 240.389546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.541315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.876129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-23T23:53:16.541511Z","caller":"traceutil/trace.go:171","msg":"trace[1101207069] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5038; }","duration":"249.108666ms","start":"2026-07-23T23:53:16.292391Z","end":"2026-07-23T23:53:16.541499Z","steps":["trace[1101207069] 'agreement among raft nodes before linearized reading' (duration: 248.863099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.541361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.775065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:53:16.541593Z","caller":"traceutil/trace.go:171","msg":"trace[518165203] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:5038; }","duration":"301.035831ms","start":"2026-07-23T23:53:16.240546Z","end":"2026-07-23T23:53:16.541582Z","steps":["trace[518165203] 'agreement among raft nodes before linearized reading' (duration: 300.790316ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:16.541465Z","caller":"traceutil/trace.go:171","msg":"trace[1181854486] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5038; }","duration":"351.741358ms","start":"2026-07-23T23:53:16.189713Z","end":"2026-07-23T23:53:16.541454Z","steps":["trace[1181854486] 'agreement among raft nodes before linearized reading' (duration: 351.662787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.541638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:16.240524Z","time spent":"301.101584ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:53:16.541645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:16.189694Z","time spent":"351.935833ms","remote":"127.0.0.1:46188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-07-23T23:53:16.659349Z","caller":"traceutil/trace.go:171","msg":"trace[453645315] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"106.907189ms","start":"2026-07-23T23:53:16.552419Z","end":"2026-07-23T23:53:16.659326Z","steps":["trace[453645315] 'process raft request' (duration: 106.845447ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:16.659453Z","caller":"traceutil/trace.go:171","msg":"trace[1473072180] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"107.119895ms","start":"2026-07-23T23:53:16.552328Z","end":"2026-07-23T23:53:16.659448Z","steps":["trace[1473072180] 'process raft request' (duration: 106.812227ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:16.659603Z","caller":"traceutil/trace.go:171","msg":"trace[1115463707] linearizableReadLoop","detail":"{readStateIndex:5234; appliedIndex:5230; }","duration":"105.134444ms","start":"2026-07-23T23:53:16.554431Z","end":"2026-07-23T23:53:16.659565Z","steps":["trace[1115463707] 'read index received' (duration: 38.161531ms)","trace[1115463707] 'applied index is now lower than readState.Index' (duration: 66.970153ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:53:16.65972Z","caller":"traceutil/trace.go:171","msg":"trace[75085684] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"105.139785ms","start":"2026-07-23T23:53:16.554544Z","end":"2026-07-23T23:53:16.659684Z","steps":["trace[75085684] 'process raft request' (duration: 104.758295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:16.659735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.286389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:53:16.659809Z","caller":"traceutil/trace.go:171","msg":"trace[1239294989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5044; }","duration":"105.361141ms","start":"2026-07-23T23:53:16.554427Z","end":"2026-07-23T23:53:16.659788Z","steps":["trace[1239294989] 'agreement among raft nodes before linearized reading' (duration: 105.248308ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:17.667556Z","caller":"traceutil/trace.go:171","msg":"trace[1474919053] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"255.685101ms","start":"2026-07-23T23:53:17.411647Z","end":"2026-07-23T23:53:17.667332Z","steps":["trace[1474919053] 'process raft request' (duration: 255.521027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:17.984887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.23902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:53:17.985065Z","caller":"traceutil/trace.go:171","msg":"trace[411932551] linearizableReadLoop","detail":"{readStateIndex:5242; appliedIndex:5239; }","duration":"455.796594ms","start":"2026-07-23T23:53:17.529249Z","end":"2026-07-23T23:53:17.985046Z","steps":["trace[411932551] 'read index received' (duration: 137.838327ms)","trace[411932551] 'applied index is now lower than readState.Index' (duration: 317.957007ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:53:17.985076Z","caller":"traceutil/trace.go:171","msg":"trace[597578885] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"486.406025ms","start":"2026-07-23T23:53:17.498646Z","end":"2026-07-23T23:53:17.985052Z","steps":["trace[597578885] 'process raft request' (duration: 263.921768ms)","trace[597578885] 'compare' (duration: 221.971203ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:53:17.985182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:17.498623Z","time spent":"486.495946ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:53:17.985302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.684731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-pf8mq.18c51052bf4db9a2\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-07-23T23:53:17.985349Z","caller":"traceutil/trace.go:171","msg":"trace[65181551] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-pf8mq.18c51052bf4db9a2; range_end:; response_count:1; response_revision:5051; }","duration":"352.760142ms","start":"2026-07-23T23:53:17.632577Z","end":"2026-07-23T23:53:17.985337Z","steps":["trace[65181551] 'agreement among raft nodes before linearized reading' (duration: 352.579408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:17.985387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:17.632561Z","time spent":"352.818294ms","remote":"127.0.0.1:46102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":928,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-pf8mq.18c51052bf4db9a2\" "} {"level":"info","ts":"2026-07-23T23:53:17.985915Z","caller":"traceutil/trace.go:171","msg":"trace[1396401110] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"485.732648ms","start":"2026-07-23T23:53:17.500164Z","end":"2026-07-23T23:53:17.985897Z","steps":["trace[1396401110] 'process raft request' (duration: 484.813095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:17.985999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:17.500152Z","time spent":"485.805739ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:53:17.986178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.919042ms","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-23T23:53:17.986228Z","caller":"traceutil/trace.go:171","msg":"trace[1499961619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5051; }","duration":"457.000413ms","start":"2026-07-23T23:53:17.529216Z","end":"2026-07-23T23:53:17.986216Z","steps":["trace[1499961619] 'agreement among raft nodes before linearized reading' (duration: 456.84866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:17.986261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:17.529204Z","time spent":"457.050205ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-23T23:53:18.225607Z","caller":"traceutil/trace.go:171","msg":"trace[1198328425] linearizableReadLoop","detail":"{readStateIndex:5244; appliedIndex:5242; }","duration":"234.672832ms","start":"2026-07-23T23:53:17.990914Z","end":"2026-07-23T23:53:18.225587Z","steps":["trace[1198328425] 'read index received' (duration: 67.945068ms)","trace[1198328425] 'applied index is now lower than readState.Index' (duration: 166.726803ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:53:18.225706Z","caller":"traceutil/trace.go:171","msg":"trace[310523463] transaction","detail":"{read_only:false; response_revision:5053; number_of_response:1; }","duration":"235.268457ms","start":"2026-07-23T23:53:17.990418Z","end":"2026-07-23T23:53:18.225686Z","steps":["trace[310523463] 'process raft request' (duration: 235.022941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:18.225828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.901439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-23T23:53:18.225866Z","caller":"traceutil/trace.go:171","msg":"trace[1913475111] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5053; }","duration":"235.008311ms","start":"2026-07-23T23:53:17.990848Z","end":"2026-07-23T23:53:18.225857Z","steps":["trace[1913475111] 'agreement among raft nodes before linearized reading' (duration: 234.854276ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:52.356167Z","caller":"traceutil/trace.go:171","msg":"trace[472547402] transaction","detail":"{read_only:false; response_revision:5353; number_of_response:1; }","duration":"239.543579ms","start":"2026-07-23T23:53:52.116598Z","end":"2026-07-23T23:53:52.356141Z","steps":["trace[472547402] 'process raft request' (duration: 239.381615ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:52.472476Z","caller":"traceutil/trace.go:171","msg":"trace[246053590] transaction","detail":"{read_only:false; response_revision:5354; number_of_response:1; }","duration":"353.785271ms","start":"2026-07-23T23:53:52.118657Z","end":"2026-07-23T23:53:52.472442Z","steps":["trace[246053590] 'process raft request' (duration: 347.051781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:53:52.472811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:53:52.118634Z","time spent":"353.987525ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:54:09.587606Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2866} {"level":"info","ts":"2026-07-23T23:54:09.709337Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2866,"took":"120.437715ms","hash":2078919408,"current-db-size-bytes":53096448,"current-db-size":"53 MB","current-db-size-in-use-bytes":28475392,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-23T23:54:09.709391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2078919408,"revision":2866,"compact-revision":-1} {"level":"info","ts":"2026-07-23T23:59:09.595155Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5475} {"level":"info","ts":"2026-07-23T23:59:09.673875Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5475,"took":"76.993141ms","hash":2142118235,"current-db-size-bytes":53096448,"current-db-size":"53 MB","current-db-size-in-use-bytes":18317312,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-23T23:59:09.673959Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2142118235,"revision":5475,"compact-revision":2866} {"level":"info","ts":"2026-07-24T00:00:45.147879Z","caller":"traceutil/trace.go:171","msg":"trace[1855791851] transaction","detail":"{read_only:false; response_revision:7900; number_of_response:1; }","duration":"253.112521ms","start":"2026-07-24T00:00:44.894733Z","end":"2026-07-24T00:00:45.147845Z","steps":["trace[1855791851] 'process raft request' (duration: 252.953076ms)"],"step_count":1}