{"level":"warn","ts":"2026-02-07T04:00:01.290584Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-07T04:00:01.2907Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.204: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.204:2380","--initial-cluster=instance=https://162.253.55.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.204: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-02-07T04:00:01.290808Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-07T04:00:01.290835Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-07T04:00:01.290863Z","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-02-07T04:00:01.291605Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"]} {"level":"info","ts":"2026-02-07T04:00:01.291728Z","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":2,"max-cpu-available":2,"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.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.204: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-02-07T04:00:01.296704Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.721747ms"} {"level":"info","ts":"2026-02-07T04:00:01.318351Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-02-07T04:00:01.31842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-07T04:00:01.31845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-02-07T04:00:01.318483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a11c20306510c1f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-07T04:00:01.318512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-02-07T04:00:01.31855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-02-07T04:00:01.325466Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-07T04:00:01.329957Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-07T04:00:01.33506Z","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-02-07T04:00:01.341135Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a11c20306510c1f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-07T04:00:01.341446Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a11c20306510c1f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-07T04:00:01.341598Z","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-02-07T04:00:01.341649Z","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-02-07T04:00:01.34167Z","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-02-07T04:00:01.341922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-02-07T04:00:01.342014Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","added-peer-id":"a11c20306510c1f7","added-peer-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-07T04:00:01.354623Z","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-02-07T04:00:01.355332Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-07T04:00:01.360295Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-07T04:00:01.359895Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a11c20306510c1f7","initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-07T04:00:01.359909Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-07T04:00:01.919712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-07T04:00:01.919827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-07T04:00:01.919853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-02-07T04:00:01.919873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-02-07T04:00:01.919908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-07T04:00:01.919927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-02-07T04:00:01.919944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-07T04:00:01.926293Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a11c20306510c1f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.204:2379]}","request-path":"/0/members/a11c20306510c1f7/attributes","cluster-id":"97da89d0cbebd2b5","publish-timeout":"7s"} {"level":"info","ts":"2026-02-07T04:00:01.926356Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T04:00:01.926667Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:00:01.928669Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-07T04:00:01.929362Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:00:01.929438Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:00:01.929467Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:00:01.931123Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T04:00:01.931533Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-07T04:00:01.931554Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-07T04:00:01.933332Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"info","ts":"2026-02-07T04:00:15.132587Z","caller":"traceutil/trace.go:171","msg":"trace[1152733965] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"101.859751ms","start":"2026-02-07T04:00:15.030695Z","end":"2026-02-07T04:00:15.132554Z","steps":["trace[1152733965] 'process raft request' (duration: 101.70493ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:16.597496Z","caller":"traceutil/trace.go:171","msg":"trace[921895417] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"390.365876ms","start":"2026-02-07T04:00:16.207112Z","end":"2026-02-07T04:00:16.597478Z","steps":["trace[921895417] 'process raft request' (duration: 390.249694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:16.597913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:16.207093Z","time spent":"390.458666ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:00:16.666497Z","caller":"traceutil/trace.go:171","msg":"trace[1976391159] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"137.764452ms","start":"2026-02-07T04:00:16.528707Z","end":"2026-02-07T04:00:16.666472Z","steps":["trace[1976391159] 'process raft request' (duration: 137.627911ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:16.667933Z","caller":"traceutil/trace.go:171","msg":"trace[539131229] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"460.573061ms","start":"2026-02-07T04:00:16.20734Z","end":"2026-02-07T04:00:16.667914Z","steps":["trace[539131229] 'process raft request' (duration: 394.567873ms)","trace[539131229] 'compare' (duration: 63.895589ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:00:16.668064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:16.207331Z","time spent":"460.647121ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:00:21.182686Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811725412051371,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:00:21.683007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811725412051371,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:00:22.183935Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811725412051371,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:00:22.385326Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.772314547s","expected-duration":"1s"} {"level":"info","ts":"2026-02-07T04:00:22.385588Z","caller":"traceutil/trace.go:171","msg":"trace[890695145] linearizableReadLoop","detail":"{readStateIndex:452; appliedIndex:451; }","duration":"1.703543111s","start":"2026-02-07T04:00:20.68203Z","end":"2026-02-07T04:00:22.385573Z","steps":["trace[890695145] 'read index received' (duration: 1.70339372s)","trace[890695145] 'applied index is now lower than readState.Index' (duration: 148.951µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:00:22.385758Z","caller":"traceutil/trace.go:171","msg":"trace[1275916546] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"1.772818182s","start":"2026-02-07T04:00:20.612934Z","end":"2026-02-07T04:00:22.385753Z","steps":["trace[1275916546] 'process raft request' (duration: 1.772527289s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:22.385819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:20.612922Z","time spent":"1.772849512s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:00:22.385916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.703890224s","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-02-07T04:00:22.385937Z","caller":"traceutil/trace.go:171","msg":"trace[268726184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:436; }","duration":"1.703962185s","start":"2026-02-07T04:00:20.68197Z","end":"2026-02-07T04:00:22.385932Z","steps":["trace[268726184] 'agreement among raft nodes before linearized reading' (duration: 1.703926945s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:22.385951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:20.681956Z","time spent":"1.703991586s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:00:22.386041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.682293187s","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-02-07T04:00:22.386054Z","caller":"traceutil/trace.go:171","msg":"trace[1638684370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:436; }","duration":"1.682334737s","start":"2026-02-07T04:00:20.703715Z","end":"2026-02-07T04:00:22.38605Z","steps":["trace[1638684370] 'agreement among raft nodes before linearized reading' (duration: 1.682308537s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:22.386065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:20.703676Z","time spent":"1.682386159s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T04:00:22.386106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.447376711s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:00:22.386118Z","caller":"traceutil/trace.go:171","msg":"trace[984074609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:436; }","duration":"1.447420292s","start":"2026-02-07T04:00:20.938694Z","end":"2026-02-07T04:00:22.386115Z","steps":["trace[984074609] 'agreement among raft nodes before linearized reading' (duration: 1.447400282s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:22.386132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:20.938681Z","time spent":"1.447445352s","remote":"127.0.0.1:44050","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-02-07T04:00:22.386204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.681610942s","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-02-07T04:00:22.386216Z","caller":"traceutil/trace.go:171","msg":"trace[147789375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:436; }","duration":"1.681635382s","start":"2026-02-07T04:00:20.704577Z","end":"2026-02-07T04:00:22.386212Z","steps":["trace[147789375] 'agreement among raft nodes before linearized reading' (duration: 1.681615102s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:22.386227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:20.704571Z","time spent":"1.681653602s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-07T04:00:22.509195Z","caller":"traceutil/trace.go:171","msg":"trace[1042451012] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"113.338962ms","start":"2026-02-07T04:00:22.395839Z","end":"2026-02-07T04:00:22.509178Z","steps":["trace[1042451012] 'process raft request' (duration: 111.850479ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:22.513508Z","caller":"traceutil/trace.go:171","msg":"trace[1059896117] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"116.933633ms","start":"2026-02-07T04:00:22.396562Z","end":"2026-02-07T04:00:22.513495Z","steps":["trace[1059896117] 'process raft request' (duration: 116.63174ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:22.5136Z","caller":"traceutil/trace.go:171","msg":"trace[420556714] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"116.267017ms","start":"2026-02-07T04:00:22.397327Z","end":"2026-02-07T04:00:22.513594Z","steps":["trace[420556714] 'process raft request' (duration: 115.920844ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:22.51375Z","caller":"traceutil/trace.go:171","msg":"trace[1619885701] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"113.15481ms","start":"2026-02-07T04:00:22.400589Z","end":"2026-02-07T04:00:22.513744Z","steps":["trace[1619885701] 'process raft request' (duration: 112.688766ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:33.293604Z","caller":"traceutil/trace.go:171","msg":"trace[2102055523] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"438.501988ms","start":"2026-02-07T04:00:32.855079Z","end":"2026-02-07T04:00:33.293581Z","steps":["trace[2102055523] 'process raft request' (duration: 438.333187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:33.293773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:32.855066Z","time spent":"438.634379ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4056,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:00:40.272445Z","caller":"traceutil/trace.go:171","msg":"trace[1687420387] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"168.236257ms","start":"2026-02-07T04:00:40.104192Z","end":"2026-02-07T04:00:40.272428Z","steps":["trace[1687420387] 'process raft request' (duration: 168.136383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:41.160553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.218325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:00:41.160648Z","caller":"traceutil/trace.go:171","msg":"trace[905081919] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"530.99832ms","start":"2026-02-07T04:00:40.629637Z","end":"2026-02-07T04:00:41.160635Z","steps":["trace[905081919] 'process raft request' (duration: 398.549408ms)","trace[905081919] 'compare' (duration: 132.023717ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:00:41.160679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:40.629629Z","time spent":"531.036603ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:00:41.160752Z","caller":"traceutil/trace.go:171","msg":"trace[336115094] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"527.774381ms","start":"2026-02-07T04:00:40.632974Z","end":"2026-02-07T04:00:41.160749Z","steps":["trace[336115094] 'process raft request' (duration: 527.635296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:41.160773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:40.632966Z","time spent":"527.797162ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:00:43.187551Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811725412051605,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:00:43.688561Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811725412051605,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:00:43.777646Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.131162543s","expected-duration":"1s"} {"level":"info","ts":"2026-02-07T04:00:43.77803Z","caller":"traceutil/trace.go:171","msg":"trace[1663138855] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:564; }","duration":"1.091217303s","start":"2026-02-07T04:00:42.686767Z","end":"2026-02-07T04:00:43.777984Z","steps":["trace[1663138855] 'read index received' (duration: 1.091015326s)","trace[1663138855] 'applied index is now lower than readState.Index' (duration: 201.137µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:00:43.778109Z","caller":"traceutil/trace.go:171","msg":"trace[1698269722] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.146433368s","start":"2026-02-07T04:00:42.631666Z","end":"2026-02-07T04:00:43.778099Z","steps":["trace[1698269722] 'process raft request' (duration: 1.14619738s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:43.778217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:42.631654Z","time spent":"1.14648035s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:00:43.778316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.29079ms","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-02-07T04:00:43.778368Z","caller":"traceutil/trace.go:171","msg":"trace[429492536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"614.392064ms","start":"2026-02-07T04:00:43.163963Z","end":"2026-02-07T04:00:43.778355Z","steps":["trace[429492536] 'agreement among raft nodes before linearized reading' (duration: 614.250899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:43.778411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:43.163943Z","time spent":"614.462906ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T04:00:43.778516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091754863s","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-02-07T04:00:43.778532Z","caller":"traceutil/trace.go:171","msg":"trace[1183137762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:545; }","duration":"1.091798224s","start":"2026-02-07T04:00:42.686728Z","end":"2026-02-07T04:00:43.778527Z","steps":["trace[1183137762] 'agreement among raft nodes before linearized reading' (duration: 1.091747623s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:43.778547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:42.686712Z","time spent":"1.091831255s","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:00:43.778618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.828706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:00:43.778639Z","caller":"traceutil/trace.go:171","msg":"trace[260448434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"319.881527ms","start":"2026-02-07T04:00:43.458749Z","end":"2026-02-07T04:00:43.77863Z","steps":["trace[260448434] 'agreement among raft nodes before linearized reading' (duration: 319.857026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:43.778653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:43.458729Z","time spent":"319.921659ms","remote":"127.0.0.1:44050","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-02-07T04:00:43.77884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.873647ms","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-02-07T04:00:43.778888Z","caller":"traceutil/trace.go:171","msg":"trace[1004634707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"611.105146ms","start":"2026-02-07T04:00:43.167772Z","end":"2026-02-07T04:00:43.778877Z","steps":["trace[1004634707] 'agreement among raft nodes before linearized reading' (duration: 610.958611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:43.778926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:43.167752Z","time spent":"611.167508ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-07T04:00:44.965744Z","caller":"traceutil/trace.go:171","msg":"trace[1390511629] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"144.830294ms","start":"2026-02-07T04:00:44.820891Z","end":"2026-02-07T04:00:44.965721Z","steps":["trace[1390511629] 'process raft request' (duration: 144.650688ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:45.419364Z","caller":"traceutil/trace.go:171","msg":"trace[1510883125] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"102.853923ms","start":"2026-02-07T04:00:45.316454Z","end":"2026-02-07T04:00:45.419308Z","steps":["trace[1510883125] 'process raft request' (duration: 102.76015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:45.942664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.393747ms","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-02-07T04:00:45.942706Z","caller":"traceutil/trace.go:171","msg":"trace[2119256843] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"137.464009ms","start":"2026-02-07T04:00:45.805233Z","end":"2026-02-07T04:00:45.942697Z","steps":["trace[2119256843] 'range keys from in-memory index tree' (duration: 137.334035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:45.943132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.638348ms","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-02-07T04:00:45.943149Z","caller":"traceutil/trace.go:171","msg":"trace[1383663521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"157.68791ms","start":"2026-02-07T04:00:45.785455Z","end":"2026-02-07T04:00:45.943143Z","steps":["trace[1383663521] 'range keys from in-memory index tree' (duration: 156.854161ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:48.594073Z","caller":"traceutil/trace.go:171","msg":"trace[748107085] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"130.827849ms","start":"2026-02-07T04:00:48.46323Z","end":"2026-02-07T04:00:48.594058Z","steps":["trace[748107085] 'read index received' (duration: 130.708455ms)","trace[748107085] 'applied index is now lower than readState.Index' (duration: 118.864µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:00:48.594185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.946343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:00:48.594203Z","caller":"traceutil/trace.go:171","msg":"trace[1746095534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"131.015815ms","start":"2026-02-07T04:00:48.463181Z","end":"2026-02-07T04:00:48.594197Z","steps":["trace[1746095534] 'agreement among raft nodes before linearized reading' (duration: 130.935692ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:48.594312Z","caller":"traceutil/trace.go:171","msg":"trace[1367913211] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"209.160053ms","start":"2026-02-07T04:00:48.38513Z","end":"2026-02-07T04:00:48.59429Z","steps":["trace[1367913211] 'process raft request' (duration: 208.835092ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:55.97362Z","caller":"traceutil/trace.go:171","msg":"trace[1537364346] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"837.922462ms","start":"2026-02-07T04:00:55.135682Z","end":"2026-02-07T04:00:55.973604Z","steps":["trace[1537364346] 'process raft request' (duration: 834.015509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:55.978214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:55.135666Z","time spent":"842.464786ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:00:56.661713Z","caller":"traceutil/trace.go:171","msg":"trace[1407400475] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"587.871834ms","start":"2026-02-07T04:00:56.073816Z","end":"2026-02-07T04:00:56.661688Z","steps":["trace[1407400475] 'process raft request' (duration: 587.73973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:56.661833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:56.073804Z","time spent":"587.968768ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:00:56.663146Z","caller":"traceutil/trace.go:171","msg":"trace[1149949779] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"587.570196ms","start":"2026-02-07T04:00:56.075568Z","end":"2026-02-07T04:00:56.663138Z","steps":["trace[1149949779] 'process raft request' (duration: 587.524794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:56.663187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:56.075559Z","time spent":"587.607596ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:02:14.61979Z","caller":"traceutil/trace.go:171","msg":"trace[187136616] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"203.093294ms","start":"2026-02-07T04:02:14.416674Z","end":"2026-02-07T04:02:14.619767Z","steps":["trace[187136616] 'read index received' (duration: 202.935322ms)","trace[187136616] 'applied index is now lower than readState.Index' (duration: 157.112µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:02:14.619901Z","caller":"traceutil/trace.go:171","msg":"trace[822392461] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"218.860648ms","start":"2026-02-07T04:02:14.401027Z","end":"2026-02-07T04:02:14.619888Z","steps":["trace[822392461] 'process raft request' (duration: 218.608354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:02:14.62002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.8498ms","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-02-07T04:02:14.620043Z","caller":"traceutil/trace.go:171","msg":"trace[582578924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:997; }","duration":"165.907511ms","start":"2026-02-07T04:02:14.454129Z","end":"2026-02-07T04:02:14.620036Z","steps":["trace[582578924] 'agreement among raft nodes before linearized reading' (duration: 165.822329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:02:14.620119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.44705ms","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-02-07T04:02:14.620133Z","caller":"traceutil/trace.go:171","msg":"trace[1503514172] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:997; }","duration":"203.48617ms","start":"2026-02-07T04:02:14.416643Z","end":"2026-02-07T04:02:14.620129Z","steps":["trace[1503514172] 'agreement among raft nodes before linearized reading' (duration: 203.43699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:02:15.044202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.902288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-07T04:02:15.044344Z","caller":"traceutil/trace.go:171","msg":"trace[1467617334] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:999; }","duration":"299.09269ms","start":"2026-02-07T04:02:14.745232Z","end":"2026-02-07T04:02:15.044325Z","steps":["trace[1467617334] 'range keys from in-memory index tree' (duration: 298.726084ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:02:45.630667Z","caller":"traceutil/trace.go:171","msg":"trace[632747216] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"108.450976ms","start":"2026-02-07T04:02:45.522209Z","end":"2026-02-07T04:02:45.63066Z","steps":["trace[632747216] 'process raft request' (duration: 99.293354ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:02:45.630565Z","caller":"traceutil/trace.go:171","msg":"trace[1455210794] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"108.076291ms","start":"2026-02-07T04:02:45.522476Z","end":"2026-02-07T04:02:45.630552Z","steps":["trace[1455210794] 'read index received' (duration: 98.973889ms)","trace[1455210794] 'applied index is now lower than readState.Index' (duration: 9.101822ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:02:45.631185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.700179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:4 size:329936"} {"level":"info","ts":"2026-02-07T04:02:45.632613Z","caller":"traceutil/trace.go:171","msg":"trace[1201620743] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1091; }","duration":"110.140821ms","start":"2026-02-07T04:02:45.522455Z","end":"2026-02-07T04:02:45.632595Z","steps":["trace[1201620743] 'agreement among raft nodes before linearized reading' (duration: 108.613719ms)"],"step_count":1}