{"level":"warn","ts":"2026-04-09T05:57:17.64399Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T05:57:17.644087Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-04-09T05:57:17.644154Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T05:57:17.644163Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-04-09T05:57:17.644181Z","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-04-09T05:57:17.644857Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-04-09T05:57:17.644947Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-04-09T05:57:17.649954Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.78602ms"} {"level":"info","ts":"2026-04-09T05:57:17.662132Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-04-09T05:57:17.662318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T05:57:17.662359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-04-09T05:57:17.662387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T05:57:17.662408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-04-09T05:57:17.662477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-04-09T05:57:17.671335Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T05:57:17.672481Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T05:57:17.673427Z","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-04-09T05:57:17.675475Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T05:57:17.675633Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T05:57:17.675695Z","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-04-09T05:57:17.67581Z","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-04-09T05:57:17.675827Z","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-04-09T05:57:17.676976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-04-09T05:57:17.677501Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-04-09T05:57:17.679105Z","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-04-09T05:57:17.679202Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-04-09T05:57:17.679231Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-04-09T05:57:17.679392Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T05:57:17.679447Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T05:57:18.164543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T05:57:18.164635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T05:57:18.164662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-04-09T05:57:18.16468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-04-09T05:57:18.164687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-04-09T05:57:18.164701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-04-09T05:57:18.164713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-04-09T05:57:18.165947Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T05:57:18.165968Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:57:18.166261Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:57:18.166377Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:57:18.166399Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T05:57:18.166688Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T05:57:18.16997Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-04-09T05:57:18.170169Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:57:18.170293Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:57:18.170331Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:57:18.170431Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T05:57:56.205677Z","caller":"traceutil/trace.go:171","msg":"trace[2063756542] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"158.879574ms","start":"2026-04-09T05:57:56.04677Z","end":"2026-04-09T05:57:56.205649Z","steps":["trace[2063756542] 'process raft request' (duration: 158.346323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:56.633275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.147853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:57:56.633453Z","caller":"traceutil/trace.go:171","msg":"trace[796030288] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"409.446773ms","start":"2026-04-09T05:57:56.223984Z","end":"2026-04-09T05:57:56.633431Z","steps":["trace[796030288] 'process raft request' (duration: 55.35506ms)","trace[796030288] 'compare' (duration: 352.852587ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:57:56.633523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:56.223972Z","time spent":"409.521104ms","remote":"127.0.0.1:60870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:57:56.830661Z","caller":"traceutil/trace.go:171","msg":"trace[1633018050] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"566.290694ms","start":"2026-04-09T05:57:56.26427Z","end":"2026-04-09T05:57:56.830561Z","steps":["trace[1633018050] 'process raft request' (duration: 474.257714ms)","trace[1633018050] 'compare' (duration: 91.56264ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:57:56.830749Z","caller":"traceutil/trace.go:171","msg":"trace[1617238838] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"544.97991ms","start":"2026-04-09T05:57:56.285677Z","end":"2026-04-09T05:57:56.830657Z","steps":["trace[1617238838] 'read index received' (duration: 452.868147ms)","trace[1617238838] 'applied index is now lower than readState.Index' (duration: 92.099663ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:57:56.83086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:56.264248Z","time spent":"566.521059ms","remote":"127.0.0.1:32868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T05:57:56.835504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.800643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T05:57:56.835512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.022279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-09T05:57:56.835699Z","caller":"traceutil/trace.go:171","msg":"trace[1564841675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"550.058678ms","start":"2026-04-09T05:57:56.285613Z","end":"2026-04-09T05:57:56.835671Z","steps":["trace[1564841675] 'agreement among raft nodes before linearized reading' (duration: 545.293137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:56.83575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.881163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-09T05:57:56.835578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.143219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gg4fw\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-09T05:57:56.835948Z","caller":"traceutil/trace.go:171","msg":"trace[629503714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"104.253941ms","start":"2026-04-09T05:57:56.731635Z","end":"2026-04-09T05:57:56.835889Z","steps":["trace[629503714] 'agreement among raft nodes before linearized reading' (duration: 103.814172ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:56.835996Z","caller":"traceutil/trace.go:171","msg":"trace[938247912] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gg4fw; range_end:; response_count:1; response_revision:579; }","duration":"304.608619ms","start":"2026-04-09T05:57:56.531375Z","end":"2026-04-09T05:57:56.835984Z","steps":["trace[938247912] 'agreement among raft nodes before linearized reading' (duration: 304.123499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:56.835962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:56.285579Z","time spent":"550.365244ms","remote":"127.0.0.1:60632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T05:57:56.836051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:56.531352Z","time spent":"304.68602ms","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-gg4fw\" "} {"level":"info","ts":"2026-04-09T05:57:56.83579Z","caller":"traceutil/trace.go:171","msg":"trace[1730628958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"398.361606ms","start":"2026-04-09T05:57:56.437402Z","end":"2026-04-09T05:57:56.835764Z","steps":["trace[1730628958] 'agreement among raft nodes before linearized reading' (duration: 397.943897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:56.836309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:56.437383Z","time spent":"398.889479ms","remote":"127.0.0.1:60870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T05:58:12.865666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.209388ms","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-04-09T05:58:12.86578Z","caller":"traceutil/trace.go:171","msg":"trace[1984690976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"149.410513ms","start":"2026-04-09T05:58:12.716347Z","end":"2026-04-09T05:58:12.865758Z","steps":["trace[1984690976] 'range keys from in-memory index tree' (duration: 148.963882ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:39.903159Z","caller":"traceutil/trace.go:171","msg":"trace[1874596446] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"127.65423ms","start":"2026-04-09T05:58:39.775476Z","end":"2026-04-09T05:58:39.903131Z","steps":["trace[1874596446] 'process raft request' (duration: 127.490137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:30.515639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.518302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:59:30.515866Z","caller":"traceutil/trace.go:171","msg":"trace[557280462] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"345.271668ms","start":"2026-04-09T05:59:30.17057Z","end":"2026-04-09T05:59:30.515842Z","steps":["trace[557280462] 'process raft request' (duration: 62.438759ms)","trace[557280462] 'compare' (duration: 282.322748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:59:30.515933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:30.17054Z","time spent":"345.367299ms","remote":"127.0.0.1:60870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:59:30.516272Z","caller":"traceutil/trace.go:171","msg":"trace[1439496654] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"344.242756ms","start":"2026-04-09T05:59:30.171986Z","end":"2026-04-09T05:59:30.516229Z","steps":["trace[1439496654] 'process raft request' (duration: 343.790856ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:30.516422Z","caller":"traceutil/trace.go:171","msg":"trace[588729124] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"344.368058ms","start":"2026-04-09T05:59:30.17199Z","end":"2026-04-09T05:59:30.516358Z","steps":["trace[588729124] 'process raft request' (duration: 344.129013ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:30.516741Z","caller":"traceutil/trace.go:171","msg":"trace[1844546171] linearizableReadLoop","detail":"{readStateIndex:1896; appliedIndex:1890; }","duration":"336.933073ms","start":"2026-04-09T05:59:30.179773Z","end":"2026-04-09T05:59:30.516706Z","steps":["trace[1844546171] 'read index received' (duration: 53.276207ms)","trace[1844546171] 'applied index is now lower than readState.Index' (duration: 283.655746ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:59:30.516782Z","caller":"traceutil/trace.go:171","msg":"trace[1084164992] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"340.58408ms","start":"2026-04-09T05:59:30.176179Z","end":"2026-04-09T05:59:30.516763Z","steps":["trace[1084164992] 'process raft request' (duration: 340.432596ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:30.516809Z","caller":"traceutil/trace.go:171","msg":"trace[1314074709] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"344.563063ms","start":"2026-04-09T05:59:30.172213Z","end":"2026-04-09T05:59:30.516777Z","steps":["trace[1314074709] 'process raft request' (duration: 344.224735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:30.516916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:30.176166Z","time spent":"340.691762ms","remote":"127.0.0.1:60780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:59:30.51653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:30.171965Z","time spent":"344.4123ms","remote":"127.0.0.1:60774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:59:30.516987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:30.172184Z","time spent":"344.704485ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:59:30.517063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:30.171963Z","time spent":"344.663645ms","remote":"127.0.0.1:60774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:59:30.516523Z","caller":"traceutil/trace.go:171","msg":"trace[162180112] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"344.404949ms","start":"2026-04-09T05:59:30.1721Z","end":"2026-04-09T05:59:30.516505Z","steps":["trace[162180112] 'process raft request' (duration: 344.135144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:30.517089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.28014ms","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-04-09T05:59:30.51745Z","caller":"traceutil/trace.go:171","msg":"trace[1595863239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1821; }","duration":"337.70933ms","start":"2026-04-09T05:59:30.17973Z","end":"2026-04-09T05:59:30.517439Z","steps":["trace[1595863239] 'agreement among raft nodes before linearized reading' (duration: 337.168029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:30.517471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:30.172094Z","time spent":"345.33515ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:59:30.517508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:30.179718Z","time spent":"337.778931ms","remote":"127.0.0.1:60870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-09T05:59:39.384583Z","caller":"traceutil/trace.go:171","msg":"trace[642316747] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"150.433144ms","start":"2026-04-09T05:59:39.234107Z","end":"2026-04-09T05:59:39.38454Z","steps":["trace[642316747] 'process raft request' (duration: 150.188749ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:39.557148Z","caller":"traceutil/trace.go:171","msg":"trace[1596385330] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1975; }","duration":"143.602761ms","start":"2026-04-09T05:59:39.413512Z","end":"2026-04-09T05:59:39.557115Z","steps":["trace[1596385330] 'read index received' (duration: 131.549738ms)","trace[1596385330] 'applied index is now lower than readState.Index' (duration: 12.051693ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:59:39.55725Z","caller":"traceutil/trace.go:171","msg":"trace[131079355] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"156.648023ms","start":"2026-04-09T05:59:39.400558Z","end":"2026-04-09T05:59:39.557206Z","steps":["trace[131079355] 'process raft request' (duration: 144.484168ms)","trace[131079355] 'compare' (duration: 11.931661ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:59:39.557483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.936478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-09T05:59:39.557589Z","caller":"traceutil/trace.go:171","msg":"trace[654854864] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1899; }","duration":"144.057991ms","start":"2026-04-09T05:59:39.413506Z","end":"2026-04-09T05:59:39.557564Z","steps":["trace[654854864] 'agreement among raft nodes before linearized reading' (duration: 143.767725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:15.979111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.284562ms","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-04-09T06:00:15.979264Z","caller":"traceutil/trace.go:171","msg":"trace[2071898272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2116; }","duration":"160.503007ms","start":"2026-04-09T06:00:15.818732Z","end":"2026-04-09T06:00:15.979235Z","steps":["trace[2071898272] 'range keys from in-memory index tree' (duration: 160.023706ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:39.674505Z","caller":"traceutil/trace.go:171","msg":"trace[1140063800] transaction","detail":"{read_only:false; response_revision:2619; number_of_response:1; }","duration":"138.030208ms","start":"2026-04-09T06:01:39.536435Z","end":"2026-04-09T06:01:39.674465Z","steps":["trace[1140063800] 'process raft request' (duration: 137.773543ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:05:51.615024Z","caller":"traceutil/trace.go:171","msg":"trace[757842035] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"152.619415ms","start":"2026-04-09T06:05:51.462345Z","end":"2026-04-09T06:05:51.614965Z","steps":["trace[757842035] 'process raft request' (duration: 118.955282ms)","trace[757842035] 'compare' (duration: 33.452527ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:05:53.244527Z","caller":"traceutil/trace.go:171","msg":"trace[1919203037] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"102.627474ms","start":"2026-04-09T06:05:53.141832Z","end":"2026-04-09T06:05:53.24446Z","steps":["trace[1919203037] 'process raft request' (duration: 101.831487ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:05:59.259107Z","caller":"traceutil/trace.go:171","msg":"trace[1874672970] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"103.267078ms","start":"2026-04-09T06:05:59.155797Z","end":"2026-04-09T06:05:59.259064Z","steps":["trace[1874672970] 'process raft request' (duration: 103.042833ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:05:59.259151Z","caller":"traceutil/trace.go:171","msg":"trace[638752201] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"103.279168ms","start":"2026-04-09T06:05:59.155825Z","end":"2026-04-09T06:05:59.259104Z","steps":["trace[638752201] 'process raft request' (duration: 103.063754ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:05:59.259189Z","caller":"traceutil/trace.go:171","msg":"trace[866593519] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"103.745798ms","start":"2026-04-09T06:05:59.155421Z","end":"2026-04-09T06:05:59.259167Z","steps":["trace[866593519] 'process raft request' (duration: 95.946523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:06:04.205818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.829323ms","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-04-09T06:06:04.205915Z","caller":"traceutil/trace.go:171","msg":"trace[1816890883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5101; }","duration":"190.991166ms","start":"2026-04-09T06:06:04.014903Z","end":"2026-04-09T06:06:04.205894Z","steps":["trace[1816890883] 'range keys from in-memory index tree' (duration: 190.596198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:06:11.605269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.693417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T06:06:11.60537Z","caller":"traceutil/trace.go:171","msg":"trace[354716363] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:5274; }","duration":"187.862501ms","start":"2026-04-09T06:06:11.417486Z","end":"2026-04-09T06:06:11.605349Z","steps":["trace[354716363] 'range keys from in-memory index tree' (duration: 187.522114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:06:11.605298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.75751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-26859ee8-f901-4011-9f8c-ef76ca9e1bfc\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-04-09T06:06:11.605444Z","caller":"traceutil/trace.go:171","msg":"trace[1358692611] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-26859ee8-f901-4011-9f8c-ef76ca9e1bfc; range_end:; response_count:1; response_revision:5274; }","duration":"197.970505ms","start":"2026-04-09T06:06:11.407447Z","end":"2026-04-09T06:06:11.605418Z","steps":["trace[1358692611] 'range keys from in-memory index tree' (duration: 197.629327ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:06:11.776182Z","caller":"traceutil/trace.go:171","msg":"trace[301502226] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"168.763916ms","start":"2026-04-09T06:06:11.607378Z","end":"2026-04-09T06:06:11.776141Z","steps":["trace[301502226] 'process raft request' (duration: 165.229062ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:06:11.811275Z","caller":"traceutil/trace.go:171","msg":"trace[55739667] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"127.062672ms","start":"2026-04-09T06:06:11.684187Z","end":"2026-04-09T06:06:11.81125Z","steps":["trace[55739667] 'process raft request' (duration: 126.835018ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:06:13.543314Z","caller":"traceutil/trace.go:171","msg":"trace[1929566806] linearizableReadLoop","detail":"{readStateIndex:5486; appliedIndex:5485; }","duration":"114.260841ms","start":"2026-04-09T06:06:13.42902Z","end":"2026-04-09T06:06:13.543281Z","steps":["trace[1929566806] 'read index received' (duration: 95.140106ms)","trace[1929566806] 'applied index is now lower than readState.Index' (duration: 19.119495ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:06:13.5434Z","caller":"traceutil/trace.go:171","msg":"trace[1675624690] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"117.177543ms","start":"2026-04-09T06:06:13.426163Z","end":"2026-04-09T06:06:13.54334Z","steps":["trace[1675624690] 'process raft request' (duration: 98.074698ms)","trace[1675624690] 'compare' (duration: 18.915791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:06:13.543775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.707461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-26859ee8-f901-4011-9f8c-ef76ca9e1bfc\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-04-09T06:06:13.543911Z","caller":"traceutil/trace.go:171","msg":"trace[1302134170] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-26859ee8-f901-4011-9f8c-ef76ca9e1bfc; range_end:; response_count:1; response_revision:5290; }","duration":"114.909665ms","start":"2026-04-09T06:06:13.428971Z","end":"2026-04-09T06:06:13.54388Z","steps":["trace[1302134170] 'agreement among raft nodes before linearized reading' (duration: 114.520187ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:18.200101Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2820} {"level":"info","ts":"2026-04-09T06:07:18.26788Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2820,"took":"66.270511ms","hash":2753008245,"current-db-size-bytes":57540608,"current-db-size":"58 MB","current-db-size-in-use-bytes":32530432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-09T06:07:18.267953Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2753008245,"revision":2820,"compact-revision":-1} {"level":"info","ts":"2026-04-09T06:12:01.664563Z","caller":"traceutil/trace.go:171","msg":"trace[1016266591] linearizableReadLoop","detail":"{readStateIndex:8295; appliedIndex:8294; }","duration":"161.145581ms","start":"2026-04-09T06:12:01.50339Z","end":"2026-04-09T06:12:01.664536Z","steps":["trace[1016266591] 'read index received' (duration: 161.020577ms)","trace[1016266591] 'applied index is now lower than readState.Index' (duration: 123.384µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:12:01.664798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.380006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-09T06:12:01.664846Z","caller":"traceutil/trace.go:171","msg":"trace[799743146] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8012; }","duration":"161.485458ms","start":"2026-04-09T06:12:01.503346Z","end":"2026-04-09T06:12:01.664831Z","steps":["trace[799743146] 'agreement among raft nodes before linearized reading' (duration: 161.301994ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:12:13.847106Z","caller":"traceutil/trace.go:171","msg":"trace[857521695] transaction","detail":"{read_only:false; response_revision:8079; number_of_response:1; }","duration":"102.613601ms","start":"2026-04-09T06:12:13.744441Z","end":"2026-04-09T06:12:13.847054Z","steps":["trace[857521695] 'process raft request' (duration: 102.379726ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:12:14.168752Z","caller":"traceutil/trace.go:171","msg":"trace[716346265] transaction","detail":"{read_only:false; response_revision:8081; number_of_response:1; }","duration":"316.847106ms","start":"2026-04-09T06:12:13.851877Z","end":"2026-04-09T06:12:14.168724Z","steps":["trace[716346265] 'process raft request' (duration: 268.32471ms)","trace[716346265] 'compare' (duration: 48.082177ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:12:14.168878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:12:13.851854Z","time spent":"316.957348ms","remote":"127.0.0.1:60870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:12:18.208082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5907} {"level":"info","ts":"2026-04-09T06:12:18.291019Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5907,"took":"80.846661ms","hash":811469777,"current-db-size-bytes":57540608,"current-db-size":"58 MB","current-db-size-in-use-bytes":26497024,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-09T06:12:18.291085Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":811469777,"revision":5907,"compact-revision":2820} {"level":"info","ts":"2026-04-09T06:12:41.272474Z","caller":"traceutil/trace.go:171","msg":"trace[23838320] linearizableReadLoop","detail":"{readStateIndex:8572; appliedIndex:8571; }","duration":"225.676766ms","start":"2026-04-09T06:12:41.046767Z","end":"2026-04-09T06:12:41.272443Z","steps":["trace[23838320] 'read index received' (duration: 225.551083ms)","trace[23838320] 'applied index is now lower than readState.Index' (duration: 124.583µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:12:41.272639Z","caller":"traceutil/trace.go:171","msg":"trace[1965185252] transaction","detail":"{read_only:false; response_revision:8279; number_of_response:1; }","duration":"274.484479ms","start":"2026-04-09T06:12:40.998129Z","end":"2026-04-09T06:12:41.272614Z","steps":["trace[1965185252] 'process raft request' (duration: 274.118442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:12:41.272931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.109585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T06:12:41.273064Z","caller":"traceutil/trace.go:171","msg":"trace[209110803] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:8279; }","duration":"226.31431ms","start":"2026-04-09T06:12:41.046721Z","end":"2026-04-09T06:12:41.273035Z","steps":["trace[209110803] 'agreement among raft nodes before linearized reading' (duration: 226.034043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:12:41.27295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.706291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9vhgz\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-09T06:12:41.273152Z","caller":"traceutil/trace.go:171","msg":"trace[1425392800] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9vhgz; range_end:; response_count:1; response_revision:8279; }","duration":"117.985088ms","start":"2026-04-09T06:12:41.155145Z","end":"2026-04-09T06:12:41.27313Z","steps":["trace[1425392800] 'agreement among raft nodes before linearized reading' (duration: 117.569198ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:12:54.769169Z","caller":"traceutil/trace.go:171","msg":"trace[107173344] linearizableReadLoop","detail":"{readStateIndex:8689; appliedIndex:8688; }","duration":"206.838318ms","start":"2026-04-09T06:12:54.56231Z","end":"2026-04-09T06:12:54.769149Z","steps":["trace[107173344] 'read index received' (duration: 174.416633ms)","trace[107173344] 'applied index is now lower than readState.Index' (duration: 32.420635ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:12:54.769362Z","caller":"traceutil/trace.go:171","msg":"trace[1216199164] transaction","detail":"{read_only:false; response_revision:8391; number_of_response:1; }","duration":"231.183073ms","start":"2026-04-09T06:12:54.53817Z","end":"2026-04-09T06:12:54.769353Z","steps":["trace[1216199164] 'process raft request' (duration: 198.543962ms)","trace[1216199164] 'compare' (duration: 32.352255ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:12:54.76957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.247647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-09T06:12:54.769614Z","caller":"traceutil/trace.go:171","msg":"trace[1086111229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8391; }","duration":"207.341589ms","start":"2026-04-09T06:12:54.562264Z","end":"2026-04-09T06:12:54.769606Z","steps":["trace[1086111229] 'agreement among raft nodes before linearized reading' (duration: 207.223136ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:12:55.515435Z","caller":"traceutil/trace.go:171","msg":"trace[443380320] transaction","detail":"{read_only:false; response_revision:8398; number_of_response:1; }","duration":"115.821992ms","start":"2026-04-09T06:12:55.399574Z","end":"2026-04-09T06:12:55.515396Z","steps":["trace[443380320] 'process raft request' (duration: 115.596457ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:12:55.515458Z","caller":"traceutil/trace.go:171","msg":"trace[499245575] linearizableReadLoop","detail":"{readStateIndex:8696; appliedIndex:8696; }","duration":"108.749992ms","start":"2026-04-09T06:12:55.406664Z","end":"2026-04-09T06:12:55.515414Z","steps":["trace[499245575] 'read index received' (duration: 108.732612ms)","trace[499245575] 'applied index is now lower than readState.Index' (duration: 14.18µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:12:55.515949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.228542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9vhgz\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-09T06:12:55.51612Z","caller":"traceutil/trace.go:171","msg":"trace[728382859] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9vhgz; range_end:; response_count:1; response_revision:8398; }","duration":"109.478087ms","start":"2026-04-09T06:12:55.406592Z","end":"2026-04-09T06:12:55.51607Z","steps":["trace[728382859] 'agreement among raft nodes before linearized reading' (duration: 108.972836ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:12:56.321239Z","caller":"traceutil/trace.go:171","msg":"trace[1100893827] linearizableReadLoop","detail":"{readStateIndex:8700; appliedIndex:8699; }","duration":"157.927612ms","start":"2026-04-09T06:12:56.163293Z","end":"2026-04-09T06:12:56.32122Z","steps":["trace[1100893827] 'read index received' (duration: 157.756688ms)","trace[1100893827] 'applied index is now lower than readState.Index' (duration: 170.154µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:12:56.321389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.101256ms","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-04-09T06:12:56.321418Z","caller":"traceutil/trace.go:171","msg":"trace[1728737577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8402; }","duration":"158.205448ms","start":"2026-04-09T06:12:56.163204Z","end":"2026-04-09T06:12:56.321409Z","steps":["trace[1728737577] 'agreement among raft nodes before linearized reading' (duration: 158.089255ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:12:56.321472Z","caller":"traceutil/trace.go:171","msg":"trace[820323940] transaction","detail":"{read_only:false; response_revision:8402; number_of_response:1; }","duration":"223.768087ms","start":"2026-04-09T06:12:56.097681Z","end":"2026-04-09T06:12:56.321449Z","steps":["trace[820323940] 'process raft request' (duration: 223.390229ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:13:57.469781Z","caller":"traceutil/trace.go:171","msg":"trace[1098309537] linearizableReadLoop","detail":"{readStateIndex:9323; appliedIndex:9322; }","duration":"168.002497ms","start":"2026-04-09T06:13:57.301751Z","end":"2026-04-09T06:13:57.469754Z","steps":["trace[1098309537] 'read index received' (duration: 167.19148ms)","trace[1098309537] 'applied index is now lower than readState.Index' (duration: 809.877µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:13:57.469854Z","caller":"traceutil/trace.go:171","msg":"trace[300676863] transaction","detail":"{read_only:false; response_revision:9006; number_of_response:1; }","duration":"183.610407ms","start":"2026-04-09T06:13:57.286207Z","end":"2026-04-09T06:13:57.469817Z","steps":["trace[300676863] 'process raft request' (duration: 183.056926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:57.470074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.300753ms","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-04-09T06:13:57.470122Z","caller":"traceutil/trace.go:171","msg":"trace[306701161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9006; }","duration":"168.394046ms","start":"2026-04-09T06:13:57.301717Z","end":"2026-04-09T06:13:57.470111Z","steps":["trace[306701161] 'agreement among raft nodes before linearized reading' (duration: 168.193271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:57.470178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.614264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-04-09T06:13:57.470173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.013808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-09T06:13:57.470289Z","caller":"traceutil/trace.go:171","msg":"trace[1690806910] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:9006; }","duration":"146.737408ms","start":"2026-04-09T06:13:57.323518Z","end":"2026-04-09T06:13:57.470256Z","steps":["trace[1690806910] 'agreement among raft nodes before linearized reading' (duration: 146.492421ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:13:57.470302Z","caller":"traceutil/trace.go:171","msg":"trace[1131313216] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9006; }","duration":"168.178642ms","start":"2026-04-09T06:13:57.302097Z","end":"2026-04-09T06:13:57.470276Z","steps":["trace[1131313216] 'agreement among raft nodes before linearized reading' (duration: 167.828264ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:13:57.635903Z","caller":"traceutil/trace.go:171","msg":"trace[880564148] transaction","detail":"{read_only:false; response_revision:9009; number_of_response:1; }","duration":"160.5471ms","start":"2026-04-09T06:13:57.475328Z","end":"2026-04-09T06:13:57.635875Z","steps":["trace[880564148] 'process raft request' (duration: 160.498709ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:13:57.635942Z","caller":"traceutil/trace.go:171","msg":"trace[1507569225] transaction","detail":"{read_only:false; response_revision:9008; number_of_response:1; }","duration":"160.833496ms","start":"2026-04-09T06:13:57.475092Z","end":"2026-04-09T06:13:57.635925Z","steps":["trace[1507569225] 'process raft request' (duration: 160.593371ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:09.512242Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024d1.wal"} {"level":"info","ts":"2026-04-09T06:15:17.295972Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-09T06:15:17.300849Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-09T06:15:17.300977Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-09T06:17:18.214778Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8107} {"level":"info","ts":"2026-04-09T06:17:18.280408Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8107,"took":"63.610813ms","hash":1272945924,"current-db-size-bytes":57540608,"current-db-size":"58 MB","current-db-size-in-use-bytes":29523968,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-09T06:17:18.280461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1272945924,"revision":8107,"compact-revision":5907} {"level":"info","ts":"2026-04-09T06:22:18.221964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10536} {"level":"info","ts":"2026-04-09T06:22:18.298775Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10536,"took":"74.914442ms","hash":2174730162,"current-db-size-bytes":57540608,"current-db-size":"58 MB","current-db-size-in-use-bytes":19959808,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-09T06:22:18.298846Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2174730162,"revision":10536,"compact-revision":8107}