{"level":"warn","ts":"2026-09-19T16:54:44.4102Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T16:54:44.41033Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.129:2380","--initial-cluster=instance=https://162.253.55.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.129:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-19T16:54:44.410419Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T16:54:44.410437Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.129:2380"]} {"level":"info","ts":"2026-09-19T16:54:44.410462Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T16:54:44.411325Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.129:2379"]} {"level":"info","ts":"2026-09-19T16:54:44.411417Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.129:2380"],"listen-peer-urls":["https://162.253.55.129:2380"],"advertise-client-urls":["https://162.253.55.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.129:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-19T16:54:44.419659Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.212654ms"} {"level":"info","ts":"2026-09-19T16:54:44.425988Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d232699cb6c87299","cluster-id":"f2b9115890c3fc7f"} {"level":"info","ts":"2026-09-19T16:54:44.426064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T16:54:44.426088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 became follower at term 0"} {"level":"info","ts":"2026-09-19T16:54:44.426102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d232699cb6c87299 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T16:54:44.426111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 became follower at term 1"} {"level":"info","ts":"2026-09-19T16:54:44.426152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 switched to configuration voters=(15146284618602803865)"} {"level":"warn","ts":"2026-09-19T16:54:44.432963Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T16:54:44.434339Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T16:54:44.435909Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-19T16:54:44.43944Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d232699cb6c87299","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T16:54:44.43969Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d232699cb6c87299","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T16:54:44.440168Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T16:54:44.44021Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T16:54:44.440216Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T16:54:44.440573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 switched to configuration voters=(15146284618602803865)"} {"level":"info","ts":"2026-09-19T16:54:44.441026Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f2b9115890c3fc7f","local-member-id":"d232699cb6c87299","added-peer-id":"d232699cb6c87299","added-peer-peer-urls":["https://162.253.55.129:2380"]} {"level":"info","ts":"2026-09-19T16:54:44.443692Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T16:54:44.443849Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.129:2380"} {"level":"info","ts":"2026-09-19T16:54:44.44386Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.129:2380"} {"level":"info","ts":"2026-09-19T16:54:44.44459Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d232699cb6c87299","initial-advertise-peer-urls":["https://162.253.55.129:2380"],"listen-peer-urls":["https://162.253.55.129:2380"],"advertise-client-urls":["https://162.253.55.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T16:54:44.444614Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T16:54:44.627731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T16:54:44.627796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T16:54:44.627876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 received MsgPreVoteResp from d232699cb6c87299 at term 1"} {"level":"info","ts":"2026-09-19T16:54:44.627993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 became candidate at term 2"} {"level":"info","ts":"2026-09-19T16:54:44.628015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 received MsgVoteResp from d232699cb6c87299 at term 2"} {"level":"info","ts":"2026-09-19T16:54:44.628099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 became leader at term 2"} {"level":"info","ts":"2026-09-19T16:54:44.628112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d232699cb6c87299 elected leader d232699cb6c87299 at term 2"} {"level":"info","ts":"2026-09-19T16:54:44.630826Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T16:54:44.632315Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T16:54:44.632309Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d232699cb6c87299","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.129:2379]}","request-path":"/0/members/d232699cb6c87299/attributes","cluster-id":"f2b9115890c3fc7f","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T16:54:44.63235Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T16:54:44.632757Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T16:54:44.632806Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f2b9115890c3fc7f","local-member-id":"d232699cb6c87299","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T16:54:44.632824Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T16:54:44.632888Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T16:54:44.632909Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T16:54:44.635479Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.129:2379"} {"level":"info","ts":"2026-09-19T16:54:44.636398Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T16:54:58.06536Z","caller":"traceutil/trace.go:171","msg":"trace[1582353863] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"137.523432ms","start":"2026-09-19T16:54:57.927813Z","end":"2026-09-19T16:54:58.065337Z","steps":["trace[1582353863] 'process raft request' (duration: 137.21811ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:54:58.065445Z","caller":"traceutil/trace.go:171","msg":"trace[937835262] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:373; }","duration":"106.508287ms","start":"2026-09-19T16:54:57.95869Z","end":"2026-09-19T16:54:58.065198Z","steps":["trace[937835262] 'read index received' (duration: 106.253965ms)","trace[937835262] 'applied index is now lower than readState.Index' (duration: 253.262µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T16:54:58.065672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.93019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-19T16:54:58.065836Z","caller":"traceutil/trace.go:171","msg":"trace[701941048] range","detail":"{range_begin:/registry/masterleases/162.253.55.129; range_end:; response_count:1; response_revision:362; }","duration":"107.148921ms","start":"2026-09-19T16:54:57.958658Z","end":"2026-09-19T16:54:58.065807Z","steps":["trace[701941048] 'agreement among raft nodes before linearized reading' (duration: 106.836919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:58.490717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.526762ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-09-19T16:54:58.490872Z","caller":"traceutil/trace.go:171","msg":"trace[74769709] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:374; }","duration":"230.984626ms","start":"2026-09-19T16:54:58.259863Z","end":"2026-09-19T16:54:58.490847Z","steps":["trace[74769709] 'read index received' (duration: 46.131µs)","trace[74769709] 'applied index is now lower than readState.Index' (duration: 230.936605ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T16:54:58.491074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.215178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-19T16:54:58.491105Z","caller":"traceutil/trace.go:171","msg":"trace[556692743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:362; }","duration":"231.298968ms","start":"2026-09-19T16:54:58.259796Z","end":"2026-09-19T16:54:58.491095Z","steps":["trace[556692743] 'agreement among raft nodes before linearized reading' (duration: 231.168117ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:58.49115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.281662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T16:54:58.490991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:58.066894Z","time spent":"424.089369ms","remote":"127.0.0.1:36548","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-19T16:54:58.491275Z","caller":"traceutil/trace.go:171","msg":"trace[1264498997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:362; }","duration":"123.470093ms","start":"2026-09-19T16:54:58.367781Z","end":"2026-09-19T16:54:58.491251Z","steps":["trace[1264498997] 'agreement among raft nodes before linearized reading' (duration: 123.260362ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:55:12.251134Z","caller":"traceutil/trace.go:171","msg":"trace[1233518745] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"116.100335ms","start":"2026-09-19T16:55:12.135014Z","end":"2026-09-19T16:55:12.251115Z","steps":["trace[1233518745] 'process raft request' (duration: 115.975964ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:55:35.537488Z","caller":"traceutil/trace.go:171","msg":"trace[1156121904] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"223.98382ms","start":"2026-09-19T16:55:35.313467Z","end":"2026-09-19T16:55:35.537451Z","steps":["trace[1156121904] 'process raft request' (duration: 223.293556ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:55:35.808506Z","caller":"traceutil/trace.go:171","msg":"trace[732086494] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"181.867076ms","start":"2026-09-19T16:55:35.62658Z","end":"2026-09-19T16:55:35.808447Z","steps":["trace[732086494] 'process raft request' (duration: 181.592994ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:55:36.346957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.400766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4jb4j\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-19T16:55:36.34706Z","caller":"traceutil/trace.go:171","msg":"trace[950002697] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4jb4j; range_end:; response_count:1; response_revision:609; }","duration":"425.573927ms","start":"2026-09-19T16:55:35.92146Z","end":"2026-09-19T16:55:36.347034Z","steps":["trace[950002697] 'range keys from in-memory index tree' (duration: 425.107984ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:55:36.347105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:55:35.921446Z","time spent":"425.648919ms","remote":"127.0.0.1:36718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-4jb4j\" "} {"level":"info","ts":"2026-09-19T16:55:38.273451Z","caller":"traceutil/trace.go:171","msg":"trace[1345169409] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"115.033691ms","start":"2026-09-19T16:55:38.158387Z","end":"2026-09-19T16:55:38.273421Z","steps":["trace[1345169409] 'process raft request' (duration: 109.118274ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:55:41.345799Z","caller":"traceutil/trace.go:171","msg":"trace[1859617250] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"194.837568ms","start":"2026-09-19T16:55:41.150941Z","end":"2026-09-19T16:55:41.345778Z","steps":["trace[1859617250] 'process raft request' (duration: 194.699957ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:55:41.372098Z","caller":"traceutil/trace.go:171","msg":"trace[69091111] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"214.830043ms","start":"2026-09-19T16:55:41.157191Z","end":"2026-09-19T16:55:41.372021Z","steps":["trace[69091111] 'process raft request' (duration: 214.548181ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:55:41.741219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.554188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T16:55:41.741333Z","caller":"traceutil/trace.go:171","msg":"trace[1724649251] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"174.36185ms","start":"2026-09-19T16:55:41.566955Z","end":"2026-09-19T16:55:41.741317Z","steps":["trace[1724649251] 'process raft request' (duration: 54.3867ms)","trace[1724649251] 'compare' (duration: 119.386367ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T16:55:41.916166Z","caller":"traceutil/trace.go:171","msg":"trace[1645873066] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"101.510634ms","start":"2026-09-19T16:55:41.814625Z","end":"2026-09-19T16:55:41.916136Z","steps":["trace[1645873066] 'process raft request' (duration: 65.318988ms)","trace[1645873066] 'compare' (duration: 35.822584ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T16:56:24.832331Z","caller":"traceutil/trace.go:171","msg":"trace[511122261] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"196.814175ms","start":"2026-09-19T16:56:24.635493Z","end":"2026-09-19T16:56:24.832307Z","steps":["trace[511122261] 'process raft request' (duration: 196.679063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:56:49.533737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.803575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-19T16:56:49.533836Z","caller":"traceutil/trace.go:171","msg":"trace[1568948388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1452; }","duration":"120.955197ms","start":"2026-09-19T16:56:49.412859Z","end":"2026-09-19T16:56:49.533814Z","steps":["trace[1568948388] 'range keys from in-memory index tree' (duration: 120.568862ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:56:49.69363Z","caller":"traceutil/trace.go:171","msg":"trace[681028538] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"154.816867ms","start":"2026-09-19T16:56:49.538777Z","end":"2026-09-19T16:56:49.693594Z","steps":["trace[681028538] 'process raft request' (duration: 154.635924ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:57:11.222656Z","caller":"traceutil/trace.go:171","msg":"trace[515711702] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"146.368238ms","start":"2026-09-19T16:57:11.076245Z","end":"2026-09-19T16:57:11.222613Z","steps":["trace[515711702] 'process raft request' (duration: 145.571628ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:57:16.284048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.358384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90e807f5-d74d-4aa5-a6f2-22804d291df8\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-19T16:57:16.284133Z","caller":"traceutil/trace.go:171","msg":"trace[1407350654] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-90e807f5-d74d-4aa5-a6f2-22804d291df8; range_end:; response_count:1; response_revision:1875; }","duration":"336.469895ms","start":"2026-09-19T16:57:15.94764Z","end":"2026-09-19T16:57:16.28411Z","steps":["trace[1407350654] 'range keys from in-memory index tree' (duration: 336.149251ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:57:16.284169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:57:15.947625Z","time spent":"336.535346ms","remote":"127.0.0.1:36718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90e807f5-d74d-4aa5-a6f2-22804d291df8\" "} {"level":"info","ts":"2026-09-19T16:57:16.652145Z","caller":"traceutil/trace.go:171","msg":"trace[46048028] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1951; }","duration":"131.313404ms","start":"2026-09-19T16:57:16.52081Z","end":"2026-09-19T16:57:16.652123Z","steps":["trace[46048028] 'read index received' (duration: 131.178772ms)","trace[46048028] 'applied index is now lower than readState.Index' (duration: 133.542µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T16:57:16.652206Z","caller":"traceutil/trace.go:171","msg":"trace[223259917] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"168.96537ms","start":"2026-09-19T16:57:16.483207Z","end":"2026-09-19T16:57:16.652172Z","steps":["trace[223259917] 'process raft request' (duration: 168.766927ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:57:16.652368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.520396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T16:57:16.652409Z","caller":"traceutil/trace.go:171","msg":"trace[398763352] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1877; }","duration":"131.663998ms","start":"2026-09-19T16:57:16.520733Z","end":"2026-09-19T16:57:16.652397Z","steps":["trace[398763352] 'agreement among raft nodes before linearized reading' (duration: 131.484736ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:57:16.664602Z","caller":"traceutil/trace.go:171","msg":"trace[824838006] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"104.298914ms","start":"2026-09-19T16:57:16.560282Z","end":"2026-09-19T16:57:16.664581Z","steps":["trace[824838006] 'process raft request' (duration: 104.148793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:57:29.662528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.00087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T16:57:29.662659Z","caller":"traceutil/trace.go:171","msg":"trace[992597692] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"188.473856ms","start":"2026-09-19T16:57:29.474158Z","end":"2026-09-19T16:57:29.662632Z","steps":["trace[992597692] 'process raft request' (duration: 37.044172ms)","trace[992597692] 'compare' (duration: 150.610385ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T16:57:33.258459Z","caller":"traceutil/trace.go:171","msg":"trace[1331338184] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"134.365099ms","start":"2026-09-19T16:57:33.124059Z","end":"2026-09-19T16:57:33.258424Z","steps":["trace[1331338184] 'process raft request' (duration: 134.136926ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:57:56.298325Z","caller":"traceutil/trace.go:171","msg":"trace[1685259121] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"117.749792ms","start":"2026-09-19T16:57:56.180551Z","end":"2026-09-19T16:57:56.298301Z","steps":["trace[1685259121] 'process raft request' (duration: 117.630871ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:57:57.128813Z","caller":"traceutil/trace.go:171","msg":"trace[1516282297] linearizableReadLoop","detail":"{readStateIndex:2232; appliedIndex:2231; }","duration":"167.986743ms","start":"2026-09-19T16:57:56.960751Z","end":"2026-09-19T16:57:57.128738Z","steps":["trace[1516282297] 'read index received' (duration: 154.849401ms)","trace[1516282297] 'applied index is now lower than readState.Index' (duration: 13.136362ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T16:57:57.128836Z","caller":"traceutil/trace.go:171","msg":"trace[868346173] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"218.364819ms","start":"2026-09-19T16:57:56.910426Z","end":"2026-09-19T16:57:57.12879Z","steps":["trace[868346173] 'process raft request' (duration: 205.153127ms)","trace[868346173] 'compare' (duration: 13.054631ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T16:57:57.128994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.203485ms","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-09-19T16:57:57.129035Z","caller":"traceutil/trace.go:171","msg":"trace[999818312] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2148; }","duration":"168.319896ms","start":"2026-09-19T16:57:56.960703Z","end":"2026-09-19T16:57:57.129022Z","steps":["trace[999818312] 'agreement among raft nodes before linearized reading' (duration: 168.217134ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:57:57.519255Z","caller":"traceutil/trace.go:171","msg":"trace[1908430064] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"161.965031ms","start":"2026-09-19T16:57:57.357264Z","end":"2026-09-19T16:57:57.519229Z","steps":["trace[1908430064] 'process raft request' (duration: 161.799549ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:57:57.580871Z","caller":"traceutil/trace.go:171","msg":"trace[526649896] linearizableReadLoop","detail":"{readStateIndex:2234; appliedIndex:2232; }","duration":"213.83188ms","start":"2026-09-19T16:57:57.367022Z","end":"2026-09-19T16:57:57.580853Z","steps":["trace[526649896] 'read index received' (duration: 152.060722ms)","trace[526649896] 'applied index is now lower than readState.Index' (duration: 61.770238ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T16:57:57.581Z","caller":"traceutil/trace.go:171","msg":"trace[888250057] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"221.650569ms","start":"2026-09-19T16:57:57.359339Z","end":"2026-09-19T16:57:57.580989Z","steps":["trace[888250057] 'process raft request' (duration: 221.442397ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:57:57.581228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.116333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T16:57:57.581268Z","caller":"traceutil/trace.go:171","msg":"trace[386086787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2150; }","duration":"214.271825ms","start":"2026-09-19T16:57:57.366986Z","end":"2026-09-19T16:57:57.581258Z","steps":["trace[386086787] 'agreement among raft nodes before linearized reading' (duration: 214.102553ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:57:57.581742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.032542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-19T16:57:57.581915Z","caller":"traceutil/trace.go:171","msg":"trace[1332353404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2150; }","duration":"102.216604ms","start":"2026-09-19T16:57:57.479641Z","end":"2026-09-19T16:57:57.581858Z","steps":["trace[1332353404] 'agreement among raft nodes before linearized reading' (duration: 101.645048ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:57:57.889378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.620999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T16:57:57.88949Z","caller":"traceutil/trace.go:171","msg":"trace[1960073425] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2234; }","duration":"208.936581ms","start":"2026-09-19T16:57:57.680535Z","end":"2026-09-19T16:57:57.889472Z","steps":["trace[1960073425] 'read index received' (duration: 55.22µs)","trace[1960073425] 'applied index is now lower than readState.Index' (duration: 208.880351ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T16:57:57.889567Z","caller":"traceutil/trace.go:171","msg":"trace[546449836] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"302.788501ms","start":"2026-09-19T16:57:57.586767Z","end":"2026-09-19T16:57:57.889555Z","steps":["trace[546449836] 'process raft request' (duration: 59.90294ms)","trace[546449836] 'compare' (duration: 242.498147ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T16:57:57.889628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:57:57.586746Z","time spent":"302.849272ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T16:57:57.889837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.311646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T16:57:57.889867Z","caller":"traceutil/trace.go:171","msg":"trace[850842643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2151; }","duration":"209.389936ms","start":"2026-09-19T16:57:57.680468Z","end":"2026-09-19T16:57:57.889858Z","steps":["trace[850842643] 'agreement among raft nodes before linearized reading' (duration: 209.276085ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:58:47.906741Z","caller":"traceutil/trace.go:171","msg":"trace[1926513122] transaction","detail":"{read_only:false; response_revision:2527; number_of_response:1; }","duration":"213.083379ms","start":"2026-09-19T16:58:47.693631Z","end":"2026-09-19T16:58:47.906714Z","steps":["trace[1926513122] 'process raft request' (duration: 212.934348ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:58:47.93046Z","caller":"traceutil/trace.go:171","msg":"trace[933835833] transaction","detail":"{read_only:false; response_revision:2528; number_of_response:1; }","duration":"162.64013ms","start":"2026-09-19T16:58:47.76779Z","end":"2026-09-19T16:58:47.930431Z","steps":["trace[933835833] 'process raft request' (duration: 162.499869ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:59:24.627241Z","caller":"traceutil/trace.go:171","msg":"trace[1883154926] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"201.450164ms","start":"2026-09-19T16:59:24.425746Z","end":"2026-09-19T16:59:24.627197Z","steps":["trace[1883154926] 'process raft request' (duration: 180.521616ms)","trace[1883154926] 'compare' (duration: 20.746646ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T17:03:54.011614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.332522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-19T17:03:54.011704Z","caller":"traceutil/trace.go:171","msg":"trace[367152770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4838; }","duration":"161.457823ms","start":"2026-09-19T17:03:53.850227Z","end":"2026-09-19T17:03:54.011684Z","steps":["trace[367152770] 'range keys from in-memory index tree' (duration: 161.167911ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T17:03:54.304659Z","caller":"traceutil/trace.go:171","msg":"trace[1268086223] linearizableReadLoop","detail":"{readStateIndex:5032; appliedIndex:5029; }","duration":"111.355922ms","start":"2026-09-19T17:03:54.193281Z","end":"2026-09-19T17:03:54.304636Z","steps":["trace[1268086223] 'read index received' (duration: 16.483462ms)","trace[1268086223] 'applied index is now lower than readState.Index' (duration: 94.87158ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T17:03:54.304756Z","caller":"traceutil/trace.go:171","msg":"trace[732172806] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"124.80759ms","start":"2026-09-19T17:03:54.179921Z","end":"2026-09-19T17:03:54.304729Z","steps":["trace[732172806] 'process raft request' (duration: 123.849112ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T17:03:54.304877Z","caller":"traceutil/trace.go:171","msg":"trace[292605757] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"124.600458ms","start":"2026-09-19T17:03:54.180247Z","end":"2026-09-19T17:03:54.304848Z","steps":["trace[292605757] 'process raft request' (duration: 124.305875ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T17:03:54.305485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.603024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-19T17:03:54.305536Z","caller":"traceutil/trace.go:171","msg":"trace[660537837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4844; }","duration":"112.276909ms","start":"2026-09-19T17:03:54.193247Z","end":"2026-09-19T17:03:54.305524Z","steps":["trace[660537837] 'agreement among raft nodes before linearized reading' (duration: 111.539713ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T17:03:59.930599Z","caller":"traceutil/trace.go:171","msg":"trace[1279487065] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"355.056273ms","start":"2026-09-19T17:03:59.575512Z","end":"2026-09-19T17:03:59.930568Z","steps":["trace[1279487065] 'process raft request' (duration: 354.897152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T17:03:59.9309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T17:03:59.575491Z","time spent":"355.202185ms","remote":"127.0.0.1:36612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":880,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T17:04:00.068195Z","caller":"traceutil/trace.go:171","msg":"trace[962584765] linearizableReadLoop","detail":"{readStateIndex:5129; appliedIndex:5128; }","duration":"291.898267ms","start":"2026-09-19T17:03:59.776275Z","end":"2026-09-19T17:04:00.068173Z","steps":["trace[962584765] 'read index received' (duration: 154.293686ms)","trace[962584765] 'applied index is now lower than readState.Index' (duration: 137.603731ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T17:04:00.068369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.070908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T17:04:00.068407Z","caller":"traceutil/trace.go:171","msg":"trace[681178437] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:4939; }","duration":"292.169489ms","start":"2026-09-19T17:03:59.776226Z","end":"2026-09-19T17:04:00.068395Z","steps":["trace[681178437] 'agreement among raft nodes before linearized reading' (duration: 292.080578ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T17:04:00.06848Z","caller":"traceutil/trace.go:171","msg":"trace[1562659545] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"486.190192ms","start":"2026-09-19T17:03:59.582045Z","end":"2026-09-19T17:04:00.068236Z","steps":["trace[1562659545] 'process raft request' (duration: 485.989231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T17:04:00.068747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.99771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-09-19T17:04:00.068763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.047476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-19T17:04:00.068814Z","caller":"traceutil/trace.go:171","msg":"trace[1752991257] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4939; }","duration":"203.122816ms","start":"2026-09-19T17:03:59.865677Z","end":"2026-09-19T17:04:00.068799Z","steps":["trace[1752991257] 'agreement among raft nodes before linearized reading' (duration: 202.987225ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T17:04:00.068893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T17:03:59.582031Z","time spent":"486.641286ms","remote":"127.0.0.1:36718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3748,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T17:04:00.068587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.775788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bd4f288f-3f59-475c-ad1b-d50e5f6d3553\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-09-19T17:04:00.069367Z","caller":"traceutil/trace.go:171","msg":"trace[866567614] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bd4f288f-3f59-475c-ad1b-d50e5f6d3553; range_end:; response_count:1; response_revision:4939; }","duration":"135.533735ms","start":"2026-09-19T17:03:59.933741Z","end":"2026-09-19T17:04:00.069275Z","steps":["trace[866567614] 'agreement among raft nodes before linearized reading' (duration: 134.774069ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T17:04:00.068803Z","caller":"traceutil/trace.go:171","msg":"trace[1938815366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4939; }","duration":"225.089402ms","start":"2026-09-19T17:03:59.8437Z","end":"2026-09-19T17:04:00.06879Z","steps":["trace[1938815366] 'agreement among raft nodes before linearized reading' (duration: 224.721319ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T17:04:13.3259Z","caller":"traceutil/trace.go:171","msg":"trace[569511241] linearizableReadLoop","detail":"{readStateIndex:5301; appliedIndex:5300; }","duration":"390.564907ms","start":"2026-09-19T17:04:12.935313Z","end":"2026-09-19T17:04:13.325878Z","steps":["trace[569511241] 'read index received' (duration: 390.480596ms)","trace[569511241] 'applied index is now lower than readState.Index' (duration: 83.201µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T17:04:13.325996Z","caller":"traceutil/trace.go:171","msg":"trace[259863510] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"415.841499ms","start":"2026-09-19T17:04:12.910111Z","end":"2026-09-19T17:04:13.325952Z","steps":["trace[259863510] 'process raft request' (duration: 415.611187ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T17:04:13.326132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.798888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T17:04:13.326175Z","caller":"traceutil/trace.go:171","msg":"trace[34794305] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5106; }","duration":"390.9027ms","start":"2026-09-19T17:04:12.93526Z","end":"2026-09-19T17:04:13.326163Z","steps":["trace[34794305] 'agreement among raft nodes before linearized reading' (duration: 390.760159ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T17:04:13.326183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T17:04:12.91006Z","time spent":"416.040321ms","remote":"127.0.0.1:36612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":901,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T17:04:13.326205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T17:04:12.935242Z","time spent":"390.9541ms","remote":"127.0.0.1:36860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":5,"response size":31,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-09-19T17:04:13.586787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.027709ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-19T17:04:13.587657Z","caller":"traceutil/trace.go:171","msg":"trace[647605350] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"365.345196ms","start":"2026-09-19T17:04:13.222269Z","end":"2026-09-19T17:04:13.587614Z","steps":["trace[647605350] 'process raft request' (duration: 365.114614ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T17:04:13.587722Z","caller":"traceutil/trace.go:171","msg":"trace[211389469] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"292.799794ms","start":"2026-09-19T17:04:13.294884Z","end":"2026-09-19T17:04:13.587684Z","steps":["trace[211389469] 'process raft request' (duration: 292.637493ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T17:04:13.587793Z","caller":"traceutil/trace.go:171","msg":"trace[584145604] linearizableReadLoop","detail":"{readStateIndex:5304; appliedIndex:5301; }","duration":"261.773496ms","start":"2026-09-19T17:04:13.326002Z","end":"2026-09-19T17:04:13.587776Z","steps":["trace[584145604] 'read index received' (duration: 154.646888ms)","trace[584145604] 'applied index is now lower than readState.Index' (duration: 107.125108ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T17:04:13.587839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T17:04:13.222242Z","time spent":"365.485177ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T17:04:13.587905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.859559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T17:04:13.587934Z","caller":"traceutil/trace.go:171","msg":"trace[837353228] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:5109; }","duration":"491.932289ms","start":"2026-09-19T17:04:13.09599Z","end":"2026-09-19T17:04:13.587922Z","steps":["trace[837353228] 'agreement among raft nodes before linearized reading' (duration: 491.877549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T17:04:13.58797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T17:04:13.095971Z","time spent":"491.98947ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-19T17:04:13.588132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.9668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-09-19T17:04:13.58819Z","caller":"traceutil/trace.go:171","msg":"trace[1313431672] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5109; }","duration":"597.049241ms","start":"2026-09-19T17:04:12.991123Z","end":"2026-09-19T17:04:13.588172Z","steps":["trace[1313431672] 'agreement among raft nodes before linearized reading' (duration: 596.85599ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T17:04:13.588237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T17:04:12.991108Z","time spent":"597.117212ms","remote":"127.0.0.1:43692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3340,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-09-19T17:04:13.58846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.194035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T17:04:13.588675Z","caller":"traceutil/trace.go:171","msg":"trace[1978632700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5109; }","duration":"219.386497ms","start":"2026-09-19T17:04:13.369168Z","end":"2026-09-19T17:04:13.588555Z","steps":["trace[1978632700] 'agreement among raft nodes before linearized reading' (duration: 218.776112ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T17:04:13.747017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.020213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T17:04:13.747138Z","caller":"traceutil/trace.go:171","msg":"trace[220221948] linearizableReadLoop","detail":"{readStateIndex:5306; appliedIndex:5305; }","duration":"141.320382ms","start":"2026-09-19T17:04:13.605798Z","end":"2026-09-19T17:04:13.747118Z","steps":["trace[220221948] 'read index received' (duration: 17.026257ms)","trace[220221948] 'applied index is now lower than readState.Index' (duration: 124.292725ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T17:04:13.747231Z","caller":"traceutil/trace.go:171","msg":"trace[142791755] transaction","detail":"{read_only:false; response_revision:5110; number_of_response:1; }","duration":"143.912223ms","start":"2026-09-19T17:04:13.603307Z","end":"2026-09-19T17:04:13.747219Z","steps":["trace[142791755] 'process raft request' (duration: 19.601207ms)","trace[142791755] 'compare' (duration: 123.760101ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T17:04:13.747529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.713405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-09-19T17:04:13.747603Z","caller":"traceutil/trace.go:171","msg":"trace[480813121] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5110; }","duration":"141.833855ms","start":"2026-09-19T17:04:13.605752Z","end":"2026-09-19T17:04:13.747586Z","steps":["trace[480813121] 'agreement among raft nodes before linearized reading' (duration: 141.637564ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T17:04:44.686845Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2835} {"level":"info","ts":"2026-09-19T17:04:44.773835Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2835,"took":"85.668196ms","hash":2559352081,"current-db-size-bytes":52621312,"current-db-size":"53 MB","current-db-size-in-use-bytes":28418048,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-19T17:04:44.77389Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2559352081,"revision":2835,"compact-revision":-1} {"level":"info","ts":"2026-09-19T17:04:52.675187Z","caller":"traceutil/trace.go:171","msg":"trace[1978291457] transaction","detail":"{read_only:false; response_revision:5458; number_of_response:1; }","duration":"128.155296ms","start":"2026-09-19T17:04:52.547002Z","end":"2026-09-19T17:04:52.675157Z","steps":["trace[1978291457] 'process raft request' (duration: 127.941254ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T17:04:53.058141Z","caller":"traceutil/trace.go:171","msg":"trace[1268844654] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"199.789659ms","start":"2026-09-19T17:04:52.858324Z","end":"2026-09-19T17:04:53.058114Z","steps":["trace[1268844654] 'process raft request' (duration: 199.611478ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T17:04:53.069406Z","caller":"traceutil/trace.go:171","msg":"trace[247942877] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"193.326627ms","start":"2026-09-19T17:04:52.876046Z","end":"2026-09-19T17:04:53.069372Z","steps":["trace[247942877] 'process raft request' (duration: 192.995655ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T17:08:22.801737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.817169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T17:08:22.801892Z","caller":"traceutil/trace.go:171","msg":"trace[1567955177] transaction","detail":"{read_only:false; response_revision:6917; number_of_response:1; }","duration":"125.02435ms","start":"2026-09-19T17:08:22.676857Z","end":"2026-09-19T17:08:22.801881Z","steps":["trace[1567955177] 'process raft request' (duration: 19.885529ms)","trace[1567955177] 'compare' (duration: 104.189584ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T17:09:44.693447Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5411} {"level":"info","ts":"2026-09-19T17:09:44.789012Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5411,"took":"93.912651ms","hash":2419664084,"current-db-size-bytes":52621312,"current-db-size":"53 MB","current-db-size-in-use-bytes":18640896,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-19T17:09:44.789078Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2419664084,"revision":5411,"compact-revision":2835}