{"level":"warn","ts":"2026-04-17T00:33:18.598462Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T00:33:18.598547Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-04-17T00:33:18.598609Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T00:33:18.598622Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-04-17T00:33:18.598643Z","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-04-17T00:33:18.59927Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-04-17T00:33:18.599347Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-04-17T00:33:18.603037Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.484358ms"} {"level":"info","ts":"2026-04-17T00:33:18.609146Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-04-17T00:33:18.609219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T00:33:18.609242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-04-17T00:33:18.609257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T00:33:18.609277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-04-17T00:33:18.609311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-04-17T00:33:18.616217Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T00:33:18.617786Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T00:33:18.619833Z","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-04-17T00:33:18.622185Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T00:33:18.622351Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T00:33:18.623509Z","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-04-17T00:33:18.624746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-04-17T00:33:18.624856Z","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-04-17T00:33:18.625146Z","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-04-17T00:33:18.625801Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-04-17T00:33:18.629164Z","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-04-17T00:33:18.629423Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-04-17T00:33:18.629463Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-04-17T00:33:18.62962Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T00:33:18.629673Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T00:33:18.81026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T00:33:18.810321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T00:33:18.81034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-04-17T00:33:18.810353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-04-17T00:33:18.810361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-04-17T00:33:18.810372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-04-17T00:33:18.810388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-04-17T00:33:18.811248Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:33:18.812239Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:33:18.812334Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:33:18.812525Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:33:18.812538Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T00:33:18.812623Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:33:18.812437Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:33:18.812978Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T00:33:18.813061Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T00:33:18.816081Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T00:33:18.82089Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-04-17T00:33:44.975968Z","caller":"traceutil/trace.go:171","msg":"trace[2084501861] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"174.50508ms","start":"2026-04-17T00:33:44.801427Z","end":"2026-04-17T00:33:44.975932Z","steps":["trace[2084501861] 'process raft request' (duration: 174.277415ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:45.145385Z","caller":"traceutil/trace.go:171","msg":"trace[802640138] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"151.187238ms","start":"2026-04-17T00:33:44.994164Z","end":"2026-04-17T00:33:45.145351Z","steps":["trace[802640138] 'read index received' (duration: 43.476701ms)","trace[802640138] 'applied index is now lower than readState.Index' (duration: 107.709577ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:33:45.145512Z","caller":"traceutil/trace.go:171","msg":"trace[1615733340] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"166.509602ms","start":"2026-04-17T00:33:44.978958Z","end":"2026-04-17T00:33:45.145468Z","steps":["trace[1615733340] 'process raft request' (duration: 135.435558ms)","trace[1615733340] 'compare' (duration: 30.860399ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:33:45.145528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.342541ms","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-04-17T00:33:45.145606Z","caller":"traceutil/trace.go:171","msg":"trace[1004648846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:423; }","duration":"151.470675ms","start":"2026-04-17T00:33:44.994123Z","end":"2026-04-17T00:33:45.145594Z","steps":["trace[1004648846] 'agreement among raft nodes before linearized reading' (duration: 151.336532ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:47.81137Z","caller":"traceutil/trace.go:171","msg":"trace[1182239643] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"262.091036ms","start":"2026-04-17T00:33:47.549237Z","end":"2026-04-17T00:33:47.811328Z","steps":["trace[1182239643] 'process raft request' (duration: 250.000406ms)","trace[1182239643] 'compare' (duration: 11.61255ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:33:49.317094Z","caller":"traceutil/trace.go:171","msg":"trace[789983769] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"144.032129ms","start":"2026-04-17T00:33:49.17304Z","end":"2026-04-17T00:33:49.317072Z","steps":["trace[789983769] 'process raft request' (duration: 143.893976ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:49.467865Z","caller":"traceutil/trace.go:171","msg":"trace[2146611835] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:482; }","duration":"253.078216ms","start":"2026-04-17T00:33:49.214743Z","end":"2026-04-17T00:33:49.467821Z","steps":["trace[2146611835] 'read index received' (duration: 102.197874ms)","trace[2146611835] 'applied index is now lower than readState.Index' (duration: 150.879032ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:33:49.467933Z","caller":"traceutil/trace.go:171","msg":"trace[1656575959] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"294.775897ms","start":"2026-04-17T00:33:49.173113Z","end":"2026-04-17T00:33:49.467889Z","steps":["trace[1656575959] 'process raft request' (duration: 273.91452ms)","trace[1656575959] 'compare' (duration: 20.615542ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:33:49.468081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.302131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:33:49.468165Z","caller":"traceutil/trace.go:171","msg":"trace[500374267] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:467; }","duration":"253.405564ms","start":"2026-04-17T00:33:49.214738Z","end":"2026-04-17T00:33:49.468144Z","steps":["trace[500374267] 'agreement among raft nodes before linearized reading' (duration: 253.23777ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:56.039094Z","caller":"traceutil/trace.go:171","msg":"trace[923543812] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"255.834246ms","start":"2026-04-17T00:33:55.783228Z","end":"2026-04-17T00:33:56.039062Z","steps":["trace[923543812] 'process raft request' (duration: 255.286384ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:56.301349Z","caller":"traceutil/trace.go:171","msg":"trace[1098958285] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"241.947947ms","start":"2026-04-17T00:33:56.059365Z","end":"2026-04-17T00:33:56.301313Z","steps":["trace[1098958285] 'process raft request' (duration: 241.773713ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:56.325465Z","caller":"traceutil/trace.go:171","msg":"trace[1996824585] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"224.849645ms","start":"2026-04-17T00:33:56.100572Z","end":"2026-04-17T00:33:56.325422Z","steps":["trace[1996824585] 'process raft request' (duration: 224.671111ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:57.061876Z","caller":"traceutil/trace.go:171","msg":"trace[1066047072] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"319.241484ms","start":"2026-04-17T00:33:56.742591Z","end":"2026-04-17T00:33:57.061833Z","steps":["trace[1066047072] 'process raft request' (duration: 316.422881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:33:57.062703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:33:56.742554Z","time spent":"319.427988ms","remote":"127.0.0.1:46196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:34:14.326542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.312516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:34:14.326601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.899632ms","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-04-17T00:34:14.326651Z","caller":"traceutil/trace.go:171","msg":"trace[1000720105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:745; }","duration":"933.472811ms","start":"2026-04-17T00:34:13.393155Z","end":"2026-04-17T00:34:14.326628Z","steps":["trace[1000720105] 'range keys from in-memory index tree' (duration: 933.220334ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:14.326681Z","caller":"traceutil/trace.go:171","msg":"trace[199543871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:745; }","duration":"841.017454ms","start":"2026-04-17T00:34:13.485643Z","end":"2026-04-17T00:34:14.32666Z","steps":["trace[199543871] 'range keys from in-memory index tree' (duration: 840.730067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:14.32673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:13.48563Z","time spent":"841.090736ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T00:34:14.326709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:13.393133Z","time spent":"933.562392ms","remote":"127.0.0.1:46140","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-04-17T00:34:14.326965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.683837ms","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-04-17T00:34:14.327014Z","caller":"traceutil/trace.go:171","msg":"trace[1684949745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:745; }","duration":"695.769259ms","start":"2026-04-17T00:34:13.631229Z","end":"2026-04-17T00:34:14.326998Z","steps":["trace[1684949745] 'range keys from in-memory index tree' (duration: 695.524774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:14.327044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:13.631207Z","time spent":"695.82953ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T00:34:14.361047Z","caller":"traceutil/trace.go:171","msg":"trace[1202836619] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"255.136301ms","start":"2026-04-17T00:34:14.105888Z","end":"2026-04-17T00:34:14.361025Z","steps":["trace[1202836619] 'process raft request' (duration: 254.998468ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:48.882179Z","caller":"traceutil/trace.go:171","msg":"trace[1762383405] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"359.133464ms","start":"2026-04-17T00:34:48.523019Z","end":"2026-04-17T00:34:48.882152Z","steps":["trace[1762383405] 'process raft request' (duration: 358.995341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:48.882344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:48.522991Z","time spent":"359.279457ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:34:48.971149Z","caller":"traceutil/trace.go:171","msg":"trace[1353348623] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"323.249641ms","start":"2026-04-17T00:34:48.647875Z","end":"2026-04-17T00:34:48.971124Z","steps":["trace[1353348623] 'read index received' (duration: 234.392584ms)","trace[1353348623] 'applied index is now lower than readState.Index' (duration: 88.855967ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:34:48.971368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.472666ms","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-04-17T00:34:48.971402Z","caller":"traceutil/trace.go:171","msg":"trace[1251602308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:961; }","duration":"323.550899ms","start":"2026-04-17T00:34:48.647842Z","end":"2026-04-17T00:34:48.971392Z","steps":["trace[1251602308] 'agreement among raft nodes before linearized reading' (duration: 323.376965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:48.971435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:48.647826Z","time spent":"323.601159ms","remote":"127.0.0.1:46376","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":"info","ts":"2026-04-17T00:34:48.971471Z","caller":"traceutil/trace.go:171","msg":"trace[495746635] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"447.795828ms","start":"2026-04-17T00:34:48.523655Z","end":"2026-04-17T00:34:48.97145Z","steps":["trace[495746635] 'process raft request' (duration: 443.650885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:48.971583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:48.523638Z","time spent":"447.886859ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:34:49.543997Z","caller":"traceutil/trace.go:171","msg":"trace[418094685] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"231.352576ms","start":"2026-04-17T00:34:49.312621Z","end":"2026-04-17T00:34:49.543974Z","steps":["trace[418094685] 'read index received' (duration: 231.124381ms)","trace[418094685] 'applied index is now lower than readState.Index' (duration: 227.325µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:34:49.544164Z","caller":"traceutil/trace.go:171","msg":"trace[562162266] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"568.313464ms","start":"2026-04-17T00:34:48.975824Z","end":"2026-04-17T00:34:49.544137Z","steps":["trace[562162266] 'process raft request' (duration: 567.999417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:49.544299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.664623ms","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-04-17T00:34:49.544374Z","caller":"traceutil/trace.go:171","msg":"trace[262494216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:962; }","duration":"231.788756ms","start":"2026-04-17T00:34:49.312568Z","end":"2026-04-17T00:34:49.544357Z","steps":["trace[262494216] 'agreement among raft nodes before linearized reading' (duration: 231.571921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:49.544425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:48.9758Z","time spent":"568.529418ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:34:49.544444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.45143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:34:49.544497Z","caller":"traceutil/trace.go:171","msg":"trace[1217004855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"152.544532ms","start":"2026-04-17T00:34:49.391939Z","end":"2026-04-17T00:34:49.544483Z","steps":["trace[1217004855] 'agreement among raft nodes before linearized reading' (duration: 152.421649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:49.544478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.902086ms","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-04-17T00:34:49.544568Z","caller":"traceutil/trace.go:171","msg":"trace[1280246093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:962; }","duration":"223.010198ms","start":"2026-04-17T00:34:49.321538Z","end":"2026-04-17T00:34:49.544548Z","steps":["trace[1280246093] 'agreement among raft nodes before linearized reading' (duration: 222.801573ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:11.163709Z","caller":"traceutil/trace.go:171","msg":"trace[95389801] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"184.739623ms","start":"2026-04-17T00:35:10.978942Z","end":"2026-04-17T00:35:11.163682Z","steps":["trace[95389801] 'process raft request' (duration: 184.566428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:14.296135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.62029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:35:14.296474Z","caller":"traceutil/trace.go:171","msg":"trace[1187472953] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"619.800852ms","start":"2026-04-17T00:35:13.676652Z","end":"2026-04-17T00:35:14.296453Z","steps":["trace[1187472953] 'process raft request' (duration: 176.638101ms)","trace[1187472953] 'compare' (duration: 442.210139ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:35:14.29655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:13.676632Z","time spent":"619.882503ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:35:14.296937Z","caller":"traceutil/trace.go:171","msg":"trace[969181808] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"608.56551ms","start":"2026-04-17T00:35:13.68836Z","end":"2026-04-17T00:35:14.296926Z","steps":["trace[969181808] 'read index received' (duration: 164.846127ms)","trace[969181808] 'applied index is now lower than readState.Index' (duration: 443.718233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:35:14.297174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.946014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-17T00:35:14.297152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.746938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:35:14.297213Z","caller":"traceutil/trace.go:171","msg":"trace[1331729640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1043; }","duration":"548.020326ms","start":"2026-04-17T00:35:13.749183Z","end":"2026-04-17T00:35:14.297203Z","steps":["trace[1331729640] 'agreement among raft nodes before linearized reading' (duration: 547.877263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:14.297199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.822236ms","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-04-17T00:35:14.297615Z","caller":"traceutil/trace.go:171","msg":"trace[1031574913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1043; }","duration":"609.248855ms","start":"2026-04-17T00:35:13.688317Z","end":"2026-04-17T00:35:14.297566Z","steps":["trace[1031574913] 'agreement among raft nodes before linearized reading' (duration: 608.693403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:14.297698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:13.6883Z","time spent":"609.356747ms","remote":"127.0.0.1:46376","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":"info","ts":"2026-04-17T00:35:14.297268Z","caller":"traceutil/trace.go:171","msg":"trace[1554727267] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1043; }","duration":"479.864371ms","start":"2026-04-17T00:35:13.817347Z","end":"2026-04-17T00:35:14.297212Z","steps":["trace[1554727267] 'agreement among raft nodes before linearized reading' (duration: 479.731248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:14.298258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:13.817335Z","time spent":"480.878844ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:35:14.29836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:13.74917Z","time spent":"549.148241ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T00:35:14.800253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.57657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:35:14.800394Z","caller":"traceutil/trace.go:171","msg":"trace[1554929344] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"403.267911ms","start":"2026-04-17T00:35:14.397071Z","end":"2026-04-17T00:35:14.800339Z","steps":["trace[1554929344] 'read index received' (duration: 171.564628ms)","trace[1554929344] 'applied index is now lower than readState.Index' (duration: 231.702033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:35:14.800534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.457215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:35:14.800519Z","caller":"traceutil/trace.go:171","msg":"trace[510909552] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"498.46619ms","start":"2026-04-17T00:35:14.302007Z","end":"2026-04-17T00:35:14.800473Z","steps":["trace[510909552] 'process raft request' (duration: 266.582273ms)","trace[510909552] 'compare' (duration: 231.233532ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:35:14.800599Z","caller":"traceutil/trace.go:171","msg":"trace[1937203324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1045; }","duration":"403.523137ms","start":"2026-04-17T00:35:14.397035Z","end":"2026-04-17T00:35:14.800558Z","steps":["trace[1937203324] 'agreement among raft nodes before linearized reading' (duration: 403.461045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:14.800642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:14.397018Z","time spent":"403.615238ms","remote":"127.0.0.1:46140","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-04-17T00:35:14.800749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:14.301986Z","time spent":"498.634103ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:35:14.801136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.745963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:35:14.801194Z","caller":"traceutil/trace.go:171","msg":"trace[507844810] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1045; }","duration":"184.845485ms","start":"2026-04-17T00:35:14.616333Z","end":"2026-04-17T00:35:14.801179Z","steps":["trace[507844810] 'agreement among raft nodes before linearized reading' (duration: 184.760943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:29.749954Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880868783748750055,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:35:30.250395Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880868783748750055,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T00:35:30.290953Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.041933129s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T00:35:30.291307Z","caller":"traceutil/trace.go:171","msg":"trace[1450155878] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"1.042363268s","start":"2026-04-17T00:35:29.248915Z","end":"2026-04-17T00:35:30.291278Z","steps":["trace[1450155878] 'process raft request' (duration: 1.042206095s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:30.29148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:29.248894Z","time spent":"1.042505693s","remote":"127.0.0.1:46376","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-04-17T00:35:30.337668Z","caller":"traceutil/trace.go:171","msg":"trace[1501119523] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1197; }","duration":"1.088131402s","start":"2026-04-17T00:35:29.249508Z","end":"2026-04-17T00:35:30.33764Z","steps":["trace[1501119523] 'read index received' (duration: 1.041613451s)","trace[1501119523] 'applied index is now lower than readState.Index' (duration: 46.516351ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:35:30.337771Z","caller":"traceutil/trace.go:171","msg":"trace[235125047] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"613.325001ms","start":"2026-04-17T00:35:29.724422Z","end":"2026-04-17T00:35:30.337747Z","steps":["trace[235125047] 'process raft request' (duration: 613.061365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:30.337852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.020451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:35:30.337889Z","caller":"traceutil/trace.go:171","msg":"trace[2079445679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1153; }","duration":"945.073732ms","start":"2026-04-17T00:35:29.392805Z","end":"2026-04-17T00:35:30.337879Z","steps":["trace[2079445679] 'agreement among raft nodes before linearized reading' (duration: 944.99185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:30.337925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:29.392727Z","time spent":"945.190305ms","remote":"127.0.0.1:46140","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-04-17T00:35:30.337924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.747282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:35:30.337977Z","caller":"traceutil/trace.go:171","msg":"trace[1946000498] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1153; }","duration":"125.840685ms","start":"2026-04-17T00:35:30.212122Z","end":"2026-04-17T00:35:30.337963Z","steps":["trace[1946000498] 'agreement among raft nodes before linearized reading' (duration: 125.753023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:30.337992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.456446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-17T00:35:30.337944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:29.724405Z","time spent":"613.412352ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:35:30.338065Z","caller":"traceutil/trace.go:171","msg":"trace[303696458] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1153; }","duration":"576.574029ms","start":"2026-04-17T00:35:29.761473Z","end":"2026-04-17T00:35:30.338047Z","steps":["trace[303696458] 'agreement among raft nodes before linearized reading' (duration: 576.410775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:30.338105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:29.761459Z","time spent":"576.63663ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:35:30.338479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088972581s","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-04-17T00:35:30.338541Z","caller":"traceutil/trace.go:171","msg":"trace[574849052] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1153; }","duration":"1.089062202s","start":"2026-04-17T00:35:29.249467Z","end":"2026-04-17T00:35:30.338529Z","steps":["trace[574849052] 'agreement among raft nodes before linearized reading' (duration: 1.088314076s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:30.338572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:29.249442Z","time spent":"1.089123024s","remote":"127.0.0.1:46376","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":"info","ts":"2026-04-17T00:35:45.288343Z","caller":"traceutil/trace.go:171","msg":"trace[243857989] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"308.482267ms","start":"2026-04-17T00:35:44.97983Z","end":"2026-04-17T00:35:45.288312Z","steps":["trace[243857989] 'process raft request' (duration: 308.17294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:45.288493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:44.979821Z","time spent":"308.597039ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:35:46.83689Z","caller":"traceutil/trace.go:171","msg":"trace[126947610] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"469.694301ms","start":"2026-04-17T00:35:46.367174Z","end":"2026-04-17T00:35:46.836868Z","steps":["trace[126947610] 'process raft request' (duration: 469.546758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:46.837045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:46.367162Z","time spent":"469.803443ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:35:46.837029Z","caller":"traceutil/trace.go:171","msg":"trace[1865114735] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1274; }","duration":"444.641202ms","start":"2026-04-17T00:35:46.392363Z","end":"2026-04-17T00:35:46.837004Z","steps":["trace[1865114735] 'read index received' (duration: 444.630212ms)","trace[1865114735] 'applied index is now lower than readState.Index' (duration: 9.1µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:35:46.837176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.801316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:35:46.837258Z","caller":"traceutil/trace.go:171","msg":"trace[2137378164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1225; }","duration":"444.929089ms","start":"2026-04-17T00:35:46.392309Z","end":"2026-04-17T00:35:46.837238Z","steps":["trace[2137378164] 'agreement among raft nodes before linearized reading' (duration: 444.803146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:46.837302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:46.392288Z","time spent":"445.00611ms","remote":"127.0.0.1:46140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T00:35:46.844699Z","caller":"traceutil/trace.go:171","msg":"trace[1617909913] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"380.89376ms","start":"2026-04-17T00:35:46.463771Z","end":"2026-04-17T00:35:46.844665Z","steps":["trace[1617909913] 'process raft request' (duration: 380.693706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:46.844861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.266898ms","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-04-17T00:35:46.844911Z","caller":"traceutil/trace.go:171","msg":"trace[1566088750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1227; }","duration":"354.34496ms","start":"2026-04-17T00:35:46.490554Z","end":"2026-04-17T00:35:46.844898Z","steps":["trace[1566088750] 'agreement among raft nodes before linearized reading' (duration: 354.162036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:46.844966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:46.490537Z","time spent":"354.421312ms","remote":"127.0.0.1:46376","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-04-17T00:35:46.844868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:46.463749Z","time spent":"381.043495ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:35:46.845095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.520972ms","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":"warn","ts":"2026-04-17T00:35:46.845111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.454643ms","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-04-17T00:35:46.845128Z","caller":"traceutil/trace.go:171","msg":"trace[1290306421] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1227; }","duration":"358.595234ms","start":"2026-04-17T00:35:46.486525Z","end":"2026-04-17T00:35:46.84512Z","steps":["trace[1290306421] 'agreement among raft nodes before linearized reading' (duration: 358.161725ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:46.845143Z","caller":"traceutil/trace.go:171","msg":"trace[755712769] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1227; }","duration":"354.523674ms","start":"2026-04-17T00:35:46.49061Z","end":"2026-04-17T00:35:46.845134Z","steps":["trace[755712769] 'agreement among raft nodes before linearized reading' (duration: 354.023172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:46.84515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:46.486512Z","time spent":"358.631625ms","remote":"127.0.0.1:46376","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-04-17T00:35:46.845176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:35:46.490585Z","time spent":"354.584275ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T00:35:47.437554Z","caller":"traceutil/trace.go:171","msg":"trace[1083579466] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"105.476702ms","start":"2026-04-17T00:35:47.332045Z","end":"2026-04-17T00:35:47.437522Z","steps":["trace[1083579466] 'process raft request' (duration: 105.159265ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:57.120317Z","caller":"traceutil/trace.go:171","msg":"trace[382161210] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"200.113141ms","start":"2026-04-17T00:35:56.920176Z","end":"2026-04-17T00:35:57.120289Z","steps":["trace[382161210] 'process raft request' (duration: 200.012209ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:57.120364Z","caller":"traceutil/trace.go:171","msg":"trace[1755125788] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"200.443779ms","start":"2026-04-17T00:35:56.919891Z","end":"2026-04-17T00:35:57.120335Z","steps":["trace[1755125788] 'process raft request' (duration: 156.411457ms)","trace[1755125788] 'compare' (duration: 43.626863ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:35:57.14824Z","caller":"traceutil/trace.go:171","msg":"trace[2002557108] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"167.267829ms","start":"2026-04-17T00:35:56.980955Z","end":"2026-04-17T00:35:57.148222Z","steps":["trace[2002557108] 'process raft request' (duration: 167.184407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:57.422425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.026895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-04-17T00:35:57.422541Z","caller":"traceutil/trace.go:171","msg":"trace[2046093996] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1307; }","duration":"137.186819ms","start":"2026-04-17T00:35:57.285329Z","end":"2026-04-17T00:35:57.422515Z","steps":["trace[2046093996] 'range keys from in-memory index tree' (duration: 136.830749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:35:57.558835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.461098ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-17T00:35:57.558936Z","caller":"traceutil/trace.go:171","msg":"trace[1975200391] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1358; }","duration":"166.44992ms","start":"2026-04-17T00:35:57.392469Z","end":"2026-04-17T00:35:57.558918Z","steps":["trace[1975200391] 'read index received' (duration: 54.971µs)","trace[1975200391] 'applied index is now lower than readState.Index' (duration: 166.393319ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:35:57.559048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.569192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:35:57.559133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.25816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-17T00:35:57.559134Z","caller":"traceutil/trace.go:171","msg":"trace[1022964969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"166.689065ms","start":"2026-04-17T00:35:57.392417Z","end":"2026-04-17T00:35:57.559106Z","steps":["trace[1022964969] 'agreement among raft nodes before linearized reading' (duration: 166.564762ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:35:57.559163Z","caller":"traceutil/trace.go:171","msg":"trace[1025810172] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1307; }","duration":"133.335272ms","start":"2026-04-17T00:35:57.425819Z","end":"2026-04-17T00:35:57.559154Z","steps":["trace[1025810172] 'agreement among raft nodes before linearized reading' (duration: 133.192329ms)"],"step_count":1}