{"level":"warn","ts":"2026-08-14T02:04:47.794683Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T02:04:47.794762Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129: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://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-08-14T02:04:47.79483Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T02:04:47.794842Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-08-14T02:04:47.794857Z","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-08-14T02:04:47.795441Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-08-14T02:04:47.795508Z","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://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-08-14T02:04:47.812338Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.63401ms"} {"level":"info","ts":"2026-08-14T02:04:47.82525Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-08-14T02:04:47.825322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T02:04:47.825341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-08-14T02:04:47.825387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T02:04:47.825404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-08-14T02:04:47.825441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-08-14T02:04:47.832532Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T02:04:47.833888Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T02:04:47.834698Z","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-08-14T02:04:47.836683Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T02:04:47.836752Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T02:04:47.836955Z","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-08-14T02:04:47.837037Z","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-08-14T02:04:47.837053Z","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-08-14T02:04:47.83758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-08-14T02:04:47.837712Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-08-14T02:04:47.840513Z","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-08-14T02:04:47.840905Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-08-14T02:04:47.840957Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-08-14T02:04:47.842259Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T02:04:47.842309Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T02:04:48.726485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T02:04:48.726538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T02:04:48.726573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-08-14T02:04:48.726588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-08-14T02:04:48.726595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-08-14T02:04:48.726606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-08-14T02:04:48.726614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-08-14T02:04:48.727212Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T02:04:48.7279Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T02:04:48.727929Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T02:04:48.728187Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T02:04:48.730015Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-14T02:04:48.73786Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-08-14T02:04:48.737934Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T02:04:48.737942Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T02:04:48.739544Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T02:04:48.740244Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T02:04:48.740309Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T02:04:51.424339Z","caller":"traceutil/trace.go:171","msg":"trace[586766843] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"360.745477ms","start":"2026-08-14T02:04:51.063566Z","end":"2026-08-14T02:04:51.424311Z","steps":["trace[586766843] 'read index received' (duration: 360.635524ms)","trace[586766843] 'applied index is now lower than readState.Index' (duration: 108.803µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T02:04:51.424392Z","caller":"traceutil/trace.go:171","msg":"trace[865116324] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"370.904599ms","start":"2026-08-14T02:04:51.05345Z","end":"2026-08-14T02:04:51.424355Z","steps":["trace[865116324] 'process raft request' (duration: 370.736115ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:04:51.424557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.965752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-14T02:04:51.424628Z","caller":"traceutil/trace.go:171","msg":"trace[1822361279] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:71; }","duration":"361.092366ms","start":"2026-08-14T02:04:51.063524Z","end":"2026-08-14T02:04:51.424616Z","steps":["trace[1822361279] 'agreement among raft nodes before linearized reading' (duration: 360.924751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:04:51.424666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T02:04:51.063508Z","time spent":"361.148107ms","remote":"127.0.0.1:60772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-08-14T02:04:51.424977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T02:04:51.053414Z","time spent":"371.046323ms","remote":"127.0.0.1:60782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T02:04:51.787384Z","caller":"traceutil/trace.go:171","msg":"trace[1018323986] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"362.929763ms","start":"2026-08-14T02:04:51.424424Z","end":"2026-08-14T02:04:51.787354Z","steps":["trace[1018323986] 'read index received' (duration: 278.993742ms)","trace[1018323986] 'applied index is now lower than readState.Index' (duration: 83.930851ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T02:04:51.787396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T02:04:51.394466Z","time spent":"392.924329ms","remote":"127.0.0.1:60474","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-14T02:04:51.787491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.328689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-14T02:04:51.787559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.632976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-14T02:04:51.787596Z","caller":"traceutil/trace.go:171","msg":"trace[302612056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"673.704228ms","start":"2026-08-14T02:04:51.113878Z","end":"2026-08-14T02:04:51.787582Z","steps":["trace[302612056] 'agreement among raft nodes before linearized reading' (duration: 673.624386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:04:51.787633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T02:04:51.11387Z","time spent":"673.754109ms","remote":"127.0.0.1:60398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T02:04:51.787551Z","caller":"traceutil/trace.go:171","msg":"trace[439033151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"622.406311ms","start":"2026-08-14T02:04:51.165125Z","end":"2026-08-14T02:04:51.787532Z","steps":["trace[439033151] 'agreement among raft nodes before linearized reading' (duration: 622.280328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:04:51.787885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.383217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-14T02:04:51.787893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T02:04:51.16507Z","time spent":"622.793881ms","remote":"127.0.0.1:60382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T02:04:51.787914Z","caller":"traceutil/trace.go:171","msg":"trace[1835818638] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:71; }","duration":"360.442209ms","start":"2026-08-14T02:04:51.427463Z","end":"2026-08-14T02:04:51.787906Z","steps":["trace[1835818638] 'agreement among raft nodes before linearized reading' (duration: 360.397248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:04:51.787969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T02:04:51.427446Z","time spent":"360.51645ms","remote":"127.0.0.1:60782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-cluster-critical\" "} {"level":"warn","ts":"2026-08-14T02:04:51.788057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.485743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-14T02:04:51.7881Z","caller":"traceutil/trace.go:171","msg":"trace[2033563041] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:71; }","duration":"234.550314ms","start":"2026-08-14T02:04:51.553534Z","end":"2026-08-14T02:04:51.788084Z","steps":["trace[2033563041] 'agreement among raft nodes before linearized reading' (duration: 234.474122ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:04:51.788255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.585152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-14T02:04:51.788371Z","caller":"traceutil/trace.go:171","msg":"trace[1550394175] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:71; }","duration":"360.753146ms","start":"2026-08-14T02:04:51.427591Z","end":"2026-08-14T02:04:51.788344Z","steps":["trace[1550394175] 'agreement among raft nodes before linearized reading' (duration: 360.4956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:04:51.78843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T02:04:51.427482Z","time spent":"360.937871ms","remote":"127.0.0.1:60778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-08-14T02:05:25.88124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.665709ms","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-08-14T02:05:25.881408Z","caller":"traceutil/trace.go:171","msg":"trace[18824303] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"132.935996ms","start":"2026-08-14T02:05:25.748448Z","end":"2026-08-14T02:05:25.881384Z","steps":["trace[18824303] 'range keys from in-memory index tree' (duration: 132.511696ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T02:05:26.560267Z","caller":"traceutil/trace.go:171","msg":"trace[621659930] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"130.683999ms","start":"2026-08-14T02:05:26.429545Z","end":"2026-08-14T02:05:26.560229Z","steps":["trace[621659930] 'process raft request' (duration: 130.048292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:05:26.83745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.302354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T02:05:26.837654Z","caller":"traceutil/trace.go:171","msg":"trace[988495187] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"358.336554ms","start":"2026-08-14T02:05:26.479273Z","end":"2026-08-14T02:05:26.837609Z","steps":["trace[988495187] 'process raft request' (duration: 226.551867ms)","trace[988495187] 'compare' (duration: 130.852753ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T02:05:26.837863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T02:05:26.479257Z","time spent":"358.482447ms","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T02:05:26.837966Z","caller":"traceutil/trace.go:171","msg":"trace[1301903574] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"306.640058ms","start":"2026-08-14T02:05:26.531312Z","end":"2026-08-14T02:05:26.837953Z","steps":["trace[1301903574] 'process raft request' (duration: 306.289079ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T02:05:26.837892Z","caller":"traceutil/trace.go:171","msg":"trace[1416973137] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"319.937051ms","start":"2026-08-14T02:05:26.517878Z","end":"2026-08-14T02:05:26.837815Z","steps":["trace[1416973137] 'process raft request' (duration: 319.657064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:05:26.838017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T02:05:26.531295Z","time spent":"306.701859ms","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T02:05:26.838141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T02:05:26.517853Z","time spent":"320.183918ms","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T02:05:26.860792Z","caller":"traceutil/trace.go:171","msg":"trace[818275649] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:588; }","duration":"207.050863ms","start":"2026-08-14T02:05:26.653691Z","end":"2026-08-14T02:05:26.860742Z","steps":["trace[818275649] 'read index received' (duration: 52.232841ms)","trace[818275649] 'applied index is now lower than readState.Index' (duration: 154.817112ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T02:05:26.860992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.279259ms","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-08-14T02:05:26.861015Z","caller":"traceutil/trace.go:171","msg":"trace[50152953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"207.351611ms","start":"2026-08-14T02:05:26.653657Z","end":"2026-08-14T02:05:26.861008Z","steps":["trace[50152953] 'agreement among raft nodes before linearized reading' (duration: 207.218647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:05:26.861195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.230772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T02:05:26.86125Z","caller":"traceutil/trace.go:171","msg":"trace[214364372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"189.322455ms","start":"2026-08-14T02:05:26.671914Z","end":"2026-08-14T02:05:26.861236Z","steps":["trace[214364372] 'agreement among raft nodes before linearized reading' (duration: 189.186511ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T02:05:26.860832Z","caller":"traceutil/trace.go:171","msg":"trace[602484977] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"282.466352ms","start":"2026-08-14T02:05:26.57832Z","end":"2026-08-14T02:05:26.860786Z","steps":["trace[602484977] 'process raft request' (duration: 282.304388ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T02:05:32.026622Z","caller":"traceutil/trace.go:171","msg":"trace[815699434] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"182.1908ms","start":"2026-08-14T02:05:31.844402Z","end":"2026-08-14T02:05:32.026593Z","steps":["trace[815699434] 'process raft request' (duration: 89.681898ms)","trace[815699434] 'compare' (duration: 92.345548ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T02:05:32.045126Z","caller":"traceutil/trace.go:171","msg":"trace[1416849687] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"134.310203ms","start":"2026-08-14T02:05:31.910791Z","end":"2026-08-14T02:05:32.045102Z","steps":["trace[1416849687] 'read index received' (duration: 23.318323ms)","trace[1416849687] 'applied index is now lower than readState.Index' (duration: 110.99072ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T02:05:32.045255Z","caller":"traceutil/trace.go:171","msg":"trace[466219262] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"200.809921ms","start":"2026-08-14T02:05:31.844421Z","end":"2026-08-14T02:05:32.045231Z","steps":["trace[466219262] 'process raft request' (duration: 200.571265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:05:32.045393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.560439ms","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-08-14T02:05:32.045436Z","caller":"traceutil/trace.go:171","msg":"trace[1591990696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"134.661061ms","start":"2026-08-14T02:05:31.910763Z","end":"2026-08-14T02:05:32.045424Z","steps":["trace[1591990696] 'agreement among raft nodes before linearized reading' (duration: 134.430055ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T02:05:32.673903Z","caller":"traceutil/trace.go:171","msg":"trace[48291287] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"444.121182ms","start":"2026-08-14T02:05:32.229705Z","end":"2026-08-14T02:05:32.673826Z","steps":["trace[48291287] 'process raft request' (duration: 443.177068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:05:32.67406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T02:05:32.229675Z","time spent":"444.314608ms","remote":"127.0.0.1:60498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036015,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T02:05:32.674143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.633993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l258d\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-14T02:05:32.674198Z","caller":"traceutil/trace.go:171","msg":"trace[1986252530] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l258d; range_end:; response_count:1; response_revision:622; }","duration":"394.727266ms","start":"2026-08-14T02:05:32.279455Z","end":"2026-08-14T02:05:32.674182Z","steps":["trace[1986252530] 'agreement among raft nodes before linearized reading' (duration: 394.482739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:05:32.674239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T02:05:32.279436Z","time spent":"394.793077ms","remote":"127.0.0.1:60596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-l258d\" "} {"level":"info","ts":"2026-08-14T02:05:32.674945Z","caller":"traceutil/trace.go:171","msg":"trace[667877634] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"394.221022ms","start":"2026-08-14T02:05:32.2795Z","end":"2026-08-14T02:05:32.673721Z","steps":["trace[667877634] 'read index received' (duration: 392.672522ms)","trace[667877634] 'applied index is now lower than readState.Index' (duration: 1.54759ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T02:05:32.675084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.741342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-14T02:05:32.67511Z","caller":"traceutil/trace.go:171","msg":"trace[678246786] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:622; }","duration":"108.767612ms","start":"2026-08-14T02:05:32.566333Z","end":"2026-08-14T02:05:32.675101Z","steps":["trace[678246786] 'agreement among raft nodes before linearized reading' (duration: 108.711901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:06:32.311124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.305482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-14T02:06:32.311203Z","caller":"traceutil/trace.go:171","msg":"trace[1947185210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1043; }","duration":"175.443216ms","start":"2026-08-14T02:06:32.135747Z","end":"2026-08-14T02:06:32.31119Z","steps":["trace[1947185210] 'range keys from in-memory index tree' (duration: 175.140888ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T02:06:32.96436Z","caller":"traceutil/trace.go:171","msg":"trace[1959641088] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"102.647653ms","start":"2026-08-14T02:06:32.861686Z","end":"2026-08-14T02:06:32.964333Z","steps":["trace[1959641088] 'process raft request' (duration: 102.485699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:06:33.446521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.961309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T02:06:33.446592Z","caller":"traceutil/trace.go:171","msg":"trace[1755064781] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1048; }","duration":"164.085632ms","start":"2026-08-14T02:06:33.28249Z","end":"2026-08-14T02:06:33.446576Z","steps":["trace[1755064781] 'count revisions from in-memory index tree' (duration: 163.865396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:06:33.893885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.213417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T02:06:33.894002Z","caller":"traceutil/trace.go:171","msg":"trace[789191044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1050; }","duration":"224.377461ms","start":"2026-08-14T02:06:33.669608Z","end":"2026-08-14T02:06:33.893986Z","steps":["trace[789191044] 'range keys from in-memory index tree' (duration: 224.131224ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T02:06:49.456368Z","caller":"traceutil/trace.go:171","msg":"trace[748542498] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"204.868336ms","start":"2026-08-14T02:06:49.251483Z","end":"2026-08-14T02:06:49.456351Z","steps":["trace[748542498] 'process raft request' (duration: 204.754414ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T02:07:02.587924Z","caller":"traceutil/trace.go:171","msg":"trace[648990557] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"104.101362ms","start":"2026-08-14T02:07:02.483796Z","end":"2026-08-14T02:07:02.587897Z","steps":["trace[648990557] 'process raft request' (duration: 103.918837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T02:07:03.334501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.97331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T02:07:03.334604Z","caller":"traceutil/trace.go:171","msg":"trace[1858048535] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1244; }","duration":"113.626648ms","start":"2026-08-14T02:07:03.220961Z","end":"2026-08-14T02:07:03.334588Z","steps":["trace[1858048535] 'read index received' (duration: 17.741µs)","trace[1858048535] 'applied index is now lower than readState.Index' (duration: 113.608077ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T02:07:03.334709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.740001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T02:07:03.334747Z","caller":"traceutil/trace.go:171","msg":"trace[1568016127] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1198; }","duration":"113.803182ms","start":"2026-08-14T02:07:03.220933Z","end":"2026-08-14T02:07:03.334736Z","steps":["trace[1568016127] 'agreement among raft nodes before linearized reading' (duration: 113.708919ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T02:07:03.334735Z","caller":"traceutil/trace.go:171","msg":"trace[2127591886] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"251.061291ms","start":"2026-08-14T02:07:03.083594Z","end":"2026-08-14T02:07:03.334655Z","steps":["trace[2127591886] 'process raft request' (duration: 115.848585ms)","trace[2127591886] 'compare' (duration: 134.854517ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T02:07:04.246661Z","caller":"traceutil/trace.go:171","msg":"trace[6660351] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"268.03504ms","start":"2026-08-14T02:07:03.978607Z","end":"2026-08-14T02:07:04.246642Z","steps":["trace[6660351] 'process raft request' (duration: 267.942167ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T02:07:04.377244Z","caller":"traceutil/trace.go:171","msg":"trace[2116101977] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"126.913271ms","start":"2026-08-14T02:07:04.250301Z","end":"2026-08-14T02:07:04.377214Z","steps":["trace[2116101977] 'process raft request' (duration: 61.682245ms)","trace[2116101977] 'compare' (duration: 65.099963ms)"],"step_count":2}