{"level":"warn","ts":"2026-02-04T01:50:42.174305Z","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-04T01:50:42.17439Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-04T01:50:42.174622Z","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-04T01:50:42.174638Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-02-04T01:50:42.174706Z","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-04T01:50:42.175314Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-02-04T01:50:42.177625Z","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://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-04T01:50:42.182091Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.104559ms"} {"level":"info","ts":"2026-02-04T01:50:42.225915Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-02-04T01:50:42.225976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T01:50:42.226057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-02-04T01:50:42.226101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T01:50:42.226138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-02-04T01:50:42.226203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-02-04T01:50:42.231044Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T01:50:42.233431Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T01:50:42.236126Z","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-04T01:50:42.24796Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T01:50:42.2491Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T01:50:42.249383Z","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-04T01:50:42.249412Z","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-04T01:50:42.249555Z","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-04T01:50:42.270399Z","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-04T01:50:42.270656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-02-04T01:50:42.270781Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-02-04T01:50:42.270941Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-02-04T01:50:42.270964Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-02-04T01:50:42.272149Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T01:50:42.272212Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T01:50:42.527179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T01:50:42.527238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T01:50:42.52734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-02-04T01:50:42.527397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-02-04T01:50:42.527409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-02-04T01:50:42.52745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-02-04T01:50:42.527501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-02-04T01:50:42.529417Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T01:50:42.52944Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:50:42.529477Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:50:42.529977Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:50:42.531741Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-02-04T01:50:42.532947Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:50:42.533567Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:50:42.533592Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:50:42.540117Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T01:50:42.540167Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T01:50:42.544217Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T01:50:44.959336Z","caller":"traceutil/trace.go:171","msg":"trace[170437387] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"104.982478ms","start":"2026-02-04T01:50:44.854162Z","end":"2026-02-04T01:50:44.959145Z","steps":["trace[170437387] 'process raft request' (duration: 104.797004ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:50:44.962369Z","caller":"traceutil/trace.go:171","msg":"trace[1025370998] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"108.168639ms","start":"2026-02-04T01:50:44.854185Z","end":"2026-02-04T01:50:44.962354Z","steps":["trace[1025370998] 'process raft request' (duration: 108.067367ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:50:53.342677Z","caller":"traceutil/trace.go:171","msg":"trace[1820734975] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"148.993036ms","start":"2026-02-04T01:50:53.19366Z","end":"2026-02-04T01:50:53.342653Z","steps":["trace[1820734975] 'process raft request' (duration: 144.415226ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:50:54.066893Z","caller":"traceutil/trace.go:171","msg":"trace[1558464257] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:361; }","duration":"102.683534ms","start":"2026-02-04T01:50:53.964186Z","end":"2026-02-04T01:50:54.06687Z","steps":["trace[1558464257] 'read index received' (duration: 102.512651ms)","trace[1558464257] 'applied index is now lower than readState.Index' (duration: 169.983µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:50:54.067057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.832637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4013"} {"level":"info","ts":"2026-02-04T01:50:54.067096Z","caller":"traceutil/trace.go:171","msg":"trace[1520891786] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:353; }","duration":"102.928629ms","start":"2026-02-04T01:50:53.964158Z","end":"2026-02-04T01:50:54.067086Z","steps":["trace[1520891786] 'agreement among raft nodes before linearized reading' (duration: 102.803527ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:50:54.067332Z","caller":"traceutil/trace.go:171","msg":"trace[153992746] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"190.954664ms","start":"2026-02-04T01:50:53.876368Z","end":"2026-02-04T01:50:54.067322Z","steps":["trace[153992746] 'process raft request' (duration: 190.388834ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:50:54.764591Z","caller":"traceutil/trace.go:171","msg":"trace[1393326330] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"141.089867ms","start":"2026-02-04T01:50:54.623457Z","end":"2026-02-04T01:50:54.764547Z","steps":["trace[1393326330] 'process raft request' (duration: 83.592685ms)","trace[1393326330] 'compare' (duration: 57.313818ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:50:54.972875Z","caller":"traceutil/trace.go:171","msg":"trace[1742964464] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"141.220368ms","start":"2026-02-04T01:50:54.831633Z","end":"2026-02-04T01:50:54.972853Z","steps":["trace[1742964464] 'process raft request' (duration: 79.086506ms)","trace[1742964464] 'compare' (duration: 62.02824ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:51:16.212909Z","caller":"traceutil/trace.go:171","msg":"trace[1516962072] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"282.533943ms","start":"2026-02-04T01:51:15.930359Z","end":"2026-02-04T01:51:16.212893Z","steps":["trace[1516962072] 'read index received' (duration: 282.422131ms)","trace[1516962072] 'applied index is now lower than readState.Index' (duration: 111.132µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:51:16.213011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.634314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T01:51:16.213029Z","caller":"traceutil/trace.go:171","msg":"trace[221873005] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:482; }","duration":"282.689485ms","start":"2026-02-04T01:51:15.930333Z","end":"2026-02-04T01:51:16.213023Z","steps":["trace[221873005] 'agreement among raft nodes before linearized reading' (duration: 282.637084ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:51:16.213164Z","caller":"traceutil/trace.go:171","msg":"trace[534071426] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"512.817138ms","start":"2026-02-04T01:51:15.700322Z","end":"2026-02-04T01:51:16.213139Z","steps":["trace[534071426] 'process raft request' (duration: 512.478843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:16.213758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:15.700307Z","time spent":"512.915642ms","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:51:16.766564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408188764599850,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:51:17.025185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.50018ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-04T01:51:17.02529Z","caller":"traceutil/trace.go:171","msg":"trace[1030884406] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:498; }","duration":"759.255868ms","start":"2026-02-04T01:51:16.265998Z","end":"2026-02-04T01:51:17.025254Z","steps":["trace[1030884406] 'read index received' (duration: 528.472643ms)","trace[1030884406] 'applied index is now lower than readState.Index' (duration: 230.782215ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:51:17.025374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:16.214648Z","time spent":"810.723065ms","remote":"127.0.0.1:42654","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-04T01:51:17.0256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.592884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-04T01:51:17.025642Z","caller":"traceutil/trace.go:171","msg":"trace[902885624] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:482; }","duration":"759.669596ms","start":"2026-02-04T01:51:16.265961Z","end":"2026-02-04T01:51:17.025631Z","steps":["trace[902885624] 'agreement among raft nodes before linearized reading' (duration: 759.584133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:17.025679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:16.265944Z","time spent":"759.726486ms","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:51:17.025818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.722717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:51:17.025855Z","caller":"traceutil/trace.go:171","msg":"trace[1949323812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"395.791409ms","start":"2026-02-04T01:51:16.630054Z","end":"2026-02-04T01:51:17.025846Z","steps":["trace[1949323812] 'agreement among raft nodes before linearized reading' (duration: 395.742058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:17.025886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:16.630036Z","time spent":"395.844319ms","remote":"127.0.0.1:42628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:51:17.026032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.237161ms","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-04T01:51:17.026072Z","caller":"traceutil/trace.go:171","msg":"trace[512332509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:482; }","duration":"227.310352ms","start":"2026-02-04T01:51:16.798752Z","end":"2026-02-04T01:51:17.026062Z","steps":["trace[512332509] 'agreement among raft nodes before linearized reading' (duration: 227.249041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:18.376537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.901779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:51:18.37714Z","caller":"traceutil/trace.go:171","msg":"trace[1900867220] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"240.180855ms","start":"2026-02-04T01:51:18.13694Z","end":"2026-02-04T01:51:18.377121Z","steps":["trace[1900867220] 'process raft request' (duration: 70.623007ms)","trace[1900867220] 'compare' (duration: 168.784786ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:51:18.377399Z","caller":"traceutil/trace.go:171","msg":"trace[1858759774] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"132.98705ms","start":"2026-02-04T01:51:18.244382Z","end":"2026-02-04T01:51:18.377369Z","steps":["trace[1858759774] 'process raft request' (duration: 132.244209ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:51:18.377905Z","caller":"traceutil/trace.go:171","msg":"trace[1728397208] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"167.06858ms","start":"2026-02-04T01:51:18.210824Z","end":"2026-02-04T01:51:18.377893Z","steps":["trace[1728397208] 'read index received' (duration: 18.971µs)","trace[1728397208] 'applied index is now lower than readState.Index' (duration: 167.048589ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:51:18.378204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.357684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-04T01:51:18.378282Z","caller":"traceutil/trace.go:171","msg":"trace[1509719790] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:535; }","duration":"167.455235ms","start":"2026-02-04T01:51:18.210793Z","end":"2026-02-04T01:51:18.378248Z","steps":["trace[1509719790] 'agreement among raft nodes before linearized reading' (duration: 167.283273ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:51:18.839469Z","caller":"traceutil/trace.go:171","msg":"trace[1043409096] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:554; }","duration":"209.547766ms","start":"2026-02-04T01:51:18.629887Z","end":"2026-02-04T01:51:18.839434Z","steps":["trace[1043409096] 'read index received' (duration: 209.522486ms)","trace[1043409096] 'applied index is now lower than readState.Index' (duration: 24.53µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:51:18.839552Z","caller":"traceutil/trace.go:171","msg":"trace[1940737362] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"333.488791ms","start":"2026-02-04T01:51:18.506058Z","end":"2026-02-04T01:51:18.839547Z","steps":["trace[1940737362] 'process raft request' (duration: 333.219687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:18.839626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:18.506034Z","time spent":"333.535931ms","remote":"127.0.0.1:42680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:51:18.839787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.883641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:51:18.839843Z","caller":"traceutil/trace.go:171","msg":"trace[50812846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"209.978812ms","start":"2026-02-04T01:51:18.629849Z","end":"2026-02-04T01:51:18.839827Z","steps":["trace[50812846] 'agreement among raft nodes before linearized reading' (duration: 209.788699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:19.340497Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408188764599947,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T01:51:19.45039Z","caller":"traceutil/trace.go:171","msg":"trace[715684195] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"798.619492ms","start":"2026-02-04T01:51:18.651758Z","end":"2026-02-04T01:51:19.450377Z","steps":["trace[715684195] 'process raft request' (duration: 798.51707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:19.45095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:18.651743Z","time spent":"799.14764ms","remote":"127.0.0.1:42834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4365,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:51:19.99631Z","caller":"traceutil/trace.go:171","msg":"trace[955871146] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:556; }","duration":"1.156778485s","start":"2026-02-04T01:51:18.83951Z","end":"2026-02-04T01:51:19.996289Z","steps":["trace[955871146] 'read index received' (duration: 610.966093ms)","trace[955871146] 'applied index is now lower than readState.Index' (duration: 545.811062ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:51:19.996596Z","caller":"traceutil/trace.go:171","msg":"trace[243358000] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.134168766s","start":"2026-02-04T01:51:18.862413Z","end":"2026-02-04T01:51:19.996582Z","steps":["trace[243358000] 'process raft request' (duration: 1.133569268s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:19.996721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:18.862391Z","time spent":"1.134235537s","remote":"127.0.0.1:42680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:51:19.996897Z","caller":"traceutil/trace.go:171","msg":"trace[82227070] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.057663942s","start":"2026-02-04T01:51:18.939221Z","end":"2026-02-04T01:51:19.996885Z","steps":["trace[82227070] 'process raft request' (duration: 1.056955961s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:19.996945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:18.939205Z","time spent":"1.057716262s","remote":"127.0.0.1:42680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:51:19.997082Z","caller":"traceutil/trace.go:171","msg":"trace[414491375] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"890.849002ms","start":"2026-02-04T01:51:19.106212Z","end":"2026-02-04T01:51:19.99706Z","steps":["trace[414491375] 'process raft request' (duration: 890.043481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:19.997129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:19.106196Z","time spent":"890.910053ms","remote":"127.0.0.1:42680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:51:19.997304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292680489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:51:19.997338Z","caller":"traceutil/trace.go:171","msg":"trace[1989767319] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:541; }","duration":"1.29273619s","start":"2026-02-04T01:51:18.704591Z","end":"2026-02-04T01:51:19.997327Z","steps":["trace[1989767319] 'agreement among raft nodes before linearized reading' (duration: 1.292681469s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:19.997372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:18.704576Z","time spent":"1.292788451s","remote":"127.0.0.1:42680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-04T01:51:19.997514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.156506261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:51:19.997543Z","caller":"traceutil/trace.go:171","msg":"trace[871919388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"1.156548721s","start":"2026-02-04T01:51:18.840984Z","end":"2026-02-04T01:51:19.997533Z","steps":["trace[871919388] 'agreement among raft nodes before linearized reading' (duration: 1.15650545s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:19.99757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:18.840975Z","time spent":"1.156588512s","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:51:19.998079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.767342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-04T01:51:19.998114Z","caller":"traceutil/trace.go:171","msg":"trace[1455236947] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:541; }","duration":"292.833243ms","start":"2026-02-04T01:51:19.705269Z","end":"2026-02-04T01:51:19.998102Z","steps":["trace[1455236947] 'agreement among raft nodes before linearized reading' (duration: 292.742922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:19.998295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.665886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-04T01:51:19.998327Z","caller":"traceutil/trace.go:171","msg":"trace[1109577080] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:541; }","duration":"493.722237ms","start":"2026-02-04T01:51:19.504595Z","end":"2026-02-04T01:51:19.998317Z","steps":["trace[1109577080] 'agreement among raft nodes before linearized reading' (duration: 493.629715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:19.998355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:19.504583Z","time spent":"493.765437ms","remote":"127.0.0.1:42680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13251,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-04T01:51:19.998551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.786211ms","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-04T01:51:19.998594Z","caller":"traceutil/trace.go:171","msg":"trace[1115437856] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:541; }","duration":"602.861031ms","start":"2026-02-04T01:51:19.395712Z","end":"2026-02-04T01:51:19.998573Z","steps":["trace[1115437856] 'agreement among raft nodes before linearized reading' (duration: 602.77197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:19.998617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:19.395702Z","time spent":"602.910343ms","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T01:51:19.998741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.806517ms","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-02-04T01:51:19.998759Z","caller":"traceutil/trace.go:171","msg":"trace[1298888684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"654.844457ms","start":"2026-02-04T01:51:19.343909Z","end":"2026-02-04T01:51:19.998753Z","steps":["trace[1298888684] 'agreement among raft nodes before linearized reading' (duration: 654.790786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:19.998775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:19.343899Z","time spent":"654.872777ms","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:51:19.998876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.528126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T01:51:19.998895Z","caller":"traceutil/trace.go:171","msg":"trace[1112286069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:541; }","duration":"661.570717ms","start":"2026-02-04T01:51:19.337319Z","end":"2026-02-04T01:51:19.99889Z","steps":["trace[1112286069] 'agreement among raft nodes before linearized reading' (duration: 661.521056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:19.998912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:19.337269Z","time spent":"661.639417ms","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:51:19.999014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.159653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-04T01:51:19.999035Z","caller":"traceutil/trace.go:171","msg":"trace[1166388093] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:541; }","duration":"694.200723ms","start":"2026-02-04T01:51:19.304828Z","end":"2026-02-04T01:51:19.999029Z","steps":["trace[1166388093] 'agreement among raft nodes before linearized reading' (duration: 694.146652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:19.999052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:19.304814Z","time spent":"694.234223ms","remote":"127.0.0.1:42680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5608,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-04T01:51:19.999158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.341465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-04T01:51:19.999175Z","caller":"traceutil/trace.go:171","msg":"trace[1512124914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"939.369985ms","start":"2026-02-04T01:51:19.0598Z","end":"2026-02-04T01:51:19.999169Z","steps":["trace[1512124914] 'agreement among raft nodes before linearized reading' (duration: 939.320384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:19.999192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:19.059794Z","time spent":"939.394535ms","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:51:19.999294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.175817ms","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-04T01:51:19.999311Z","caller":"traceutil/trace.go:171","msg":"trace[802263870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:541; }","duration":"946.214468ms","start":"2026-02-04T01:51:19.053091Z","end":"2026-02-04T01:51:19.999306Z","steps":["trace[802263870] 'agreement among raft nodes before linearized reading' (duration: 946.163427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:19.999329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:19.053078Z","time spent":"946.246058ms","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:51:27.758951Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.035618615s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T01:51:27.759222Z","caller":"traceutil/trace.go:171","msg":"trace[97354735] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"133.396344ms","start":"2026-02-04T01:51:27.625811Z","end":"2026-02-04T01:51:27.759207Z","steps":["trace[97354735] 'read index received' (duration: 133.239519ms)","trace[97354735] 'applied index is now lower than readState.Index' (duration: 156.055µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:51:27.759302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.466926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:51:27.759319Z","caller":"traceutil/trace.go:171","msg":"trace[105667275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"133.510168ms","start":"2026-02-04T01:51:27.625803Z","end":"2026-02-04T01:51:27.759313Z","steps":["trace[105667275] 'agreement among raft nodes before linearized reading' (duration: 133.438655ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:51:27.759461Z","caller":"traceutil/trace.go:171","msg":"trace[339095301] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.036207436s","start":"2026-02-04T01:51:26.723246Z","end":"2026-02-04T01:51:27.759453Z","steps":["trace[339095301] 'process raft request' (duration: 1.035872094s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:27.759508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:26.723231Z","time spent":"1.036242117s","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:51:28.217489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.657857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-04T01:51:28.217541Z","caller":"traceutil/trace.go:171","msg":"trace[859468163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"136.773691ms","start":"2026-02-04T01:51:28.080757Z","end":"2026-02-04T01:51:28.217531Z","steps":["trace[859468163] 'range keys from in-memory index tree' (duration: 136.538793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:28.217633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.324761ms","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-02-04T01:51:28.217648Z","caller":"traceutil/trace.go:171","msg":"trace[481329924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:582; }","duration":"126.358382ms","start":"2026-02-04T01:51:28.091284Z","end":"2026-02-04T01:51:28.217642Z","steps":["trace[481329924] 'range keys from in-memory index tree' (duration: 126.250589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:28.217722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.501096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-04T01:51:28.217736Z","caller":"traceutil/trace.go:171","msg":"trace[311453305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"109.561238ms","start":"2026-02-04T01:51:28.108169Z","end":"2026-02-04T01:51:28.21773Z","steps":["trace[311453305] 'range keys from in-memory index tree' (duration: 109.386252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:28.21781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.694474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:51:28.217822Z","caller":"traceutil/trace.go:171","msg":"trace[1505751234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"100.717006ms","start":"2026-02-04T01:51:28.117101Z","end":"2026-02-04T01:51:28.217818Z","steps":["trace[1505751234] 'range keys from in-memory index tree' (duration: 100.647312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:29.131183Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408188764600078,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T01:51:29.135564Z","caller":"traceutil/trace.go:171","msg":"trace[1804262436] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"908.498386ms","start":"2026-02-04T01:51:28.227046Z","end":"2026-02-04T01:51:29.135544Z","steps":["trace[1804262436] 'process raft request' (duration: 908.367081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:29.13568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:28.227028Z","time spent":"908.589609ms","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:51:30.630471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000127576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T01:51:30.630544Z","caller":"traceutil/trace.go:171","msg":"trace[12827989] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00024855s","start":"2026-02-04T01:51:28.630281Z","end":"2026-02-04T01:51:30.63053Z","steps":["trace[12827989] 'agreement among raft nodes before linearized reading' (duration: 2.000153127s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:30.630586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:28.630267Z","time spent":"2.000313442s","remote":"127.0.0.1:42628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:51:30.909275Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.773922368s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T01:51:30.909525Z","caller":"traceutil/trace.go:171","msg":"trace[314105027] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:602; }","duration":"2.279199181s","start":"2026-02-04T01:51:28.630313Z","end":"2026-02-04T01:51:30.909513Z","steps":["trace[314105027] 'read index received' (duration: 505.20189ms)","trace[314105027] 'applied index is now lower than readState.Index' (duration: 1.773996711s)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:51:30.909654Z","caller":"traceutil/trace.go:171","msg":"trace[986096097] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"2.677233469s","start":"2026-02-04T01:51:28.232415Z","end":"2026-02-04T01:51:30.909649Z","steps":["trace[986096097] 'process raft request' (duration: 2.676937779s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:30.909709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:28.23239Z","time spent":"2.677278441s","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:51:30.909793Z","caller":"traceutil/trace.go:171","msg":"trace[128447228] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"2.677329993s","start":"2026-02-04T01:51:28.232459Z","end":"2026-02-04T01:51:30.909789Z","steps":["trace[128447228] 'process raft request' (duration: 2.676964761s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:30.909814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:28.232455Z","time spent":"2.677347164s","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:51:30.909865Z","caller":"traceutil/trace.go:171","msg":"trace[632411357] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"2.677378785s","start":"2026-02-04T01:51:28.232482Z","end":"2026-02-04T01:51:30.90986Z","steps":["trace[632411357] 'process raft request' (duration: 2.67696723s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:30.909885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:28.232479Z","time spent":"2.677395685s","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:51:30.909951Z","caller":"traceutil/trace.go:171","msg":"trace[89694023] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"2.677371255s","start":"2026-02-04T01:51:28.232574Z","end":"2026-02-04T01:51:30.909945Z","steps":["trace[89694023] 'process raft request' (duration: 2.676900138s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:30.909974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:28.23257Z","time spent":"2.677390075s","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:51:30.910037Z","caller":"traceutil/trace.go:171","msg":"trace[1878780171] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"2.42408408s","start":"2026-02-04T01:51:28.485949Z","end":"2026-02-04T01:51:30.910033Z","steps":["trace[1878780171] 'process raft request' (duration: 2.423548171s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:51:30.910061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:51:28.485922Z","time spent":"2.424126221s","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:51:30.910132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.873249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:51:30.910147Z","caller":"traceutil/trace.go:171","msg":"trace[958461035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"278.905789ms","start":"2026-02-04T01:51:30.631237Z","end":"2026-02-04T01:51:30.910142Z","steps":["trace[958461035] 'agreement among raft nodes before linearized reading' (duration: 278.882349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:52:51.773764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.980878ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-04T01:52:51.773846Z","caller":"traceutil/trace.go:171","msg":"trace[1764379841] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"304.812291ms","start":"2026-02-04T01:52:51.469021Z","end":"2026-02-04T01:52:51.773833Z","steps":["trace[1764379841] 'read index received' (duration: 37.271µs)","trace[1764379841] 'applied index is now lower than readState.Index' (duration: 304.77407ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:52:51.773988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.960935ms","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-04T01:52:51.774005Z","caller":"traceutil/trace.go:171","msg":"trace[167472018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:958; }","duration":"305.021297ms","start":"2026-02-04T01:52:51.468978Z","end":"2026-02-04T01:52:51.773999Z","steps":["trace[167472018] 'agreement among raft nodes before linearized reading' (duration: 304.923604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:52:51.774026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:52:51.46896Z","time spent":"305.061557ms","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:52:51.774263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.03893ms","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-04T01:52:51.774281Z","caller":"traceutil/trace.go:171","msg":"trace[24727449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:958; }","duration":"213.093881ms","start":"2026-02-04T01:52:51.561181Z","end":"2026-02-04T01:52:51.774275Z","steps":["trace[24727449] 'agreement among raft nodes before linearized reading' (duration: 213.04277ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:52:51.940216Z","caller":"traceutil/trace.go:171","msg":"trace[1978429911] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"160.067386ms","start":"2026-02-04T01:52:51.780133Z","end":"2026-02-04T01:52:51.940201Z","steps":["trace[1978429911] 'process raft request' (duration: 120.497042ms)","trace[1978429911] 'compare' (duration: 39.303909ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:52:51.940339Z","caller":"traceutil/trace.go:171","msg":"trace[1119152047] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"158.79759ms","start":"2026-02-04T01:52:51.781538Z","end":"2026-02-04T01:52:51.940335Z","steps":["trace[1119152047] 'process raft request' (duration: 158.474603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:52:52.30596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.323421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:52:52.306318Z","caller":"traceutil/trace.go:171","msg":"trace[1035938206] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"359.789803ms","start":"2026-02-04T01:52:51.946495Z","end":"2026-02-04T01:52:52.306285Z","steps":["trace[1035938206] 'process raft request' (duration: 194.066293ms)","trace[1035938206] 'compare' (duration: 165.231209ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:52:52.306764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:52:51.946489Z","time spent":"359.895635ms","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}