{"level":"warn","ts":"2026-04-26T05:44:57.079397Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-26T05:44:57.079494Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-26T05:44:57.07956Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-26T05:44:57.079575Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-26T05:44:57.079601Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-26T05:44:57.080411Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-04-26T05:44:57.080499Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-26T05:44:57.089348Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.614479ms"} {"level":"info","ts":"2026-04-26T05:44:57.139058Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-04-26T05:44:57.139238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-04-26T05:44:57.139312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-04-26T05:44:57.139339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-26T05:44:57.139365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-04-26T05:44:57.139425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-04-26T05:44:57.161015Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-26T05:44:57.165802Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-26T05:44:57.168293Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-26T05:44:57.171185Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-26T05:44:57.171424Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-26T05:44:57.171541Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-26T05:44:57.171667Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-26T05:44:57.171683Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-26T05:44:57.175889Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-26T05:44:57.176005Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-26T05:44:57.176047Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-26T05:44:57.17624Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-26T05:44:57.176287Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-26T05:44:57.181665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-04-26T05:44:57.181928Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-26T05:44:57.741043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-04-26T05:44:57.741122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-04-26T05:44:57.741173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-04-26T05:44:57.741194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-04-26T05:44:57.741212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-26T05:44:57.741228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-04-26T05:44:57.741247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-26T05:44:57.742142Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-26T05:44:57.742172Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-26T05:44:57.742227Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-26T05:44:57.742439Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-26T05:44:57.742522Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-26T05:44:57.742548Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-26T05:44:57.743133Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-04-26T05:44:57.743297Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-26T05:44:57.743333Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-26T05:44:57.745768Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-04-26T05:44:57.755422Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-26T05:44:59.888774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.22779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T05:44:59.888902Z","caller":"traceutil/trace.go:171","msg":"trace[2094546083] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:69; }","duration":"180.416468ms","start":"2026-04-26T05:44:59.708462Z","end":"2026-04-26T05:44:59.888878Z","steps":["trace[2094546083] 'range keys from in-memory index tree' (duration: 180.091064ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:00.123357Z","caller":"traceutil/trace.go:171","msg":"trace[556356841] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"103.66573ms","start":"2026-04-26T05:45:00.019654Z","end":"2026-04-26T05:45:00.12332Z","steps":["trace[556356841] 'process raft request' (duration: 103.266833ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:00.560988Z","caller":"traceutil/trace.go:171","msg":"trace[1499447144] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"165.51853ms","start":"2026-04-26T05:45:00.395444Z","end":"2026-04-26T05:45:00.560962Z","steps":["trace[1499447144] 'process raft request' (duration: 139.457154ms)","trace[1499447144] 'compare' (duration: 25.968702ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:00.704266Z","caller":"traceutil/trace.go:171","msg":"trace[2131175867] linearizableReadLoop","detail":"{readStateIndex:84; appliedIndex:83; }","duration":"141.168486ms","start":"2026-04-26T05:45:00.563074Z","end":"2026-04-26T05:45:00.704243Z","steps":["trace[2131175867] 'read index received' (duration: 129.60452ms)","trace[2131175867] 'applied index is now lower than readState.Index' (duration: 11.562906ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:00.704354Z","caller":"traceutil/trace.go:171","msg":"trace[1740843725] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"149.646122ms","start":"2026-04-26T05:45:00.554677Z","end":"2026-04-26T05:45:00.704323Z","steps":["trace[1740843725] 'process raft request' (duration: 138.130298ms)","trace[1740843725] 'compare' (duration: 11.339837ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:00.704429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.329693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T05:45:00.70462Z","caller":"traceutil/trace.go:171","msg":"trace[1155426400] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:80; }","duration":"141.454028ms","start":"2026-04-26T05:45:00.563032Z","end":"2026-04-26T05:45:00.704486Z","steps":["trace[1155426400] 'agreement among raft nodes before linearized reading' (duration: 141.291591ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:01.267975Z","caller":"traceutil/trace.go:171","msg":"trace[2109914487] linearizableReadLoop","detail":"{readStateIndex:107; appliedIndex:106; }","duration":"131.017544ms","start":"2026-04-26T05:45:01.136939Z","end":"2026-04-26T05:45:01.267957Z","steps":["trace[2109914487] 'read index received' (duration: 130.849127ms)","trace[2109914487] 'applied index is now lower than readState.Index' (duration: 167.697µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:01.268034Z","caller":"traceutil/trace.go:171","msg":"trace[1270984093] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"192.765259ms","start":"2026-04-26T05:45:01.075241Z","end":"2026-04-26T05:45:01.268006Z","steps":["trace[1270984093] 'process raft request' (duration: 192.601883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:01.268148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.203272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T05:45:01.268187Z","caller":"traceutil/trace.go:171","msg":"trace[295357056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:103; }","duration":"131.308006ms","start":"2026-04-26T05:45:01.136868Z","end":"2026-04-26T05:45:01.268176Z","steps":["trace[295357056] 'agreement among raft nodes before linearized reading' (duration: 131.202872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:01.657681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.947885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-26T05:45:01.657819Z","caller":"traceutil/trace.go:171","msg":"trace[776686530] transaction","detail":"{read_only:false; response_revision:104; number_of_response:1; }","duration":"384.231406ms","start":"2026-04-26T05:45:01.273556Z","end":"2026-04-26T05:45:01.657788Z","steps":["trace[776686530] 'process raft request' (duration: 82.581662ms)","trace[776686530] 'compare' (duration: 300.757917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:01.657895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:01.27353Z","time spent":"384.33376ms","remote":"127.0.0.1:45146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":699,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T05:45:04.13783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000691357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-26T05:45:04.137928Z","caller":"traceutil/trace.go:171","msg":"trace[177045312] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000844423s","start":"2026-04-26T05:45:02.137063Z","end":"2026-04-26T05:45:04.137907Z","steps":["trace[177045312] 'agreement among raft nodes before linearized reading' (duration: 2.000706988s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:04.137982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:02.137049Z","time spent":"2.000923226s","remote":"127.0.0.1:44786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/26 05:45:04 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-26T05:45:04.196705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000613156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-26T05:45:04.196805Z","caller":"traceutil/trace.go:171","msg":"trace[451115168] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000770763s","start":"2026-04-26T05:45:02.196013Z","end":"2026-04-26T05:45:04.196784Z","steps":["trace[451115168] 'agreement among raft nodes before linearized reading' (duration: 2.000636468s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:04.196857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:02.195989Z","time spent":"2.000856907s","remote":"127.0.0.1:44782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/26 05:45:04 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-26T05:45:04.346037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.105031961s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-26T05:45:04.346156Z","caller":"traceutil/trace.go:171","msg":"trace[1742311758] transaction","detail":"{read_only:false; response_revision:105; number_of_response:1; }","duration":"2.682289361s","start":"2026-04-26T05:45:01.663847Z","end":"2026-04-26T05:45:04.346136Z","steps":["trace[1742311758] 'process raft request' (duration: 577.072023ms)","trace[1742311758] 'compare' (duration: 2.104431207s)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:04.34621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:01.663824Z","time spent":"2.682364825s","remote":"127.0.0.1:45146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1368,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T05:45:05.089991Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.369533048s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-26T05:45:06.297244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000257463s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-26T05:45:06.297329Z","caller":"traceutil/trace.go:171","msg":"trace[388614917] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000388508s","start":"2026-04-26T05:45:04.296919Z","end":"2026-04-26T05:45:06.297307Z","steps":["trace[388614917] 'agreement among raft nodes before linearized reading' (duration: 2.000285744s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:06.297376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:04.296896Z","time spent":"2.000471101s","remote":"127.0.0.1:44786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/26 05:45:06 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-26T05:45:06.344272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253991213s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-26T05:45:06.344394Z","caller":"traceutil/trace.go:171","msg":"trace[1732428468] linearizableReadLoop","detail":"{readStateIndex:110; appliedIndex:108; }","duration":"4.298028986s","start":"2026-04-26T05:45:02.046347Z","end":"2026-04-26T05:45:06.344376Z","steps":["trace[1732428468] 'read index received' (duration: 194.58741ms)","trace[1732428468] 'applied index is now lower than readState.Index' (duration: 4.103440136s)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:06.344549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.216935626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T05:45:06.344573Z","caller":"traceutil/trace.go:171","msg":"trace[1616583068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:106; }","duration":"3.217004209s","start":"2026-04-26T05:45:03.127558Z","end":"2026-04-26T05:45:06.344563Z","steps":["trace[1616583068] 'agreement among raft nodes before linearized reading' (duration: 3.216922466s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:06.344602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:03.127537Z","time spent":"3.217059292s","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-26T05:45:06.344665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.298316238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T05:45:06.344731Z","caller":"traceutil/trace.go:171","msg":"trace[1666327399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:106; }","duration":"4.298415021s","start":"2026-04-26T05:45:02.046297Z","end":"2026-04-26T05:45:06.344712Z","steps":["trace[1666327399] 'agreement among raft nodes before linearized reading' (duration: 4.298169732s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:06.344706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.461522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-26T05:45:06.344769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:02.046275Z","time spent":"4.298486414s","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-26T05:45:06.3448Z","caller":"traceutil/trace.go:171","msg":"trace[1581531555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:106; }","duration":"707.597718ms","start":"2026-04-26T05:45:05.637177Z","end":"2026-04-26T05:45:06.344774Z","steps":["trace[1581531555] 'agreement among raft nodes before linearized reading' (duration: 707.442942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:06.34485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:05.637157Z","time spent":"707.68245ms","remote":"127.0.0.1:44786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-26T05:45:06.344961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.996302421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T05:45:06.344999Z","caller":"traceutil/trace.go:171","msg":"trace[1445289156] range","detail":"{range_begin:/registry/clusterroles/system:controller:attachdetach-controller; range_end:; response_count:0; response_revision:106; }","duration":"1.996387674s","start":"2026-04-26T05:45:04.348595Z","end":"2026-04-26T05:45:06.344983Z","steps":["trace[1445289156] 'agreement among raft nodes before linearized reading' (duration: 1.996338792s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:06.345029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:04.34857Z","time spent":"1.996452436s","remote":"127.0.0.1:45146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" "} {"level":"warn","ts":"2026-04-26T05:45:06.345249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672274146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-04-26T05:45:06.345281Z","caller":"traceutil/trace.go:171","msg":"trace[88486175] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:106; }","duration":"1.672334348s","start":"2026-04-26T05:45:04.672936Z","end":"2026-04-26T05:45:06.34527Z","steps":["trace[88486175] 'agreement among raft nodes before linearized reading' (duration: 1.672262605s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:06.345328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:04.672919Z","time spent":"1.672402061s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3488,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-04-26T05:45:06.345957Z","caller":"traceutil/trace.go:171","msg":"trace[1214564572] transaction","detail":"{read_only:false; response_revision:106; number_of_response:1; }","duration":"4.573886035s","start":"2026-04-26T05:45:01.772046Z","end":"2026-04-26T05:45:06.345932Z","steps":["trace[1214564572] 'process raft request' (duration: 3.318138163s)","trace[1214564572] 'compare' (duration: 1.253821267s)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:06.34611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:01.77202Z","time spent":"4.57401602s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T05:45:10.651431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.242502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-26T05:45:10.651478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.271363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-26T05:45:10.651542Z","caller":"traceutil/trace.go:171","msg":"trace[679895749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:314; }","duration":"218.386517ms","start":"2026-04-26T05:45:10.433137Z","end":"2026-04-26T05:45:10.651524Z","steps":["trace[679895749] 'range keys from in-memory index tree' (duration: 218.129588ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:10.651558Z","caller":"traceutil/trace.go:171","msg":"trace[413051047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:314; }","duration":"218.400868ms","start":"2026-04-26T05:45:10.433139Z","end":"2026-04-26T05:45:10.65154Z","steps":["trace[413051047] 'range keys from in-memory index tree' (duration: 218.183949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:10.651432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.679725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:45:10.651686Z","caller":"traceutil/trace.go:171","msg":"trace[1336533639] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:314; }","duration":"254.999616ms","start":"2026-04-26T05:45:10.396679Z","end":"2026-04-26T05:45:10.651679Z","steps":["trace[1336533639] 'range keys from in-memory index tree' (duration: 254.54623ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:10.982433Z","caller":"traceutil/trace.go:171","msg":"trace[760457626] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:329; }","duration":"218.179129ms","start":"2026-04-26T05:45:10.764228Z","end":"2026-04-26T05:45:10.982408Z","steps":["trace[760457626] 'read index received' (duration: 136.588648ms)","trace[760457626] 'applied index is now lower than readState.Index' (duration: 81.589601ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:10.982586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.339066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-04-26T05:45:10.982555Z","caller":"traceutil/trace.go:171","msg":"trace[272155919] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"242.998117ms","start":"2026-04-26T05:45:10.739521Z","end":"2026-04-26T05:45:10.982519Z","steps":["trace[272155919] 'process raft request' (duration: 161.275561ms)","trace[272155919] 'compare' (duration: 81.499068ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:10.982621Z","caller":"traceutil/trace.go:171","msg":"trace[562762592] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:318; }","duration":"218.425809ms","start":"2026-04-26T05:45:10.764183Z","end":"2026-04-26T05:45:10.982609Z","steps":["trace[562762592] 'agreement among raft nodes before linearized reading' (duration: 218.325365ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:11.24047Z","caller":"traceutil/trace.go:171","msg":"trace[615985984] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:330; }","duration":"253.779351ms","start":"2026-04-26T05:45:10.986669Z","end":"2026-04-26T05:45:11.240449Z","steps":["trace[615985984] 'read index received' (duration: 251.893191ms)","trace[615985984] 'applied index is now lower than readState.Index' (duration: 1.88531ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:11.240485Z","caller":"traceutil/trace.go:171","msg":"trace[1183015122] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"474.90264ms","start":"2026-04-26T05:45:10.765551Z","end":"2026-04-26T05:45:11.240454Z","steps":["trace[1183015122] 'process raft request' (duration: 472.881235ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:11.240538Z","caller":"traceutil/trace.go:171","msg":"trace[923187965] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"473.715406ms","start":"2026-04-26T05:45:10.766817Z","end":"2026-04-26T05:45:11.240533Z","steps":["trace[923187965] 'process raft request' (duration: 473.606172ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:11.240542Z","caller":"traceutil/trace.go:171","msg":"trace[1585906830] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"474.910051ms","start":"2026-04-26T05:45:10.765611Z","end":"2026-04-26T05:45:11.240521Z","steps":["trace[1585906830] 'process raft request' (duration: 474.756995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:11.240596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.900565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T05:45:11.240615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:10.766791Z","time spent":"473.779677ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T05:45:11.240618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:10.765531Z","time spent":"475.032835ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T05:45:11.240651Z","caller":"traceutil/trace.go:171","msg":"trace[1886385186] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:318; }","duration":"254.000558ms","start":"2026-04-26T05:45:10.986625Z","end":"2026-04-26T05:45:11.240625Z","steps":["trace[1886385186] 'agreement among raft nodes before linearized reading' (duration: 253.915685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:11.24065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:10.765584Z","time spent":"475.009964ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T05:45:11.240487Z","caller":"traceutil/trace.go:171","msg":"trace[590619292] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"474.007217ms","start":"2026-04-26T05:45:10.766466Z","end":"2026-04-26T05:45:11.240473Z","steps":["trace[590619292] 'process raft request' (duration: 473.925104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:11.240743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:10.766447Z","time spent":"474.276166ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T05:45:11.278147Z","caller":"traceutil/trace.go:171","msg":"trace[1130748297] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"287.09019ms","start":"2026-04-26T05:45:10.990991Z","end":"2026-04-26T05:45:11.278081Z","steps":["trace[1130748297] 'process raft request' (duration: 286.957145ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:11.825448Z","caller":"traceutil/trace.go:171","msg":"trace[633473275] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:339; }","duration":"366.664017ms","start":"2026-04-26T05:45:11.458758Z","end":"2026-04-26T05:45:11.825422Z","steps":["trace[633473275] 'read index received' (duration: 300.912406ms)","trace[633473275] 'applied index is now lower than readState.Index' (duration: 65.750621ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:11.825492Z","caller":"traceutil/trace.go:171","msg":"trace[1588850632] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"380.530629ms","start":"2026-04-26T05:45:11.444932Z","end":"2026-04-26T05:45:11.825463Z","steps":["trace[1588850632] 'process raft request' (duration: 314.82206ms)","trace[1588850632] 'compare' (duration: 65.533853ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:11.825565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.787181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:45:11.825601Z","caller":"traceutil/trace.go:171","msg":"trace[525768642] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:324; }","duration":"366.866054ms","start":"2026-04-26T05:45:11.458723Z","end":"2026-04-26T05:45:11.825589Z","steps":["trace[525768642] 'agreement among raft nodes before linearized reading' (duration: 366.790641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:11.825655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:11.458707Z","time spent":"366.938306ms","remote":"127.0.0.1:44968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-26T05:45:11.825836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:11.444907Z","time spent":"380.644794ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T05:45:12.949788Z","caller":"traceutil/trace.go:171","msg":"trace[1417475640] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"141.320336ms","start":"2026-04-26T05:45:12.80819Z","end":"2026-04-26T05:45:12.94951Z","steps":["trace[1417475640] 'process raft request' (duration: 140.924791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:13.061527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.293485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-26T05:45:13.061641Z","caller":"traceutil/trace.go:171","msg":"trace[1940408733] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:343; }","duration":"102.461482ms","start":"2026-04-26T05:45:12.959157Z","end":"2026-04-26T05:45:13.061619Z","steps":["trace[1940408733] 'range keys from in-memory index tree' (duration: 102.033036ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:13.683494Z","caller":"traceutil/trace.go:171","msg":"trace[989942758] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:363; }","duration":"143.176994ms","start":"2026-04-26T05:45:13.540294Z","end":"2026-04-26T05:45:13.683471Z","steps":["trace[989942758] 'read index received' (duration: 142.999917ms)","trace[989942758] 'applied index is now lower than readState.Index' (duration: 176.157µs)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:13.683719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.406521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6964"} {"level":"info","ts":"2026-04-26T05:45:13.683777Z","caller":"traceutil/trace.go:171","msg":"trace[629020874] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:348; }","duration":"143.513625ms","start":"2026-04-26T05:45:13.540253Z","end":"2026-04-26T05:45:13.683766Z","steps":["trace[629020874] 'agreement among raft nodes before linearized reading' (duration: 143.401051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:13.683837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.077323ms","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-04-26T05:45:13.683826Z","caller":"traceutil/trace.go:171","msg":"trace[2028334817] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"160.658956ms","start":"2026-04-26T05:45:13.523143Z","end":"2026-04-26T05:45:13.683801Z","steps":["trace[2028334817] 'process raft request' (duration: 160.203609ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:13.683978Z","caller":"traceutil/trace.go:171","msg":"trace[948392943] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:348; }","duration":"137.267009ms","start":"2026-04-26T05:45:13.546641Z","end":"2026-04-26T05:45:13.683908Z","steps":["trace[948392943] 'agreement among raft nodes before linearized reading' (duration: 137.047191ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:14.464829Z","caller":"traceutil/trace.go:171","msg":"trace[241965231] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"117.047042ms","start":"2026-04-26T05:45:14.347755Z","end":"2026-04-26T05:45:14.464802Z","steps":["trace[241965231] 'read index received' (duration: 114.480861ms)","trace[241965231] 'applied index is now lower than readState.Index' (duration: 2.565121ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:14.464938Z","caller":"traceutil/trace.go:171","msg":"trace[1088979508] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"136.56833ms","start":"2026-04-26T05:45:14.328347Z","end":"2026-04-26T05:45:14.464915Z","steps":["trace[1088979508] 'process raft request' (duration: 133.952147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:14.465038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.259301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-26T05:45:14.465126Z","caller":"traceutil/trace.go:171","msg":"trace[1696958970] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:367; }","duration":"117.376994ms","start":"2026-04-26T05:45:14.347728Z","end":"2026-04-26T05:45:14.465105Z","steps":["trace[1696958970] 'agreement among raft nodes before linearized reading' (duration: 117.214248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:16.361154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.80435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T05:45:16.361256Z","caller":"traceutil/trace.go:171","msg":"trace[1121523564] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"200.892328ms","start":"2026-04-26T05:45:16.160347Z","end":"2026-04-26T05:45:16.361239Z","steps":["trace[1121523564] 'process raft request' (duration: 67.939703ms)","trace[1121523564] 'compare' (duration: 132.655505ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:16.560284Z","caller":"traceutil/trace.go:171","msg":"trace[1906049569] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"192.965062ms","start":"2026-04-26T05:45:16.367299Z","end":"2026-04-26T05:45:16.560264Z","steps":["trace[1906049569] 'process raft request' (duration: 192.9008ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:16.560361Z","caller":"traceutil/trace.go:171","msg":"trace[1682599306] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"195.392486ms","start":"2026-04-26T05:45:16.364933Z","end":"2026-04-26T05:45:16.560326Z","steps":["trace[1682599306] 'process raft request' (duration: 106.896735ms)","trace[1682599306] 'compare' (duration: 88.253463ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:18.494883Z","caller":"traceutil/trace.go:171","msg":"trace[8669100] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"106.898182ms","start":"2026-04-26T05:45:18.387953Z","end":"2026-04-26T05:45:18.494851Z","steps":["trace[8669100] 'process raft request' (duration: 71.235964ms)","trace[8669100] 'compare' (duration: 35.545824ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:21.822474Z","caller":"traceutil/trace.go:171","msg":"trace[976548445] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"122.424688ms","start":"2026-04-26T05:45:21.700027Z","end":"2026-04-26T05:45:21.822452Z","steps":["trace[976548445] 'process raft request' (duration: 122.288313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:24.538411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.806198ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-26T05:45:24.538536Z","caller":"traceutil/trace.go:171","msg":"trace[1725291874] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:476; }","duration":"1.145942842s","start":"2026-04-26T05:45:23.392569Z","end":"2026-04-26T05:45:24.538511Z","steps":["trace[1725291874] 'read index received' (duration: 212.976919ms)","trace[1725291874] 'applied index is now lower than readState.Index' (duration: 932.964223ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:24.538696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146126429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-26T05:45:24.538745Z","caller":"traceutil/trace.go:171","msg":"trace[685821565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:458; }","duration":"1.146209431s","start":"2026-04-26T05:45:23.392517Z","end":"2026-04-26T05:45:24.538727Z","steps":["trace[685821565] 'agreement among raft nodes before linearized reading' (duration: 1.146111908s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:24.538696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.209844ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:45:24.538803Z","caller":"traceutil/trace.go:171","msg":"trace[1084396652] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:458; }","duration":"344.323768ms","start":"2026-04-26T05:45:24.194463Z","end":"2026-04-26T05:45:24.538786Z","steps":["trace[1084396652] 'range keys from in-memory index tree' (duration: 344.197514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:24.538808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:23.392496Z","time spent":"1.146297314s","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-26T05:45:24.538777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.682068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:45:24.538883Z","caller":"traceutil/trace.go:171","msg":"trace[1195882376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:458; }","duration":"742.828552ms","start":"2026-04-26T05:45:23.796031Z","end":"2026-04-26T05:45:24.538859Z","steps":["trace[1195882376] 'agreement among raft nodes before linearized reading' (duration: 742.663397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:24.538902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.795591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-26T05:45:24.539011Z","caller":"traceutil/trace.go:171","msg":"trace[1532231474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:458; }","duration":"712.927166ms","start":"2026-04-26T05:45:23.826063Z","end":"2026-04-26T05:45:24.53899Z","steps":["trace[1532231474] 'agreement among raft nodes before linearized reading' (duration: 712.628496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:24.539061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:23.826008Z","time spent":"713.044969ms","remote":"127.0.0.1:45030","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-04-26T05:45:24.538934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:23.796009Z","time spent":"742.916435ms","remote":"127.0.0.1:44786","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-04-26T05:45:28.983877Z","caller":"traceutil/trace.go:171","msg":"trace[1246475996] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"188.945234ms","start":"2026-04-26T05:45:28.794875Z","end":"2026-04-26T05:45:28.98382Z","steps":["trace[1246475996] 'read index received' (duration: 185.146638ms)","trace[1246475996] 'applied index is now lower than readState.Index' (duration: 3.795346ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:28.984011Z","caller":"traceutil/trace.go:171","msg":"trace[244719881] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"202.761068ms","start":"2026-04-26T05:45:28.781197Z","end":"2026-04-26T05:45:28.983958Z","steps":["trace[244719881] 'process raft request' (duration: 201.911552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:28.984731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.835021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:45:28.984814Z","caller":"traceutil/trace.go:171","msg":"trace[390308010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"189.973145ms","start":"2026-04-26T05:45:28.794821Z","end":"2026-04-26T05:45:28.984794Z","steps":["trace[390308010] 'agreement among raft nodes before linearized reading' (duration: 189.16105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:31.255779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.84004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:1019"} {"level":"warn","ts":"2026-04-26T05:45:31.255829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.795599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-04-26T05:45:31.255849Z","caller":"traceutil/trace.go:171","msg":"trace[361193653] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"189.084649ms","start":"2026-04-26T05:45:31.066733Z","end":"2026-04-26T05:45:31.255818Z","steps":["trace[361193653] 'process raft request' (duration: 114.341168ms)","trace[361193653] 'compare' (duration: 74.580097ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:31.255876Z","caller":"traceutil/trace.go:171","msg":"trace[834395853] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:533; }","duration":"193.982224ms","start":"2026-04-26T05:45:31.061872Z","end":"2026-04-26T05:45:31.255854Z","steps":["trace[834395853] 'range keys from in-memory index tree' (duration: 193.619524ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:31.25594Z","caller":"traceutil/trace.go:171","msg":"trace[419618269] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:533; }","duration":"193.900662ms","start":"2026-04-26T05:45:31.061982Z","end":"2026-04-26T05:45:31.255882Z","steps":["trace[419618269] 'range keys from in-memory index tree' (duration: 193.736527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:31.520281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.030911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T05:45:31.520544Z","caller":"traceutil/trace.go:171","msg":"trace[522989793] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"433.800081ms","start":"2026-04-26T05:45:31.086719Z","end":"2026-04-26T05:45:31.520519Z","steps":["trace[522989793] 'process raft request' (duration: 332.387078ms)","trace[522989793] 'compare' (duration: 100.833276ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:31.520676Z","caller":"traceutil/trace.go:171","msg":"trace[964219224] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:557; }","duration":"432.374338ms","start":"2026-04-26T05:45:31.088284Z","end":"2026-04-26T05:45:31.520659Z","steps":["trace[964219224] 'read index received' (duration: 92.856299ms)","trace[964219224] 'applied index is now lower than readState.Index' (duration: 339.516729ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:31.520699Z","caller":"traceutil/trace.go:171","msg":"trace[94899982] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"430.244035ms","start":"2026-04-26T05:45:31.090437Z","end":"2026-04-26T05:45:31.520681Z","steps":["trace[94899982] 'process raft request' (duration: 429.989727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:31.520806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:31.090428Z","time spent":"430.322238ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T05:45:31.520864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.550883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-26T05:45:31.520934Z","caller":"traceutil/trace.go:171","msg":"trace[177104693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"432.644866ms","start":"2026-04-26T05:45:31.088279Z","end":"2026-04-26T05:45:31.520924Z","steps":["trace[177104693] 'agreement among raft nodes before linearized reading' (duration: 432.4526ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:31.520944Z","caller":"traceutil/trace.go:171","msg":"trace[292353925] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"427.38309ms","start":"2026-04-26T05:45:31.093364Z","end":"2026-04-26T05:45:31.520747Z","steps":["trace[292353925] 'process raft request' (duration: 427.166113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:31.521024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:31.088236Z","time spent":"432.74919ms","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-26T05:45:31.521188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:31.093353Z","time spent":"427.69882ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T05:45:31.52136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:31.0867Z","time spent":"433.910364ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T05:45:31.521674Z","caller":"traceutil/trace.go:171","msg":"trace[2022007837] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"254.75142ms","start":"2026-04-26T05:45:31.266908Z","end":"2026-04-26T05:45:31.52166Z","steps":["trace[2022007837] 'process raft request' (duration: 254.691638ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:31.521738Z","caller":"traceutil/trace.go:171","msg":"trace[1741422315] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"257.356597ms","start":"2026-04-26T05:45:31.264358Z","end":"2026-04-26T05:45:31.521714Z","steps":["trace[1741422315] 'process raft request' (duration: 257.159641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:32.295678Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367264934475906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T05:45:32.560866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.649188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T05:45:32.56126Z","caller":"traceutil/trace.go:171","msg":"trace[1649788378] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"980.349203ms","start":"2026-04-26T05:45:31.580771Z","end":"2026-04-26T05:45:32.56112Z","steps":["trace[1649788378] 'process raft request' (duration: 166.379045ms)","trace[1649788378] 'compare' (duration: 813.463853ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:32.562105Z","caller":"traceutil/trace.go:171","msg":"trace[338356670] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"964.572154ms","start":"2026-04-26T05:45:31.597458Z","end":"2026-04-26T05:45:32.56203Z","steps":["trace[338356670] 'process raft request' (duration: 963.651936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:32.562089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:31.580748Z","time spent":"981.255039ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T05:45:32.562238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:31.597432Z","time spent":"964.731538ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T05:45:32.565803Z","caller":"traceutil/trace.go:171","msg":"trace[749876418] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:568; }","duration":"770.120874ms","start":"2026-04-26T05:45:31.795645Z","end":"2026-04-26T05:45:32.565766Z","steps":["trace[749876418] 'read index received' (duration: 766.713463ms)","trace[749876418] 'applied index is now lower than readState.Index' (duration: 3.4038ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:32.566071Z","caller":"traceutil/trace.go:171","msg":"trace[1210322456] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"954.090961ms","start":"2026-04-26T05:45:31.611786Z","end":"2026-04-26T05:45:32.565877Z","steps":["trace[1210322456] 'process raft request' (duration: 951.125393ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:32.565915Z","caller":"traceutil/trace.go:171","msg":"trace[776722271] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"951.889295ms","start":"2026-04-26T05:45:31.613992Z","end":"2026-04-26T05:45:32.565881Z","steps":["trace[776722271] 'process raft request' (duration: 949.747222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:32.56626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:31.611762Z","time spent":"954.430181ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T05:45:32.566523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.862865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:45:32.566582Z","caller":"traceutil/trace.go:171","msg":"trace[1745952545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"770.947298ms","start":"2026-04-26T05:45:31.795611Z","end":"2026-04-26T05:45:32.566559Z","steps":["trace[1745952545] 'agreement among raft nodes before linearized reading' (duration: 770.254557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:32.566616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:31.795599Z","time spent":"771.00929ms","remote":"127.0.0.1:44786","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-04-26T05:45:32.56713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:31.613972Z","time spent":"952.235746ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T05:45:33.638036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.17719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T05:45:33.638144Z","caller":"traceutil/trace.go:171","msg":"trace[1464451012] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.069442973s","start":"2026-04-26T05:45:32.568682Z","end":"2026-04-26T05:45:33.638125Z","steps":["trace[1464451012] 'process raft request' (duration: 162.107068ms)","trace[1464451012] 'compare' (duration: 906.917403ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:33.638207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:32.568666Z","time spent":"1.069517595s","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T05:45:34.155174Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.388727542s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-26T05:45:34.770505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.890156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-04-26T05:45:34.770388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.249533ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:45:34.770642Z","caller":"traceutil/trace.go:171","msg":"trace[1477367894] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:547; }","duration":"576.501501ms","start":"2026-04-26T05:45:34.194097Z","end":"2026-04-26T05:45:34.770599Z","steps":["trace[1477367894] 'range keys from in-memory index tree' (duration: 576.221253ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:34.770977Z","caller":"traceutil/trace.go:171","msg":"trace[761792862] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"2.19438654s","start":"2026-04-26T05:45:32.576576Z","end":"2026-04-26T05:45:34.770963Z","steps":["trace[761792862] 'process raft request' (duration: 1.578919797s)","trace[761792862] 'compare' (duration: 614.596488ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:34.770965Z","caller":"traceutil/trace.go:171","msg":"trace[447563094] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"2.175402281s","start":"2026-04-26T05:45:32.595528Z","end":"2026-04-26T05:45:34.770931Z","steps":["trace[447563094] 'process raft request' (duration: 2.175146544s)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:34.771012Z","caller":"traceutil/trace.go:171","msg":"trace[1758037453] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:570; }","duration":"2.114370866s","start":"2026-04-26T05:45:32.656587Z","end":"2026-04-26T05:45:34.770958Z","steps":["trace[1758037453] 'read index received' (duration: 74.265404ms)","trace[1758037453] 'applied index is now lower than readState.Index' (duration: 2.040101652s)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:34.771112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:32.576558Z","time spent":"2.194453791s","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T05:45:34.771127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:32.595509Z","time spent":"2.175524415s","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T05:45:34.770965Z","caller":"traceutil/trace.go:171","msg":"trace[1888575388] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"2.119420125s","start":"2026-04-26T05:45:32.651513Z","end":"2026-04-26T05:45:34.770933Z","steps":["trace[1888575388] 'process raft request' (duration: 2.119308292s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:34.771256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:32.651485Z","time spent":"2.119697172s","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T05:45:35.37943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.494203ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-26T05:45:35.379733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.723103035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-26T05:45:35.379802Z","caller":"traceutil/trace.go:171","msg":"trace[686115821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"2.723231348s","start":"2026-04-26T05:45:32.656549Z","end":"2026-04-26T05:45:35.37978Z","steps":["trace[686115821] 'agreement among raft nodes before linearized reading' (duration: 2.114488799s)","trace[686115821] 'range keys from in-memory index tree' (duration: 608.489201ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:35.379858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:32.656533Z","time spent":"2.723311771s","remote":"127.0.0.1:45030","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-04-26T05:45:35.379802Z","caller":"traceutil/trace.go:171","msg":"trace[1966453738] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"608.710668ms","start":"2026-04-26T05:45:34.771068Z","end":"2026-04-26T05:45:35.379779Z","steps":["trace[1966453738] 'read index received' (duration: 92.013µs)","trace[1966453738] 'applied index is now lower than readState.Index' (duration: 608.617205ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:35.380012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.705441285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-26T05:45:35.380063Z","caller":"traceutil/trace.go:171","msg":"trace[997363640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"2.705550909s","start":"2026-04-26T05:45:32.674499Z","end":"2026-04-26T05:45:35.38005Z","steps":["trace[997363640] 'agreement among raft nodes before linearized reading' (duration: 2.705334623s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:35.380108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:32.674486Z","time spent":"2.70560948s","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-26T05:45:35.380312Z","caller":"traceutil/trace.go:171","msg":"trace[2099959193] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"596.810866ms","start":"2026-04-26T05:45:34.78347Z","end":"2026-04-26T05:45:35.380281Z","steps":["trace[2099959193] 'process raft request' (duration: 596.095125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:35.380485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:34.783448Z","time spent":"596.938939ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T05:45:35.450629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.652861724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T05:45:35.450687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.888540484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-26T05:45:35.45071Z","caller":"traceutil/trace.go:171","msg":"trace[1301923757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"1.652974938s","start":"2026-04-26T05:45:33.797707Z","end":"2026-04-26T05:45:35.450682Z","steps":["trace[1301923757] 'agreement among raft nodes before linearized reading' (duration: 1.582343413s)","trace[1301923757] 'range keys from in-memory index tree' (duration: 70.532512ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:35.450731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.385280135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-26T05:45:35.450744Z","caller":"traceutil/trace.go:171","msg":"trace[757889720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"1.888625137s","start":"2026-04-26T05:45:33.562104Z","end":"2026-04-26T05:45:35.450729Z","steps":["trace[757889720] 'agreement among raft nodes before linearized reading' (duration: 1.81792066s)","trace[757889720] 'range keys from in-memory index tree' (duration: 70.550092ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:35.45076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:33.797691Z","time spent":"1.65305726s","remote":"127.0.0.1:44786","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-04-26T05:45:35.450786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:33.562083Z","time spent":"1.888695299s","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-26T05:45:35.450791Z","caller":"traceutil/trace.go:171","msg":"trace[2146307345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"2.385369787s","start":"2026-04-26T05:45:33.065402Z","end":"2026-04-26T05:45:35.450772Z","steps":["trace[2146307345] 'agreement among raft nodes before linearized reading' (duration: 2.314682161s)","trace[2146307345] 'range keys from in-memory index tree' (duration: 70.530082ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:35.450835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:33.06536Z","time spent":"2.38546465s","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-26T05:45:35.451138Z","caller":"traceutil/trace.go:171","msg":"trace[1219388765] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"663.080434ms","start":"2026-04-26T05:45:34.788036Z","end":"2026-04-26T05:45:35.451116Z","steps":["trace[1219388765] 'process raft request' (duration: 595.838096ms)","trace[1219388765] 'compare' (duration: 66.672702ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:35.451126Z","caller":"traceutil/trace.go:171","msg":"trace[335455819] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"627.067097ms","start":"2026-04-26T05:45:34.82404Z","end":"2026-04-26T05:45:35.451107Z","steps":["trace[335455819] 'process raft request' (duration: 626.924203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:35.451304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:34.824015Z","time spent":"627.242211ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T05:45:35.451299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:34.788012Z","time spent":"663.209728ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T05:45:35.488465Z","caller":"traceutil/trace.go:171","msg":"trace[1740711167] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"104.103569ms","start":"2026-04-26T05:45:35.384327Z","end":"2026-04-26T05:45:35.48843Z","steps":["trace[1740711167] 'process raft request' (duration: 103.857932ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:35.862432Z","caller":"traceutil/trace.go:171","msg":"trace[1117398812] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"348.367776ms","start":"2026-04-26T05:45:35.514042Z","end":"2026-04-26T05:45:35.86241Z","steps":["trace[1117398812] 'process raft request' (duration: 323.454455ms)","trace[1117398812] 'compare' (duration: 24.746386ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:35.862542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:35.514019Z","time spent":"348.470629ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T05:45:37.296771Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367264934475934,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T05:45:37.312284Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.474605884s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-26T05:45:37.459171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.491534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T05:45:37.459577Z","caller":"traceutil/trace.go:171","msg":"trace[1366883136] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.899458497s","start":"2026-04-26T05:45:35.56008Z","end":"2026-04-26T05:45:37.459539Z","steps":["trace[1366883136] 'process raft request' (duration: 1.752481819s)","trace[1366883136] 'compare' (duration: 146.069272ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:37.45969Z","caller":"traceutil/trace.go:171","msg":"trace[1545980634] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.895198375s","start":"2026-04-26T05:45:35.56447Z","end":"2026-04-26T05:45:37.459668Z","steps":["trace[1545980634] 'process raft request' (duration: 1.894908087s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:37.459777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:35.560062Z","time spent":"1.899596191s","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T05:45:37.459855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:35.564459Z","time spent":"1.895346219s","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T05:45:37.477518Z","caller":"traceutil/trace.go:171","msg":"trace[2103223030] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:585; }","duration":"681.110977ms","start":"2026-04-26T05:45:36.796385Z","end":"2026-04-26T05:45:37.477496Z","steps":["trace[2103223030] 'read index received' (duration: 516.142196ms)","trace[2103223030] 'applied index is now lower than readState.Index' (duration: 164.967861ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:37.477821Z","caller":"traceutil/trace.go:171","msg":"trace[1805031981] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.591901845s","start":"2026-04-26T05:45:35.885882Z","end":"2026-04-26T05:45:37.477784Z","steps":["trace[1805031981] 'process raft request' (duration: 1.591513544s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:37.477959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:35.885854Z","time spent":"1.592042099s","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T05:45:37.478078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.658792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:45:37.478214Z","caller":"traceutil/trace.go:171","msg":"trace[1214414388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"681.854807ms","start":"2026-04-26T05:45:36.796334Z","end":"2026-04-26T05:45:37.478189Z","steps":["trace[1214414388] 'agreement among raft nodes before linearized reading' (duration: 681.385064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:37.478283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:36.796309Z","time spent":"681.965141ms","remote":"127.0.0.1:44786","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-04-26T05:45:37.603648Z","caller":"traceutil/trace.go:171","msg":"trace[393904348] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"115.801217ms","start":"2026-04-26T05:45:37.487813Z","end":"2026-04-26T05:45:37.603614Z","steps":["trace[393904348] 'process raft request' (duration: 102.887086ms)","trace[393904348] 'compare' (duration: 12.593462ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:37.603793Z","caller":"traceutil/trace.go:171","msg":"trace[811743966] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:588; }","duration":"112.062612ms","start":"2026-04-26T05:45:37.49169Z","end":"2026-04-26T05:45:37.603752Z","steps":["trace[811743966] 'read index received' (duration: 57.192908ms)","trace[811743966] 'applied index is now lower than readState.Index' (duration: 54.867554ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:37.603989Z","caller":"traceutil/trace.go:171","msg":"trace[837281754] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"107.481884ms","start":"2026-04-26T05:45:37.496497Z","end":"2026-04-26T05:45:37.603978Z","steps":["trace[837281754] 'process raft request' (duration: 107.020701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:37.604279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.005603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-26T05:45:37.604335Z","caller":"traceutil/trace.go:171","msg":"trace[2088704428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:566; }","duration":"111.090425ms","start":"2026-04-26T05:45:37.493231Z","end":"2026-04-26T05:45:37.604321Z","steps":["trace[2088704428] 'agreement among raft nodes before linearized reading' (duration: 110.905489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:37.604585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.198818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-26T05:45:37.604627Z","caller":"traceutil/trace.go:171","msg":"trace[305421985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:566; }","duration":"111.27854ms","start":"2026-04-26T05:45:37.493338Z","end":"2026-04-26T05:45:37.604617Z","steps":["trace[305421985] 'agreement among raft nodes before linearized reading' (duration: 111.156176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:37.604663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.974288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-26T05:45:37.604737Z","caller":"traceutil/trace.go:171","msg":"trace[290885410] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:566; }","duration":"113.091991ms","start":"2026-04-26T05:45:37.491633Z","end":"2026-04-26T05:45:37.604725Z","steps":["trace[290885410] 'agreement among raft nodes before linearized reading' (duration: 112.642118ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:37.924089Z","caller":"traceutil/trace.go:171","msg":"trace[2145027333] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"135.320273ms","start":"2026-04-26T05:45:37.788742Z","end":"2026-04-26T05:45:37.924062Z","steps":["trace[2145027333] 'process raft request' (duration: 119.863781ms)","trace[2145027333] 'compare' (duration: 15.341978ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:37.929883Z","caller":"traceutil/trace.go:171","msg":"trace[1340639690] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"131.28352ms","start":"2026-04-26T05:45:37.798509Z","end":"2026-04-26T05:45:37.929793Z","steps":["trace[1340639690] 'read index received' (duration: 110.160219ms)","trace[1340639690] 'applied index is now lower than readState.Index' (duration: 21.121501ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:37.93058Z","caller":"traceutil/trace.go:171","msg":"trace[977837862] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"136.088713ms","start":"2026-04-26T05:45:37.794471Z","end":"2026-04-26T05:45:37.930559Z","steps":["trace[977837862] 'process raft request' (duration: 132.982397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:37.932128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.598924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:45:37.932209Z","caller":"traceutil/trace.go:171","msg":"trace[596019605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"133.692577ms","start":"2026-04-26T05:45:37.798489Z","end":"2026-04-26T05:45:37.932182Z","steps":["trace[596019605] 'agreement among raft nodes before linearized reading' (duration: 131.778363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:39.047776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.760536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T05:45:39.048172Z","caller":"traceutil/trace.go:171","msg":"trace[1114913285] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"563.026167ms","start":"2026-04-26T05:45:38.4851Z","end":"2026-04-26T05:45:39.048126Z","steps":["trace[1114913285] 'read index received' (duration: 49.5238ms)","trace[1114913285] 'applied index is now lower than readState.Index' (duration: 513.500887ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:39.048193Z","caller":"traceutil/trace.go:171","msg":"trace[1520124389] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"573.62009ms","start":"2026-04-26T05:45:38.474533Z","end":"2026-04-26T05:45:39.048153Z","steps":["trace[1520124389] 'process raft request' (duration: 60.341949ms)","trace[1520124389] 'compare' (duration: 512.547721ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:39.048342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:38.474512Z","time spent":"573.749233ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T05:45:39.048376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.262973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-04-26T05:45:39.04842Z","caller":"traceutil/trace.go:171","msg":"trace[433080101] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:594; }","duration":"563.347265ms","start":"2026-04-26T05:45:38.485058Z","end":"2026-04-26T05:45:39.048405Z","steps":["trace[433080101] 'agreement among raft nodes before linearized reading' (duration: 563.173001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:39.048456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:38.485039Z","time spent":"563.408528ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":65459,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-04-26T05:45:39.048777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.13546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:45:39.048821Z","caller":"traceutil/trace.go:171","msg":"trace[457365203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"254.275773ms","start":"2026-04-26T05:45:38.794532Z","end":"2026-04-26T05:45:39.048807Z","steps":["trace[457365203] 'agreement among raft nodes before linearized reading' (duration: 254.14322ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:39.270423Z","caller":"traceutil/trace.go:171","msg":"trace[857417382] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"102.878038ms","start":"2026-04-26T05:45:39.167515Z","end":"2026-04-26T05:45:39.270393Z","steps":["trace[857417382] 'process raft request' (duration: 102.444296ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:40.549342Z","caller":"traceutil/trace.go:171","msg":"trace[1609569494] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"167.73107ms","start":"2026-04-26T05:45:40.381588Z","end":"2026-04-26T05:45:40.549319Z","steps":["trace[1609569494] 'process raft request' (duration: 167.597096ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:40.558963Z","caller":"traceutil/trace.go:171","msg":"trace[131292118] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"176.723034ms","start":"2026-04-26T05:45:40.382201Z","end":"2026-04-26T05:45:40.558924Z","steps":["trace[131292118] 'process raft request' (duration: 176.525159ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:41.020004Z","caller":"traceutil/trace.go:171","msg":"trace[673635082] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"223.971712ms","start":"2026-04-26T05:45:40.79601Z","end":"2026-04-26T05:45:41.019981Z","steps":["trace[673635082] 'read index received' (duration: 223.307874ms)","trace[673635082] 'applied index is now lower than readState.Index' (duration: 663.088µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:41.020137Z","caller":"traceutil/trace.go:171","msg":"trace[871153897] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"263.35309ms","start":"2026-04-26T05:45:40.756744Z","end":"2026-04-26T05:45:41.020097Z","steps":["trace[871153897] 'process raft request' (duration: 262.818165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:41.020221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.147266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:45:41.020342Z","caller":"traceutil/trace.go:171","msg":"trace[758757105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"224.29727ms","start":"2026-04-26T05:45:40.795967Z","end":"2026-04-26T05:45:41.020264Z","steps":["trace[758757105] 'agreement among raft nodes before linearized reading' (duration: 224.114355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:41.906772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.401225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:45:41.906899Z","caller":"traceutil/trace.go:171","msg":"trace[1459528569] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:606; }","duration":"821.57526ms","start":"2026-04-26T05:45:41.085294Z","end":"2026-04-26T05:45:41.90687Z","steps":["trace[1459528569] 'range keys from in-memory index tree' (duration: 821.323733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:41.906957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:41.085276Z","time spent":"821.666232ms","remote":"127.0.0.1:44968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-26T05:45:41.907203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.547057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-26T05:45:41.907292Z","caller":"traceutil/trace.go:171","msg":"trace[1028007455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"337.66308ms","start":"2026-04-26T05:45:41.569596Z","end":"2026-04-26T05:45:41.907259Z","steps":["trace[1028007455] 'range keys from in-memory index tree' (duration: 337.340872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:41.907291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.215004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-26T05:45:41.907295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.908111ms","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-04-26T05:45:41.907334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:41.569576Z","time spent":"337.748183ms","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-26T05:45:41.90736Z","caller":"traceutil/trace.go:171","msg":"trace[245095925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"246.328226ms","start":"2026-04-26T05:45:41.661014Z","end":"2026-04-26T05:45:41.907343Z","steps":["trace[245095925] 'range keys from in-memory index tree' (duration: 246.011168ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:41.90737Z","caller":"traceutil/trace.go:171","msg":"trace[2081932740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"241.005864ms","start":"2026-04-26T05:45:41.666341Z","end":"2026-04-26T05:45:41.907347Z","steps":["trace[2081932740] 'range keys from in-memory index tree' (duration: 240.702736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:41.907444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.504534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-26T05:45:41.907495Z","caller":"traceutil/trace.go:171","msg":"trace[789650989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"239.582906ms","start":"2026-04-26T05:45:41.667898Z","end":"2026-04-26T05:45:41.907481Z","steps":["trace[789650989] 'range keys from in-memory index tree' (duration: 239.38054ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:42.079191Z","caller":"traceutil/trace.go:171","msg":"trace[222237177] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"167.013847ms","start":"2026-04-26T05:45:41.912151Z","end":"2026-04-26T05:45:42.079165Z","steps":["trace[222237177] 'process raft request' (duration: 166.970206ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:42.079286Z","caller":"traceutil/trace.go:171","msg":"trace[1201102096] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"167.240533ms","start":"2026-04-26T05:45:41.911996Z","end":"2026-04-26T05:45:42.079236Z","steps":["trace[1201102096] 'process raft request' (duration: 129.221562ms)","trace[1201102096] 'compare' (duration: 37.6063ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:42.079273Z","caller":"traceutil/trace.go:171","msg":"trace[1639032889] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"167.201511ms","start":"2026-04-26T05:45:41.912044Z","end":"2026-04-26T05:45:42.079245Z","steps":["trace[1639032889] 'process raft request' (duration: 166.998316ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:42.490671Z","caller":"traceutil/trace.go:171","msg":"trace[1324208128] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"191.823243ms","start":"2026-04-26T05:45:42.298821Z","end":"2026-04-26T05:45:42.490644Z","steps":["trace[1324208128] 'process raft request' (duration: 190.775455ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:42.70342Z","caller":"traceutil/trace.go:171","msg":"trace[802977772] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"158.850336ms","start":"2026-04-26T05:45:42.544537Z","end":"2026-04-26T05:45:42.703388Z","steps":["trace[802977772] 'process raft request' (duration: 123.65708ms)","trace[802977772] 'compare' (duration: 35.058042ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:45.461266Z","caller":"traceutil/trace.go:171","msg":"trace[1857159971] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"205.688744ms","start":"2026-04-26T05:45:45.25555Z","end":"2026-04-26T05:45:45.461238Z","steps":["trace[1857159971] 'process raft request' (duration: 205.1309ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:46.169269Z","caller":"traceutil/trace.go:171","msg":"trace[1469849510] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"150.607805ms","start":"2026-04-26T05:45:46.018632Z","end":"2026-04-26T05:45:46.16924Z","steps":["trace[1469849510] 'read index received' (duration: 148.756827ms)","trace[1469849510] 'applied index is now lower than readState.Index' (duration: 1.850038ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:46.169309Z","caller":"traceutil/trace.go:171","msg":"trace[1644308599] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"208.094308ms","start":"2026-04-26T05:45:45.961189Z","end":"2026-04-26T05:45:46.169283Z","steps":["trace[1644308599] 'process raft request' (duration: 206.343563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:46.806172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.140707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-26T05:45:46.806252Z","caller":"traceutil/trace.go:171","msg":"trace[171057410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"698.227399ms","start":"2026-04-26T05:45:46.108006Z","end":"2026-04-26T05:45:46.806233Z","steps":["trace[171057410] 'agreement among raft nodes before linearized reading' (duration: 61.399572ms)","trace[171057410] 'range keys from in-memory index tree' (duration: 636.638003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:46.806291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:46.107963Z","time spent":"698.318611ms","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-26T05:45:46.806562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.927844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-26T05:45:46.806602Z","caller":"traceutil/trace.go:171","msg":"trace[1095503171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"787.995236ms","start":"2026-04-26T05:45:46.018595Z","end":"2026-04-26T05:45:46.80659Z","steps":["trace[1095503171] 'agreement among raft nodes before linearized reading' (duration: 150.746818ms)","trace[1095503171] 'range keys from in-memory index tree' (duration: 637.140985ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:46.806632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:46.018578Z","time spent":"788.047148ms","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-26T05:45:46.806839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.501478ms","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-04-26T05:45:46.806878Z","caller":"traceutil/trace.go:171","msg":"trace[1050189964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"693.567119ms","start":"2026-04-26T05:45:46.113299Z","end":"2026-04-26T05:45:46.806866Z","steps":["trace[1050189964] 'agreement among raft nodes before linearized reading' (duration: 56.128476ms)","trace[1050189964] 'range keys from in-memory index tree' (duration: 637.32525ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:46.806907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:46.113278Z","time spent":"693.623011ms","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-26T05:45:46.806934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.24302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-26T05:45:46.807162Z","caller":"traceutil/trace.go:171","msg":"trace[358341401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"691.545108ms","start":"2026-04-26T05:45:46.11559Z","end":"2026-04-26T05:45:46.807135Z","steps":["trace[358341401] 'agreement among raft nodes before linearized reading' (duration: 53.835228ms)","trace[358341401] 'range keys from in-memory index tree' (duration: 637.33475ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:46.807241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:46.115557Z","time spent":"691.671282ms","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-26T05:45:46.80701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.829859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T05:45:46.807753Z","caller":"traceutil/trace.go:171","msg":"trace[1629254844] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"571.757772ms","start":"2026-04-26T05:45:46.23598Z","end":"2026-04-26T05:45:46.807738Z","steps":["trace[1629254844] 'process raft request' (duration: 327.102681ms)","trace[1629254844] 'compare' (duration: 243.337857ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:46.809191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:46.23595Z","time spent":"571.841974ms","remote":"127.0.0.1:45002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T05:45:47.108059Z","caller":"traceutil/trace.go:171","msg":"trace[659875325] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"296.77586ms","start":"2026-04-26T05:45:46.811243Z","end":"2026-04-26T05:45:47.108019Z","steps":["trace[659875325] 'process raft request' (duration: 213.894229ms)","trace[659875325] 'compare' (duration: 82.546783ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:47.108089Z","caller":"traceutil/trace.go:171","msg":"trace[423164440] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:655; }","duration":"294.241015ms","start":"2026-04-26T05:45:46.813813Z","end":"2026-04-26T05:45:47.108054Z","steps":["trace[423164440] 'read index received' (duration: 211.375854ms)","trace[423164440] 'applied index is now lower than readState.Index' (duration: 82.863411ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:47.108081Z","caller":"traceutil/trace.go:171","msg":"trace[1540470403] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"296.043401ms","start":"2026-04-26T05:45:46.811993Z","end":"2026-04-26T05:45:47.108036Z","steps":["trace[1540470403] 'process raft request' (duration: 295.946668ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:47.108348Z","caller":"traceutil/trace.go:171","msg":"trace[1679008987] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"295.99319ms","start":"2026-04-26T05:45:46.812344Z","end":"2026-04-26T05:45:47.108337Z","steps":["trace[1679008987] 'process raft request' (duration: 295.642491ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:47.108411Z","caller":"traceutil/trace.go:171","msg":"trace[2145083764] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"295.963099ms","start":"2026-04-26T05:45:46.812403Z","end":"2026-04-26T05:45:47.108366Z","steps":["trace[2145083764] 'process raft request' (duration: 295.61478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:47.108306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.455841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-26T05:45:47.108533Z","caller":"traceutil/trace.go:171","msg":"trace[53307788] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:632; }","duration":"294.725698ms","start":"2026-04-26T05:45:46.813785Z","end":"2026-04-26T05:45:47.108511Z","steps":["trace[53307788] 'agreement among raft nodes before linearized reading' (duration: 294.392149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:48.441934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.39762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T05:45:48.44215Z","caller":"traceutil/trace.go:171","msg":"trace[526634585] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:662; }","duration":"543.674359ms","start":"2026-04-26T05:45:47.89846Z","end":"2026-04-26T05:45:48.442134Z","steps":["trace[526634585] 'read index received' (duration: 326.032033ms)","trace[526634585] 'applied index is now lower than readState.Index' (duration: 217.639466ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:48.442324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.864353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-26T05:45:48.442361Z","caller":"traceutil/trace.go:171","msg":"trace[1235586652] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:637; }","duration":"543.944265ms","start":"2026-04-26T05:45:47.898406Z","end":"2026-04-26T05:45:48.44235Z","steps":["trace[1235586652] 'agreement among raft nodes before linearized reading' (duration: 543.781991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:48.442393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:47.898384Z","time spent":"544.001426ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"info","ts":"2026-04-26T05:45:48.442559Z","caller":"traceutil/trace.go:171","msg":"trace[521141717] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.233495845s","start":"2026-04-26T05:45:47.209042Z","end":"2026-04-26T05:45:48.442538Z","steps":["trace[521141717] 'process raft request' (duration: 1.232990692s)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:48.442549Z","caller":"traceutil/trace.go:171","msg":"trace[183474352] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.234339147s","start":"2026-04-26T05:45:47.208183Z","end":"2026-04-26T05:45:48.442402Z","steps":["trace[183474352] 'process raft request' (duration: 1.016280941s)","trace[183474352] 'compare' (duration: 217.268606ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:48.442827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:47.209029Z","time spent":"1.23368851s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T05:45:48.442839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:47.208161Z","time spent":"1.234602993s","remote":"127.0.0.1:44842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T05:45:49.209096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.696426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T05:45:49.20926Z","caller":"traceutil/trace.go:171","msg":"trace[389017551] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"414.330838ms","start":"2026-04-26T05:45:48.794917Z","end":"2026-04-26T05:45:49.209248Z","steps":["trace[389017551] 'read index received' (duration: 54.443377ms)","trace[389017551] 'applied index is now lower than readState.Index' (duration: 359.886501ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:49.209315Z","caller":"traceutil/trace.go:171","msg":"trace[160114475] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"782.01088ms","start":"2026-04-26T05:45:48.427297Z","end":"2026-04-26T05:45:49.209308Z","steps":["trace[160114475] 'process raft request' (duration: 422.049818ms)","trace[160114475] 'compare' (duration: 359.504351ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:49.20935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:48.427273Z","time spent":"782.061122ms","remote":"127.0.0.1:44842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":781,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T05:45:49.209461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:48.444036Z","time spent":"765.424023ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-26T05:45:49.20956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.649825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:45:49.209583Z","caller":"traceutil/trace.go:171","msg":"trace[352919321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"414.711497ms","start":"2026-04-26T05:45:48.794865Z","end":"2026-04-26T05:45:49.209576Z","steps":["trace[352919321] 'agreement among raft nodes before linearized reading' (duration: 414.666996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:49.2096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:48.79484Z","time spent":"414.755928ms","remote":"127.0.0.1:44786","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-04-26T05:45:50.807506Z","caller":"traceutil/trace.go:171","msg":"trace[1474104337] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"486.430611ms","start":"2026-04-26T05:45:50.321051Z","end":"2026-04-26T05:45:50.807481Z","steps":["trace[1474104337] 'process raft request' (duration: 486.280867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:50.807656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:50.321027Z","time spent":"486.552043ms","remote":"127.0.0.1:45030","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-04-26T05:45:50.826522Z","caller":"traceutil/trace.go:171","msg":"trace[409374159] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"253.777025ms","start":"2026-04-26T05:45:50.572721Z","end":"2026-04-26T05:45:50.826498Z","steps":["trace[409374159] 'process raft request' (duration: 253.444627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:52.295978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367264934476175,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-26T05:45:52.318017Z","caller":"traceutil/trace.go:171","msg":"trace[44003308] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"960.887259ms","start":"2026-04-26T05:45:51.357105Z","end":"2026-04-26T05:45:52.317992Z","steps":["trace[44003308] 'process raft request' (duration: 960.711425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:52.318194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:51.357091Z","time spent":"961.006652ms","remote":"127.0.0.1:45030","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-04-26T05:45:53.466051Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.148276995s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-26T05:45:53.795653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000692849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-26T05:45:53.795776Z","caller":"traceutil/trace.go:171","msg":"trace[2029500466] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000868213s","start":"2026-04-26T05:45:51.794889Z","end":"2026-04-26T05:45:53.795758Z","steps":["trace[2029500466] 'agreement among raft nodes before linearized reading' (duration: 2.00072554s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:53.79584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:51.794873Z","time spent":"2.000956506s","remote":"127.0.0.1:44786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-26T05:45:54.195489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.123704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T05:45:54.196138Z","caller":"traceutil/trace.go:171","msg":"trace[853992186] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"2.838933754s","start":"2026-04-26T05:45:51.35717Z","end":"2026-04-26T05:45:54.196104Z","steps":["trace[853992186] 'process raft request' (duration: 2.109113573s)","trace[853992186] 'compare' (duration: 727.937606ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:54.196321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:51.356969Z","time spent":"2.839261212s","remote":"127.0.0.1:45030","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-04-26T05:45:54.196436Z","caller":"traceutil/trace.go:171","msg":"trace[1136111463] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"2.838685598s","start":"2026-04-26T05:45:51.357723Z","end":"2026-04-26T05:45:54.196409Z","steps":["trace[1136111463] 'process raft request' (duration: 2.838216977s)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:54.196544Z","caller":"traceutil/trace.go:171","msg":"trace[2120580767] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"2.838722059s","start":"2026-04-26T05:45:51.357801Z","end":"2026-04-26T05:45:54.196523Z","steps":["trace[2120580767] 'process raft request' (duration: 2.838272528s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:54.196586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:51.357716Z","time spent":"2.838794151s","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T05:45:54.196667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:51.357795Z","time spent":"2.838825391s","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T05:45:54.197064Z","caller":"traceutil/trace.go:171","msg":"trace[1926263764] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:676; }","duration":"2.401559081s","start":"2026-04-26T05:45:51.794931Z","end":"2026-04-26T05:45:54.19649Z","steps":["trace[1926263764] 'read index received' (duration: 522.879321ms)","trace[1926263764] 'applied index is now lower than readState.Index' (duration: 1.87867861s)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:54.210607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.309561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:45:54.210668Z","caller":"traceutil/trace.go:171","msg":"trace[1946585726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"414.399744ms","start":"2026-04-26T05:45:53.796254Z","end":"2026-04-26T05:45:54.210654Z","steps":["trace[1946585726] 'agreement among raft nodes before linearized reading' (duration: 414.314282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:54.210707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:53.79624Z","time spent":"414.459525ms","remote":"127.0.0.1:44782","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-04-26T05:45:56.17664Z","caller":"traceutil/trace.go:171","msg":"trace[224177889] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"136.484335ms","start":"2026-04-26T05:45:56.040092Z","end":"2026-04-26T05:45:56.176576Z","steps":["trace[224177889] 'process raft request' (duration: 136.022124ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:56.383446Z","caller":"traceutil/trace.go:171","msg":"trace[1622693591] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"195.403541ms","start":"2026-04-26T05:45:56.188019Z","end":"2026-04-26T05:45:56.383422Z","steps":["trace[1622693591] 'process raft request' (duration: 192.335069ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:56.383599Z","caller":"traceutil/trace.go:171","msg":"trace[1053473794] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:693; }","duration":"182.004028ms","start":"2026-04-26T05:45:56.201475Z","end":"2026-04-26T05:45:56.383479Z","steps":["trace[1053473794] 'read index received' (duration: 32.874417ms)","trace[1053473794] 'applied index is now lower than readState.Index' (duration: 149.128701ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:45:56.384173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.668233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-26T05:45:56.384281Z","caller":"traceutil/trace.go:171","msg":"trace[916142734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:668; }","duration":"182.811327ms","start":"2026-04-26T05:45:56.201442Z","end":"2026-04-26T05:45:56.384253Z","steps":["trace[916142734] 'agreement among raft nodes before linearized reading' (duration: 182.461469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:56.384358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.842148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-26T05:45:56.384405Z","caller":"traceutil/trace.go:171","msg":"trace[194140859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:668; }","duration":"182.901099ms","start":"2026-04-26T05:45:56.201493Z","end":"2026-04-26T05:45:56.384394Z","steps":["trace[194140859] 'agreement among raft nodes before linearized reading' (duration: 182.751116ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:56.383845Z","caller":"traceutil/trace.go:171","msg":"trace[1664610402] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"195.697188ms","start":"2026-04-26T05:45:56.188133Z","end":"2026-04-26T05:45:56.38383Z","steps":["trace[1664610402] 'process raft request' (duration: 195.147905ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:56.383882Z","caller":"traceutil/trace.go:171","msg":"trace[86319789] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"195.687857ms","start":"2026-04-26T05:45:56.188178Z","end":"2026-04-26T05:45:56.383866Z","steps":["trace[86319789] 'process raft request' (duration: 195.228886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:56.384557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.978421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-26T05:45:56.384647Z","caller":"traceutil/trace.go:171","msg":"trace[85935166] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:668; }","duration":"183.107834ms","start":"2026-04-26T05:45:56.201529Z","end":"2026-04-26T05:45:56.384637Z","steps":["trace[85935166] 'agreement among raft nodes before linearized reading' (duration: 182.980941ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:57.031985Z","caller":"traceutil/trace.go:171","msg":"trace[648456090] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"447.714145ms","start":"2026-04-26T05:45:56.584234Z","end":"2026-04-26T05:45:57.031948Z","steps":["trace[648456090] 'process raft request' (duration: 447.591752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:57.032123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:56.584208Z","time spent":"447.838897ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T05:45:57.040867Z","caller":"traceutil/trace.go:171","msg":"trace[2053449759] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"256.839537ms","start":"2026-04-26T05:45:56.784013Z","end":"2026-04-26T05:45:57.040852Z","steps":["trace[2053449759] 'process raft request' (duration: 256.755495ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:45:57.040861Z","caller":"traceutil/trace.go:171","msg":"trace[1730438484] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:712; }","duration":"147.516006ms","start":"2026-04-26T05:45:56.893324Z","end":"2026-04-26T05:45:57.04084Z","steps":["trace[1730438484] 'read index received' (duration: 138.466676ms)","trace[1730438484] 'applied index is now lower than readState.Index' (duration: 9.04826ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:45:57.041015Z","caller":"traceutil/trace.go:171","msg":"trace[1425302705] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"456.250492ms","start":"2026-04-26T05:45:56.584572Z","end":"2026-04-26T05:45:57.040822Z","steps":["trace[1425302705] 'process raft request' (duration: 456.083738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:57.041107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.772352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-b6v2h\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-04-26T05:45:57.041149Z","caller":"traceutil/trace.go:171","msg":"trace[986234634] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-b6v2h; range_end:; response_count:1; response_revision:687; }","duration":"147.870905ms","start":"2026-04-26T05:45:56.893267Z","end":"2026-04-26T05:45:57.041138Z","steps":["trace[986234634] 'agreement among raft nodes before linearized reading' (duration: 147.636619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:45:57.041203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:45:56.584556Z","time spent":"456.542699ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T05:46:00.25782Z","caller":"traceutil/trace.go:171","msg":"trace[1092385145] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"127.81984ms","start":"2026-04-26T05:46:00.129978Z","end":"2026-04-26T05:46:00.257798Z","steps":["trace[1092385145] 'process raft request' (duration: 127.578134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:06.858087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.141022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T05:46:06.858266Z","caller":"traceutil/trace.go:171","msg":"trace[1366861925] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"305.87607ms","start":"2026-04-26T05:46:06.552374Z","end":"2026-04-26T05:46:06.85825Z","steps":["trace[1366861925] 'process raft request' (duration: 305.801538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:06.858343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:06.552369Z","time spent":"305.943551ms","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T05:46:06.858345Z","caller":"traceutil/trace.go:171","msg":"trace[1002477180] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"305.995192ms","start":"2026-04-26T05:46:06.552311Z","end":"2026-04-26T05:46:06.858306Z","steps":["trace[1002477180] 'process raft request' (duration: 93.546293ms)","trace[1002477180] 'compare' (duration: 212.04094ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:46:06.858497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:06.552286Z","time spent":"306.127355ms","remote":"127.0.0.1:45030","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-04-26T05:46:09.035278Z","caller":"traceutil/trace.go:171","msg":"trace[1722080643] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"169.25449ms","start":"2026-04-26T05:46:08.865996Z","end":"2026-04-26T05:46:09.03525Z","steps":["trace[1722080643] 'process raft request' (duration: 169.101076ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:09.099125Z","caller":"traceutil/trace.go:171","msg":"trace[255314818] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"232.761046ms","start":"2026-04-26T05:46:08.866336Z","end":"2026-04-26T05:46:09.099097Z","steps":["trace[255314818] 'process raft request' (duration: 231.468179ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:13.922014Z","caller":"traceutil/trace.go:171","msg":"trace[223853032] linearizableReadLoop","detail":"{readStateIndex:850; appliedIndex:849; }","duration":"196.228311ms","start":"2026-04-26T05:46:13.725761Z","end":"2026-04-26T05:46:13.92199Z","steps":["trace[223853032] 'read index received' (duration: 196.053197ms)","trace[223853032] 'applied index is now lower than readState.Index' (duration: 174.194µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:46:13.922086Z","caller":"traceutil/trace.go:171","msg":"trace[950048163] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"200.404765ms","start":"2026-04-26T05:46:13.721659Z","end":"2026-04-26T05:46:13.922064Z","steps":["trace[950048163] 'process raft request' (duration: 200.204151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:13.922164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.378074ms","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-04-26T05:46:13.922199Z","caller":"traceutil/trace.go:171","msg":"trace[397508679] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:815; }","duration":"196.473806ms","start":"2026-04-26T05:46:13.725715Z","end":"2026-04-26T05:46:13.922189Z","steps":["trace[397508679] 'agreement among raft nodes before linearized reading' (duration: 196.372264ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:14.069583Z","caller":"traceutil/trace.go:171","msg":"trace[1472479480] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"141.244058ms","start":"2026-04-26T05:46:13.928314Z","end":"2026-04-26T05:46:14.069558Z","steps":["trace[1472479480] 'process raft request' (duration: 94.03481ms)","trace[1472479480] 'compare' (duration: 46.913132ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:46:14.362049Z","caller":"traceutil/trace.go:171","msg":"trace[649239508] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"147.41735ms","start":"2026-04-26T05:46:14.214557Z","end":"2026-04-26T05:46:14.361975Z","steps":["trace[649239508] 'process raft request' (duration: 73.060797ms)","trace[649239508] 'compare' (duration: 73.136209ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:46:16.134716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333794553s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T05:46:16.134829Z","caller":"traceutil/trace.go:171","msg":"trace[1047332842] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:855; }","duration":"1.530142564s","start":"2026-04-26T05:46:14.60467Z","end":"2026-04-26T05:46:16.134813Z","steps":["trace[1047332842] 'read index received' (duration: 196.070117ms)","trace[1047332842] 'applied index is now lower than readState.Index' (duration: 1.334071177s)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:46:16.134891Z","caller":"traceutil/trace.go:171","msg":"trace[1500253534] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"1.696633706s","start":"2026-04-26T05:46:14.438248Z","end":"2026-04-26T05:46:16.134882Z","steps":["trace[1500253534] 'process raft request' (duration: 362.584759ms)","trace[1500253534] 'compare' (duration: 1.333514646s)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:46:16.134954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530334778s","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":"warn","ts":"2026-04-26T05:46:16.134998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.438226Z","time spent":"1.696686207s","remote":"127.0.0.1:45030","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-04-26T05:46:16.135035Z","caller":"traceutil/trace.go:171","msg":"trace[733065720] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:821; }","duration":"1.53047378s","start":"2026-04-26T05:46:14.604543Z","end":"2026-04-26T05:46:16.135017Z","steps":["trace[733065720] 'agreement among raft nodes before linearized reading' (duration: 1.530339898s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:16.135077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.604519Z","time spent":"1.530547572s","remote":"127.0.0.1:45270","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-04-26T05:46:16.455478Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.269413719s","expected-duration":"1s"} {"level":"info","ts":"2026-04-26T05:46:16.456405Z","caller":"traceutil/trace.go:171","msg":"trace[781729259] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:856; }","duration":"321.527906ms","start":"2026-04-26T05:46:16.134851Z","end":"2026-04-26T05:46:16.456379Z","steps":["trace[781729259] 'read index received' (duration: 320.748661ms)","trace[781729259] 'applied index is now lower than readState.Index' (duration: 778.145µs)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:46:16.456857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.58120117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:46:16.456925Z","caller":"traceutil/trace.go:171","msg":"trace[1117494450] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:822; }","duration":"1.581329262s","start":"2026-04-26T05:46:14.87558Z","end":"2026-04-26T05:46:16.456909Z","steps":["trace[1117494450] 'agreement among raft nodes before linearized reading' (duration: 1.581136849s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:16.456956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.875571Z","time spent":"1.581377533s","remote":"127.0.0.1:54480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-26T05:46:16.456995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718828583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T05:46:16.456978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549407571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T05:46:16.457032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.582226531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:46:16.457044Z","caller":"traceutil/trace.go:171","msg":"trace[1818641026] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:822; }","duration":"1.718896715s","start":"2026-04-26T05:46:14.738123Z","end":"2026-04-26T05:46:16.457019Z","steps":["trace[1818641026] 'agreement among raft nodes before linearized reading' (duration: 1.718855444s)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:16.457069Z","caller":"traceutil/trace.go:171","msg":"trace[1602519589] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:822; }","duration":"1.582320292s","start":"2026-04-26T05:46:14.874738Z","end":"2026-04-26T05:46:16.457058Z","steps":["trace[1602519589] 'agreement among raft nodes before linearized reading' (duration: 1.582271131s)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:16.457069Z","caller":"traceutil/trace.go:171","msg":"trace[832375681] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"1.271128483s","start":"2026-04-26T05:46:15.185921Z","end":"2026-04-26T05:46:16.457049Z","steps":["trace[832375681] 'process raft request' (duration: 1.269758576s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:16.457097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.738102Z","time spent":"1.718987356s","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-04-26T05:46:16.457104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.874715Z","time spent":"1.582381453s","remote":"127.0.0.1:54480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-26T05:46:16.457146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.550164137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:46:16.457177Z","caller":"traceutil/trace.go:171","msg":"trace[1304594982] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:822; }","duration":"1.550215708s","start":"2026-04-26T05:46:14.906955Z","end":"2026-04-26T05:46:16.45717Z","steps":["trace[1304594982] 'agreement among raft nodes before linearized reading' (duration: 1.550163577s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:16.457198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.906942Z","time spent":"1.550251008s","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-04-26T05:46:16.457197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275241675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-26T05:46:16.457194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:15.185904Z","time spent":"1.271218055s","remote":"127.0.0.1:45002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T05:46:16.456984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.552603ms","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-04-26T05:46:16.457229Z","caller":"traceutil/trace.go:171","msg":"trace[916287950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:822; }","duration":"1.275300846s","start":"2026-04-26T05:46:15.181919Z","end":"2026-04-26T05:46:16.45722Z","steps":["trace[916287950] 'agreement among raft nodes before linearized reading' (duration: 1.275169433s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:16.457257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.719347893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:46:16.457279Z","caller":"traceutil/trace.go:171","msg":"trace[1651879954] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:822; }","duration":"1.719371714s","start":"2026-04-26T05:46:14.737901Z","end":"2026-04-26T05:46:16.457273Z","steps":["trace[1651879954] 'agreement among raft nodes before linearized reading' (duration: 1.719335983s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:16.457305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.737868Z","time spent":"1.719432095s","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-04-26T05:46:16.457323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.550369871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:46:16.457348Z","caller":"traceutil/trace.go:171","msg":"trace[1079699119] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:822; }","duration":"1.550415442s","start":"2026-04-26T05:46:14.906927Z","end":"2026-04-26T05:46:16.457342Z","steps":["trace[1079699119] 'agreement among raft nodes before linearized reading' (duration: 1.550377291s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:16.456965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.661483001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T05:46:16.45737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374730386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-26T05:46:16.457396Z","caller":"traceutil/trace.go:171","msg":"trace[801314393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:822; }","duration":"1.661958251s","start":"2026-04-26T05:46:14.79543Z","end":"2026-04-26T05:46:16.457388Z","steps":["trace[801314393] 'agreement among raft nodes before linearized reading' (duration: 1.661491252s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:16.457398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.565354331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T05:46:16.457416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.795418Z","time spent":"1.661992871s","remote":"127.0.0.1:44786","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-04-26T05:46:16.457417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.720349203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-26T05:46:16.45741Z","caller":"traceutil/trace.go:171","msg":"trace[1762334129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:822; }","duration":"1.374811718s","start":"2026-04-26T05:46:15.082587Z","end":"2026-04-26T05:46:16.457399Z","steps":["trace[1762334129] 'agreement among raft nodes before linearized reading' (duration: 1.374710166s)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:16.45706Z","caller":"traceutil/trace.go:171","msg":"trace[1629996086] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:822; }","duration":"1.549511424s","start":"2026-04-26T05:46:14.907531Z","end":"2026-04-26T05:46:16.457042Z","steps":["trace[1629996086] 'agreement among raft nodes before linearized reading' (duration: 1.549378631s)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:16.457444Z","caller":"traceutil/trace.go:171","msg":"trace[1582988497] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:822; }","duration":"1.720411655s","start":"2026-04-26T05:46:14.737025Z","end":"2026-04-26T05:46:16.457437Z","steps":["trace[1582988497] 'agreement among raft nodes before linearized reading' (duration: 1.720328013s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:16.457447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.565465704s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T05:46:16.457463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.737008Z","time spent":"1.720450965s","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-26T05:46:16.457273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:15.181905Z","time spent":"1.275359877s","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-26T05:46:16.457473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.907523Z","time spent":"1.549935192s","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-04-26T05:46:16.457491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.565748129s","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-04-26T05:46:16.457508Z","caller":"traceutil/trace.go:171","msg":"trace[133695486] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:822; }","duration":"1.56578565s","start":"2026-04-26T05:46:14.891717Z","end":"2026-04-26T05:46:16.457503Z","steps":["trace[133695486] 'agreement among raft nodes before linearized reading' (duration: 1.56576072s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:16.457527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.891684Z","time spent":"1.565838461s","remote":"127.0.0.1:54486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-04-26T05:46:16.457243Z","caller":"traceutil/trace.go:171","msg":"trace[1830240317] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:822; }","duration":"422.866119ms","start":"2026-04-26T05:46:16.034369Z","end":"2026-04-26T05:46:16.457235Z","steps":["trace[1830240317] 'agreement among raft nodes before linearized reading' (duration: 422.541322ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:16.457417Z","caller":"traceutil/trace.go:171","msg":"trace[2121134871] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:822; }","duration":"1.565388902s","start":"2026-04-26T05:46:14.892022Z","end":"2026-04-26T05:46:16.457411Z","steps":["trace[2121134871] 'agreement among raft nodes before linearized reading' (duration: 1.565359282s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:16.457588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:16.034345Z","time spent":"423.235747ms","remote":"127.0.0.1:44932","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-04-26T05:46:16.456947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.719191251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T05:46:16.457616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.892011Z","time spent":"1.565596806s","remote":"127.0.0.1:54486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"info","ts":"2026-04-26T05:46:16.457466Z","caller":"traceutil/trace.go:171","msg":"trace[197726728] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:822; }","duration":"1.565535515s","start":"2026-04-26T05:46:14.891925Z","end":"2026-04-26T05:46:16.457461Z","steps":["trace[197726728] 'agreement among raft nodes before linearized reading' (duration: 1.565505675s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:16.457369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.906917Z","time spent":"1.550445622s","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-04-26T05:46:16.457458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:15.082562Z","time spent":"1.3748871s","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-26T05:46:16.45769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.891897Z","time spent":"1.56577324s","remote":"127.0.0.1:54486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"info","ts":"2026-04-26T05:46:16.457636Z","caller":"traceutil/trace.go:171","msg":"trace[1388941355] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:822; }","duration":"1.719914795s","start":"2026-04-26T05:46:14.737714Z","end":"2026-04-26T05:46:16.457628Z","steps":["trace[1388941355] 'agreement among raft nodes before linearized reading' (duration: 1.71916517s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:16.457891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.737709Z","time spent":"1.72017181s","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-26T05:46:16.458138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.780563992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-26T05:46:16.458176Z","caller":"traceutil/trace.go:171","msg":"trace[2043724226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:822; }","duration":"1.780704264s","start":"2026-04-26T05:46:14.677463Z","end":"2026-04-26T05:46:16.458167Z","steps":["trace[2043724226] 'agreement among raft nodes before linearized reading' (duration: 1.780546501s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:16.458206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.677422Z","time spent":"1.780773246s","remote":"127.0.0.1:45030","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-04-26T05:46:16.458255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.583123118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:46:16.458327Z","caller":"traceutil/trace.go:171","msg":"trace[1426032189] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:822; }","duration":"1.583428504s","start":"2026-04-26T05:46:14.874889Z","end":"2026-04-26T05:46:16.458317Z","steps":["trace[1426032189] 'agreement among raft nodes before linearized reading' (duration: 1.581845823s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:16.45838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:14.874858Z","time spent":"1.583514635s","remote":"127.0.0.1:54480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-26T05:46:20.982679Z","caller":"traceutil/trace.go:171","msg":"trace[1658698173] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"123.889466ms","start":"2026-04-26T05:46:20.858757Z","end":"2026-04-26T05:46:20.982646Z","steps":["trace[1658698173] 'process raft request' (duration: 123.618921ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:31.420173Z","caller":"traceutil/trace.go:171","msg":"trace[525210497] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1024; }","duration":"150.676773ms","start":"2026-04-26T05:46:31.269472Z","end":"2026-04-26T05:46:31.420149Z","steps":["trace[525210497] 'read index received' (duration: 150.465619ms)","trace[525210497] 'applied index is now lower than readState.Index' (duration: 210.094µs)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:46:31.420314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.822656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-26T05:46:31.420353Z","caller":"traceutil/trace.go:171","msg":"trace[366730267] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:987; }","duration":"150.917928ms","start":"2026-04-26T05:46:31.269424Z","end":"2026-04-26T05:46:31.420341Z","steps":["trace[366730267] 'agreement among raft nodes before linearized reading' (duration: 150.819536ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:31.420289Z","caller":"traceutil/trace.go:171","msg":"trace[562499656] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"308.866989ms","start":"2026-04-26T05:46:31.111374Z","end":"2026-04-26T05:46:31.420241Z","steps":["trace[562499656] 'process raft request' (duration: 308.640835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:31.420827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:31.111359Z","time spent":"309.339757ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T05:46:31.626705Z","caller":"traceutil/trace.go:171","msg":"trace[1185844966] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"198.222347ms","start":"2026-04-26T05:46:31.428458Z","end":"2026-04-26T05:46:31.62668Z","steps":["trace[1185844966] 'process raft request' (duration: 198.147275ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:31.626767Z","caller":"traceutil/trace.go:171","msg":"trace[737656805] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"198.43199ms","start":"2026-04-26T05:46:31.428289Z","end":"2026-04-26T05:46:31.626721Z","steps":["trace[737656805] 'process raft request' (duration: 172.809396ms)","trace[737656805] 'compare' (duration: 25.353529ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:46:31.648423Z","caller":"traceutil/trace.go:171","msg":"trace[1887954307] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"157.334771ms","start":"2026-04-26T05:46:31.49107Z","end":"2026-04-26T05:46:31.648404Z","steps":["trace[1887954307] 'process raft request' (duration: 157.224719ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:32.260269Z","caller":"traceutil/trace.go:171","msg":"trace[121568818] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"191.151177ms","start":"2026-04-26T05:46:32.069095Z","end":"2026-04-26T05:46:32.260246Z","steps":["trace[121568818] 'process raft request' (duration: 190.74264ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:32.412002Z","caller":"traceutil/trace.go:171","msg":"trace[756163563] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"146.293747ms","start":"2026-04-26T05:46:32.265678Z","end":"2026-04-26T05:46:32.411972Z","steps":["trace[756163563] 'process raft request' (duration: 129.860597ms)","trace[756163563] 'compare' (duration: 16.235866ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:46:38.821721Z","caller":"traceutil/trace.go:171","msg":"trace[752335077] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"116.744368ms","start":"2026-04-26T05:46:38.70494Z","end":"2026-04-26T05:46:38.821684Z","steps":["trace[752335077] 'process raft request' (duration: 100.267799ms)","trace[752335077] 'compare' (duration: 16.258405ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:46:45.097923Z","caller":"traceutil/trace.go:171","msg":"trace[494972684] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"245.305427ms","start":"2026-04-26T05:46:44.852576Z","end":"2026-04-26T05:46:45.097881Z","steps":["trace[494972684] 'process raft request' (duration: 245.157084ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:45.098337Z","caller":"traceutil/trace.go:171","msg":"trace[1361268213] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"210.800363ms","start":"2026-04-26T05:46:44.88705Z","end":"2026-04-26T05:46:45.09785Z","steps":["trace[1361268213] 'read index received' (duration: 210.61166ms)","trace[1361268213] 'applied index is now lower than readState.Index' (duration: 187.683µs)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:46:45.098402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.350493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-26T05:46:45.098496Z","caller":"traceutil/trace.go:171","msg":"trace[2018784939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1048; }","duration":"211.470424ms","start":"2026-04-26T05:46:44.88698Z","end":"2026-04-26T05:46:45.09845Z","steps":["trace[2018784939] 'agreement among raft nodes before linearized reading' (duration: 210.993276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:45.098614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.946783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-26T05:46:45.098657Z","caller":"traceutil/trace.go:171","msg":"trace[2061076894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1048; }","duration":"179.031034ms","start":"2026-04-26T05:46:44.919616Z","end":"2026-04-26T05:46:45.098647Z","steps":["trace[2061076894] 'agreement among raft nodes before linearized reading' (duration: 178.79468ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:47.214752Z","caller":"traceutil/trace.go:171","msg":"trace[1299568787] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"108.540351ms","start":"2026-04-26T05:46:47.106174Z","end":"2026-04-26T05:46:47.214714Z","steps":["trace[1299568787] 'process raft request' (duration: 108.207366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:47.498453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.725025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T05:46:47.498558Z","caller":"traceutil/trace.go:171","msg":"trace[1786009058] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"279.636315ms","start":"2026-04-26T05:46:47.218904Z","end":"2026-04-26T05:46:47.49854Z","steps":["trace[1786009058] 'process raft request' (duration: 69.737198ms)","trace[1786009058] 'compare' (duration: 209.496961ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:46:53.072011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.306453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T05:46:53.072188Z","caller":"traceutil/trace.go:171","msg":"trace[603620926] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"446.321684ms","start":"2026-04-26T05:46:52.625848Z","end":"2026-04-26T05:46:53.07217Z","steps":["trace[603620926] 'process raft request' (duration: 446.263363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:53.072262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:52.625822Z","time spent":"446.408446ms","remote":"127.0.0.1:45030","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-04-26T05:46:53.072262Z","caller":"traceutil/trace.go:171","msg":"trace[1636211768] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"526.504824ms","start":"2026-04-26T05:46:52.545716Z","end":"2026-04-26T05:46:53.072221Z","steps":["trace[1636211768] 'read index received' (duration: 81.910257ms)","trace[1636211768] 'applied index is now lower than readState.Index' (duration: 444.590617ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:46:53.07231Z","caller":"traceutil/trace.go:171","msg":"trace[866835224] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"686.378697ms","start":"2026-04-26T05:46:52.38583Z","end":"2026-04-26T05:46:53.072209Z","steps":["trace[866835224] 'process raft request' (duration: 241.78223ms)","trace[866835224] 'compare' (duration: 444.179731ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:46:53.072681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:52.385806Z","time spent":"686.705943ms","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T05:46:53.072708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.958341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-365004cc-aa49-4b84-92cf-9f3c6708ae53\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-04-26T05:46:53.072908Z","caller":"traceutil/trace.go:171","msg":"trace[460430182] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-365004cc-aa49-4b84-92cf-9f3c6708ae53; range_end:; response_count:1; response_revision:1089; }","duration":"527.202296ms","start":"2026-04-26T05:46:52.545683Z","end":"2026-04-26T05:46:53.072886Z","steps":["trace[460430182] 'agreement among raft nodes before linearized reading' (duration: 526.786829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:53.073133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:52.54567Z","time spent":"527.443249ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3602,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-365004cc-aa49-4b84-92cf-9f3c6708ae53\" "} {"level":"warn","ts":"2026-04-26T05:46:53.573518Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367264934477083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T05:46:54.144494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.349951ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-26T05:46:54.14462Z","caller":"traceutil/trace.go:171","msg":"trace[1237095263] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"1.072212564s","start":"2026-04-26T05:46:53.072384Z","end":"2026-04-26T05:46:54.144597Z","steps":["trace[1237095263] 'read index received' (duration: 561.68834ms)","trace[1237095263] 'applied index is now lower than readState.Index' (duration: 510.522364ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:46:54.145126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236533391s","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-04-26T05:46:54.145136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.31409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T05:46:54.145138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349881939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:46:54.145177Z","caller":"traceutil/trace.go:171","msg":"trace[1265739524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1089; }","duration":"1.236632543s","start":"2026-04-26T05:46:52.908531Z","end":"2026-04-26T05:46:54.145164Z","steps":["trace[1265739524] 'agreement among raft nodes before linearized reading' (duration: 1.23644615s)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:54.145196Z","caller":"traceutil/trace.go:171","msg":"trace[608520268] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1089; }","duration":"922.428991ms","start":"2026-04-26T05:46:53.222752Z","end":"2026-04-26T05:46:54.145181Z","steps":["trace[608520268] 'agreement among raft nodes before linearized reading' (duration: 922.31831ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:54.145204Z","caller":"traceutil/trace.go:171","msg":"trace[2107934049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1089; }","duration":"1.34998417s","start":"2026-04-26T05:46:52.795206Z","end":"2026-04-26T05:46:54.14519Z","steps":["trace[2107934049] 'agreement among raft nodes before linearized reading' (duration: 1.349877349s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:54.145221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:52.908509Z","time spent":"1.236702234s","remote":"127.0.0.1:45030","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-04-26T05:46:54.145234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:52.795189Z","time spent":"1.350039811s","remote":"127.0.0.1:44786","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-04-26T05:46:54.145313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.555584376s","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-04-26T05:46:54.145233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:53.222731Z","time spent":"922.495163ms","remote":"127.0.0.1:33670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-26T05:46:54.145382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.23775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-26T05:46:54.145374Z","caller":"traceutil/trace.go:171","msg":"trace[1287508206] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1089; }","duration":"1.555681778s","start":"2026-04-26T05:46:52.589676Z","end":"2026-04-26T05:46:54.145357Z","steps":["trace[1287508206] 'agreement among raft nodes before linearized reading' (duration: 1.555529186s)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:54.145409Z","caller":"traceutil/trace.go:171","msg":"trace[258770760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1089; }","duration":"797.293321ms","start":"2026-04-26T05:46:53.348109Z","end":"2026-04-26T05:46:54.145402Z","steps":["trace[258770760] 'agreement among raft nodes before linearized reading' (duration: 797.24059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:54.145427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:53.348093Z","time spent":"797.330802ms","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-26T05:46:54.145425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:52.589656Z","time spent":"1.555756239s","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-26T05:46:54.145338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.458687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-26T05:46:54.14555Z","caller":"traceutil/trace.go:171","msg":"trace[982411687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1089; }","duration":"616.699071ms","start":"2026-04-26T05:46:53.528834Z","end":"2026-04-26T05:46:54.145534Z","steps":["trace[982411687] 'agreement among raft nodes before linearized reading' (duration: 616.413546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:54.145572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.602835ms","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-04-26T05:46:54.145602Z","caller":"traceutil/trace.go:171","msg":"trace[1255270224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1089; }","duration":"815.678996ms","start":"2026-04-26T05:46:53.329916Z","end":"2026-04-26T05:46:54.145595Z","steps":["trace[1255270224] 'agreement among raft nodes before linearized reading' (duration: 815.606784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:54.145596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:53.528814Z","time spent":"616.768221ms","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-26T05:46:54.145621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:53.329893Z","time spent":"815.721327ms","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-26T05:46:54.60682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.171497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T05:46:54.607138Z","caller":"traceutil/trace.go:171","msg":"trace[2060269342] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"457.943513ms","start":"2026-04-26T05:46:54.149158Z","end":"2026-04-26T05:46:54.607101Z","steps":["trace[2060269342] 'process raft request' (duration: 187.325399ms)","trace[2060269342] 'compare' (duration: 269.885062ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:46:54.607184Z","caller":"traceutil/trace.go:171","msg":"trace[1501471839] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1133; }","duration":"454.218346ms","start":"2026-04-26T05:46:54.152941Z","end":"2026-04-26T05:46:54.607159Z","steps":["trace[1501471839] 'read index received' (duration: 89.098874ms)","trace[1501471839] 'applied index is now lower than readState.Index' (duration: 365.117412ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:46:54.607268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.322577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:46:54.607303Z","caller":"traceutil/trace.go:171","msg":"trace[999408660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1094; }","duration":"454.369268ms","start":"2026-04-26T05:46:54.15292Z","end":"2026-04-26T05:46:54.607289Z","steps":["trace[999408660] 'agreement among raft nodes before linearized reading' (duration: 454.306787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:54.60733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:54.149139Z","time spent":"458.076876ms","remote":"127.0.0.1:45030","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-04-26T05:46:54.607352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:54.152913Z","time spent":"454.431089ms","remote":"127.0.0.1:44786","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-04-26T05:46:54.607315Z","caller":"traceutil/trace.go:171","msg":"trace[1010202644] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"457.174772ms","start":"2026-04-26T05:46:54.150114Z","end":"2026-04-26T05:46:54.607288Z","steps":["trace[1010202644] 'process raft request' (duration: 456.924758ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:54.607364Z","caller":"traceutil/trace.go:171","msg":"trace[618438035] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"455.669028ms","start":"2026-04-26T05:46:54.151669Z","end":"2026-04-26T05:46:54.607338Z","steps":["trace[618438035] 'process raft request' (duration: 455.425535ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:54.607469Z","caller":"traceutil/trace.go:171","msg":"trace[2106199671] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"458.187507ms","start":"2026-04-26T05:46:54.149256Z","end":"2026-04-26T05:46:54.607443Z","steps":["trace[2106199671] 'process raft request' (duration: 457.7059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:46:54.607503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:54.150102Z","time spent":"457.347774ms","remote":"127.0.0.1:45030","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-04-26T05:46:54.607506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:54.151661Z","time spent":"455.77024ms","remote":"127.0.0.1:45030","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-04-26T05:46:54.607655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:54.149247Z","time spent":"458.273498ms","remote":"127.0.0.1:45030","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-04-26T05:46:54.979055Z","caller":"traceutil/trace.go:171","msg":"trace[212904505] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"620.572331ms","start":"2026-04-26T05:46:54.358456Z","end":"2026-04-26T05:46:54.979028Z","steps":["trace[212904505] 'process raft request' (duration: 602.580783ms)","trace[212904505] 'compare' (duration: 17.647843ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:46:54.979431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:46:54.358431Z","time spent":"620.692093ms","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T05:46:55.905383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.922597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:46:55.905466Z","caller":"traceutil/trace.go:171","msg":"trace[944712839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1096; }","duration":"110.051918ms","start":"2026-04-26T05:46:55.795398Z","end":"2026-04-26T05:46:55.90545Z","steps":["trace[944712839] 'range keys from in-memory index tree' (duration: 109.857575ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:56.481497Z","caller":"traceutil/trace.go:171","msg":"trace[322098272] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"135.983955ms","start":"2026-04-26T05:46:56.34549Z","end":"2026-04-26T05:46:56.481474Z","steps":["trace[322098272] 'process raft request' (duration: 135.857683ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:56.823252Z","caller":"traceutil/trace.go:171","msg":"trace[951768264] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"125.632377ms","start":"2026-04-26T05:46:56.697554Z","end":"2026-04-26T05:46:56.823187Z","steps":["trace[951768264] 'process raft request' (duration: 95.213893ms)","trace[951768264] 'compare' (duration: 30.246302ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:46:56.823478Z","caller":"traceutil/trace.go:171","msg":"trace[1972744940] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"125.901801ms","start":"2026-04-26T05:46:56.697562Z","end":"2026-04-26T05:46:56.823463Z","steps":["trace[1972744940] 'process raft request' (duration: 125.574036ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:56.823593Z","caller":"traceutil/trace.go:171","msg":"trace[2054311420] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"125.576276ms","start":"2026-04-26T05:46:56.697999Z","end":"2026-04-26T05:46:56.823575Z","steps":["trace[2054311420] 'process raft request' (duration: 125.322192ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:58.938296Z","caller":"traceutil/trace.go:171","msg":"trace[790110069] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"107.786168ms","start":"2026-04-26T05:46:58.830476Z","end":"2026-04-26T05:46:58.938262Z","steps":["trace[790110069] 'process raft request' (duration: 107.659206ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:58.93909Z","caller":"traceutil/trace.go:171","msg":"trace[1663653217] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"106.966435ms","start":"2026-04-26T05:46:58.832107Z","end":"2026-04-26T05:46:58.939074Z","steps":["trace[1663653217] 'process raft request' (duration: 106.932185ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:46:58.939392Z","caller":"traceutil/trace.go:171","msg":"trace[268810086] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"108.401697ms","start":"2026-04-26T05:46:58.830978Z","end":"2026-04-26T05:46:58.93938Z","steps":["trace[268810086] 'process raft request' (duration: 107.997221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:47:03.0721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.417387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-26T05:47:03.07218Z","caller":"traceutil/trace.go:171","msg":"trace[2136973959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1137; }","duration":"120.555239ms","start":"2026-04-26T05:47:02.951607Z","end":"2026-04-26T05:47:03.072162Z","steps":["trace[2136973959] 'range keys from in-memory index tree' (duration: 120.224884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:47:03.072225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.928805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-26T05:47:03.072254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.96798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-26T05:47:03.072311Z","caller":"traceutil/trace.go:171","msg":"trace[444258627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1137; }","duration":"118.049812ms","start":"2026-04-26T05:47:02.954247Z","end":"2026-04-26T05:47:03.072297Z","steps":["trace[444258627] 'range keys from in-memory index tree' (duration: 117.602276ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:47:03.072317Z","caller":"traceutil/trace.go:171","msg":"trace[24961580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1137; }","duration":"119.058706ms","start":"2026-04-26T05:47:02.953234Z","end":"2026-04-26T05:47:03.072293Z","steps":["trace[24961580] 'range keys from in-memory index tree' (duration: 118.697091ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:47:03.208624Z","caller":"traceutil/trace.go:171","msg":"trace[1224386696] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"131.77011ms","start":"2026-04-26T05:47:03.076824Z","end":"2026-04-26T05:47:03.208594Z","steps":["trace[1224386696] 'process raft request' (duration: 102.936686ms)","trace[1224386696] 'compare' (duration: 28.645171ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:47:03.208926Z","caller":"traceutil/trace.go:171","msg":"trace[267141547] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"130.679504ms","start":"2026-04-26T05:47:03.07822Z","end":"2026-04-26T05:47:03.2089Z","steps":["trace[267141547] 'process raft request' (duration: 130.332089ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:47:03.209397Z","caller":"traceutil/trace.go:171","msg":"trace[1267275771] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"109.506722ms","start":"2026-04-26T05:47:03.099868Z","end":"2026-04-26T05:47:03.209375Z","steps":["trace[1267275771] 'process raft request' (duration: 109.36871ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:47:03.209365Z","caller":"traceutil/trace.go:171","msg":"trace[213994815] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"129.6977ms","start":"2026-04-26T05:47:03.079627Z","end":"2026-04-26T05:47:03.209325Z","steps":["trace[213994815] 'process raft request' (duration: 129.123901ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:47:03.876948Z","caller":"traceutil/trace.go:171","msg":"trace[620745747] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"539.96777ms","start":"2026-04-26T05:47:03.336959Z","end":"2026-04-26T05:47:03.876927Z","steps":["trace[620745747] 'process raft request' (duration: 539.743326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:47:03.877122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T05:47:03.336931Z","time spent":"540.097082ms","remote":"127.0.0.1:45030","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-04-26T05:47:06.72838Z","caller":"traceutil/trace.go:171","msg":"trace[437591135] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"101.259749ms","start":"2026-04-26T05:47:06.627084Z","end":"2026-04-26T05:47:06.728344Z","steps":["trace[437591135] 'process raft request' (duration: 101.137988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:47:07.004896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.284343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-26T05:47:07.004988Z","caller":"traceutil/trace.go:171","msg":"trace[1024592991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1150; }","duration":"195.454966ms","start":"2026-04-26T05:47:06.809512Z","end":"2026-04-26T05:47:07.004967Z","steps":["trace[1024592991] 'range keys from in-memory index tree' (duration: 195.06183ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:47:08.933549Z","caller":"traceutil/trace.go:171","msg":"trace[368498321] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"119.990095ms","start":"2026-04-26T05:47:08.813534Z","end":"2026-04-26T05:47:08.933524Z","steps":["trace[368498321] 'process raft request' (duration: 119.790412ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:47:11.597925Z","caller":"traceutil/trace.go:171","msg":"trace[1927447519] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"140.183737ms","start":"2026-04-26T05:47:11.457709Z","end":"2026-04-26T05:47:11.597892Z","steps":["trace[1927447519] 'process raft request' (duration: 53.914284ms)","trace[1927447519] 'compare' (duration: 86.163412ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:47:13.115686Z","caller":"traceutil/trace.go:171","msg":"trace[351662357] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"194.531129ms","start":"2026-04-26T05:47:12.921129Z","end":"2026-04-26T05:47:13.11566Z","steps":["trace[351662357] 'read index received' (duration: 194.277615ms)","trace[351662357] 'applied index is now lower than readState.Index' (duration: 252.654µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:47:13.115775Z","caller":"traceutil/trace.go:171","msg":"trace[301950786] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"238.473159ms","start":"2026-04-26T05:47:12.877279Z","end":"2026-04-26T05:47:13.115752Z","steps":["trace[301950786] 'process raft request' (duration: 238.201086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:47:13.11591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.751642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:47:13.116513Z","caller":"traceutil/trace.go:171","msg":"trace[906164209] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1176; }","duration":"195.224869ms","start":"2026-04-26T05:47:12.921123Z","end":"2026-04-26T05:47:13.116348Z","steps":["trace[906164209] 'agreement among raft nodes before linearized reading' (duration: 194.701711ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:47:19.446923Z","caller":"traceutil/trace.go:171","msg":"trace[2017898499] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"132.341223ms","start":"2026-04-26T05:47:19.314567Z","end":"2026-04-26T05:47:19.446909Z","steps":["trace[2017898499] 'process raft request' (duration: 132.204472ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:47:23.618041Z","caller":"traceutil/trace.go:171","msg":"trace[769624154] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"116.655152ms","start":"2026-04-26T05:47:23.501358Z","end":"2026-04-26T05:47:23.618013Z","steps":["trace[769624154] 'process raft request' (duration: 116.48305ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:47:24.197576Z","caller":"traceutil/trace.go:171","msg":"trace[591332000] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1272; }","duration":"135.999116ms","start":"2026-04-26T05:47:24.061557Z","end":"2026-04-26T05:47:24.197556Z","steps":["trace[591332000] 'read index received' (duration: 135.873745ms)","trace[591332000] 'applied index is now lower than readState.Index' (duration: 124.421µs)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:47:24.197866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.29002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" ","response":"range_response_count:1 size:743"} {"level":"info","ts":"2026-04-26T05:47:24.197757Z","caller":"traceutil/trace.go:171","msg":"trace[1689361302] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"141.896376ms","start":"2026-04-26T05:47:24.055831Z","end":"2026-04-26T05:47:24.197727Z","steps":["trace[1689361302] 'process raft request' (duration: 141.603502ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:47:24.197949Z","caller":"traceutil/trace.go:171","msg":"trace[2041903738] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:expand-controller; range_end:; response_count:1; response_revision:1220; }","duration":"136.411102ms","start":"2026-04-26T05:47:24.061519Z","end":"2026-04-26T05:47:24.19793Z","steps":["trace[2041903738] 'agreement among raft nodes before linearized reading' (duration: 136.2535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:47:24.197897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.016359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-account-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-04-26T05:47:24.198318Z","caller":"traceutil/trace.go:171","msg":"trace[1053940948] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-account-controller; range_end:; response_count:1; response_revision:1220; }","duration":"134.460266ms","start":"2026-04-26T05:47:24.063845Z","end":"2026-04-26T05:47:24.198306Z","steps":["trace[1053940948] 'agreement among raft nodes before linearized reading' (duration: 133.95622ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:47:33.881964Z","caller":"traceutil/trace.go:171","msg":"trace[566187181] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"184.604517ms","start":"2026-04-26T05:47:33.697343Z","end":"2026-04-26T05:47:33.881948Z","steps":["trace[566187181] 'read index received' (duration: 184.475696ms)","trace[566187181] 'applied index is now lower than readState.Index' (duration: 128.271µs)"],"step_count":2} {"level":"warn","ts":"2026-04-26T05:47:33.882099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.748299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-26T05:47:33.882124Z","caller":"traceutil/trace.go:171","msg":"trace[374912131] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:1249; }","duration":"184.806261ms","start":"2026-04-26T05:47:33.697311Z","end":"2026-04-26T05:47:33.882117Z","steps":["trace[374912131] 'agreement among raft nodes before linearized reading' (duration: 184.711189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:47:33.882126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.074361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/local-path-storage/\" range_end:\"/registry/networkpolicies/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:47:33.882043Z","caller":"traceutil/trace.go:171","msg":"trace[9018308] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"197.334373ms","start":"2026-04-26T05:47:33.684655Z","end":"2026-04-26T05:47:33.88199Z","steps":["trace[9018308] 'process raft request' (duration: 197.211021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:47:33.882228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.048101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:1 size:612"} {"level":"info","ts":"2026-04-26T05:47:33.882325Z","caller":"traceutil/trace.go:171","msg":"trace[1538391244] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:1; response_revision:1249; }","duration":"171.203664ms","start":"2026-04-26T05:47:33.711104Z","end":"2026-04-26T05:47:33.882308Z","steps":["trace[1538391244] 'agreement among raft nodes before linearized reading' (duration: 170.984911ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:47:33.882348Z","caller":"traceutil/trace.go:171","msg":"trace[1514473248] range","detail":"{range_begin:/registry/networkpolicies/local-path-storage/; range_end:/registry/networkpolicies/local-path-storage0; response_count:0; response_revision:1249; }","duration":"164.307414ms","start":"2026-04-26T05:47:33.718024Z","end":"2026-04-26T05:47:33.882331Z","steps":["trace[1514473248] 'agreement among raft nodes before linearized reading' (duration: 164.063901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:47:33.882411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.140081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-26T05:47:33.882519Z","caller":"traceutil/trace.go:171","msg":"trace[225079704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1249; }","duration":"167.287623ms","start":"2026-04-26T05:47:33.715208Z","end":"2026-04-26T05:47:33.882495Z","steps":["trace[225079704] 'agreement among raft nodes before linearized reading' (duration: 167.029679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:47:33.882896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.938618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/local-path-storage/\" range_end:\"/registry/jobs/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T05:47:33.883002Z","caller":"traceutil/trace.go:171","msg":"trace[1181434037] range","detail":"{range_begin:/registry/jobs/local-path-storage/; range_end:/registry/jobs/local-path-storage0; response_count:0; response_revision:1249; }","duration":"167.06859ms","start":"2026-04-26T05:47:33.715921Z","end":"2026-04-26T05:47:33.882989Z","steps":["trace[1181434037] 'agreement among raft nodes before linearized reading' (duration: 166.239119ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:47:39.333233Z","caller":"traceutil/trace.go:171","msg":"trace[986196705] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"143.115672ms","start":"2026-04-26T05:47:39.190095Z","end":"2026-04-26T05:47:39.33321Z","steps":["trace[986196705] 'read index received' (duration: 142.95255ms)","trace[986196705] 'applied index is now lower than readState.Index' (duration: 161.902µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T05:47:39.33336Z","caller":"traceutil/trace.go:171","msg":"trace[253981411] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"166.222036ms","start":"2026-04-26T05:47:39.167126Z","end":"2026-04-26T05:47:39.333348Z","steps":["trace[253981411] 'process raft request' (duration: 165.929912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:47:39.333685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.833943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:1 size:201"} {"level":"info","ts":"2026-04-26T05:47:39.333766Z","caller":"traceutil/trace.go:171","msg":"trace[604668010] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:1; response_revision:1267; }","duration":"118.957334ms","start":"2026-04-26T05:47:39.21479Z","end":"2026-04-26T05:47:39.333747Z","steps":["trace[604668010] 'agreement among raft nodes before linearized reading' (duration: 118.779412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T05:47:39.334229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.114255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:87 size:64463"} {"level":"info","ts":"2026-04-26T05:47:39.334742Z","caller":"traceutil/trace.go:171","msg":"trace[269199231] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:87; response_revision:1267; }","duration":"144.642282ms","start":"2026-04-26T05:47:39.190081Z","end":"2026-04-26T05:47:39.334723Z","steps":["trace[269199231] 'agreement among raft nodes before linearized reading' (duration: 143.504847ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T05:47:39.488872Z","caller":"traceutil/trace.go:171","msg":"trace[126139793] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"109.96688ms","start":"2026-04-26T05:47:39.378877Z","end":"2026-04-26T05:47:39.488844Z","steps":["trace[126139793] 'process raft request' (duration: 69.16258ms)","trace[126139793] 'compare' (duration: 40.406115ms)"],"step_count":2}