{"level":"warn","ts":"2026-09-24T07:34:31.729706Z","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-24T07:34:31.729801Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.104: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.104:2380","--initial-cluster=instance=https://162.253.55.104:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.104:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.104: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-24T07:34:31.729858Z","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-24T07:34:31.729865Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.104:2380"]} {"level":"info","ts":"2026-09-24T07:34:31.729879Z","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-24T07:34:31.730446Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.104:2379"]} {"level":"info","ts":"2026-09-24T07:34:31.730512Z","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.104:2380"],"listen-peer-urls":["https://162.253.55.104:2380"],"advertise-client-urls":["https://162.253.55.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.104: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-24T07:34:31.734532Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.846213ms"} {"level":"info","ts":"2026-09-24T07:34:31.740644Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8572cc3a5351216d","cluster-id":"c1a156b8f69e6a79"} {"level":"info","ts":"2026-09-24T07:34:31.740691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d switched to configuration voters=()"} {"level":"info","ts":"2026-09-24T07:34:31.740704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became follower at term 0"} {"level":"info","ts":"2026-09-24T07:34:31.740713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8572cc3a5351216d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-24T07:34:31.740719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became follower at term 1"} {"level":"info","ts":"2026-09-24T07:34:31.740744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d switched to configuration voters=(9615972705267425645)"} {"level":"warn","ts":"2026-09-24T07:34:31.749686Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-24T07:34:31.750927Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-24T07:34:31.753252Z","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-24T07:34:31.755909Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8572cc3a5351216d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-24T07:34:31.757544Z","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-24T07:34:31.757647Z","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-24T07:34:31.757661Z","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-24T07:34:31.757579Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8572cc3a5351216d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-24T07:34:31.758768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d switched to configuration voters=(9615972705267425645)"} {"level":"info","ts":"2026-09-24T07:34:31.758913Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c1a156b8f69e6a79","local-member-id":"8572cc3a5351216d","added-peer-id":"8572cc3a5351216d","added-peer-peer-urls":["https://162.253.55.104:2380"]} {"level":"info","ts":"2026-09-24T07:34:31.76154Z","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-24T07:34:31.761775Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.104:2380"} {"level":"info","ts":"2026-09-24T07:34:31.761873Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.104:2380"} {"level":"info","ts":"2026-09-24T07:34:31.764355Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8572cc3a5351216d","initial-advertise-peer-urls":["https://162.253.55.104:2380"],"listen-peer-urls":["https://162.253.55.104:2380"],"advertise-client-urls":["https://162.253.55.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-24T07:34:31.764384Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-24T07:34:31.844132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d is starting a new election at term 1"} {"level":"info","ts":"2026-09-24T07:34:31.844189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-24T07:34:31.844217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d received MsgPreVoteResp from 8572cc3a5351216d at term 1"} {"level":"info","ts":"2026-09-24T07:34:31.844231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became candidate at term 2"} {"level":"info","ts":"2026-09-24T07:34:31.844239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d received MsgVoteResp from 8572cc3a5351216d at term 2"} {"level":"info","ts":"2026-09-24T07:34:31.844335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became leader at term 2"} {"level":"info","ts":"2026-09-24T07:34:31.844353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8572cc3a5351216d elected leader 8572cc3a5351216d at term 2"} {"level":"info","ts":"2026-09-24T07:34:31.851927Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8572cc3a5351216d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.104:2379]}","request-path":"/0/members/8572cc3a5351216d/attributes","cluster-id":"c1a156b8f69e6a79","publish-timeout":"7s"} {"level":"info","ts":"2026-09-24T07:34:31.852032Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-24T07:34:31.852174Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-24T07:34:31.852274Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-24T07:34:31.853012Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-24T07:34:31.853036Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-24T07:34:31.853273Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c1a156b8f69e6a79","local-member-id":"8572cc3a5351216d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-24T07:34:31.853337Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-24T07:34:31.853374Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-24T07:34:31.858354Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-24T07:34:31.859462Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.104:2379"} {"level":"warn","ts":"2026-09-24T07:34:42.226052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.317506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-24T07:34:42.226164Z","caller":"traceutil/trace.go:171","msg":"trace[2140913244] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:316; }","duration":"268.073201ms","start":"2026-09-24T07:34:41.958073Z","end":"2026-09-24T07:34:42.226146Z","steps":["trace[2140913244] 'read index received' (duration: 72.112067ms)","trace[2140913244] 'applied index is now lower than readState.Index' (duration: 195.960074ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:34:42.226236Z","caller":"traceutil/trace.go:171","msg":"trace[255080292] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"292.335175ms","start":"2026-09-24T07:34:41.933862Z","end":"2026-09-24T07:34:42.226197Z","steps":["trace[255080292] 'process raft request' (duration: 96.36495ms)","trace[255080292] 'compare' (duration: 195.198723ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T07:34:42.226299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.219696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-24T07:34:42.226347Z","caller":"traceutil/trace.go:171","msg":"trace[1808604042] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:304; }","duration":"268.283537ms","start":"2026-09-24T07:34:41.958049Z","end":"2026-09-24T07:34:42.226333Z","steps":["trace[1808604042] 'agreement among raft nodes before linearized reading' (duration: 268.186244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:34:42.226577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.43531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"warn","ts":"2026-09-24T07:34:42.226597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.635897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-09-24T07:34:42.226625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.074047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-09-24T07:34:42.226686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.278195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-09-24T07:34:42.226704Z","caller":"traceutil/trace.go:171","msg":"trace[970165875] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:304; }","duration":"163.312436ms","start":"2026-09-24T07:34:42.063388Z","end":"2026-09-24T07:34:42.2267Z","steps":["trace[970165875] 'agreement among raft nodes before linearized reading' (duration: 163.274955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:34:42.226689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.834501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-09-24T07:34:42.226703Z","caller":"traceutil/trace.go:171","msg":"trace[465110871] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:304; }","duration":"217.772381ms","start":"2026-09-24T07:34:42.008888Z","end":"2026-09-24T07:34:42.226661Z","steps":["trace[465110871] 'agreement among raft nodes before linearized reading' (duration: 217.593756ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:34:42.226708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.491256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-09-24T07:34:42.226728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.651086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-09-24T07:34:42.226748Z","caller":"traceutil/trace.go:171","msg":"trace[1070266304] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:304; }","duration":"167.556568ms","start":"2026-09-24T07:34:42.059179Z","end":"2026-09-24T07:34:42.226736Z","steps":["trace[1070266304] 'agreement among raft nodes before linearized reading' (duration: 167.501587ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:34:42.226752Z","caller":"traceutil/trace.go:171","msg":"trace[447789640] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:304; }","duration":"163.687877ms","start":"2026-09-24T07:34:42.063057Z","end":"2026-09-24T07:34:42.226745Z","steps":["trace[447789640] 'agreement among raft nodes before linearized reading' (duration: 163.642205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:34:42.226759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.271711ms","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-24T07:34:42.226743Z","caller":"traceutil/trace.go:171","msg":"trace[1464207208] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:304; }","duration":"163.913883ms","start":"2026-09-24T07:34:42.062812Z","end":"2026-09-24T07:34:42.226726Z","steps":["trace[1464207208] 'agreement among raft nodes before linearized reading' (duration: 163.773299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:34:42.226792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.929664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-09-24T07:34:42.226681Z","caller":"traceutil/trace.go:171","msg":"trace[1618235267] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:304; }","duration":"117.150568ms","start":"2026-09-24T07:34:42.109516Z","end":"2026-09-24T07:34:42.226666Z","steps":["trace[1618235267] 'agreement among raft nodes before linearized reading' (duration: 117.048005ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:34:42.226818Z","caller":"traceutil/trace.go:171","msg":"trace[874678300] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:304; }","duration":"163.962865ms","start":"2026-09-24T07:34:42.062847Z","end":"2026-09-24T07:34:42.22681Z","steps":["trace[874678300] 'agreement among raft nodes before linearized reading' (duration: 163.918133ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:34:42.226817Z","caller":"traceutil/trace.go:171","msg":"trace[1052266610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:304; }","duration":"214.344843ms","start":"2026-09-24T07:34:42.012455Z","end":"2026-09-24T07:34:42.2268Z","steps":["trace[1052266610] 'agreement among raft nodes before linearized reading' (duration: 214.260891ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:34:42.226646Z","caller":"traceutil/trace.go:171","msg":"trace[182576160] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:304; }","duration":"163.513122ms","start":"2026-09-24T07:34:42.063106Z","end":"2026-09-24T07:34:42.226619Z","steps":["trace[182576160] 'agreement among raft nodes before linearized reading' (duration: 163.394669ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:34:42.449276Z","caller":"traceutil/trace.go:171","msg":"trace[814899999] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:317; }","duration":"215.139446ms","start":"2026-09-24T07:34:42.234103Z","end":"2026-09-24T07:34:42.449242Z","steps":["trace[814899999] 'read index received' (duration: 146.504803ms)","trace[814899999] 'applied index is now lower than readState.Index' (duration: 68.633593ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:34:42.449406Z","caller":"traceutil/trace.go:171","msg":"trace[216037880] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"218.678598ms","start":"2026-09-24T07:34:42.230697Z","end":"2026-09-24T07:34:42.449375Z","steps":["trace[216037880] 'process raft request' (duration: 149.90164ms)","trace[216037880] 'compare' (duration: 68.556642ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T07:34:42.449519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.404394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-09-24T07:34:42.449562Z","caller":"traceutil/trace.go:171","msg":"trace[2043711323] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:305; }","duration":"215.506336ms","start":"2026-09-24T07:34:42.234045Z","end":"2026-09-24T07:34:42.449551Z","steps":["trace[2043711323] 'agreement among raft nodes before linearized reading' (duration: 215.428914ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:34:42.514587Z","caller":"traceutil/trace.go:171","msg":"trace[1401678195] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"279.197306ms","start":"2026-09-24T07:34:42.235374Z","end":"2026-09-24T07:34:42.514571Z","steps":["trace[1401678195] 'process raft request' (duration: 278.97369ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:34:42.514764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.134653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-09-24T07:34:42.514815Z","caller":"traceutil/trace.go:171","msg":"trace[2010839562] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:308; }","duration":"280.217546ms","start":"2026-09-24T07:34:42.234587Z","end":"2026-09-24T07:34:42.514804Z","steps":["trace[2010839562] 'agreement among raft nodes before linearized reading' (duration: 280.048161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:34:42.51484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.996331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-24T07:34:42.514926Z","caller":"traceutil/trace.go:171","msg":"trace[681263834] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:308; }","duration":"206.125665ms","start":"2026-09-24T07:34:42.308781Z","end":"2026-09-24T07:34:42.514906Z","steps":["trace[681263834] 'agreement among raft nodes before linearized reading' (duration: 205.843507ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:34:42.514997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.67847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-09-24T07:34:42.515014Z","caller":"traceutil/trace.go:171","msg":"trace[133761641] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:308; }","duration":"256.70635ms","start":"2026-09-24T07:34:42.258303Z","end":"2026-09-24T07:34:42.515009Z","steps":["trace[133761641] 'agreement among raft nodes before linearized reading' (duration: 256.667089ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:34:42.51506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.346399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-09-24T07:34:42.515073Z","caller":"traceutil/trace.go:171","msg":"trace[278796914] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:308; }","duration":"280.3669ms","start":"2026-09-24T07:34:42.234703Z","end":"2026-09-24T07:34:42.51507Z","steps":["trace[278796914] 'agreement among raft nodes before linearized reading' (duration: 280.344869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:34:42.515137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.512945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-09-24T07:34:42.515152Z","caller":"traceutil/trace.go:171","msg":"trace[762664506] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:308; }","duration":"280.534805ms","start":"2026-09-24T07:34:42.234613Z","end":"2026-09-24T07:34:42.515148Z","steps":["trace[762664506] 'agreement among raft nodes before linearized reading' (duration: 280.512654ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:34:42.515401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.060218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-09-24T07:34:42.514931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.757231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-09-24T07:34:42.515422Z","caller":"traceutil/trace.go:171","msg":"trace[1528367345] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:308; }","duration":"106.10723ms","start":"2026-09-24T07:34:42.409308Z","end":"2026-09-24T07:34:42.515415Z","steps":["trace[1528367345] 'agreement among raft nodes before linearized reading' (duration: 106.073879ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:34:42.51544Z","caller":"traceutil/trace.go:171","msg":"trace[533508759] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:308; }","duration":"281.285257ms","start":"2026-09-24T07:34:42.234143Z","end":"2026-09-24T07:34:42.515428Z","steps":["trace[533508759] 'agreement among raft nodes before linearized reading' (duration: 280.735921ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:34:42.515488Z","caller":"traceutil/trace.go:171","msg":"trace[112868365] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"279.264659ms","start":"2026-09-24T07:34:42.236217Z","end":"2026-09-24T07:34:42.515482Z","steps":["trace[112868365] 'process raft request' (duration: 278.225959ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:34:42.515549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.085454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-24T07:34:42.515581Z","caller":"traceutil/trace.go:171","msg":"trace[1751339436] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"277.498237ms","start":"2026-09-24T07:34:42.238077Z","end":"2026-09-24T07:34:42.515576Z","steps":["trace[1751339436] 'process raft request' (duration: 276.410916ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:34:42.515578Z","caller":"traceutil/trace.go:171","msg":"trace[1243933651] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:308; }","duration":"158.140316ms","start":"2026-09-24T07:34:42.357431Z","end":"2026-09-24T07:34:42.515571Z","steps":["trace[1243933651] 'agreement among raft nodes before linearized reading' (duration: 158.089245ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:35:12.06294Z","caller":"traceutil/trace.go:171","msg":"trace[355547466] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"228.65341ms","start":"2026-09-24T07:35:11.834251Z","end":"2026-09-24T07:35:12.062904Z","steps":["trace[355547466] 'process raft request' (duration: 228.146289ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:35:12.131364Z","caller":"traceutil/trace.go:171","msg":"trace[1939685988] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"200.710001ms","start":"2026-09-24T07:35:11.930543Z","end":"2026-09-24T07:35:12.131253Z","steps":["trace[1939685988] 'read index received' (duration: 133.039271ms)","trace[1939685988] 'applied index is now lower than readState.Index' (duration: 67.66999ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:35:12.131394Z","caller":"traceutil/trace.go:171","msg":"trace[459628520] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"268.32552ms","start":"2026-09-24T07:35:11.863033Z","end":"2026-09-24T07:35:12.131358Z","steps":["trace[459628520] 'process raft request' (duration: 268.098335ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:35:12.131865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.167201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-24T07:35:12.132028Z","caller":"traceutil/trace.go:171","msg":"trace[827935692] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:559; }","duration":"201.498869ms","start":"2026-09-24T07:35:11.930497Z","end":"2026-09-24T07:35:12.131996Z","steps":["trace[827935692] 'agreement among raft nodes before linearized reading' (duration: 201.036698ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:35:12.134213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.636296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-24T07:35:12.134309Z","caller":"traceutil/trace.go:171","msg":"trace[1592550694] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:560; }","duration":"203.744549ms","start":"2026-09-24T07:35:11.930539Z","end":"2026-09-24T07:35:12.134284Z","steps":["trace[1592550694] 'agreement among raft nodes before linearized reading' (duration: 203.611136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:35:12.134672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.904948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-24T07:35:12.134744Z","caller":"traceutil/trace.go:171","msg":"trace[695996672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"134.021071ms","start":"2026-09-24T07:35:12.000714Z","end":"2026-09-24T07:35:12.134735Z","steps":["trace[695996672] 'agreement among raft nodes before linearized reading' (duration: 133.922078ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:35:16.392841Z","caller":"traceutil/trace.go:171","msg":"trace[2137744322] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"134.78801ms","start":"2026-09-24T07:35:16.258009Z","end":"2026-09-24T07:35:16.392797Z","steps":["trace[2137744322] 'process raft request' (duration: 40.583299ms)","trace[2137744322] 'compare' (duration: 93.986906ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:35:16.594379Z","caller":"traceutil/trace.go:171","msg":"trace[638300466] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"142.460815ms","start":"2026-09-24T07:35:16.451891Z","end":"2026-09-24T07:35:16.594351Z","steps":["trace[638300466] 'read index received' (duration: 142.144778ms)","trace[638300466] 'applied index is now lower than readState.Index' (duration: 314.277µs)"],"step_count":2} {"level":"warn","ts":"2026-09-24T07:35:16.594593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.67319ms","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-24T07:35:16.594638Z","caller":"traceutil/trace.go:171","msg":"trace[312178790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"142.780672ms","start":"2026-09-24T07:35:16.451843Z","end":"2026-09-24T07:35:16.594623Z","steps":["trace[312178790] 'agreement among raft nodes before linearized reading' (duration: 142.615299ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:35:16.594858Z","caller":"traceutil/trace.go:171","msg":"trace[1089366472] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"185.58365ms","start":"2026-09-24T07:35:16.409246Z","end":"2026-09-24T07:35:16.594829Z","steps":["trace[1089366472] 'process raft request' (duration: 184.790692ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:35:17.067718Z","caller":"traceutil/trace.go:171","msg":"trace[1758544379] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"401.316503ms","start":"2026-09-24T07:35:16.666381Z","end":"2026-09-24T07:35:17.067698Z","steps":["trace[1758544379] 'process raft request' (duration: 400.990096ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:35:17.067852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T07:35:16.666359Z","time spent":"401.429525ms","remote":"127.0.0.1:39228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-24T07:35:32.651576Z","caller":"traceutil/trace.go:171","msg":"trace[122136717] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"238.440291ms","start":"2026-09-24T07:35:32.413105Z","end":"2026-09-24T07:35:32.651545Z","steps":["trace[122136717] 'process raft request' (duration: 182.164979ms)","trace[122136717] 'compare' (duration: 55.984897ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:36:04.068557Z","caller":"traceutil/trace.go:171","msg":"trace[735123359] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"201.486354ms","start":"2026-09-24T07:36:03.866994Z","end":"2026-09-24T07:36:04.06848Z","steps":["trace[735123359] 'process raft request' (duration: 201.103298ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:36:04.068594Z","caller":"traceutil/trace.go:171","msg":"trace[1664929484] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1200; }","duration":"166.750623ms","start":"2026-09-24T07:36:03.901656Z","end":"2026-09-24T07:36:04.068407Z","steps":["trace[1664929484] 'read index received' (duration: 166.400767ms)","trace[1664929484] 'applied index is now lower than readState.Index' (duration: 348.926µs)"],"step_count":2} {"level":"warn","ts":"2026-09-24T07:36:04.068657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.975898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-24T07:36:04.06873Z","caller":"traceutil/trace.go:171","msg":"trace[1527777294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1163; }","duration":"167.087229ms","start":"2026-09-24T07:36:03.901623Z","end":"2026-09-24T07:36:04.06871Z","steps":["trace[1527777294] 'agreement among raft nodes before linearized reading' (duration: 166.953647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:36:52.425982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.104782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-24T07:36:52.426075Z","caller":"traceutil/trace.go:171","msg":"trace[1029708474] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1845; }","duration":"322.236484ms","start":"2026-09-24T07:36:52.103817Z","end":"2026-09-24T07:36:52.426053Z","steps":["trace[1029708474] 'count revisions from in-memory index tree' (duration: 322.034341ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:36:52.426175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T07:36:52.103805Z","time spent":"322.352055ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-09-24T07:36:52.426211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.339516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-24T07:36:52.426301Z","caller":"traceutil/trace.go:171","msg":"trace[902804466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1845; }","duration":"524.472649ms","start":"2026-09-24T07:36:51.901807Z","end":"2026-09-24T07:36:52.42628Z","steps":["trace[902804466] 'range keys from in-memory index tree' (duration: 524.220035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:36:52.426346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T07:36:51.901787Z","time spent":"524.550369ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-24T07:36:52.426358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.763498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-24T07:36:52.426475Z","caller":"traceutil/trace.go:171","msg":"trace[233341503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1845; }","duration":"355.959891ms","start":"2026-09-24T07:36:52.070494Z","end":"2026-09-24T07:36:52.426454Z","steps":["trace[233341503] 'range keys from in-memory index tree' (duration: 355.534905ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:36:52.426549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T07:36:52.070469Z","time spent":"356.068292ms","remote":"127.0.0.1:39438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-24T07:36:52.426726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.337033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d299123-c58b-4c48-8dff-feeeccb2063e\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-24T07:36:52.426763Z","caller":"traceutil/trace.go:171","msg":"trace[2009212394] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7d299123-c58b-4c48-8dff-feeeccb2063e; range_end:; response_count:1; response_revision:1845; }","duration":"487.392183ms","start":"2026-09-24T07:36:51.939359Z","end":"2026-09-24T07:36:52.426751Z","steps":["trace[2009212394] 'range keys from in-memory index tree' (duration: 487.13343ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:36:52.426741Z","caller":"traceutil/trace.go:171","msg":"trace[1233384641] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"108.619305ms","start":"2026-09-24T07:36:52.318082Z","end":"2026-09-24T07:36:52.426701Z","steps":["trace[1233384641] 'process raft request' (duration: 77.727237ms)","trace[1233384641] 'compare' (duration: 30.057916ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T07:36:52.426791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T07:36:51.939348Z","time spent":"487.436824ms","remote":"127.0.0.1:39346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d299123-c58b-4c48-8dff-feeeccb2063e\" "} {"level":"info","ts":"2026-09-24T07:36:52.568503Z","caller":"traceutil/trace.go:171","msg":"trace[520008582] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"137.594446ms","start":"2026-09-24T07:36:52.430878Z","end":"2026-09-24T07:36:52.568472Z","steps":["trace[520008582] 'process raft request' (duration: 95.575564ms)","trace[520008582] 'compare' (duration: 41.88327ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:37:07.420227Z","caller":"traceutil/trace.go:171","msg":"trace[789593906] linearizableReadLoop","detail":"{readStateIndex:2057; appliedIndex:2056; }","duration":"143.119056ms","start":"2026-09-24T07:37:07.277082Z","end":"2026-09-24T07:37:07.420201Z","steps":["trace[789593906] 'read index received' (duration: 142.68482ms)","trace[789593906] 'applied index is now lower than readState.Index' (duration: 433.296µs)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:37:07.420421Z","caller":"traceutil/trace.go:171","msg":"trace[57752937] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"152.696694ms","start":"2026-09-24T07:37:07.267702Z","end":"2026-09-24T07:37:07.420398Z","steps":["trace[57752937] 'process raft request' (duration: 151.933184ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:37:07.420549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.41486ms","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-24T07:37:07.420608Z","caller":"traceutil/trace.go:171","msg":"trace[878327462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1976; }","duration":"143.540061ms","start":"2026-09-24T07:37:07.277056Z","end":"2026-09-24T07:37:07.420596Z","steps":["trace[878327462] 'agreement among raft nodes before linearized reading' (duration: 143.317378ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:37:30.910208Z","caller":"traceutil/trace.go:171","msg":"trace[820414863] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"104.296051ms","start":"2026-09-24T07:37:30.805878Z","end":"2026-09-24T07:37:30.910174Z","steps":["trace[820414863] 'process raft request' (duration: 38.84616ms)","trace[820414863] 'compare' (duration: 65.33644ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T07:37:31.201779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.16625ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-24T07:37:31.201956Z","caller":"traceutil/trace.go:171","msg":"trace[1719950886] linearizableReadLoop","detail":"{readStateIndex:2194; appliedIndex:2193; }","duration":"173.65984ms","start":"2026-09-24T07:37:31.028274Z","end":"2026-09-24T07:37:31.201934Z","steps":["trace[1719950886] 'read index received' (duration: 36.258706ms)","trace[1719950886] 'applied index is now lower than readState.Index' (duration: 137.399184ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T07:37:31.202139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.856872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-24T07:37:31.202236Z","caller":"traceutil/trace.go:171","msg":"trace[1954429453] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2108; }","duration":"174.004754ms","start":"2026-09-24T07:37:31.028196Z","end":"2026-09-24T07:37:31.202201Z","steps":["trace[1954429453] 'agreement among raft nodes before linearized reading' (duration: 173.833352ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:37:31.452216Z","caller":"traceutil/trace.go:171","msg":"trace[1032316941] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"143.084107ms","start":"2026-09-24T07:37:31.309092Z","end":"2026-09-24T07:37:31.452176Z","steps":["trace[1032316941] 'process raft request' (duration: 142.809294ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:37:31.452198Z","caller":"traceutil/trace.go:171","msg":"trace[1502417650] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2194; }","duration":"102.09698ms","start":"2026-09-24T07:37:31.35007Z","end":"2026-09-24T07:37:31.452167Z","steps":["trace[1502417650] 'read index received' (duration: 101.812337ms)","trace[1502417650] 'applied index is now lower than readState.Index' (duration: 283.193µs)"],"step_count":2} {"level":"warn","ts":"2026-09-24T07:37:31.452453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.364915ms","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-24T07:37:31.452512Z","caller":"traceutil/trace.go:171","msg":"trace[603701034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2109; }","duration":"102.468355ms","start":"2026-09-24T07:37:31.35003Z","end":"2026-09-24T07:37:31.452498Z","steps":["trace[603701034] 'agreement among raft nodes before linearized reading' (duration: 102.247503ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:37:31.719193Z","caller":"traceutil/trace.go:171","msg":"trace[1125535822] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"218.816229ms","start":"2026-09-24T07:37:31.500346Z","end":"2026-09-24T07:37:31.719162Z","steps":["trace[1125535822] 'process raft request' (duration: 159.798091ms)","trace[1125535822] 'compare' (duration: 58.866686ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:37:31.722415Z","caller":"traceutil/trace.go:171","msg":"trace[1840571584] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2196; }","duration":"190.637028ms","start":"2026-09-24T07:37:31.531756Z","end":"2026-09-24T07:37:31.722393Z","steps":["trace[1840571584] 'read index received' (duration: 128.365878ms)","trace[1840571584] 'applied index is now lower than readState.Index' (duration: 62.27024ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T07:37:31.722672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.013968ms","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-24T07:37:31.722764Z","caller":"traceutil/trace.go:171","msg":"trace[454143108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2112; }","duration":"186.130439ms","start":"2026-09-24T07:37:31.536616Z","end":"2026-09-24T07:37:31.722746Z","steps":["trace[454143108] 'agreement among raft nodes before linearized reading' (duration: 185.946467ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:37:31.722714Z","caller":"traceutil/trace.go:171","msg":"trace[866365323] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"215.706509ms","start":"2026-09-24T07:37:31.506804Z","end":"2026-09-24T07:37:31.72251Z","steps":["trace[866365323] 'process raft request' (duration: 215.466496ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:37:31.723887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.114686ms","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-24T07:37:31.723942Z","caller":"traceutil/trace.go:171","msg":"trace[1819567849] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2112; }","duration":"192.223258ms","start":"2026-09-24T07:37:31.531706Z","end":"2026-09-24T07:37:31.72393Z","steps":["trace[1819567849] 'agreement among raft nodes before linearized reading' (duration: 191.040913ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:37:31.85972Z","caller":"traceutil/trace.go:171","msg":"trace[1496265498] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"131.909183ms","start":"2026-09-24T07:37:31.727782Z","end":"2026-09-24T07:37:31.859691Z","steps":["trace[1496265498] 'process raft request' (duration: 104.498691ms)","trace[1496265498] 'compare' (duration: 27.2396ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:37:31.859861Z","caller":"traceutil/trace.go:171","msg":"trace[1761891361] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"129.704535ms","start":"2026-09-24T07:37:31.730116Z","end":"2026-09-24T07:37:31.859821Z","steps":["trace[1761891361] 'process raft request' (duration: 129.492462ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:38:33.84883Z","caller":"traceutil/trace.go:171","msg":"trace[1215108726] linearizableReadLoop","detail":"{readStateIndex:2648; appliedIndex:2647; }","duration":"113.250809ms","start":"2026-09-24T07:38:33.735562Z","end":"2026-09-24T07:38:33.848813Z","steps":["trace[1215108726] 'read index received' (duration: 113.210629ms)","trace[1215108726] 'applied index is now lower than readState.Index' (duration: 39.22µs)"],"step_count":2} {"level":"warn","ts":"2026-09-24T07:38:33.848964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.404102ms","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-24T07:38:33.848983Z","caller":"traceutil/trace.go:171","msg":"trace[615752997] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2548; }","duration":"113.500783ms","start":"2026-09-24T07:38:33.735476Z","end":"2026-09-24T07:38:33.848977Z","steps":["trace[615752997] 'agreement among raft nodes before linearized reading' (duration: 113.401562ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:38:33.849193Z","caller":"traceutil/trace.go:171","msg":"trace[2084028314] transaction","detail":"{read_only:false; response_revision:2548; number_of_response:1; }","duration":"189.882597ms","start":"2026-09-24T07:38:33.659305Z","end":"2026-09-24T07:38:33.849187Z","steps":["trace[2084028314] 'process raft request' (duration: 189.422771ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:43:33.650519Z","caller":"traceutil/trace.go:171","msg":"trace[1524633044] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"110.96366ms","start":"2026-09-24T07:43:33.539528Z","end":"2026-09-24T07:43:33.650491Z","steps":["trace[1524633044] 'process raft request' (duration: 110.819458ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:43:39.46524Z","caller":"traceutil/trace.go:171","msg":"trace[1104206679] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"121.84523ms","start":"2026-09-24T07:43:39.343364Z","end":"2026-09-24T07:43:39.465209Z","steps":["trace[1104206679] 'process raft request' (duration: 121.592786ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:43:39.692144Z","caller":"traceutil/trace.go:171","msg":"trace[970022982] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"222.845773ms","start":"2026-09-24T07:43:39.469276Z","end":"2026-09-24T07:43:39.692122Z","steps":["trace[970022982] 'process raft request' (duration: 222.331174ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:43:40.101525Z","caller":"traceutil/trace.go:171","msg":"trace[1519983038] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"259.154276ms","start":"2026-09-24T07:43:39.84234Z","end":"2026-09-24T07:43:40.101494Z","steps":["trace[1519983038] 'process raft request' (duration: 258.857881ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:43:40.101701Z","caller":"traceutil/trace.go:171","msg":"trace[1180690640] linearizableReadLoop","detail":"{readStateIndex:5130; appliedIndex:5128; }","duration":"202.181413ms","start":"2026-09-24T07:43:39.899499Z","end":"2026-09-24T07:43:40.10168Z","steps":["trace[1180690640] 'read index received' (duration: 201.845507ms)","trace[1180690640] 'applied index is now lower than readState.Index' (duration: 334.346µs)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:43:40.101778Z","caller":"traceutil/trace.go:171","msg":"trace[856441597] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"256.337591ms","start":"2026-09-24T07:43:39.845414Z","end":"2026-09-24T07:43:40.101752Z","steps":["trace[856441597] 'process raft request' (duration: 255.965895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:43:40.101948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.426477ms","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-24T07:43:40.102223Z","caller":"traceutil/trace.go:171","msg":"trace[441170484] 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:4938; }","duration":"202.716712ms","start":"2026-09-24T07:43:39.899484Z","end":"2026-09-24T07:43:40.1022Z","steps":["trace[441170484] 'agreement among raft nodes before linearized reading' (duration: 202.309525ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:43:40.127229Z","caller":"traceutil/trace.go:171","msg":"trace[1435093766] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"115.53757ms","start":"2026-09-24T07:43:40.011666Z","end":"2026-09-24T07:43:40.127203Z","steps":["trace[1435093766] 'process raft request' (duration: 115.193464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:43:40.127543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.793559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-24T07:43:40.127603Z","caller":"traceutil/trace.go:171","msg":"trace[1760940690] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:4939; }","duration":"169.86004ms","start":"2026-09-24T07:43:39.957723Z","end":"2026-09-24T07:43:40.127583Z","steps":["trace[1760940690] 'agreement among raft nodes before linearized reading' (duration: 169.717498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:43:40.1277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.828448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-24T07:43:40.127746Z","caller":"traceutil/trace.go:171","msg":"trace[348953308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4939; }","duration":"225.911599ms","start":"2026-09-24T07:43:39.901823Z","end":"2026-09-24T07:43:40.127734Z","steps":["trace[348953308] 'agreement among raft nodes before linearized reading' (duration: 225.839958ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:43:40.301674Z","caller":"traceutil/trace.go:171","msg":"trace[168068087] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"160.750755ms","start":"2026-09-24T07:43:40.1409Z","end":"2026-09-24T07:43:40.301651Z","steps":["trace[168068087] 'process raft request' (duration: 160.604703ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:43:40.325774Z","caller":"traceutil/trace.go:171","msg":"trace[431891242] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"170.627072ms","start":"2026-09-24T07:43:40.155122Z","end":"2026-09-24T07:43:40.325749Z","steps":["trace[431891242] 'process raft request' (duration: 170.49515ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:43:53.733157Z","caller":"traceutil/trace.go:171","msg":"trace[456791504] linearizableReadLoop","detail":"{readStateIndex:5312; appliedIndex:5311; }","duration":"106.387893ms","start":"2026-09-24T07:43:53.626726Z","end":"2026-09-24T07:43:53.733114Z","steps":["trace[456791504] 'read index received' (duration: 106.149259ms)","trace[456791504] 'applied index is now lower than readState.Index' (duration: 237.374µs)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:43:53.733266Z","caller":"traceutil/trace.go:171","msg":"trace[411753384] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"111.613505ms","start":"2026-09-24T07:43:53.621624Z","end":"2026-09-24T07:43:53.733238Z","steps":["trace[411753384] 'process raft request' (duration: 111.32386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:43:53.733383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.633707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-09-24T07:43:53.733424Z","caller":"traceutil/trace.go:171","msg":"trace[1315759270] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5114; }","duration":"106.711319ms","start":"2026-09-24T07:43:53.626702Z","end":"2026-09-24T07:43:53.733413Z","steps":["trace[1315759270] 'agreement among raft nodes before linearized reading' (duration: 106.518116ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:43:54.450955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.086663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5d95768cd7-v56xr\" ","response":"range_response_count:1 size:11848"} {"level":"info","ts":"2026-09-24T07:43:54.451034Z","caller":"traceutil/trace.go:171","msg":"trace[439344029] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-5d95768cd7-v56xr; range_end:; response_count:1; response_revision:5121; }","duration":"153.198424ms","start":"2026-09-24T07:43:54.297812Z","end":"2026-09-24T07:43:54.45101Z","steps":["trace[439344029] 'range keys from in-memory index tree' (duration: 152.91454ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:43:54.451344Z","caller":"traceutil/trace.go:171","msg":"trace[221459470] transaction","detail":"{read_only:false; response_revision:5122; number_of_response:1; }","duration":"150.587513ms","start":"2026-09-24T07:43:54.300731Z","end":"2026-09-24T07:43:54.451318Z","steps":["trace[221459470] 'process raft request' (duration: 84.990878ms)","trace[221459470] 'compare' (duration: 64.955715ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:43:54.604603Z","caller":"traceutil/trace.go:171","msg":"trace[298524695] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"135.980855ms","start":"2026-09-24T07:43:54.468542Z","end":"2026-09-24T07:43:54.604522Z","steps":["trace[298524695] 'process raft request' (duration: 82.058872ms)","trace[298524695] 'compare' (duration: 53.421755ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:44:32.150897Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2866} {"level":"info","ts":"2026-09-24T07:44:32.309352Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2866,"took":"156.836788ms","hash":330377213,"current-db-size-bytes":52813824,"current-db-size":"53 MB","current-db-size-in-use-bytes":28405760,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-24T07:44:32.309425Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":330377213,"revision":2866,"compact-revision":-1} {"level":"info","ts":"2026-09-24T07:47:35.649615Z","caller":"traceutil/trace.go:171","msg":"trace[127639646] transaction","detail":"{read_only:false; response_revision:6718; number_of_response:1; }","duration":"117.878369ms","start":"2026-09-24T07:47:35.531695Z","end":"2026-09-24T07:47:35.649574Z","steps":["trace[127639646] 'process raft request' (duration: 40.469486ms)","trace[127639646] 'compare' (duration: 77.283118ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:49:32.158021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5445} {"level":"info","ts":"2026-09-24T07:49:32.282121Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5445,"took":"122.436572ms","hash":39572273,"current-db-size-bytes":52813824,"current-db-size":"53 MB","current-db-size-in-use-bytes":18726912,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-24T07:49:32.282225Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":39572273,"revision":5445,"compact-revision":2866}