{"level":"warn","ts":"2026-10-04T12:42:39.217952Z","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-04T12:42:39.218059Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.223: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.223:2380","--initial-cluster=instance=https://162.253.55.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.223: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-04T12:42:39.218123Z","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-04T12:42:39.218133Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.223:2380"]} {"level":"info","ts":"2026-10-04T12:42:39.218151Z","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-04T12:42:39.218898Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.223:2379"]} {"level":"info","ts":"2026-10-04T12:42:39.219002Z","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.223:2380"],"listen-peer-urls":["https://162.253.55.223:2380"],"advertise-client-urls":["https://162.253.55.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.223: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-04T12:42:39.223907Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.581325ms"} {"level":"info","ts":"2026-10-04T12:42:39.229089Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0f50f8a6c5f7d06","cluster-id":"62f129f77cf8e487"} {"level":"info","ts":"2026-10-04T12:42:39.229175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 switched to configuration voters=()"} {"level":"info","ts":"2026-10-04T12:42:39.229193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became follower at term 0"} {"level":"info","ts":"2026-10-04T12:42:39.229204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0f50f8a6c5f7d06 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-04T12:42:39.229214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became follower at term 1"} {"level":"info","ts":"2026-10-04T12:42:39.229249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 switched to configuration voters=(11598193502560681222)"} {"level":"warn","ts":"2026-10-04T12:42:39.23757Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-04T12:42:39.239492Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-04T12:42:39.241704Z","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-04T12:42:39.244909Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0f50f8a6c5f7d06","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-04T12:42:39.245418Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0f50f8a6c5f7d06","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-04T12:42:39.245587Z","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-04T12:42:39.245645Z","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-04T12:42:39.245683Z","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-04T12:42:39.247455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 switched to configuration voters=(11598193502560681222)"} {"level":"info","ts":"2026-10-04T12:42:39.247593Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62f129f77cf8e487","local-member-id":"a0f50f8a6c5f7d06","added-peer-id":"a0f50f8a6c5f7d06","added-peer-peer-urls":["https://162.253.55.223:2380"]} {"level":"info","ts":"2026-10-04T12:42:39.249618Z","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-04T12:42:39.24972Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.223:2380"} {"level":"info","ts":"2026-10-04T12:42:39.249763Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.223:2380"} {"level":"info","ts":"2026-10-04T12:42:39.249848Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0f50f8a6c5f7d06","initial-advertise-peer-urls":["https://162.253.55.223:2380"],"listen-peer-urls":["https://162.253.55.223:2380"],"advertise-client-urls":["https://162.253.55.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-04T12:42:39.249878Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-04T12:42:39.93007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 is starting a new election at term 1"} {"level":"info","ts":"2026-10-04T12:42:39.930177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-04T12:42:39.930218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 received MsgPreVoteResp from a0f50f8a6c5f7d06 at term 1"} {"level":"info","ts":"2026-10-04T12:42:39.930242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became candidate at term 2"} {"level":"info","ts":"2026-10-04T12:42:39.930284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 received MsgVoteResp from a0f50f8a6c5f7d06 at term 2"} {"level":"info","ts":"2026-10-04T12:42:39.930305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became leader at term 2"} {"level":"info","ts":"2026-10-04T12:42:39.930326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0f50f8a6c5f7d06 elected leader a0f50f8a6c5f7d06 at term 2"} {"level":"info","ts":"2026-10-04T12:42:39.931937Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0f50f8a6c5f7d06","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.223:2379]}","request-path":"/0/members/a0f50f8a6c5f7d06/attributes","cluster-id":"62f129f77cf8e487","publish-timeout":"7s"} {"level":"info","ts":"2026-10-04T12:42:39.931955Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T12:42:39.932502Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:42:39.932582Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T12:42:39.933056Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-04T12:42:39.933134Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-04T12:42:39.933799Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62f129f77cf8e487","local-member-id":"a0f50f8a6c5f7d06","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:42:39.933908Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:42:39.933955Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:42:39.934454Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-04T12:42:39.936638Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.223:2379"} {"level":"info","ts":"2026-10-04T12:42:58.695079Z","caller":"traceutil/trace.go:171","msg":"trace[1292464817] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"154.396745ms","start":"2026-10-04T12:42:58.54066Z","end":"2026-10-04T12:42:58.695057Z","steps":["trace[1292464817] 'process raft request' (duration: 153.968318ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:42:58.982288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.526621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T12:42:58.982435Z","caller":"traceutil/trace.go:171","msg":"trace[1491658344] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"282.475949ms","start":"2026-10-04T12:42:58.699933Z","end":"2026-10-04T12:42:58.982409Z","steps":["trace[1491658344] 'process raft request' (duration: 112.060343ms)","trace[1491658344] 'compare' (duration: 169.403078ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:42:58.983342Z","caller":"traceutil/trace.go:171","msg":"trace[1073023531] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"278.826315ms","start":"2026-10-04T12:42:58.704496Z","end":"2026-10-04T12:42:58.983323Z","steps":["trace[1073023531] 'process raft request' (duration: 278.610111ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:42:59.074877Z","caller":"traceutil/trace.go:171","msg":"trace[1418494217] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:391; }","duration":"144.801408ms","start":"2026-10-04T12:42:58.930049Z","end":"2026-10-04T12:42:59.074851Z","steps":["trace[1418494217] 'read index received' (duration: 53.008919ms)","trace[1418494217] 'applied index is now lower than readState.Index' (duration: 91.790979ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:42:59.075054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.986691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T12:42:59.075101Z","caller":"traceutil/trace.go:171","msg":"trace[1400092110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:380; }","duration":"145.091182ms","start":"2026-10-04T12:42:58.929994Z","end":"2026-10-04T12:42:59.075086Z","steps":["trace[1400092110] 'agreement among raft nodes before linearized reading' (duration: 144.98985ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:43:23.294146Z","caller":"traceutil/trace.go:171","msg":"trace[960726497] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"127.744124ms","start":"2026-10-04T12:43:23.166351Z","end":"2026-10-04T12:43:23.294095Z","steps":["trace[960726497] 'process raft request' (duration: 127.419419ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:43:23.597146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.855587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-04T12:43:23.597242Z","caller":"traceutil/trace.go:171","msg":"trace[889431539] range","detail":"{range_begin:/registry/masterleases/162.253.55.223; range_end:; response_count:1; response_revision:592; }","duration":"244.038201ms","start":"2026-10-04T12:43:23.353181Z","end":"2026-10-04T12:43:23.59722Z","steps":["trace[889431539] 'range keys from in-memory index tree' (duration: 243.662625ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:43:23.603899Z","caller":"traceutil/trace.go:171","msg":"trace[1331463917] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"212.370775ms","start":"2026-10-04T12:43:23.391503Z","end":"2026-10-04T12:43:23.603874Z","steps":["trace[1331463917] 'read index received' (duration: 211.676065ms)","trace[1331463917] 'applied index is now lower than readState.Index' (duration: 693.72µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:43:23.603937Z","caller":"traceutil/trace.go:171","msg":"trace[1973751502] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"227.093532ms","start":"2026-10-04T12:43:23.37682Z","end":"2026-10-04T12:43:23.603914Z","steps":["trace[1973751502] 'process raft request' (duration: 226.515303ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:43:23.604096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.572988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nb6j9\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-04T12:43:23.604151Z","caller":"traceutil/trace.go:171","msg":"trace[523568372] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nb6j9; range_end:; response_count:1; response_revision:593; }","duration":"212.67355ms","start":"2026-10-04T12:43:23.391467Z","end":"2026-10-04T12:43:23.60414Z","steps":["trace[523568372] 'agreement among raft nodes before linearized reading' (duration: 212.511178ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:43:24.247366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.394404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T12:43:24.247469Z","caller":"traceutil/trace.go:171","msg":"trace[1364013300] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"616.786585ms","start":"2026-10-04T12:43:23.630664Z","end":"2026-10-04T12:43:24.24745Z","steps":["trace[1364013300] 'read index received' (duration: 179.501393ms)","trace[1364013300] 'applied index is now lower than readState.Index' (duration: 437.284162ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:43:24.247536Z","caller":"traceutil/trace.go:171","msg":"trace[998499860] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"617.281932ms","start":"2026-10-04T12:43:23.630243Z","end":"2026-10-04T12:43:24.247525Z","steps":["trace[998499860] 'process raft request' (duration: 179.624134ms)","trace[998499860] 'compare' (duration: 437.248291ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:43:24.247595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:43:23.630212Z","time spent":"617.352883ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T12:43:24.247794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.092489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-04T12:43:24.247909Z","caller":"traceutil/trace.go:171","msg":"trace[1142670350] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:595; }","duration":"617.228871ms","start":"2026-10-04T12:43:23.630656Z","end":"2026-10-04T12:43:24.247885Z","steps":["trace[1142670350] 'agreement among raft nodes before linearized reading' (duration: 616.975477ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:43:24.247956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:43:23.630596Z","time spent":"617.352323ms","remote":"127.0.0.1:59306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-10-04T12:43:24.271855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.111908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T12:43:24.271945Z","caller":"traceutil/trace.go:171","msg":"trace[356402069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"341.234811ms","start":"2026-10-04T12:43:23.930687Z","end":"2026-10-04T12:43:24.271922Z","steps":["trace[356402069] 'agreement among raft nodes before linearized reading' (duration: 341.057259ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:43:24.271964Z","caller":"traceutil/trace.go:171","msg":"trace[304603461] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"575.048701ms","start":"2026-10-04T12:43:23.696889Z","end":"2026-10-04T12:43:24.271937Z","steps":["trace[304603461] 'process raft request' (duration: 574.698385ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:43:24.271991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:43:23.930666Z","time spent":"341.312841ms","remote":"127.0.0.1:59160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T12:43:24.272096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:43:23.696855Z","time spent":"575.168562ms","remote":"127.0.0.1:59660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T12:43:24.272582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.440181ms","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-10-04T12:43:24.272735Z","caller":"traceutil/trace.go:171","msg":"trace[412707654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"334.720205ms","start":"2026-10-04T12:43:23.937988Z","end":"2026-10-04T12:43:24.272708Z","steps":["trace[412707654] 'agreement among raft nodes before linearized reading' (duration: 334.120966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:43:24.272826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:43:23.93796Z","time spent":"334.852907ms","remote":"127.0.0.1:59414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-04T12:44:09.726848Z","caller":"traceutil/trace.go:171","msg":"trace[1393189984] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"161.386335ms","start":"2026-10-04T12:44:09.565434Z","end":"2026-10-04T12:44:09.726821Z","steps":["trace[1393189984] 'process raft request' (duration: 161.217693ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:44:56.768501Z","caller":"traceutil/trace.go:171","msg":"trace[940227237] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"109.540468ms","start":"2026-10-04T12:44:56.658894Z","end":"2026-10-04T12:44:56.768435Z","steps":["trace[940227237] 'process raft request' (duration: 108.82747ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:44:59.646419Z","caller":"traceutil/trace.go:171","msg":"trace[1375526279] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"272.858684ms","start":"2026-10-04T12:44:59.373276Z","end":"2026-10-04T12:44:59.646135Z","steps":["trace[1375526279] 'process raft request' (duration: 272.686053ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:44:59.715575Z","caller":"traceutil/trace.go:171","msg":"trace[1293250121] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1900; }","duration":"313.697386ms","start":"2026-10-04T12:44:59.401855Z","end":"2026-10-04T12:44:59.715552Z","steps":["trace[1293250121] 'read index received' (duration: 244.062435ms)","trace[1293250121] 'applied index is now lower than readState.Index' (duration: 69.633631ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:44:59.715708Z","caller":"traceutil/trace.go:171","msg":"trace[739196924] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"326.655716ms","start":"2026-10-04T12:44:59.389034Z","end":"2026-10-04T12:44:59.71569Z","steps":["trace[739196924] 'process raft request' (duration: 292.19577ms)","trace[739196924] 'compare' (duration: 34.229344ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:44:59.715846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:44:59.38902Z","time spent":"326.730918ms","remote":"127.0.0.1:59414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T12:44:59.716106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.242152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-04T12:44:59.71618Z","caller":"traceutil/trace.go:171","msg":"trace[1348270694] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1829; }","duration":"314.344963ms","start":"2026-10-04T12:44:59.401815Z","end":"2026-10-04T12:44:59.71616Z","steps":["trace[1348270694] 'agreement among raft nodes before linearized reading' (duration: 314.186252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:44:59.716234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:44:59.4018Z","time spent":"314.420974ms","remote":"127.0.0.1:59414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-04T12:45:00.058452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.281035ms","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-04T12:45:00.058579Z","caller":"traceutil/trace.go:171","msg":"trace[419946502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1830; }","duration":"219.453287ms","start":"2026-10-04T12:44:59.839104Z","end":"2026-10-04T12:45:00.058558Z","steps":["trace[419946502] 'range keys from in-memory index tree' (duration: 219.118874ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:45:00.059439Z","caller":"traceutil/trace.go:171","msg":"trace[2098126676] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"100.491009ms","start":"2026-10-04T12:44:59.958922Z","end":"2026-10-04T12:45:00.059413Z","steps":["trace[2098126676] 'process raft request' (duration: 59.294396ms)","trace[2098126676] 'compare' (duration: 39.934881ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:45:00.059666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.324611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be727350-4996-4a00-8439-69db9d44f8a9\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-04T12:45:00.060493Z","caller":"traceutil/trace.go:171","msg":"trace[354812509] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-be727350-4996-4a00-8439-69db9d44f8a9; range_end:; response_count:1; response_revision:1830; }","duration":"141.19351ms","start":"2026-10-04T12:44:59.919272Z","end":"2026-10-04T12:45:00.060466Z","steps":["trace[354812509] 'range keys from in-memory index tree' (duration: 139.669634ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:45:12.315604Z","caller":"traceutil/trace.go:171","msg":"trace[248773032] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2007; }","duration":"163.02664ms","start":"2026-10-04T12:45:12.152558Z","end":"2026-10-04T12:45:12.315585Z","steps":["trace[248773032] 'read index received' (duration: 162.888679ms)","trace[248773032] 'applied index is now lower than readState.Index' (duration: 137.021µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:45:12.315789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.211642ms","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-04T12:45:12.315828Z","caller":"traceutil/trace.go:171","msg":"trace[509993720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1932; }","duration":"163.282203ms","start":"2026-10-04T12:45:12.152536Z","end":"2026-10-04T12:45:12.315818Z","steps":["trace[509993720] 'agreement among raft nodes before linearized reading' (duration: 163.129672ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:45:12.316091Z","caller":"traceutil/trace.go:171","msg":"trace[1554073418] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"175.411372ms","start":"2026-10-04T12:45:12.140667Z","end":"2026-10-04T12:45:12.316079Z","steps":["trace[1554073418] 'process raft request' (duration: 174.802717ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:45:12.586042Z","caller":"traceutil/trace.go:171","msg":"trace[1151217079] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"266.307105ms","start":"2026-10-04T12:45:12.319684Z","end":"2026-10-04T12:45:12.585991Z","steps":["trace[1151217079] 'process raft request' (duration: 256.793971ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:45:34.202188Z","caller":"traceutil/trace.go:171","msg":"trace[1016626733] linearizableReadLoop","detail":"{readStateIndex:2148; appliedIndex:2147; }","duration":"254.372149ms","start":"2026-10-04T12:45:33.94779Z","end":"2026-10-04T12:45:34.202162Z","steps":["trace[1016626733] 'read index received' (duration: 177.241456ms)","trace[1016626733] 'applied index is now lower than readState.Index' (duration: 77.129713ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:45:34.202251Z","caller":"traceutil/trace.go:171","msg":"trace[1485923475] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"269.498473ms","start":"2026-10-04T12:45:33.932713Z","end":"2026-10-04T12:45:34.202211Z","steps":["trace[1485923475] 'process raft request' (duration: 192.389301ms)","trace[1485923475] 'compare' (duration: 76.954341ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:45:34.202407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.595062ms","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-04T12:45:34.202449Z","caller":"traceutil/trace.go:171","msg":"trace[481873096] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2067; }","duration":"254.697263ms","start":"2026-10-04T12:45:33.947739Z","end":"2026-10-04T12:45:34.202436Z","steps":["trace[481873096] 'agreement among raft nodes before linearized reading' (duration: 254.532781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:45:34.658332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.294766ms","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-04T12:45:34.658422Z","caller":"traceutil/trace.go:171","msg":"trace[548945510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2068; }","duration":"136.507357ms","start":"2026-10-04T12:45:34.521895Z","end":"2026-10-04T12:45:34.658402Z","steps":["trace[548945510] 'range keys from in-memory index tree' (duration: 136.057543ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:45:35.531704Z","caller":"traceutil/trace.go:171","msg":"trace[805253207] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"106.476771ms","start":"2026-10-04T12:45:35.425196Z","end":"2026-10-04T12:45:35.531673Z","steps":["trace[805253207] 'process raft request' (duration: 106.318319ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:46:46.683207Z","caller":"traceutil/trace.go:171","msg":"trace[598131335] transaction","detail":"{read_only:false; response_revision:2557; number_of_response:1; }","duration":"129.841046ms","start":"2026-10-04T12:46:46.553342Z","end":"2026-10-04T12:46:46.683183Z","steps":["trace[598131335] 'process raft request' (duration: 129.697212ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:46:46.683208Z","caller":"traceutil/trace.go:171","msg":"trace[1376595353] linearizableReadLoop","detail":"{readStateIndex:2654; appliedIndex:2654; }","duration":"128.417791ms","start":"2026-10-04T12:46:46.554757Z","end":"2026-10-04T12:46:46.683175Z","steps":["trace[1376595353] 'read index received' (duration: 128.40614ms)","trace[1376595353] 'applied index is now lower than readState.Index' (duration: 9.28µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:46:46.683456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.671276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1315"} {"level":"info","ts":"2026-10-04T12:46:46.683529Z","caller":"traceutil/trace.go:171","msg":"trace[1257252243] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2557; }","duration":"128.772859ms","start":"2026-10-04T12:46:46.554746Z","end":"2026-10-04T12:46:46.683519Z","steps":["trace[1257252243] 'agreement among raft nodes before linearized reading' (duration: 128.469671ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:47:18.892516Z","caller":"traceutil/trace.go:171","msg":"trace[1155561397] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"106.82646ms","start":"2026-10-04T12:47:18.785665Z","end":"2026-10-04T12:47:18.892492Z","steps":["trace[1155561397] 'process raft request' (duration: 106.39663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:50.20789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.239969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T12:51:50.208045Z","caller":"traceutil/trace.go:171","msg":"trace[581587657] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"185.292736ms","start":"2026-10-04T12:51:50.022742Z","end":"2026-10-04T12:51:50.208035Z","steps":["trace[581587657] 'process raft request' (duration: 185.216425ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:50.20823Z","caller":"traceutil/trace.go:171","msg":"trace[484223186] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"213.459073ms","start":"2026-10-04T12:51:49.994765Z","end":"2026-10-04T12:51:50.208224Z","steps":["trace[484223186] 'process raft request' (duration: 36.818528ms)","trace[484223186] 'compare' (duration: 176.072428ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:51:50.20829Z","caller":"traceutil/trace.go:171","msg":"trace[728957560] linearizableReadLoop","detail":"{readStateIndex:5112; appliedIndex:5111; }","duration":"211.822492ms","start":"2026-10-04T12:51:49.996464Z","end":"2026-10-04T12:51:50.208286Z","steps":["trace[728957560] 'read index received' (duration: 35.101356ms)","trace[728957560] 'applied index is now lower than readState.Index' (duration: 176.720526ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:51:50.208448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.981925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-10-04T12:51:50.208467Z","caller":"traceutil/trace.go:171","msg":"trace[1111868746] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4924; }","duration":"212.015646ms","start":"2026-10-04T12:51:49.996447Z","end":"2026-10-04T12:51:50.208463Z","steps":["trace[1111868746] 'agreement among raft nodes before linearized reading' (duration: 211.949795ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:50.208567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.459249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-fxn86.18db549af024e8d9\" ","response":"range_response_count:1 size:832"} {"level":"info","ts":"2026-10-04T12:51:50.208584Z","caller":"traceutil/trace.go:171","msg":"trace[1429791975] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-fxn86.18db549af024e8d9; range_end:; response_count:1; response_revision:4924; }","duration":"167.499589ms","start":"2026-10-04T12:51:50.041079Z","end":"2026-10-04T12:51:50.208579Z","steps":["trace[1429791975] 'agreement among raft nodes before linearized reading' (duration: 167.455808ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:50.209171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.746994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-10-04T12:51:50.209185Z","caller":"traceutil/trace.go:171","msg":"trace[353627126] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4924; }","duration":"138.788494ms","start":"2026-10-04T12:51:50.070393Z","end":"2026-10-04T12:51:50.209181Z","steps":["trace[353627126] 'agreement among raft nodes before linearized reading' (duration: 138.281408ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:50.401727Z","caller":"traceutil/trace.go:171","msg":"trace[1902638903] linearizableReadLoop","detail":"{readStateIndex:5114; appliedIndex:5113; }","duration":"182.654653ms","start":"2026-10-04T12:51:50.219039Z","end":"2026-10-04T12:51:50.401694Z","steps":["trace[1902638903] 'read index received' (duration: 119.413819ms)","trace[1902638903] 'applied index is now lower than readState.Index' (duration: 63.239604ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:51:50.401809Z","caller":"traceutil/trace.go:171","msg":"trace[652634823] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"187.508094ms","start":"2026-10-04T12:51:50.214235Z","end":"2026-10-04T12:51:50.401743Z","steps":["trace[652634823] 'process raft request' (duration: 124.172049ms)","trace[652634823] 'compare' (duration: 63.158523ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:51:50.402091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.021947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-10-04T12:51:50.402138Z","caller":"traceutil/trace.go:171","msg":"trace[478352876] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4925; }","duration":"183.090958ms","start":"2026-10-04T12:51:50.219035Z","end":"2026-10-04T12:51:50.402126Z","steps":["trace[478352876] 'agreement among raft nodes before linearized reading' (duration: 182.806784ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:50.402501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.641862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:60885"} {"level":"info","ts":"2026-10-04T12:51:50.402559Z","caller":"traceutil/trace.go:171","msg":"trace[905442995] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4925; }","duration":"182.730573ms","start":"2026-10-04T12:51:50.219814Z","end":"2026-10-04T12:51:50.402545Z","steps":["trace[905442995] 'agreement among raft nodes before linearized reading' (duration: 182.47141ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:50.402601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.510324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-10-04T12:51:50.402645Z","caller":"traceutil/trace.go:171","msg":"trace[293870000] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4925; }","duration":"154.552365ms","start":"2026-10-04T12:51:50.248079Z","end":"2026-10-04T12:51:50.402632Z","steps":["trace[293870000] 'agreement among raft nodes before linearized reading' (duration: 154.446203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:50.799199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.474012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T12:51:50.799491Z","caller":"traceutil/trace.go:171","msg":"trace[1918090092] linearizableReadLoop","detail":"{readStateIndex:5119; appliedIndex:5117; }","duration":"169.763919ms","start":"2026-10-04T12:51:50.629706Z","end":"2026-10-04T12:51:50.79947Z","steps":["trace[1918090092] 'read index received' (duration: 15.813522ms)","trace[1918090092] 'applied index is now lower than readState.Index' (duration: 153.949127ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:51:50.799646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.922391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T12:51:50.79968Z","caller":"traceutil/trace.go:171","msg":"trace[779400591] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:4930; }","duration":"170.014541ms","start":"2026-10-04T12:51:50.629656Z","end":"2026-10-04T12:51:50.79967Z","steps":["trace[779400591] 'agreement among raft nodes before linearized reading' (duration: 169.934889ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:50.799707Z","caller":"traceutil/trace.go:171","msg":"trace[1084268036] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"229.441178ms","start":"2026-10-04T12:51:50.570228Z","end":"2026-10-04T12:51:50.799669Z","steps":["trace[1084268036] 'process raft request' (duration: 229.106453ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:50.799785Z","caller":"traceutil/trace.go:171","msg":"trace[591433253] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"234.032235ms","start":"2026-10-04T12:51:50.565725Z","end":"2026-10-04T12:51:50.799757Z","steps":["trace[591433253] 'process raft request' (duration: 79.848384ms)","trace[591433253] 'compare' (duration: 153.145317ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:51:51.265691Z","caller":"traceutil/trace.go:171","msg":"trace[169446088] linearizableReadLoop","detail":"{readStateIndex:5125; appliedIndex:5124; }","duration":"157.328519ms","start":"2026-10-04T12:51:51.10834Z","end":"2026-10-04T12:51:51.265669Z","steps":["trace[169446088] 'read index received' (duration: 143.844048ms)","trace[169446088] 'applied index is now lower than readState.Index' (duration: 13.483391ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:51:51.265807Z","caller":"traceutil/trace.go:171","msg":"trace[725563220] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"158.061917ms","start":"2026-10-04T12:51:51.107732Z","end":"2026-10-04T12:51:51.265794Z","steps":["trace[725563220] 'process raft request' (duration: 144.502845ms)","trace[725563220] 'compare' (duration: 13.212328ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:51:51.266172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.844045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-10-04T12:51:51.266206Z","caller":"traceutil/trace.go:171","msg":"trace[96561990] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4937; }","duration":"157.921857ms","start":"2026-10-04T12:51:51.108273Z","end":"2026-10-04T12:51:51.266195Z","steps":["trace[96561990] 'agreement among raft nodes before linearized reading' (duration: 157.752984ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:52:04.244831Z","caller":"traceutil/trace.go:171","msg":"trace[2024022254] linearizableReadLoop","detail":"{readStateIndex:5311; appliedIndex:5310; }","duration":"125.207716ms","start":"2026-10-04T12:52:04.119589Z","end":"2026-10-04T12:52:04.244796Z","steps":["trace[2024022254] 'read index received' (duration: 124.958342ms)","trace[2024022254] 'applied index is now lower than readState.Index' (duration: 248.244µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:52:04.244954Z","caller":"traceutil/trace.go:171","msg":"trace[1391906042] transaction","detail":"{read_only:false; response_revision:5115; number_of_response:1; }","duration":"176.559951ms","start":"2026-10-04T12:52:04.068384Z","end":"2026-10-04T12:52:04.244944Z","steps":["trace[1391906042] 'process raft request' (duration: 176.271097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:52:04.245281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.659441ms","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-10-04T12:52:04.24613Z","caller":"traceutil/trace.go:171","msg":"trace[978831953] 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:5115; }","duration":"125.821822ms","start":"2026-10-04T12:52:04.119555Z","end":"2026-10-04T12:52:04.245376Z","steps":["trace[978831953] 'agreement among raft nodes before linearized reading' (duration: 125.507889ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:52:39.962549Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2849} {"level":"info","ts":"2026-10-04T12:52:40.093495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2849,"took":"129.331243ms","hash":3474377471,"current-db-size-bytes":53264384,"current-db-size":"53 MB","current-db-size-in-use-bytes":28291072,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-04T12:52:40.093584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3474377471,"revision":2849,"compact-revision":-1} {"level":"info","ts":"2026-10-04T12:55:38.55645Z","caller":"traceutil/trace.go:171","msg":"trace[638140307] transaction","detail":"{read_only:false; response_revision:6679; number_of_response:1; }","duration":"128.954832ms","start":"2026-10-04T12:55:38.427467Z","end":"2026-10-04T12:55:38.556422Z","steps":["trace[638140307] 'process raft request' (duration: 128.787217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:56:08.106192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.313775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107251"} {"level":"info","ts":"2026-10-04T12:56:08.106296Z","caller":"traceutil/trace.go:171","msg":"trace[1478274034] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6888; }","duration":"117.468669ms","start":"2026-10-04T12:56:07.988805Z","end":"2026-10-04T12:56:08.106273Z","steps":["trace[1478274034] 'range keys from in-memory index tree' (duration: 116.242224ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:57:39.970027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5436} {"level":"info","ts":"2026-10-04T12:57:40.052149Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5436,"took":"80.531794ms","hash":1338920696,"current-db-size-bytes":53264384,"current-db-size":"53 MB","current-db-size-in-use-bytes":18690048,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-04T12:57:40.052213Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1338920696,"revision":5436,"compact-revision":2849} {"level":"info","ts":"2026-10-04T13:02:39.976312Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7380} {"level":"info","ts":"2026-10-04T13:02:40.031236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7380,"took":"53.541884ms","hash":639463875,"current-db-size-bytes":53264384,"current-db-size":"53 MB","current-db-size-in-use-bytes":15065088,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-10-04T13:02:40.031302Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":639463875,"revision":7380,"compact-revision":5436}