{"level":"warn","ts":"2026-10-04T05:39:46.010136Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T05:39:46.010244Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.93: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.93:2380","--initial-cluster=instance=https://162.253.55.93:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.93:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.93: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-10-04T05:39:46.010304Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T05:39:46.010315Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-10-04T05:39:46.010472Z","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-10-04T05:39:46.011282Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"]} {"level":"info","ts":"2026-10-04T05:39:46.011362Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.93: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-10-04T05:39:46.016463Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.901501ms"} {"level":"info","ts":"2026-10-04T05:39:46.023853Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ea06c5748459930b","cluster-id":"49d4e1d71c75bc5"} {"level":"info","ts":"2026-10-04T05:39:46.023941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=()"} {"level":"info","ts":"2026-10-04T05:39:46.023961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 0"} {"level":"info","ts":"2026-10-04T05:39:46.023974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ea06c5748459930b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-04T05:39:46.023982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 1"} {"level":"info","ts":"2026-10-04T05:39:46.024038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"warn","ts":"2026-10-04T05:39:46.036056Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-04T05:39:46.037918Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-04T05:39:46.038951Z","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-10-04T05:39:46.041235Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ea06c5748459930b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-04T05:39:46.041471Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ea06c5748459930b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-04T05:39:46.04297Z","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-10-04T05:39:46.043181Z","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-10-04T05:39:46.043202Z","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-10-04T05:39:46.045132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"info","ts":"2026-10-04T05:39:46.048419Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","added-peer-id":"ea06c5748459930b","added-peer-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-10-04T05:39:46.048441Z","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-10-04T05:39:46.048469Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-10-04T05:39:46.048672Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-10-04T05:39:46.049088Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ea06c5748459930b","initial-advertise-peer-urls":["https://162.253.55.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-04T05:39:46.049196Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-04T05:39:46.124729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b is starting a new election at term 1"} {"level":"info","ts":"2026-10-04T05:39:46.124767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-04T05:39:46.124782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgPreVoteResp from ea06c5748459930b at term 1"} {"level":"info","ts":"2026-10-04T05:39:46.124795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became candidate at term 2"} {"level":"info","ts":"2026-10-04T05:39:46.124801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgVoteResp from ea06c5748459930b at term 2"} {"level":"info","ts":"2026-10-04T05:39:46.124811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became leader at term 2"} {"level":"info","ts":"2026-10-04T05:39:46.124819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ea06c5748459930b elected leader ea06c5748459930b at term 2"} {"level":"info","ts":"2026-10-04T05:39:46.125663Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:39:46.126424Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ea06c5748459930b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.93:2379]}","request-path":"/0/members/ea06c5748459930b/attributes","cluster-id":"49d4e1d71c75bc5","publish-timeout":"7s"} {"level":"info","ts":"2026-10-04T05:39:46.12642Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T05:39:46.126633Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T05:39:46.126816Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-04T05:39:46.126892Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-04T05:39:46.127099Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:39:46.127191Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:39:46.127215Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:39:46.12889Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-04T05:39:46.130139Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.93:2379"} {"level":"info","ts":"2026-10-04T05:39:50.139516Z","caller":"traceutil/trace.go:171","msg":"trace[1817084816] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"138.892859ms","start":"2026-10-04T05:39:50.000594Z","end":"2026-10-04T05:39:50.139487Z","steps":["trace[1817084816] 'process raft request' (duration: 138.713113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:39:50.515896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.275801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:39:50.516067Z","caller":"traceutil/trace.go:171","msg":"trace[36803167] linearizableReadLoop","detail":"{readStateIndex:204; appliedIndex:203; }","duration":"350.200479ms","start":"2026-10-04T05:39:50.16583Z","end":"2026-10-04T05:39:50.51603Z","steps":["trace[36803167] 'read index received' (duration: 160.041112ms)","trace[36803167] 'applied index is now lower than readState.Index' (duration: 190.158037ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:39:50.516174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.342073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:39:50.516207Z","caller":"traceutil/trace.go:171","msg":"trace[1278626914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:199; }","duration":"350.435695ms","start":"2026-10-04T05:39:50.165761Z","end":"2026-10-04T05:39:50.516196Z","steps":["trace[1278626914] 'agreement among raft nodes before linearized reading' (duration: 350.339113ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:39:50.516132Z","caller":"traceutil/trace.go:171","msg":"trace[612742609] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"372.146869ms","start":"2026-10-04T05:39:50.143942Z","end":"2026-10-04T05:39:50.516089Z","steps":["trace[612742609] 'process raft request' (duration: 182.042183ms)","trace[612742609] 'compare' (duration: 189.169368ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:39:50.516256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:39:50.165735Z","time spent":"350.512018ms","remote":"127.0.0.1:60162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-04T05:39:50.51637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:39:50.143894Z","time spent":"372.369734ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:39:50.716236Z","caller":"traceutil/trace.go:171","msg":"trace[1315621316] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"195.132005ms","start":"2026-10-04T05:39:50.521078Z","end":"2026-10-04T05:39:50.71621Z","steps":["trace[1315621316] 'process raft request' (duration: 194.792195ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:39:50.718369Z","caller":"traceutil/trace.go:171","msg":"trace[758654223] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"196.007062ms","start":"2026-10-04T05:39:50.522324Z","end":"2026-10-04T05:39:50.718331Z","steps":["trace[758654223] 'process raft request' (duration: 195.854657ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:40:27.497196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.423045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-10-04T05:40:27.49732Z","caller":"traceutil/trace.go:171","msg":"trace[1254877413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"259.575619ms","start":"2026-10-04T05:40:27.23772Z","end":"2026-10-04T05:40:27.497296Z","steps":["trace[1254877413] 'range keys from in-memory index tree' (duration: 259.281582ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:40:27.51575Z","caller":"traceutil/trace.go:171","msg":"trace[496308691] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"258.573715ms","start":"2026-10-04T05:40:27.257151Z","end":"2026-10-04T05:40:27.515724Z","steps":["trace[496308691] 'process raft request' (duration: 258.387291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:40:29.678691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.665084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:40:29.678895Z","caller":"traceutil/trace.go:171","msg":"trace[48887553] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"218.572427ms","start":"2026-10-04T05:40:29.460301Z","end":"2026-10-04T05:40:29.678874Z","steps":["trace[48887553] 'read index received' (duration: 124.503µs)","trace[48887553] 'applied index is now lower than readState.Index' (duration: 218.446034ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:40:29.678934Z","caller":"traceutil/trace.go:171","msg":"trace[184772245] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"633.897687ms","start":"2026-10-04T05:40:29.044982Z","end":"2026-10-04T05:40:29.67888Z","steps":["trace[184772245] 'process raft request' (duration: 386.888435ms)","trace[184772245] 'compare' (duration: 246.285496ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:40:29.679175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:40:29.044944Z","time spent":"634.107852ms","remote":"127.0.0.1:60248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:40:29.679195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.910494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:40:29.679289Z","caller":"traceutil/trace.go:171","msg":"trace[1607897580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"219.099158ms","start":"2026-10-04T05:40:29.460172Z","end":"2026-10-04T05:40:29.679271Z","steps":["trace[1607897580] 'agreement among raft nodes before linearized reading' (duration: 218.818043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:40:29.679297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.203433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qsbbh\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-10-04T05:40:29.679361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.493664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-10-04T05:40:29.679417Z","caller":"traceutil/trace.go:171","msg":"trace[1464813959] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qsbbh; range_end:; response_count:1; response_revision:587; }","duration":"197.400577ms","start":"2026-10-04T05:40:29.481991Z","end":"2026-10-04T05:40:29.679392Z","steps":["trace[1464813959] 'agreement among raft nodes before linearized reading' (duration: 197.139332ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:40:29.679463Z","caller":"traceutil/trace.go:171","msg":"trace[568711297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"158.647347ms","start":"2026-10-04T05:40:29.520792Z","end":"2026-10-04T05:40:29.679439Z","steps":["trace[568711297] 'agreement among raft nodes before linearized reading' (duration: 158.349331ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:40:30.799118Z","caller":"traceutil/trace.go:171","msg":"trace[263708599] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"143.425473ms","start":"2026-10-04T05:40:30.655667Z","end":"2026-10-04T05:40:30.799092Z","steps":["trace[263708599] 'process raft request' (duration: 136.140459ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:06.215012Z","caller":"traceutil/trace.go:171","msg":"trace[1171308676] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"111.897401ms","start":"2026-10-04T05:42:06.103084Z","end":"2026-10-04T05:42:06.214981Z","steps":["trace[1171308676] 'process raft request' (duration: 111.673047ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:06.215361Z","caller":"traceutil/trace.go:171","msg":"trace[957281715] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"100.173495ms","start":"2026-10-04T05:42:06.115109Z","end":"2026-10-04T05:42:06.215283Z","steps":["trace[957281715] 'process raft request' (duration: 99.824889ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:06.415768Z","caller":"traceutil/trace.go:171","msg":"trace[407606273] linearizableReadLoop","detail":"{readStateIndex:1908; appliedIndex:1907; }","duration":"183.882846ms","start":"2026-10-04T05:42:06.231847Z","end":"2026-10-04T05:42:06.41573Z","steps":["trace[407606273] 'read index received' (duration: 183.657732ms)","trace[407606273] 'applied index is now lower than readState.Index' (duration: 223.734µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:42:06.416218Z","caller":"traceutil/trace.go:171","msg":"trace[231152545] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"252.284204ms","start":"2026-10-04T05:42:06.16389Z","end":"2026-10-04T05:42:06.416175Z","steps":["trace[231152545] 'process raft request' (duration: 251.652444ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:06.416333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.445205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-10-04T05:42:06.416495Z","caller":"traceutil/trace.go:171","msg":"trace[1041882978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1830; }","duration":"184.74605ms","start":"2026-10-04T05:42:06.231727Z","end":"2026-10-04T05:42:06.416473Z","steps":["trace[1041882978] 'agreement among raft nodes before linearized reading' (duration: 184.292263ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:06.56693Z","caller":"traceutil/trace.go:171","msg":"trace[941883399] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"144.116114ms","start":"2026-10-04T05:42:06.42277Z","end":"2026-10-04T05:42:06.566886Z","steps":["trace[941883399] 'process raft request' (duration: 126.226518ms)","trace[941883399] 'compare' (duration: 17.710883ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:42:06.601739Z","caller":"traceutil/trace.go:171","msg":"trace[887128318] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"166.840285ms","start":"2026-10-04T05:42:06.434877Z","end":"2026-10-04T05:42:06.601717Z","steps":["trace[887128318] 'process raft request' (duration: 166.768854ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:06.601734Z","caller":"traceutil/trace.go:171","msg":"trace[744718547] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"178.013384ms","start":"2026-10-04T05:42:06.42368Z","end":"2026-10-04T05:42:06.601694Z","steps":["trace[744718547] 'process raft request' (duration: 177.861221ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:06.762152Z","caller":"traceutil/trace.go:171","msg":"trace[445842675] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"151.667724ms","start":"2026-10-04T05:42:06.610449Z","end":"2026-10-04T05:42:06.762117Z","steps":["trace[445842675] 'process raft request' (duration: 119.807298ms)","trace[445842675] 'compare' (duration: 31.686443ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:42:06.840323Z","caller":"traceutil/trace.go:171","msg":"trace[636936233] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"229.795557ms","start":"2026-10-04T05:42:06.610506Z","end":"2026-10-04T05:42:06.840302Z","steps":["trace[636936233] 'process raft request' (duration: 229.620954ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:06.8408Z","caller":"traceutil/trace.go:171","msg":"trace[471087059] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"230.366126ms","start":"2026-10-04T05:42:06.610406Z","end":"2026-10-04T05:42:06.840772Z","steps":["trace[471087059] 'process raft request' (duration: 229.826957ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:06.841393Z","caller":"traceutil/trace.go:171","msg":"trace[525599990] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"230.574299ms","start":"2026-10-04T05:42:06.610788Z","end":"2026-10-04T05:42:06.841363Z","steps":["trace[525599990] 'process raft request' (duration: 229.883238ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:14.368808Z","caller":"traceutil/trace.go:171","msg":"trace[459140417] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"176.283256ms","start":"2026-10-04T05:42:14.192492Z","end":"2026-10-04T05:42:14.368776Z","steps":["trace[459140417] 'process raft request' (duration: 176.078863ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:14.407789Z","caller":"traceutil/trace.go:171","msg":"trace[828642566] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"163.063059ms","start":"2026-10-04T05:42:14.244693Z","end":"2026-10-04T05:42:14.407757Z","steps":["trace[828642566] 'process raft request' (duration: 162.867126ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:18.940702Z","caller":"traceutil/trace.go:171","msg":"trace[9271608] linearizableReadLoop","detail":"{readStateIndex:2015; appliedIndex:2014; }","duration":"291.311882ms","start":"2026-10-04T05:42:18.649362Z","end":"2026-10-04T05:42:18.940673Z","steps":["trace[9271608] 'read index received' (duration: 291.21368ms)","trace[9271608] 'applied index is now lower than readState.Index' (duration: 97.302µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:42:18.940746Z","caller":"traceutil/trace.go:171","msg":"trace[1952706385] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"314.766377ms","start":"2026-10-04T05:42:18.625948Z","end":"2026-10-04T05:42:18.940714Z","steps":["trace[1952706385] 'process raft request' (duration: 314.520934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:18.941113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:18.625933Z","time spent":"314.87435ms","remote":"127.0.0.1:60162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:42:18.946959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.580289ms","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-10-04T05:42:18.947043Z","caller":"traceutil/trace.go:171","msg":"trace[663384738] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1934; }","duration":"297.729532ms","start":"2026-10-04T05:42:18.649293Z","end":"2026-10-04T05:42:18.947022Z","steps":["trace[663384738] 'agreement among raft nodes before linearized reading' (duration: 291.515105ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:35.16477Z","caller":"traceutil/trace.go:171","msg":"trace[306053948] linearizableReadLoop","detail":"{readStateIndex:2123; appliedIndex:2121; }","duration":"135.661186ms","start":"2026-10-04T05:42:35.029085Z","end":"2026-10-04T05:42:35.164746Z","steps":["trace[306053948] 'read index received' (duration: 85.317489ms)","trace[306053948] 'applied index is now lower than readState.Index' (duration: 50.342587ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:42:35.164862Z","caller":"traceutil/trace.go:171","msg":"trace[684315298] transaction","detail":"{read_only:false; response_revision:2039; number_of_response:1; }","duration":"136.245155ms","start":"2026-10-04T05:42:35.028582Z","end":"2026-10-04T05:42:35.164827Z","steps":["trace[684315298] 'process raft request' (duration: 112.645595ms)","trace[684315298] 'compare' (duration: 23.404497ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:42:35.165177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.906229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-10-04T05:42:35.165233Z","caller":"traceutil/trace.go:171","msg":"trace[1399632621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2039; }","duration":"136.173834ms","start":"2026-10-04T05:42:35.02904Z","end":"2026-10-04T05:42:35.165214Z","steps":["trace[1399632621] 'agreement among raft nodes before linearized reading' (duration: 135.845709ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:35.165208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.169536ms","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-10-04T05:42:35.16531Z","caller":"traceutil/trace.go:171","msg":"trace[1831312349] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2039; }","duration":"100.406779ms","start":"2026-10-04T05:42:35.064883Z","end":"2026-10-04T05:42:35.16529Z","steps":["trace[1831312349] 'agreement among raft nodes before linearized reading' (duration: 100.086794ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:40.608387Z","caller":"traceutil/trace.go:171","msg":"trace[847808399] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"104.959558ms","start":"2026-10-04T05:42:40.50339Z","end":"2026-10-04T05:42:40.60835Z","steps":["trace[847808399] 'process raft request' (duration: 100.131445ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:41.274269Z","caller":"traceutil/trace.go:171","msg":"trace[1109104922] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"129.809092ms","start":"2026-10-04T05:42:41.144435Z","end":"2026-10-04T05:42:41.274244Z","steps":["trace[1109104922] 'process raft request' (duration: 129.489998ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:41.439696Z","caller":"traceutil/trace.go:171","msg":"trace[165379811] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"157.546071ms","start":"2026-10-04T05:42:41.282127Z","end":"2026-10-04T05:42:41.439673Z","steps":["trace[165379811] 'process raft request' (duration: 157.233446ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:41.451281Z","caller":"traceutil/trace.go:171","msg":"trace[1468774825] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"169.040585ms","start":"2026-10-04T05:42:41.282221Z","end":"2026-10-04T05:42:41.451261Z","steps":["trace[1468774825] 'process raft request' (duration: 168.623749ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:41.899196Z","caller":"traceutil/trace.go:171","msg":"trace[2147168334] linearizableReadLoop","detail":"{readStateIndex:2162; appliedIndex:2161; }","duration":"147.934166ms","start":"2026-10-04T05:42:41.75124Z","end":"2026-10-04T05:42:41.899174Z","steps":["trace[2147168334] 'read index received' (duration: 143.961986ms)","trace[2147168334] 'applied index is now lower than readState.Index' (duration: 3.97098ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:42:41.899239Z","caller":"traceutil/trace.go:171","msg":"trace[849041923] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"175.065746ms","start":"2026-10-04T05:42:41.724139Z","end":"2026-10-04T05:42:41.899205Z","steps":["trace[849041923] 'process raft request' (duration: 171.148698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:41.899455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.109129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-04T05:42:41.899598Z","caller":"traceutil/trace.go:171","msg":"trace[1264573371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2076; }","duration":"148.347733ms","start":"2026-10-04T05:42:41.751201Z","end":"2026-10-04T05:42:41.899549Z","steps":["trace[1264573371] 'agreement among raft nodes before linearized reading' (duration: 148.054358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:42.236627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.561352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:42:42.236736Z","caller":"traceutil/trace.go:171","msg":"trace[1466141676] linearizableReadLoop","detail":"{readStateIndex:2163; appliedIndex:2162; }","duration":"303.007158ms","start":"2026-10-04T05:42:41.933708Z","end":"2026-10-04T05:42:42.236715Z","steps":["trace[1466141676] 'read index received' (duration: 178.168992ms)","trace[1466141676] 'applied index is now lower than readState.Index' (duration: 124.836716ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:42:42.236895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.19483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-04T05:42:42.236928Z","caller":"traceutil/trace.go:171","msg":"trace[44346957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2077; }","duration":"303.346123ms","start":"2026-10-04T05:42:41.933571Z","end":"2026-10-04T05:42:42.236917Z","steps":["trace[44346957] 'agreement among raft nodes before linearized reading' (duration: 303.19372ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:42.236881Z","caller":"traceutil/trace.go:171","msg":"trace[890290332] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"331.62348ms","start":"2026-10-04T05:42:41.905202Z","end":"2026-10-04T05:42:42.236826Z","steps":["trace[890290332] 'process raft request' (duration: 206.758914ms)","trace[890290332] 'compare' (duration: 124.43339ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:42:42.236972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:41.933545Z","time spent":"303.413423ms","remote":"127.0.0.1:60162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-04T05:42:42.237351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:41.905169Z","time spent":"331.838123ms","remote":"127.0.0.1:60162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:43:46.790769Z","caller":"traceutil/trace.go:171","msg":"trace[106795626] transaction","detail":"{read_only:false; response_revision:2526; number_of_response:1; }","duration":"217.210957ms","start":"2026-10-04T05:43:46.573538Z","end":"2026-10-04T05:43:46.790749Z","steps":["trace[106795626] 'process raft request' (duration: 217.107623ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:43:46.791378Z","caller":"traceutil/trace.go:171","msg":"trace[2081860459] linearizableReadLoop","detail":"{readStateIndex:2627; appliedIndex:2627; }","duration":"162.272589ms","start":"2026-10-04T05:43:46.62908Z","end":"2026-10-04T05:43:46.791353Z","steps":["trace[2081860459] 'read index received' (duration: 162.264038ms)","trace[2081860459] 'applied index is now lower than readState.Index' (duration: 7.041µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:43:46.791576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.471826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-04T05:43:46.791608Z","caller":"traceutil/trace.go:171","msg":"trace[1743449672] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2526; }","duration":"162.521647ms","start":"2026-10-04T05:43:46.629075Z","end":"2026-10-04T05:43:46.791597Z","steps":["trace[1743449672] 'agreement among raft nodes before linearized reading' (duration: 162.376082ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:18.302894Z","caller":"traceutil/trace.go:171","msg":"trace[1991671813] transaction","detail":"{read_only:false; response_revision:2701; number_of_response:1; }","duration":"259.617916ms","start":"2026-10-04T05:44:18.043217Z","end":"2026-10-04T05:44:18.302835Z","steps":["trace[1991671813] 'process raft request' (duration: 176.148625ms)","trace[1991671813] 'compare' (duration: 83.08767ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:44:18.303627Z","caller":"traceutil/trace.go:171","msg":"trace[1005966395] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"258.221094ms","start":"2026-10-04T05:44:18.045382Z","end":"2026-10-04T05:44:18.303603Z","steps":["trace[1005966395] 'process raft request' (duration: 258.107191ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:48:45.823318Z","caller":"traceutil/trace.go:171","msg":"trace[1899019461] linearizableReadLoop","detail":"{readStateIndex:4470; appliedIndex:4469; }","duration":"112.298278ms","start":"2026-10-04T05:48:45.710998Z","end":"2026-10-04T05:48:45.823296Z","steps":["trace[1899019461] 'read index received' (duration: 112.097074ms)","trace[1899019461] 'applied index is now lower than readState.Index' (duration: 200.154µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:48:45.823406Z","caller":"traceutil/trace.go:171","msg":"trace[1444061444] transaction","detail":"{read_only:false; response_revision:4288; number_of_response:1; }","duration":"120.181007ms","start":"2026-10-04T05:48:45.703212Z","end":"2026-10-04T05:48:45.823393Z","steps":["trace[1444061444] 'process raft request' (duration: 119.946543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:48:45.82353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.521071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-04T05:48:45.823568Z","caller":"traceutil/trace.go:171","msg":"trace[304160116] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4288; }","duration":"112.594493ms","start":"2026-10-04T05:48:45.710962Z","end":"2026-10-04T05:48:45.823556Z","steps":["trace[304160116] 'agreement among raft nodes before linearized reading' (duration: 112.448321ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:48:58.4777Z","caller":"traceutil/trace.go:171","msg":"trace[745025717] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4872; }","duration":"145.405466ms","start":"2026-10-04T05:48:58.332269Z","end":"2026-10-04T05:48:58.477675Z","steps":["trace[745025717] 'process raft request' (duration: 113.807278ms)","trace[745025717] 'compare' (duration: 31.550327ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:48:58.477746Z","caller":"traceutil/trace.go:171","msg":"trace[628325630] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"110.596047ms","start":"2026-10-04T05:48:58.367141Z","end":"2026-10-04T05:48:58.477737Z","steps":["trace[628325630] 'process raft request' (duration: 110.545096ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:48:58.47776Z","caller":"traceutil/trace.go:171","msg":"trace[634343785] linearizableReadLoop","detail":"{readStateIndex:5064; appliedIndex:5062; }","duration":"137.466978ms","start":"2026-10-04T05:48:58.340258Z","end":"2026-10-04T05:48:58.477725Z","steps":["trace[634343785] 'read index received' (duration: 20.329048ms)","trace[634343785] 'applied index is now lower than readState.Index' (duration: 117.13566ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:48:58.47805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.927563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-85pj2-tls-4h2hz\" ","response":"range_response_count:1 size:2625"} {"level":"warn","ts":"2026-10-04T05:48:58.478049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.772333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-04T05:48:58.478102Z","caller":"traceutil/trace.go:171","msg":"trace[912042935] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4873; }","duration":"137.858356ms","start":"2026-10-04T05:48:58.340231Z","end":"2026-10-04T05:48:58.478089Z","steps":["trace[912042935] 'agreement among raft nodes before linearized reading' (duration: 137.57017ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:48:58.478108Z","caller":"traceutil/trace.go:171","msg":"trace[1796830523] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-85pj2-tls-4h2hz; range_end:; response_count:1; response_revision:4873; }","duration":"111.024535ms","start":"2026-10-04T05:48:58.367051Z","end":"2026-10-04T05:48:58.478075Z","steps":["trace[1796830523] 'agreement among raft nodes before linearized reading' (duration: 110.853382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:48:58.478443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.888403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-85pj2-tls\" ","response":"range_response_count:1 size:4431"} {"level":"info","ts":"2026-10-04T05:48:58.478461Z","caller":"traceutil/trace.go:171","msg":"trace[1012448765] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-85pj2-tls; range_end:; response_count:1; response_revision:4873; }","duration":"110.943044ms","start":"2026-10-04T05:48:58.367514Z","end":"2026-10-04T05:48:58.478457Z","steps":["trace[1012448765] 'agreement among raft nodes before linearized reading' (duration: 110.888953ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:48:58.608705Z","caller":"traceutil/trace.go:171","msg":"trace[1595311269] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4877; }","duration":"104.518795ms","start":"2026-10-04T05:48:58.504163Z","end":"2026-10-04T05:48:58.608681Z","steps":["trace[1595311269] 'process raft request' (duration: 101.611501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:48:58.609103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.73047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-10-04T05:48:58.609131Z","caller":"traceutil/trace.go:171","msg":"trace[1527947983] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4877; }","duration":"103.793192ms","start":"2026-10-04T05:48:58.50533Z","end":"2026-10-04T05:48:58.609123Z","steps":["trace[1527947983] 'agreement among raft nodes before linearized reading' (duration: 103.68015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:48:58.609535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.485799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-10-04T05:48:58.609619Z","caller":"traceutil/trace.go:171","msg":"trace[605906959] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4877; }","duration":"107.595472ms","start":"2026-10-04T05:48:58.502006Z","end":"2026-10-04T05:48:58.609601Z","steps":["trace[605906959] 'agreement among raft nodes before linearized reading' (duration: 107.257055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:05.297991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.790667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:49:05.298108Z","caller":"traceutil/trace.go:171","msg":"trace[1780884863] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"374.126402ms","start":"2026-10-04T05:49:04.923964Z","end":"2026-10-04T05:49:05.29809Z","steps":["trace[1780884863] 'process raft request' (duration: 266.111861ms)","trace[1780884863] 'compare' (duration: 107.545842ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:05.298152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:04.923945Z","time spent":"374.187004ms","remote":"127.0.0.1:59990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":922,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:49:05.327325Z","caller":"traceutil/trace.go:171","msg":"trace[1647982394] linearizableReadLoop","detail":"{readStateIndex:5178; appliedIndex:5176; }","duration":"256.516102ms","start":"2026-10-04T05:49:05.070788Z","end":"2026-10-04T05:49:05.327304Z","steps":["trace[1647982394] 'read index received' (duration: 119.429343ms)","trace[1647982394] 'applied index is now lower than readState.Index' (duration: 137.086119ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:49:05.327388Z","caller":"traceutil/trace.go:171","msg":"trace[909099407] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"303.955452ms","start":"2026-10-04T05:49:05.023417Z","end":"2026-10-04T05:49:05.327372Z","steps":["trace[909099407] 'process raft request' (duration: 303.769088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:05.327447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.640986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:49:05.327477Z","caller":"traceutil/trace.go:171","msg":"trace[1339838090] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:4983; }","duration":"256.685187ms","start":"2026-10-04T05:49:05.070783Z","end":"2026-10-04T05:49:05.327468Z","steps":["trace[1339838090] 'agreement among raft nodes before linearized reading' (duration: 256.615275ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:05.327483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:05.023397Z","time spent":"304.022193ms","remote":"127.0.0.1:60162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:49:05.366776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.308958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e69d67fd-9319-4f44-b006-a931e42fd9bb\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-04T05:49:05.367553Z","caller":"traceutil/trace.go:171","msg":"trace[1278781149] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e69d67fd-9319-4f44-b006-a931e42fd9bb; range_end:; response_count:1; response_revision:4984; }","duration":"101.49392ms","start":"2026-10-04T05:49:05.265401Z","end":"2026-10-04T05:49:05.366895Z","steps":["trace[1278781149] 'agreement among raft nodes before linearized reading' (duration: 100.605924ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:05.740896Z","caller":"traceutil/trace.go:171","msg":"trace[608614507] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"282.429153ms","start":"2026-10-04T05:49:05.45843Z","end":"2026-10-04T05:49:05.740859Z","steps":["trace[608614507] 'process raft request' (duration: 267.495745ms)","trace[608614507] 'compare' (duration: 14.50315ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:49:05.74094Z","caller":"traceutil/trace.go:171","msg":"trace[542893475] linearizableReadLoop","detail":"{readStateIndex:5188; appliedIndex:5184; }","duration":"256.139966ms","start":"2026-10-04T05:49:05.484789Z","end":"2026-10-04T05:49:05.740929Z","steps":["trace[542893475] 'read index received' (duration: 241.138037ms)","trace[542893475] 'applied index is now lower than readState.Index' (duration: 15.001169ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:49:05.741185Z","caller":"traceutil/trace.go:171","msg":"trace[409929981] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"282.672068ms","start":"2026-10-04T05:49:05.458504Z","end":"2026-10-04T05:49:05.741176Z","steps":["trace[409929981] 'process raft request' (duration: 282.369712ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:05.7413Z","caller":"traceutil/trace.go:171","msg":"trace[105780583] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"282.80736ms","start":"2026-10-04T05:49:05.458486Z","end":"2026-10-04T05:49:05.741293Z","steps":["trace[105780583] 'process raft request' (duration: 282.30632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:05.741334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.935875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-221da770-74f2-4145-9649-136723e3084c\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-10-04T05:49:05.741366Z","caller":"traceutil/trace.go:171","msg":"trace[1269732262] range","detail":"{range_begin:/registry/persistentvolumes/pvc-221da770-74f2-4145-9649-136723e3084c; range_end:; response_count:1; response_revision:4992; }","duration":"253.991427ms","start":"2026-10-04T05:49:05.487364Z","end":"2026-10-04T05:49:05.741356Z","steps":["trace[1269732262] 'agreement among raft nodes before linearized reading' (duration: 253.877535ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:05.741419Z","caller":"traceutil/trace.go:171","msg":"trace[749883323] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"282.855671ms","start":"2026-10-04T05:49:05.458494Z","end":"2026-10-04T05:49:05.74135Z","steps":["trace[749883323] 'process raft request' (duration: 282.346461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:05.74152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.568104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16231"} {"level":"info","ts":"2026-10-04T05:49:05.741543Z","caller":"traceutil/trace.go:171","msg":"trace[1871459004] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4992; }","duration":"283.620825ms","start":"2026-10-04T05:49:05.457916Z","end":"2026-10-04T05:49:05.741537Z","steps":["trace[1871459004] 'agreement among raft nodes before linearized reading' (duration: 283.556284ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:05.741549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.838364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-04T05:49:05.741567Z","caller":"traceutil/trace.go:171","msg":"trace[218251936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4992; }","duration":"219.885955ms","start":"2026-10-04T05:49:05.521675Z","end":"2026-10-04T05:49:05.741561Z","steps":["trace[218251936] 'agreement among raft nodes before linearized reading' (duration: 219.820194ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:17.611574Z","caller":"traceutil/trace.go:171","msg":"trace[2012733723] linearizableReadLoop","detail":"{readStateIndex:5346; appliedIndex:5345; }","duration":"114.419005ms","start":"2026-10-04T05:49:17.497134Z","end":"2026-10-04T05:49:17.611553Z","steps":["trace[2012733723] 'read index received' (duration: 114.290093ms)","trace[2012733723] 'applied index is now lower than readState.Index' (duration: 127.872µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:49:17.611606Z","caller":"traceutil/trace.go:171","msg":"trace[837426683] transaction","detail":"{read_only:false; response_revision:5145; number_of_response:1; }","duration":"156.430508ms","start":"2026-10-04T05:49:17.455146Z","end":"2026-10-04T05:49:17.611576Z","steps":["trace[837426683] 'process raft request' (duration: 156.279235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:17.611786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.631709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-10-04T05:49:17.61183Z","caller":"traceutil/trace.go:171","msg":"trace[426878372] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5145; }","duration":"114.71896ms","start":"2026-10-04T05:49:17.497101Z","end":"2026-10-04T05:49:17.61182Z","steps":["trace[426878372] 'agreement among raft nodes before linearized reading' (duration: 114.555957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:17.61219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.690853ms","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-10-04T05:49:17.612219Z","caller":"traceutil/trace.go:171","msg":"trace[228869620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5145; }","duration":"112.753304ms","start":"2026-10-04T05:49:17.49946Z","end":"2026-10-04T05:49:17.612213Z","steps":["trace[228869620] 'agreement among raft nodes before linearized reading' (duration: 112.580611ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:17.846884Z","caller":"traceutil/trace.go:171","msg":"trace[336099565] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"226.07331ms","start":"2026-10-04T05:49:17.620791Z","end":"2026-10-04T05:49:17.846865Z","steps":["trace[336099565] 'process raft request' (duration: 225.891426ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:18.681934Z","caller":"traceutil/trace.go:171","msg":"trace[1706118591] linearizableReadLoop","detail":"{readStateIndex:5360; appliedIndex:5359; }","duration":"126.906133ms","start":"2026-10-04T05:49:18.555008Z","end":"2026-10-04T05:49:18.681914Z","steps":["trace[1706118591] 'read index received' (duration: 126.686359ms)","trace[1706118591] 'applied index is now lower than readState.Index' (duration: 218.894µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:49:18.682032Z","caller":"traceutil/trace.go:171","msg":"trace[498256922] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"157.327182ms","start":"2026-10-04T05:49:18.524695Z","end":"2026-10-04T05:49:18.682022Z","steps":["trace[498256922] 'process raft request' (duration: 157.086488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:18.682342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.333031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-04T05:49:18.682412Z","caller":"traceutil/trace.go:171","msg":"trace[706256572] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5159; }","duration":"127.442493ms","start":"2026-10-04T05:49:18.554956Z","end":"2026-10-04T05:49:18.682398Z","steps":["trace[706256572] 'agreement among raft nodes before linearized reading' (duration: 127.323951ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:46.412901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2849} {"level":"info","ts":"2026-10-04T05:49:46.488953Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2849,"took":"74.735226ms","hash":575932463,"current-db-size-bytes":52338688,"current-db-size":"52 MB","current-db-size-in-use-bytes":28262400,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-04T05:49:46.489016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":575932463,"revision":2849,"compact-revision":-1} {"level":"info","ts":"2026-10-04T05:54:46.421476Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5449} {"level":"info","ts":"2026-10-04T05:54:46.527465Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5449,"took":"104.650364ms","hash":4091059511,"current-db-size-bytes":52338688,"current-db-size":"52 MB","current-db-size-in-use-bytes":18452480,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-04T05:54:46.527746Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4091059511,"revision":5449,"compact-revision":2849}