{"level":"warn","ts":"2026-09-22T01:57:11.252138Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:57:11.252274Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209: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.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-22T01:57:11.252386Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:57:11.252397Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-09-22T01:57:11.25244Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-22T01:57:11.253239Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-09-22T01:57:11.253326Z","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.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-22T01:57:11.257553Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.978632ms"} {"level":"info","ts":"2026-09-22T01:57:11.26795Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-09-22T01:57:11.268054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T01:57:11.268121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-09-22T01:57:11.268134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T01:57:11.268141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-09-22T01:57:11.26823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-09-22T01:57:11.275667Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T01:57:11.279591Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T01:57:11.28099Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-22T01:57:11.282866Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T01:57:11.284807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-09-22T01:57:11.284721Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-22T01:57:11.284942Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-09-22T01:57:11.284308Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T01:57:11.285356Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-22T01:57:11.285413Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-22T01:57:11.288442Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-22T01:57:11.288768Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T01:57:11.288811Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T01:57:11.28902Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-09-22T01:57:11.289035Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-09-22T01:57:11.968545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T01:57:11.968628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T01:57:11.968672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-09-22T01:57:11.968702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-09-22T01:57:11.96872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-09-22T01:57:11.968743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-09-22T01:57:11.968764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-09-22T01:57:11.970239Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T01:57:11.970442Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:57:11.970564Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:57:11.970893Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:57:11.971398Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T01:57:11.971424Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T01:57:11.97194Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:57:11.972554Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:57:11.972744Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:57:11.976274Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-09-22T01:57:11.976283Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-22T01:57:14.096529Z","caller":"traceutil/trace.go:171","msg":"trace[526439961] linearizableReadLoop","detail":"{readStateIndex:71; appliedIndex:70; }","duration":"212.665631ms","start":"2026-09-22T01:57:13.883825Z","end":"2026-09-22T01:57:14.09649Z","steps":["trace[526439961] 'read index received' (duration: 208.998016ms)","trace[526439961] 'applied index is now lower than readState.Index' (duration: 3.666265ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:57:14.096823Z","caller":"traceutil/trace.go:171","msg":"trace[1300525753] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"250.640547ms","start":"2026-09-22T01:57:13.846168Z","end":"2026-09-22T01:57:14.096809Z","steps":["trace[1300525753] 'process raft request' (duration: 246.618536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:14.096961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.083068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-22T01:57:14.097024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.836627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:57:14.097161Z","caller":"traceutil/trace.go:171","msg":"trace[1753814683] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:67; }","duration":"130.05338ms","start":"2026-09-22T01:57:13.967074Z","end":"2026-09-22T01:57:14.097128Z","steps":["trace[1753814683] 'agreement among raft nodes before linearized reading' (duration: 129.791086ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:14.097075Z","caller":"traceutil/trace.go:171","msg":"trace[532431560] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:67; }","duration":"213.301501ms","start":"2026-09-22T01:57:13.883747Z","end":"2026-09-22T01:57:14.097049Z","steps":["trace[532431560] 'agreement among raft nodes before linearized reading' (duration: 213.100588ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:15.193839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.314826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-09-22T01:57:15.19396Z","caller":"traceutil/trace.go:171","msg":"trace[1559510929] linearizableReadLoop","detail":"{readStateIndex:85; appliedIndex:84; }","duration":"515.748892ms","start":"2026-09-22T01:57:14.678193Z","end":"2026-09-22T01:57:15.193942Z","steps":["trace[1559510929] 'read index received' (duration: 148.651415ms)","trace[1559510929] 'applied index is now lower than readState.Index' (duration: 367.095887ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:57:15.19413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.932734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:57:15.194162Z","caller":"traceutil/trace.go:171","msg":"trace[953640805] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:80; }","duration":"515.988815ms","start":"2026-09-22T01:57:14.678165Z","end":"2026-09-22T01:57:15.194153Z","steps":["trace[953640805] 'agreement among raft nodes before linearized reading' (duration: 515.869803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:15.194186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.764481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-22T01:57:15.194197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:14.67815Z","time spent":"516.039536ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"info","ts":"2026-09-22T01:57:15.194237Z","caller":"traceutil/trace.go:171","msg":"trace[33046612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:80; }","duration":"481.840052ms","start":"2026-09-22T01:57:14.712385Z","end":"2026-09-22T01:57:15.194225Z","steps":["trace[33046612] 'agreement among raft nodes before linearized reading' (duration: 481.69986ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:15.194232Z","caller":"traceutil/trace.go:171","msg":"trace[1651570537] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"516.180248ms","start":"2026-09-22T01:57:14.677979Z","end":"2026-09-22T01:57:15.19416Z","steps":["trace[1651570537] 'process raft request' (duration: 148.856757ms)","trace[1651570537] 'compare' (duration: 366.188154ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:57:15.194266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:14.712374Z","time spent":"481.885343ms","remote":"127.0.0.1:57916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T01:57:15.194402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:14.677962Z","time spent":"516.344371ms","remote":"127.0.0.1:57990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:57:15.453061Z","caller":"traceutil/trace.go:171","msg":"trace[986369499] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"197.656009ms","start":"2026-09-22T01:57:15.255382Z","end":"2026-09-22T01:57:15.453038Z","steps":["trace[986369499] 'process raft request' (duration: 197.514107ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:15.453789Z","caller":"traceutil/trace.go:171","msg":"trace[1628695475] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"197.115391ms","start":"2026-09-22T01:57:15.256651Z","end":"2026-09-22T01:57:15.453766Z","steps":["trace[1628695475] 'process raft request' (duration: 196.930128ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:57.697151Z","caller":"traceutil/trace.go:171","msg":"trace[785849928] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"110.44718ms","start":"2026-09-22T01:57:57.586673Z","end":"2026-09-22T01:57:57.69712Z","steps":["trace[785849928] 'process raft request' (duration: 110.183057ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:57.706849Z","caller":"traceutil/trace.go:171","msg":"trace[614191192] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"112.221201ms","start":"2026-09-22T01:57:57.594602Z","end":"2026-09-22T01:57:57.706823Z","steps":["trace[614191192] 'process raft request' (duration: 111.641504ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:57.706966Z","caller":"traceutil/trace.go:171","msg":"trace[1631441995] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"109.853583ms","start":"2026-09-22T01:57:57.597103Z","end":"2026-09-22T01:57:57.706957Z","steps":["trace[1631441995] 'process raft request' (duration: 109.331287ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:03.752525Z","caller":"traceutil/trace.go:171","msg":"trace[742635909] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"207.938443ms","start":"2026-09-22T01:58:03.544563Z","end":"2026-09-22T01:58:03.752501Z","steps":["trace[742635909] 'process raft request' (duration: 207.406507ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:58:04.048065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.067447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-22T01:58:04.048174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.606039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:58:04.048241Z","caller":"traceutil/trace.go:171","msg":"trace[2066089422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"259.318829ms","start":"2026-09-22T01:58:03.788899Z","end":"2026-09-22T01:58:04.048218Z","steps":["trace[2066089422] 'range keys from in-memory index tree' (duration: 258.918815ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:04.048389Z","caller":"traceutil/trace.go:171","msg":"trace[1595861805] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"217.249553ms","start":"2026-09-22T01:58:03.83109Z","end":"2026-09-22T01:58:04.04834Z","steps":["trace[1595861805] 'process raft request' (duration: 53.390431ms)","trace[1595861805] 'compare' (duration: 163.491168ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:58:04.470858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.776197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-22T01:58:04.470938Z","caller":"traceutil/trace.go:171","msg":"trace[908225030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:652; }","duration":"195.874048ms","start":"2026-09-22T01:58:04.275043Z","end":"2026-09-22T01:58:04.470917Z","steps":["trace[908225030] 'range keys from in-memory index tree' (duration: 195.544615ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:58:04.471522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.912434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-22T01:58:04.471615Z","caller":"traceutil/trace.go:171","msg":"trace[440123345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:652; }","duration":"281.269999ms","start":"2026-09-22T01:58:04.190327Z","end":"2026-09-22T01:58:04.471597Z","steps":["trace[440123345] 'range keys from in-memory index tree' (duration: 280.720013ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:04.626832Z","caller":"traceutil/trace.go:171","msg":"trace[1852115961] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"153.175428ms","start":"2026-09-22T01:58:04.473592Z","end":"2026-09-22T01:58:04.626767Z","steps":["trace[1852115961] 'process raft request' (duration: 152.978446ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:04.663879Z","caller":"traceutil/trace.go:171","msg":"trace[2064108384] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"188.862871ms","start":"2026-09-22T01:58:04.474959Z","end":"2026-09-22T01:58:04.663822Z","steps":["trace[2064108384] 'process raft request' (duration: 188.587608ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:04.664491Z","caller":"traceutil/trace.go:171","msg":"trace[2021090255] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"176.695937ms","start":"2026-09-22T01:58:04.487766Z","end":"2026-09-22T01:58:04.664462Z","steps":["trace[2021090255] 'process raft request' (duration: 176.03475ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:21.402485Z","caller":"traceutil/trace.go:171","msg":"trace[1438384650] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"279.036245ms","start":"2026-09-22T01:58:21.123423Z","end":"2026-09-22T01:58:21.402459Z","steps":["trace[1438384650] 'process raft request' (duration: 278.880034ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:52.577599Z","caller":"traceutil/trace.go:171","msg":"trace[1460442276] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"135.808965ms","start":"2026-09-22T01:58:52.441759Z","end":"2026-09-22T01:58:52.577568Z","steps":["trace[1460442276] 'process raft request' (duration: 129.276304ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:09.817825Z","caller":"traceutil/trace.go:171","msg":"trace[1287815012] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"108.967807ms","start":"2026-09-22T01:59:09.708835Z","end":"2026-09-22T01:59:09.817802Z","steps":["trace[1287815012] 'process raft request' (duration: 108.836496ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:09.821331Z","caller":"traceutil/trace.go:171","msg":"trace[642420561] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"111.070586ms","start":"2026-09-22T01:59:09.710246Z","end":"2026-09-22T01:59:09.821316Z","steps":["trace[642420561] 'process raft request' (duration: 110.821564ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:34.567902Z","caller":"traceutil/trace.go:171","msg":"trace[454501860] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"134.627681ms","start":"2026-09-22T01:59:34.43325Z","end":"2026-09-22T01:59:34.567878Z","steps":["trace[454501860] 'process raft request' (duration: 134.47885ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:34.578144Z","caller":"traceutil/trace.go:171","msg":"trace[836135274] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"144.265623ms","start":"2026-09-22T01:59:34.433852Z","end":"2026-09-22T01:59:34.578117Z","steps":["trace[836135274] 'process raft request' (duration: 144.061931ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:34.57836Z","caller":"traceutil/trace.go:171","msg":"trace[134695224] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"143.731809ms","start":"2026-09-22T01:59:34.434609Z","end":"2026-09-22T01:59:34.578341Z","steps":["trace[134695224] 'process raft request' (duration: 143.632408ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:34.578372Z","caller":"traceutil/trace.go:171","msg":"trace[1633112923] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"143.352965ms","start":"2026-09-22T01:59:34.43501Z","end":"2026-09-22T01:59:34.578363Z","steps":["trace[1633112923] 'process raft request' (duration: 143.281655ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:34.578355Z","caller":"traceutil/trace.go:171","msg":"trace[150213255] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"143.929061ms","start":"2026-09-22T01:59:34.434386Z","end":"2026-09-22T01:59:34.578315Z","steps":["trace[150213255] 'process raft request' (duration: 143.655109ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:34.579281Z","caller":"traceutil/trace.go:171","msg":"trace[214392680] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"143.197434ms","start":"2026-09-22T01:59:34.436071Z","end":"2026-09-22T01:59:34.579268Z","steps":["trace[214392680] 'process raft request' (duration: 142.246616ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:34.579775Z","caller":"traceutil/trace.go:171","msg":"trace[1177033429] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"141.426349ms","start":"2026-09-22T01:59:34.438337Z","end":"2026-09-22T01:59:34.579763Z","steps":["trace[1177033429] 'process raft request' (duration: 140.556072ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:34.58003Z","caller":"traceutil/trace.go:171","msg":"trace[1490600356] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"127.743713ms","start":"2026-09-22T01:59:34.452273Z","end":"2026-09-22T01:59:34.580017Z","steps":["trace[1490600356] 'process raft request' (duration: 127.217419ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:43.450962Z","caller":"traceutil/trace.go:171","msg":"trace[1702092410] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"111.583004ms","start":"2026-09-22T01:59:43.339351Z","end":"2026-09-22T01:59:43.450934Z","steps":["trace[1702092410] 'process raft request' (duration: 90.415227ms)","trace[1702092410] 'compare' (duration: 20.836444ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:59:44.149828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.590244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:59:44.150095Z","caller":"traceutil/trace.go:171","msg":"trace[1418851690] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1995; }","duration":"361.569406ms","start":"2026-09-22T01:59:43.788472Z","end":"2026-09-22T01:59:44.150042Z","steps":["trace[1418851690] 'read index received' (duration: 59.66642ms)","trace[1418851690] 'applied index is now lower than readState.Index' (duration: 301.901766ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:59:44.15018Z","caller":"traceutil/trace.go:171","msg":"trace[822682663] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"617.546909ms","start":"2026-09-22T01:59:43.5326Z","end":"2026-09-22T01:59:44.150147Z","steps":["trace[822682663] 'process raft request' (duration: 315.521032ms)","trace[822682663] 'compare' (duration: 300.533225ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:59:44.150234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.742728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:59:44.150219Z","caller":"traceutil/trace.go:171","msg":"trace[1228743189] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"524.506451ms","start":"2026-09-22T01:59:43.625666Z","end":"2026-09-22T01:59:44.150173Z","steps":["trace[1228743189] 'process raft request' (duration: 524.283389ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:44.150307Z","caller":"traceutil/trace.go:171","msg":"trace[69160783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1919; }","duration":"361.861839ms","start":"2026-09-22T01:59:43.788427Z","end":"2026-09-22T01:59:44.150288Z","steps":["trace[69160783] 'agreement among raft nodes before linearized reading' (duration: 361.743038ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:44.150324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:43.532572Z","time spent":"617.66935ms","remote":"127.0.0.1:58106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:59:44.150349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:43.788407Z","time spent":"361.935289ms","remote":"127.0.0.1:57920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T01:59:44.150609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:43.625639Z","time spent":"524.682382ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:59:44.150276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.395387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:59:44.150874Z","caller":"traceutil/trace.go:171","msg":"trace[1726238066] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1919; }","duration":"262.036203ms","start":"2026-09-22T01:59:43.888822Z","end":"2026-09-22T01:59:44.150858Z","steps":["trace[1726238066] 'agreement among raft nodes before linearized reading' (duration: 261.405808ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:56.612052Z","caller":"traceutil/trace.go:171","msg":"trace[1805301078] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"130.717041ms","start":"2026-09-22T01:59:56.481252Z","end":"2026-09-22T01:59:56.611969Z","steps":["trace[1805301078] 'process raft request' (duration: 129.90185ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:59.396336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.913718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-22T01:59:59.396405Z","caller":"traceutil/trace.go:171","msg":"trace[1464068044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2051; }","duration":"105.023983ms","start":"2026-09-22T01:59:59.291364Z","end":"2026-09-22T01:59:59.396387Z","steps":["trace[1464068044] 'range keys from in-memory index tree' (duration: 104.795012ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:23.043443Z","caller":"traceutil/trace.go:171","msg":"trace[926015652] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2270; }","duration":"153.966819ms","start":"2026-09-22T02:00:22.889444Z","end":"2026-09-22T02:00:23.043411Z","steps":["trace[926015652] 'read index received' (duration: 83.347557ms)","trace[926015652] 'applied index is now lower than readState.Index' (duration: 70.617882ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:00:23.043894Z","caller":"traceutil/trace.go:171","msg":"trace[444763017] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"201.857689ms","start":"2026-09-22T02:00:22.842011Z","end":"2026-09-22T02:00:23.043869Z","steps":["trace[444763017] 'process raft request' (duration: 130.828339ms)","trace[444763017] 'compare' (duration: 70.442285ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:00:23.044372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.250941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-22T02:00:23.044429Z","caller":"traceutil/trace.go:171","msg":"trace[1756006044] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2183; }","duration":"155.000213ms","start":"2026-09-22T02:00:22.889414Z","end":"2026-09-22T02:00:23.044415Z","steps":["trace[1756006044] 'agreement among raft nodes before linearized reading' (duration: 154.116415ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:24.568305Z","caller":"traceutil/trace.go:171","msg":"trace[652617191] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"101.472775ms","start":"2026-09-22T02:00:24.466806Z","end":"2026-09-22T02:00:24.568279Z","steps":["trace[652617191] 'process raft request' (duration: 101.279947ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:24.570325Z","caller":"traceutil/trace.go:171","msg":"trace[361394877] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"101.921414ms","start":"2026-09-22T02:00:24.468379Z","end":"2026-09-22T02:00:24.5703Z","steps":["trace[361394877] 'process raft request' (duration: 101.740816ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:29.998843Z","caller":"traceutil/trace.go:171","msg":"trace[1293911428] linearizableReadLoop","detail":"{readStateIndex:2713; appliedIndex:2712; }","duration":"102.316401ms","start":"2026-09-22T02:01:29.896506Z","end":"2026-09-22T02:01:29.998822Z","steps":["trace[1293911428] 'read index received' (duration: 102.047063ms)","trace[1293911428] 'applied index is now lower than readState.Index' (duration: 268.448µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:29.999105Z","caller":"traceutil/trace.go:171","msg":"trace[1819773060] transaction","detail":"{read_only:false; response_revision:2611; number_of_response:1; }","duration":"146.140145ms","start":"2026-09-22T02:01:29.852942Z","end":"2026-09-22T02:01:29.999082Z","steps":["trace[1819773060] 'process raft request' (duration: 145.738983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:29.999283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.728115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-22T02:01:29.999389Z","caller":"traceutil/trace.go:171","msg":"trace[1307761389] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2611; }","duration":"102.894449ms","start":"2026-09-22T02:01:29.896475Z","end":"2026-09-22T02:01:29.99937Z","steps":["trace[1307761389] 'agreement among raft nodes before linearized reading' (duration: 102.544318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:06.350206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.565599ms","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-09-22T02:02:06.350303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.610939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-22T02:02:06.350339Z","caller":"traceutil/trace.go:171","msg":"trace[1325371507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2843; }","duration":"116.793815ms","start":"2026-09-22T02:02:06.233524Z","end":"2026-09-22T02:02:06.350318Z","steps":["trace[1325371507] 'range keys from in-memory index tree' (duration: 116.322773ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:06.350326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.741332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-22T02:02:06.350392Z","caller":"traceutil/trace.go:171","msg":"trace[1559455421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2843; }","duration":"138.737483ms","start":"2026-09-22T02:02:06.211624Z","end":"2026-09-22T02:02:06.350361Z","steps":["trace[1559455421] 'range keys from in-memory index tree' (duration: 138.419435ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:06.350395Z","caller":"traceutil/trace.go:171","msg":"trace[1422926734] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:2843; }","duration":"109.857915ms","start":"2026-09-22T02:02:06.24052Z","end":"2026-09-22T02:02:06.350378Z","steps":["trace[1422926734] 'range keys from in-memory index tree' (duration: 109.500096ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:06.718221Z","caller":"traceutil/trace.go:171","msg":"trace[1069601075] transaction","detail":"{read_only:false; response_revision:2845; number_of_response:1; }","duration":"363.488385ms","start":"2026-09-22T02:02:06.354708Z","end":"2026-09-22T02:02:06.718197Z","steps":["trace[1069601075] 'process raft request' (duration: 363.440393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:06.718359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:06.354688Z","time spent":"363.603017ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:02:06.718776Z","caller":"traceutil/trace.go:171","msg":"trace[491371379] transaction","detail":"{read_only:false; response_revision:2844; number_of_response:1; }","duration":"364.185323ms","start":"2026-09-22T02:02:06.354554Z","end":"2026-09-22T02:02:06.718739Z","steps":["trace[491371379] 'process raft request' (duration: 363.433393ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:06.718941Z","caller":"traceutil/trace.go:171","msg":"trace[881425304] transaction","detail":"{read_only:false; response_revision:2846; number_of_response:1; }","duration":"216.22278ms","start":"2026-09-22T02:02:06.502692Z","end":"2026-09-22T02:02:06.718915Z","steps":["trace[881425304] 'process raft request' (duration: 215.986314ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:06.719019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:06.354532Z","time spent":"364.311515ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:06:34.401157Z","caller":"traceutil/trace.go:171","msg":"trace[785940263] linearizableReadLoop","detail":"{readStateIndex:5161; appliedIndex:5160; }","duration":"183.117809ms","start":"2026-09-22T02:06:34.218015Z","end":"2026-09-22T02:06:34.401132Z","steps":["trace[785940263] 'read index received' (duration: 120.306191ms)","trace[785940263] 'applied index is now lower than readState.Index' (duration: 62.810608ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:06:34.401617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.285541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:06:34.401594Z","caller":"traceutil/trace.go:171","msg":"trace[820458212] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"251.961282ms","start":"2026-09-22T02:06:34.149598Z","end":"2026-09-22T02:06:34.40156Z","steps":["trace[820458212] 'process raft request' (duration: 188.81808ms)","trace[820458212] 'compare' (duration: 62.535283ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:06:34.401706Z","caller":"traceutil/trace.go:171","msg":"trace[1919254724] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:4965; }","duration":"183.752458ms","start":"2026-09-22T02:06:34.217937Z","end":"2026-09-22T02:06:34.40169Z","steps":["trace[1919254724] 'agreement among raft nodes before linearized reading' (duration: 183.282791ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:34.687559Z","caller":"traceutil/trace.go:171","msg":"trace[294834299] linearizableReadLoop","detail":"{readStateIndex:5165; appliedIndex:5164; }","duration":"101.566245ms","start":"2026-09-22T02:06:34.585963Z","end":"2026-09-22T02:06:34.687529Z","steps":["trace[294834299] 'read index received' (duration: 86.096777ms)","trace[294834299] 'applied index is now lower than readState.Index' (duration: 15.468148ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:06:34.687703Z","caller":"traceutil/trace.go:171","msg":"trace[1537006822] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"104.502768ms","start":"2026-09-22T02:06:34.583184Z","end":"2026-09-22T02:06:34.687687Z","steps":["trace[1537006822] 'process raft request' (duration: 88.875817ms)","trace[1537006822] 'compare' (duration: 15.351077ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:06:34.688139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.154114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-22T02:06:34.68819Z","caller":"traceutil/trace.go:171","msg":"trace[320047659] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4969; }","duration":"102.240396ms","start":"2026-09-22T02:06:34.585934Z","end":"2026-09-22T02:06:34.688174Z","steps":["trace[320047659] 'agreement among raft nodes before linearized reading' (duration: 102.048693ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:34.712314Z","caller":"traceutil/trace.go:171","msg":"trace[422374943] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"124.713873ms","start":"2026-09-22T02:06:34.587585Z","end":"2026-09-22T02:06:34.712299Z","steps":["trace[422374943] 'process raft request' (duration: 124.432109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:06:34.712752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.941971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-22T02:06:34.712822Z","caller":"traceutil/trace.go:171","msg":"trace[2058220884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4970; }","duration":"114.100553ms","start":"2026-09-22T02:06:34.598705Z","end":"2026-09-22T02:06:34.712805Z","steps":["trace[2058220884] 'agreement among raft nodes before linearized reading' (duration: 113.815819ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:38.416007Z","caller":"traceutil/trace.go:171","msg":"trace[1830543859] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"231.307378ms","start":"2026-09-22T02:06:38.18467Z","end":"2026-09-22T02:06:38.415977Z","steps":["trace[1830543859] 'process raft request' (duration: 223.6378ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:41.829047Z","caller":"traceutil/trace.go:171","msg":"trace[1445131939] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"119.401588ms","start":"2026-09-22T02:06:41.709621Z","end":"2026-09-22T02:06:41.829023Z","steps":["trace[1445131939] 'process raft request' (duration: 119.272676ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:06:50.360091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.679826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T02:06:50.360262Z","caller":"traceutil/trace.go:171","msg":"trace[1284129927] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"196.006412ms","start":"2026-09-22T02:06:50.16424Z","end":"2026-09-22T02:06:50.360247Z","steps":["trace[1284129927] 'process raft request' (duration: 195.951282ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:50.360486Z","caller":"traceutil/trace.go:171","msg":"trace[344564797] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"292.076562ms","start":"2026-09-22T02:06:50.068363Z","end":"2026-09-22T02:06:50.36044Z","steps":["trace[344564797] 'process raft request' (duration: 126.97225ms)","trace[344564797] 'compare' (duration: 164.580965ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:06:50.64342Z","caller":"traceutil/trace.go:171","msg":"trace[121254143] linearizableReadLoop","detail":"{readStateIndex:5393; appliedIndex:5392; }","duration":"341.927736ms","start":"2026-09-22T02:06:50.301468Z","end":"2026-09-22T02:06:50.643396Z","steps":["trace[121254143] 'read index received' (duration: 290.52553ms)","trace[121254143] 'applied index is now lower than readState.Index' (duration: 51.401296ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:06:50.643583Z","caller":"traceutil/trace.go:171","msg":"trace[584032136] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"395.042836ms","start":"2026-09-22T02:06:50.248513Z","end":"2026-09-22T02:06:50.643556Z","steps":["trace[584032136] 'process raft request' (duration: 343.480388ms)","trace[584032136] 'compare' (duration: 51.272774ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:06:50.643716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:06:50.248495Z","time spent":"395.140849ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:06:50.643709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.2301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-71a85238-a985-4389-8812-984b914bb122\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-22T02:06:50.643779Z","caller":"traceutil/trace.go:171","msg":"trace[1119991388] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-71a85238-a985-4389-8812-984b914bb122; range_end:; response_count:1; response_revision:5192; }","duration":"342.335761ms","start":"2026-09-22T02:06:50.301425Z","end":"2026-09-22T02:06:50.643761Z","steps":["trace[1119991388] 'agreement among raft nodes before linearized reading' (duration: 342.125108ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:06:50.643823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:06:50.301407Z","time spent":"342.402293ms","remote":"127.0.0.1:58106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-71a85238-a985-4389-8812-984b914bb122\" "} {"level":"warn","ts":"2026-09-22T02:06:50.651004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.078136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:06:50.651043Z","caller":"traceutil/trace.go:171","msg":"trace[195007726] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:5195; }","duration":"184.132097ms","start":"2026-09-22T02:06:50.466903Z","end":"2026-09-22T02:06:50.651035Z","steps":["trace[195007726] 'agreement among raft nodes before linearized reading' (duration: 184.043686ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:50.651042Z","caller":"traceutil/trace.go:171","msg":"trace[977944331] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"298.350169ms","start":"2026-09-22T02:06:50.35268Z","end":"2026-09-22T02:06:50.65103Z","steps":["trace[977944331] 'process raft request' (duration: 298.131406ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:50.650993Z","caller":"traceutil/trace.go:171","msg":"trace[33800779] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"177.208451ms","start":"2026-09-22T02:06:50.473761Z","end":"2026-09-22T02:06:50.650969Z","steps":["trace[33800779] 'process raft request' (duration: 177.13124ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:50.651166Z","caller":"traceutil/trace.go:171","msg":"trace[69371959] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"111.598236ms","start":"2026-09-22T02:06:50.539554Z","end":"2026-09-22T02:06:50.651152Z","steps":["trace[69371959] 'process raft request' (duration: 111.365853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:06:50.651347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.344306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-22T02:06:50.651478Z","caller":"traceutil/trace.go:171","msg":"trace[841831751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5195; }","duration":"178.503068ms","start":"2026-09-22T02:06:50.472924Z","end":"2026-09-22T02:06:50.651427Z","steps":["trace[841831751] 'agreement among raft nodes before linearized reading' (duration: 178.127633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:07:04.100728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.616925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-09-22T02:07:04.100838Z","caller":"traceutil/trace.go:171","msg":"trace[2125714407] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5298; }","duration":"107.809247ms","start":"2026-09-22T02:07:03.993003Z","end":"2026-09-22T02:07:04.100813Z","steps":["trace[2125714407] 'range keys from in-memory index tree' (duration: 107.339121ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:07:12.045035Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2874} {"level":"info","ts":"2026-09-22T02:07:12.217418Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2874,"took":"170.965554ms","hash":3153698661,"current-db-size-bytes":51134464,"current-db-size":"51 MB","current-db-size-in-use-bytes":27811840,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T02:07:12.217496Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3153698661,"revision":2874,"compact-revision":-1} {"level":"warn","ts":"2026-09-22T02:11:51.658155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.306842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T02:11:51.658314Z","caller":"traceutil/trace.go:171","msg":"trace[1742957449] transaction","detail":"{read_only:false; response_revision:7276; number_of_response:1; }","duration":"169.200542ms","start":"2026-09-22T02:11:51.489093Z","end":"2026-09-22T02:11:51.658294Z","steps":["trace[1742957449] 'process raft request' (duration: 59.584691ms)","trace[1742957449] 'compare' (duration: 109.145627ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:12:12.051871Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5347} {"level":"info","ts":"2026-09-22T02:12:12.158006Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5347,"took":"104.504262ms","hash":3559707930,"current-db-size-bytes":51134464,"current-db-size":"51 MB","current-db-size-in-use-bytes":18771968,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-22T02:12:12.158087Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3559707930,"revision":5347,"compact-revision":2874}