{"level":"warn","ts":"2026-09-18T15:25:34.427172Z","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-18T15:25:34.427263Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.107: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.107:2380","--initial-cluster=instance=https://162.253.55.107:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.107:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.107: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-18T15:25:34.427331Z","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-18T15:25:34.42734Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.107:2380"]} {"level":"info","ts":"2026-09-18T15:25:34.427356Z","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-18T15:25:34.428433Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.107:2379"]} {"level":"info","ts":"2026-09-18T15:25:34.428686Z","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.107:2380"],"listen-peer-urls":["https://162.253.55.107:2380"],"advertise-client-urls":["https://162.253.55.107:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.107:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.107: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-18T15:25:34.440759Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.695253ms"} {"level":"info","ts":"2026-09-18T15:25:34.498536Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9139094afdb348df","cluster-id":"a56c8121e8858232"} {"level":"info","ts":"2026-09-18T15:25:34.49889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T15:25:34.498965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df became follower at term 0"} {"level":"info","ts":"2026-09-18T15:25:34.49901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9139094afdb348df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T15:25:34.499072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df became follower at term 1"} {"level":"info","ts":"2026-09-18T15:25:34.499201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df switched to configuration voters=(10464405426860673247)"} {"level":"warn","ts":"2026-09-18T15:25:34.575563Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T15:25:34.589696Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T15:25:34.603368Z","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-18T15:25:34.611924Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9139094afdb348df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T15:25:34.612511Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9139094afdb348df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T15:25:34.612789Z","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-18T15:25:34.61293Z","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-18T15:25:34.61294Z","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-18T15:25:34.620018Z","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-18T15:25:34.620305Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9139094afdb348df","initial-advertise-peer-urls":["https://162.253.55.107:2380"],"listen-peer-urls":["https://162.253.55.107:2380"],"advertise-client-urls":["https://162.253.55.107:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.107:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T15:25:34.620358Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T15:25:34.620337Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.107:2380"} {"level":"info","ts":"2026-09-18T15:25:34.621523Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.107:2380"} {"level":"info","ts":"2026-09-18T15:25:34.626357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df switched to configuration voters=(10464405426860673247)"} {"level":"info","ts":"2026-09-18T15:25:34.626508Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a56c8121e8858232","local-member-id":"9139094afdb348df","added-peer-id":"9139094afdb348df","added-peer-peer-urls":["https://162.253.55.107:2380"]} {"level":"info","ts":"2026-09-18T15:25:34.800528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T15:25:34.800576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T15:25:34.800594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df received MsgPreVoteResp from 9139094afdb348df at term 1"} {"level":"info","ts":"2026-09-18T15:25:34.800607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df became candidate at term 2"} {"level":"info","ts":"2026-09-18T15:25:34.800614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df received MsgVoteResp from 9139094afdb348df at term 2"} {"level":"info","ts":"2026-09-18T15:25:34.800624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df became leader at term 2"} {"level":"info","ts":"2026-09-18T15:25:34.800632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9139094afdb348df elected leader 9139094afdb348df at term 2"} {"level":"info","ts":"2026-09-18T15:25:34.81744Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:25:34.827855Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:25:34.827856Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9139094afdb348df","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.107:2379]}","request-path":"/0/members/9139094afdb348df/attributes","cluster-id":"a56c8121e8858232","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T15:25:34.828093Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:25:34.828179Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T15:25:34.828266Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T15:25:34.830016Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.107:2379"} {"level":"info","ts":"2026-09-18T15:25:34.830318Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a56c8121e8858232","local-member-id":"9139094afdb348df","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:25:34.830398Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:25:34.830467Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:25:34.8315Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T15:26:16.211136Z","caller":"traceutil/trace.go:171","msg":"trace[354927194] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"131.374042ms","start":"2026-09-18T15:26:16.079744Z","end":"2026-09-18T15:26:16.211118Z","steps":["trace[354927194] 'process raft request' (duration: 131.264489ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:16.359823Z","caller":"traceutil/trace.go:171","msg":"trace[1965923928] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"275.212683ms","start":"2026-09-18T15:26:16.084587Z","end":"2026-09-18T15:26:16.359799Z","steps":["trace[1965923928] 'process raft request' (duration: 275.11602ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:16.360072Z","caller":"traceutil/trace.go:171","msg":"trace[1842157451] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"278.380527ms","start":"2026-09-18T15:26:16.081685Z","end":"2026-09-18T15:26:16.360066Z","steps":["trace[1842157451] 'process raft request' (duration: 277.901071ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:16.360122Z","caller":"traceutil/trace.go:171","msg":"trace[765051180] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"263.918476ms","start":"2026-09-18T15:26:16.096197Z","end":"2026-09-18T15:26:16.360116Z","steps":["trace[765051180] 'process raft request' (duration: 263.652318ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:16.360109Z","caller":"traceutil/trace.go:171","msg":"trace[1018328925] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"268.149153ms","start":"2026-09-18T15:26:16.091941Z","end":"2026-09-18T15:26:16.36009Z","steps":["trace[1018328925] 'process raft request' (duration: 267.793732ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:16.360167Z","caller":"traceutil/trace.go:171","msg":"trace[1224471634] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"264.05715ms","start":"2026-09-18T15:26:16.096061Z","end":"2026-09-18T15:26:16.360118Z","steps":["trace[1224471634] 'process raft request' (duration: 263.74323ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:16.360199Z","caller":"traceutil/trace.go:171","msg":"trace[1232236554] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"266.5246ms","start":"2026-09-18T15:26:16.093623Z","end":"2026-09-18T15:26:16.360148Z","steps":["trace[1232236554] 'process raft request' (duration: 266.136678ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:16.360308Z","caller":"traceutil/trace.go:171","msg":"trace[2050093532] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"255.600536ms","start":"2026-09-18T15:26:16.104694Z","end":"2026-09-18T15:26:16.360295Z","steps":["trace[2050093532] 'process raft request' (duration: 255.262085ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:16.360422Z","caller":"traceutil/trace.go:171","msg":"trace[1723987523] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"262.508889ms","start":"2026-09-18T15:26:16.097887Z","end":"2026-09-18T15:26:16.360395Z","steps":["trace[1723987523] 'process raft request' (duration: 261.993083ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:16.36073Z","caller":"traceutil/trace.go:171","msg":"trace[1639132393] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"244.810095ms","start":"2026-09-18T15:26:16.115911Z","end":"2026-09-18T15:26:16.360722Z","steps":["trace[1639132393] 'process raft request' (duration: 244.120333ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:16.360742Z","caller":"traceutil/trace.go:171","msg":"trace[262564499] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"234.684004ms","start":"2026-09-18T15:26:16.126044Z","end":"2026-09-18T15:26:16.360728Z","steps":["trace[262564499] 'process raft request' (duration: 234.604311ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:16.389306Z","caller":"traceutil/trace.go:171","msg":"trace[491376425] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"173.579297ms","start":"2026-09-18T15:26:16.215705Z","end":"2026-09-18T15:26:16.389284Z","steps":["trace[491376425] 'process raft request' (duration: 173.302028ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:16.389439Z","caller":"traceutil/trace.go:171","msg":"trace[604193403] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"172.314346ms","start":"2026-09-18T15:26:16.21709Z","end":"2026-09-18T15:26:16.389405Z","steps":["trace[604193403] 'process raft request' (duration: 172.069238ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:16.389805Z","caller":"traceutil/trace.go:171","msg":"trace[488975200] transaction","detail":"{read_only:false; number_of_response:1; response_revision:548; }","duration":"171.717137ms","start":"2026-09-18T15:26:16.218054Z","end":"2026-09-18T15:26:16.389771Z","steps":["trace[488975200] 'process raft request' (duration: 171.20319ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:16.389972Z","caller":"traceutil/trace.go:171","msg":"trace[508704946] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"135.200558ms","start":"2026-09-18T15:26:16.254497Z","end":"2026-09-18T15:26:16.389697Z","steps":["trace[508704946] 'process raft request' (duration: 135.019963ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:22.607344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.195465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:26:22.607505Z","caller":"traceutil/trace.go:171","msg":"trace[379978540] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"152.404618ms","start":"2026-09-18T15:26:22.455083Z","end":"2026-09-18T15:26:22.607488Z","steps":["trace[379978540] 'read index received' (duration: 40.711µs)","trace[379978540] 'applied index is now lower than readState.Index' (duration: 152.362657ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:26:22.607595Z","caller":"traceutil/trace.go:171","msg":"trace[1068166140] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"288.629581ms","start":"2026-09-18T15:26:22.318952Z","end":"2026-09-18T15:26:22.607581Z","steps":["trace[1068166140] 'process raft request' (duration: 88.623141ms)","trace[1068166140] 'compare' (duration: 199.04884ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:26:22.607757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.596636ms","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-09-18T15:26:22.607811Z","caller":"traceutil/trace.go:171","msg":"trace[1793262997] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"141.69679ms","start":"2026-09-18T15:26:22.466102Z","end":"2026-09-18T15:26:22.607799Z","steps":["trace[1793262997] 'agreement among raft nodes before linearized reading' (duration: 141.542435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:22.607981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.908204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-18T15:26:22.608002Z","caller":"traceutil/trace.go:171","msg":"trace[95108577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"152.965405ms","start":"2026-09-18T15:26:22.455031Z","end":"2026-09-18T15:26:22.607997Z","steps":["trace[95108577] 'agreement among raft nodes before linearized reading' (duration: 152.910384ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:22.608095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.363034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T15:26:22.608155Z","caller":"traceutil/trace.go:171","msg":"trace[52402878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"133.461847ms","start":"2026-09-18T15:26:22.474678Z","end":"2026-09-18T15:26:22.60814Z","steps":["trace[52402878] 'agreement among raft nodes before linearized reading' (duration: 133.277361ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:22.926229Z","caller":"traceutil/trace.go:171","msg":"trace[1115595955] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"315.842125ms","start":"2026-09-18T15:26:22.610353Z","end":"2026-09-18T15:26:22.926195Z","steps":["trace[1115595955] 'process raft request' (duration: 216.28689ms)","trace[1115595955] 'compare' (duration: 99.253846ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:26:22.926664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:26:22.610337Z","time spent":"315.97368ms","remote":"127.0.0.1:45788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:26:22.926987Z","caller":"traceutil/trace.go:171","msg":"trace[213095890] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"315.64643ms","start":"2026-09-18T15:26:22.611324Z","end":"2026-09-18T15:26:22.92697Z","steps":["trace[213095890] 'process raft request' (duration: 314.783553ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:22.927075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:26:22.611303Z","time spent":"315.728532ms","remote":"127.0.0.1:45788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:26:22.927181Z","caller":"traceutil/trace.go:171","msg":"trace[1949281891] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"310.160011ms","start":"2026-09-18T15:26:22.616965Z","end":"2026-09-18T15:26:22.927125Z","steps":["trace[1949281891] 'process raft request' (duration: 309.185401ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:22.927476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:26:22.616947Z","time spent":"310.350397ms","remote":"127.0.0.1:45788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:26:23.01118Z","caller":"traceutil/trace.go:171","msg":"trace[1584542426] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"394.193959ms","start":"2026-09-18T15:26:22.616962Z","end":"2026-09-18T15:26:23.011156Z","steps":["trace[1584542426] 'read index received' (duration: 209.750869ms)","trace[1584542426] 'applied index is now lower than readState.Index' (duration: 184.4418ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:26:23.011283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.301092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:26:23.011307Z","caller":"traceutil/trace.go:171","msg":"trace[832258417] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:648; }","duration":"394.362103ms","start":"2026-09-18T15:26:22.616935Z","end":"2026-09-18T15:26:23.011297Z","steps":["trace[832258417] 'agreement among raft nodes before linearized reading' (duration: 394.303462ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:23.011327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:26:22.616923Z","time spent":"394.399205ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-09-18T15:26:23.700815Z","caller":"traceutil/trace.go:171","msg":"trace[1759531072] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"162.085759ms","start":"2026-09-18T15:26:23.538705Z","end":"2026-09-18T15:26:23.700791Z","steps":["trace[1759531072] 'read index received' (duration: 161.343586ms)","trace[1759531072] 'applied index is now lower than readState.Index' (duration: 741.243µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:26:23.700928Z","caller":"traceutil/trace.go:171","msg":"trace[677034108] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"315.142344ms","start":"2026-09-18T15:26:23.385737Z","end":"2026-09-18T15:26:23.70088Z","steps":["trace[677034108] 'process raft request' (duration: 314.521984ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:23.700996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.263984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:26:23.701051Z","caller":"traceutil/trace.go:171","msg":"trace[307885368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"162.364627ms","start":"2026-09-18T15:26:23.538667Z","end":"2026-09-18T15:26:23.701031Z","steps":["trace[307885368] 'agreement among raft nodes before linearized reading' (duration: 162.268164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:23.70106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:26:23.385708Z","time spent":"315.276798ms","remote":"127.0.0.1:45660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:26:40.596478Z","caller":"traceutil/trace.go:171","msg":"trace[830283273] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"277.101452ms","start":"2026-09-18T15:26:40.319354Z","end":"2026-09-18T15:26:40.596456Z","steps":["trace[830283273] 'process raft request' (duration: 276.963869ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:40.596521Z","caller":"traceutil/trace.go:171","msg":"trace[1180358294] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:838; }","duration":"260.744664ms","start":"2026-09-18T15:26:40.335722Z","end":"2026-09-18T15:26:40.596467Z","steps":["trace[1180358294] 'read index received' (duration: 260.711773ms)","trace[1180358294] 'applied index is now lower than readState.Index' (duration: 18.211µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:26:40.596993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.202526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qsk9j\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-09-18T15:26:40.597137Z","caller":"traceutil/trace.go:171","msg":"trace[1636236979] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qsk9j; range_end:; response_count:1; response_revision:813; }","duration":"261.436312ms","start":"2026-09-18T15:26:40.335664Z","end":"2026-09-18T15:26:40.5971Z","steps":["trace[1636236979] 'agreement among raft nodes before linearized reading' (duration: 260.94607ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:40.771022Z","caller":"traceutil/trace.go:171","msg":"trace[1673436079] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"174.327986ms","start":"2026-09-18T15:26:40.59666Z","end":"2026-09-18T15:26:40.770988Z","steps":["trace[1673436079] 'read index received' (duration: 174.177612ms)","trace[1673436079] 'applied index is now lower than readState.Index' (duration: 149.004µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:26:40.771145Z","caller":"traceutil/trace.go:171","msg":"trace[1357381481] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"382.326352ms","start":"2026-09-18T15:26:40.388781Z","end":"2026-09-18T15:26:40.771108Z","steps":["trace[1357381481] 'process raft request' (duration: 381.932372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:40.771248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.36748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:26:40.771798Z","caller":"traceutil/trace.go:171","msg":"trace[85703315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:814; }","duration":"233.917784ms","start":"2026-09-18T15:26:40.537858Z","end":"2026-09-18T15:26:40.771775Z","steps":["trace[85703315] 'agreement among raft nodes before linearized reading' (duration: 233.344629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:40.772031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:26:40.388738Z","time spent":"382.955809ms","remote":"127.0.0.1:45726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:26:40.786115Z","caller":"traceutil/trace.go:171","msg":"trace[1888102656] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"182.276154ms","start":"2026-09-18T15:26:40.603797Z","end":"2026-09-18T15:26:40.786074Z","steps":["trace[1888102656] 'process raft request' (duration: 181.964726ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:16.618341Z","caller":"traceutil/trace.go:171","msg":"trace[1336174668] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"140.014656ms","start":"2026-09-18T15:27:16.478291Z","end":"2026-09-18T15:27:16.618305Z","steps":["trace[1336174668] 'process raft request' (duration: 128.725149ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:16.618382Z","caller":"traceutil/trace.go:171","msg":"trace[2092865156] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"139.597008ms","start":"2026-09-18T15:27:16.478743Z","end":"2026-09-18T15:27:16.61834Z","steps":["trace[2092865156] 'process raft request' (duration: 139.407224ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:33.80423Z","caller":"traceutil/trace.go:171","msg":"trace[419818255] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"118.695687ms","start":"2026-09-18T15:27:33.685506Z","end":"2026-09-18T15:27:33.804201Z","steps":["trace[419818255] 'process raft request' (duration: 118.416901ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:57.18216Z","caller":"traceutil/trace.go:171","msg":"trace[599479597] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"120.957458ms","start":"2026-09-18T15:27:57.061184Z","end":"2026-09-18T15:27:57.182142Z","steps":["trace[599479597] 'process raft request' (duration: 120.812875ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:57.561242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.080552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T15:27:57.561319Z","caller":"traceutil/trace.go:171","msg":"trace[1445482543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1864; }","duration":"120.236416ms","start":"2026-09-18T15:27:57.441066Z","end":"2026-09-18T15:27:57.561302Z","steps":["trace[1445482543] 'range keys from in-memory index tree' (duration: 119.819138ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:57.845746Z","caller":"traceutil/trace.go:171","msg":"trace[2041132860] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"280.914423ms","start":"2026-09-18T15:27:57.564813Z","end":"2026-09-18T15:27:57.845727Z","steps":["trace[2041132860] 'process raft request' (duration: 280.773651ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:57.845984Z","caller":"traceutil/trace.go:171","msg":"trace[99199847] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1918; }","duration":"275.629647ms","start":"2026-09-18T15:27:57.570332Z","end":"2026-09-18T15:27:57.845961Z","steps":["trace[99199847] 'read index received' (duration: 27.697503ms)","trace[99199847] 'applied index is now lower than readState.Index' (duration: 247.931394ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:27:57.846006Z","caller":"traceutil/trace.go:171","msg":"trace[2005032823] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"280.516617ms","start":"2026-09-18T15:27:57.565466Z","end":"2026-09-18T15:27:57.845983Z","steps":["trace[2005032823] 'process raft request' (duration: 280.237342ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:57.84604Z","caller":"traceutil/trace.go:171","msg":"trace[1365629959] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"279.268007ms","start":"2026-09-18T15:27:57.566754Z","end":"2026-09-18T15:27:57.846022Z","steps":["trace[1365629959] 'process raft request' (duration: 279.126134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:57.846214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.86636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.107\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-18T15:27:57.846241Z","caller":"traceutil/trace.go:171","msg":"trace[869187447] range","detail":"{range_begin:/registry/masterleases/162.253.55.107; range_end:; response_count:1; response_revision:1868; }","duration":"275.919481ms","start":"2026-09-18T15:27:57.570314Z","end":"2026-09-18T15:27:57.846234Z","steps":["trace[869187447] 'agreement among raft nodes before linearized reading' (duration: 275.757138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:57.847045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.524335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:9033"} {"level":"info","ts":"2026-09-18T15:27:57.847115Z","caller":"traceutil/trace.go:171","msg":"trace[1805517274] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1868; }","duration":"157.645516ms","start":"2026-09-18T15:27:57.689457Z","end":"2026-09-18T15:27:57.847103Z","steps":["trace[1805517274] 'agreement among raft nodes before linearized reading' (duration: 156.724202ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:59.951915Z","caller":"traceutil/trace.go:171","msg":"trace[1211361763] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1945; }","duration":"101.67333ms","start":"2026-09-18T15:27:59.850216Z","end":"2026-09-18T15:27:59.95189Z","steps":["trace[1211361763] 'read index received' (duration: 101.599529ms)","trace[1211361763] 'applied index is now lower than readState.Index' (duration: 72.571µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:27:59.952118Z","caller":"traceutil/trace.go:171","msg":"trace[615553493] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"202.040527ms","start":"2026-09-18T15:27:59.74997Z","end":"2026-09-18T15:27:59.952011Z","steps":["trace[615553493] 'process raft request' (duration: 201.753623ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:59.952232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.005065ms","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-09-18T15:27:59.952234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.002395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-18T15:27:59.95227Z","caller":"traceutil/trace.go:171","msg":"trace[1491811140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1891; }","duration":"102.097507ms","start":"2026-09-18T15:27:59.850163Z","end":"2026-09-18T15:27:59.952261Z","steps":["trace[1491811140] 'agreement among raft nodes before linearized reading' (duration: 101.944905ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:59.952288Z","caller":"traceutil/trace.go:171","msg":"trace[58224463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1891; }","duration":"102.089196ms","start":"2026-09-18T15:27:59.850183Z","end":"2026-09-18T15:27:59.952272Z","steps":["trace[58224463] 'agreement among raft nodes before linearized reading' (duration: 101.838622ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:59.961521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.195218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-09-18T15:27:59.961553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.646886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T15:27:59.961618Z","caller":"traceutil/trace.go:171","msg":"trace[1043059841] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1892; }","duration":"102.31696ms","start":"2026-09-18T15:27:59.859273Z","end":"2026-09-18T15:27:59.96159Z","steps":["trace[1043059841] 'agreement among raft nodes before linearized reading' (duration: 101.975264ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:27:59.961625Z","caller":"traceutil/trace.go:171","msg":"trace[852930282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1892; }","duration":"110.766267ms","start":"2026-09-18T15:27:59.850836Z","end":"2026-09-18T15:27:59.961602Z","steps":["trace[852930282] 'agreement among raft nodes before linearized reading' (duration: 110.585224ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:27:59.961784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.826224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d261fd7-1dba-45c9-986a-c9363ebe737e\" ","response":"range_response_count:1 size:3920"} {"level":"info","ts":"2026-09-18T15:27:59.961881Z","caller":"traceutil/trace.go:171","msg":"trace[417863609] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d261fd7-1dba-45c9-986a-c9363ebe737e; range_end:; response_count:1; response_revision:1892; }","duration":"106.966336ms","start":"2026-09-18T15:27:59.85489Z","end":"2026-09-18T15:27:59.961856Z","steps":["trace[417863609] 'agreement among raft nodes before linearized reading' (duration: 106.711542ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:28:05.376007Z","caller":"traceutil/trace.go:171","msg":"trace[1783863490] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"152.059332ms","start":"2026-09-18T15:28:05.223877Z","end":"2026-09-18T15:28:05.375936Z","steps":["trace[1783863490] 'process raft request' (duration: 151.874199ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:28:30.227974Z","caller":"traceutil/trace.go:171","msg":"trace[668760945] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"246.512218ms","start":"2026-09-18T15:28:29.981435Z","end":"2026-09-18T15:28:30.227947Z","steps":["trace[668760945] 'process raft request' (duration: 178.383638ms)","trace[668760945] 'compare' (duration: 67.845275ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:28:30.233574Z","caller":"traceutil/trace.go:171","msg":"trace[1989725178] linearizableReadLoop","detail":"{readStateIndex:2207; appliedIndex:2205; }","duration":"155.718685ms","start":"2026-09-18T15:28:30.07783Z","end":"2026-09-18T15:28:30.233549Z","steps":["trace[1989725178] 'read index received' (duration: 81.974343ms)","trace[1989725178] 'applied index is now lower than readState.Index' (duration: 73.743522ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:28:30.234246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.166334ms","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-18T15:28:30.234297Z","caller":"traceutil/trace.go:171","msg":"trace[1501188187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2139; }","duration":"133.255495ms","start":"2026-09-18T15:28:30.101029Z","end":"2026-09-18T15:28:30.234285Z","steps":["trace[1501188187] 'agreement among raft nodes before linearized reading' (duration: 133.055942ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:28:30.234405Z","caller":"traceutil/trace.go:171","msg":"trace[2135644053] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"177.807509ms","start":"2026-09-18T15:28:30.056579Z","end":"2026-09-18T15:28:30.234387Z","steps":["trace[2135644053] 'process raft request' (duration: 176.888725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:28:30.234639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.804131ms","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-09-18T15:28:30.234687Z","caller":"traceutil/trace.go:171","msg":"trace[1232697308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2139; }","duration":"156.876421ms","start":"2026-09-18T15:28:30.077797Z","end":"2026-09-18T15:28:30.234674Z","steps":["trace[1232697308] 'agreement among raft nodes before linearized reading' (duration: 156.736059ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:28:30.234796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.856495ms","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-09-18T15:28:30.234842Z","caller":"traceutil/trace.go:171","msg":"trace[365230745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2139; }","duration":"130.007066ms","start":"2026-09-18T15:28:30.104826Z","end":"2026-09-18T15:28:30.234833Z","steps":["trace[365230745] 'agreement among raft nodes before linearized reading' (duration: 129.688892ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:28:30.474893Z","caller":"traceutil/trace.go:171","msg":"trace[954874500] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"237.036548ms","start":"2026-09-18T15:28:30.237824Z","end":"2026-09-18T15:28:30.474861Z","steps":["trace[954874500] 'process raft request' (duration: 196.429212ms)","trace[954874500] 'compare' (duration: 40.322512ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:28:30.475368Z","caller":"traceutil/trace.go:171","msg":"trace[2127439857] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"235.609396ms","start":"2026-09-18T15:28:30.239748Z","end":"2026-09-18T15:28:30.475357Z","steps":["trace[2127439857] 'process raft request' (duration: 235.425314ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:28:30.47553Z","caller":"traceutil/trace.go:171","msg":"trace[676108846] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"236.940746ms","start":"2026-09-18T15:28:30.238571Z","end":"2026-09-18T15:28:30.475512Z","steps":["trace[676108846] 'process raft request' (duration: 236.225606ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:28:30.475333Z","caller":"traceutil/trace.go:171","msg":"trace[682147053] linearizableReadLoop","detail":"{readStateIndex:2210; appliedIndex:2207; }","duration":"200.842696ms","start":"2026-09-18T15:28:30.274478Z","end":"2026-09-18T15:28:30.475321Z","steps":["trace[682147053] 'read index received' (duration: 159.789524ms)","trace[682147053] 'applied index is now lower than readState.Index' (duration: 41.052252ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:28:30.475782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.251731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-09-18T15:28:30.475887Z","caller":"traceutil/trace.go:171","msg":"trace[699017779] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2142; }","duration":"201.441606ms","start":"2026-09-18T15:28:30.274424Z","end":"2026-09-18T15:28:30.475866Z","steps":["trace[699017779] 'agreement among raft nodes before linearized reading' (duration: 201.031569ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:28:30.643563Z","caller":"traceutil/trace.go:171","msg":"trace[1105929832] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"161.362978ms","start":"2026-09-18T15:28:30.482163Z","end":"2026-09-18T15:28:30.643525Z","steps":["trace[1105929832] 'process raft request' (duration: 156.957613ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:28:30.643683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.330477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-09-18T15:28:30.64404Z","caller":"traceutil/trace.go:171","msg":"trace[1701163240] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2143; }","duration":"114.720244ms","start":"2026-09-18T15:28:30.529303Z","end":"2026-09-18T15:28:30.644023Z","steps":["trace[1701163240] 'agreement among raft nodes before linearized reading' (duration: 114.276037ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:28:30.643469Z","caller":"traceutil/trace.go:171","msg":"trace[1416487821] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2210; }","duration":"114.109044ms","start":"2026-09-18T15:28:30.529342Z","end":"2026-09-18T15:28:30.643451Z","steps":["trace[1416487821] 'read index received' (duration: 109.606918ms)","trace[1416487821] 'applied index is now lower than readState.Index' (duration: 4.501186ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:28:30.644283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.036946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:28:30.6444Z","caller":"traceutil/trace.go:171","msg":"trace[1449117016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2143; }","duration":"106.224739ms","start":"2026-09-18T15:28:30.538168Z","end":"2026-09-18T15:28:30.644393Z","steps":["trace[1449117016] 'agreement among raft nodes before linearized reading' (duration: 106.089727ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:32:31.897432Z","caller":"traceutil/trace.go:171","msg":"trace[1981446063] linearizableReadLoop","detail":"{readStateIndex:4660; appliedIndex:4659; }","duration":"118.929983ms","start":"2026-09-18T15:32:31.778481Z","end":"2026-09-18T15:32:31.897411Z","steps":["trace[1981446063] 'read index received' (duration: 96.552003ms)","trace[1981446063] 'applied index is now lower than readState.Index' (duration: 22.37728ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:32:31.897658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.104967ms","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-18T15:32:31.897734Z","caller":"traceutil/trace.go:171","msg":"trace[1148318291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4469; }","duration":"119.234021ms","start":"2026-09-18T15:32:31.778447Z","end":"2026-09-18T15:32:31.897681Z","steps":["trace[1148318291] 'agreement among raft nodes before linearized reading' (duration: 119.067277ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:32:31.897759Z","caller":"traceutil/trace.go:171","msg":"trace[1345742492] transaction","detail":"{read_only:false; response_revision:4469; number_of_response:1; }","duration":"129.384634ms","start":"2026-09-18T15:32:31.768137Z","end":"2026-09-18T15:32:31.897522Z","steps":["trace[1345742492] 'process raft request' (duration: 106.820349ms)","trace[1345742492] 'compare' (duration: 22.35563ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:32:31.916739Z","caller":"traceutil/trace.go:171","msg":"trace[2033801621] transaction","detail":"{read_only:false; response_revision:4470; number_of_response:1; }","duration":"130.951635ms","start":"2026-09-18T15:32:31.785767Z","end":"2026-09-18T15:32:31.916719Z","steps":["trace[2033801621] 'process raft request' (duration: 130.872503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:32:31.969666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.885183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d358e5e7-25fd-42b3-af59-977ed814974e\" ","response":"range_response_count:1 size:3774"} {"level":"warn","ts":"2026-09-18T15:32:31.969729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.556126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-18T15:32:31.96976Z","caller":"traceutil/trace.go:171","msg":"trace[1919583699] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d358e5e7-25fd-42b3-af59-977ed814974e; range_end:; response_count:1; response_revision:4472; }","duration":"108.963735ms","start":"2026-09-18T15:32:31.860755Z","end":"2026-09-18T15:32:31.969719Z","steps":["trace[1919583699] 'agreement among raft nodes before linearized reading' (duration: 108.798371ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:32:31.969789Z","caller":"traceutil/trace.go:171","msg":"trace[201229459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4472; }","duration":"164.671689ms","start":"2026-09-18T15:32:31.805104Z","end":"2026-09-18T15:32:31.969776Z","steps":["trace[201229459] 'agreement among raft nodes before linearized reading' (duration: 164.479314ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:32:31.969574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.453578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-18T15:32:31.970481Z","caller":"traceutil/trace.go:171","msg":"trace[395780629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4472; }","duration":"169.38957ms","start":"2026-09-18T15:32:31.801073Z","end":"2026-09-18T15:32:31.970463Z","steps":["trace[395780629] 'agreement among raft nodes before linearized reading' (duration: 168.373105ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:32:53.496005Z","caller":"traceutil/trace.go:171","msg":"trace[722616326] linearizableReadLoop","detail":"{readStateIndex:5073; appliedIndex:5072; }","duration":"347.06622ms","start":"2026-09-18T15:32:53.148916Z","end":"2026-09-18T15:32:53.495982Z","steps":["trace[722616326] 'read index received' (duration: 346.988968ms)","trace[722616326] 'applied index is now lower than readState.Index' (duration: 76.242µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:32:53.496122Z","caller":"traceutil/trace.go:171","msg":"trace[1155762674] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"428.863051ms","start":"2026-09-18T15:32:53.067221Z","end":"2026-09-18T15:32:53.496084Z","steps":["trace[1155762674] 'process raft request' (duration: 428.615325ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:32:53.496263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:32:53.067196Z","time spent":"428.990304ms","remote":"127.0.0.1:45730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10794,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:32:53.496295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.369568ms","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-09-18T15:32:53.496363Z","caller":"traceutil/trace.go:171","msg":"trace[915256345] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4867; }","duration":"347.49035ms","start":"2026-09-18T15:32:53.148854Z","end":"2026-09-18T15:32:53.496345Z","steps":["trace[915256345] 'agreement among raft nodes before linearized reading' (duration: 347.248124ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:32:53.496403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:32:53.148835Z","time spent":"347.561832ms","remote":"127.0.0.1:45714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-09-18T15:32:53.609862Z","caller":"traceutil/trace.go:171","msg":"trace[1496019532] linearizableReadLoop","detail":"{readStateIndex:5074; appliedIndex:5073; }","duration":"113.768584ms","start":"2026-09-18T15:32:53.496068Z","end":"2026-09-18T15:32:53.609837Z","steps":["trace[1496019532] 'read index received' (duration: 77.901936ms)","trace[1496019532] 'applied index is now lower than readState.Index' (duration: 35.865858ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:32:53.60994Z","caller":"traceutil/trace.go:171","msg":"trace[1937677284] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"293.237614ms","start":"2026-09-18T15:32:53.316674Z","end":"2026-09-18T15:32:53.609912Z","steps":["trace[1937677284] 'process raft request' (duration: 257.269484ms)","trace[1937677284] 'compare' (duration: 35.780134ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:32:53.610381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.891938ms","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-09-18T15:32:53.610476Z","caller":"traceutil/trace.go:171","msg":"trace[1636696618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4868; }","duration":"426.003131ms","start":"2026-09-18T15:32:53.184452Z","end":"2026-09-18T15:32:53.610455Z","steps":["trace[1636696618] 'agreement among raft nodes before linearized reading' (duration: 425.511499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:32:53.610634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:32:53.184441Z","time spent":"426.176944ms","remote":"127.0.0.1:45788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-18T15:32:53.641181Z","caller":"traceutil/trace.go:171","msg":"trace[1623893491] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"137.35554ms","start":"2026-09-18T15:32:53.503805Z","end":"2026-09-18T15:32:53.641161Z","steps":["trace[1623893491] 'process raft request' (duration: 137.095624ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:32:53.641296Z","caller":"traceutil/trace.go:171","msg":"trace[1022088038] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"137.115595ms","start":"2026-09-18T15:32:53.504129Z","end":"2026-09-18T15:32:53.641244Z","steps":["trace[1022088038] 'process raft request' (duration: 136.977072ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:32:53.641401Z","caller":"traceutil/trace.go:171","msg":"trace[715629839] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"137.013422ms","start":"2026-09-18T15:32:53.50438Z","end":"2026-09-18T15:32:53.641393Z","steps":["trace[715629839] 'process raft request' (duration: 136.814788ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:32:53.641315Z","caller":"traceutil/trace.go:171","msg":"trace[1248037951] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"137.433872ms","start":"2026-09-18T15:32:53.503847Z","end":"2026-09-18T15:32:53.641281Z","steps":["trace[1248037951] 'process raft request' (duration: 137.206386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:32:53.641609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.824391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e84a5500-5d67-401e-9265-b9df42d4e14d\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-18T15:32:53.641677Z","caller":"traceutil/trace.go:171","msg":"trace[1242909144] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e84a5500-5d67-401e-9265-b9df42d4e14d; range_end:; response_count:1; response_revision:4872; }","duration":"137.936204ms","start":"2026-09-18T15:32:53.503721Z","end":"2026-09-18T15:32:53.641657Z","steps":["trace[1242909144] 'agreement among raft nodes before linearized reading' (duration: 137.617736ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:33:30.152713Z","caller":"traceutil/trace.go:171","msg":"trace[2098860542] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"197.050188ms","start":"2026-09-18T15:33:29.955631Z","end":"2026-09-18T15:33:30.152681Z","steps":["trace[2098860542] 'process raft request' (duration: 196.873554ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:35:34.861326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3085} {"level":"info","ts":"2026-09-18T15:35:34.967194Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3085,"took":"103.914055ms","hash":22707444,"current-db-size-bytes":58527744,"current-db-size":"58 MB","current-db-size-in-use-bytes":30986240,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-18T15:35:34.967291Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":22707444,"revision":3085,"compact-revision":-1} {"level":"info","ts":"2026-09-18T15:36:30.896073Z","caller":"traceutil/trace.go:171","msg":"trace[1702865072] transaction","detail":"{read_only:false; response_revision:6769; number_of_response:1; }","duration":"124.70903ms","start":"2026-09-18T15:36:30.771329Z","end":"2026-09-18T15:36:30.896038Z","steps":["trace[1702865072] 'process raft request' (duration: 124.516267ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:36:55.80995Z","caller":"traceutil/trace.go:171","msg":"trace[1626389810] linearizableReadLoop","detail":"{readStateIndex:7299; appliedIndex:7298; }","duration":"150.703714ms","start":"2026-09-18T15:36:55.659226Z","end":"2026-09-18T15:36:55.80993Z","steps":["trace[1626389810] 'read index received' (duration: 150.570002ms)","trace[1626389810] 'applied index is now lower than readState.Index' (duration: 132.912µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:36:55.810109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.862917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-18T15:36:55.810132Z","caller":"traceutil/trace.go:171","msg":"trace[1325593048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6986; }","duration":"150.926368ms","start":"2026-09-18T15:36:55.659198Z","end":"2026-09-18T15:36:55.810124Z","steps":["trace[1325593048] 'agreement among raft nodes before linearized reading' (duration: 150.804216ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:36:55.810354Z","caller":"traceutil/trace.go:171","msg":"trace[296064755] transaction","detail":"{read_only:false; response_revision:6986; number_of_response:1; }","duration":"164.351258ms","start":"2026-09-18T15:36:55.645998Z","end":"2026-09-18T15:36:55.810349Z","steps":["trace[296064755] 'process raft request' (duration: 163.844819ms)"],"step_count":1}