{"level":"warn","ts":"2026-04-08T13:01:29.55678Z","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-08T13:01:29.556888Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.221: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.221:2380","--initial-cluster=instance=https://162.253.55.221:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.221:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.221: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-08T13:01:29.556987Z","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-08T13:01:29.556997Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-04-08T13:01:29.557023Z","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-08T13:01:29.557802Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"]} {"level":"info","ts":"2026-04-08T13:01:29.557901Z","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://162.253.55.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.221: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-08T13:01:29.562159Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.006219ms"} {"level":"info","ts":"2026-04-08T13:01:29.568503Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c187bdee547e8fd1","cluster-id":"ee7528b898b8b18e"} {"level":"info","ts":"2026-04-08T13:01:29.568572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T13:01:29.568592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 0"} {"level":"info","ts":"2026-04-08T13:01:29.56861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c187bdee547e8fd1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T13:01:29.568621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 1"} {"level":"info","ts":"2026-04-08T13:01:29.568655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"warn","ts":"2026-04-08T13:01:29.577948Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T13:01:29.579868Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T13:01:29.581284Z","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-08T13:01:29.584165Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c187bdee547e8fd1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T13:01:29.584405Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c187bdee547e8fd1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T13:01:29.584553Z","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-08T13:01:29.584632Z","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-08T13:01:29.584647Z","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-08T13:01:29.585468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"info","ts":"2026-04-08T13:01:29.585645Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","added-peer-id":"c187bdee547e8fd1","added-peer-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-04-08T13:01:29.590092Z","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-08T13:01:29.590257Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-04-08T13:01:29.590395Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-04-08T13:01:29.590541Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c187bdee547e8fd1","initial-advertise-peer-urls":["https://162.253.55.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T13:01:29.590588Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T13:01:30.568908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T13:01:30.568991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T13:01:30.569032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgPreVoteResp from c187bdee547e8fd1 at term 1"} {"level":"info","ts":"2026-04-08T13:01:30.569054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became candidate at term 2"} {"level":"info","ts":"2026-04-08T13:01:30.569066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgVoteResp from c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-04-08T13:01:30.569081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became leader at term 2"} {"level":"info","ts":"2026-04-08T13:01:30.569107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c187bdee547e8fd1 elected leader c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-04-08T13:01:30.570776Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c187bdee547e8fd1","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.221:2379]}","request-path":"/0/members/c187bdee547e8fd1/attributes","cluster-id":"ee7528b898b8b18e","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T13:01:30.570809Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T13:01:30.570827Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T13:01:30.570968Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T13:01:30.571075Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T13:01:30.57112Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T13:01:30.572329Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T13:01:30.572689Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T13:01:30.572751Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T13:01:30.573608Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.221:2379"} {"level":"info","ts":"2026-04-08T13:01:30.574612Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-08T13:01:39.735924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.694436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:01:39.736047Z","caller":"traceutil/trace.go:171","msg":"trace[687944670] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:296; }","duration":"274.883061ms","start":"2026-04-08T13:01:39.461143Z","end":"2026-04-08T13:01:39.736026Z","steps":["trace[687944670] 'range keys from in-memory index tree' (duration: 274.612684ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:39.876913Z","caller":"traceutil/trace.go:171","msg":"trace[338566999] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"133.814447ms","start":"2026-04-08T13:01:39.743068Z","end":"2026-04-08T13:01:39.876882Z","steps":["trace[338566999] 'process raft request' (duration: 133.612871ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:40.056023Z","caller":"traceutil/trace.go:171","msg":"trace[982822366] linearizableReadLoop","detail":"{readStateIndex:306; appliedIndex:305; }","duration":"173.183306ms","start":"2026-04-08T13:01:39.882814Z","end":"2026-04-08T13:01:40.055997Z","steps":["trace[982822366] 'read index received' (duration: 96.950854ms)","trace[982822366] 'applied index is now lower than readState.Index' (duration: 76.231402ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:01:40.056173Z","caller":"traceutil/trace.go:171","msg":"trace[218346453] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"175.643513ms","start":"2026-04-08T13:01:39.880501Z","end":"2026-04-08T13:01:40.056145Z","steps":["trace[218346453] 'process raft request' (duration: 99.314459ms)","trace[218346453] 'compare' (duration: 76.064887ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:01:40.056281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.443974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-08T13:01:40.056375Z","caller":"traceutil/trace.go:171","msg":"trace[1893791413] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:298; }","duration":"173.565407ms","start":"2026-04-08T13:01:39.882772Z","end":"2026-04-08T13:01:40.056338Z","steps":["trace[1893791413] 'agreement among raft nodes before linearized reading' (duration: 173.428423ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:06.214723Z","caller":"traceutil/trace.go:171","msg":"trace[2025667869] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"106.968757ms","start":"2026-04-08T13:02:06.107722Z","end":"2026-04-08T13:02:06.214691Z","steps":["trace[2025667869] 'process raft request' (duration: 101.919759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:06.473037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.040307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:02:06.47334Z","caller":"traceutil/trace.go:171","msg":"trace[507331462] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"199.573705ms","start":"2026-04-08T13:02:06.273733Z","end":"2026-04-08T13:02:06.473307Z","steps":["trace[507331462] 'process raft request' (duration: 199.42078ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:06.473327Z","caller":"traceutil/trace.go:171","msg":"trace[1619361174] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"233.374843ms","start":"2026-04-08T13:02:06.239863Z","end":"2026-04-08T13:02:06.473238Z","steps":["trace[1619361174] 'process raft request' (duration: 56.924618ms)","trace[1619361174] 'compare' (duration: 174.886623ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:02:06.858277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:02:06.858538Z","caller":"traceutil/trace.go:171","msg":"trace[1244749767] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:597; }","duration":"160.166694ms","start":"2026-04-08T13:02:06.698356Z","end":"2026-04-08T13:02:06.858522Z","steps":["trace[1244749767] 'read index received' (duration: 135.190255ms)","trace[1244749767] 'applied index is now lower than readState.Index' (duration: 24.975539ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:06.858609Z","caller":"traceutil/trace.go:171","msg":"trace[741411474] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"271.318354ms","start":"2026-04-08T13:02:06.587266Z","end":"2026-04-08T13:02:06.858584Z","steps":["trace[741411474] 'process raft request' (duration: 271.200651ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:06.858765Z","caller":"traceutil/trace.go:171","msg":"trace[655904626] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"348.750728ms","start":"2026-04-08T13:02:06.509991Z","end":"2026-04-08T13:02:06.858742Z","steps":["trace[655904626] 'process raft request' (duration: 76.679154ms)","trace[655904626] 'compare' (duration: 271.374656ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:02:06.858791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.314106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-08T13:02:06.858759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.42207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:02:06.858831Z","caller":"traceutil/trace.go:171","msg":"trace[2145730374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"111.42798ms","start":"2026-04-08T13:02:06.747391Z","end":"2026-04-08T13:02:06.858819Z","steps":["trace[2145730374] 'agreement among raft nodes before linearized reading' (duration: 111.245465ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:06.858863Z","caller":"traceutil/trace.go:171","msg":"trace[1460870110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"160.565014ms","start":"2026-04-08T13:02:06.69827Z","end":"2026-04-08T13:02:06.858835Z","steps":["trace[1460870110] 'agreement among raft nodes before linearized reading' (duration: 160.356708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:06.85887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.509969Z","time spent":"348.834091ms","remote":"127.0.0.1:50312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:02:06.982235Z","caller":"traceutil/trace.go:171","msg":"trace[1655800275] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"119.000964ms","start":"2026-04-08T13:02:06.863209Z","end":"2026-04-08T13:02:06.98221Z","steps":["trace[1655800275] 'process raft request' (duration: 118.937602ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:06.982282Z","caller":"traceutil/trace.go:171","msg":"trace[1107835498] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"121.964514ms","start":"2026-04-08T13:02:06.860287Z","end":"2026-04-08T13:02:06.982251Z","steps":["trace[1107835498] 'process raft request' (duration: 121.313157ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:48.277554Z","caller":"traceutil/trace.go:171","msg":"trace[442590942] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"101.152102ms","start":"2026-04-08T13:02:48.176364Z","end":"2026-04-08T13:02:48.277517Z","steps":["trace[442590942] 'process raft request' (duration: 65.490255ms)","trace[442590942] 'compare' (duration: 35.533594ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:03:09.324618Z","caller":"traceutil/trace.go:171","msg":"trace[623263327] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1431; }","duration":"122.603649ms","start":"2026-04-08T13:03:09.201988Z","end":"2026-04-08T13:03:09.324591Z","steps":["trace[623263327] 'read index received' (duration: 110.564292ms)","trace[623263327] 'applied index is now lower than readState.Index' (duration: 12.037977ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:03:09.324875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.857327ms","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-08T13:03:09.324989Z","caller":"traceutil/trace.go:171","msg":"trace[1414573631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1395; }","duration":"123.020771ms","start":"2026-04-08T13:03:09.201943Z","end":"2026-04-08T13:03:09.324964Z","steps":["trace[1414573631] 'agreement among raft nodes before linearized reading' (duration: 122.737193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:34.958628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.540109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:03:34.958887Z","caller":"traceutil/trace.go:171","msg":"trace[1920648796] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"215.295954ms","start":"2026-04-08T13:03:34.743558Z","end":"2026-04-08T13:03:34.958854Z","steps":["trace[1920648796] 'process raft request' (duration: 75.443425ms)","trace[1920648796] 'compare' (duration: 139.217439ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:03:35.616591Z","caller":"traceutil/trace.go:171","msg":"trace[1365196277] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"166.278344ms","start":"2026-04-08T13:03:35.450286Z","end":"2026-04-08T13:03:35.616565Z","steps":["trace[1365196277] 'process raft request' (duration: 158.894222ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:35.616863Z","caller":"traceutil/trace.go:171","msg":"trace[1366582383] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"166.383257ms","start":"2026-04-08T13:03:35.45045Z","end":"2026-04-08T13:03:35.616833Z","steps":["trace[1366582383] 'process raft request' (duration: 166.058196ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:35.617017Z","caller":"traceutil/trace.go:171","msg":"trace[2135072828] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"158.272052ms","start":"2026-04-08T13:03:35.458708Z","end":"2026-04-08T13:03:35.61698Z","steps":["trace[2135072828] 'process raft request' (duration: 158.117017ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:51.547833Z","caller":"traceutil/trace.go:171","msg":"trace[476631315] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"148.867154ms","start":"2026-04-08T13:03:51.398941Z","end":"2026-04-08T13:03:51.547808Z","steps":["trace[476631315] 'process raft request' (duration: 148.692638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:30.150592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.435788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:04:30.150718Z","caller":"traceutil/trace.go:171","msg":"trace[1184739594] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2234; }","duration":"168.313122ms","start":"2026-04-08T13:04:29.982386Z","end":"2026-04-08T13:04:30.150699Z","steps":["trace[1184739594] 'read index received' (duration: 50.740471ms)","trace[1184739594] 'applied index is now lower than readState.Index' (duration: 117.571601ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:04:30.150748Z","caller":"traceutil/trace.go:171","msg":"trace[491612781] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"209.582157ms","start":"2026-04-08T13:04:29.941131Z","end":"2026-04-08T13:04:30.150713Z","steps":["trace[491612781] 'process raft request' (duration: 91.918652ms)","trace[491612781] 'compare' (duration: 117.286674ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:04:30.150908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.503998ms","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-08T13:04:30.151001Z","caller":"traceutil/trace.go:171","msg":"trace[539270593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2158; }","duration":"168.649863ms","start":"2026-04-08T13:04:29.98233Z","end":"2026-04-08T13:04:30.15098Z","steps":["trace[539270593] 'agreement among raft nodes before linearized reading' (duration: 168.415766ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:30.641196Z","caller":"traceutil/trace.go:171","msg":"trace[1314543794] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"137.40757ms","start":"2026-04-08T13:04:30.503767Z","end":"2026-04-08T13:04:30.641174Z","steps":["trace[1314543794] 'process raft request' (duration: 137.244646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:31.013405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.928294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:04:31.013616Z","caller":"traceutil/trace.go:171","msg":"trace[363018159] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"462.060897ms","start":"2026-04-08T13:04:30.551535Z","end":"2026-04-08T13:04:31.013596Z","steps":["trace[363018159] 'process raft request' (duration: 235.848685ms)","trace[363018159] 'compare' (duration: 225.573893ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:04:31.01369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:04:30.551508Z","time spent":"462.150089ms","remote":"127.0.0.1:50312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:04:31.013756Z","caller":"traceutil/trace.go:171","msg":"trace[1279135699] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2237; }","duration":"196.931497ms","start":"2026-04-08T13:04:30.816792Z","end":"2026-04-08T13:04:31.013723Z","steps":["trace[1279135699] 'read index received' (duration: 49.611µs)","trace[1279135699] 'applied index is now lower than readState.Index' (duration: 196.879226ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:04:31.013867Z","caller":"traceutil/trace.go:171","msg":"trace[78330592] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"449.375796ms","start":"2026-04-08T13:04:30.56438Z","end":"2026-04-08T13:04:31.013756Z","steps":["trace[78330592] 'process raft request' (duration: 449.124839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:31.014036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.228226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-08T13:04:31.014133Z","caller":"traceutil/trace.go:171","msg":"trace[104235490] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2162; }","duration":"197.370501ms","start":"2026-04-08T13:04:30.816738Z","end":"2026-04-08T13:04:31.014109Z","steps":["trace[104235490] 'agreement among raft nodes before linearized reading' (duration: 197.078162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:31.014276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:04:30.564355Z","time spent":"449.672665ms","remote":"127.0.0.1:50312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:05:11.870336Z","caller":"traceutil/trace.go:171","msg":"trace[1879860434] linearizableReadLoop","detail":"{readStateIndex:2648; appliedIndex:2647; }","duration":"192.470518ms","start":"2026-04-08T13:05:11.677845Z","end":"2026-04-08T13:05:11.870315Z","steps":["trace[1879860434] 'read index received' (duration: 192.372685ms)","trace[1879860434] 'applied index is now lower than readState.Index' (duration: 96.653µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:05:11.870372Z","caller":"traceutil/trace.go:171","msg":"trace[479894210] transaction","detail":"{read_only:false; response_revision:2556; number_of_response:1; }","duration":"211.521834ms","start":"2026-04-08T13:05:11.65883Z","end":"2026-04-08T13:05:11.870352Z","steps":["trace[479894210] 'process raft request' (duration: 211.349999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:05:11.870492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.623482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:05:11.870536Z","caller":"traceutil/trace.go:171","msg":"trace[1305772813] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2556; }","duration":"192.725185ms","start":"2026-04-08T13:05:11.6778Z","end":"2026-04-08T13:05:11.870525Z","steps":["trace[1305772813] 'agreement among raft nodes before linearized reading' (duration: 192.635133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:05:11.871271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.829623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:05:11.871363Z","caller":"traceutil/trace.go:171","msg":"trace[148249839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2557; }","duration":"174.964907ms","start":"2026-04-08T13:05:11.696375Z","end":"2026-04-08T13:05:11.87134Z","steps":["trace[148249839] 'agreement among raft nodes before linearized reading' (duration: 174.819462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:05:11.87144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.299751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T13:05:11.871493Z","caller":"traceutil/trace.go:171","msg":"trace[693218899] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2557; }","duration":"119.396095ms","start":"2026-04-08T13:05:11.75208Z","end":"2026-04-08T13:05:11.871477Z","steps":["trace[693218899] 'agreement among raft nodes before linearized reading' (duration: 119.274071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:05:45.84713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.774215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:05:45.847282Z","caller":"traceutil/trace.go:171","msg":"trace[1881819302] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"199.268841ms","start":"2026-04-08T13:05:45.647995Z","end":"2026-04-08T13:05:45.847264Z","steps":["trace[1881819302] 'process raft request' (duration: 91.278082ms)","trace[1881819302] 'compare' (duration: 107.448217ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:05:45.848707Z","caller":"traceutil/trace.go:171","msg":"trace[203484201] linearizableReadLoop","detail":"{readStateIndex:2863; appliedIndex:2861; }","duration":"152.842159ms","start":"2026-04-08T13:05:45.695836Z","end":"2026-04-08T13:05:45.848678Z","steps":["trace[203484201] 'read index received' (duration: 43.441637ms)","trace[203484201] 'applied index is now lower than readState.Index' (duration: 109.399302ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:05:45.848886Z","caller":"traceutil/trace.go:171","msg":"trace[924658542] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"199.345753ms","start":"2026-04-08T13:05:45.649509Z","end":"2026-04-08T13:05:45.848855Z","steps":["trace[924658542] 'process raft request' (duration: 199.031996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:05:45.848969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.195061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:05:45.849025Z","caller":"traceutil/trace.go:171","msg":"trace[963335740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2763; }","duration":"151.280002ms","start":"2026-04-08T13:05:45.69773Z","end":"2026-04-08T13:05:45.84901Z","steps":["trace[963335740] 'agreement among raft nodes before linearized reading' (duration: 151.19168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:05:45.848959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.066304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:05:45.849535Z","caller":"traceutil/trace.go:171","msg":"trace[142644107] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2763; }","duration":"153.729628ms","start":"2026-04-08T13:05:45.695782Z","end":"2026-04-08T13:05:45.849512Z","steps":["trace[142644107] 'agreement among raft nodes before linearized reading' (duration: 153.081704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:28.925779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.969936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:09:28.925911Z","caller":"traceutil/trace.go:171","msg":"trace[509561836] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"110.250391ms","start":"2026-04-08T13:09:28.815642Z","end":"2026-04-08T13:09:28.925893Z","steps":["trace[509561836] 'compare' (duration: 100.627637ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:34.865945Z","caller":"traceutil/trace.go:171","msg":"trace[1825478474] transaction","detail":"{read_only:false; response_revision:4893; number_of_response:1; }","duration":"185.803002ms","start":"2026-04-08T13:09:34.680116Z","end":"2026-04-08T13:09:34.865919Z","steps":["trace[1825478474] 'process raft request' (duration: 185.613637ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:34.918682Z","caller":"traceutil/trace.go:171","msg":"trace[666803439] linearizableReadLoop","detail":"{readStateIndex:5076; appliedIndex:5074; }","duration":"221.300433ms","start":"2026-04-08T13:09:34.697352Z","end":"2026-04-08T13:09:34.918652Z","steps":["trace[666803439] 'read index received' (duration: 168.466341ms)","trace[666803439] 'applied index is now lower than readState.Index' (duration: 52.831172ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:34.918682Z","caller":"traceutil/trace.go:171","msg":"trace[51094506] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"226.809193ms","start":"2026-04-08T13:09:34.691837Z","end":"2026-04-08T13:09:34.918646Z","steps":["trace[51094506] 'process raft request' (duration: 216.393848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:34.918945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.58032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:09:34.918947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.256112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T13:09:34.918983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.501321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7ae3a92f-e0a0-45e7-8f70-cd8636bb8ec8\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-08T13:09:34.919022Z","caller":"traceutil/trace.go:171","msg":"trace[1814494975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4895; }","duration":"221.705043ms","start":"2026-04-08T13:09:34.697299Z","end":"2026-04-08T13:09:34.919004Z","steps":["trace[1814494975] 'agreement among raft nodes before linearized reading' (duration: 221.466187ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:34.919029Z","caller":"traceutil/trace.go:171","msg":"trace[984379637] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7ae3a92f-e0a0-45e7-8f70-cd8636bb8ec8; range_end:; response_count:1; response_revision:4895; }","duration":"203.617674ms","start":"2026-04-08T13:09:34.715397Z","end":"2026-04-08T13:09:34.919015Z","steps":["trace[984379637] 'agreement among raft nodes before linearized reading' (duration: 203.405719ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:34.919031Z","caller":"traceutil/trace.go:171","msg":"trace[1977734293] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:4895; }","duration":"158.380965ms","start":"2026-04-08T13:09:34.760628Z","end":"2026-04-08T13:09:34.919009Z","steps":["trace[1977734293] 'agreement among raft nodes before linearized reading' (duration: 158.238142ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:37.279818Z","caller":"traceutil/trace.go:171","msg":"trace[1917349744] linearizableReadLoop","detail":"{readStateIndex:5127; appliedIndex:5126; }","duration":"197.063657ms","start":"2026-04-08T13:09:37.08273Z","end":"2026-04-08T13:09:37.279794Z","steps":["trace[1917349744] 'read index received' (duration: 143.857215ms)","trace[1917349744] 'applied index is now lower than readState.Index' (duration: 53.205482ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:37.279924Z","caller":"traceutil/trace.go:171","msg":"trace[1806574337] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"208.664172ms","start":"2026-04-08T13:09:37.07119Z","end":"2026-04-08T13:09:37.279854Z","steps":["trace[1806574337] 'process raft request' (duration: 155.357337ms)","trace[1806574337] 'compare' (duration: 53.1096ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:09:37.28008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.334124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pzgn2\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T13:09:37.280142Z","caller":"traceutil/trace.go:171","msg":"trace[113341979] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pzgn2; range_end:; response_count:1; response_revision:4944; }","duration":"197.444548ms","start":"2026-04-08T13:09:37.082683Z","end":"2026-04-08T13:09:37.280127Z","steps":["trace[113341979] 'agreement among raft nodes before linearized reading' (duration: 197.204371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:45.67104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.195858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:09:45.671127Z","caller":"traceutil/trace.go:171","msg":"trace[278283244] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5115; }","duration":"129.333273ms","start":"2026-04-08T13:09:45.541776Z","end":"2026-04-08T13:09:45.671109Z","steps":["trace[278283244] 'range keys from in-memory index tree' (duration: 129.124024ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:53.373779Z","caller":"traceutil/trace.go:171","msg":"trace[725196695] linearizableReadLoop","detail":"{readStateIndex:5461; appliedIndex:5459; }","duration":"172.735459ms","start":"2026-04-08T13:09:53.201016Z","end":"2026-04-08T13:09:53.373752Z","steps":["trace[725196695] 'read index received' (duration: 22.4864ms)","trace[725196695] 'applied index is now lower than readState.Index' (duration: 150.247818ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:53.373847Z","caller":"traceutil/trace.go:171","msg":"trace[1262874177] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"177.152018ms","start":"2026-04-08T13:09:53.196658Z","end":"2026-04-08T13:09:53.37381Z","steps":["trace[1262874177] 'process raft request' (duration: 147.132881ms)","trace[1262874177] 'compare' (duration: 29.804407ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:09:53.374093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.053303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-04-08T13:09:53.374169Z","caller":"traceutil/trace.go:171","msg":"trace[415462854] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5272; }","duration":"173.151598ms","start":"2026-04-08T13:09:53.201006Z","end":"2026-04-08T13:09:53.374158Z","steps":["trace[415462854] 'agreement among raft nodes before linearized reading' (duration: 172.921377ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:53.565228Z","caller":"traceutil/trace.go:171","msg":"trace[1527759813] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"183.777304ms","start":"2026-04-08T13:09:53.38143Z","end":"2026-04-08T13:09:53.565207Z","steps":["trace[1527759813] 'process raft request' (duration: 183.709991ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:53.56524Z","caller":"traceutil/trace.go:171","msg":"trace[990393082] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"184.416675ms","start":"2026-04-08T13:09:53.380798Z","end":"2026-04-08T13:09:53.565215Z","steps":["trace[990393082] 'process raft request' (duration: 184.197074ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:53.565378Z","caller":"traceutil/trace.go:171","msg":"trace[151683498] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"183.886799ms","start":"2026-04-08T13:09:53.381484Z","end":"2026-04-08T13:09:53.565371Z","steps":["trace[151683498] 'process raft request' (duration: 183.6965ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:53.565498Z","caller":"traceutil/trace.go:171","msg":"trace[1388466211] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"183.275431ms","start":"2026-04-08T13:09:53.382215Z","end":"2026-04-08T13:09:53.56549Z","steps":["trace[1388466211] 'process raft request' (duration: 183.102863ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:53.565529Z","caller":"traceutil/trace.go:171","msg":"trace[1233722440] linearizableReadLoop","detail":"{readStateIndex:5466; appliedIndex:5461; }","duration":"183.163165ms","start":"2026-04-08T13:09:53.382356Z","end":"2026-04-08T13:09:53.565519Z","steps":["trace[1233722440] 'read index received' (duration: 35.637136ms)","trace[1233722440] 'applied index is now lower than readState.Index' (duration: 147.525199ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:53.565604Z","caller":"traceutil/trace.go:171","msg":"trace[493222320] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"181.231634ms","start":"2026-04-08T13:09:53.384363Z","end":"2026-04-08T13:09:53.565595Z","steps":["trace[493222320] 'process raft request' (duration: 181.115238ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:53.565618Z","caller":"traceutil/trace.go:171","msg":"trace[1738861948] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"182.808208ms","start":"2026-04-08T13:09:53.382763Z","end":"2026-04-08T13:09:53.565572Z","steps":["trace[1738861948] 'process raft request' (duration: 182.675351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:53.566013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.644839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pzgn2\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T13:09:53.566047Z","caller":"traceutil/trace.go:171","msg":"trace[1508930379] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pzgn2; range_end:; response_count:1; response_revision:5279; }","duration":"183.711462ms","start":"2026-04-08T13:09:53.382328Z","end":"2026-04-08T13:09:53.566039Z","steps":["trace[1508930379] 'agreement among raft nodes before linearized reading' (duration: 183.553714ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:55.421506Z","caller":"traceutil/trace.go:171","msg":"trace[1372084568] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"189.141618ms","start":"2026-04-08T13:09:55.232336Z","end":"2026-04-08T13:09:55.421478Z","steps":["trace[1372084568] 'process raft request' (duration: 134.328221ms)","trace[1372084568] 'compare' (duration: 54.312884ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:11:30.595081Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3008} {"level":"info","ts":"2026-04-08T13:11:30.679523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3008,"took":"82.320111ms","hash":1847984224,"current-db-size-bytes":59219968,"current-db-size":"59 MB","current-db-size-in-use-bytes":32079872,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-08T13:11:30.679579Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1847984224,"revision":3008,"compact-revision":-1} {"level":"info","ts":"2026-04-08T13:16:08.613945Z","caller":"traceutil/trace.go:171","msg":"trace[2038602004] transaction","detail":"{read_only:false; response_revision:8170; number_of_response:1; }","duration":"202.462155ms","start":"2026-04-08T13:16:08.411455Z","end":"2026-04-08T13:16:08.613917Z","steps":["trace[2038602004] 'process raft request' (duration: 202.27891ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:16:08.644407Z","caller":"traceutil/trace.go:171","msg":"trace[1158313145] transaction","detail":"{read_only:false; response_revision:8171; number_of_response:1; }","duration":"221.742539ms","start":"2026-04-08T13:16:08.422639Z","end":"2026-04-08T13:16:08.644381Z","steps":["trace[1158313145] 'process raft request' (duration: 221.608035ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:16:19.616311Z","caller":"traceutil/trace.go:171","msg":"trace[440162268] transaction","detail":"{read_only:false; response_revision:8231; number_of_response:1; }","duration":"209.992717ms","start":"2026-04-08T13:16:19.406289Z","end":"2026-04-08T13:16:19.616281Z","steps":["trace[440162268] 'process raft request' (duration: 209.788052ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:16:19.674292Z","caller":"traceutil/trace.go:171","msg":"trace[567852581] transaction","detail":"{read_only:false; response_revision:8232; number_of_response:1; }","duration":"150.700681ms","start":"2026-04-08T13:16:19.523557Z","end":"2026-04-08T13:16:19.674257Z","steps":["trace[567852581] 'process raft request' (duration: 133.610458ms)","trace[567852581] 'compare' (duration: 16.887868ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:16:20.206617Z","caller":"traceutil/trace.go:171","msg":"trace[286075204] transaction","detail":"{read_only:false; response_revision:8235; number_of_response:1; }","duration":"154.384476ms","start":"2026-04-08T13:16:20.052202Z","end":"2026-04-08T13:16:20.206586Z","steps":["trace[286075204] 'process raft request' (duration: 154.18539ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:16:20.95072Z","caller":"traceutil/trace.go:171","msg":"trace[1889207442] transaction","detail":"{read_only:false; response_revision:8236; number_of_response:1; }","duration":"299.8591ms","start":"2026-04-08T13:16:20.650828Z","end":"2026-04-08T13:16:20.950687Z","steps":["trace[1889207442] 'process raft request' (duration: 299.667044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:16:20.95092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:16:20.650803Z","time spent":"300.023634ms","remote":"127.0.0.1:50312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:16:21.079188Z","caller":"traceutil/trace.go:171","msg":"trace[546181689] linearizableReadLoop","detail":"{readStateIndex:8524; appliedIndex:8522; }","duration":"298.452131ms","start":"2026-04-08T13:16:20.780705Z","end":"2026-04-08T13:16:21.079157Z","steps":["trace[546181689] 'read index received' (duration: 169.923766ms)","trace[546181689] 'applied index is now lower than readState.Index' (duration: 128.527265ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:16:21.079457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.710367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:16:21.079548Z","caller":"traceutil/trace.go:171","msg":"trace[36343923] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:8237; }","duration":"298.876982ms","start":"2026-04-08T13:16:20.78065Z","end":"2026-04-08T13:16:21.079527Z","steps":["trace[36343923] 'agreement among raft nodes before linearized reading' (duration: 298.668056ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:16:21.079587Z","caller":"traceutil/trace.go:171","msg":"trace[1817916019] transaction","detail":"{read_only:false; response_revision:8237; number_of_response:1; }","duration":"393.653362ms","start":"2026-04-08T13:16:20.685909Z","end":"2026-04-08T13:16:21.079562Z","steps":["trace[1817916019] 'process raft request' (duration: 325.968428ms)","trace[1817916019] 'compare' (duration: 67.16876ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:16:21.07977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:16:20.685886Z","time spent":"393.747165ms","remote":"127.0.0.1:50312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:16:21.289861Z","caller":"traceutil/trace.go:171","msg":"trace[280895368] linearizableReadLoop","detail":"{readStateIndex:8528; appliedIndex:8526; }","duration":"148.067327ms","start":"2026-04-08T13:16:21.141768Z","end":"2026-04-08T13:16:21.289835Z","steps":["trace[280895368] 'read index received' (duration: 66.698686ms)","trace[280895368] 'applied index is now lower than readState.Index' (duration: 81.367601ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:16:21.289933Z","caller":"traceutil/trace.go:171","msg":"trace[1012428023] transaction","detail":"{read_only:false; response_revision:8240; number_of_response:1; }","duration":"170.40996ms","start":"2026-04-08T13:16:21.119481Z","end":"2026-04-08T13:16:21.289891Z","steps":["trace[1012428023] 'process raft request' (duration: 88.932305ms)","trace[1012428023] 'compare' (duration: 81.250388ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:16:21.289949Z","caller":"traceutil/trace.go:171","msg":"trace[643689220] transaction","detail":"{read_only:false; response_revision:8241; number_of_response:1; }","duration":"163.03499ms","start":"2026-04-08T13:16:21.126877Z","end":"2026-04-08T13:16:21.289912Z","steps":["trace[643689220] 'process raft request' (duration: 162.913367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:16:21.290452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.669234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-08T13:16:21.29051Z","caller":"traceutil/trace.go:171","msg":"trace[930729387] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8241; }","duration":"148.764767ms","start":"2026-04-08T13:16:21.141732Z","end":"2026-04-08T13:16:21.290497Z","steps":["trace[930729387] 'agreement among raft nodes before linearized reading' (duration: 148.242252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:16:21.92748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.237027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:16:21.927588Z","caller":"traceutil/trace.go:171","msg":"trace[594567223] linearizableReadLoop","detail":"{readStateIndex:8530; appliedIndex:8529; }","duration":"231.62393ms","start":"2026-04-08T13:16:21.695944Z","end":"2026-04-08T13:16:21.927568Z","steps":["trace[594567223] 'read index received' (duration: 98.202697ms)","trace[594567223] 'applied index is now lower than readState.Index' (duration: 133.419733ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:16:21.927922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.425448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-08T13:16:21.927969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.825376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:16:21.928076Z","caller":"traceutil/trace.go:171","msg":"trace[189630135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8243; }","duration":"223.562732ms","start":"2026-04-08T13:16:21.704437Z","end":"2026-04-08T13:16:21.928Z","steps":["trace[189630135] 'agreement among raft nodes before linearized reading' (duration: 223.292835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:16:21.928096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.0415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pzgn2\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T13:16:21.928148Z","caller":"traceutil/trace.go:171","msg":"trace[508839566] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pzgn2; range_end:; response_count:1; response_revision:8243; }","duration":"222.098631ms","start":"2026-04-08T13:16:21.706031Z","end":"2026-04-08T13:16:21.92813Z","steps":["trace[508839566] 'agreement among raft nodes before linearized reading' (duration: 221.911376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:16:21.928155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.183432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T13:16:21.928167Z","caller":"traceutil/trace.go:171","msg":"trace[2062138318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8243; }","duration":"232.205257ms","start":"2026-04-08T13:16:21.695915Z","end":"2026-04-08T13:16:21.92812Z","steps":["trace[2062138318] 'agreement among raft nodes before linearized reading' (duration: 231.758354ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:16:21.928204Z","caller":"traceutil/trace.go:171","msg":"trace[4426262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8243; }","duration":"176.288165ms","start":"2026-04-08T13:16:21.751905Z","end":"2026-04-08T13:16:21.928193Z","steps":["trace[4426262] 'agreement among raft nodes before linearized reading' (duration: 176.024977ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:16:21.927972Z","caller":"traceutil/trace.go:171","msg":"trace[240376403] transaction","detail":"{read_only:false; response_revision:8243; number_of_response:1; }","duration":"250.126483ms","start":"2026-04-08T13:16:21.677806Z","end":"2026-04-08T13:16:21.927932Z","steps":["trace[240376403] 'process raft request' (duration: 116.34141ms)","trace[240376403] 'compare' (duration: 132.880847ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:16:22.143546Z","caller":"traceutil/trace.go:171","msg":"trace[1581025755] transaction","detail":"{read_only:false; response_revision:8245; number_of_response:1; }","duration":"211.110601ms","start":"2026-04-08T13:16:21.9324Z","end":"2026-04-08T13:16:22.143511Z","steps":["trace[1581025755] 'process raft request' (duration: 139.637929ms)","trace[1581025755] 'compare' (duration: 71.221225ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:16:30.600989Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6146} {"level":"info","ts":"2026-04-08T13:16:30.697041Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6146,"took":"94.0408ms","hash":1463939550,"current-db-size-bytes":59219968,"current-db-size":"59 MB","current-db-size-in-use-bytes":22913024,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-08T13:16:30.697126Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1463939550,"revision":6146,"compact-revision":3008} {"level":"warn","ts":"2026-04-08T13:16:50.900035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.446699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:16:50.900103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.993872ms","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-08T13:16:50.900184Z","caller":"traceutil/trace.go:171","msg":"trace[381918004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8449; }","duration":"771.120776ms","start":"2026-04-08T13:16:50.129042Z","end":"2026-04-08T13:16:50.900163Z","steps":["trace[381918004] 'range keys from in-memory index tree' (duration: 770.794596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:16:50.900183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.501427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pzgn2\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T13:16:50.900225Z","caller":"traceutil/trace.go:171","msg":"trace[1457471122] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pzgn2; range_end:; response_count:1; response_revision:8449; }","duration":"753.56742ms","start":"2026-04-08T13:16:50.146647Z","end":"2026-04-08T13:16:50.900214Z","steps":["trace[1457471122] 'range keys from in-memory index tree' (duration: 753.348333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:16:50.900221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:16:50.129021Z","time spent":"771.191459ms","remote":"127.0.0.1:50312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T13:16:50.900207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.118212ms","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-04-08T13:16:50.900129Z","caller":"traceutil/trace.go:171","msg":"trace[1887846245] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:8449; }","duration":"136.579353ms","start":"2026-04-08T13:16:50.76353Z","end":"2026-04-08T13:16:50.900109Z","steps":["trace[1887846245] 'count revisions from in-memory index tree' (duration: 136.370236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:16:50.900257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:16:50.146634Z","time spent":"753.614551ms","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6555,"request content":"key:\"/registry/pods/openstack/keepalived-pzgn2\" "} {"level":"info","ts":"2026-04-08T13:16:50.900283Z","caller":"traceutil/trace.go:171","msg":"trace[354785996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8449; }","duration":"628.231215ms","start":"2026-04-08T13:16:50.27203Z","end":"2026-04-08T13:16:50.900262Z","steps":["trace[354785996] 'range keys from in-memory index tree' (duration: 627.977328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:16:50.900333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:16:50.271996Z","time spent":"628.321848ms","remote":"127.0.0.1:50312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T13:16:53.501833Z","caller":"traceutil/trace.go:171","msg":"trace[1641570388] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"101.663125ms","start":"2026-04-08T13:16:53.400143Z","end":"2026-04-08T13:16:53.501807Z","steps":["trace[1641570388] 'process raft request' (duration: 101.153971ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:16:53.726293Z","caller":"traceutil/trace.go:171","msg":"trace[799660573] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"220.63968ms","start":"2026-04-08T13:16:53.505621Z","end":"2026-04-08T13:16:53.72626Z","steps":["trace[799660573] 'process raft request' (duration: 146.01144ms)","trace[799660573] 'compare' (duration: 74.348331ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:17:03.482401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.802018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:17:03.482527Z","caller":"traceutil/trace.go:171","msg":"trace[219460018] linearizableReadLoop","detail":"{readStateIndex:8863; appliedIndex:8862; }","duration":"182.153942ms","start":"2026-04-08T13:17:03.300355Z","end":"2026-04-08T13:17:03.482509Z","steps":["trace[219460018] 'read index received' (duration: 39.027044ms)","trace[219460018] 'applied index is now lower than readState.Index' (duration: 143.125568ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:17:03.482671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.324406ms","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-04-08T13:17:03.482698Z","caller":"traceutil/trace.go:171","msg":"trace[1362437645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8561; }","duration":"182.394678ms","start":"2026-04-08T13:17:03.300295Z","end":"2026-04-08T13:17:03.48269Z","steps":["trace[1362437645] 'agreement among raft nodes before linearized reading' (duration: 182.260434ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:17:03.482832Z","caller":"traceutil/trace.go:171","msg":"trace[657795210] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"240.903843ms","start":"2026-04-08T13:17:03.24189Z","end":"2026-04-08T13:17:03.482794Z","steps":["trace[657795210] 'process raft request' (duration: 97.59775ms)","trace[657795210] 'compare' (duration: 142.317575ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:17:03.648047Z","caller":"traceutil/trace.go:171","msg":"trace[1339836764] transaction","detail":"{read_only:false; response_revision:8562; number_of_response:1; }","duration":"160.231571ms","start":"2026-04-08T13:17:03.487725Z","end":"2026-04-08T13:17:03.647956Z","steps":["trace[1339836764] 'process raft request' (duration: 137.938371ms)","trace[1339836764] 'compare' (duration: 21.2092ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:17:03.674076Z","caller":"traceutil/trace.go:171","msg":"trace[700596923] transaction","detail":"{read_only:false; response_revision:8563; number_of_response:1; }","duration":"142.488509ms","start":"2026-04-08T13:17:03.531543Z","end":"2026-04-08T13:17:03.674032Z","steps":["trace[700596923] 'process raft request' (duration: 142.222831ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:17:03.674591Z","caller":"traceutil/trace.go:171","msg":"trace[205474582] linearizableReadLoop","detail":"{readStateIndex:8865; appliedIndex:8863; }","duration":"113.84639ms","start":"2026-04-08T13:17:03.560715Z","end":"2026-04-08T13:17:03.674562Z","steps":["trace[205474582] 'read index received' (duration: 64.969757ms)","trace[205474582] 'applied index is now lower than readState.Index' (duration: 48.875133ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:17:03.674484Z","caller":"traceutil/trace.go:171","msg":"trace[869069245] transaction","detail":"{read_only:false; response_revision:8564; number_of_response:1; }","duration":"113.157881ms","start":"2026-04-08T13:17:03.561291Z","end":"2026-04-08T13:17:03.674449Z","steps":["trace[869069245] 'process raft request' (duration: 112.650507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:17:03.674856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.114137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-fmxbv\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-04-08T13:17:03.674921Z","caller":"traceutil/trace.go:171","msg":"trace[331161392] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-fmxbv; range_end:; response_count:1; response_revision:8565; }","duration":"114.257931ms","start":"2026-04-08T13:17:03.560646Z","end":"2026-04-08T13:17:03.674904Z","steps":["trace[331161392] 'agreement among raft nodes before linearized reading' (duration: 114.043635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:17:03.899885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.963634ms","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":"warn","ts":"2026-04-08T13:17:03.899925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.190613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.221\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T13:17:03.899957Z","caller":"traceutil/trace.go:171","msg":"trace[74008682] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8565; }","duration":"130.086018ms","start":"2026-04-08T13:17:03.769856Z","end":"2026-04-08T13:17:03.899942Z","steps":["trace[74008682] 'range keys from in-memory index tree' (duration: 129.760028ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:17:03.899973Z","caller":"traceutil/trace.go:171","msg":"trace[561537885] range","detail":"{range_begin:/registry/masterleases/162.253.55.221; range_end:; response_count:1; response_revision:8565; }","duration":"152.276115ms","start":"2026-04-08T13:17:03.747681Z","end":"2026-04-08T13:17:03.899957Z","steps":["trace[561537885] 'range keys from in-memory index tree' (duration: 151.963527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:17:03.899887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.529635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-04-08T13:17:03.900048Z","caller":"traceutil/trace.go:171","msg":"trace[1993947402] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8565; }","duration":"110.69192ms","start":"2026-04-08T13:17:03.789322Z","end":"2026-04-08T13:17:03.900014Z","steps":["trace[1993947402] 'range keys from in-memory index tree' (duration: 110.419732ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:17:04.050869Z","caller":"traceutil/trace.go:171","msg":"trace[1278314858] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"146.06827ms","start":"2026-04-08T13:17:03.904773Z","end":"2026-04-08T13:17:04.050841Z","steps":["trace[1278314858] 'process raft request' (duration: 145.948247ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:17:04.05091Z","caller":"traceutil/trace.go:171","msg":"trace[1294331923] transaction","detail":"{read_only:false; response_revision:8566; number_of_response:1; }","duration":"146.818021ms","start":"2026-04-08T13:17:03.904051Z","end":"2026-04-08T13:17:04.050869Z","steps":["trace[1294331923] 'process raft request' (duration: 146.518642ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:17:04.05102Z","caller":"traceutil/trace.go:171","msg":"trace[464207629] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"134.35183ms","start":"2026-04-08T13:17:03.91665Z","end":"2026-04-08T13:17:04.051002Z","steps":["trace[464207629] 'process raft request' (duration: 134.126293ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:17:04.202243Z","caller":"traceutil/trace.go:171","msg":"trace[1270744733] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"138.437944ms","start":"2026-04-08T13:17:04.063783Z","end":"2026-04-08T13:17:04.202221Z","steps":["trace[1270744733] 'process raft request' (duration: 108.014044ms)","trace[1270744733] 'compare' (duration: 30.245755ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:17:04.202211Z","caller":"traceutil/trace.go:171","msg":"trace[1101805536] linearizableReadLoop","detail":"{readStateIndex:8873; appliedIndex:8872; }","duration":"133.135054ms","start":"2026-04-08T13:17:04.069042Z","end":"2026-04-08T13:17:04.202177Z","steps":["trace[1101805536] 'read index received' (duration: 102.700954ms)","trace[1101805536] 'applied index is now lower than readState.Index' (duration: 30.43313ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:17:04.202443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.372331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-08T13:17:04.202512Z","caller":"traceutil/trace.go:171","msg":"trace[1501079161] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:8570; }","duration":"133.478774ms","start":"2026-04-08T13:17:04.069016Z","end":"2026-04-08T13:17:04.202495Z","steps":["trace[1501079161] 'agreement among raft nodes before linearized reading' (duration: 133.267588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:17:04.202518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.393733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:17:04.202593Z","caller":"traceutil/trace.go:171","msg":"trace[1624277808] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-config; range_end:; response_count:0; response_revision:8570; }","duration":"132.501386ms","start":"2026-04-08T13:17:04.070069Z","end":"2026-04-08T13:17:04.202571Z","steps":["trace[1624277808] 'agreement among raft nodes before linearized reading' (duration: 132.381083ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:17:04.726733Z","caller":"traceutil/trace.go:171","msg":"trace[1073132609] transaction","detail":"{read_only:false; response_revision:8572; number_of_response:1; }","duration":"146.587435ms","start":"2026-04-08T13:17:04.580122Z","end":"2026-04-08T13:17:04.726709Z","steps":["trace[1073132609] 'process raft request' (duration: 146.448911ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:17:41.511355Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000240f.wal"} {"level":"info","ts":"2026-04-08T13:18:58.827242Z","caller":"traceutil/trace.go:171","msg":"trace[1501345727] transaction","detail":"{read_only:false; response_revision:9595; number_of_response:1; }","duration":"160.349703ms","start":"2026-04-08T13:18:58.666859Z","end":"2026-04-08T13:18:58.827209Z","steps":["trace[1501345727] 'process raft request' (duration: 118.59827ms)","trace[1501345727] 'compare' (duration: 41.190577ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:18:58.827224Z","caller":"traceutil/trace.go:171","msg":"trace[773191369] transaction","detail":"{read_only:false; response_revision:9596; number_of_response:1; }","duration":"159.377616ms","start":"2026-04-08T13:18:58.667806Z","end":"2026-04-08T13:18:58.827184Z","steps":["trace[773191369] 'process raft request' (duration: 159.231602ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:19:10.715332Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c187bdee547e8fd1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T13:19:10.721063Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T13:19:10.721177Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-08T13:20:13.390227Z","caller":"traceutil/trace.go:171","msg":"trace[60246906] transaction","detail":"{read_only:false; response_revision:10125; number_of_response:1; }","duration":"110.114625ms","start":"2026-04-08T13:20:13.280075Z","end":"2026-04-08T13:20:13.390189Z","steps":["trace[60246906] 'process raft request' (duration: 56.399861ms)","trace[60246906] 'compare' (duration: 53.56078ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:21:30.60837Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8296} {"level":"info","ts":"2026-04-08T13:21:30.683299Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8296,"took":"73.295295ms","hash":1384739931,"current-db-size-bytes":59219968,"current-db-size":"59 MB","current-db-size-in-use-bytes":24924160,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-08T13:21:30.683377Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1384739931,"revision":8296,"compact-revision":6146} {"level":"info","ts":"2026-04-08T13:26:30.615099Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10689} {"level":"info","ts":"2026-04-08T13:26:30.693147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10689,"took":"75.83417ms","hash":2026325170,"current-db-size-bytes":59219968,"current-db-size":"59 MB","current-db-size-in-use-bytes":20975616,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-08T13:26:30.693204Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2026325170,"revision":10689,"compact-revision":8296}