{"level":"warn","ts":"2026-09-19T01:57:33.018882Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T01:57:33.018975Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.92:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.92:2380","--initial-cluster=instance=https://162.253.55.92:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.92:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.92:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-19T01:57:33.019058Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T01:57:33.019078Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.92:2380"]} {"level":"info","ts":"2026-09-19T01:57:33.0191Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T01:57:33.019711Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.92:2379"]} {"level":"info","ts":"2026-09-19T01:57:33.019788Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.92:2380"],"listen-peer-urls":["https://162.253.55.92:2380"],"advertise-client-urls":["https://162.253.55.92:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.92:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.92:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-19T01:57:33.025113Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.075988ms"} {"level":"info","ts":"2026-09-19T01:57:33.031967Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39a4f14f6507253d","cluster-id":"22d06cab78b8ace5"} {"level":"info","ts":"2026-09-19T01:57:33.032046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T01:57:33.032084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became follower at term 0"} {"level":"info","ts":"2026-09-19T01:57:33.032098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39a4f14f6507253d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T01:57:33.032106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became follower at term 1"} {"level":"info","ts":"2026-09-19T01:57:33.032189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d switched to configuration voters=(4153710079642117437)"} {"level":"warn","ts":"2026-09-19T01:57:33.040186Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T01:57:33.041829Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T01:57:33.043431Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-19T01:57:33.048859Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39a4f14f6507253d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T01:57:33.049233Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39a4f14f6507253d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T01:57:33.049058Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T01:57:33.050366Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T01:57:33.05041Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T01:57:33.051269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d switched to configuration voters=(4153710079642117437)"} {"level":"info","ts":"2026-09-19T01:57:33.051636Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"22d06cab78b8ace5","local-member-id":"39a4f14f6507253d","added-peer-id":"39a4f14f6507253d","added-peer-peer-urls":["https://162.253.55.92:2380"]} {"level":"info","ts":"2026-09-19T01:57:33.054323Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T01:57:33.054463Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.92:2380"} {"level":"info","ts":"2026-09-19T01:57:33.054521Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.92:2380"} {"level":"info","ts":"2026-09-19T01:57:33.054628Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39a4f14f6507253d","initial-advertise-peer-urls":["https://162.253.55.92:2380"],"listen-peer-urls":["https://162.253.55.92:2380"],"advertise-client-urls":["https://162.253.55.92:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.92:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T01:57:33.054691Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T01:57:33.432813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T01:57:33.43295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T01:57:33.432981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d received MsgPreVoteResp from 39a4f14f6507253d at term 1"} {"level":"info","ts":"2026-09-19T01:57:33.433006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became candidate at term 2"} {"level":"info","ts":"2026-09-19T01:57:33.433032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d received MsgVoteResp from 39a4f14f6507253d at term 2"} {"level":"info","ts":"2026-09-19T01:57:33.433053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became leader at term 2"} {"level":"info","ts":"2026-09-19T01:57:33.433126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39a4f14f6507253d elected leader 39a4f14f6507253d at term 2"} {"level":"info","ts":"2026-09-19T01:57:33.439146Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:57:33.439593Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39a4f14f6507253d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.92:2379]}","request-path":"/0/members/39a4f14f6507253d/attributes","cluster-id":"22d06cab78b8ace5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T01:57:33.43973Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:57:33.439766Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:57:33.440275Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T01:57:33.440347Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T01:57:33.444496Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"22d06cab78b8ace5","local-member-id":"39a4f14f6507253d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:57:33.444699Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:57:33.444712Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.92:2379"} {"level":"info","ts":"2026-09-19T01:57:33.444782Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:57:33.445859Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-19T01:57:52.969637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.079636ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-19T01:57:52.96975Z","caller":"traceutil/trace.go:171","msg":"trace[1986677204] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"866.479818ms","start":"2026-09-19T01:57:52.103254Z","end":"2026-09-19T01:57:52.969734Z","steps":["trace[1986677204] 'read index received' (duration: 212.119476ms)","trace[1986677204] 'applied index is now lower than readState.Index' (duration: 654.359042ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:57:52.969893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.612911ms","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-09-19T01:57:52.969965Z","caller":"traceutil/trace.go:171","msg":"trace[1447536933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:377; }","duration":"866.734803ms","start":"2026-09-19T01:57:52.103214Z","end":"2026-09-19T01:57:52.969948Z","steps":["trace[1447536933] 'agreement among raft nodes before linearized reading' (duration: 866.57836ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:57:52.969994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.734304ms","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-09-19T01:57:52.970044Z","caller":"traceutil/trace.go:171","msg":"trace[1311161858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"431.807096ms","start":"2026-09-19T01:57:52.538214Z","end":"2026-09-19T01:57:52.970021Z","steps":["trace[1311161858] 'agreement among raft nodes before linearized reading' (duration: 431.730723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:57:52.969913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.258587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T01:57:52.970052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:57:52.103195Z","time spent":"866.814557ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T01:57:52.970108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:57:52.538191Z","time spent":"431.905699ms","remote":"127.0.0.1:33764","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-09-19T01:57:52.970133Z","caller":"traceutil/trace.go:171","msg":"trace[149482561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"802.506423ms","start":"2026-09-19T01:57:52.167606Z","end":"2026-09-19T01:57:52.970112Z","steps":["trace[149482561] 'agreement among raft nodes before linearized reading' (duration: 802.273557ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:57:52.970201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:57:52.167586Z","time spent":"802.599626ms","remote":"127.0.0.1:33476","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-09-19T01:57:52.970273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.69037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-19T01:57:52.970727Z","caller":"traceutil/trace.go:171","msg":"trace[639421760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:377; }","duration":"726.209131ms","start":"2026-09-19T01:57:52.244456Z","end":"2026-09-19T01:57:52.970665Z","steps":["trace[639421760] 'agreement among raft nodes before linearized reading' (duration: 725.507405ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:57:52.970793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:57:52.244441Z","time spent":"726.335714ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-19T01:57:53.282397Z","caller":"traceutil/trace.go:171","msg":"trace[1833592433] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"307.537191ms","start":"2026-09-19T01:57:52.974833Z","end":"2026-09-19T01:57:53.28237Z","steps":["trace[1833592433] 'process raft request' (duration: 305.203676ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:57:53.283141Z","caller":"traceutil/trace.go:171","msg":"trace[223324460] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"306.812124ms","start":"2026-09-19T01:57:52.975528Z","end":"2026-09-19T01:57:53.282341Z","steps":["trace[223324460] 'process raft request' (duration: 306.740233ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:57:53.283178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:57:52.974813Z","time spent":"307.717595ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:57:53.283376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:57:52.975504Z","time spent":"307.739456ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:58:15.839181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.80798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:58:15.839503Z","caller":"traceutil/trace.go:171","msg":"trace[1212203904] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"350.018111ms","start":"2026-09-19T01:58:15.489458Z","end":"2026-09-19T01:58:15.839476Z","steps":["trace[1212203904] 'process raft request' (duration: 82.799651ms)","trace[1212203904] 'compare' (duration: 266.675837ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:58:15.839479Z","caller":"traceutil/trace.go:171","msg":"trace[2099009973] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"149.99342ms","start":"2026-09-19T01:58:15.689463Z","end":"2026-09-19T01:58:15.839456Z","steps":["trace[2099009973] 'read index received' (duration: 48.132µs)","trace[2099009973] 'applied index is now lower than readState.Index' (duration: 149.943248ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:58:15.839617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:58:15.489437Z","time spent":"350.129113ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:58:15.839753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.279036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T01:58:15.83984Z","caller":"traceutil/trace.go:171","msg":"trace[1548129499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:569; }","duration":"150.408369ms","start":"2026-09-19T01:58:15.689416Z","end":"2026-09-19T01:58:15.839824Z","steps":["trace[1548129499] 'agreement among raft nodes before linearized reading' (duration: 150.152744ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:58:19.642993Z","caller":"traceutil/trace.go:171","msg":"trace[1890406625] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"141.499673ms","start":"2026-09-19T01:58:19.501477Z","end":"2026-09-19T01:58:19.642976Z","steps":["trace[1890406625] 'process raft request' (duration: 141.408661ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:58:20.091384Z","caller":"traceutil/trace.go:171","msg":"trace[888761999] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"294.977774ms","start":"2026-09-19T01:58:19.796373Z","end":"2026-09-19T01:58:20.091351Z","steps":["trace[888761999] 'process raft request' (duration: 288.672677ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:58:20.736915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.526156ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:58:20.73706Z","caller":"traceutil/trace.go:171","msg":"trace[718539218] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:598; }","duration":"189.6891ms","start":"2026-09-19T01:58:20.547352Z","end":"2026-09-19T01:58:20.737041Z","steps":["trace[718539218] 'range keys from in-memory index tree' (duration: 189.511556ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:58:20.737556Z","caller":"traceutil/trace.go:171","msg":"trace[151508582] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"909.498279ms","start":"2026-09-19T01:58:19.828042Z","end":"2026-09-19T01:58:20.73754Z","steps":["trace[151508582] 'process raft request' (duration: 854.263879ms)","trace[151508582] 'compare' (duration: 54.483933ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:58:20.737561Z","caller":"traceutil/trace.go:171","msg":"trace[250917408] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"885.207796ms","start":"2026-09-19T01:58:19.852281Z","end":"2026-09-19T01:58:20.737489Z","steps":["trace[250917408] 'read index received' (duration: 231.931971ms)","trace[250917408] 'applied index is now lower than readState.Index' (duration: 653.273945ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:58:20.737797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:58:19.828017Z","time spent":"909.577603ms","remote":"127.0.0.1:33764","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-09-19T01:58:20.73799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.686728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-19T01:58:20.738114Z","caller":"traceutil/trace.go:171","msg":"trace[517814000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"885.858542ms","start":"2026-09-19T01:58:19.852202Z","end":"2026-09-19T01:58:20.73806Z","steps":["trace[517814000] 'agreement among raft nodes before linearized reading' (duration: 885.522824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:58:20.738224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:58:19.852185Z","time spent":"886.023975ms","remote":"127.0.0.1:33764","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":"info","ts":"2026-09-19T01:58:20.751627Z","caller":"traceutil/trace.go:171","msg":"trace[1814233607] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"591.013553ms","start":"2026-09-19T01:58:20.160582Z","end":"2026-09-19T01:58:20.751596Z","steps":["trace[1814233607] 'process raft request' (duration: 590.856808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:58:20.751787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.408867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T01:58:20.751877Z","caller":"traceutil/trace.go:171","msg":"trace[1315028097] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"874.553239ms","start":"2026-09-19T01:58:19.877303Z","end":"2026-09-19T01:58:20.751856Z","steps":["trace[1315028097] 'agreement among raft nodes before linearized reading' (duration: 874.276963ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:58:20.751929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:58:19.87729Z","time spent":"874.627962ms","remote":"127.0.0.1:33764","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-09-19T01:58:20.75211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:58:20.160518Z","time spent":"591.478733ms","remote":"127.0.0.1:33748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T01:58:20.751716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.372028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:58:20.752424Z","caller":"traceutil/trace.go:171","msg":"trace[151340522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"585.128905ms","start":"2026-09-19T01:58:20.167274Z","end":"2026-09-19T01:58:20.752402Z","steps":["trace[151340522] 'agreement among raft nodes before linearized reading' (duration: 584.344937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:58:20.751766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.755727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sm529\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-19T01:58:20.752607Z","caller":"traceutil/trace.go:171","msg":"trace[728951107] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sm529; range_end:; response_count:1; response_revision:600; }","duration":"704.677648ms","start":"2026-09-19T01:58:20.047909Z","end":"2026-09-19T01:58:20.752587Z","steps":["trace[728951107] 'agreement among raft nodes before linearized reading' (duration: 703.763107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:58:20.752687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:58:20.047886Z","time spent":"704.784911ms","remote":"127.0.0.1:33690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-sm529\" "} {"level":"warn","ts":"2026-09-19T01:58:20.752632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:58:20.167264Z","time spent":"585.302568ms","remote":"127.0.0.1:33492","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-09-19T01:58:21.280713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2683477662467550477,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-19T01:58:21.781131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2683477662467550477,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-19T01:58:21.871885Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.09914175s","expected-duration":"1s"} {"level":"info","ts":"2026-09-19T01:58:21.909765Z","caller":"traceutil/trace.go:171","msg":"trace[1262487630] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"1.129999066s","start":"2026-09-19T01:58:20.779743Z","end":"2026-09-19T01:58:21.909742Z","steps":["trace[1262487630] 'read index received' (duration: 1.09226765s)","trace[1262487630] 'applied index is now lower than readState.Index' (duration: 37.730346ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:58:21.909779Z","caller":"traceutil/trace.go:171","msg":"trace[1494663426] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.137141951s","start":"2026-09-19T01:58:20.772612Z","end":"2026-09-19T01:58:21.909754Z","steps":["trace[1494663426] 'process raft request' (duration: 1.099502738s)","trace[1494663426] 'compare' (duration: 37.317445ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:58:21.909893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.474982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T01:58:21.909901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:58:20.772589Z","time spent":"1.137243733s","remote":"127.0.0.1:33690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:58:21.909912Z","caller":"traceutil/trace.go:171","msg":"trace[1086647618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"741.540204ms","start":"2026-09-19T01:58:21.168366Z","end":"2026-09-19T01:58:21.909906Z","steps":["trace[1086647618] 'agreement among raft nodes before linearized reading' (duration: 741.487222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:58:21.909929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:58:21.168346Z","time spent":"741.578485ms","remote":"127.0.0.1:33476","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-09-19T01:58:21.90991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130155479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:58:21.909981Z","caller":"traceutil/trace.go:171","msg":"trace[585917399] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:603; }","duration":"1.130232401s","start":"2026-09-19T01:58:20.779729Z","end":"2026-09-19T01:58:21.909961Z","steps":["trace[585917399] 'agreement among raft nodes before linearized reading' (duration: 1.130113778s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:58:21.910003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.198187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-19T01:58:21.910002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.620559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-19T01:58:21.910028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:58:20.77969Z","time spent":"1.130329363s","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"info","ts":"2026-09-19T01:58:21.910038Z","caller":"traceutil/trace.go:171","msg":"trace[1555835728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"263.257717ms","start":"2026-09-19T01:58:21.64677Z","end":"2026-09-19T01:58:21.910027Z","steps":["trace[1555835728] 'agreement among raft nodes before linearized reading' (duration: 263.130374ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:58:21.910123Z","caller":"traceutil/trace.go:171","msg":"trace[1821971470] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:603; }","duration":"664.727532ms","start":"2026-09-19T01:58:21.245325Z","end":"2026-09-19T01:58:21.910053Z","steps":["trace[1821971470] 'agreement among raft nodes before linearized reading' (duration: 664.616949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:58:21.910169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:58:21.245307Z","time spent":"664.847114ms","remote":"127.0.0.1:33764","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-09-19T01:58:35.772491Z","caller":"traceutil/trace.go:171","msg":"trace[1722276866] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"150.926132ms","start":"2026-09-19T01:58:35.621542Z","end":"2026-09-19T01:58:35.772468Z","steps":["trace[1722276866] 'process raft request' (duration: 150.772489ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:58:39.018576Z","caller":"traceutil/trace.go:171","msg":"trace[327418133] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:798; }","duration":"153.593414ms","start":"2026-09-19T01:58:38.86496Z","end":"2026-09-19T01:58:39.018553Z","steps":["trace[327418133] 'read index received' (duration: 153.43146ms)","trace[327418133] 'applied index is now lower than readState.Index' (duration: 161.044µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:58:39.018652Z","caller":"traceutil/trace.go:171","msg":"trace[1599278851] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"172.810948ms","start":"2026-09-19T01:58:38.845825Z","end":"2026-09-19T01:58:39.018636Z","steps":["trace[1599278851] 'process raft request' (duration: 172.593983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:58:39.018801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.804118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T01:58:39.018848Z","caller":"traceutil/trace.go:171","msg":"trace[1554222803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:769; }","duration":"153.907761ms","start":"2026-09-19T01:58:38.864928Z","end":"2026-09-19T01:58:39.018836Z","steps":["trace[1554222803] 'agreement among raft nodes before linearized reading' (duration: 153.716877ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:58:43.367547Z","caller":"traceutil/trace.go:171","msg":"trace[929700409] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:855; }","duration":"120.993677ms","start":"2026-09-19T01:58:43.246524Z","end":"2026-09-19T01:58:43.367518Z","steps":["trace[929700409] 'read index received' (duration: 71.165001ms)","trace[929700409] 'applied index is now lower than readState.Index' (duration: 49.827486ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:58:43.367635Z","caller":"traceutil/trace.go:171","msg":"trace[1416487201] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"129.590016ms","start":"2026-09-19T01:58:43.238023Z","end":"2026-09-19T01:58:43.367613Z","steps":["trace[1416487201] 'process raft request' (duration: 129.426472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:58:43.367868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.238912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T01:58:43.368037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.990098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:58:43.368195Z","caller":"traceutil/trace.go:171","msg":"trace[1443132533] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:825; }","duration":"121.170382ms","start":"2026-09-19T01:58:43.247001Z","end":"2026-09-19T01:58:43.368172Z","steps":["trace[1443132533] 'agreement among raft nodes before linearized reading' (duration: 120.986297ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:58:43.3677Z","caller":"traceutil/trace.go:171","msg":"trace[764981014] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"129.941695ms","start":"2026-09-19T01:58:43.237723Z","end":"2026-09-19T01:58:43.367665Z","steps":["trace[764981014] 'process raft request' (duration: 79.949946ms)","trace[764981014] 'compare' (duration: 49.624231ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:58:43.368094Z","caller":"traceutil/trace.go:171","msg":"trace[1164848536] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:825; }","duration":"121.485599ms","start":"2026-09-19T01:58:43.246563Z","end":"2026-09-19T01:58:43.368049Z","steps":["trace[1164848536] 'agreement among raft nodes before linearized reading' (duration: 121.239742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:58:43.367866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.321474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:58:43.36844Z","caller":"traceutil/trace.go:171","msg":"trace[1596556413] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:825; }","duration":"121.934619ms","start":"2026-09-19T01:58:43.246488Z","end":"2026-09-19T01:58:43.368423Z","steps":["trace[1596556413] 'agreement among raft nodes before linearized reading' (duration: 121.216922ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:14.547881Z","caller":"traceutil/trace.go:171","msg":"trace[583571598] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"246.289159ms","start":"2026-09-19T01:59:14.30157Z","end":"2026-09-19T01:59:14.547859Z","steps":["trace[583571598] 'process raft request' (duration: 246.118885ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:20.193267Z","caller":"traceutil/trace.go:171","msg":"trace[1471236645] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"101.318266ms","start":"2026-09-19T01:59:20.091912Z","end":"2026-09-19T01:59:20.19323Z","steps":["trace[1471236645] 'process raft request' (duration: 101.05923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:20.700684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.723985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:59:20.700777Z","caller":"traceutil/trace.go:171","msg":"trace[893913546] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1054; }","duration":"420.885118ms","start":"2026-09-19T01:59:20.279872Z","end":"2026-09-19T01:59:20.700758Z","steps":["trace[893913546] 'count revisions from in-memory index tree' (duration: 420.595531ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:20.700814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:20.279851Z","time spent":"420.953489ms","remote":"127.0.0.1:33920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-09-19T01:59:20.701038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.652919ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:59:20.701221Z","caller":"traceutil/trace.go:171","msg":"trace[1950354345] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1054; }","duration":"153.875133ms","start":"2026-09-19T01:59:20.547324Z","end":"2026-09-19T01:59:20.701199Z","steps":["trace[1950354345] 'range keys from in-memory index tree' (duration: 153.634628ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:20.701317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.517745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:59:20.701396Z","caller":"traceutil/trace.go:171","msg":"trace[516050595] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"399.773996ms","start":"2026-09-19T01:59:20.301611Z","end":"2026-09-19T01:59:20.701385Z","steps":["trace[516050595] 'read index received' (duration: 118.116137ms)","trace[516050595] 'applied index is now lower than readState.Index' (duration: 281.656569ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:59:20.701469Z","caller":"traceutil/trace.go:171","msg":"trace[1355066319] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"409.189398ms","start":"2026-09-19T01:59:20.292268Z","end":"2026-09-19T01:59:20.701457Z","steps":["trace[1355066319] 'process raft request' (duration: 127.490388ms)","trace[1355066319] 'compare' (duration: 280.948794ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:59:20.701538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:20.292235Z","time spent":"409.252899ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:59:20.701632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.769459ms","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-09-19T01:59:20.701687Z","caller":"traceutil/trace.go:171","msg":"trace[1974815701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1055; }","duration":"130.850741ms","start":"2026-09-19T01:59:20.570819Z","end":"2026-09-19T01:59:20.70167Z","steps":["trace[1974815701] 'agreement among raft nodes before linearized reading' (duration: 130.657257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:20.701757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.125453ms","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-09-19T01:59:20.70182Z","caller":"traceutil/trace.go:171","msg":"trace[1789775807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1055; }","duration":"400.244996ms","start":"2026-09-19T01:59:20.301562Z","end":"2026-09-19T01:59:20.701807Z","steps":["trace[1789775807] 'agreement among raft nodes before linearized reading' (duration: 400.049852ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:20.701851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:20.301549Z","time spent":"400.297047ms","remote":"127.0.0.1:33764","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":"info","ts":"2026-09-19T01:59:22.663221Z","caller":"traceutil/trace.go:171","msg":"trace[1633011511] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"497.575356ms","start":"2026-09-19T01:59:22.165621Z","end":"2026-09-19T01:59:22.663196Z","steps":["trace[1633011511] 'read index received' (duration: 470.920214ms)","trace[1633011511] 'applied index is now lower than readState.Index' (duration: 26.653362ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:59:22.66335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.710979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:59:22.663385Z","caller":"traceutil/trace.go:171","msg":"trace[35724324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"497.804201ms","start":"2026-09-19T01:59:22.165572Z","end":"2026-09-19T01:59:22.663376Z","steps":["trace[35724324] 'agreement among raft nodes before linearized reading' (duration: 497.724319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:22.66341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:22.165551Z","time spent":"497.854002ms","remote":"127.0.0.1:33492","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-09-19T01:59:22.6635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.56362ms","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-09-19T01:59:22.663545Z","caller":"traceutil/trace.go:171","msg":"trace[1372624762] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1060; }","duration":"466.650021ms","start":"2026-09-19T01:59:22.196883Z","end":"2026-09-19T01:59:22.663533Z","steps":["trace[1372624762] 'agreement among raft nodes before linearized reading' (duration: 466.469387ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:22.663577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:22.196861Z","time spent":"466.708013ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-19T01:59:23.224956Z","caller":"traceutil/trace.go:171","msg":"trace[1163390050] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"556.993901ms","start":"2026-09-19T01:59:22.667905Z","end":"2026-09-19T01:59:23.224899Z","steps":["trace[1163390050] 'process raft request' (duration: 556.830828ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:23.225141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:22.667882Z","time spent":"557.149855ms","remote":"127.0.0.1:33764","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-09-19T01:59:23.253155Z","caller":"traceutil/trace.go:171","msg":"trace[2102314283] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1101; }","duration":"527.09599ms","start":"2026-09-19T01:59:22.726039Z","end":"2026-09-19T01:59:23.253135Z","steps":["trace[2102314283] 'read index received' (duration: 498.70541ms)","trace[2102314283] 'applied index is now lower than readState.Index' (duration: 28.389799ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:59:23.253276Z","caller":"traceutil/trace.go:171","msg":"trace[2020018024] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"545.31907ms","start":"2026-09-19T01:59:22.70791Z","end":"2026-09-19T01:59:23.25323Z","steps":["trace[2020018024] 'process raft request' (duration: 545.075685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:23.253397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.490411ms","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-09-19T01:59:23.253423Z","caller":"traceutil/trace.go:171","msg":"trace[2112521687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1062; }","duration":"522.545811ms","start":"2026-09-19T01:59:22.730869Z","end":"2026-09-19T01:59:23.253415Z","steps":["trace[2112521687] 'agreement among raft nodes before linearized reading' (duration: 522.48542ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:23.253458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:22.730853Z","time spent":"522.598263ms","remote":"127.0.0.1:33764","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-09-19T01:59:23.253449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:22.70788Z","time spent":"545.486744ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:59:23.253329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.279993ms","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-09-19T01:59:23.254216Z","caller":"traceutil/trace.go:171","msg":"trace[358848041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1062; }","duration":"528.216624ms","start":"2026-09-19T01:59:22.725989Z","end":"2026-09-19T01:59:23.254206Z","steps":["trace[358848041] 'agreement among raft nodes before linearized reading' (duration: 527.242463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:23.254247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:22.725971Z","time spent":"528.266984ms","remote":"127.0.0.1:33764","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-09-19T01:59:35.624961Z","caller":"traceutil/trace.go:171","msg":"trace[1459801569] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"184.658085ms","start":"2026-09-19T01:59:35.440282Z","end":"2026-09-19T01:59:35.62494Z","steps":["trace[1459801569] 'read index received' (duration: 184.524883ms)","trace[1459801569] 'applied index is now lower than readState.Index' (duration: 130.562µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:59:35.624996Z","caller":"traceutil/trace.go:171","msg":"trace[1275527169] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"188.693172ms","start":"2026-09-19T01:59:35.436274Z","end":"2026-09-19T01:59:35.624968Z","steps":["trace[1275527169] 'process raft request' (duration: 188.546999ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:35.625159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.86738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-19T01:59:35.625179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.55171ms","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-09-19T01:59:35.625187Z","caller":"traceutil/trace.go:171","msg":"trace[2133510675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1102; }","duration":"184.934831ms","start":"2026-09-19T01:59:35.440244Z","end":"2026-09-19T01:59:35.625179Z","steps":["trace[2133510675] 'agreement among raft nodes before linearized reading' (duration: 184.823279ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:35.625208Z","caller":"traceutil/trace.go:171","msg":"trace[1599769127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1102; }","duration":"182.623772ms","start":"2026-09-19T01:59:35.442576Z","end":"2026-09-19T01:59:35.625199Z","steps":["trace[1599769127] 'agreement among raft nodes before linearized reading' (duration: 182.54579ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:35.625265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.675398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T01:59:35.625294Z","caller":"traceutil/trace.go:171","msg":"trace[2003724331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"158.748999ms","start":"2026-09-19T01:59:35.466535Z","end":"2026-09-19T01:59:35.625284Z","steps":["trace[2003724331] 'agreement among raft nodes before linearized reading' (duration: 158.627426ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:37.576065Z","caller":"traceutil/trace.go:171","msg":"trace[61787514] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"120.353724ms","start":"2026-09-19T01:59:37.455696Z","end":"2026-09-19T01:59:37.576049Z","steps":["trace[61787514] 'process raft request' (duration: 120.243252ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:38.084871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.996851ms","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-09-19T01:59:38.084932Z","caller":"traceutil/trace.go:171","msg":"trace[1332691711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1110; }","duration":"408.103934ms","start":"2026-09-19T01:59:37.676814Z","end":"2026-09-19T01:59:38.084918Z","steps":["trace[1332691711] 'range keys from in-memory index tree' (duration: 407.824987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:38.084959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:37.676789Z","time spent":"408.163765ms","remote":"127.0.0.1:33764","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-09-19T01:59:38.084873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.552852ms","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-09-19T01:59:38.084996Z","caller":"traceutil/trace.go:171","msg":"trace[955805346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1110; }","duration":"407.735406ms","start":"2026-09-19T01:59:37.677253Z","end":"2026-09-19T01:59:38.084988Z","steps":["trace[955805346] 'range keys from in-memory index tree' (duration: 407.43552ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:38.085017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:37.677236Z","time spent":"407.774767ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-19T01:59:38.084999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.045192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T01:59:38.0851Z","caller":"traceutil/trace.go:171","msg":"trace[938877499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1110; }","duration":"408.153514ms","start":"2026-09-19T01:59:37.676927Z","end":"2026-09-19T01:59:38.08508Z","steps":["trace[938877499] 'range keys from in-memory index tree' (duration: 407.863248ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:38.085148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:37.676877Z","time spent":"408.259356ms","remote":"127.0.0.1:33764","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":"info","ts":"2026-09-19T01:59:38.276672Z","caller":"traceutil/trace.go:171","msg":"trace[670547257] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"186.827442ms","start":"2026-09-19T01:59:38.089809Z","end":"2026-09-19T01:59:38.276636Z","steps":["trace[670547257] 'process raft request' (duration: 186.544016ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:39.259752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.811826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:59:39.260043Z","caller":"traceutil/trace.go:171","msg":"trace[2073345675] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"1.170044196s","start":"2026-09-19T01:59:38.089985Z","end":"2026-09-19T01:59:39.260029Z","steps":["trace[2073345675] 'process raft request' (duration: 959.74254ms)","trace[2073345675] 'compare' (duration: 209.587461ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:59:39.260084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:38.089949Z","time spent":"1.170118008s","remote":"127.0.0.1:33764","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-09-19T01:59:39.260214Z","caller":"traceutil/trace.go:171","msg":"trace[1515636532] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"1.170070518s","start":"2026-09-19T01:59:38.090139Z","end":"2026-09-19T01:59:39.260209Z","steps":["trace[1515636532] 'process raft request' (duration: 1.169686919s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:39.260248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:38.09013Z","time spent":"1.170103928s","remote":"127.0.0.1:33764","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-09-19T01:59:39.52142Z","caller":"traceutil/trace.go:171","msg":"trace[1144533582] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"121.779265ms","start":"2026-09-19T01:59:39.399608Z","end":"2026-09-19T01:59:39.521388Z","steps":["trace[1144533582] 'process raft request' (duration: 121.622412ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:39.694714Z","caller":"traceutil/trace.go:171","msg":"trace[1211394388] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"108.592982ms","start":"2026-09-19T01:59:39.586079Z","end":"2026-09-19T01:59:39.694672Z","steps":["trace[1211394388] 'process raft request' (duration: 95.481801ms)","trace[1211394388] 'compare' (duration: 12.918867ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:59:40.958831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.066948ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:59:40.959069Z","caller":"traceutil/trace.go:171","msg":"trace[1160168298] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1118; }","duration":"411.326634ms","start":"2026-09-19T01:59:40.547709Z","end":"2026-09-19T01:59:40.959035Z","steps":["trace[1160168298] 'range keys from in-memory index tree' (duration: 411.020927ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:41.372563Z","caller":"traceutil/trace.go:171","msg":"trace[1828739977] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"102.218475ms","start":"2026-09-19T01:59:41.270314Z","end":"2026-09-19T01:59:41.372532Z","steps":["trace[1828739977] 'process raft request' (duration: 102.033241ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:41.424249Z","caller":"traceutil/trace.go:171","msg":"trace[1357886938] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"151.889672ms","start":"2026-09-19T01:59:41.27233Z","end":"2026-09-19T01:59:41.42422Z","steps":["trace[1357886938] 'process raft request' (duration: 151.721658ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:41.676599Z","caller":"traceutil/trace.go:171","msg":"trace[1875739514] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"147.212441ms","start":"2026-09-19T01:59:41.529356Z","end":"2026-09-19T01:59:41.676568Z","steps":["trace[1875739514] 'process raft request' (duration: 146.923085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:42.2011Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2683477662467551632,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-19T01:59:42.70207Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2683477662467551632,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-19T01:59:42.88959Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.196078916s","expected-duration":"1s"} {"level":"warn","ts":"2026-09-19T01:59:43.233693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.819563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:59:43.233961Z","caller":"traceutil/trace.go:171","msg":"trace[606326849] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"1.540599545s","start":"2026-09-19T01:59:41.693332Z","end":"2026-09-19T01:59:43.233931Z","steps":["trace[606326849] 'process raft request' (duration: 1.196443504s)","trace[606326849] 'compare' (duration: 343.65929ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:59:43.234169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:41.693303Z","time spent":"1.540774828s","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:59:43.325608Z","caller":"traceutil/trace.go:171","msg":"trace[1034318824] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1165; }","duration":"1.624995936s","start":"2026-09-19T01:59:41.700576Z","end":"2026-09-19T01:59:43.325572Z","steps":["trace[1034318824] 'read index received' (duration: 1.189221058s)","trace[1034318824] 'applied index is now lower than readState.Index' (duration: 435.772878ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:59:43.325829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.019027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:59:43.325899Z","caller":"traceutil/trace.go:171","msg":"trace[1677337541] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1122; }","duration":"452.129819ms","start":"2026-09-19T01:59:42.87375Z","end":"2026-09-19T01:59:43.32588Z","steps":["trace[1677337541] 'agreement among raft nodes before linearized reading' (duration: 452.011347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:43.325924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.599506799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-19T01:59:43.325936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:42.873732Z","time spent":"452.194671ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-09-19T01:59:43.325975Z","caller":"traceutil/trace.go:171","msg":"trace[1019957816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1122; }","duration":"1.599629142s","start":"2026-09-19T01:59:41.726329Z","end":"2026-09-19T01:59:43.325959Z","steps":["trace[1019957816] 'agreement among raft nodes before linearized reading' (duration: 1.599425978s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:43.326068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:41.726298Z","time spent":"1.599759064s","remote":"127.0.0.1:33764","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-09-19T01:59:43.32616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.907406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:59:43.326188Z","caller":"traceutil/trace.go:171","msg":"trace[2139116869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1122; }","duration":"160.962427ms","start":"2026-09-19T01:59:43.165217Z","end":"2026-09-19T01:59:43.326179Z","steps":["trace[2139116869] 'agreement among raft nodes before linearized reading' (duration: 160.920866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:43.326178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015675512s","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-09-19T01:59:43.32625Z","caller":"traceutil/trace.go:171","msg":"trace[1180729842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1122; }","duration":"1.015781125s","start":"2026-09-19T01:59:42.31045Z","end":"2026-09-19T01:59:43.326231Z","steps":["trace[1180729842] 'agreement among raft nodes before linearized reading' (duration: 1.015551959s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:43.326308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:42.310394Z","time spent":"1.015903407s","remote":"127.0.0.1:33764","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-09-19T01:59:43.326536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.625304013s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-19T01:59:43.326642Z","caller":"traceutil/trace.go:171","msg":"trace[164833362] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1122; }","duration":"1.626055749s","start":"2026-09-19T01:59:41.700544Z","end":"2026-09-19T01:59:43.3266Z","steps":["trace[164833362] 'agreement among raft nodes before linearized reading' (duration: 1.62516231s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:43.326695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:41.700524Z","time spent":"1.626156333s","remote":"127.0.0.1:33764","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-09-19T01:59:43.505194Z","caller":"traceutil/trace.go:171","msg":"trace[1780924595] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"174.15919ms","start":"2026-09-19T01:59:43.331007Z","end":"2026-09-19T01:59:43.505166Z","steps":["trace[1780924595] 'process raft request' (duration: 173.991806ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:43.773043Z","caller":"traceutil/trace.go:171","msg":"trace[936606934] linearizableReadLoop","detail":"{readStateIndex:1170; appliedIndex:1168; }","duration":"396.228779ms","start":"2026-09-19T01:59:43.376764Z","end":"2026-09-19T01:59:43.772993Z","steps":["trace[936606934] 'read index received' (duration: 128.793266ms)","trace[936606934] 'applied index is now lower than readState.Index' (duration: 267.434283ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:59:43.77354Z","caller":"traceutil/trace.go:171","msg":"trace[649851967] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"441.205685ms","start":"2026-09-19T01:59:43.332235Z","end":"2026-09-19T01:59:43.773441Z","steps":["trace[649851967] 'process raft request' (duration: 440.540661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:43.773769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:43.332216Z","time spent":"441.46215ms","remote":"127.0.0.1:33764","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-09-19T01:59:43.774046Z","caller":"traceutil/trace.go:171","msg":"trace[36367188] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"441.45385ms","start":"2026-09-19T01:59:43.332554Z","end":"2026-09-19T01:59:43.774008Z","steps":["trace[36367188] 'process raft request' (duration: 440.385947ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:43.774125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:43.332545Z","time spent":"441.540111ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:59:43.7744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.630179ms","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-09-19T01:59:43.77445Z","caller":"traceutil/trace.go:171","msg":"trace[234468091] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1125; }","duration":"397.700031ms","start":"2026-09-19T01:59:43.376736Z","end":"2026-09-19T01:59:43.774436Z","steps":["trace[234468091] 'agreement among raft nodes before linearized reading' (duration: 397.544457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:43.774653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:43.376726Z","time spent":"397.909925ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-19T01:59:43.775689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.842028ms","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-09-19T01:59:43.775736Z","caller":"traceutil/trace.go:171","msg":"trace[822868525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1126; }","duration":"346.93235ms","start":"2026-09-19T01:59:43.42879Z","end":"2026-09-19T01:59:43.775722Z","steps":["trace[822868525] 'agreement among raft nodes before linearized reading' (duration: 346.703544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:43.77579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:43.428772Z","time spent":"347.009251ms","remote":"127.0.0.1:33764","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-09-19T01:59:54.05572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2683477662467551800,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T01:59:54.12964Z","caller":"traceutil/trace.go:171","msg":"trace[1712112408] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"927.58087ms","start":"2026-09-19T01:59:53.202031Z","end":"2026-09-19T01:59:54.129612Z","steps":["trace[1712112408] 'process raft request' (duration: 927.245812ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:54.1298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:53.201986Z","time spent":"927.731964ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:59:54.158572Z","caller":"traceutil/trace.go:171","msg":"trace[1424867952] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1262; }","duration":"603.618022ms","start":"2026-09-19T01:59:53.554931Z","end":"2026-09-19T01:59:54.158549Z","steps":["trace[1424867952] 'read index received' (duration: 573.246001ms)","trace[1424867952] 'applied index is now lower than readState.Index' (duration: 30.370951ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:59:54.158624Z","caller":"traceutil/trace.go:171","msg":"trace[602747726] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"661.303751ms","start":"2026-09-19T01:59:53.4973Z","end":"2026-09-19T01:59:54.158604Z","steps":["trace[602747726] 'process raft request' (duration: 661.085997ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:54.158658Z","caller":"traceutil/trace.go:171","msg":"trace[2000680601] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"354.597095ms","start":"2026-09-19T01:59:53.804033Z","end":"2026-09-19T01:59:54.15863Z","steps":["trace[2000680601] 'process raft request' (duration: 354.463202ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:54.158757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:53.803983Z","time spent":"354.718897ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:59:54.158757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:53.497254Z","time spent":"661.434544ms","remote":"127.0.0.1:33764","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-09-19T01:59:54.158867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.930438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-19T01:59:54.158925Z","caller":"traceutil/trace.go:171","msg":"trace[1984790145] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1217; }","duration":"604.016671ms","start":"2026-09-19T01:59:53.554894Z","end":"2026-09-19T01:59:54.15891Z","steps":["trace[1984790145] 'agreement among raft nodes before linearized reading' (duration: 603.793816ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:54.159103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.836802ms","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:532"} {"level":"info","ts":"2026-09-19T01:59:54.159155Z","caller":"traceutil/trace.go:171","msg":"trace[274994549] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1217; }","duration":"559.923544ms","start":"2026-09-19T01:59:53.599215Z","end":"2026-09-19T01:59:54.159139Z","steps":["trace[274994549] 'agreement among raft nodes before linearized reading' (duration: 559.814912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:54.159213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:53.599187Z","time spent":"560.015807ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-09-19T01:59:54.159283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.885266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T01:59:54.159334Z","caller":"traceutil/trace.go:171","msg":"trace[1958862684] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1217; }","duration":"330.957998ms","start":"2026-09-19T01:59:53.828362Z","end":"2026-09-19T01:59:54.15932Z","steps":["trace[1958862684] 'agreement among raft nodes before linearized reading' (duration: 330.819755ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:54.159318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.434058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T01:59:54.159376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:53.828351Z","time spent":"331.015519ms","remote":"127.0.0.1:33764","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-09-19T01:59:54.15939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.515398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-19T01:59:54.159419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.764973ms","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-09-19T01:59:54.159428Z","caller":"traceutil/trace.go:171","msg":"trace[96951832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1217; }","duration":"337.586089ms","start":"2026-09-19T01:59:53.82183Z","end":"2026-09-19T01:59:54.159416Z","steps":["trace[96951832] 'agreement among raft nodes before linearized reading' (duration: 337.480317ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:54.159417Z","caller":"traceutil/trace.go:171","msg":"trace[1229998241] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1217; }","duration":"405.605521ms","start":"2026-09-19T01:59:53.753795Z","end":"2026-09-19T01:59:54.1594Z","steps":["trace[1229998241] 'agreement among raft nodes before linearized reading' (duration: 405.429697ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:54.159456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.789926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-19T01:59:54.15947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:53.821811Z","time spent":"337.649321ms","remote":"127.0.0.1:33764","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":"info","ts":"2026-09-19T01:59:54.159469Z","caller":"traceutil/trace.go:171","msg":"trace[65694940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1217; }","duration":"337.842375ms","start":"2026-09-19T01:59:53.821612Z","end":"2026-09-19T01:59:54.159454Z","steps":["trace[65694940] 'agreement among raft nodes before linearized reading' (duration: 337.559929ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:54.159513Z","caller":"traceutil/trace.go:171","msg":"trace[1002882737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1217; }","duration":"338.879758ms","start":"2026-09-19T01:59:53.820614Z","end":"2026-09-19T01:59:54.159494Z","steps":["trace[1002882737] 'agreement among raft nodes before linearized reading' (duration: 338.622782ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:54.159521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:53.821598Z","time spent":"337.911806ms","remote":"127.0.0.1:33764","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-09-19T01:59:54.159506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:53.753765Z","time spent":"405.724874ms","remote":"127.0.0.1:33718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-09-19T01:59:54.15955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:53.82059Z","time spent":"338.950269ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-19T01:59:54.158972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:59:53.554879Z","time spent":"604.081123ms","remote":"127.0.0.1:33764","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-09-19T02:02:06.063356Z","caller":"traceutil/trace.go:171","msg":"trace[40106437] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"130.938717ms","start":"2026-09-19T02:02:05.932392Z","end":"2026-09-19T02:02:06.06333Z","steps":["trace[40106437] 'process raft request' (duration: 130.777232ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:06.977345Z","caller":"traceutil/trace.go:171","msg":"trace[951919746] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"277.28903ms","start":"2026-09-19T02:02:06.700031Z","end":"2026-09-19T02:02:06.97732Z","steps":["trace[951919746] 'process raft request' (duration: 277.088965ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:06.987904Z","caller":"traceutil/trace.go:171","msg":"trace[1297145909] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1897; }","duration":"187.32957ms","start":"2026-09-19T02:02:06.800543Z","end":"2026-09-19T02:02:06.987872Z","steps":["trace[1297145909] 'read index received' (duration: 176.571161ms)","trace[1297145909] 'applied index is now lower than readState.Index' (duration: 10.756719ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:02:06.988035Z","caller":"traceutil/trace.go:171","msg":"trace[1295660277] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"187.507885ms","start":"2026-09-19T02:02:06.800438Z","end":"2026-09-19T02:02:06.987946Z","steps":["trace[1295660277] 'process raft request' (duration: 181.109396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:06.988318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.697478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-09-19T02:02:06.989737Z","caller":"traceutil/trace.go:171","msg":"trace[552878724] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1825; }","duration":"189.215143ms","start":"2026-09-19T02:02:06.800495Z","end":"2026-09-19T02:02:06.98971Z","steps":["trace[552878724] 'agreement among raft nodes before linearized reading' (duration: 187.575386ms)"],"step_count":1}