{"level":"warn","ts":"2026-04-17T00:31:54.518443Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T00:31:54.518553Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-17T00:31:54.518668Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T00:31:54.518713Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-17T00:31:54.518762Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T00:31:54.519662Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-04-17T00:31:54.519765Z","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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-17T00:31:54.524497Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.417776ms"} {"level":"info","ts":"2026-04-17T00:31:54.537693Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-04-17T00:31:54.537766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T00:31:54.537811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-04-17T00:31:54.53783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T00:31:54.537839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-04-17T00:31:54.5379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-04-17T00:31:54.546924Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T00:31:54.55055Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T00:31:54.551892Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-17T00:31:54.554123Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T00:31:54.55423Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T00:31:54.55453Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T00:31:54.554681Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T00:31:54.554692Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T00:31:54.555424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-04-17T00:31:54.555567Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-17T00:31:54.561689Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T00:31:54.561859Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T00:31:54.561884Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T00:31:54.561956Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-17T00:31:54.561968Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-17T00:31:54.739134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T00:31:54.739196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T00:31:54.739228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-04-17T00:31:54.739246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-04-17T00:31:54.739258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-17T00:31:54.73928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-04-17T00:31:54.739299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-17T00:31:54.740637Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:31:54.740633Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T00:31:54.740666Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:31:54.740875Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:31:54.741065Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T00:31:54.741088Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T00:31:54.741549Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:31:54.741702Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:31:54.741736Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:31:54.744432Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-04-17T00:31:54.744573Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T00:31:56.499868Z","caller":"traceutil/trace.go:171","msg":"trace[787781627] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"121.575212ms","start":"2026-04-17T00:31:56.378237Z","end":"2026-04-17T00:31:56.499812Z","steps":["trace[787781627] 'process raft request' (duration: 121.399978ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:31:57.009792Z","caller":"traceutil/trace.go:171","msg":"trace[40094815] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"562.222526ms","start":"2026-04-17T00:31:56.447531Z","end":"2026-04-17T00:31:57.009754Z","steps":["trace[40094815] 'process raft request' (duration: 562.097623ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:31:57.009935Z","caller":"traceutil/trace.go:171","msg":"trace[573934148] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"562.363739ms","start":"2026-04-17T00:31:56.447553Z","end":"2026-04-17T00:31:57.009917Z","steps":["trace[573934148] 'process raft request' (duration: 562.098673ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:31:57.009988Z","caller":"traceutil/trace.go:171","msg":"trace[1735884985] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"562.492452ms","start":"2026-04-17T00:31:56.447485Z","end":"2026-04-17T00:31:57.009977Z","steps":["trace[1735884985] 'process raft request' (duration: 562.088663ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:31:57.009951Z","caller":"traceutil/trace.go:171","msg":"trace[1356718210] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"631.435004ms","start":"2026-04-17T00:31:56.378468Z","end":"2026-04-17T00:31:57.009903Z","steps":["trace[1356718210] 'process raft request' (duration: 630.929762ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:31:57.01002Z","caller":"traceutil/trace.go:171","msg":"trace[1354022433] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"562.953494ms","start":"2026-04-17T00:31:56.446992Z","end":"2026-04-17T00:31:57.009946Z","steps":["trace[1354022433] 'process raft request' (duration: 562.554724ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:31:57.010084Z","caller":"traceutil/trace.go:171","msg":"trace[2124075740] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"563.080467ms","start":"2026-04-17T00:31:56.446918Z","end":"2026-04-17T00:31:57.009999Z","steps":["trace[2124075740] 'process raft request' (duration: 562.592045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:31:57.010419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.782763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T00:31:57.010498Z","caller":"traceutil/trace.go:171","msg":"trace[259681524] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"621.912506ms","start":"2026-04-17T00:31:56.388572Z","end":"2026-04-17T00:31:57.010485Z","steps":["trace[259681524] 'agreement among raft nodes before linearized reading' (duration: 621.741512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:31:57.010546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:56.388557Z","time spent":"621.970978ms","remote":"127.0.0.1:45366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-17T00:31:57.010632Z","caller":"traceutil/trace.go:171","msg":"trace[435066745] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"551.51113ms","start":"2026-04-17T00:31:56.459074Z","end":"2026-04-17T00:31:57.010585Z","steps":["trace[435066745] 'process raft request' (duration: 550.665159ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:31:57.010701Z","caller":"traceutil/trace.go:171","msg":"trace[149828909] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"544.474142ms","start":"2026-04-17T00:31:56.466212Z","end":"2026-04-17T00:31:57.010686Z","steps":["trace[149828909] 'process raft request' (duration: 544.056861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:31:57.010713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:56.459065Z","time spent":"551.604532ms","remote":"127.0.0.1:45142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:31:57.010744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:56.447528Z","time spent":"562.362589ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:31:57.010802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:56.466207Z","time spent":"544.540314ms","remote":"127.0.0.1:45216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:31:57.010914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:56.447551Z","time spent":"562.41644ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:31:57.011017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:56.447478Z","time spent":"562.527243ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T00:31:57.012542Z","caller":"traceutil/trace.go:171","msg":"trace[1772501161] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"621.708581ms","start":"2026-04-17T00:31:56.388602Z","end":"2026-04-17T00:31:57.01031Z","steps":["trace[1772501161] 'read index received' (duration: 111.144442ms)","trace[1772501161] 'applied index is now lower than readState.Index' (duration: 510.563279ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:31:57.014069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:56.378461Z","time spent":"631.636058ms","remote":"127.0.0.1:45020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:31:57.01047Z","caller":"traceutil/trace.go:171","msg":"trace[188708728] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"562.620535ms","start":"2026-04-17T00:31:56.447686Z","end":"2026-04-17T00:31:57.010307Z","steps":["trace[188708728] 'process raft request' (duration: 561.99365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:31:57.014414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:56.447679Z","time spent":"566.714434ms","remote":"127.0.0.1:45424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:31:57.013977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:56.446908Z","time spent":"563.309992ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:31:57.015356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:56.446989Z","time spent":"563.153898ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:31:57.02032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.313488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T00:31:57.020384Z","caller":"traceutil/trace.go:171","msg":"trace[2104538063] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"572.43099ms","start":"2026-04-17T00:31:56.447922Z","end":"2026-04-17T00:31:57.020353Z","steps":["trace[2104538063] 'agreement among raft nodes before linearized reading' (duration: 568.336283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:31:57.020413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:56.447919Z","time spent":"572.486172ms","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-17T00:31:57.020697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.660381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T00:31:57.020891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.599707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T00:31:57.0209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.144262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T00:31:57.020929Z","caller":"traceutil/trace.go:171","msg":"trace[164772263] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"522.661599ms","start":"2026-04-17T00:31:56.498254Z","end":"2026-04-17T00:31:57.020915Z","steps":["trace[164772263] 'agreement among raft nodes before linearized reading' (duration: 517.998268ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:31:57.020843Z","caller":"traceutil/trace.go:171","msg":"trace[390532145] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"586.794455ms","start":"2026-04-17T00:31:56.433996Z","end":"2026-04-17T00:31:57.020791Z","steps":["trace[390532145] 'agreement among raft nodes before linearized reading' (duration: 582.475062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:31:57.020847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.787711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T00:31:57.020959Z","caller":"traceutil/trace.go:171","msg":"trace[1413164259] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"574.195023ms","start":"2026-04-17T00:31:56.446741Z","end":"2026-04-17T00:31:57.020936Z","steps":["trace[1413164259] 'agreement among raft nodes before linearized reading' (duration: 569.590433ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:31:57.02161Z","caller":"traceutil/trace.go:171","msg":"trace[1934881471] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:0; response_revision:11; }","duration":"576.57724ms","start":"2026-04-17T00:31:56.445018Z","end":"2026-04-17T00:31:57.021596Z","steps":["trace[1934881471] 'agreement among raft nodes before linearized reading' (duration: 571.448638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:31:57.021782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:56.445002Z","time spent":"576.766555ms","remote":"127.0.0.1:44982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.246\" "} {"level":"warn","ts":"2026-04-17T00:31:57.021001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:56.433983Z","time spent":"587.004319ms","remote":"127.0.0.1:45064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-17T00:31:57.020979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:56.498236Z","time spent":"522.73668ms","remote":"127.0.0.1:45064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-17T00:31:57.022918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:31:56.446735Z","time spent":"576.170901ms","remote":"127.0.0.1:45064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-17T00:32:24.63055Z","caller":"traceutil/trace.go:171","msg":"trace[709679783] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"141.242264ms","start":"2026-04-17T00:32:24.489288Z","end":"2026-04-17T00:32:24.63053Z","steps":["trace[709679783] 'process raft request' (duration: 140.522747ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:24.638027Z","caller":"traceutil/trace.go:171","msg":"trace[1932780362] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"142.506535ms","start":"2026-04-17T00:32:24.495506Z","end":"2026-04-17T00:32:24.638012Z","steps":["trace[1932780362] 'process raft request' (duration: 142.198758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:25.765693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.68678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:32:25.765828Z","caller":"traceutil/trace.go:171","msg":"trace[1930550657] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"822.317208ms","start":"2026-04-17T00:32:24.943492Z","end":"2026-04-17T00:32:25.765809Z","steps":["trace[1930550657] 'process raft request' (duration: 200.27953ms)","trace[1930550657] 'compare' (duration: 621.564547ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:32:25.765891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:24.943465Z","time spent":"822.39523ms","remote":"127.0.0.1:45144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4873,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:32:26.243464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.475566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:32:26.243763Z","caller":"traceutil/trace.go:171","msg":"trace[820783845] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:622; }","duration":"1.226925114s","start":"2026-04-17T00:32:25.016819Z","end":"2026-04-17T00:32:26.243744Z","steps":["trace[820783845] 'read index received' (duration: 127.132763ms)","trace[820783845] 'applied index is now lower than readState.Index' (duration: 1.099791311s)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:32:26.243835Z","caller":"traceutil/trace.go:171","msg":"trace[262432943] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.291685214s","start":"2026-04-17T00:32:24.952139Z","end":"2026-04-17T00:32:26.243824Z","steps":["trace[262432943] 'process raft request' (duration: 1.153746157s)","trace[262432943] 'compare' (duration: 137.140849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:32:26.243904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:24.952119Z","time spent":"1.291748925s","remote":"127.0.0.1:45002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:32:26.243942Z","caller":"traceutil/trace.go:171","msg":"trace[1549843753] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.270379014s","start":"2026-04-17T00:32:24.973503Z","end":"2026-04-17T00:32:26.243882Z","steps":["trace[1549843753] 'process raft request' (duration: 1.270123528s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:26.244103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:24.973477Z","time spent":"1.270548719s","remote":"127.0.0.1:45002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:32:26.244158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227338344s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:32:26.244195Z","caller":"traceutil/trace.go:171","msg":"trace[2103706171] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:604; }","duration":"1.227410995s","start":"2026-04-17T00:32:25.01677Z","end":"2026-04-17T00:32:26.244181Z","steps":["trace[2103706171] 'agreement among raft nodes before linearized reading' (duration: 1.227341194s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:26.244224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:25.016749Z","time spent":"1.227468187s","remote":"127.0.0.1:45002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-04-17T00:32:26.263369Z","caller":"traceutil/trace.go:171","msg":"trace[623928937] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"421.00543ms","start":"2026-04-17T00:32:25.842332Z","end":"2026-04-17T00:32:26.263337Z","steps":["trace[623928937] 'process raft request' (duration: 420.879597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:26.263391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.370036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:32:26.263568Z","caller":"traceutil/trace.go:171","msg":"trace[1574829289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"599.588861ms","start":"2026-04-17T00:32:25.663943Z","end":"2026-04-17T00:32:26.263531Z","steps":["trace[1574829289] 'agreement among raft nodes before linearized reading' (duration: 599.357986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:26.263574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.326971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"warn","ts":"2026-04-17T00:32:26.263624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:25.663895Z","time spent":"599.717194ms","remote":"127.0.0.1:44954","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-17T00:32:26.263628Z","caller":"traceutil/trace.go:171","msg":"trace[1824724937] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:606; }","duration":"249.415753ms","start":"2026-04-17T00:32:26.014197Z","end":"2026-04-17T00:32:26.263612Z","steps":["trace[1824724937] 'agreement among raft nodes before linearized reading' (duration: 249.249389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:26.263792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.946917ms","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-17T00:32:26.263858Z","caller":"traceutil/trace.go:171","msg":"trace[694101829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"997.064449ms","start":"2026-04-17T00:32:25.266779Z","end":"2026-04-17T00:32:26.263844Z","steps":["trace[694101829] 'agreement among raft nodes before linearized reading' (duration: 996.954567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:26.263879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:25.266761Z","time spent":"997.113881ms","remote":"127.0.0.1:45224","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-17T00:32:26.263978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.476293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:32:26.263998Z","caller":"traceutil/trace.go:171","msg":"trace[1126270028] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:606; }","duration":"649.541294ms","start":"2026-04-17T00:32:25.61445Z","end":"2026-04-17T00:32:26.263991Z","steps":["trace[1126270028] 'agreement among raft nodes before linearized reading' (duration: 649.512173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:26.264012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:25.614424Z","time spent":"649.584724ms","remote":"127.0.0.1:45002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-17T00:32:26.263839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:25.84232Z","time spent":"421.181315ms","remote":"127.0.0.1:45002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:32:26.264095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.905811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:32:26.264116Z","caller":"traceutil/trace.go:171","msg":"trace[1105493696] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:606; }","duration":"842.964223ms","start":"2026-04-17T00:32:25.421146Z","end":"2026-04-17T00:32:26.26411Z","steps":["trace[1105493696] 'agreement among raft nodes before linearized reading' (duration: 842.934472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:26.264131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:25.421124Z","time spent":"843.004344ms","remote":"127.0.0.1:45002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-17T00:32:26.263376Z","caller":"traceutil/trace.go:171","msg":"trace[820438014] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"1.048429525s","start":"2026-04-17T00:32:25.214919Z","end":"2026-04-17T00:32:26.263349Z","steps":["trace[820438014] 'process raft request' (duration: 1.048061107s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:26.26432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:25.214887Z","time spent":"1.049398888s","remote":"127.0.0.1:45002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T00:32:26.264746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.114857ms","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-17T00:32:26.264897Z","caller":"traceutil/trace.go:171","msg":"trace[1979272706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"999.303772ms","start":"2026-04-17T00:32:25.26558Z","end":"2026-04-17T00:32:26.264884Z","steps":["trace[1979272706] 'agreement among raft nodes before linearized reading' (duration: 998.37491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:26.265071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:25.265561Z","time spent":"999.496477ms","remote":"127.0.0.1:45224","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-17T00:32:26.267382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.055828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-gdhlp\" ","response":"range_response_count:1 size:4888"} {"level":"info","ts":"2026-04-17T00:32:26.26747Z","caller":"traceutil/trace.go:171","msg":"trace[940509448] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-gdhlp; range_end:; response_count:1; response_revision:606; }","duration":"495.17865ms","start":"2026-04-17T00:32:25.772277Z","end":"2026-04-17T00:32:26.267456Z","steps":["trace[940509448] 'agreement among raft nodes before linearized reading' (duration: 491.013803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:26.267538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:25.772255Z","time spent":"495.270933ms","remote":"127.0.0.1:45144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4911,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-gdhlp\" "} {"level":"info","ts":"2026-04-17T00:32:27.871474Z","caller":"traceutil/trace.go:171","msg":"trace[2018074416] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"143.950818ms","start":"2026-04-17T00:32:27.727497Z","end":"2026-04-17T00:32:27.871447Z","steps":["trace[2018074416] 'process raft request' (duration: 138.02934ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:33.461444Z","caller":"traceutil/trace.go:171","msg":"trace[2102363515] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"499.37163ms","start":"2026-04-17T00:32:32.962042Z","end":"2026-04-17T00:32:33.461414Z","steps":["trace[2102363515] 'read index received' (duration: 499.220656ms)","trace[2102363515] 'applied index is now lower than readState.Index' (duration: 149.964µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:32:33.461597Z","caller":"traceutil/trace.go:171","msg":"trace[1634083082] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"501.376127ms","start":"2026-04-17T00:32:32.960174Z","end":"2026-04-17T00:32:33.46155Z","steps":["trace[1634083082] 'process raft request' (duration: 501.10333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:33.461683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.624975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5q64n\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-17T00:32:33.461718Z","caller":"traceutil/trace.go:171","msg":"trace[1909680927] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5q64n; range_end:; response_count:1; response_revision:681; }","duration":"499.709427ms","start":"2026-04-17T00:32:32.961998Z","end":"2026-04-17T00:32:33.461707Z","steps":["trace[1909680927] 'agreement among raft nodes before linearized reading' (duration: 499.534003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:33.461751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:32.96199Z","time spent":"499.753378ms","remote":"127.0.0.1:45144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-5q64n\" "} {"level":"warn","ts":"2026-04-17T00:32:33.461791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:32.960149Z","time spent":"501.54929ms","remote":"127.0.0.1:45224","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-17T00:32:33.616664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.721732ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T00:32:33.642116Z","caller":"traceutil/trace.go:171","msg":"trace[2056491483] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"162.309419ms","start":"2026-04-17T00:32:33.479777Z","end":"2026-04-17T00:32:33.642087Z","steps":["trace[2056491483] 'process raft request' (duration: 162.130105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:34.463203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.5233ms","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-17T00:32:34.463286Z","caller":"traceutil/trace.go:171","msg":"trace[547404411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"109.653693ms","start":"2026-04-17T00:32:34.353614Z","end":"2026-04-17T00:32:34.463267Z","steps":["trace[547404411] 'range keys from in-memory index tree' (duration: 109.376957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:34.463313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.572245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-17T00:32:34.46335Z","caller":"traceutil/trace.go:171","msg":"trace[893651098] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:682; }","duration":"133.627216ms","start":"2026-04-17T00:32:34.329709Z","end":"2026-04-17T00:32:34.463336Z","steps":["trace[893651098] 'range keys from in-memory index tree' (duration: 133.459032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:34.46319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.424682ms","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-17T00:32:34.463415Z","caller":"traceutil/trace.go:171","msg":"trace[1780966355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:682; }","duration":"133.708438ms","start":"2026-04-17T00:32:34.329691Z","end":"2026-04-17T00:32:34.463399Z","steps":["trace[1780966355] 'range keys from in-memory index tree' (duration: 133.250208ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:34.721167Z","caller":"traceutil/trace.go:171","msg":"trace[1599314311] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"253.770446ms","start":"2026-04-17T00:32:34.467371Z","end":"2026-04-17T00:32:34.721141Z","steps":["trace[1599314311] 'process raft request' (duration: 253.628303ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:34.753962Z","caller":"traceutil/trace.go:171","msg":"trace[396650698] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"286.464974ms","start":"2026-04-17T00:32:34.467461Z","end":"2026-04-17T00:32:34.753925Z","steps":["trace[396650698] 'process raft request' (duration: 286.259999ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:34.754169Z","caller":"traceutil/trace.go:171","msg":"trace[731861676] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"286.274808ms","start":"2026-04-17T00:32:34.467867Z","end":"2026-04-17T00:32:34.754142Z","steps":["trace[731861676] 'process raft request' (duration: 286.004082ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:32:52.02227Z","caller":"traceutil/trace.go:171","msg":"trace[886222833] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"470.501073ms","start":"2026-04-17T00:32:51.551738Z","end":"2026-04-17T00:32:52.022239Z","steps":["trace[886222833] 'read index received' (duration: 470.215926ms)","trace[886222833] 'applied index is now lower than readState.Index' (duration: 283.487µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:32:52.022553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.796739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T00:32:52.022608Z","caller":"traceutil/trace.go:171","msg":"trace[224907235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"470.923182ms","start":"2026-04-17T00:32:51.551669Z","end":"2026-04-17T00:32:52.022593Z","steps":["trace[224907235] 'agreement among raft nodes before linearized reading' (duration: 470.690556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:52.022655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:51.551645Z","time spent":"470.999054ms","remote":"127.0.0.1:45224","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-17T00:32:52.022736Z","caller":"traceutil/trace.go:171","msg":"trace[652175715] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"943.263227ms","start":"2026-04-17T00:32:51.079397Z","end":"2026-04-17T00:32:52.02266Z","steps":["trace[652175715] 'process raft request' (duration: 942.685924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:32:52.023002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:32:51.079376Z","time spent":"943.483303ms","remote":"127.0.0.1:45144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:33:26.580004Z","caller":"traceutil/trace.go:171","msg":"trace[1215857519] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"222.827189ms","start":"2026-04-17T00:33:26.357155Z","end":"2026-04-17T00:33:26.579982Z","steps":["trace[1215857519] 'process raft request' (duration: 222.669195ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:27.031599Z","caller":"traceutil/trace.go:171","msg":"trace[41832082] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"672.617674ms","start":"2026-04-17T00:33:26.358958Z","end":"2026-04-17T00:33:27.031575Z","steps":["trace[41832082] 'process raft request' (duration: 647.26976ms)","trace[41832082] 'compare' (duration: 24.869803ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:33:27.031733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.320067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:33:27.0318Z","caller":"traceutil/trace.go:171","msg":"trace[502790062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"369.426229ms","start":"2026-04-17T00:33:26.662353Z","end":"2026-04-17T00:33:27.031779Z","steps":["trace[502790062] 'agreement among raft nodes before linearized reading' (duration: 369.302536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:33:27.03185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:33:26.66234Z","time spent":"369.500362ms","remote":"127.0.0.1:44954","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-17T00:33:27.031739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:33:26.358945Z","time spent":"672.716606ms","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:33:27.03157Z","caller":"traceutil/trace.go:171","msg":"trace[627323214] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1048; }","duration":"369.147684ms","start":"2026-04-17T00:33:26.662385Z","end":"2026-04-17T00:33:27.031533Z","steps":["trace[627323214] 'read index received' (duration: 343.744698ms)","trace[627323214] 'applied index is now lower than readState.Index' (duration: 25.401095ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:33:27.71462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.440794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:33:27.714739Z","caller":"traceutil/trace.go:171","msg":"trace[438621885] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"609.145114ms","start":"2026-04-17T00:33:27.105571Z","end":"2026-04-17T00:33:27.714716Z","steps":["trace[438621885] 'process raft request' (duration: 281.515826ms)","trace[438621885] 'compare' (duration: 326.983083ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:33:27.714806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:33:27.105558Z","time spent":"609.213216ms","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:33:27.719698Z","caller":"traceutil/trace.go:171","msg":"trace[360099550] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1049; }","duration":"606.38803ms","start":"2026-04-17T00:33:27.113291Z","end":"2026-04-17T00:33:27.719679Z","steps":["trace[360099550] 'read index received' (duration: 273.922028ms)","trace[360099550] 'applied index is now lower than readState.Index' (duration: 332.465142ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:33:27.7199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.588485ms","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-17T00:33:27.719955Z","caller":"traceutil/trace.go:171","msg":"trace[184579149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1015; }","duration":"606.688207ms","start":"2026-04-17T00:33:27.113254Z","end":"2026-04-17T00:33:27.719942Z","steps":["trace[184579149] 'agreement among raft nodes before linearized reading' (duration: 606.490343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:33:27.719982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:33:27.113238Z","time spent":"606.739248ms","remote":"127.0.0.1:45224","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-17T00:33:27.720098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.640219ms","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-17T00:33:27.720227Z","caller":"traceutil/trace.go:171","msg":"trace[1160947294] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"611.668593ms","start":"2026-04-17T00:33:27.108527Z","end":"2026-04-17T00:33:27.720196Z","steps":["trace[1160947294] 'process raft request' (duration: 611.049619ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:27.720241Z","caller":"traceutil/trace.go:171","msg":"trace[167787993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1015; }","duration":"604.781682ms","start":"2026-04-17T00:33:27.115445Z","end":"2026-04-17T00:33:27.720227Z","steps":["trace[167787993] 'agreement among raft nodes before linearized reading' (duration: 604.355312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:33:27.720276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:33:27.11543Z","time spent":"604.838414ms","remote":"127.0.0.1:45224","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-17T00:33:27.720319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:33:27.108502Z","time spent":"611.750875ms","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:33:45.94364Z","caller":"traceutil/trace.go:171","msg":"trace[243782653] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1113; }","duration":"123.001027ms","start":"2026-04-17T00:33:45.820621Z","end":"2026-04-17T00:33:45.943622Z","steps":["trace[243782653] 'read index received' (duration: 8.700254ms)","trace[243782653] 'applied index is now lower than readState.Index' (duration: 114.299773ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:33:45.94383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.031584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-17T00:33:45.943866Z","caller":"traceutil/trace.go:171","msg":"trace[598105300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1075; }","duration":"108.112516ms","start":"2026-04-17T00:33:45.835732Z","end":"2026-04-17T00:33:45.943845Z","steps":["trace[598105300] 'agreement among raft nodes before linearized reading' (duration: 107.990243ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:45.943871Z","caller":"traceutil/trace.go:171","msg":"trace[1792940441] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"126.276823ms","start":"2026-04-17T00:33:45.817562Z","end":"2026-04-17T00:33:45.943839Z","steps":["trace[1792940441] 'process raft request' (duration: 47.121646ms)","trace[1792940441] 'compare' (duration: 78.827849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:33:45.944069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.381735ms","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-17T00:33:45.944198Z","caller":"traceutil/trace.go:171","msg":"trace[151260445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1075; }","duration":"123.615631ms","start":"2026-04-17T00:33:45.820557Z","end":"2026-04-17T00:33:45.944172Z","steps":["trace[151260445] 'agreement among raft nodes before linearized reading' (duration: 123.196031ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:51.274391Z","caller":"traceutil/trace.go:171","msg":"trace[1277037560] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"138.878169ms","start":"2026-04-17T00:33:51.135479Z","end":"2026-04-17T00:33:51.274357Z","steps":["trace[1277037560] 'process raft request' (duration: 138.617713ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:53.214512Z","caller":"traceutil/trace.go:171","msg":"trace[799237679] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"458.277895ms","start":"2026-04-17T00:33:52.756205Z","end":"2026-04-17T00:33:53.214483Z","steps":["trace[799237679] 'process raft request' (duration: 458.1364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:33:53.214679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:33:52.756177Z","time spent":"458.414498ms","remote":"127.0.0.1:45224","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-17T00:33:53.660967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.27029ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T00:33:53.661399Z","caller":"traceutil/trace.go:171","msg":"trace[1800259261] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"381.084903ms","start":"2026-04-17T00:33:53.280259Z","end":"2026-04-17T00:33:53.661344Z","steps":["trace[1800259261] 'process raft request' (duration: 380.836007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:33:53.661573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:33:53.280225Z","time spent":"381.255866ms","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:33:54.041484Z","caller":"traceutil/trace.go:171","msg":"trace[1062653909] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"123.384525ms","start":"2026-04-17T00:33:53.918076Z","end":"2026-04-17T00:33:54.04146Z","steps":["trace[1062653909] 'process raft request' (duration: 123.077747ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:55.358484Z","caller":"traceutil/trace.go:171","msg":"trace[840888818] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"135.536371ms","start":"2026-04-17T00:33:55.222921Z","end":"2026-04-17T00:33:55.358457Z","steps":["trace[840888818] 'process raft request' (duration: 135.383857ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:33:55.783522Z","caller":"traceutil/trace.go:171","msg":"trace[632127110] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"116.876174ms","start":"2026-04-17T00:33:55.66662Z","end":"2026-04-17T00:33:55.783496Z","steps":["trace[632127110] 'process raft request' (duration: 116.72385ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:05.998718Z","caller":"traceutil/trace.go:171","msg":"trace[909078262] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"181.934099ms","start":"2026-04-17T00:34:05.816766Z","end":"2026-04-17T00:34:05.9987Z","steps":["trace[909078262] 'process raft request' (duration: 181.833107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:07.909618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959883177669672,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T00:34:08.166802Z","caller":"traceutil/trace.go:171","msg":"trace[443733809] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1240; }","duration":"757.425404ms","start":"2026-04-17T00:34:07.40936Z","end":"2026-04-17T00:34:08.166785Z","steps":["trace[443733809] 'read index received' (duration: 757.301111ms)","trace[443733809] 'applied index is now lower than readState.Index' (duration: 123.293µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:34:08.166879Z","caller":"traceutil/trace.go:171","msg":"trace[1511273272] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"761.011399ms","start":"2026-04-17T00:34:07.40586Z","end":"2026-04-17T00:34:08.166871Z","steps":["trace[1511273272] 'process raft request' (duration: 760.796814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:08.166956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:07.405851Z","time spent":"761.044529ms","remote":"127.0.0.1:45224","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-17T00:34:08.167066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.652769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:34:08.167139Z","caller":"traceutil/trace.go:171","msg":"trace[2137558545] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1195; }","duration":"757.774421ms","start":"2026-04-17T00:34:07.409348Z","end":"2026-04-17T00:34:08.167123Z","steps":["trace[2137558545] 'agreement among raft nodes before linearized reading' (duration: 757.623538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:08.167179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:07.409344Z","time spent":"757.825973ms","remote":"127.0.0.1:45200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:34:08.202523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.070231ms","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-17T00:34:08.202591Z","caller":"traceutil/trace.go:171","msg":"trace[945522966] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1196; }","duration":"695.170223ms","start":"2026-04-17T00:34:07.5074Z","end":"2026-04-17T00:34:08.20257Z","steps":["trace[945522966] 'agreement among raft nodes before linearized reading' (duration: 695.05759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:08.202622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:07.507381Z","time spent":"695.235515ms","remote":"127.0.0.1:49344","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":"info","ts":"2026-04-17T00:34:08.202849Z","caller":"traceutil/trace.go:171","msg":"trace[1579871315] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"198.086079ms","start":"2026-04-17T00:34:08.004756Z","end":"2026-04-17T00:34:08.202842Z","steps":["trace[1579871315] 'process raft request' (duration: 197.595228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:08.203222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.434122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:34:08.203244Z","caller":"traceutil/trace.go:171","msg":"trace[1703079727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1196; }","duration":"540.486433ms","start":"2026-04-17T00:34:07.662753Z","end":"2026-04-17T00:34:08.203239Z","steps":["trace[1703079727] 'agreement among raft nodes before linearized reading' (duration: 540.454372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:08.203259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:07.662736Z","time spent":"540.519953ms","remote":"127.0.0.1:44954","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-17T00:34:08.203288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.985455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-17T00:34:08.203343Z","caller":"traceutil/trace.go:171","msg":"trace[1734418312] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:1196; }","duration":"163.092567ms","start":"2026-04-17T00:34:08.040238Z","end":"2026-04-17T00:34:08.203331Z","steps":["trace[1734418312] 'agreement among raft nodes before linearized reading' (duration: 162.921843ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:08.486261Z","caller":"traceutil/trace.go:171","msg":"trace[344859398] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"270.921428ms","start":"2026-04-17T00:34:08.215308Z","end":"2026-04-17T00:34:08.486229Z","steps":["trace[344859398] 'process raft request' (duration: 270.707553ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:08.486395Z","caller":"traceutil/trace.go:171","msg":"trace[729667480] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"270.985629ms","start":"2026-04-17T00:34:08.215379Z","end":"2026-04-17T00:34:08.486364Z","steps":["trace[729667480] 'process raft request' (duration: 270.760004ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:08.486754Z","caller":"traceutil/trace.go:171","msg":"trace[1683367052] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"271.321377ms","start":"2026-04-17T00:34:08.215424Z","end":"2026-04-17T00:34:08.486745Z","steps":["trace[1683367052] 'process raft request' (duration: 270.749674ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:08.486866Z","caller":"traceutil/trace.go:171","msg":"trace[70749650] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"271.266166ms","start":"2026-04-17T00:34:08.215436Z","end":"2026-04-17T00:34:08.486702Z","steps":["trace[70749650] 'process raft request' (duration: 271.126683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:24.880768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.146868ms","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-17T00:34:24.881152Z","caller":"traceutil/trace.go:171","msg":"trace[217869823] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1269; }","duration":"403.550118ms","start":"2026-04-17T00:34:24.477581Z","end":"2026-04-17T00:34:24.881131Z","steps":["trace[217869823] 'range keys from in-memory index tree' (duration: 403.128967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:24.881016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.626027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:34:24.8813Z","caller":"traceutil/trace.go:171","msg":"trace[936166212] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"361.08737ms","start":"2026-04-17T00:34:24.5202Z","end":"2026-04-17T00:34:24.881288Z","steps":["trace[936166212] 'read index received' (duration: 229.054104ms)","trace[936166212] 'applied index is now lower than readState.Index' (duration: 132.032136ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:34:24.881395Z","caller":"traceutil/trace.go:171","msg":"trace[1792412180] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"569.455023ms","start":"2026-04-17T00:34:24.311926Z","end":"2026-04-17T00:34:24.881381Z","steps":["trace[1792412180] 'process raft request' (duration: 437.394785ms)","trace[1792412180] 'compare' (duration: 131.371911ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:34:24.88146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:24.311893Z","time spent":"569.534225ms","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:34:24.881657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.298969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:34:24.881703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.817577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-17T00:34:24.881739Z","caller":"traceutil/trace.go:171","msg":"trace[504565402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"218.386941ms","start":"2026-04-17T00:34:24.663332Z","end":"2026-04-17T00:34:24.881719Z","steps":["trace[504565402] 'agreement among raft nodes before linearized reading' (duration: 218.273559ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:24.881755Z","caller":"traceutil/trace.go:171","msg":"trace[1809805788] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1270; }","duration":"210.89882ms","start":"2026-04-17T00:34:24.670842Z","end":"2026-04-17T00:34:24.881741Z","steps":["trace[1809805788] 'agreement among raft nodes before linearized reading' (duration: 210.727566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:24.881745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.509891ms","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":"info","ts":"2026-04-17T00:34:24.881824Z","caller":"traceutil/trace.go:171","msg":"trace[707237911] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1270; }","duration":"361.619353ms","start":"2026-04-17T00:34:24.520186Z","end":"2026-04-17T00:34:24.881805Z","steps":["trace[707237911] 'agreement among raft nodes before linearized reading' (duration: 361.47027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:24.881866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.955769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-17T00:34:24.881877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:24.520124Z","time spent":"361.742496ms","remote":"127.0.0.1:49272","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":"info","ts":"2026-04-17T00:34:24.881901Z","caller":"traceutil/trace.go:171","msg":"trace[719435936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1270; }","duration":"154.022261ms","start":"2026-04-17T00:34:24.727869Z","end":"2026-04-17T00:34:24.881892Z","steps":["trace[719435936] 'agreement among raft nodes before linearized reading' (duration: 153.917679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:24.881959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.784277ms","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-17T00:34:24.881999Z","caller":"traceutil/trace.go:171","msg":"trace[1933682312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1270; }","duration":"294.861088ms","start":"2026-04-17T00:34:24.587129Z","end":"2026-04-17T00:34:24.88199Z","steps":["trace[1933682312] 'agreement among raft nodes before linearized reading' (duration: 294.432009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:24.882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.265903ms","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-17T00:34:24.882204Z","caller":"traceutil/trace.go:171","msg":"trace[2011198712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1270; }","duration":"256.561339ms","start":"2026-04-17T00:34:24.625567Z","end":"2026-04-17T00:34:24.882128Z","steps":["trace[2011198712] 'agreement among raft nodes before linearized reading' (duration: 256.13586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:25.247747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.07656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:34:25.247891Z","caller":"traceutil/trace.go:171","msg":"trace[653005314] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"362.114655ms","start":"2026-04-17T00:34:24.885765Z","end":"2026-04-17T00:34:25.247879Z","steps":["trace[653005314] 'process raft request' (duration: 362.049543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:25.247929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:24.885744Z","time spent":"362.166936ms","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:34:25.248052Z","caller":"traceutil/trace.go:171","msg":"trace[1377738604] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"362.595515ms","start":"2026-04-17T00:34:24.885448Z","end":"2026-04-17T00:34:25.248043Z","steps":["trace[1377738604] 'process raft request' (duration: 232.162727ms)","trace[1377738604] 'compare' (duration: 129.981088ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:34:25.248043Z","caller":"traceutil/trace.go:171","msg":"trace[48146562] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1320; }","duration":"202.275791ms","start":"2026-04-17T00:34:25.045747Z","end":"2026-04-17T00:34:25.248023Z","steps":["trace[48146562] 'read index received' (duration: 71.796302ms)","trace[48146562] 'applied index is now lower than readState.Index' (duration: 130.478589ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:34:25.248089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:24.885434Z","time spent":"362.640468ms","remote":"127.0.0.1:45224","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-17T00:34:25.248169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.397405ms","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-17T00:34:25.248218Z","caller":"traceutil/trace.go:171","msg":"trace[1222649489] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1274; }","duration":"202.506157ms","start":"2026-04-17T00:34:25.0457Z","end":"2026-04-17T00:34:25.248206Z","steps":["trace[1222649489] 'agreement among raft nodes before linearized reading' (duration: 202.401665ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:25.248219Z","caller":"traceutil/trace.go:171","msg":"trace[141894602] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"361.47476ms","start":"2026-04-17T00:34:24.886723Z","end":"2026-04-17T00:34:25.248197Z","steps":["trace[141894602] 'process raft request' (duration: 361.125191ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:25.248263Z","caller":"traceutil/trace.go:171","msg":"trace[1924060214] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"361.01999ms","start":"2026-04-17T00:34:24.887212Z","end":"2026-04-17T00:34:25.248232Z","steps":["trace[1924060214] 'process raft request' (duration: 360.730652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:25.248339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:24.886716Z","time spent":"361.552863ms","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:34:25.248441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:24.887205Z","time spent":"361.138383ms","remote":"127.0.0.1:45224","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-17T00:34:26.602041Z","caller":"traceutil/trace.go:171","msg":"trace[1559108914] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"250.988031ms","start":"2026-04-17T00:34:26.351031Z","end":"2026-04-17T00:34:26.602019Z","steps":["trace[1559108914] 'process raft request' (duration: 250.844628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:27.165913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.417187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:34:27.165985Z","caller":"traceutil/trace.go:171","msg":"trace[1255089302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"504.537009ms","start":"2026-04-17T00:34:26.661432Z","end":"2026-04-17T00:34:27.165969Z","steps":["trace[1255089302] 'range keys from in-memory index tree' (duration: 504.343686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:27.166028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:26.661413Z","time spent":"504.607912ms","remote":"127.0.0.1:44954","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-17T00:34:27.166111Z","caller":"traceutil/trace.go:171","msg":"trace[1104479562] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"277.819388ms","start":"2026-04-17T00:34:26.888264Z","end":"2026-04-17T00:34:27.166083Z","steps":["trace[1104479562] 'process raft request' (duration: 259.954287ms)","trace[1104479562] 'compare' (duration: 17.612735ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:34:28.557455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.716978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:34:28.557562Z","caller":"traceutil/trace.go:171","msg":"trace[1912177067] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"444.265344ms","start":"2026-04-17T00:34:28.113275Z","end":"2026-04-17T00:34:28.55754Z","steps":["trace[1912177067] 'process raft request' (duration: 51.380412ms)","trace[1912177067] 'compare' (duration: 392.517044ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:34:28.557618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:28.113254Z","time spent":"444.337277ms","remote":"127.0.0.1:44982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:34:28.753826Z","caller":"traceutil/trace.go:171","msg":"trace[2070023851] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"142.942866ms","start":"2026-04-17T00:34:28.610844Z","end":"2026-04-17T00:34:28.753787Z","steps":["trace[2070023851] 'process raft request' (duration: 53.084771ms)","trace[2070023851] 'compare' (duration: 89.362904ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:34:29.375125Z","caller":"traceutil/trace.go:171","msg":"trace[1102046435] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"103.214703ms","start":"2026-04-17T00:34:29.271854Z","end":"2026-04-17T00:34:29.375068Z","steps":["trace[1102046435] 'process raft request' (duration: 102.998909ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:38.381094Z","caller":"traceutil/trace.go:171","msg":"trace[2003439445] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"339.114226ms","start":"2026-04-17T00:34:38.041958Z","end":"2026-04-17T00:34:38.381072Z","steps":["trace[2003439445] 'read index received' (duration: 338.844609ms)","trace[2003439445] 'applied index is now lower than readState.Index' (duration: 268.637µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:34:38.381268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.303171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-17T00:34:38.381206Z","caller":"traceutil/trace.go:171","msg":"trace[1331651048] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"360.876246ms","start":"2026-04-17T00:34:38.020286Z","end":"2026-04-17T00:34:38.381162Z","steps":["trace[1331651048] 'process raft request' (duration: 360.628471ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:38.381305Z","caller":"traceutil/trace.go:171","msg":"trace[1546556648] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:1361; }","duration":"339.398873ms","start":"2026-04-17T00:34:38.041896Z","end":"2026-04-17T00:34:38.381295Z","steps":["trace[1546556648] 'agreement among raft nodes before linearized reading' (duration: 339.25953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:38.381348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:38.041873Z","time spent":"339.466945ms","remote":"127.0.0.1:44982","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.246\" "} {"level":"warn","ts":"2026-04-17T00:34:38.381436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:38.020269Z","time spent":"361.070651ms","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:34:39.067925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.971959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:34:39.068047Z","caller":"traceutil/trace.go:171","msg":"trace[795118540] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1414; }","duration":"271.743657ms","start":"2026-04-17T00:34:38.796288Z","end":"2026-04-17T00:34:39.068032Z","steps":["trace[795118540] 'read index received' (duration: 144.534023ms)","trace[795118540] 'applied index is now lower than readState.Index' (duration: 127.208264ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:34:39.068185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.903461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T00:34:39.068218Z","caller":"traceutil/trace.go:171","msg":"trace[1230381637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1362; }","duration":"271.969272ms","start":"2026-04-17T00:34:38.79624Z","end":"2026-04-17T00:34:39.06821Z","steps":["trace[1230381637] 'agreement among raft nodes before linearized reading' (duration: 271.840969ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:39.068394Z","caller":"traceutil/trace.go:171","msg":"trace[1519494762] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"495.402909ms","start":"2026-04-17T00:34:38.57298Z","end":"2026-04-17T00:34:39.068382Z","steps":["trace[1519494762] 'process raft request' (duration: 367.894468ms)","trace[1519494762] 'compare' (duration: 126.849746ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:34:39.068449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:38.572958Z","time spent":"495.45994ms","remote":"127.0.0.1:44982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:37:40.798952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.390668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:37:40.799084Z","caller":"traceutil/trace.go:171","msg":"trace[1670825167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2223; }","duration":"135.570882ms","start":"2026-04-17T00:37:40.663466Z","end":"2026-04-17T00:37:40.799037Z","steps":["trace[1670825167] 'range keys from in-memory index tree' (duration: 135.260186ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:37:41.019614Z","caller":"traceutil/trace.go:171","msg":"trace[1235915685] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"184.248637ms","start":"2026-04-17T00:37:40.83533Z","end":"2026-04-17T00:37:41.019578Z","steps":["trace[1235915685] 'process raft request' (duration: 184.045573ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:37:50.122046Z","caller":"traceutil/trace.go:171","msg":"trace[1875743010] transaction","detail":"{read_only:false; response_revision:2269; number_of_response:1; }","duration":"122.060778ms","start":"2026-04-17T00:37:49.999949Z","end":"2026-04-17T00:37:50.12201Z","steps":["trace[1875743010] 'process raft request' (duration: 121.851713ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:37:50.124864Z","caller":"traceutil/trace.go:171","msg":"trace[169293626] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"120.683396ms","start":"2026-04-17T00:37:50.004153Z","end":"2026-04-17T00:37:50.124836Z","steps":["trace[169293626] 'process raft request' (duration: 120.475181ms)"],"step_count":1}