{"level":"warn","ts":"2026-03-19T09:00:31.005715Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T09:00:31.006151Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.207: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.207:2380","--initial-cluster=instance=https://162.253.55.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.207: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-03-19T09:00:31.006457Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T09:00:31.006475Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-03-19T09:00:31.006673Z","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-03-19T09:00:31.009275Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"]} {"level":"info","ts":"2026-03-19T09:00:31.009359Z","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.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.207: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-03-19T09:00:31.014855Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.429129ms"} {"level":"info","ts":"2026-03-19T09:00:31.027822Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6f1fcbe50562a63a","cluster-id":"a9b9f04f4e8a9fba"} {"level":"info","ts":"2026-03-19T09:00:31.027914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T09:00:31.027941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 0"} {"level":"info","ts":"2026-03-19T09:00:31.027958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6f1fcbe50562a63a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T09:00:31.027974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 1"} {"level":"info","ts":"2026-03-19T09:00:31.028013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"warn","ts":"2026-03-19T09:00:31.040291Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T09:00:31.041863Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T09:00:31.042959Z","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-03-19T09:00:31.046066Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6f1fcbe50562a63a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T09:00:31.046246Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6f1fcbe50562a63a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T09:00:31.046373Z","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-03-19T09:00:31.046431Z","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-03-19T09:00:31.046444Z","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-03-19T09:00:31.048401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"info","ts":"2026-03-19T09:00:31.048576Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","added-peer-id":"6f1fcbe50562a63a","added-peer-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-03-19T09:00:31.05111Z","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-03-19T09:00:31.051233Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-03-19T09:00:31.051313Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-03-19T09:00:31.051329Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6f1fcbe50562a63a","initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T09:00:31.051366Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T09:00:31.92854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T09:00:31.928627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T09:00:31.928653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgPreVoteResp from 6f1fcbe50562a63a at term 1"} {"level":"info","ts":"2026-03-19T09:00:31.928671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became candidate at term 2"} {"level":"info","ts":"2026-03-19T09:00:31.928682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgVoteResp from 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-03-19T09:00:31.928699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became leader at term 2"} {"level":"info","ts":"2026-03-19T09:00:31.928717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6f1fcbe50562a63a elected leader 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-03-19T09:00:31.929765Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6f1fcbe50562a63a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.207:2379]}","request-path":"/0/members/6f1fcbe50562a63a/attributes","cluster-id":"a9b9f04f4e8a9fba","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T09:00:31.929934Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T09:00:31.929996Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T09:00:31.930089Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T09:00:31.93031Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T09:00:31.930333Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T09:00:31.931017Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T09:00:31.931224Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T09:00:31.931326Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T09:00:31.935239Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.207:2379"} {"level":"info","ts":"2026-03-19T09:00:31.936365Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-19T09:00:41.488971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.722462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:00:41.48911Z","caller":"traceutil/trace.go:171","msg":"trace[876900060] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:380; }","duration":"275.073983ms","start":"2026-03-19T09:00:41.214011Z","end":"2026-03-19T09:00:41.489085Z","steps":["trace[876900060] 'read index received' (duration: 4.861401ms)","trace[876900060] 'applied index is now lower than readState.Index' (duration: 270.210192ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:00:41.489137Z","caller":"traceutil/trace.go:171","msg":"trace[266890681] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"291.052414ms","start":"2026-03-19T09:00:41.19805Z","end":"2026-03-19T09:00:41.489102Z","steps":["trace[266890681] 'process raft request' (duration: 139.588102ms)","trace[266890681] 'compare' (duration: 150.591359ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:00:41.489328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.310329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-03-19T09:00:41.489385Z","caller":"traceutil/trace.go:171","msg":"trace[2126602675] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:371; }","duration":"275.401063ms","start":"2026-03-19T09:00:41.213969Z","end":"2026-03-19T09:00:41.48937Z","steps":["trace[2126602675] 'agreement among raft nodes before linearized reading' (duration: 275.248438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:41.489414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.362561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-19T09:00:41.489462Z","caller":"traceutil/trace.go:171","msg":"trace[1165037074] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:371; }","duration":"275.459683ms","start":"2026-03-19T09:00:41.21399Z","end":"2026-03-19T09:00:41.489449Z","steps":["trace[1165037074] 'agreement among raft nodes before linearized reading' (duration: 275.36323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:41.489573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.275796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-19T09:00:41.489607Z","caller":"traceutil/trace.go:171","msg":"trace[1919432519] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:371; }","duration":"175.347338ms","start":"2026-03-19T09:00:41.314251Z","end":"2026-03-19T09:00:41.489599Z","steps":["trace[1919432519] 'agreement among raft nodes before linearized reading' (duration: 175.293916ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:41.48958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.298973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-19T09:00:41.489692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.108091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-03-19T09:00:41.489739Z","caller":"traceutil/trace.go:171","msg":"trace[1498610742] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:371; }","duration":"226.193172ms","start":"2026-03-19T09:00:41.263537Z","end":"2026-03-19T09:00:41.489731Z","steps":["trace[1498610742] 'agreement among raft nodes before linearized reading' (duration: 226.132119ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:00:41.489786Z","caller":"traceutil/trace.go:171","msg":"trace[1450984164] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:371; }","duration":"126.417306ms","start":"2026-03-19T09:00:41.363225Z","end":"2026-03-19T09:00:41.489642Z","steps":["trace[1450984164] 'agreement among raft nodes before linearized reading' (duration: 126.154519ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:41.900894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.863096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:00:41.90123Z","caller":"traceutil/trace.go:171","msg":"trace[1008883638] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"401.517361ms","start":"2026-03-19T09:00:41.499694Z","end":"2026-03-19T09:00:41.901211Z","steps":["trace[1008883638] 'process raft request' (duration: 401.439659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:41.901364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:41.499687Z","time spent":"401.623624ms","remote":"127.0.0.1:52656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T09:00:41.901164Z","caller":"traceutil/trace.go:171","msg":"trace[845203993] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"403.73691ms","start":"2026-03-19T09:00:41.497353Z","end":"2026-03-19T09:00:41.901089Z","steps":["trace[845203993] 'process raft request' (duration: 289.615276ms)","trace[845203993] 'compare' (duration: 113.674661ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:00:41.901724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:41.497344Z","time spent":"404.247513ms","remote":"127.0.0.1:52746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T09:00:41.901823Z","caller":"traceutil/trace.go:171","msg":"trace[1532249184] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"404.368788ms","start":"2026-03-19T09:00:41.497434Z","end":"2026-03-19T09:00:41.901802Z","steps":["trace[1532249184] 'process raft request' (duration: 403.604047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:41.902011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:41.497416Z","time spent":"404.49005ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T09:00:41.902186Z","caller":"traceutil/trace.go:171","msg":"trace[395884317] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"404.628125ms","start":"2026-03-19T09:00:41.49742Z","end":"2026-03-19T09:00:41.902048Z","steps":["trace[395884317] 'process raft request' (duration: 403.574376ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:41.902684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:41.497417Z","time spent":"405.146838ms","remote":"127.0.0.1:52528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T09:00:41.943441Z","caller":"traceutil/trace.go:171","msg":"trace[466729284] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"360.840771ms","start":"2026-03-19T09:00:41.582574Z","end":"2026-03-19T09:00:41.943415Z","steps":["trace[466729284] 'process raft request' (duration: 360.710466ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:41.943604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:41.582555Z","time spent":"360.967765ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:00:42.086573Z","caller":"traceutil/trace.go:171","msg":"trace[968381695] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"178.183406ms","start":"2026-03-19T09:00:41.908367Z","end":"2026-03-19T09:00:42.08655Z","steps":["trace[968381695] 'process raft request' (duration: 178.115334ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:00:42.086633Z","caller":"traceutil/trace.go:171","msg":"trace[746448591] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"178.299718ms","start":"2026-03-19T09:00:41.908299Z","end":"2026-03-19T09:00:42.086598Z","steps":["trace[746448591] 'process raft request' (duration: 178.140934ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:00:42.086701Z","caller":"traceutil/trace.go:171","msg":"trace[1376601346] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"173.067906ms","start":"2026-03-19T09:00:41.913622Z","end":"2026-03-19T09:00:42.08669Z","steps":["trace[1376601346] 'process raft request' (duration: 172.889201ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:00:42.086587Z","caller":"traceutil/trace.go:171","msg":"trace[1418598276] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"178.525595ms","start":"2026-03-19T09:00:41.908035Z","end":"2026-03-19T09:00:42.086561Z","steps":["trace[1418598276] 'process raft request' (duration: 176.013106ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:00:46.044075Z","caller":"traceutil/trace.go:171","msg":"trace[1101788861] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"197.24868ms","start":"2026-03-19T09:00:45.846793Z","end":"2026-03-19T09:00:46.044042Z","steps":["trace[1101788861] 'read index received' (duration: 196.976183ms)","trace[1101788861] 'applied index is now lower than readState.Index' (duration: 271.287µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:00:46.044149Z","caller":"traceutil/trace.go:171","msg":"trace[828353073] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"453.468134ms","start":"2026-03-19T09:00:45.590633Z","end":"2026-03-19T09:00:46.044101Z","steps":["trace[828353073] 'process raft request' (duration: 453.208417ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:46.044289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.444976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:00:46.044338Z","caller":"traceutil/trace.go:171","msg":"trace[1470444882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"197.572279ms","start":"2026-03-19T09:00:45.846749Z","end":"2026-03-19T09:00:46.044321Z","steps":["trace[1470444882] 'agreement among raft nodes before linearized reading' (duration: 197.445905ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:46.044355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:45.590614Z","time spent":"453.651588ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:00:47.323255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.371796ms","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-03-19T09:00:47.323346Z","caller":"traceutil/trace.go:171","msg":"trace[459547643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:409; }","duration":"287.52379ms","start":"2026-03-19T09:00:47.035806Z","end":"2026-03-19T09:00:47.32333Z","steps":["trace[459547643] 'range keys from in-memory index tree' (duration: 287.252333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:47.323401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.077354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-19T09:00:47.323448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.948861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:00:47.323465Z","caller":"traceutil/trace.go:171","msg":"trace[1098733626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:409; }","duration":"530.176257ms","start":"2026-03-19T09:00:46.793268Z","end":"2026-03-19T09:00:47.323445Z","steps":["trace[1098733626] 'range keys from in-memory index tree' (duration: 529.997662ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:00:47.323491Z","caller":"traceutil/trace.go:171","msg":"trace[939435380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"476.018653ms","start":"2026-03-19T09:00:46.84746Z","end":"2026-03-19T09:00:47.323479Z","steps":["trace[939435380] 'range keys from in-memory index tree' (duration: 475.869589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:47.32353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:46.847447Z","time spent":"476.073515ms","remote":"127.0.0.1:52422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T09:00:47.323539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208612301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-19T09:00:47.323497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:46.793249Z","time spent":"530.241848ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T09:00:47.323641Z","caller":"traceutil/trace.go:171","msg":"trace[658689743] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:409; }","duration":"1.208760235s","start":"2026-03-19T09:00:46.11486Z","end":"2026-03-19T09:00:47.323621Z","steps":["trace[658689743] 'range keys from in-memory index tree' (duration: 1.208431265s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:47.323684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:46.114839Z","time spent":"1.208835737s","remote":"127.0.0.1:52734","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-03-19T09:00:51.470814Z","caller":"traceutil/trace.go:171","msg":"trace[793108757] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:462; }","duration":"106.966761ms","start":"2026-03-19T09:00:51.363824Z","end":"2026-03-19T09:00:51.470791Z","steps":["trace[793108757] 'read index received' (duration: 13.150856ms)","trace[793108757] 'applied index is now lower than readState.Index' (duration: 93.814945ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:00:51.470917Z","caller":"traceutil/trace.go:171","msg":"trace[1471530495] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"109.721765ms","start":"2026-03-19T09:00:51.361164Z","end":"2026-03-19T09:00:51.470886Z","steps":["trace[1471530495] 'process raft request' (duration: 62.934122ms)","trace[1471530495] 'compare' (duration: 46.598718ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:00:51.470963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.122574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T09:00:51.471014Z","caller":"traceutil/trace.go:171","msg":"trace[1968849225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:450; }","duration":"107.208746ms","start":"2026-03-19T09:00:51.363791Z","end":"2026-03-19T09:00:51.470999Z","steps":["trace[1968849225] 'agreement among raft nodes before linearized reading' (duration: 107.120164ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:00:51.658912Z","caller":"traceutil/trace.go:171","msg":"trace[248970141] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"183.365945ms","start":"2026-03-19T09:00:51.475523Z","end":"2026-03-19T09:00:51.658889Z","steps":["trace[248970141] 'process raft request' (duration: 183.21348ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:54.201121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.470081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T09:00:54.201186Z","caller":"traceutil/trace.go:171","msg":"trace[774961360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:454; }","duration":"538.582494ms","start":"2026-03-19T09:00:53.662589Z","end":"2026-03-19T09:00:54.201172Z","steps":["trace[774961360] 'range keys from in-memory index tree' (duration: 538.360578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:54.20121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:53.662569Z","time spent":"538.636245ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T09:00:54.201294Z","caller":"traceutil/trace.go:171","msg":"trace[1254654569] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:468; }","duration":"353.940385ms","start":"2026-03-19T09:00:53.8473Z","end":"2026-03-19T09:00:54.20124Z","steps":["trace[1254654569] 'read index received' (duration: 324.025137ms)","trace[1254654569] 'applied index is now lower than readState.Index' (duration: 29.914148ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:00:54.2014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.110899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:00:54.202238Z","caller":"traceutil/trace.go:171","msg":"trace[707597570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:455; }","duration":"354.966192ms","start":"2026-03-19T09:00:53.847247Z","end":"2026-03-19T09:00:54.202213Z","steps":["trace[707597570] 'agreement among raft nodes before linearized reading' (duration: 354.13371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:54.202332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:53.847225Z","time spent":"355.072645ms","remote":"127.0.0.1:52422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T09:00:54.201353Z","caller":"traceutil/trace.go:171","msg":"trace[431786660] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"416.197026ms","start":"2026-03-19T09:00:53.785117Z","end":"2026-03-19T09:00:54.201314Z","steps":["trace[431786660] 'process raft request' (duration: 386.289329ms)","trace[431786660] 'compare' (duration: 29.62792ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:00:54.202672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.88029ms","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-03-19T09:00:54.202719Z","caller":"traceutil/trace.go:171","msg":"trace[1352387175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:455; }","duration":"122.948341ms","start":"2026-03-19T09:00:54.079755Z","end":"2026-03-19T09:00:54.202704Z","steps":["trace[1352387175] 'agreement among raft nodes before linearized reading' (duration: 122.852298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:54.202714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:00:53.785084Z","time spent":"417.544554ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:00:54.470662Z","caller":"traceutil/trace.go:171","msg":"trace[355638511] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"264.858837ms","start":"2026-03-19T09:00:54.205783Z","end":"2026-03-19T09:00:54.470642Z","steps":["trace[355638511] 'process raft request' (duration: 202.986165ms)","trace[355638511] 'compare' (duration: 61.79153ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:00:54.473767Z","caller":"traceutil/trace.go:171","msg":"trace[1455295201] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"267.743815ms","start":"2026-03-19T09:00:54.206001Z","end":"2026-03-19T09:00:54.473745Z","steps":["trace[1455295201] 'process raft request' (duration: 267.645202ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:02.78857Z","caller":"traceutil/trace.go:171","msg":"trace[1627352328] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"279.589545ms","start":"2026-03-19T09:01:02.508952Z","end":"2026-03-19T09:01:02.788542Z","steps":["trace[1627352328] 'process raft request' (duration: 279.430291ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:03.210926Z","caller":"traceutil/trace.go:171","msg":"trace[547333986] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"364.683155ms","start":"2026-03-19T09:01:02.846218Z","end":"2026-03-19T09:01:03.210901Z","steps":["trace[547333986] 'read index received' (duration: 359.372432ms)","trace[547333986] 'applied index is now lower than readState.Index' (duration: 5.309773ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:03.210991Z","caller":"traceutil/trace.go:171","msg":"trace[1156603749] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"701.730163ms","start":"2026-03-19T09:01:02.509224Z","end":"2026-03-19T09:01:03.210954Z","steps":["trace[1156603749] 'process raft request' (duration: 696.44102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:03.211064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.820178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:01:03.211155Z","caller":"traceutil/trace.go:171","msg":"trace[560138552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"364.954922ms","start":"2026-03-19T09:01:02.846179Z","end":"2026-03-19T09:01:03.211134Z","steps":["trace[560138552] 'agreement among raft nodes before linearized reading' (duration: 364.813939ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:03.21118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:02.509215Z","time spent":"701.886118ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:03.211204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:02.846162Z","time spent":"365.032485ms","remote":"127.0.0.1:52422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T09:01:09.764315Z","caller":"traceutil/trace.go:171","msg":"trace[1057833856] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"172.82828ms","start":"2026-03-19T09:01:09.591453Z","end":"2026-03-19T09:01:09.764282Z","steps":["trace[1057833856] 'read index received' (duration: 172.576094ms)","trace[1057833856] 'applied index is now lower than readState.Index' (duration: 251.066µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:09.764354Z","caller":"traceutil/trace.go:171","msg":"trace[2041203956] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"529.170281ms","start":"2026-03-19T09:01:09.235141Z","end":"2026-03-19T09:01:09.764311Z","steps":["trace[2041203956] 'process raft request' (duration: 528.974725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:09.764497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:09.235125Z","time spent":"529.303934ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:09.764557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.103869ms","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-03-19T09:01:09.764623Z","caller":"traceutil/trace.go:171","msg":"trace[661163886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"173.20759ms","start":"2026-03-19T09:01:09.591401Z","end":"2026-03-19T09:01:09.764609Z","steps":["trace[661163886] 'agreement among raft nodes before linearized reading' (duration: 173.013825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:09.764667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.208148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T09:01:09.764744Z","caller":"traceutil/trace.go:171","msg":"trace[1035273053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"117.34093ms","start":"2026-03-19T09:01:09.647382Z","end":"2026-03-19T09:01:09.764723Z","steps":["trace[1035273053] 'agreement among raft nodes before linearized reading' (duration: 117.097254ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:10.202443Z","caller":"traceutil/trace.go:171","msg":"trace[1042412159] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"433.809674ms","start":"2026-03-19T09:01:09.768608Z","end":"2026-03-19T09:01:10.202418Z","steps":["trace[1042412159] 'process raft request' (duration: 433.662049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:10.202698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:09.768586Z","time spent":"433.937797ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:01:10.22289Z","caller":"traceutil/trace.go:171","msg":"trace[2099040240] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"454.156602ms","start":"2026-03-19T09:01:09.768704Z","end":"2026-03-19T09:01:10.22286Z","steps":["trace[2099040240] 'process raft request' (duration: 453.895696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:10.223136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:09.76847Z","time spent":"454.510813ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:10.782364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.591803ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-19T09:01:10.782485Z","caller":"traceutil/trace.go:171","msg":"trace[584496236] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:545; }","duration":"933.824825ms","start":"2026-03-19T09:01:09.848643Z","end":"2026-03-19T09:01:10.782468Z","steps":["trace[584496236] 'read index received' (duration: 353.587204ms)","trace[584496236] 'applied index is now lower than readState.Index' (duration: 580.236261ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:01:10.782601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.957189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:01:10.782627Z","caller":"traceutil/trace.go:171","msg":"trace[638366001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"934.02515ms","start":"2026-03-19T09:01:09.848593Z","end":"2026-03-19T09:01:10.782618Z","steps":["trace[638366001] 'agreement among raft nodes before linearized reading' (duration: 933.967459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:10.782659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:09.848573Z","time spent":"934.078212ms","remote":"127.0.0.1:52428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T09:01:10.782716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.899996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4jj6c\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-19T09:01:10.782786Z","caller":"traceutil/trace.go:171","msg":"trace[2055654662] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4jj6c; range_end:; response_count:1; response_revision:530; }","duration":"770.006887ms","start":"2026-03-19T09:01:10.012763Z","end":"2026-03-19T09:01:10.78277Z","steps":["trace[2055654662] 'agreement among raft nodes before linearized reading' (duration: 769.833823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:10.782836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:10.012745Z","time spent":"770.07502ms","remote":"127.0.0.1:52656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-4jj6c\" "} {"level":"info","ts":"2026-03-19T09:01:11.057891Z","caller":"traceutil/trace.go:171","msg":"trace[1809797848] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"226.613994ms","start":"2026-03-19T09:01:10.831257Z","end":"2026-03-19T09:01:11.057871Z","steps":["trace[1809797848] 'read index received' (duration: 226.396428ms)","trace[1809797848] 'applied index is now lower than readState.Index' (duration: 216.816µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:11.058004Z","caller":"traceutil/trace.go:171","msg":"trace[934281683] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"260.399767ms","start":"2026-03-19T09:01:10.797586Z","end":"2026-03-19T09:01:11.057986Z","steps":["trace[934281683] 'process raft request' (duration: 260.15315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:11.058091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.821819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T09:01:11.058118Z","caller":"traceutil/trace.go:171","msg":"trace[1312462593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"226.890992ms","start":"2026-03-19T09:01:10.831219Z","end":"2026-03-19T09:01:11.05811Z","steps":["trace[1312462593] 'agreement among raft nodes before linearized reading' (duration: 226.771749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:11.05812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.545901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:01:11.058197Z","caller":"traceutil/trace.go:171","msg":"trace[317226721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"206.663925ms","start":"2026-03-19T09:01:10.851515Z","end":"2026-03-19T09:01:11.058179Z","steps":["trace[317226721] 'agreement among raft nodes before linearized reading' (duration: 206.524581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:13.215277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.705688734s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:01:13.215386Z","caller":"traceutil/trace.go:171","msg":"trace[439351767] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"1.4461829s","start":"2026-03-19T09:01:11.769186Z","end":"2026-03-19T09:01:13.215369Z","steps":["trace[439351767] 'read index received' (duration: 47.291µs)","trace[439351767] 'applied index is now lower than readState.Index' (duration: 1.446134219s)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:13.21564Z","caller":"traceutil/trace.go:171","msg":"trace[2030084520] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"2.153817724s","start":"2026-03-19T09:01:11.061809Z","end":"2026-03-19T09:01:13.215627Z","steps":["trace[2030084520] 'process raft request' (duration: 447.683158ms)","trace[2030084520] 'compare' (duration: 1.705473338s)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:01:13.215704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:11.06179Z","time spent":"2.153879885s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:13.215909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.888641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:01:13.216002Z","caller":"traceutil/trace.go:171","msg":"trace[1356829235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"368.017575ms","start":"2026-03-19T09:01:12.847951Z","end":"2026-03-19T09:01:13.215968Z","steps":["trace[1356829235] 'agreement among raft nodes before linearized reading' (duration: 367.866261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:13.215987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00949817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-19T09:01:13.216019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.735919ms","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-03-19T09:01:13.21602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446819857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-19T09:01:13.216055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:12.847931Z","time spent":"368.110607ms","remote":"127.0.0.1:52422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T09:01:13.216075Z","caller":"traceutil/trace.go:171","msg":"trace[109939905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"1.009637004s","start":"2026-03-19T09:01:12.206418Z","end":"2026-03-19T09:01:13.216055Z","steps":["trace[109939905] 'agreement among raft nodes before linearized reading' (duration: 1.009387776s)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:13.216077Z","caller":"traceutil/trace.go:171","msg":"trace[320748006] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"988.814422ms","start":"2026-03-19T09:01:12.227246Z","end":"2026-03-19T09:01:13.21606Z","steps":["trace[320748006] 'agreement among raft nodes before linearized reading' (duration: 988.638987ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:13.216104Z","caller":"traceutil/trace.go:171","msg":"trace[927793558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"1.446955272s","start":"2026-03-19T09:01:11.769132Z","end":"2026-03-19T09:01:13.216088Z","steps":["trace[927793558] 'agreement among raft nodes before linearized reading' (duration: 1.446722516s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:13.216119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:12.227232Z","time spent":"988.879443ms","remote":"127.0.0.1:52734","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-03-19T09:01:13.216122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:12.206398Z","time spent":"1.009715205s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T09:01:13.216148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:11.76911Z","time spent":"1.447028774s","remote":"127.0.0.1:52734","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-03-19T09:01:13.826384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.221174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:01:13.82649Z","caller":"traceutil/trace.go:171","msg":"trace[870756775] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"606.718116ms","start":"2026-03-19T09:01:13.219753Z","end":"2026-03-19T09:01:13.826472Z","steps":["trace[870756775] 'process raft request' (duration: 276.333537ms)","trace[870756775] 'compare' (duration: 329.980897ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:01:13.826562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:13.219737Z","time spent":"606.797258ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:01:13.827112Z","caller":"traceutil/trace.go:171","msg":"trace[1668587791] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"606.493078ms","start":"2026-03-19T09:01:13.220596Z","end":"2026-03-19T09:01:13.82709Z","steps":["trace[1668587791] 'process raft request' (duration: 606.356505ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:13.827234Z","caller":"traceutil/trace.go:171","msg":"trace[903476845] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"606.442498ms","start":"2026-03-19T09:01:13.220772Z","end":"2026-03-19T09:01:13.827215Z","steps":["trace[903476845] 'process raft request' (duration: 606.277383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:13.827262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:13.220576Z","time spent":"606.612842ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:13.82734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:13.220747Z","time spent":"606.545541ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:15.568544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.152265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T09:01:15.568646Z","caller":"traceutil/trace.go:171","msg":"trace[2100223565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:541; }","duration":"349.305169ms","start":"2026-03-19T09:01:15.219317Z","end":"2026-03-19T09:01:15.568622Z","steps":["trace[2100223565] 'range keys from in-memory index tree' (duration: 348.933139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:15.568702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:15.219293Z","time spent":"349.394581ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T09:01:17.285959Z","caller":"traceutil/trace.go:171","msg":"trace[1448348955] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"115.8278ms","start":"2026-03-19T09:01:17.170107Z","end":"2026-03-19T09:01:17.285935Z","steps":["trace[1448348955] 'process raft request' (duration: 107.449314ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:26.408083Z","caller":"traceutil/trace.go:171","msg":"trace[308775762] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"493.619259ms","start":"2026-03-19T09:01:25.914437Z","end":"2026-03-19T09:01:26.408056Z","steps":["trace[308775762] 'read index received' (duration: 493.245039ms)","trace[308775762] 'applied index is now lower than readState.Index' (duration: 373.25µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:26.408494Z","caller":"traceutil/trace.go:171","msg":"trace[966144306] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"688.208517ms","start":"2026-03-19T09:01:25.720216Z","end":"2026-03-19T09:01:26.408424Z","steps":["trace[966144306] 'process raft request' (duration: 687.671524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:26.408564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.935159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-19T09:01:26.408558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.091482ms","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-03-19T09:01:26.408623Z","caller":"traceutil/trace.go:171","msg":"trace[719466655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"480.019882ms","start":"2026-03-19T09:01:25.928589Z","end":"2026-03-19T09:01:26.408608Z","steps":["trace[719466655] 'agreement among raft nodes before linearized reading' (duration: 479.855968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:26.40866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:25.928582Z","time spent":"480.069933ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T09:01:26.408655Z","caller":"traceutil/trace.go:171","msg":"trace[1147411521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"494.253386ms","start":"2026-03-19T09:01:25.91438Z","end":"2026-03-19T09:01:26.408633Z","steps":["trace[1147411521] 'agreement among raft nodes before linearized reading' (duration: 493.967479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:26.408707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:25.914353Z","time spent":"494.345138ms","remote":"127.0.0.1:52734","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-03-19T09:01:26.408755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:25.720204Z","time spent":"688.421243ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:26.40882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.335576ms","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-03-19T09:01:26.408935Z","caller":"traceutil/trace.go:171","msg":"trace[49002465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"478.535071ms","start":"2026-03-19T09:01:25.930375Z","end":"2026-03-19T09:01:26.40891Z","steps":["trace[49002465] 'agreement among raft nodes before linearized reading' (duration: 478.158471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:26.40901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:25.930348Z","time spent":"478.642735ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T09:01:32.942815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058805141289441,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T09:01:33.091248Z","caller":"traceutil/trace.go:171","msg":"trace[440193449] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:736; }","duration":"649.248071ms","start":"2026-03-19T09:01:32.441974Z","end":"2026-03-19T09:01:33.091222Z","steps":["trace[440193449] 'read index received' (duration: 649.048085ms)","trace[440193449] 'applied index is now lower than readState.Index' (duration: 198.866µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:33.091353Z","caller":"traceutil/trace.go:171","msg":"trace[32488715] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"651.985514ms","start":"2026-03-19T09:01:32.439331Z","end":"2026-03-19T09:01:33.091316Z","steps":["trace[32488715] 'process raft request' (duration: 651.709687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:33.091531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.39983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T09:01:33.091536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:32.439311Z","time spent":"652.135629ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:01:33.091571Z","caller":"traceutil/trace.go:171","msg":"trace[80303632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:713; }","duration":"243.475842ms","start":"2026-03-19T09:01:32.848085Z","end":"2026-03-19T09:01:33.091561Z","steps":["trace[80303632] 'agreement among raft nodes before linearized reading' (duration: 243.397739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:33.091593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.507657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T09:01:33.091661Z","caller":"traceutil/trace.go:171","msg":"trace[187293117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:713; }","duration":"649.700672ms","start":"2026-03-19T09:01:32.441943Z","end":"2026-03-19T09:01:33.091644Z","steps":["trace[187293117] 'agreement among raft nodes before linearized reading' (duration: 649.410604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:33.091664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.964657ms","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-03-19T09:01:33.091779Z","caller":"traceutil/trace.go:171","msg":"trace[1028712986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:713; }","duration":"132.139402ms","start":"2026-03-19T09:01:32.959616Z","end":"2026-03-19T09:01:33.091756Z","steps":["trace[1028712986] 'agreement among raft nodes before linearized reading' (duration: 131.843474ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:33.091789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:32.441931Z","time spent":"649.756584ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T09:01:33.091995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.487358ms","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-03-19T09:01:33.092261Z","caller":"traceutil/trace.go:171","msg":"trace[786576958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:713; }","duration":"646.089735ms","start":"2026-03-19T09:01:32.446154Z","end":"2026-03-19T09:01:33.092244Z","steps":["trace[786576958] 'agreement among raft nodes before linearized reading' (duration: 645.338234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:33.092266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.963348ms","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-03-19T09:01:33.092308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:32.446141Z","time spent":"646.155827ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-19T09:01:33.092308Z","caller":"traceutil/trace.go:171","msg":"trace[1594994723] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:713; }","duration":"647.02674ms","start":"2026-03-19T09:01:32.445269Z","end":"2026-03-19T09:01:33.092296Z","steps":["trace[1594994723] 'agreement among raft nodes before linearized reading' (duration: 646.151295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:33.092354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:32.445246Z","time spent":"647.096362ms","remote":"127.0.0.1:52734","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-03-19T09:01:33.205948Z","caller":"traceutil/trace.go:171","msg":"trace[1168904353] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"110.890008ms","start":"2026-03-19T09:01:33.095035Z","end":"2026-03-19T09:01:33.205925Z","steps":["trace[1168904353] 'process raft request' (duration: 110.767325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:34.231244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.143957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:01:34.231439Z","caller":"traceutil/trace.go:171","msg":"trace[575263884] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"1.135606207s","start":"2026-03-19T09:01:33.095814Z","end":"2026-03-19T09:01:34.23142Z","steps":["trace[575263884] 'process raft request' (duration: 604.036748ms)","trace[575263884] 'compare' (duration: 531.053275ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:01:34.231525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:33.095799Z","time spent":"1.13568458s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:01:34.231745Z","caller":"traceutil/trace.go:171","msg":"trace[196703793] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"1.135383312s","start":"2026-03-19T09:01:33.096345Z","end":"2026-03-19T09:01:34.231728Z","steps":["trace[196703793] 'process raft request' (duration: 1.135289219s)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:34.231784Z","caller":"traceutil/trace.go:171","msg":"trace[166047515] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"1.135872714s","start":"2026-03-19T09:01:33.095876Z","end":"2026-03-19T09:01:34.231748Z","steps":["trace[166047515] 'process raft request' (duration: 1.135490013s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:34.231902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:33.096335Z","time spent":"1.135479454s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:34.231999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:33.095855Z","time spent":"1.136007148s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:34.88298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.97931ms","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":"info","ts":"2026-03-19T09:01:34.883078Z","caller":"traceutil/trace.go:171","msg":"trace[1555738532] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:717; }","duration":"438.137384ms","start":"2026-03-19T09:01:34.444922Z","end":"2026-03-19T09:01:34.883059Z","steps":["trace[1555738532] 'count revisions from in-memory index tree' (duration: 437.869546ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:34.883122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:34.444901Z","time spent":"438.209325ms","remote":"127.0.0.1:52690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-03-19T09:01:35.432445Z","caller":"traceutil/trace.go:171","msg":"trace[1204816541] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"337.135264ms","start":"2026-03-19T09:01:35.095288Z","end":"2026-03-19T09:01:35.432423Z","steps":["trace[1204816541] 'read index received' (duration: 337.049881ms)","trace[1204816541] 'applied index is now lower than readState.Index' (duration: 84.203µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:01:35.432479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:34.890273Z","time spent":"542.198265ms","remote":"127.0.0.1:52480","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-19T09:01:35.432705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.39688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T09:01:35.432808Z","caller":"traceutil/trace.go:171","msg":"trace[1227153460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:717; }","duration":"337.514563ms","start":"2026-03-19T09:01:35.09527Z","end":"2026-03-19T09:01:35.432785Z","steps":["trace[1227153460] 'agreement among raft nodes before linearized reading' (duration: 337.254166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:35.43287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:35.095214Z","time spent":"337.643726ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T09:01:35.481993Z","caller":"traceutil/trace.go:171","msg":"trace[1379969346] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"304.494681ms","start":"2026-03-19T09:01:35.177473Z","end":"2026-03-19T09:01:35.481968Z","steps":["trace[1379969346] 'process raft request' (duration: 304.223293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:35.482457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:01:35.177453Z","time spent":"304.80546ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:01:35.482085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.572108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T09:01:35.482644Z","caller":"traceutil/trace.go:171","msg":"trace[222078702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"273.182274ms","start":"2026-03-19T09:01:35.209441Z","end":"2026-03-19T09:01:35.482623Z","steps":["trace[222078702] 'agreement among raft nodes before linearized reading' (duration: 272.452644ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:35.611815Z","caller":"traceutil/trace.go:171","msg":"trace[1942777703] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"174.353333ms","start":"2026-03-19T09:01:35.437442Z","end":"2026-03-19T09:01:35.611795Z","steps":["trace[1942777703] 'process raft request' (duration: 174.305372ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:35.611878Z","caller":"traceutil/trace.go:171","msg":"trace[1598197407] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"178.076594ms","start":"2026-03-19T09:01:35.433775Z","end":"2026-03-19T09:01:35.611852Z","steps":["trace[1598197407] 'process raft request' (duration: 177.800386ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:35.653841Z","caller":"traceutil/trace.go:171","msg":"trace[700240770] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"167.165419ms","start":"2026-03-19T09:01:35.48665Z","end":"2026-03-19T09:01:35.653816Z","steps":["trace[700240770] 'process raft request' (duration: 166.863491ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:36.373649Z","caller":"traceutil/trace.go:171","msg":"trace[471377903] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"133.024065ms","start":"2026-03-19T09:01:36.240601Z","end":"2026-03-19T09:01:36.373626Z","steps":["trace[471377903] 'process raft request' (duration: 132.851461ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:36.515454Z","caller":"traceutil/trace.go:171","msg":"trace[127649611] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:746; }","duration":"272.325101ms","start":"2026-03-19T09:01:36.243108Z","end":"2026-03-19T09:01:36.515433Z","steps":["trace[127649611] 'read index received' (duration: 130.379804ms)","trace[127649611] 'applied index is now lower than readState.Index' (duration: 141.944387ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:36.515474Z","caller":"traceutil/trace.go:171","msg":"trace[425607311] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"274.589041ms","start":"2026-03-19T09:01:36.24086Z","end":"2026-03-19T09:01:36.515449Z","steps":["trace[425607311] 'process raft request' (duration: 223.507961ms)","trace[425607311] 'compare' (duration: 50.871864ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:36.51552Z","caller":"traceutil/trace.go:171","msg":"trace[2026552231] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"273.562675ms","start":"2026-03-19T09:01:36.241924Z","end":"2026-03-19T09:01:36.515487Z","steps":["trace[2026552231] 'process raft request' (duration: 273.462962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:01:36.515642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.505606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:01:36.515685Z","caller":"traceutil/trace.go:171","msg":"trace[1693926846] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:724; }","duration":"272.583768ms","start":"2026-03-19T09:01:36.24309Z","end":"2026-03-19T09:01:36.515674Z","steps":["trace[1693926846] 'agreement among raft nodes before linearized reading' (duration: 272.468535ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:37.41199Z","caller":"traceutil/trace.go:171","msg":"trace[1369974197] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"241.779236ms","start":"2026-03-19T09:01:37.170186Z","end":"2026-03-19T09:01:37.411965Z","steps":["trace[1369974197] 'process raft request' (duration: 241.641332ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:37.827831Z","caller":"traceutil/trace.go:171","msg":"trace[1874991294] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"168.598117ms","start":"2026-03-19T09:01:37.65921Z","end":"2026-03-19T09:01:37.827808Z","steps":["trace[1874991294] 'read index received' (duration: 168.343501ms)","trace[1874991294] 'applied index is now lower than readState.Index' (duration: 253.666µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:01:37.828026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.801943ms","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-03-19T09:01:37.828068Z","caller":"traceutil/trace.go:171","msg":"trace[2086329738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:730; }","duration":"168.895325ms","start":"2026-03-19T09:01:37.65916Z","end":"2026-03-19T09:01:37.828055Z","steps":["trace[2086329738] 'agreement among raft nodes before linearized reading' (duration: 168.732562ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:37.828031Z","caller":"traceutil/trace.go:171","msg":"trace[452869886] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"208.876136ms","start":"2026-03-19T09:01:37.619111Z","end":"2026-03-19T09:01:37.827988Z","steps":["trace[452869886] 'process raft request' (duration: 208.520407ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:48.033896Z","caller":"traceutil/trace.go:171","msg":"trace[1144021155] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"111.588517ms","start":"2026-03-19T09:01:47.922273Z","end":"2026-03-19T09:01:48.033862Z","steps":["trace[1144021155] 'process raft request' (duration: 111.3472ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:52.781787Z","caller":"traceutil/trace.go:171","msg":"trace[1854517867] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"128.498033ms","start":"2026-03-19T09:01:52.653266Z","end":"2026-03-19T09:01:52.781764Z","steps":["trace[1854517867] 'process raft request' (duration: 104.741191ms)","trace[1854517867] 'compare' (duration: 23.66892ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:01:54.209233Z","caller":"traceutil/trace.go:171","msg":"trace[2141677624] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"140.040006ms","start":"2026-03-19T09:01:54.069173Z","end":"2026-03-19T09:01:54.209213Z","steps":["trace[2141677624] 'process raft request' (duration: 139.922433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:02.895522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.375972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:02:02.89563Z","caller":"traceutil/trace.go:171","msg":"trace[1044393263] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"310.74904ms","start":"2026-03-19T09:02:02.584864Z","end":"2026-03-19T09:02:02.895613Z","steps":["trace[1044393263] 'read index received' (duration: 42.071µs)","trace[1044393263] 'applied index is now lower than readState.Index' (duration: 310.705479ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:02.89565Z","caller":"traceutil/trace.go:171","msg":"trace[53307019] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"644.44615ms","start":"2026-03-19T09:02:02.251176Z","end":"2026-03-19T09:02:02.895622Z","steps":["trace[53307019] 'process raft request' (duration: 263.886184ms)","trace[53307019] 'compare' (duration: 380.159006ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:02.89581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:02.251159Z","time spent":"644.575963ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:02.895836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.974756ms","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-03-19T09:02:02.895872Z","caller":"traceutil/trace.go:171","msg":"trace[1299999127] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:799; }","duration":"311.044058ms","start":"2026-03-19T09:02:02.584818Z","end":"2026-03-19T09:02:02.895862Z","steps":["trace[1299999127] 'agreement among raft nodes before linearized reading' (duration: 310.876993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:02.895905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:02.584799Z","time spent":"311.09792ms","remote":"127.0.0.1:52734","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-03-19T09:02:02.896017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.734835ms","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-03-19T09:02:02.896167Z","caller":"traceutil/trace.go:171","msg":"trace[1880469442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"123.926999ms","start":"2026-03-19T09:02:02.772221Z","end":"2026-03-19T09:02:02.896149Z","steps":["trace[1880469442] 'agreement among raft nodes before linearized reading' (duration: 123.641152ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:04.674824Z","caller":"traceutil/trace.go:171","msg":"trace[1284041997] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"464.507296ms","start":"2026-03-19T09:02:04.21029Z","end":"2026-03-19T09:02:04.674798Z","steps":["trace[1284041997] 'process raft request' (duration: 464.32096ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:04.675108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:04.210265Z","time spent":"464.735482ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:02:05.081803Z","caller":"traceutil/trace.go:171","msg":"trace[1782392153] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"150.505808ms","start":"2026-03-19T09:02:04.931259Z","end":"2026-03-19T09:02:05.081765Z","steps":["trace[1782392153] 'process raft request' (duration: 150.384895ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:05.081847Z","caller":"traceutil/trace.go:171","msg":"trace[1589110037] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"148.783471ms","start":"2026-03-19T09:02:04.933038Z","end":"2026-03-19T09:02:05.081821Z","steps":["trace[1589110037] 'process raft request' (duration: 148.668338ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:05.081972Z","caller":"traceutil/trace.go:171","msg":"trace[838912807] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"150.807307ms","start":"2026-03-19T09:02:04.931151Z","end":"2026-03-19T09:02:05.081959Z","steps":["trace[838912807] 'process raft request' (duration: 150.367265ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:05.081809Z","caller":"traceutil/trace.go:171","msg":"trace[580069105] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"150.114568ms","start":"2026-03-19T09:02:04.931672Z","end":"2026-03-19T09:02:05.081787Z","steps":["trace[580069105] 'process raft request' (duration: 150.007105ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:06.416119Z","caller":"traceutil/trace.go:171","msg":"trace[382331444] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"229.200275ms","start":"2026-03-19T09:02:06.186895Z","end":"2026-03-19T09:02:06.416095Z","steps":["trace[382331444] 'process raft request' (duration: 229.062541ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:07.256173Z","caller":"traceutil/trace.go:171","msg":"trace[1989161333] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"165.570295ms","start":"2026-03-19T09:02:07.090568Z","end":"2026-03-19T09:02:07.256138Z","steps":["trace[1989161333] 'process raft request' (duration: 165.291577ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:07.256278Z","caller":"traceutil/trace.go:171","msg":"trace[1484595782] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"165.507623ms","start":"2026-03-19T09:02:07.090745Z","end":"2026-03-19T09:02:07.256252Z","steps":["trace[1484595782] 'process raft request' (duration: 165.446771ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:07.256399Z","caller":"traceutil/trace.go:171","msg":"trace[321317968] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"165.656567ms","start":"2026-03-19T09:02:07.090695Z","end":"2026-03-19T09:02:07.256351Z","steps":["trace[321317968] 'process raft request' (duration: 165.333218ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:08.965083Z","caller":"traceutil/trace.go:171","msg":"trace[583989453] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"244.446418ms","start":"2026-03-19T09:02:08.720612Z","end":"2026-03-19T09:02:08.965058Z","steps":["trace[583989453] 'process raft request' (duration: 244.315395ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:08.965536Z","caller":"traceutil/trace.go:171","msg":"trace[1667655906] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:846; }","duration":"117.821165ms","start":"2026-03-19T09:02:08.847699Z","end":"2026-03-19T09:02:08.96552Z","steps":["trace[1667655906] 'read index received' (duration: 117.813164ms)","trace[1667655906] 'applied index is now lower than readState.Index' (duration: 6.191µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:08.965634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.927628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:02:08.965686Z","caller":"traceutil/trace.go:171","msg":"trace[1331559009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:815; }","duration":"118.0285ms","start":"2026-03-19T09:02:08.847643Z","end":"2026-03-19T09:02:08.965671Z","steps":["trace[1331559009] 'agreement among raft nodes before linearized reading' (duration: 117.938798ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:09.473619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.897181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:02:09.473775Z","caller":"traceutil/trace.go:171","msg":"trace[204460852] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"328.704915ms","start":"2026-03-19T09:02:09.14505Z","end":"2026-03-19T09:02:09.473754Z","steps":["trace[204460852] 'read index received' (duration: 43.684151ms)","trace[204460852] 'applied index is now lower than readState.Index' (duration: 285.016674ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:09.473813Z","caller":"traceutil/trace.go:171","msg":"trace[478011516] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"426.55107ms","start":"2026-03-19T09:02:09.04724Z","end":"2026-03-19T09:02:09.473791Z","steps":["trace[478011516] 'process raft request' (duration: 141.385242ms)","trace[478011516] 'compare' (duration: 284.779657ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:09.473967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:09.047213Z","time spent":"426.650663ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:09.474036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.966823ms","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-03-19T09:02:09.474094Z","caller":"traceutil/trace.go:171","msg":"trace[1605897614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:816; }","duration":"329.076895ms","start":"2026-03-19T09:02:09.145002Z","end":"2026-03-19T09:02:09.474079Z","steps":["trace[1605897614] 'agreement among raft nodes before linearized reading' (duration: 328.855039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:09.474146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:09.144982Z","time spent":"329.151237ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T09:02:09.474166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.008774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-19T09:02:09.474284Z","caller":"traceutil/trace.go:171","msg":"trace[465279845] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:816; }","duration":"214.170568ms","start":"2026-03-19T09:02:09.260085Z","end":"2026-03-19T09:02:09.474256Z","steps":["trace[465279845] 'agreement among raft nodes before linearized reading' (duration: 213.880601ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:09.474311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.758668ms","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":"warn","ts":"2026-03-19T09:02:09.474343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.154579ms","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-03-19T09:02:09.474391Z","caller":"traceutil/trace.go:171","msg":"trace[1151413329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:816; }","duration":"213.86344ms","start":"2026-03-19T09:02:09.26051Z","end":"2026-03-19T09:02:09.474374Z","steps":["trace[1151413329] 'agreement among raft nodes before linearized reading' (duration: 213.633534ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:09.474454Z","caller":"traceutil/trace.go:171","msg":"trace[1012473241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:816; }","duration":"214.268032ms","start":"2026-03-19T09:02:09.260144Z","end":"2026-03-19T09:02:09.474412Z","steps":["trace[1012473241] 'agreement among raft nodes before linearized reading' (duration: 214.022985ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:10.558283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.073296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:02:10.558382Z","caller":"traceutil/trace.go:171","msg":"trace[1425091968] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"1.079933832s","start":"2026-03-19T09:02:09.478428Z","end":"2026-03-19T09:02:10.558362Z","steps":["trace[1425091968] 'process raft request' (duration: 218.728573ms)","trace[1425091968] 'compare' (duration: 860.964953ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:10.558449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:09.478406Z","time spent":"1.080012753s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:10.922135Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.224935622s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T09:02:10.923398Z","caller":"traceutil/trace.go:171","msg":"trace[1283398402] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:847; }","duration":"1.299124757s","start":"2026-03-19T09:02:09.624251Z","end":"2026-03-19T09:02:10.923376Z","steps":["trace[1283398402] 'read index received' (duration: 72.923481ms)","trace[1283398402] 'applied index is now lower than readState.Index' (duration: 1.226200246s)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:10.923555Z","caller":"traceutil/trace.go:171","msg":"trace[2022779229] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"1.443965952s","start":"2026-03-19T09:02:09.47953Z","end":"2026-03-19T09:02:10.923496Z","steps":["trace[2022779229] 'process raft request' (duration: 1.443806208s)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:10.923524Z","caller":"traceutil/trace.go:171","msg":"trace[628390130] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"1.444279391s","start":"2026-03-19T09:02:09.479207Z","end":"2026-03-19T09:02:10.923486Z","steps":["trace[628390130] 'process raft request' (duration: 1.444090766s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:10.923632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299364263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T09:02:10.923685Z","caller":"traceutil/trace.go:171","msg":"trace[1591880889] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:820; }","duration":"1.299463176s","start":"2026-03-19T09:02:09.624204Z","end":"2026-03-19T09:02:10.923667Z","steps":["trace[1591880889] 'agreement among raft nodes before linearized reading' (duration: 1.299384794s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:10.923743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:09.624173Z","time spent":"1.299561619s","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-03-19T09:02:10.923741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:09.479523Z","time spent":"1.444075245s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:10.923827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:09.4792Z","time spent":"1.444412904s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:02:10.924348Z","caller":"traceutil/trace.go:171","msg":"trace[1074529803] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"1.444684331s","start":"2026-03-19T09:02:09.479016Z","end":"2026-03-19T09:02:10.9237Z","steps":["trace[1074529803] 'process raft request' (duration: 1.443433868s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:10.924595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:09.478999Z","time spent":"1.445490444s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:10.928546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.315164ms","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-03-19T09:02:10.92862Z","caller":"traceutil/trace.go:171","msg":"trace[227212939] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:820; }","duration":"106.437038ms","start":"2026-03-19T09:02:10.822171Z","end":"2026-03-19T09:02:10.928608Z","steps":["trace[227212939] 'agreement among raft nodes before linearized reading' (duration: 106.326375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:21.220246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.369091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:02:21.220326Z","caller":"traceutil/trace.go:171","msg":"trace[469835072] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:933; }","duration":"126.681954ms","start":"2026-03-19T09:02:21.093632Z","end":"2026-03-19T09:02:21.220314Z","steps":["trace[469835072] 'read index received' (duration: 15.211781ms)","trace[469835072] 'applied index is now lower than readState.Index' (duration: 111.469283ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:21.220336Z","caller":"traceutil/trace.go:171","msg":"trace[1082182939] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"133.57616ms","start":"2026-03-19T09:02:21.086741Z","end":"2026-03-19T09:02:21.220317Z","steps":["trace[1082182939] 'process raft request' (duration: 22.080916ms)","trace[1082182939] 'compare' (duration: 111.170225ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:21.220387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.749546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T09:02:21.220405Z","caller":"traceutil/trace.go:171","msg":"trace[549869202] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:898; }","duration":"126.798676ms","start":"2026-03-19T09:02:21.093599Z","end":"2026-03-19T09:02:21.220397Z","steps":["trace[549869202] 'agreement among raft nodes before linearized reading' (duration: 126.743135ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:23.277707Z","caller":"traceutil/trace.go:171","msg":"trace[1724030556] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"272.335192ms","start":"2026-03-19T09:02:23.005356Z","end":"2026-03-19T09:02:23.277691Z","steps":["trace[1724030556] 'read index received' (duration: 272.166868ms)","trace[1724030556] 'applied index is now lower than readState.Index' (duration: 167.924µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:23.277773Z","caller":"traceutil/trace.go:171","msg":"trace[519768147] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"305.132998ms","start":"2026-03-19T09:02:22.972617Z","end":"2026-03-19T09:02:23.27775Z","steps":["trace[519768147] 'process raft request' (duration: 304.964003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:23.277865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.518017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T09:02:23.277884Z","caller":"traceutil/trace.go:171","msg":"trace[1302635255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:902; }","duration":"272.571228ms","start":"2026-03-19T09:02:23.005307Z","end":"2026-03-19T09:02:23.277878Z","steps":["trace[1302635255] 'agreement among raft nodes before linearized reading' (duration: 272.461255ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:23.277904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:22.972602Z","time spent":"305.23691ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:23.277974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.312961ms","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-03-19T09:02:23.277985Z","caller":"traceutil/trace.go:171","msg":"trace[414645626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:902; }","duration":"272.346383ms","start":"2026-03-19T09:02:23.005635Z","end":"2026-03-19T09:02:23.277981Z","steps":["trace[414645626] 'agreement among raft nodes before linearized reading' (duration: 272.311571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:23.278085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.78574ms","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-03-19T09:02:23.278114Z","caller":"traceutil/trace.go:171","msg":"trace[1786118270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:902; }","duration":"216.844362ms","start":"2026-03-19T09:02:23.06126Z","end":"2026-03-19T09:02:23.278104Z","steps":["trace[1786118270] 'agreement among raft nodes before linearized reading' (duration: 216.743469ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:23.384114Z","caller":"traceutil/trace.go:171","msg":"trace[1080436604] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"100.2721ms","start":"2026-03-19T09:02:23.28382Z","end":"2026-03-19T09:02:23.384092Z","steps":["trace[1080436604] 'process raft request' (duration: 100.23896ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:23.384286Z","caller":"traceutil/trace.go:171","msg":"trace[52425137] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"100.424884ms","start":"2026-03-19T09:02:23.283813Z","end":"2026-03-19T09:02:23.384238Z","steps":["trace[52425137] 'process raft request' (duration: 100.173997ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:25.132485Z","caller":"traceutil/trace.go:171","msg":"trace[839502616] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"284.592623ms","start":"2026-03-19T09:02:24.847867Z","end":"2026-03-19T09:02:25.13246Z","steps":["trace[839502616] 'read index received' (duration: 284.355136ms)","trace[839502616] 'applied index is now lower than readState.Index' (duration: 236.807µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:25.132602Z","caller":"traceutil/trace.go:171","msg":"trace[265379719] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"492.698069ms","start":"2026-03-19T09:02:24.639893Z","end":"2026-03-19T09:02:25.132591Z","steps":["trace[265379719] 'process raft request' (duration: 492.431291ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:25.13276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.523731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-19T09:02:25.132804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.917401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T09:02:25.132813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:24.63985Z","time spent":"492.798612ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:02:25.132872Z","caller":"traceutil/trace.go:171","msg":"trace[1641380571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"285.044085ms","start":"2026-03-19T09:02:24.847813Z","end":"2026-03-19T09:02:25.132857Z","steps":["trace[1641380571] 'agreement among raft nodes before linearized reading' (duration: 284.895472ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:25.132822Z","caller":"traceutil/trace.go:171","msg":"trace[1471650268] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:906; }","duration":"240.621224ms","start":"2026-03-19T09:02:24.892182Z","end":"2026-03-19T09:02:25.132803Z","steps":["trace[1471650268] 'agreement among raft nodes before linearized reading' (duration: 240.436449ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:25.786447Z","caller":"traceutil/trace.go:171","msg":"trace[141567314] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"503.383047ms","start":"2026-03-19T09:02:25.28304Z","end":"2026-03-19T09:02:25.786423Z","steps":["trace[141567314] 'read index received' (duration: 484.761414ms)","trace[141567314] 'applied index is now lower than readState.Index' (duration: 18.620213ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:25.786573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:25.134253Z","time spent":"652.316113ms","remote":"127.0.0.1:52480","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-19T09:02:25.78696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.904677ms","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-03-19T09:02:25.787037Z","caller":"traceutil/trace.go:171","msg":"trace[1852696422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:906; }","duration":"401.015329ms","start":"2026-03-19T09:02:25.386Z","end":"2026-03-19T09:02:25.787016Z","steps":["trace[1852696422] 'agreement among raft nodes before linearized reading' (duration: 400.824174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:25.787073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:25.385983Z","time spent":"401.082372ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T09:02:25.786966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.522672ms","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-03-19T09:02:25.787154Z","caller":"traceutil/trace.go:171","msg":"trace[1162974845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:906; }","duration":"398.725778ms","start":"2026-03-19T09:02:25.388403Z","end":"2026-03-19T09:02:25.787129Z","steps":["trace[1162974845] 'agreement among raft nodes before linearized reading' (duration: 398.439681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:25.787224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:25.388383Z","time spent":"398.82288ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T09:02:25.787213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.136077ms","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-03-19T09:02:25.787439Z","caller":"traceutil/trace.go:171","msg":"trace[118476075] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:906; }","duration":"504.440566ms","start":"2026-03-19T09:02:25.282988Z","end":"2026-03-19T09:02:25.787429Z","steps":["trace[118476075] 'agreement among raft nodes before linearized reading' (duration: 504.012174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:25.787475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:25.28297Z","time spent":"504.494658ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T09:02:25.787989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.339281ms","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-03-19T09:02:25.788029Z","caller":"traceutil/trace.go:171","msg":"trace[1309921564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:906; }","duration":"400.423254ms","start":"2026-03-19T09:02:25.387595Z","end":"2026-03-19T09:02:25.788018Z","steps":["trace[1309921564] 'agreement among raft nodes before linearized reading' (duration: 399.350934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:25.788058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:25.38757Z","time spent":"400.476086ms","remote":"127.0.0.1:52734","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-03-19T09:02:28.102804Z","caller":"traceutil/trace.go:171","msg":"trace[1772503609] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"301.52612ms","start":"2026-03-19T09:02:27.801252Z","end":"2026-03-19T09:02:28.102778Z","steps":["trace[1772503609] 'process raft request' (duration: 301.417707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:28.102945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:27.801239Z","time spent":"301.634623ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:02:28.103069Z","caller":"traceutil/trace.go:171","msg":"trace[1038139991] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"303.567586ms","start":"2026-03-19T09:02:27.799478Z","end":"2026-03-19T09:02:28.103046Z","steps":["trace[1038139991] 'process raft request' (duration: 267.578353ms)","trace[1038139991] 'compare' (duration: 35.177861ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:28.103193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:27.799469Z","time spent":"303.651408ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:02:28.1126Z","caller":"traceutil/trace.go:171","msg":"trace[561380111] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:951; }","duration":"265.225489ms","start":"2026-03-19T09:02:27.847359Z","end":"2026-03-19T09:02:28.112584Z","steps":["trace[561380111] 'read index received' (duration: 219.706848ms)","trace[561380111] 'applied index is now lower than readState.Index' (duration: 45.517671ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:28.112636Z","caller":"traceutil/trace.go:171","msg":"trace[1275485488] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"271.558741ms","start":"2026-03-19T09:02:27.841063Z","end":"2026-03-19T09:02:28.112622Z","steps":["trace[1275485488] 'process raft request' (duration: 271.454268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:28.112717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.341352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:02:28.112741Z","caller":"traceutil/trace.go:171","msg":"trace[1261883227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"265.380173ms","start":"2026-03-19T09:02:27.847354Z","end":"2026-03-19T09:02:28.112734Z","steps":["trace[1261883227] 'agreement among raft nodes before linearized reading' (duration: 265.324201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:28.112759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.678616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:02:28.112778Z","caller":"traceutil/trace.go:171","msg":"trace[2115274737] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:917; }","duration":"159.727338ms","start":"2026-03-19T09:02:27.953046Z","end":"2026-03-19T09:02:28.112773Z","steps":["trace[2115274737] 'agreement among raft nodes before linearized reading' (duration: 159.677557ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:37.298909Z","caller":"traceutil/trace.go:171","msg":"trace[1979128190] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"160.489182ms","start":"2026-03-19T09:02:37.138388Z","end":"2026-03-19T09:02:37.298877Z","steps":["trace[1979128190] 'process raft request' (duration: 160.264046ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:38.359886Z","caller":"traceutil/trace.go:171","msg":"trace[61719535] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"143.908126ms","start":"2026-03-19T09:02:38.215959Z","end":"2026-03-19T09:02:38.359867Z","steps":["trace[61719535] 'read index received' (duration: 143.744181ms)","trace[61719535] 'applied index is now lower than readState.Index' (duration: 163.385µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:38.359953Z","caller":"traceutil/trace.go:171","msg":"trace[869106809] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"159.451944ms","start":"2026-03-19T09:02:38.200468Z","end":"2026-03-19T09:02:38.35992Z","steps":["trace[869106809] 'process raft request' (duration: 159.29711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:38.36006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.372689ms","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-03-19T09:02:38.360088Z","caller":"traceutil/trace.go:171","msg":"trace[1390612031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:943; }","duration":"127.413831ms","start":"2026-03-19T09:02:38.232663Z","end":"2026-03-19T09:02:38.360077Z","steps":["trace[1390612031] 'agreement among raft nodes before linearized reading' (duration: 127.308278ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:38.360132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.142592ms","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-03-19T09:02:38.360213Z","caller":"traceutil/trace.go:171","msg":"trace[1047099745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:943; }","duration":"144.267916ms","start":"2026-03-19T09:02:38.215925Z","end":"2026-03-19T09:02:38.360193Z","steps":["trace[1047099745] 'agreement among raft nodes before linearized reading' (duration: 144.016189ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:38.530721Z","caller":"traceutil/trace.go:171","msg":"trace[1794257784] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"166.129425ms","start":"2026-03-19T09:02:38.364571Z","end":"2026-03-19T09:02:38.5307Z","steps":["trace[1794257784] 'process raft request' (duration: 166.014062ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:39.467536Z","caller":"traceutil/trace.go:171","msg":"trace[1195098569] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"154.248095ms","start":"2026-03-19T09:02:39.31327Z","end":"2026-03-19T09:02:39.467518Z","steps":["trace[1195098569] 'process raft request' (duration: 154.145652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:40.486711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058805141290055,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T09:02:40.987395Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058805141290055,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T09:02:41.300787Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.486245448s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T09:02:41.314511Z","caller":"traceutil/trace.go:171","msg":"trace[1565241016] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"1.500044659s","start":"2026-03-19T09:02:39.814442Z","end":"2026-03-19T09:02:41.314487Z","steps":["trace[1565241016] 'process raft request' (duration: 1.486568726s)","trace[1565241016] 'compare' (duration: 13.107462ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:41.314643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:39.814419Z","time spent":"1.500150072s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:02:41.359692Z","caller":"traceutil/trace.go:171","msg":"trace[588469679] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:985; }","duration":"1.373250294s","start":"2026-03-19T09:02:39.986417Z","end":"2026-03-19T09:02:41.359668Z","steps":["trace[588469679] 'read index received' (duration: 1.314554313s)","trace[588469679] 'applied index is now lower than readState.Index' (duration: 58.694611ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:41.359947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.308043ms","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-03-19T09:02:41.359981Z","caller":"traceutil/trace.go:171","msg":"trace[1332231712] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:947; }","duration":"996.394254ms","start":"2026-03-19T09:02:40.363578Z","end":"2026-03-19T09:02:41.359972Z","steps":["trace[1332231712] 'agreement among raft nodes before linearized reading' (duration: 996.24112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:41.359949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37349953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T09:02:41.360048Z","caller":"traceutil/trace.go:171","msg":"trace[1078415686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:947; }","duration":"1.373673736s","start":"2026-03-19T09:02:39.98636Z","end":"2026-03-19T09:02:41.360034Z","steps":["trace[1078415686] 'agreement among raft nodes before linearized reading' (duration: 1.373414899s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:41.360072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.628005ms","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":"warn","ts":"2026-03-19T09:02:41.360083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:39.986337Z","time spent":"1.373738417s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T09:02:41.360095Z","caller":"traceutil/trace.go:171","msg":"trace[255153557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:947; }","duration":"825.649845ms","start":"2026-03-19T09:02:40.534439Z","end":"2026-03-19T09:02:41.360089Z","steps":["trace[255153557] 'agreement among raft nodes before linearized reading' (duration: 825.588704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:41.36008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.133172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T09:02:41.360117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:40.534384Z","time spent":"825.728898ms","remote":"127.0.0.1:52734","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-03-19T09:02:41.360132Z","caller":"traceutil/trace.go:171","msg":"trace[1722981064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:947; }","duration":"512.219946ms","start":"2026-03-19T09:02:40.847898Z","end":"2026-03-19T09:02:41.360118Z","steps":["trace[1722981064] 'agreement among raft nodes before linearized reading' (duration: 512.123633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:41.360166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:40.847882Z","time spent":"512.277876ms","remote":"127.0.0.1:52422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T09:02:41.360245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.477443ms","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-03-19T09:02:41.360267Z","caller":"traceutil/trace.go:171","msg":"trace[655430851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:947; }","duration":"981.537874ms","start":"2026-03-19T09:02:40.378723Z","end":"2026-03-19T09:02:41.360261Z","steps":["trace[655430851] 'agreement among raft nodes before linearized reading' (duration: 981.477822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:41.36001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:40.36356Z","time spent":"996.443316ms","remote":"127.0.0.1:52734","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-03-19T09:02:41.360292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:40.378704Z","time spent":"981.583225ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T09:02:41.655822Z","caller":"traceutil/trace.go:171","msg":"trace[1385434756] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"181.036525ms","start":"2026-03-19T09:02:41.474755Z","end":"2026-03-19T09:02:41.655792Z","steps":["trace[1385434756] 'process raft request' (duration: 98.452841ms)","trace[1385434756] 'compare' (duration: 82.410189ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:44.48605Z","caller":"traceutil/trace.go:171","msg":"trace[58453746] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"207.065197ms","start":"2026-03-19T09:02:44.278961Z","end":"2026-03-19T09:02:44.486026Z","steps":["trace[58453746] 'process raft request' (duration: 206.927634ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:47.264903Z","caller":"traceutil/trace.go:171","msg":"trace[360543331] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"203.155961ms","start":"2026-03-19T09:02:47.06172Z","end":"2026-03-19T09:02:47.264876Z","steps":["trace[360543331] 'process raft request' (duration: 202.994917ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:49.673344Z","caller":"traceutil/trace.go:171","msg":"trace[64204366] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"123.508626ms","start":"2026-03-19T09:02:49.549815Z","end":"2026-03-19T09:02:49.673323Z","steps":["trace[64204366] 'process raft request' (duration: 123.336701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:50.215888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.82212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:02:50.215983Z","caller":"traceutil/trace.go:171","msg":"trace[337555215] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"538.531484ms","start":"2026-03-19T09:02:49.677435Z","end":"2026-03-19T09:02:50.215967Z","steps":["trace[337555215] 'process raft request' (duration: 243.549619ms)","trace[337555215] 'compare' (duration: 294.745948ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:50.216028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:49.67742Z","time spent":"538.588905ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:50.99719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.795767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:02:50.99748Z","caller":"traceutil/trace.go:171","msg":"trace[1003370842] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"1.319449176s","start":"2026-03-19T09:02:49.678009Z","end":"2026-03-19T09:02:50.997459Z","steps":["trace[1003370842] 'process raft request' (duration: 1.319300312s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:50.997565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:49.677997Z","time spent":"1.319528818s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:02:50.997442Z","caller":"traceutil/trace.go:171","msg":"trace[1726311264] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.319806094s","start":"2026-03-19T09:02:49.67759Z","end":"2026-03-19T09:02:50.997396Z","steps":["trace[1726311264] 'process raft request' (duration: 694.696199ms)","trace[1726311264] 'compare' (duration: 624.395957ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:50.997787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:49.677565Z","time spent":"1.320076863s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:02:51.035289Z","caller":"traceutil/trace.go:171","msg":"trace[1023626922] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1031; }","duration":"1.346931565s","start":"2026-03-19T09:02:49.688315Z","end":"2026-03-19T09:02:51.035246Z","steps":["trace[1023626922] 'read index received' (duration: 232.684437ms)","trace[1023626922] 'applied index is now lower than readState.Index' (duration: 1.114245228s)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:51.035445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347094069s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T09:02:51.035495Z","caller":"traceutil/trace.go:171","msg":"trace[81571293] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:993; }","duration":"1.347171841s","start":"2026-03-19T09:02:49.688311Z","end":"2026-03-19T09:02:51.035483Z","steps":["trace[81571293] 'agreement among raft nodes before linearized reading' (duration: 1.347044258s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:51.035536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:49.688288Z","time spent":"1.347238634s","remote":"127.0.0.1:52962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-03-19T09:02:51.035538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.609949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:02:51.035594Z","caller":"traceutil/trace.go:171","msg":"trace[2064567000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"188.708032ms","start":"2026-03-19T09:02:50.846867Z","end":"2026-03-19T09:02:51.035575Z","steps":["trace[2064567000] 'agreement among raft nodes before linearized reading' (duration: 188.614199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:51.035928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245072182s","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-03-19T09:02:51.035996Z","caller":"traceutil/trace.go:171","msg":"trace[75211112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:993; }","duration":"1.245178165s","start":"2026-03-19T09:02:49.7908Z","end":"2026-03-19T09:02:51.035978Z","steps":["trace[75211112] 'agreement among raft nodes before linearized reading' (duration: 1.244667861s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:51.03599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223766328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T09:02:51.036041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:49.790771Z","time spent":"1.245259707s","remote":"127.0.0.1:52734","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-03-19T09:02:51.03609Z","caller":"traceutil/trace.go:171","msg":"trace[1220188046] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:993; }","duration":"1.223881901s","start":"2026-03-19T09:02:49.812178Z","end":"2026-03-19T09:02:51.03606Z","steps":["trace[1220188046] 'agreement among raft nodes before linearized reading' (duration: 1.223668645s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:51.03614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:49.812111Z","time spent":"1.224018796s","remote":"127.0.0.1:52656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-03-19T09:02:52.03904Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058805141290155,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T09:02:52.306167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.359997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:02:52.306241Z","caller":"traceutil/trace.go:171","msg":"trace[661067576] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"768.22925ms","start":"2026-03-19T09:02:51.538Z","end":"2026-03-19T09:02:52.306229Z","steps":["trace[661067576] 'read index received' (duration: 616.797322ms)","trace[661067576] 'applied index is now lower than readState.Index' (duration: 151.431188ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:52.306299Z","caller":"traceutil/trace.go:171","msg":"trace[1615794328] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"770.389677ms","start":"2026-03-19T09:02:51.535903Z","end":"2026-03-19T09:02:52.306292Z","steps":["trace[1615794328] 'process raft request' (duration: 618.853496ms)","trace[1615794328] 'compare' (duration: 151.274474ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:52.306331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:51.535883Z","time spent":"770.432578ms","remote":"127.0.0.1:52528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T09:02:52.306514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.514407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:02:52.306535Z","caller":"traceutil/trace.go:171","msg":"trace[433273602] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:999; }","duration":"768.562078ms","start":"2026-03-19T09:02:51.537966Z","end":"2026-03-19T09:02:52.306528Z","steps":["trace[433273602] 'agreement among raft nodes before linearized reading' (duration: 768.491467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:52.306554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:51.537954Z","time spent":"768.59532ms","remote":"127.0.0.1:52534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-03-19T09:02:52.30671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.116975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T09:02:52.306734Z","caller":"traceutil/trace.go:171","msg":"trace[859189478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:999; }","duration":"628.176838ms","start":"2026-03-19T09:02:51.678552Z","end":"2026-03-19T09:02:52.306729Z","steps":["trace[859189478] 'agreement among raft nodes before linearized reading' (duration: 628.090655ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:52.306753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:51.67853Z","time spent":"628.217158ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T09:02:52.782232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.442296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:02:52.782438Z","caller":"traceutil/trace.go:171","msg":"trace[2067885399] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"472.904626ms","start":"2026-03-19T09:02:52.309491Z","end":"2026-03-19T09:02:52.782395Z","steps":["trace[2067885399] 'process raft request' (duration: 369.250405ms)","trace[2067885399] 'compare' (duration: 103.199609ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:52.78252Z","caller":"traceutil/trace.go:171","msg":"trace[1342886267] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"471.856947ms","start":"2026-03-19T09:02:52.310635Z","end":"2026-03-19T09:02:52.782492Z","steps":["trace[1342886267] 'process raft request' (duration: 471.672672ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:52.782584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:52.309467Z","time spent":"473.05617ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T09:02:52.782653Z","caller":"traceutil/trace.go:171","msg":"trace[335243130] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"471.337845ms","start":"2026-03-19T09:02:52.311054Z","end":"2026-03-19T09:02:52.782392Z","steps":["trace[335243130] 'process raft request' (duration: 471.305264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:52.782663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:52.310619Z","time spent":"471.970542ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:52.78272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:52.311042Z","time spent":"471.644883ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:53.212172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.886852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:02:53.212273Z","caller":"traceutil/trace.go:171","msg":"trace[381085707] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"366.051168ms","start":"2026-03-19T09:02:52.846206Z","end":"2026-03-19T09:02:53.212257Z","steps":["trace[381085707] 'read index received' (duration: 80.885148ms)","trace[381085707] 'applied index is now lower than readState.Index' (duration: 285.16485ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:53.212363Z","caller":"traceutil/trace.go:171","msg":"trace[2014013622] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"424.107772ms","start":"2026-03-19T09:02:52.788244Z","end":"2026-03-19T09:02:53.212352Z","steps":["trace[2014013622] 'process raft request' (duration: 138.957923ms)","trace[2014013622] 'compare' (duration: 284.722317ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:53.21244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:52.788237Z","time spent":"424.172994ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:53.212536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.099532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T09:02:53.21259Z","caller":"traceutil/trace.go:171","msg":"trace[101511319] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1004; }","duration":"310.223725ms","start":"2026-03-19T09:02:52.902353Z","end":"2026-03-19T09:02:53.212577Z","steps":["trace[101511319] 'agreement among raft nodes before linearized reading' (duration: 310.107311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:53.212615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:52.902333Z","time spent":"310.275946ms","remote":"127.0.0.1:52908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-19T09:02:53.212653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.048379ms","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-03-19T09:02:53.212709Z","caller":"traceutil/trace.go:171","msg":"trace[2049421673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1004; }","duration":"144.106771ms","start":"2026-03-19T09:02:53.068587Z","end":"2026-03-19T09:02:53.212694Z","steps":["trace[2049421673] 'agreement among raft nodes before linearized reading' (duration: 143.952626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:53.212662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.451419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:02:53.21283Z","caller":"traceutil/trace.go:171","msg":"trace[193490685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"366.620884ms","start":"2026-03-19T09:02:52.8462Z","end":"2026-03-19T09:02:53.21282Z","steps":["trace[193490685] 'agreement among raft nodes before linearized reading' (duration: 366.427459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:53.212869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:52.846141Z","time spent":"366.719427ms","remote":"127.0.0.1:52422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T09:02:53.21286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.971866ms","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-03-19T09:02:53.212951Z","caller":"traceutil/trace.go:171","msg":"trace[402036973] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1004; }","duration":"210.113129ms","start":"2026-03-19T09:02:53.002816Z","end":"2026-03-19T09:02:53.212929Z","steps":["trace[402036973] 'agreement among raft nodes before linearized reading' (duration: 209.835681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:53.212876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.65271ms","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-03-19T09:02:53.21305Z","caller":"traceutil/trace.go:171","msg":"trace[512727771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1004; }","duration":"211.882337ms","start":"2026-03-19T09:02:53.001154Z","end":"2026-03-19T09:02:53.213036Z","steps":["trace[512727771] 'agreement among raft nodes before linearized reading' (duration: 211.540948ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:53.418291Z","caller":"traceutil/trace.go:171","msg":"trace[1197766831] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"202.40041ms","start":"2026-03-19T09:02:53.215862Z","end":"2026-03-19T09:02:53.418263Z","steps":["trace[1197766831] 'process raft request' (duration: 170.942333ms)","trace[1197766831] 'compare' (duration: 31.340124ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:53.451339Z","caller":"traceutil/trace.go:171","msg":"trace[1328584035] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"234.182426ms","start":"2026-03-19T09:02:53.21712Z","end":"2026-03-19T09:02:53.451303Z","steps":["trace[1328584035] 'process raft request' (duration: 234.116715ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:53.45147Z","caller":"traceutil/trace.go:171","msg":"trace[1331806229] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"234.248259ms","start":"2026-03-19T09:02:53.2171Z","end":"2026-03-19T09:02:53.451348Z","steps":["trace[1331806229] 'process raft request' (duration: 233.962641ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:55.348148Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058805141290173,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T09:02:55.424779Z","caller":"traceutil/trace.go:171","msg":"trace[1737345369] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"633.497761ms","start":"2026-03-19T09:02:54.791188Z","end":"2026-03-19T09:02:55.424686Z","steps":["trace[1737345369] 'process raft request' (duration: 633.282785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:55.425227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:54.79116Z","time spent":"633.776489ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:02:56.21782Z","caller":"traceutil/trace.go:171","msg":"trace[219941258] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1049; }","duration":"1.370219083s","start":"2026-03-19T09:02:54.847582Z","end":"2026-03-19T09:02:56.217801Z","steps":["trace[219941258] 'read index received' (duration: 576.840556ms)","trace[219941258] 'applied index is now lower than readState.Index' (duration: 793.377937ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:56.218103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37048705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:02:56.218228Z","caller":"traceutil/trace.go:171","msg":"trace[1909026617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"1.370648123s","start":"2026-03-19T09:02:54.847553Z","end":"2026-03-19T09:02:56.218201Z","steps":["trace[1909026617] 'agreement among raft nodes before linearized reading' (duration: 1.370443708s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:56.218271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:54.84754Z","time spent":"1.370721545s","remote":"127.0.0.1:52422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T09:02:56.218274Z","caller":"traceutil/trace.go:171","msg":"trace[1924904041] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"1.4265379s","start":"2026-03-19T09:02:54.791696Z","end":"2026-03-19T09:02:56.218234Z","steps":["trace[1924904041] 'process raft request' (duration: 1.421918085s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:56.218413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.359752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-19T09:02:56.218438Z","caller":"traceutil/trace.go:171","msg":"trace[1239217480] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1009; }","duration":"762.423043ms","start":"2026-03-19T09:02:55.456008Z","end":"2026-03-19T09:02:56.218431Z","steps":["trace[1239217480] 'agreement among raft nodes before linearized reading' (duration: 762.362962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:56.218459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:55.455986Z","time spent":"762.468175ms","remote":"127.0.0.1:52734","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":"warn","ts":"2026-03-19T09:02:56.218463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:54.791682Z","time spent":"1.426701704s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:56.218349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322849727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-19T09:02:56.218588Z","caller":"traceutil/trace.go:171","msg":"trace[411962003] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:1009; }","duration":"1.323204976s","start":"2026-03-19T09:02:54.895377Z","end":"2026-03-19T09:02:56.218582Z","steps":["trace[411962003] 'agreement among raft nodes before linearized reading' (duration: 1.322830115s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:56.218604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:54.895362Z","time spent":"1.323237517s","remote":"127.0.0.1:52480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.207\" "} {"level":"warn","ts":"2026-03-19T09:02:56.21859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.031909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-19T09:02:56.21859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.583418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-19T09:02:56.218662Z","caller":"traceutil/trace.go:171","msg":"trace[113828418] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"762.699391ms","start":"2026-03-19T09:02:55.455949Z","end":"2026-03-19T09:02:56.218648Z","steps":["trace[113828418] 'agreement among raft nodes before linearized reading' (duration: 762.468125ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:56.21866Z","caller":"traceutil/trace.go:171","msg":"trace[154030749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"796.150101ms","start":"2026-03-19T09:02:55.422493Z","end":"2026-03-19T09:02:56.218643Z","steps":["trace[154030749] 'agreement among raft nodes before linearized reading' (duration: 795.977017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:56.218703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:55.422474Z","time spent":"796.221803ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T09:02:56.218703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:55.455933Z","time spent":"762.762323ms","remote":"127.0.0.1:52734","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-03-19T09:02:56.973288Z","caller":"traceutil/trace.go:171","msg":"trace[755978078] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"749.646829ms","start":"2026-03-19T09:02:56.223611Z","end":"2026-03-19T09:02:56.973258Z","steps":["trace[755978078] 'process raft request' (duration: 749.527046ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:56.973468Z","caller":"traceutil/trace.go:171","msg":"trace[1559135496] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1052; }","duration":"746.902964ms","start":"2026-03-19T09:02:56.226548Z","end":"2026-03-19T09:02:56.973451Z","steps":["trace[1559135496] 'read index received' (duration: 10.895753ms)","trace[1559135496] 'applied index is now lower than readState.Index' (duration: 736.004791ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:56.973593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:56.223597Z","time spent":"749.870625ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:02:56.973332Z","caller":"traceutil/trace.go:171","msg":"trace[2113810872] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"750.937783ms","start":"2026-03-19T09:02:56.222352Z","end":"2026-03-19T09:02:56.97329Z","steps":["trace[2113810872] 'process raft request' (duration: 750.631035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:56.973799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:56.222306Z","time spent":"751.395766ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:56.973638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.07035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:02:56.973611Z","caller":"traceutil/trace.go:171","msg":"trace[247785234] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"749.902586ms","start":"2026-03-19T09:02:56.223645Z","end":"2026-03-19T09:02:56.973547Z","steps":["trace[247785234] 'process raft request' (duration: 749.563207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:56.974129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:56.223616Z","time spent":"750.476221ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:02:56.973948Z","caller":"traceutil/trace.go:171","msg":"trace[783943812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1012; }","duration":"747.395629ms","start":"2026-03-19T09:02:56.226477Z","end":"2026-03-19T09:02:56.973872Z","steps":["trace[783943812] 'agreement among raft nodes before linearized reading' (duration: 747.08106ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:56.974284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:56.226455Z","time spent":"747.80971ms","remote":"127.0.0.1:52422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T09:02:56.998477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.036297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T09:02:56.998536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.548768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:02:56.998554Z","caller":"traceutil/trace.go:171","msg":"trace[1576358853] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1013; }","duration":"467.172112ms","start":"2026-03-19T09:02:56.531366Z","end":"2026-03-19T09:02:56.998538Z","steps":["trace[1576358853] 'agreement among raft nodes before linearized reading' (duration: 467.040299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:56.998589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:56.53135Z","time spent":"467.232233ms","remote":"127.0.0.1:40336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-03-19T09:02:56.99857Z","caller":"traceutil/trace.go:171","msg":"trace[1198972533] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1013; }","duration":"579.61187ms","start":"2026-03-19T09:02:56.418944Z","end":"2026-03-19T09:02:56.998556Z","steps":["trace[1198972533] 'agreement among raft nodes before linearized reading' (duration: 579.57507ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:56.998649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:56.418922Z","time spent":"579.717513ms","remote":"127.0.0.1:40312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-03-19T09:02:56.998482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.981102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T09:02:56.998855Z","caller":"traceutil/trace.go:171","msg":"trace[202989639] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"760.178003ms","start":"2026-03-19T09:02:56.238665Z","end":"2026-03-19T09:02:56.998843Z","steps":["trace[202989639] 'process raft request' (duration: 759.611358ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:56.998981Z","caller":"traceutil/trace.go:171","msg":"trace[1090380648] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1013; }","duration":"667.523347ms","start":"2026-03-19T09:02:56.33143Z","end":"2026-03-19T09:02:56.998953Z","steps":["trace[1090380648] 'agreement among raft nodes before linearized reading' (duration: 666.950002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:56.999279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:56.238647Z","time spent":"760.580533ms","remote":"127.0.0.1:52480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:56.999295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:56.331402Z","time spent":"667.842036ms","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-19T09:02:58.352615Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058805141290190,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T09:02:58.65884Z","caller":"traceutil/trace.go:171","msg":"trace[940106903] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.225199876s","start":"2026-03-19T09:02:57.433616Z","end":"2026-03-19T09:02:58.658815Z","steps":["trace[940106903] 'process raft request' (duration: 1.225043572s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:58.659079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:57.433592Z","time spent":"1.225402102s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:02:58.678273Z","caller":"traceutil/trace.go:171","msg":"trace[2044003444] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1056; }","duration":"826.672863ms","start":"2026-03-19T09:02:57.851577Z","end":"2026-03-19T09:02:58.67825Z","steps":["trace[2044003444] 'read index received' (duration: 807.048555ms)","trace[2044003444] 'applied index is now lower than readState.Index' (duration: 19.623248ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:58.678401Z","caller":"traceutil/trace.go:171","msg":"trace[1910590986] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"739.248739ms","start":"2026-03-19T09:02:57.939135Z","end":"2026-03-19T09:02:58.678383Z","steps":["trace[1910590986] 'process raft request' (duration: 738.983162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:58.678484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:57.939104Z","time spent":"739.336142ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:58.678673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.318334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-03-19T09:02:58.678683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.295228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T09:02:58.678747Z","caller":"traceutil/trace.go:171","msg":"trace[1315746058] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1015; }","duration":"461.425247ms","start":"2026-03-19T09:02:58.217304Z","end":"2026-03-19T09:02:58.678729Z","steps":["trace[1315746058] 'agreement among raft nodes before linearized reading' (duration: 461.222921ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:58.678757Z","caller":"traceutil/trace.go:171","msg":"trace[187623814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1015; }","duration":"456.398462ms","start":"2026-03-19T09:02:58.22234Z","end":"2026-03-19T09:02:58.678739Z","steps":["trace[187623814] 'agreement among raft nodes before linearized reading' (duration: 456.214027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:58.678804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:58.222321Z","time spent":"456.474223ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T09:02:58.67881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:58.217285Z","time spent":"461.511929ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-19T09:02:58.679173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.887719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:02:58.679269Z","caller":"traceutil/trace.go:171","msg":"trace[2090947152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"827.722191ms","start":"2026-03-19T09:02:57.851534Z","end":"2026-03-19T09:02:58.679256Z","steps":["trace[2090947152] 'agreement among raft nodes before linearized reading' (duration: 826.839127ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:58.679315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:57.851514Z","time spent":"827.789134ms","remote":"127.0.0.1:52422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T09:02:59.210787Z","caller":"traceutil/trace.go:171","msg":"trace[772188162] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"527.012193ms","start":"2026-03-19T09:02:58.683748Z","end":"2026-03-19T09:02:59.21076Z","steps":["trace[772188162] 'process raft request' (duration: 435.107148ms)","trace[772188162] 'compare' (duration: 91.803322ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:59.210951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:58.683698Z","time spent":"527.177097ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:02:59.218215Z","caller":"traceutil/trace.go:171","msg":"trace[849177191] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1058; }","duration":"239.873981ms","start":"2026-03-19T09:02:58.978307Z","end":"2026-03-19T09:02:59.218181Z","steps":["trace[849177191] 'read index received' (duration: 140.562246ms)","trace[849177191] 'applied index is now lower than readState.Index' (duration: 99.310265ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:59.218367Z","caller":"traceutil/trace.go:171","msg":"trace[43054094] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"534.167265ms","start":"2026-03-19T09:02:58.684154Z","end":"2026-03-19T09:02:59.218321Z","steps":["trace[43054094] 'process raft request' (duration: 533.779215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:59.218531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.190048ms","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-03-19T09:02:59.218585Z","caller":"traceutil/trace.go:171","msg":"trace[228434945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1017; }","duration":"240.312721ms","start":"2026-03-19T09:02:58.978257Z","end":"2026-03-19T09:02:59.21857Z","steps":["trace[228434945] 'agreement among raft nodes before linearized reading' (duration: 240.103855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:59.218586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:02:58.684132Z","time spent":"534.34806ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:02:59.218664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.309731ms","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-03-19T09:02:59.218757Z","caller":"traceutil/trace.go:171","msg":"trace[195544897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1017; }","duration":"240.439096ms","start":"2026-03-19T09:02:58.978302Z","end":"2026-03-19T09:02:59.218741Z","steps":["trace[195544897] 'agreement among raft nodes before linearized reading' (duration: 240.125907ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:02:59.218691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.23528ms","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-03-19T09:02:59.218875Z","caller":"traceutil/trace.go:171","msg":"trace[973148653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1017; }","duration":"240.486386ms","start":"2026-03-19T09:02:58.978367Z","end":"2026-03-19T09:02:59.218853Z","steps":["trace[973148653] 'agreement among raft nodes before linearized reading' (duration: 240.106135ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:03:00.723291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.584648ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-19T09:03:01.327019Z","caller":"traceutil/trace.go:171","msg":"trace[251141631] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"108.275447ms","start":"2026-03-19T09:03:01.218708Z","end":"2026-03-19T09:03:01.326983Z","steps":["trace[251141631] 'process raft request' (duration: 108.122642ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:03:10.53868Z","caller":"traceutil/trace.go:171","msg":"trace[195975253] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"129.508238ms","start":"2026-03-19T09:03:10.409138Z","end":"2026-03-19T09:03:10.538646Z","steps":["trace[195975253] 'process raft request' (duration: 129.22208ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:03:10.687846Z","caller":"traceutil/trace.go:171","msg":"trace[1351762612] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"119.338963ms","start":"2026-03-19T09:03:10.568475Z","end":"2026-03-19T09:03:10.687814Z","steps":["trace[1351762612] 'process raft request' (duration: 119.166508ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:03:17.538611Z","caller":"traceutil/trace.go:171","msg":"trace[208900960] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"102.472891ms","start":"2026-03-19T09:03:17.435919Z","end":"2026-03-19T09:03:17.538392Z","steps":["trace[208900960] 'process raft request' (duration: 102.305186ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:03:17.786632Z","caller":"traceutil/trace.go:171","msg":"trace[1305282507] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"244.485444ms","start":"2026-03-19T09:03:17.542124Z","end":"2026-03-19T09:03:17.78661Z","steps":["trace[1305282507] 'process raft request' (duration: 242.943032ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:03:17.786871Z","caller":"traceutil/trace.go:171","msg":"trace[6843838] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"244.233228ms","start":"2026-03-19T09:03:17.542606Z","end":"2026-03-19T09:03:17.786839Z","steps":["trace[6843838] 'process raft request' (duration: 243.96594ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:03:17.787059Z","caller":"traceutil/trace.go:171","msg":"trace[919723829] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"243.849567ms","start":"2026-03-19T09:03:17.543159Z","end":"2026-03-19T09:03:17.787009Z","steps":["trace[919723829] 'process raft request' (duration: 243.628181ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:03:19.246103Z","caller":"traceutil/trace.go:171","msg":"trace[1895410128] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"221.34347ms","start":"2026-03-19T09:03:19.024713Z","end":"2026-03-19T09:03:19.246057Z","steps":["trace[1895410128] 'process raft request' (duration: 221.189066ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:03:19.605579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.501131ms","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:525"} {"level":"info","ts":"2026-03-19T09:03:19.605849Z","caller":"traceutil/trace.go:171","msg":"trace[2142525977] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1124; }","duration":"275.633823ms","start":"2026-03-19T09:03:19.330007Z","end":"2026-03-19T09:03:19.605641Z","steps":["trace[2142525977] 'range keys from in-memory index tree' (duration: 275.342076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:03:19.606475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.118018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-19T09:03:19.607248Z","caller":"traceutil/trace.go:171","msg":"trace[23863129] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1124; }","duration":"205.929757ms","start":"2026-03-19T09:03:19.401294Z","end":"2026-03-19T09:03:19.607223Z","steps":["trace[23863129] 'range keys from in-memory index tree' (duration: 203.776248ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:03:19.761506Z","caller":"traceutil/trace.go:171","msg":"trace[303343129] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"149.513497ms","start":"2026-03-19T09:03:19.611965Z","end":"2026-03-19T09:03:19.761478Z","steps":["trace[303343129] 'process raft request' (duration: 149.321752ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:03:20.033072Z","caller":"traceutil/trace.go:171","msg":"trace[2027667548] linearizableReadLoop","detail":"{readStateIndex:1174; appliedIndex:1171; }","duration":"303.518864ms","start":"2026-03-19T09:03:19.729529Z","end":"2026-03-19T09:03:20.033048Z","steps":["trace[2027667548] 'read index received' (duration: 31.749665ms)","trace[2027667548] 'applied index is now lower than readState.Index' (duration: 271.767759ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:03:20.033181Z","caller":"traceutil/trace.go:171","msg":"trace[2016228438] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"421.118421ms","start":"2026-03-19T09:03:19.612025Z","end":"2026-03-19T09:03:20.033144Z","steps":["trace[2016228438] 'process raft request' (duration: 420.979338ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:03:20.033198Z","caller":"traceutil/trace.go:171","msg":"trace[327508712] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"421.176253ms","start":"2026-03-19T09:03:19.611998Z","end":"2026-03-19T09:03:20.033175Z","steps":["trace[327508712] 'process raft request' (duration: 420.867185ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:03:20.033252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.706429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:03:20.033304Z","caller":"traceutil/trace.go:171","msg":"trace[352428267] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1127; }","duration":"303.820642ms","start":"2026-03-19T09:03:19.729472Z","end":"2026-03-19T09:03:20.033293Z","steps":["trace[352428267] 'agreement among raft nodes before linearized reading' (duration: 303.718649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:03:20.033332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:03:19.611978Z","time spent":"421.278165ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:03:20.033332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:03:19.612001Z","time spent":"421.251365ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:03:20.033342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:03:19.729449Z","time spent":"303.882055ms","remote":"127.0.0.1:40324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-19T09:03:20.20824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.050688ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-19T09:03:20.208338Z","caller":"traceutil/trace.go:171","msg":"trace[712430223] linearizableReadLoop","detail":"{readStateIndex:1175; appliedIndex:1174; }","duration":"175.156457ms","start":"2026-03-19T09:03:20.033163Z","end":"2026-03-19T09:03:20.208319Z","steps":["trace[712430223] 'read index received' (duration: 13.944876ms)","trace[712430223] 'applied index is now lower than readState.Index' (duration: 161.210081ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:03:20.208638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.588736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-19T09:03:20.208652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.306789ms","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-03-19T09:03:20.208798Z","caller":"traceutil/trace.go:171","msg":"trace[94409082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1127; }","duration":"417.557916ms","start":"2026-03-19T09:03:19.791219Z","end":"2026-03-19T09:03:20.208777Z","steps":["trace[94409082] 'agreement among raft nodes before linearized reading' (duration: 417.192735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:03:20.208877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.046149ms","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-03-19T09:03:20.208709Z","caller":"traceutil/trace.go:171","msg":"trace[2143720550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1127; }","duration":"417.72356ms","start":"2026-03-19T09:03:19.790968Z","end":"2026-03-19T09:03:20.208692Z","steps":["trace[2143720550] 'agreement among raft nodes before linearized reading' (duration: 417.495214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:03:20.209153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:03:19.790936Z","time spent":"418.132141ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T09:03:20.208885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:03:19.791201Z","time spent":"417.671429ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-19T09:03:20.208944Z","caller":"traceutil/trace.go:171","msg":"trace[1740814118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1127; }","duration":"418.166062ms","start":"2026-03-19T09:03:19.790744Z","end":"2026-03-19T09:03:20.20891Z","steps":["trace[1740814118] 'agreement among raft nodes before linearized reading' (duration: 417.854193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:03:20.209431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:03:19.790708Z","time spent":"418.703417ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T09:03:20.690815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.530988ms","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":"info","ts":"2026-03-19T09:03:20.691363Z","caller":"traceutil/trace.go:171","msg":"trace[332279437] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1130; }","duration":"358.133696ms","start":"2026-03-19T09:03:20.333208Z","end":"2026-03-19T09:03:20.691341Z","steps":["trace[332279437] 'count revisions from in-memory index tree' (duration: 357.435176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:03:20.69145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:03:20.333183Z","time spent":"358.246618ms","remote":"127.0.0.1:52690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-03-19T09:03:20.690928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.448002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-19T09:03:20.691607Z","caller":"traceutil/trace.go:171","msg":"trace[1205425728] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1130; }","duration":"141.158382ms","start":"2026-03-19T09:03:20.550397Z","end":"2026-03-19T09:03:20.691555Z","steps":["trace[1205425728] 'range keys from in-memory index tree' (duration: 140.275568ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:03:20.812042Z","caller":"traceutil/trace.go:171","msg":"trace[80194929] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"117.400172ms","start":"2026-03-19T09:03:20.694616Z","end":"2026-03-19T09:03:20.812016Z","steps":["trace[80194929] 'process raft request' (duration: 117.161915ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:03:20.856196Z","caller":"traceutil/trace.go:171","msg":"trace[281012769] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"159.773953ms","start":"2026-03-19T09:03:20.696395Z","end":"2026-03-19T09:03:20.856169Z","steps":["trace[281012769] 'process raft request' (duration: 159.510206ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:03:22.803223Z","caller":"traceutil/trace.go:171","msg":"trace[85605298] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"510.811087ms","start":"2026-03-19T09:03:22.292333Z","end":"2026-03-19T09:03:22.803144Z","steps":["trace[85605298] 'process raft request' (duration: 510.56212ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:03:22.803185Z","caller":"traceutil/trace.go:171","msg":"trace[1977683757] linearizableReadLoop","detail":"{readStateIndex:1186; appliedIndex:1185; }","duration":"257.214917ms","start":"2026-03-19T09:03:22.545862Z","end":"2026-03-19T09:03:22.803077Z","steps":["trace[1977683757] 'read index received' (duration: 256.916569ms)","trace[1977683757] 'applied index is now lower than readState.Index' (duration: 296.718µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:03:22.803329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.453523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-03-19T09:03:22.803407Z","caller":"traceutil/trace.go:171","msg":"trace[1161952526] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1138; }","duration":"257.580597ms","start":"2026-03-19T09:03:22.545807Z","end":"2026-03-19T09:03:22.803388Z","steps":["trace[1161952526] 'agreement among raft nodes before linearized reading' (duration: 257.377592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:03:22.803484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:03:22.292306Z","time spent":"511.044124ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:03:42.757128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.638741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:03:42.757249Z","caller":"traceutil/trace.go:171","msg":"trace[1171235051] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1268; }","duration":"316.529992ms","start":"2026-03-19T09:03:42.440698Z","end":"2026-03-19T09:03:42.757228Z","steps":["trace[1171235051] 'read index received' (duration: 23.827295ms)","trace[1171235051] 'applied index is now lower than readState.Index' (duration: 292.701097ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:03:42.757302Z","caller":"traceutil/trace.go:171","msg":"trace[67570] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"353.182426ms","start":"2026-03-19T09:03:42.404073Z","end":"2026-03-19T09:03:42.757255Z","steps":["trace[67570] 'process raft request' (duration: 148.323918ms)","trace[67570] 'compare' (duration: 204.329892ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:03:42.757476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.77108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-19T09:03:42.757454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.061576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:03:42.757545Z","caller":"traceutil/trace.go:171","msg":"trace[124139210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1218; }","duration":"316.880772ms","start":"2026-03-19T09:03:42.440647Z","end":"2026-03-19T09:03:42.757528Z","steps":["trace[124139210] 'agreement among raft nodes before linearized reading' (duration: 316.706017ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:03:42.757568Z","caller":"traceutil/trace.go:171","msg":"trace[1084890035] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1218; }","duration":"164.225062ms","start":"2026-03-19T09:03:42.593318Z","end":"2026-03-19T09:03:42.757544Z","steps":["trace[1084890035] 'agreement among raft nodes before linearized reading' (duration: 164.031987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:03:42.757591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:03:42.440628Z","time spent":"316.949624ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T09:03:42.757482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:03:42.40406Z","time spent":"353.33089ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:03:42.88963Z","caller":"traceutil/trace.go:171","msg":"trace[1943735263] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"127.95483ms","start":"2026-03-19T09:03:42.761643Z","end":"2026-03-19T09:03:42.889598Z","steps":["trace[1943735263] 'process raft request' (duration: 101.837096ms)","trace[1943735263] 'compare' (duration: 25.973879ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:04:15.20569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.3429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:04:15.205803Z","caller":"traceutil/trace.go:171","msg":"trace[129152794] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1350; }","duration":"101.527565ms","start":"2026-03-19T09:04:15.104248Z","end":"2026-03-19T09:04:15.205776Z","steps":["trace[129152794] 'count revisions from in-memory index tree' (duration: 101.235887ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:04:23.563916Z","caller":"traceutil/trace.go:171","msg":"trace[783391913] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1442; }","duration":"141.295784ms","start":"2026-03-19T09:04:23.422594Z","end":"2026-03-19T09:04:23.56389Z","steps":["trace[783391913] 'read index received' (duration: 20.828369ms)","trace[783391913] 'applied index is now lower than readState.Index' (duration: 120.466255ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:04:23.564059Z","caller":"traceutil/trace.go:171","msg":"trace[1767659306] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"151.487903ms","start":"2026-03-19T09:04:23.412517Z","end":"2026-03-19T09:04:23.564005Z","steps":["trace[1767659306] 'process raft request' (duration: 71.733872ms)","trace[1767659306] 'compare' (duration: 79.523825ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:04:23.564264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.644453ms","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":"warn","ts":"2026-03-19T09:04:23.564312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.41404ms","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-03-19T09:04:23.564353Z","caller":"traceutil/trace.go:171","msg":"trace[1906898646] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1384; }","duration":"141.781438ms","start":"2026-03-19T09:04:23.422547Z","end":"2026-03-19T09:04:23.564329Z","steps":["trace[1906898646] 'agreement among raft nodes before linearized reading' (duration: 141.462529ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:04:23.564383Z","caller":"traceutil/trace.go:171","msg":"trace[1789598659] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1384; }","duration":"140.517713ms","start":"2026-03-19T09:04:23.423852Z","end":"2026-03-19T09:04:23.564369Z","steps":["trace[1789598659] 'agreement among raft nodes before linearized reading' (duration: 140.355749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:04:24.209324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.915639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:04:24.209608Z","caller":"traceutil/trace.go:171","msg":"trace[584398948] linearizableReadLoop","detail":"{readStateIndex:1447; appliedIndex:1444; }","duration":"370.312887ms","start":"2026-03-19T09:04:23.839272Z","end":"2026-03-19T09:04:24.209584Z","steps":["trace[584398948] 'read index received' (duration: 137.375597ms)","trace[584398948] 'applied index is now lower than readState.Index' (duration: 232.93579ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:04:24.209667Z","caller":"traceutil/trace.go:171","msg":"trace[1677737556] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"640.344533ms","start":"2026-03-19T09:04:23.569279Z","end":"2026-03-19T09:04:24.209623Z","steps":["trace[1677737556] 'process raft request' (duration: 640.175448ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:04:24.209745Z","caller":"traceutil/trace.go:171","msg":"trace[1804503009] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"640.452476ms","start":"2026-03-19T09:04:23.569282Z","end":"2026-03-19T09:04:24.209735Z","steps":["trace[1804503009] 'process raft request' (duration: 640.24655ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:04:24.209814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:04:23.56926Z","time spent":"640.519878ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:04:24.209822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:04:23.56926Z","time spent":"640.483127ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:04:24.209852Z","caller":"traceutil/trace.go:171","msg":"trace[1322816982] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"640.849256ms","start":"2026-03-19T09:04:23.568951Z","end":"2026-03-19T09:04:24.2098Z","steps":["trace[1322816982] 'process raft request' (duration: 180.356272ms)","trace[1322816982] 'compare' (duration: 459.802546ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:04:24.209705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.424471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T09:04:24.20995Z","caller":"traceutil/trace.go:171","msg":"trace[1455160529] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1387; }","duration":"370.709058ms","start":"2026-03-19T09:04:23.83922Z","end":"2026-03-19T09:04:24.209929Z","steps":["trace[1455160529] 'agreement among raft nodes before linearized reading' (duration: 370.437401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:04:24.209993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:04:23.568928Z","time spent":"640.998861ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:04:24.210009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:04:23.839199Z","time spent":"370.790101ms","remote":"127.0.0.1:52730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-03-19T09:04:25.119471Z","caller":"traceutil/trace.go:171","msg":"trace[967218835] linearizableReadLoop","detail":"{readStateIndex:1449; appliedIndex:1448; }","duration":"169.020973ms","start":"2026-03-19T09:04:24.950423Z","end":"2026-03-19T09:04:25.119444Z","steps":["trace[967218835] 'read index received' (duration: 168.813757ms)","trace[967218835] 'applied index is now lower than readState.Index' (duration: 205.526µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:04:25.119969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.494915ms","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:525"} {"level":"info","ts":"2026-03-19T09:04:25.12011Z","caller":"traceutil/trace.go:171","msg":"trace[646712839] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1388; }","duration":"169.6822ms","start":"2026-03-19T09:04:24.950388Z","end":"2026-03-19T09:04:25.12007Z","steps":["trace[646712839] 'agreement among raft nodes before linearized reading' (duration: 169.167257ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:04:25.39431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.609807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:04:25.394474Z","caller":"traceutil/trace.go:171","msg":"trace[652078151] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"272.527983ms","start":"2026-03-19T09:04:25.121922Z","end":"2026-03-19T09:04:25.39445Z","steps":["trace[652078151] 'process raft request' (duration: 90.66238ms)","trace[652078151] 'compare' (duration: 181.38607ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:04:25.445096Z","caller":"traceutil/trace.go:171","msg":"trace[959868919] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1450; }","duration":"149.299813ms","start":"2026-03-19T09:04:25.295779Z","end":"2026-03-19T09:04:25.445079Z","steps":["trace[959868919] 'read index received' (duration: 149.044526ms)","trace[959868919] 'applied index is now lower than readState.Index' (duration: 254.267µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:04:25.445428Z","caller":"traceutil/trace.go:171","msg":"trace[536209128] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"320.70721ms","start":"2026-03-19T09:04:25.12471Z","end":"2026-03-19T09:04:25.445417Z","steps":["trace[536209128] 'process raft request' (duration: 320.216067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:04:25.445613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:04:25.124687Z","time spent":"320.777493ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:04:25.445739Z","caller":"traceutil/trace.go:171","msg":"trace[2138741830] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"320.327601ms","start":"2026-03-19T09:04:25.125342Z","end":"2026-03-19T09:04:25.445669Z","steps":["trace[2138741830] 'process raft request' (duration: 319.696433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:04:25.445956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:04:25.125332Z","time spent":"320.507866ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:04:25.446063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.28944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-19T09:04:25.446113Z","caller":"traceutil/trace.go:171","msg":"trace[1866046816] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1391; }","duration":"150.401404ms","start":"2026-03-19T09:04:25.295699Z","end":"2026-03-19T09:04:25.4461Z","steps":["trace[1866046816] 'agreement among raft nodes before linearized reading' (duration: 150.246849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:04:25.87582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.823125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:04:25.876004Z","caller":"traceutil/trace.go:171","msg":"trace[926109895] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"425.079956ms","start":"2026-03-19T09:04:25.450905Z","end":"2026-03-19T09:04:25.875985Z","steps":["trace[926109895] 'process raft request' (duration: 73.973114ms)","trace[926109895] 'compare' (duration: 350.583438ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:04:25.876073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:04:25.450881Z","time spent":"425.162198ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:04:25.876198Z","caller":"traceutil/trace.go:171","msg":"trace[963369651] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"424.465968ms","start":"2026-03-19T09:04:25.451681Z","end":"2026-03-19T09:04:25.876147Z","steps":["trace[963369651] 'process raft request' (duration: 424.245952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:04:25.876473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:04:25.451673Z","time spent":"424.601712ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:04:26.182112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.989672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-19T09:04:26.182199Z","caller":"traceutil/trace.go:171","msg":"trace[968896048] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1393; }","duration":"116.146077ms","start":"2026-03-19T09:04:26.066032Z","end":"2026-03-19T09:04:26.182178Z","steps":["trace[968896048] 'range keys from in-memory index tree' (duration: 115.780086ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:04:26.332551Z","caller":"traceutil/trace.go:171","msg":"trace[620033852] linearizableReadLoop","detail":"{readStateIndex:1455; appliedIndex:1454; }","duration":"119.101688ms","start":"2026-03-19T09:04:26.213419Z","end":"2026-03-19T09:04:26.33252Z","steps":["trace[620033852] 'read index received' (duration: 118.725807ms)","trace[620033852] 'applied index is now lower than readState.Index' (duration: 374.481µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:04:26.332937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.447467ms","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-03-19T09:04:26.333229Z","caller":"traceutil/trace.go:171","msg":"trace[1616899949] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1394; }","duration":"119.825297ms","start":"2026-03-19T09:04:26.213386Z","end":"2026-03-19T09:04:26.333211Z","steps":["trace[1616899949] 'agreement among raft nodes before linearized reading' (duration: 119.300303ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:04:26.332762Z","caller":"traceutil/trace.go:171","msg":"trace[2104864947] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"145.330845ms","start":"2026-03-19T09:04:26.187298Z","end":"2026-03-19T09:04:26.332629Z","steps":["trace[2104864947] 'process raft request' (duration: 145.004706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:04:26.332936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.936055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T09:04:26.33376Z","caller":"traceutil/trace.go:171","msg":"trace[1938303758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1394; }","duration":"118.816409ms","start":"2026-03-19T09:04:26.214918Z","end":"2026-03-19T09:04:26.333734Z","steps":["trace[1938303758] 'agreement among raft nodes before linearized reading' (duration: 117.906414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:04:26.333014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.207733ms","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-03-19T09:04:26.333905Z","caller":"traceutil/trace.go:171","msg":"trace[1331973649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1394; }","duration":"119.137438ms","start":"2026-03-19T09:04:26.214752Z","end":"2026-03-19T09:04:26.333889Z","steps":["trace[1331973649] 'agreement among raft nodes before linearized reading' (duration: 118.066328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:04:44.656337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.968533ms","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":"warn","ts":"2026-03-19T09:04:44.656388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.822236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T09:04:44.656447Z","caller":"traceutil/trace.go:171","msg":"trace[1455026039] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1467; }","duration":"127.125246ms","start":"2026-03-19T09:04:44.529297Z","end":"2026-03-19T09:04:44.656422Z","steps":["trace[1455026039] 'range keys from in-memory index tree' (duration: 126.800478ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:04:44.656466Z","caller":"traceutil/trace.go:171","msg":"trace[465561239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1467; }","duration":"127.951809ms","start":"2026-03-19T09:04:44.528494Z","end":"2026-03-19T09:04:44.656445Z","steps":["trace[465561239] 'range keys from in-memory index tree' (duration: 127.638972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:04:45.048247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.418711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:04:45.04857Z","caller":"traceutil/trace.go:171","msg":"trace[748522948] linearizableReadLoop","detail":"{readStateIndex:1534; appliedIndex:1533; }","duration":"145.478738ms","start":"2026-03-19T09:04:44.903072Z","end":"2026-03-19T09:04:45.048551Z","steps":["trace[748522948] 'read index received' (duration: 44.051µs)","trace[748522948] 'applied index is now lower than readState.Index' (duration: 145.430427ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:04:45.048648Z","caller":"traceutil/trace.go:171","msg":"trace[590260804] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"315.633542ms","start":"2026-03-19T09:04:44.732982Z","end":"2026-03-19T09:04:45.048616Z","steps":["trace[590260804] 'process raft request' (duration: 31.765549ms)","trace[590260804] 'compare' (duration: 283.318848ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:04:45.048717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.639974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-19T09:04:45.048753Z","caller":"traceutil/trace.go:171","msg":"trace[979593541] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:1470; }","duration":"145.751256ms","start":"2026-03-19T09:04:44.902991Z","end":"2026-03-19T09:04:45.048743Z","steps":["trace[979593541] 'agreement among raft nodes before linearized reading' (duration: 145.620622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:04:45.048803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:04:44.732964Z","time spent":"315.764665ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:04:45.240438Z","caller":"traceutil/trace.go:171","msg":"trace[839301688] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"129.41551ms","start":"2026-03-19T09:04:45.110996Z","end":"2026-03-19T09:04:45.240411Z","steps":["trace[839301688] 'process raft request' (duration: 127.810396ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:05:18.098293Z","caller":"traceutil/trace.go:171","msg":"trace[788890044] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"216.776457ms","start":"2026-03-19T09:05:17.881485Z","end":"2026-03-19T09:05:18.098262Z","steps":["trace[788890044] 'process raft request' (duration: 216.645694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:06:07.385306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.748082ms","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:531"} {"level":"info","ts":"2026-03-19T09:06:07.385524Z","caller":"traceutil/trace.go:171","msg":"trace[825030815] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1830; }","duration":"112.010548ms","start":"2026-03-19T09:06:07.27349Z","end":"2026-03-19T09:06:07.3855Z","steps":["trace[825030815] 'range keys from in-memory index tree' (duration: 111.571506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:06:15.217538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.753997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T09:06:15.21762Z","caller":"traceutil/trace.go:171","msg":"trace[1409186948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1866; }","duration":"158.891401ms","start":"2026-03-19T09:06:15.05871Z","end":"2026-03-19T09:06:15.217601Z","steps":["trace[1409186948] 'range keys from in-memory index tree' (duration: 158.573652ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:06:25.0438Z","caller":"traceutil/trace.go:171","msg":"trace[1123097880] linearizableReadLoop","detail":"{readStateIndex:1996; appliedIndex:1995; }","duration":"119.946638ms","start":"2026-03-19T09:06:24.923824Z","end":"2026-03-19T09:06:25.043771Z","steps":["trace[1123097880] 'read index received' (duration: 86.857305ms)","trace[1123097880] 'applied index is now lower than readState.Index' (duration: 33.087753ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:06:25.043967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.131252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-03-19T09:06:25.044013Z","caller":"traceutil/trace.go:171","msg":"trace[1928744682] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation; range_end:; response_count:1; response_revision:1910; }","duration":"120.187414ms","start":"2026-03-19T09:06:24.923811Z","end":"2026-03-19T09:06:25.043999Z","steps":["trace[1928744682] 'agreement among raft nodes before linearized reading' (duration: 120.087811ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:06:25.075787Z","caller":"traceutil/trace.go:171","msg":"trace[797654186] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"142.399256ms","start":"2026-03-19T09:06:24.933355Z","end":"2026-03-19T09:06:25.075754Z","steps":["trace[797654186] 'process raft request' (duration: 142.18503ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:06:29.880916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.882971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:06:29.881043Z","caller":"traceutil/trace.go:171","msg":"trace[987917616] linearizableReadLoop","detail":"{readStateIndex:2020; appliedIndex:2019; }","duration":"288.378221ms","start":"2026-03-19T09:06:29.592647Z","end":"2026-03-19T09:06:29.881025Z","steps":["trace[987917616] 'read index received' (duration: 88.227482ms)","trace[987917616] 'applied index is now lower than readState.Index' (duration: 200.149359ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:06:29.881168Z","caller":"traceutil/trace.go:171","msg":"trace[1662238427] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"290.712906ms","start":"2026-03-19T09:06:29.59039Z","end":"2026-03-19T09:06:29.881102Z","steps":["trace[1662238427] 'process raft request' (duration: 90.557687ms)","trace[1662238427] 'compare' (duration: 199.751738ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:06:29.881496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.834104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:replication-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-03-19T09:06:29.881763Z","caller":"traceutil/trace.go:171","msg":"trace[1319596405] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:replication-controller; range_end:; response_count:1; response_revision:1934; }","duration":"289.092481ms","start":"2026-03-19T09:06:29.592621Z","end":"2026-03-19T09:06:29.881713Z","steps":["trace[1319596405] 'agreement among raft nodes before linearized reading' (duration: 288.478264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:06:29.881839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.592851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-03-19T09:06:29.881978Z","caller":"traceutil/trace.go:171","msg":"trace[2055032424] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:1934; }","duration":"237.778766ms","start":"2026-03-19T09:06:29.644164Z","end":"2026-03-19T09:06:29.881943Z","steps":["trace[2055032424] 'agreement among raft nodes before linearized reading' (duration: 237.020635ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:06:30.122437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.200552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:06:30.122584Z","caller":"traceutil/trace.go:171","msg":"trace[1562694633] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1935; }","duration":"121.366326ms","start":"2026-03-19T09:06:30.00116Z","end":"2026-03-19T09:06:30.122526Z","steps":["trace[1562694633] 'count revisions from in-memory index tree' (duration: 121.10965ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:06:36.523411Z","caller":"traceutil/trace.go:171","msg":"trace[1938353014] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2049; }","duration":"308.520546ms","start":"2026-03-19T09:06:36.214865Z","end":"2026-03-19T09:06:36.523385Z","steps":["trace[1938353014] 'read index received' (duration: 308.251539ms)","trace[1938353014] 'applied index is now lower than readState.Index' (duration: 268.097µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:06:36.523474Z","caller":"traceutil/trace.go:171","msg":"trace[1719579293] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"338.956585ms","start":"2026-03-19T09:06:36.184488Z","end":"2026-03-19T09:06:36.523445Z","steps":["trace[1719579293] 'process raft request' (duration: 338.740839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:06:36.523617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:06:36.184473Z","time spent":"339.07415ms","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:06:36.523672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.790824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-dns\" ","response":"range_response_count:1 size:757"} {"level":"info","ts":"2026-03-19T09:06:36.523744Z","caller":"traceutil/trace.go:171","msg":"trace[438785618] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-dns; range_end:; response_count:1; response_revision:1962; }","duration":"308.876477ms","start":"2026-03-19T09:06:36.214821Z","end":"2026-03-19T09:06:36.523698Z","steps":["trace[438785618] 'agreement among raft nodes before linearized reading' (duration: 308.722043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:06:36.524099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.950659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-xlf5b\" ","response":"range_response_count:1 size:11390"} {"level":"warn","ts":"2026-03-19T09:06:36.524156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:06:36.214803Z","time spent":"309.330259ms","remote":"127.0.0.1:52630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":780,"request content":"key:\"/registry/services/endpoints/kube-system/kube-dns\" "} {"level":"info","ts":"2026-03-19T09:06:36.524337Z","caller":"traceutil/trace.go:171","msg":"trace[973987779] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-xlf5b; range_end:; response_count:1; response_revision:1962; }","duration":"264.067091ms","start":"2026-03-19T09:06:36.26008Z","end":"2026-03-19T09:06:36.524147Z","steps":["trace[973987779] 'agreement among raft nodes before linearized reading' (duration: 263.550466ms)"],"step_count":1}