{"level":"warn","ts":"2026-02-10T14:59:36.830591Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T14:59:36.83096Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-02-10T14:59:36.831163Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T14:59:36.831213Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-02-10T14:59:36.831249Z","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-02-10T14:59:36.832122Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-02-10T14:59:36.833945Z","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":2,"max-cpu-available":2,"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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-02-10T14:59:38.686188Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.851915301s"} {"level":"info","ts":"2026-02-10T14:59:39.11155Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-02-10T14:59:39.111695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T14:59:39.111735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-02-10T14:59:39.11175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T14:59:39.111758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-02-10T14:59:39.111804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-02-10T14:59:39.463424Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T14:59:39.629496Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T14:59:39.771074Z","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-02-10T14:59:39.859897Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T14:59:39.860717Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T14:59:39.861315Z","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-02-10T14:59:39.861498Z","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-02-10T14:59:39.861606Z","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-02-10T14:59:39.872985Z","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-02-10T14:59:39.873352Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T14:59:39.873482Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-10T14:59:39.873875Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-10T14:59:39.875005Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T14:59:39.887384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-02-10T14:59:39.887695Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-02-10T14:59:39.919884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T14:59:39.920274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T14:59:39.920494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-02-10T14:59:39.920649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-02-10T14:59:39.920798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-10T14:59:39.920912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-02-10T14:59:39.921148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-10T14:59:39.961661Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:59:40.182959Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T14:59:40.183651Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T14:59:40.183997Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T14:59:40.184071Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T14:59:40.183688Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:59:40.18448Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:59:40.184515Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:59:40.183734Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T14:59:40.186511Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T14:59:40.189578Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"warn","ts":"2026-02-10T14:59:40.408052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.938787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:40.408111Z","caller":"traceutil/trace.go:171","msg":"trace[1137508992] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"122.025319ms","start":"2026-02-10T14:59:40.286073Z","end":"2026-02-10T14:59:40.408098Z","steps":["trace[1137508992] 'count revisions from in-memory index tree' (duration: 121.912436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:40.408356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.317627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:40.408387Z","caller":"traceutil/trace.go:171","msg":"trace[906654623] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"122.368849ms","start":"2026-02-10T14:59:40.286008Z","end":"2026-02-10T14:59:40.408377Z","steps":["trace[906654623] 'range keys from in-memory index tree' (duration: 122.220324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:40.408694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.284741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:40.408715Z","caller":"traceutil/trace.go:171","msg":"trace[743676264] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"113.324361ms","start":"2026-02-10T14:59:40.295386Z","end":"2026-02-10T14:59:40.40871Z","steps":["trace[743676264] 'count revisions from in-memory index tree' (duration: 113.014924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:40.408815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.683497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:40.408914Z","caller":"traceutil/trace.go:171","msg":"trace[1331065608] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"109.715017ms","start":"2026-02-10T14:59:40.299114Z","end":"2026-02-10T14:59:40.408829Z","steps":["trace[1331065608] 'range keys from in-memory index tree' (duration: 109.626934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:40.40902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.032515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:40.40909Z","caller":"traceutil/trace.go:171","msg":"trace[1014666985] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"102.114237ms","start":"2026-02-10T14:59:40.30697Z","end":"2026-02-10T14:59:40.409085Z","steps":["trace[1014666985] 'count revisions from in-memory index tree' (duration: 102.011295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:40.409185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.240591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:40.409281Z","caller":"traceutil/trace.go:171","msg":"trace[641450042] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"102.337313ms","start":"2026-02-10T14:59:40.306938Z","end":"2026-02-10T14:59:40.409276Z","steps":["trace[641450042] 'range keys from in-memory index tree' (duration: 102.16758ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:42.114462Z","caller":"traceutil/trace.go:171","msg":"trace[168351274] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"635.436741ms","start":"2026-02-10T14:59:41.478999Z","end":"2026-02-10T14:59:42.114436Z","steps":["trace[168351274] 'process raft request' (duration: 634.961099ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:42.114689Z","caller":"traceutil/trace.go:171","msg":"trace[1184065146] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"635.617596ms","start":"2026-02-10T14:59:41.479047Z","end":"2026-02-10T14:59:42.114664Z","steps":["trace[1184065146] 'read index received' (duration: 26.072714ms)","trace[1184065146] 'applied index is now lower than readState.Index' (duration: 609.543982ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:42.115118Z","caller":"traceutil/trace.go:171","msg":"trace[66024708] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"618.326842ms","start":"2026-02-10T14:59:41.496778Z","end":"2026-02-10T14:59:42.115105Z","steps":["trace[66024708] 'process raft request' (duration: 617.391518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:42.115236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.496769Z","time spent":"618.380483ms","remote":"127.0.0.1:56860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:42.11534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.478995Z","time spent":"635.554895ms","remote":"127.0.0.1:56440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:59:42.115572Z","caller":"traceutil/trace.go:171","msg":"trace[720802642] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"618.188259ms","start":"2026-02-10T14:59:41.497371Z","end":"2026-02-10T14:59:42.115559Z","steps":["trace[720802642] 'process raft request' (duration: 616.843823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:42.115633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.497358Z","time spent":"618.242101ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:42.115746Z","caller":"traceutil/trace.go:171","msg":"trace[1351368055] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"616.458003ms","start":"2026-02-10T14:59:41.499253Z","end":"2026-02-10T14:59:42.115711Z","steps":["trace[1351368055] 'process raft request' (duration: 615.384836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:42.115824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.785246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-10T14:59:42.115841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.499249Z","time spent":"616.527405ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:42.115863Z","caller":"traceutil/trace.go:171","msg":"trace[223566824] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:9; }","duration":"636.898769ms","start":"2026-02-10T14:59:41.478954Z","end":"2026-02-10T14:59:42.115853Z","steps":["trace[223566824] 'agreement among raft nodes before linearized reading' (duration: 636.792806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:42.115919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.47895Z","time spent":"636.962521ms","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":26,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-10T14:59:42.11602Z","caller":"traceutil/trace.go:171","msg":"trace[2131333573] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"617.879862ms","start":"2026-02-10T14:59:41.49813Z","end":"2026-02-10T14:59:42.11601Z","steps":["trace[2131333573] 'process raft request' (duration: 616.114515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:42.116097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.49812Z","time spent":"617.930363ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:42.116203Z","caller":"traceutil/trace.go:171","msg":"trace[1819106323] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"617.916152ms","start":"2026-02-10T14:59:41.498278Z","end":"2026-02-10T14:59:42.116194Z","steps":["trace[1819106323] 'process raft request' (duration: 616.30407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:42.116282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.49827Z","time spent":"617.993624ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:42.116382Z","caller":"traceutil/trace.go:171","msg":"trace[470426183] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"617.201013ms","start":"2026-02-10T14:59:41.499173Z","end":"2026-02-10T14:59:42.116374Z","steps":["trace[470426183] 'process raft request' (duration: 615.446267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:42.116442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.499166Z","time spent":"617.258624ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:42.615553Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338634906333057,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T14:59:42.954994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.165191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T14:59:42.955215Z","caller":"traceutil/trace.go:171","msg":"trace[815469580] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.424326285s","start":"2026-02-10T14:59:41.530852Z","end":"2026-02-10T14:59:42.955178Z","steps":["trace[815469580] 'process raft request' (duration: 1.424274354s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:42.955297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.530823Z","time spent":"1.424427268s","remote":"127.0.0.1:56666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:42.955625Z","caller":"traceutil/trace.go:171","msg":"trace[2017273092] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.430631652s","start":"2026-02-10T14:59:41.524981Z","end":"2026-02-10T14:59:42.955612Z","steps":["trace[2017273092] 'process raft request' (duration: 1.325550517s)","trace[2017273092] 'compare' (duration: 103.994476ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:42.955691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.52496Z","time spent":"1.430690863s","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2847,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:43.537598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.334681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T14:59:43.537899Z","caller":"traceutil/trace.go:171","msg":"trace[451648211] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"570.864398ms","start":"2026-02-10T14:59:42.967022Z","end":"2026-02-10T14:59:43.537887Z","steps":["trace[451648211] 'process raft request' (duration: 570.794087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.537969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:42.967007Z","time spent":"570.934411ms","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3167,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:59:43.538131Z","caller":"traceutil/trace.go:171","msg":"trace[719271333] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.411035567s","start":"2026-02-10T14:59:42.127085Z","end":"2026-02-10T14:59:43.538121Z","steps":["trace[719271333] 'process raft request' (duration: 998.002357ms)","trace[719271333] 'compare' (duration: 412.184598ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:43.538178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:42.127055Z","time spent":"1.41110871s","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:43.53833Z","caller":"traceutil/trace.go:171","msg":"trace[2003794047] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.410676208s","start":"2026-02-10T14:59:42.127647Z","end":"2026-02-10T14:59:43.538323Z","steps":["trace[2003794047] 'process raft request' (duration: 1.410030911s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.538359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:42.127617Z","time spent":"1.41072727s","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:43.538556Z","caller":"traceutil/trace.go:171","msg":"trace[1368613175] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"1.409911298s","start":"2026-02-10T14:59:42.128618Z","end":"2026-02-10T14:59:43.53853Z","steps":["trace[1368613175] 'process raft request' (duration: 1.409092316s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.538586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:42.128601Z","time spent":"1.409971989s","remote":"127.0.0.1:56860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:43.538677Z","caller":"traceutil/trace.go:171","msg":"trace[1409488623] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"1.409924819s","start":"2026-02-10T14:59:42.128747Z","end":"2026-02-10T14:59:43.538671Z","steps":["trace[1409488623] 'process raft request' (duration: 1.409012144s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.538742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:42.128743Z","time spent":"1.409947699s","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:43.53883Z","caller":"traceutil/trace.go:171","msg":"trace[1369090043] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"1.409942979s","start":"2026-02-10T14:59:42.128881Z","end":"2026-02-10T14:59:43.538824Z","steps":["trace[1369090043] 'process raft request' (duration: 1.408902952s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.538874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:42.128842Z","time spent":"1.41000193s","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:43.53896Z","caller":"traceutil/trace.go:171","msg":"trace[1466426604] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"1.409921699s","start":"2026-02-10T14:59:42.129033Z","end":"2026-02-10T14:59:43.538954Z","steps":["trace[1466426604] 'process raft request' (duration: 1.408766829s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.53903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:42.129021Z","time spent":"1.40997694s","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:43.539123Z","caller":"traceutil/trace.go:171","msg":"trace[11914225] linearizableReadLoop","detail":"{readStateIndex:20; appliedIndex:12; }","duration":"1.424228263s","start":"2026-02-10T14:59:42.114887Z","end":"2026-02-10T14:59:43.539115Z","steps":["trace[11914225] 'read index received' (duration: 735.568387ms)","trace[11914225] 'applied index is now lower than readState.Index' (duration: 688.658726ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:43.539487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.994973158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:43.539513Z","caller":"traceutil/trace.go:171","msg":"trace[1116916107] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"1.995044611s","start":"2026-02-10T14:59:41.54446Z","end":"2026-02-10T14:59:43.539504Z","steps":["trace[1116916107] 'agreement among raft nodes before linearized reading' (duration: 1.994989489s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.539531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.544416Z","time spent":"1.995111062s","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-10T14:59:43.540166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.007122797s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:43.540209Z","caller":"traceutil/trace.go:171","msg":"trace[1692635400] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"2.007189939s","start":"2026-02-10T14:59:41.533004Z","end":"2026-02-10T14:59:43.540194Z","steps":["trace[1692635400] 'agreement among raft nodes before linearized reading' (duration: 2.007131257s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.54024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.532991Z","time spent":"2.00724222s","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-10T14:59:43.540415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.041447827s","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-02-10T14:59:43.540445Z","caller":"traceutil/trace.go:171","msg":"trace[451210926] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:18; }","duration":"2.041481218s","start":"2026-02-10T14:59:41.498953Z","end":"2026-02-10T14:59:43.540435Z","steps":["trace[451210926] 'agreement among raft nodes before linearized reading' (duration: 2.041429386s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.540472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.498927Z","time spent":"2.041538339s","remote":"127.0.0.1:56470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-10T14:59:43.540591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.04383766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:43.540619Z","caller":"traceutil/trace.go:171","msg":"trace[614848085] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:0; response_revision:18; }","duration":"2.043890302s","start":"2026-02-10T14:59:41.496717Z","end":"2026-02-10T14:59:43.540607Z","steps":["trace[614848085] 'agreement among raft nodes before linearized reading' (duration: 2.043856511s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.540644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.496702Z","time spent":"2.043936843s","remote":"127.0.0.1:56424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.156\" "} {"level":"warn","ts":"2026-02-10T14:59:43.540761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.044040366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:43.540784Z","caller":"traceutil/trace.go:171","msg":"trace[2023991424] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"2.044087726s","start":"2026-02-10T14:59:41.496689Z","end":"2026-02-10T14:59:43.540777Z","steps":["trace[2023991424] 'agreement among raft nodes before linearized reading' (duration: 2.044055675s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.540807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.496655Z","time spent":"2.044147018s","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-10T14:59:43.540924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40268763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:43.540948Z","caller":"traceutil/trace.go:171","msg":"trace[2009073776] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:18; }","duration":"1.402743831s","start":"2026-02-10T14:59:42.138196Z","end":"2026-02-10T14:59:43.54094Z","steps":["trace[2009073776] 'agreement among raft nodes before linearized reading' (duration: 1.40270465s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.54097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:42.138187Z","time spent":"1.402778642s","remote":"127.0.0.1:56794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-10T14:59:43.541225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.479853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-tnvd6\" ","response":"range_response_count:1 size:857"} {"level":"warn","ts":"2026-02-10T14:59:43.541249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.122522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:43.541254Z","caller":"traceutil/trace.go:171","msg":"trace[1493900534] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-tnvd6; range_end:; response_count:1; response_revision:18; }","duration":"579.562546ms","start":"2026-02-10T14:59:42.961683Z","end":"2026-02-10T14:59:43.541245Z","steps":["trace[1493900534] 'agreement among raft nodes before linearized reading' (duration: 579.510344ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:43.541271Z","caller":"traceutil/trace.go:171","msg":"trace[1737261531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18; }","duration":"741.207943ms","start":"2026-02-10T14:59:42.800057Z","end":"2026-02-10T14:59:43.541265Z","steps":["trace[1737261531] 'agreement among raft nodes before linearized reading' (duration: 741.137881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.541281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:42.961656Z","time spent":"579.619597ms","remote":"127.0.0.1:56666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":879,"request content":"key:\"/registry/certificatesigningrequests/csr-tnvd6\" "} {"level":"warn","ts":"2026-02-10T14:59:43.54129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:42.800001Z","time spent":"741.282257ms","remote":"127.0.0.1:56386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:59:43.541432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148802782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:43.54145Z","caller":"traceutil/trace.go:171","msg":"trace[167722417] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:18; }","duration":"1.148837773s","start":"2026-02-10T14:59:42.392607Z","end":"2026-02-10T14:59:43.541445Z","steps":["trace[167722417] 'agreement among raft nodes before linearized reading' (duration: 1.148808762s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.541465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:42.392601Z","time spent":"1.148860693s","remote":"127.0.0.1:56760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-02-10T14:59:43.541587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149083519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:43.541604Z","caller":"traceutil/trace.go:171","msg":"trace[968675738] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:18; }","duration":"1.14913809s","start":"2026-02-10T14:59:42.392461Z","end":"2026-02-10T14:59:43.541599Z","steps":["trace[968675738] 'agreement among raft nodes before linearized reading' (duration: 1.14910969s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.54162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:42.392442Z","time spent":"1.149173291s","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-02-10T14:59:44.389862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.077539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T14:59:44.390052Z","caller":"traceutil/trace.go:171","msg":"trace[1225209117] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:21; }","duration":"843.378143ms","start":"2026-02-10T14:59:43.54665Z","end":"2026-02-10T14:59:44.390028Z","steps":["trace[1225209117] 'read index received' (duration: 194.102869ms)","trace[1225209117] 'applied index is now lower than readState.Index' (duration: 649.272504ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:44.390155Z","caller":"traceutil/trace.go:171","msg":"trace[42160501] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"845.634382ms","start":"2026-02-10T14:59:43.544511Z","end":"2026-02-10T14:59:44.390145Z","steps":["trace[42160501] 'process raft request' (duration: 196.230625ms)","trace[42160501] 'compare' (duration: 648.910124ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:44.390222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.544494Z","time spent":"845.679194ms","remote":"127.0.0.1:56760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:44.390223Z","caller":"traceutil/trace.go:171","msg":"trace[1654696278] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"836.340819ms","start":"2026-02-10T14:59:43.553866Z","end":"2026-02-10T14:59:44.390207Z","steps":["trace[1654696278] 'process raft request' (duration: 836.314198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:44.390284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.55386Z","time spent":"836.399421ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:44.390394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.735542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:44.39042Z","caller":"traceutil/trace.go:171","msg":"trace[1798795135] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:26; }","duration":"843.787964ms","start":"2026-02-10T14:59:43.546625Z","end":"2026-02-10T14:59:44.390413Z","steps":["trace[1798795135] 'agreement among raft nodes before linearized reading' (duration: 843.710862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:44.39044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.546581Z","time spent":"843.854106ms","remote":"127.0.0.1:56758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-02-10T14:59:44.390559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.211977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:44.390578Z","caller":"traceutil/trace.go:171","msg":"trace[2026913997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26; }","duration":"841.295319ms","start":"2026-02-10T14:59:43.549276Z","end":"2026-02-10T14:59:44.390572Z","steps":["trace[2026913997] 'agreement among raft nodes before linearized reading' (duration: 841.267818ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:44.390573Z","caller":"traceutil/trace.go:171","msg":"trace[847033680] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"842.714307ms","start":"2026-02-10T14:59:43.547848Z","end":"2026-02-10T14:59:44.390563Z","steps":["trace[847033680] 'process raft request' (duration: 842.123011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:44.390594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.549269Z","time spent":"841.321189ms","remote":"127.0.0.1:56400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:59:44.390626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.547838Z","time spent":"842.759478ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:44.390789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.242216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:44.390818Z","caller":"traceutil/trace.go:171","msg":"trace[1266192949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26; }","duration":"590.315288ms","start":"2026-02-10T14:59:43.800497Z","end":"2026-02-10T14:59:44.390812Z","steps":["trace[1266192949] 'agreement among raft nodes before linearized reading' (duration: 590.273027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:44.390836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.800475Z","time spent":"590.356519ms","remote":"127.0.0.1:56386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:59:44.39094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.904083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:44.390972Z","caller":"traceutil/trace.go:171","msg":"trace[1562566850] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:26; }","duration":"635.952474ms","start":"2026-02-10T14:59:43.754999Z","end":"2026-02-10T14:59:44.390952Z","steps":["trace[1562566850] 'agreement among raft nodes before linearized reading' (duration: 635.923214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:44.390992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.754985Z","time spent":"636.002385ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-02-10T14:59:44.391129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.262665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-10T14:59:44.391148Z","caller":"traceutil/trace.go:171","msg":"trace[1141699384] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:26; }","duration":"781.318856ms","start":"2026-02-10T14:59:43.609823Z","end":"2026-02-10T14:59:44.391142Z","steps":["trace[1141699384] 'agreement among raft nodes before linearized reading' (duration: 781.277145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:44.391164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.609803Z","time spent":"781.357227ms","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-10T14:59:44.39127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.27528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3179"} {"level":"info","ts":"2026-02-10T14:59:44.391292Z","caller":"traceutil/trace.go:171","msg":"trace[1192162474] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26; }","duration":"838.307702ms","start":"2026-02-10T14:59:43.552975Z","end":"2026-02-10T14:59:44.391282Z","steps":["trace[1192162474] 'agreement among raft nodes before linearized reading' (duration: 838.268641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:44.391308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.552969Z","time spent":"838.334482ms","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3201,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-10T14:59:44.391333Z","caller":"traceutil/trace.go:171","msg":"trace[1544088420] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"839.254835ms","start":"2026-02-10T14:59:43.552064Z","end":"2026-02-10T14:59:44.391319Z","steps":["trace[1544088420] 'process raft request' (duration: 837.944701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:44.391401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.552053Z","time spent":"839.310757ms","remote":"127.0.0.1:56860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:44.391561Z","caller":"traceutil/trace.go:171","msg":"trace[537081809] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"838.710171ms","start":"2026-02-10T14:59:43.552842Z","end":"2026-02-10T14:59:44.391552Z","steps":["trace[537081809] 'process raft request' (duration: 837.198442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:44.3916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.552835Z","time spent":"838.747162ms","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:44.391756Z","caller":"traceutil/trace.go:171","msg":"trace[1349881591] transaction","detail":"{read_only:false; number_of_response:0; response_revision:22; }","duration":"838.858375ms","start":"2026-02-10T14:59:43.55289Z","end":"2026-02-10T14:59:44.391748Z","steps":["trace[1349881591] 'process raft request' (duration: 837.180821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:44.391801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.552883Z","time spent":"838.895106ms","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:44.391863Z","caller":"traceutil/trace.go:171","msg":"trace[550076664] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"838.790943ms","start":"2026-02-10T14:59:43.553065Z","end":"2026-02-10T14:59:44.391856Z","steps":["trace[550076664] 'process raft request' (duration: 837.024287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:44.391889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.55306Z","time spent":"838.815563ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:44.391968Z","caller":"traceutil/trace.go:171","msg":"trace[321892084] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"838.28207ms","start":"2026-02-10T14:59:43.553676Z","end":"2026-02-10T14:59:44.391958Z","steps":["trace[321892084] 'process raft request' (duration: 836.460092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:44.392024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.553669Z","time spent":"838.333441ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:44.392094Z","caller":"traceutil/trace.go:171","msg":"trace[155353496] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"838.30453ms","start":"2026-02-10T14:59:43.553783Z","end":"2026-02-10T14:59:44.392087Z","steps":["trace[155353496] 'process raft request' (duration: 836.37643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:44.392127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.553775Z","time spent":"838.336182ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:45.024795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.448673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T14:59:45.025192Z","caller":"traceutil/trace.go:171","msg":"trace[544164461] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"620.345395ms","start":"2026-02-10T14:59:44.404809Z","end":"2026-02-10T14:59:45.025154Z","steps":["trace[544164461] 'process raft request' (duration: 85.44734ms)","trace[544164461] 'compare' (duration: 534.33111ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:45.025234Z","caller":"traceutil/trace.go:171","msg":"trace[888495167] linearizableReadLoop","detail":"{readStateIndex:34; appliedIndex:30; }","duration":"618.24211ms","start":"2026-02-10T14:59:44.406976Z","end":"2026-02-10T14:59:45.025218Z","steps":["trace[888495167] 'read index received' (duration: 83.288503ms)","trace[888495167] 'applied index is now lower than readState.Index' (duration: 534.952517ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:45.025303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.404788Z","time spent":"620.461529ms","remote":"127.0.0.1:56794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:45.025466Z","caller":"traceutil/trace.go:171","msg":"trace[117396226] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"620.288684ms","start":"2026-02-10T14:59:44.405169Z","end":"2026-02-10T14:59:45.025458Z","steps":["trace[117396226] 'process raft request' (duration: 619.819112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.025519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.405166Z","time spent":"620.326426ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:45.025665Z","caller":"traceutil/trace.go:171","msg":"trace[130891392] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"618.647722ms","start":"2026-02-10T14:59:44.407006Z","end":"2026-02-10T14:59:45.025654Z","steps":["trace[130891392] 'process raft request' (duration: 618.008085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.025706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.407002Z","time spent":"618.685503ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:45.025709Z","caller":"traceutil/trace.go:171","msg":"trace[569768148] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"620.677215ms","start":"2026-02-10T14:59:44.405016Z","end":"2026-02-10T14:59:45.025693Z","steps":["trace[569768148] 'process raft request' (duration: 619.875644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.025774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.405011Z","time spent":"620.735416ms","remote":"127.0.0.1:56860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:45.025887Z","caller":"traceutil/trace.go:171","msg":"trace[207332078] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"618.800806ms","start":"2026-02-10T14:59:44.407045Z","end":"2026-02-10T14:59:45.025846Z","steps":["trace[207332078] 'process raft request' (duration: 617.990144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.025941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.407042Z","time spent":"618.875427ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:45.025312Z","caller":"traceutil/trace.go:171","msg":"trace[275117595] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"620.164651ms","start":"2026-02-10T14:59:44.405139Z","end":"2026-02-10T14:59:45.025304Z","steps":["trace[275117595] 'process raft request' (duration: 619.822913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.026017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.405135Z","time spent":"620.856219ms","remote":"127.0.0.1:56492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:45.026104Z","caller":"traceutil/trace.go:171","msg":"trace[1657339727] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"617.86372ms","start":"2026-02-10T14:59:44.408232Z","end":"2026-02-10T14:59:45.026095Z","steps":["trace[1657339727] 'process raft request' (duration: 616.839003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.026148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.408227Z","time spent":"617.899111ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:45.026272Z","caller":"traceutil/trace.go:171","msg":"trace[1418479548] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"617.925523ms","start":"2026-02-10T14:59:44.408336Z","end":"2026-02-10T14:59:45.026261Z","steps":["trace[1418479548] 'process raft request' (duration: 616.763892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.026311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.408331Z","time spent":"617.962804ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:45.02641Z","caller":"traceutil/trace.go:171","msg":"trace[693841508] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"618.037845ms","start":"2026-02-10T14:59:44.408364Z","end":"2026-02-10T14:59:45.026402Z","steps":["trace[693841508] 'process raft request' (duration: 616.822703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.026445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.408349Z","time spent":"618.078966ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:45.026569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.581855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:45.026617Z","caller":"traceutil/trace.go:171","msg":"trace[229467883] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:35; }","duration":"619.642877ms","start":"2026-02-10T14:59:44.406956Z","end":"2026-02-10T14:59:45.026599Z","steps":["trace[229467883] 'agreement among raft nodes before linearized reading' (duration: 619.508624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.026649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.40695Z","time spent":"619.691968ms","remote":"127.0.0.1:56760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-cluster-critical\" "} {"level":"warn","ts":"2026-02-10T14:59:45.026844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.730089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:45.026875Z","caller":"traceutil/trace.go:171","msg":"trace[410596035] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:35; }","duration":"619.76697ms","start":"2026-02-10T14:59:44.407099Z","end":"2026-02-10T14:59:45.026865Z","steps":["trace[410596035] 'agreement among raft nodes before linearized reading' (duration: 619.721649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.026901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.407096Z","time spent":"619.799962ms","remote":"127.0.0.1:56492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":26,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"warn","ts":"2026-02-10T14:59:45.02712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.907085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:45.02715Z","caller":"traceutil/trace.go:171","msg":"trace[411232161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:35; }","duration":"423.978987ms","start":"2026-02-10T14:59:44.603161Z","end":"2026-02-10T14:59:45.02714Z","steps":["trace[411232161] 'agreement among raft nodes before linearized reading' (duration: 423.935346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.027174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.603144Z","time spent":"424.025058ms","remote":"127.0.0.1:56400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:59:45.027328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.789257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:45.027363Z","caller":"traceutil/trace.go:171","msg":"trace[665612342] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:35; }","duration":"461.85085ms","start":"2026-02-10T14:59:44.565503Z","end":"2026-02-10T14:59:45.027354Z","steps":["trace[665612342] 'agreement among raft nodes before linearized reading' (duration: 461.803399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.027393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.565488Z","time spent":"461.893302ms","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-02-10T14:59:45.02753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.310508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:45.027557Z","caller":"traceutil/trace.go:171","msg":"trace[912016559] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:35; }","duration":"619.346239ms","start":"2026-02-10T14:59:44.408202Z","end":"2026-02-10T14:59:45.027548Z","steps":["trace[912016559] 'agreement among raft nodes before linearized reading' (duration: 619.303778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.027583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.408196Z","time spent":"619.38109ms","remote":"127.0.0.1:56470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"warn","ts":"2026-02-10T14:59:45.027695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.520631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:45.027727Z","caller":"traceutil/trace.go:171","msg":"trace[750235409] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:35; }","duration":"620.546232ms","start":"2026-02-10T14:59:44.407167Z","end":"2026-02-10T14:59:45.027714Z","steps":["trace[750235409] 'agreement among raft nodes before linearized reading' (duration: 620.510011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.027751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:44.407095Z","time spent":"620.650604ms","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"info","ts":"2026-02-10T14:59:45.41838Z","caller":"traceutil/trace.go:171","msg":"trace[1240066768] linearizableReadLoop","detail":"{readStateIndex:44; appliedIndex:40; }","duration":"314.659461ms","start":"2026-02-10T14:59:45.103698Z","end":"2026-02-10T14:59:45.418357Z","steps":["trace[1240066768] 'read index received' (duration: 291.903344ms)","trace[1240066768] 'applied index is now lower than readState.Index' (duration: 22.755197ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:45.41878Z","caller":"traceutil/trace.go:171","msg":"trace[51860243] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"380.380823ms","start":"2026-02-10T14:59:45.038232Z","end":"2026-02-10T14:59:45.418613Z","steps":["trace[51860243] 'process raft request' (duration: 357.437531ms)","trace[51860243] 'compare' (duration: 22.423598ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:45.418889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.038224Z","time spent":"380.615219ms","remote":"127.0.0.1:56492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3004,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:45.419081Z","caller":"traceutil/trace.go:171","msg":"trace[1482354064] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"380.744273ms","start":"2026-02-10T14:59:45.038325Z","end":"2026-02-10T14:59:45.41907Z","steps":["trace[1482354064] 'process raft request' (duration: 379.987523ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:45.419118Z","caller":"traceutil/trace.go:171","msg":"trace[124796277] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"380.819875ms","start":"2026-02-10T14:59:45.038269Z","end":"2026-02-10T14:59:45.419089Z","steps":["trace[124796277] 'process raft request' (duration: 379.947501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.419158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.038322Z","time spent":"380.810955ms","remote":"127.0.0.1:56860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":711,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:45.419241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.038265Z","time spent":"380.921298ms","remote":"127.0.0.1:56760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:45.419513Z","caller":"traceutil/trace.go:171","msg":"trace[1310514609] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"381.199635ms","start":"2026-02-10T14:59:45.038299Z","end":"2026-02-10T14:59:45.419499Z","steps":["trace[1310514609] 'process raft request' (duration: 379.982544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.420678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.36424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:45.420746Z","caller":"traceutil/trace.go:171","msg":"trace[1466796133] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:40; }","duration":"329.206572ms","start":"2026-02-10T14:59:45.091509Z","end":"2026-02-10T14:59:45.420715Z","steps":["trace[1466796133] 'agreement among raft nodes before linearized reading' (duration: 328.078142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.420799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.091491Z","time spent":"329.294414ms","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-02-10T14:59:45.420965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.556851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:45.420992Z","caller":"traceutil/trace.go:171","msg":"trace[346662819] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:40; }","duration":"313.606253ms","start":"2026-02-10T14:59:45.107377Z","end":"2026-02-10T14:59:45.420984Z","steps":["trace[346662819] 'agreement among raft nodes before linearized reading' (duration: 313.564761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.421017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.107366Z","time spent":"313.645394ms","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-02-10T14:59:45.421227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.418868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:45.421259Z","caller":"traceutil/trace.go:171","msg":"trace[111615787] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:40; }","duration":"313.446889ms","start":"2026-02-10T14:59:45.1078Z","end":"2026-02-10T14:59:45.421247Z","steps":["trace[111615787] 'agreement among raft nodes before linearized reading' (duration: 313.405988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.421286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.107772Z","time spent":"313.506211ms","remote":"127.0.0.1:56470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" "} {"level":"warn","ts":"2026-02-10T14:59:45.419521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.584451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:45.424635Z","caller":"traceutil/trace.go:171","msg":"trace[606681909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:40; }","duration":"321.730247ms","start":"2026-02-10T14:59:45.10289Z","end":"2026-02-10T14:59:45.424621Z","steps":["trace[606681909] 'agreement among raft nodes before linearized reading' (duration: 316.516299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.424755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.102875Z","time spent":"321.838159ms","remote":"127.0.0.1:56386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:59:45.42586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.038296Z","time spent":"385.842357ms","remote":"127.0.0.1:56944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:45.938053Z","caller":"traceutil/trace.go:171","msg":"trace[74252649] linearizableReadLoop","detail":"{readStateIndex:46; appliedIndex:45; }","duration":"441.459556ms","start":"2026-02-10T14:59:45.496575Z","end":"2026-02-10T14:59:45.938034Z","steps":["trace[74252649] 'read index received' (duration: 344.421441ms)","trace[74252649] 'applied index is now lower than readState.Index' (duration: 97.037375ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:45.938256Z","caller":"traceutil/trace.go:171","msg":"trace[220540335] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"508.859122ms","start":"2026-02-10T14:59:45.429387Z","end":"2026-02-10T14:59:45.938246Z","steps":["trace[220540335] 'process raft request' (duration: 411.661444ms)","trace[220540335] 'compare' (duration: 96.646854ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:45.938319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.429365Z","time spent":"508.910874ms","remote":"127.0.0.1:56860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":715,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:45.938466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.119695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:45.938489Z","caller":"traceutil/trace.go:171","msg":"trace[577208870] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:42; }","duration":"457.176307ms","start":"2026-02-10T14:59:45.481305Z","end":"2026-02-10T14:59:45.938481Z","steps":["trace[577208870] 'agreement among raft nodes before linearized reading' (duration: 457.110715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.938508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.481287Z","time spent":"457.216989ms","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-02-10T14:59:45.938599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.948299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:45.938618Z","caller":"traceutil/trace.go:171","msg":"trace[930109964] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:42; }","duration":"433.991619ms","start":"2026-02-10T14:59:45.50462Z","end":"2026-02-10T14:59:45.938612Z","steps":["trace[930109964] 'agreement among raft nodes before linearized reading' (duration: 433.963088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.938635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.504607Z","time spent":"434.023941ms","remote":"127.0.0.1:56470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" "} {"level":"warn","ts":"2026-02-10T14:59:45.938765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.824159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:45.938785Z","caller":"traceutil/trace.go:171","msg":"trace[557921530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:42; }","duration":"334.870279ms","start":"2026-02-10T14:59:45.60391Z","end":"2026-02-10T14:59:45.93878Z","steps":["trace[557921530] 'agreement among raft nodes before linearized reading' (duration: 334.803598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.938801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.603892Z","time spent":"334.905621ms","remote":"127.0.0.1:56400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:59:45.938887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.373313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:45.938904Z","caller":"traceutil/trace.go:171","msg":"trace[1364153358] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:42; }","duration":"433.402313ms","start":"2026-02-10T14:59:45.505497Z","end":"2026-02-10T14:59:45.938899Z","steps":["trace[1364153358] 'agreement among raft nodes before linearized reading' (duration: 433.376313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.93892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.505491Z","time spent":"433.425375ms","remote":"127.0.0.1:56528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":26,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-02-10T14:59:45.938994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.257896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:45.939009Z","caller":"traceutil/trace.go:171","msg":"trace[1930205000] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:42; }","duration":"434.283787ms","start":"2026-02-10T14:59:45.504721Z","end":"2026-02-10T14:59:45.939005Z","steps":["trace[1930205000] 'agreement among raft nodes before linearized reading' (duration: 434.259116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:45.939025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.504717Z","time spent":"434.304527ms","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"info","ts":"2026-02-10T14:59:46.063265Z","caller":"traceutil/trace.go:171","msg":"trace[72997107] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"115.90712ms","start":"2026-02-10T14:59:45.947333Z","end":"2026-02-10T14:59:46.06324Z","steps":["trace[72997107] 'process raft request' (duration: 88.576083ms)","trace[72997107] 'compare' (duration: 27.162043ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:46.766553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.387655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T14:59:46.766696Z","caller":"traceutil/trace.go:171","msg":"trace[1316951098] linearizableReadLoop","detail":"{readStateIndex:50; appliedIndex:46; }","duration":"814.979488ms","start":"2026-02-10T14:59:45.951704Z","end":"2026-02-10T14:59:46.766684Z","steps":["trace[1316951098] 'read index received' (duration: 84.124535ms)","trace[1316951098] 'applied index is now lower than readState.Index' (duration: 730.854343ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:46.766868Z","caller":"traceutil/trace.go:171","msg":"trace[1054917632] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"815.533824ms","start":"2026-02-10T14:59:45.951325Z","end":"2026-02-10T14:59:46.766859Z","steps":["trace[1054917632] 'process raft request' (duration: 212.79183ms)","trace[1054917632] 'compare' (duration: 602.208719ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:46.766913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.951305Z","time spent":"815.589774ms","remote":"127.0.0.1:56578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5860,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:46.767045Z","caller":"traceutil/trace.go:171","msg":"trace[1153658163] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"815.642486ms","start":"2026-02-10T14:59:45.951395Z","end":"2026-02-10T14:59:46.767038Z","steps":["trace[1153658163] 'process raft request' (duration: 815.226266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:46.767082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.951391Z","time spent":"815.673797ms","remote":"127.0.0.1:56578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:46.767201Z","caller":"traceutil/trace.go:171","msg":"trace[2113970797] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"815.682297ms","start":"2026-02-10T14:59:45.951512Z","end":"2026-02-10T14:59:46.767194Z","steps":["trace[2113970797] 'process raft request' (duration: 815.146684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:46.76724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.951504Z","time spent":"815.722418ms","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":326,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:46.767372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.660986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:46.767398Z","caller":"traceutil/trace.go:171","msg":"trace[2090569530] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:46; }","duration":"815.709949ms","start":"2026-02-10T14:59:45.951681Z","end":"2026-02-10T14:59:46.767391Z","steps":["trace[2090569530] 'agreement among raft nodes before linearized reading' (duration: 815.631397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:46.767422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:45.951673Z","time spent":"815.743509ms","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-02-10T14:59:47.239644Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.075397077s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-10T14:59:47.366372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.447196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T14:59:47.366581Z","caller":"traceutil/trace.go:171","msg":"trace[529505264] linearizableReadLoop","detail":"{readStateIndex:52; appliedIndex:50; }","duration":"599.844847ms","start":"2026-02-10T14:59:46.766722Z","end":"2026-02-10T14:59:47.366566Z","steps":["trace[529505264] 'read index received' (duration: 473.091774ms)","trace[529505264] 'applied index is now lower than readState.Index' (duration: 126.752173ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:47.366929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263596651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-10T14:59:47.366995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.019161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:47.36703Z","caller":"traceutil/trace.go:171","msg":"trace[838837849] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:0; response_revision:48; }","duration":"595.085873ms","start":"2026-02-10T14:59:46.771932Z","end":"2026-02-10T14:59:47.367018Z","steps":["trace[838837849] 'agreement among raft nodes before linearized reading' (duration: 595.041942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:47.367063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:46.771911Z","time spent":"595.144174ms","remote":"127.0.0.1:56758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/system:discovery\" "} {"level":"info","ts":"2026-02-10T14:59:47.367233Z","caller":"traceutil/trace.go:171","msg":"trace[1276765469] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"1.299367809s","start":"2026-02-10T14:59:46.067853Z","end":"2026-02-10T14:59:47.367221Z","steps":["trace[1276765469] 'process raft request' (duration: 1.172015991s)","trace[1276765469] 'compare' (duration: 126.314171ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:47.367298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:46.067834Z","time spent":"1.29942521s","remote":"127.0.0.1:56844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:47.367439Z","caller":"traceutil/trace.go:171","msg":"trace[836165349] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"1.297673515s","start":"2026-02-10T14:59:46.069758Z","end":"2026-02-10T14:59:47.367431Z","steps":["trace[836165349] 'process raft request' (duration: 1.29671307s)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:47.366996Z","caller":"traceutil/trace.go:171","msg":"trace[175967247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:48; }","duration":"1.263713484s","start":"2026-02-10T14:59:46.103266Z","end":"2026-02-10T14:59:47.366979Z","steps":["trace[175967247] 'agreement among raft nodes before linearized reading' (duration: 1.263563721s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:47.36752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:46.103252Z","time spent":"1.264252668s","remote":"127.0.0.1:56386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:59:47.366945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365604046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:1 size:338"} {"level":"info","ts":"2026-02-10T14:59:47.367696Z","caller":"traceutil/trace.go:171","msg":"trace[1488232567] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:1; response_revision:48; }","duration":"1.366691424s","start":"2026-02-10T14:59:46.000997Z","end":"2026-02-10T14:59:47.367689Z","steps":["trace[1488232567] 'agreement among raft nodes before linearized reading' (duration: 1.365888593s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:47.367724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:46.000982Z","time spent":"1.366735165s","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":1,"response size":360,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-02-10T14:59:47.367482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:46.06975Z","time spent":"1.297704446s","remote":"127.0.0.1:56844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1058,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T14:59:47.742171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.334291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T14:59:47.742466Z","caller":"traceutil/trace.go:171","msg":"trace[454013607] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"362.672119ms","start":"2026-02-10T14:59:47.379769Z","end":"2026-02-10T14:59:47.742441Z","steps":["trace[454013607] 'process raft request' (duration: 362.615918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:47.742549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:47.379762Z","time spent":"362.751271ms","remote":"127.0.0.1:56454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:47.742832Z","caller":"traceutil/trace.go:171","msg":"trace[1586906118] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"368.44251ms","start":"2026-02-10T14:59:47.374375Z","end":"2026-02-10T14:59:47.742817Z","steps":["trace[1586906118] 'process raft request' (duration: 367.951876ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:47.742847Z","caller":"traceutil/trace.go:171","msg":"trace[144409419] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"369.676562ms","start":"2026-02-10T14:59:47.373162Z","end":"2026-02-10T14:59:47.742839Z","steps":["trace[144409419] 'process raft request' (duration: 112.622683ms)","trace[144409419] 'compare' (duration: 256.204317ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:47.742881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:47.373151Z","time spent":"369.713624ms","remote":"127.0.0.1:56844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":632,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:47.74291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:47.374365Z","time spent":"368.498502ms","remote":"127.0.0.1:56844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1120,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:59:47.743021Z","caller":"traceutil/trace.go:171","msg":"trace[611542513] linearizableReadLoop","detail":"{readStateIndex:54; appliedIndex:53; }","duration":"369.792355ms","start":"2026-02-10T14:59:47.373218Z","end":"2026-02-10T14:59:47.74301Z","steps":["trace[611542513] 'read index received' (duration: 6.1002ms)","trace[611542513] 'applied index is now lower than readState.Index' (duration: 363.691175ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:47.743076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.848317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:47.74311Z","caller":"traceutil/trace.go:171","msg":"trace[1646981049] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:0; response_revision:51; }","duration":"369.896858ms","start":"2026-02-10T14:59:47.373202Z","end":"2026-02-10T14:59:47.743099Z","steps":["trace[1646981049] 'agreement among raft nodes before linearized reading' (duration: 369.842496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:47.743141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:47.373197Z","time spent":"369.936789ms","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/cluster-admin\" "} {"level":"info","ts":"2026-02-10T14:59:48.04625Z","caller":"traceutil/trace.go:171","msg":"trace[2044028570] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"299.633577ms","start":"2026-02-10T14:59:47.746595Z","end":"2026-02-10T14:59:48.046229Z","steps":["trace[2044028570] 'process raft request' (duration: 299.514594ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:48.053413Z","caller":"traceutil/trace.go:171","msg":"trace[1735407083] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"304.139305ms","start":"2026-02-10T14:59:47.749251Z","end":"2026-02-10T14:59:48.05339Z","steps":["trace[1735407083] 'process raft request' (duration: 304.101073ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:48.05343Z","caller":"traceutil/trace.go:171","msg":"trace[789785941] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"305.44791ms","start":"2026-02-10T14:59:47.747972Z","end":"2026-02-10T14:59:48.05342Z","steps":["trace[789785941] 'process raft request' (duration: 305.272555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:48.053481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:47.749238Z","time spent":"304.208477ms","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:48.053562Z","caller":"traceutil/trace.go:171","msg":"trace[1578343234] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"305.504431ms","start":"2026-02-10T14:59:47.748052Z","end":"2026-02-10T14:59:48.053556Z","steps":["trace[1578343234] 'process raft request' (duration: 305.264704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:48.053482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:47.747956Z","time spent":"305.503281ms","remote":"127.0.0.1:56844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":796,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:48.053595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:47.748045Z","time spent":"305.535722ms","remote":"127.0.0.1:56454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:59:48.307044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.51804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T14:59:48.307136Z","caller":"traceutil/trace.go:171","msg":"trace[1939131738] linearizableReadLoop","detail":"{readStateIndex:61; appliedIndex:60; }","duration":"250.707583ms","start":"2026-02-10T14:59:48.056407Z","end":"2026-02-10T14:59:48.307114Z","steps":["trace[1939131738] 'read index received' (duration: 138.154272ms)","trace[1939131738] 'applied index is now lower than readState.Index' (duration: 112.552311ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:48.307375Z","caller":"traceutil/trace.go:171","msg":"trace[2145621145] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"251.149694ms","start":"2026-02-10T14:59:48.056211Z","end":"2026-02-10T14:59:48.307361Z","steps":["trace[2145621145] 'process raft request' (duration: 138.256685ms)","trace[2145621145] 'compare' (duration: 112.404147ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:48.307602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.186466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:48.307632Z","caller":"traceutil/trace.go:171","msg":"trace[1635138742] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:0; response_revision:56; }","duration":"251.235427ms","start":"2026-02-10T14:59:48.056387Z","end":"2026-02-10T14:59:48.307623Z","steps":["trace[1635138742] 'agreement among raft nodes before linearized reading' (duration: 251.135994ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:48.526773Z","caller":"traceutil/trace.go:171","msg":"trace[962163576] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"469.952983ms","start":"2026-02-10T14:59:48.056745Z","end":"2026-02-10T14:59:48.526698Z","steps":["trace[962163576] 'process raft request' (duration: 417.687732ms)","trace[962163576] 'compare' (duration: 51.83669ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:48.526945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:48.056736Z","time spent":"470.129358ms","remote":"127.0.0.1:56844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1216,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:59:48.527138Z","caller":"traceutil/trace.go:171","msg":"trace[1930386614] linearizableReadLoop","detail":"{readStateIndex:62; appliedIndex:61; }","duration":"219.963018ms","start":"2026-02-10T14:59:48.30716Z","end":"2026-02-10T14:59:48.527123Z","steps":["trace[1930386614] 'read index received' (duration: 167.132053ms)","trace[1930386614] 'applied index is now lower than readState.Index' (duration: 52.829615ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:48.528462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.173133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:48.528578Z","caller":"traceutil/trace.go:171","msg":"trace[1546559505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:58; }","duration":"228.337567ms","start":"2026-02-10T14:59:48.300211Z","end":"2026-02-10T14:59:48.528549Z","steps":["trace[1546559505] 'agreement among raft nodes before linearized reading' (duration: 227.041163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:48.528708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.741483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.1892ea9fd6382c0e\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:48.528755Z","caller":"traceutil/trace.go:171","msg":"trace[2013350141] range","detail":"{range_begin:/registry/events/default/instance.1892ea9fd6382c0e; range_end:; response_count:0; response_revision:58; }","duration":"470.801065ms","start":"2026-02-10T14:59:48.057941Z","end":"2026-02-10T14:59:48.528742Z","steps":["trace[2013350141] 'agreement among raft nodes before linearized reading' (duration: 469.341986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:48.528789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:48.057935Z","time spent":"470.847106ms","remote":"127.0.0.1:56454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":26,"request content":"key:\"/registry/events/default/instance.1892ea9fd6382c0e\" "} {"level":"warn","ts":"2026-02-10T14:59:48.529004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.387508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:48.529031Z","caller":"traceutil/trace.go:171","msg":"trace[653631616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:59; }","duration":"125.442609ms","start":"2026-02-10T14:59:48.40358Z","end":"2026-02-10T14:59:48.529023Z","steps":["trace[653631616] 'agreement among raft nodes before linearized reading' (duration: 125.352157ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:48.529176Z","caller":"traceutil/trace.go:171","msg":"trace[395568512] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"218.331215ms","start":"2026-02-10T14:59:48.310832Z","end":"2026-02-10T14:59:48.529163Z","steps":["trace[395568512] 'process raft request' (duration: 217.336869ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:48.529525Z","caller":"traceutil/trace.go:171","msg":"trace[1999999257] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"218.693905ms","start":"2026-02-10T14:59:48.310817Z","end":"2026-02-10T14:59:48.529511Z","steps":["trace[1999999257] 'process raft request' (duration: 215.632214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:49.033704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.342077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T14:59:49.033801Z","caller":"traceutil/trace.go:171","msg":"trace[748477418] linearizableReadLoop","detail":"{readStateIndex:65; appliedIndex:64; }","duration":"494.951758ms","start":"2026-02-10T14:59:48.538834Z","end":"2026-02-10T14:59:49.033785Z","steps":["trace[748477418] 'read index received' (duration: 316.57298ms)","trace[748477418] 'applied index is now lower than readState.Index' (duration: 178.377748ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:49.033915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.076971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/system-leader-election\" ","response":"range_response_count:1 size:1228"} {"level":"info","ts":"2026-02-10T14:59:49.03394Z","caller":"traceutil/trace.go:171","msg":"trace[686401737] range","detail":"{range_begin:/registry/flowschemas/system-leader-election; range_end:; response_count:1; response_revision:60; }","duration":"495.153403ms","start":"2026-02-10T14:59:48.538779Z","end":"2026-02-10T14:59:49.033932Z","steps":["trace[686401737] 'agreement among raft nodes before linearized reading' (duration: 495.049291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:49.033979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:48.538756Z","time spent":"495.208414ms","remote":"127.0.0.1:56844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":1250,"request content":"key:\"/registry/flowschemas/system-leader-election\" "} {"level":"info","ts":"2026-02-10T14:59:49.034176Z","caller":"traceutil/trace.go:171","msg":"trace[1999996513] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"495.538403ms","start":"2026-02-10T14:59:48.538605Z","end":"2026-02-10T14:59:49.034143Z","steps":["trace[1999996513] 'process raft request' (duration: 316.695934ms)","trace[1999996513] 'compare' (duration: 178.223183ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:49.034223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:48.538592Z","time spent":"495.608414ms","remote":"127.0.0.1:56454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:49.127562Z","caller":"traceutil/trace.go:171","msg":"trace[1154347158] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"424.060489ms","start":"2026-02-10T14:59:48.70348Z","end":"2026-02-10T14:59:49.12754Z","steps":["trace[1154347158] 'process raft request' (duration: 424.004647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:49.127816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:48.703457Z","time spent":"424.284115ms","remote":"127.0.0.1:56578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5476,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:49.128155Z","caller":"traceutil/trace.go:171","msg":"trace[1874390785] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"589.21167ms","start":"2026-02-10T14:59:48.538932Z","end":"2026-02-10T14:59:49.128144Z","steps":["trace[1874390785] 'process raft request' (duration: 588.420909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:49.12822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:48.538913Z","time spent":"589.275031ms","remote":"127.0.0.1:56844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:49.128407Z","caller":"traceutil/trace.go:171","msg":"trace[220419213] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"587.756461ms","start":"2026-02-10T14:59:48.540639Z","end":"2026-02-10T14:59:49.128395Z","steps":["trace[220419213] 'process raft request' (duration: 586.811287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:49.128467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:48.540629Z","time spent":"587.808423ms","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":621,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:49.214531Z","caller":"traceutil/trace.go:171","msg":"trace[612922909] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"175.162162ms","start":"2026-02-10T14:59:49.039351Z","end":"2026-02-10T14:59:49.214513Z","steps":["trace[612922909] 'process raft request' (duration: 174.548876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:00.600165Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338634906333808,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:00:01.100788Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338634906333808,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:00:01.318276Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.441747273s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T15:00:01.318809Z","caller":"traceutil/trace.go:171","msg":"trace[676687797] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"1.442343349s","start":"2026-02-10T14:59:59.876435Z","end":"2026-02-10T15:00:01.318779Z","steps":["trace[676687797] 'process raft request' (duration: 1.442074881s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:01.319015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:59.876423Z","time spent":"1.442451121s","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:00:02.304431Z","caller":"traceutil/trace.go:171","msg":"trace[488726754] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:402; }","duration":"2.20447975s","start":"2026-02-10T15:00:00.099927Z","end":"2026-02-10T15:00:02.304407Z","steps":["trace[488726754] 'read index received' (duration: 1.219505084s)","trace[488726754] 'applied index is now lower than readState.Index' (duration: 984.973736ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:00:02.304708Z","caller":"traceutil/trace.go:171","msg":"trace[217473585] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"2.426541254s","start":"2026-02-10T14:59:59.878155Z","end":"2026-02-10T15:00:02.304697Z","steps":["trace[217473585] 'process raft request' (duration: 2.426137633s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:02.304823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:59.878146Z","time spent":"2.426593346s","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:00:02.305024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.205091586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-10T15:00:02.305077Z","caller":"traceutil/trace.go:171","msg":"trace[23772751] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:392; }","duration":"2.205143948s","start":"2026-02-10T15:00:00.099923Z","end":"2026-02-10T15:00:02.305067Z","steps":["trace[23772751] 'agreement among raft nodes before linearized reading' (duration: 2.205067916s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:02.305107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:00.09988Z","time spent":"2.205220441s","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T15:00:02.319124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.326245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:02.319178Z","caller":"traceutil/trace.go:171","msg":"trace[1689786418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:393; }","duration":"792.466387ms","start":"2026-02-10T15:00:01.526701Z","end":"2026-02-10T15:00:02.319167Z","steps":["trace[1689786418] 'agreement among raft nodes before linearized reading' (duration: 792.384805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:02.319457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:01.526682Z","time spent":"792.656805ms","remote":"127.0.0.1:56386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T15:00:02.319751Z","caller":"traceutil/trace.go:171","msg":"trace[754343009] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"289.035559ms","start":"2026-02-10T15:00:02.030708Z","end":"2026-02-10T15:00:02.319744Z","steps":["trace[754343009] 'process raft request' (duration: 288.215857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:02.319964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.83141995s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:02.31998Z","caller":"traceutil/trace.go:171","msg":"trace[1379940591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:393; }","duration":"1.831474181s","start":"2026-02-10T15:00:00.4885Z","end":"2026-02-10T15:00:02.319974Z","steps":["trace[1379940591] 'agreement among raft nodes before linearized reading' (duration: 1.831449201s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:02.319994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:00.488481Z","time spent":"1.831510412s","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T15:00:02.320916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.202172ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:02.32096Z","caller":"traceutil/trace.go:171","msg":"trace[1761448571] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:393; }","duration":"869.248773ms","start":"2026-02-10T15:00:01.451706Z","end":"2026-02-10T15:00:02.320955Z","steps":["trace[1761448571] 'agreement among raft nodes before linearized reading' (duration: 868.650217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:02.322001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.209909393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6897"} {"level":"info","ts":"2026-02-10T15:00:02.322024Z","caller":"traceutil/trace.go:171","msg":"trace[1720861202] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:393; }","duration":"2.209969686s","start":"2026-02-10T15:00:00.11205Z","end":"2026-02-10T15:00:02.322019Z","steps":["trace[1720861202] 'agreement among raft nodes before linearized reading' (duration: 2.207003208s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:02.322038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:00.112032Z","time spent":"2.210002676s","remote":"127.0.0.1:56578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6919,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-10T15:00:02.322133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.116233778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T15:00:02.322145Z","caller":"traceutil/trace.go:171","msg":"trace[1965527888] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:393; }","duration":"2.11631379s","start":"2026-02-10T15:00:00.205827Z","end":"2026-02-10T15:00:02.322141Z","steps":["trace[1965527888] 'agreement among raft nodes before linearized reading' (duration: 2.116288839s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:02.322156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:00.205809Z","time spent":"2.11634457s","remote":"127.0.0.1:56424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.156\" "} {"level":"info","ts":"2026-02-10T15:00:06.434004Z","caller":"traceutil/trace.go:171","msg":"trace[171998230] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"105.2552ms","start":"2026-02-10T15:00:06.328727Z","end":"2026-02-10T15:00:06.433982Z","steps":["trace[171998230] 'process raft request' (duration: 104.994323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:19.671823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.335152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:19.671967Z","caller":"traceutil/trace.go:171","msg":"trace[1407245857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"145.534096ms","start":"2026-02-10T15:00:19.526413Z","end":"2026-02-10T15:00:19.671947Z","steps":["trace[1407245857] 'range keys from in-memory index tree' (duration: 145.259259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:28.709391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.405445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T15:00:28.709467Z","caller":"traceutil/trace.go:171","msg":"trace[59867046] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"110.514938ms","start":"2026-02-10T15:00:28.598939Z","end":"2026-02-10T15:00:28.709454Z","steps":["trace[59867046] 'range keys from in-memory index tree' (duration: 110.327463ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:28.874324Z","caller":"traceutil/trace.go:171","msg":"trace[2106630814] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"158.736392ms","start":"2026-02-10T15:00:28.715567Z","end":"2026-02-10T15:00:28.874303Z","steps":["trace[2106630814] 'process raft request' (duration: 158.544297ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:28.876608Z","caller":"traceutil/trace.go:171","msg":"trace[1226110891] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:561; }","duration":"155.010555ms","start":"2026-02-10T15:00:28.721583Z","end":"2026-02-10T15:00:28.876593Z","steps":["trace[1226110891] 'read index received' (duration: 154.023699ms)","trace[1226110891] 'applied index is now lower than readState.Index' (duration: 986.196µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:00:28.876851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.250841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-10T15:00:28.876934Z","caller":"traceutil/trace.go:171","msg":"trace[1199716994] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:545; }","duration":"155.363244ms","start":"2026-02-10T15:00:28.721558Z","end":"2026-02-10T15:00:28.876921Z","steps":["trace[1199716994] 'agreement among raft nodes before linearized reading' (duration: 155.150468ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:28.877358Z","caller":"traceutil/trace.go:171","msg":"trace[1133841196] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"161.529956ms","start":"2026-02-10T15:00:28.715815Z","end":"2026-02-10T15:00:28.877345Z","steps":["trace[1133841196] 'process raft request' (duration: 160.648362ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:28.878285Z","caller":"traceutil/trace.go:171","msg":"trace[674883949] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"158.330741ms","start":"2026-02-10T15:00:28.719939Z","end":"2026-02-10T15:00:28.87827Z","steps":["trace[674883949] 'process raft request' (duration: 156.587455ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:28.882807Z","caller":"traceutil/trace.go:171","msg":"trace[1086427013] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"155.695992ms","start":"2026-02-10T15:00:28.72339Z","end":"2026-02-10T15:00:28.879086Z","steps":["trace[1086427013] 'process raft request' (duration: 153.173026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:29.975872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338634906334126,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T15:00:30.018873Z","caller":"traceutil/trace.go:171","msg":"trace[1938061887] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:564; }","duration":"543.630783ms","start":"2026-02-10T15:00:29.475205Z","end":"2026-02-10T15:00:30.018836Z","steps":["trace[1938061887] 'read index received' (duration: 543.291345ms)","trace[1938061887] 'applied index is now lower than readState.Index' (duration: 338.278µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:00:30.019113Z","caller":"traceutil/trace.go:171","msg":"trace[497381985] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"889.291306ms","start":"2026-02-10T15:00:29.129811Z","end":"2026-02-10T15:00:30.019102Z","steps":["trace[497381985] 'process raft request' (duration: 888.781353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:30.019201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:29.129786Z","time spent":"889.354948ms","remote":"127.0.0.1:56578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:00:30.019415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.206229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-10T15:00:30.019449Z","caller":"traceutil/trace.go:171","msg":"trace[886517713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:546; }","duration":"544.285271ms","start":"2026-02-10T15:00:29.475153Z","end":"2026-02-10T15:00:30.019438Z","steps":["trace[886517713] 'agreement among raft nodes before linearized reading' (duration: 544.182469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:30.019475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:29.475137Z","time spent":"544.332703ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T15:00:30.019573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.871622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:30.019618Z","caller":"traceutil/trace.go:171","msg":"trace[474919776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"492.952174ms","start":"2026-02-10T15:00:29.526652Z","end":"2026-02-10T15:00:30.019604Z","steps":["trace[474919776] 'agreement among raft nodes before linearized reading' (duration: 492.883803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:30.019649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:29.526635Z","time spent":"493.007146ms","remote":"127.0.0.1:56386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T15:00:30.18752Z","caller":"traceutil/trace.go:171","msg":"trace[998074475] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"158.65366ms","start":"2026-02-10T15:00:30.028846Z","end":"2026-02-10T15:00:30.1875Z","steps":["trace[998074475] 'process raft request' (duration: 97.27045ms)","trace[998074475] 'compare' (duration: 61.306478ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:00:31.256754Z","caller":"traceutil/trace.go:171","msg":"trace[1556269058] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"123.18717ms","start":"2026-02-10T15:00:31.133542Z","end":"2026-02-10T15:00:31.25673Z","steps":["trace[1556269058] 'process raft request' (duration: 122.918463ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:31.256996Z","caller":"traceutil/trace.go:171","msg":"trace[865937258] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"122.551563ms","start":"2026-02-10T15:00:31.134424Z","end":"2026-02-10T15:00:31.256975Z","steps":["trace[865937258] 'process raft request' (duration: 122.456619ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:32.536965Z","caller":"traceutil/trace.go:171","msg":"trace[1215599450] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"229.275742ms","start":"2026-02-10T15:00:32.307661Z","end":"2026-02-10T15:00:32.536937Z","steps":["trace[1215599450] 'read index received' (duration: 135.601516ms)","trace[1215599450] 'applied index is now lower than readState.Index' (duration: 93.672956ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:00:32.537742Z","caller":"traceutil/trace.go:171","msg":"trace[636466716] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"239.86347ms","start":"2026-02-10T15:00:32.297864Z","end":"2026-02-10T15:00:32.537728Z","steps":["trace[636466716] 'process raft request' (duration: 145.443214ms)","trace[636466716] 'compare' (duration: 93.472641ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:00:32.537507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.816676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:32.53802Z","caller":"traceutil/trace.go:171","msg":"trace[156170198] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:567; }","duration":"230.375331ms","start":"2026-02-10T15:00:32.307632Z","end":"2026-02-10T15:00:32.538008Z","steps":["trace[156170198] 'agreement among raft nodes before linearized reading' (duration: 229.740434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:32.548574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.954437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:32.548717Z","caller":"traceutil/trace.go:171","msg":"trace[1539603027] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"233.153523ms","start":"2026-02-10T15:00:32.315552Z","end":"2026-02-10T15:00:32.548705Z","steps":["trace[1539603027] 'agreement among raft nodes before linearized reading' (duration: 232.936187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:32.54894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.68981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:32.549081Z","caller":"traceutil/trace.go:171","msg":"trace[1651348355] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"206.842623ms","start":"2026-02-10T15:00:32.34223Z","end":"2026-02-10T15:00:32.549073Z","steps":["trace[1651348355] 'agreement among raft nodes before linearized reading' (duration: 206.681529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:32.549267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.11295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:32.549385Z","caller":"traceutil/trace.go:171","msg":"trace[1107837396] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"207.287866ms","start":"2026-02-10T15:00:32.342089Z","end":"2026-02-10T15:00:32.549377Z","steps":["trace[1107837396] 'agreement among raft nodes before linearized reading' (duration: 207.132931ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:32.549656Z","caller":"traceutil/trace.go:171","msg":"trace[1337305296] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"155.589249ms","start":"2026-02-10T15:00:32.394057Z","end":"2026-02-10T15:00:32.549647Z","steps":["trace[1337305296] 'process raft request' (duration: 154.344126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:32.549795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.49226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:32.550235Z","caller":"traceutil/trace.go:171","msg":"trace[1930922115] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"207.931023ms","start":"2026-02-10T15:00:32.342294Z","end":"2026-02-10T15:00:32.550226Z","steps":["trace[1930922115] 'agreement among raft nodes before linearized reading' (duration: 207.48334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:32.549864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.66725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:32.550415Z","caller":"traceutil/trace.go:171","msg":"trace[1416118979] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"225.233206ms","start":"2026-02-10T15:00:32.325173Z","end":"2026-02-10T15:00:32.550406Z","steps":["trace[1416118979] 'agreement among raft nodes before linearized reading' (duration: 224.649911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:32.549889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.581167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:32.550588Z","caller":"traceutil/trace.go:171","msg":"trace[746139777] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"225.312976ms","start":"2026-02-10T15:00:32.325265Z","end":"2026-02-10T15:00:32.550578Z","steps":["trace[746139777] 'agreement among raft nodes before linearized reading' (duration: 224.611928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:32.549968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.365794ms","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-02-10T15:00:32.550788Z","caller":"traceutil/trace.go:171","msg":"trace[710481741] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"235.193186ms","start":"2026-02-10T15:00:32.315587Z","end":"2026-02-10T15:00:32.55078Z","steps":["trace[710481741] 'agreement among raft nodes before linearized reading' (duration: 234.363714ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:43.22098Z","caller":"traceutil/trace.go:171","msg":"trace[1706020066] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"244.487291ms","start":"2026-02-10T15:00:42.976474Z","end":"2026-02-10T15:00:43.220961Z","steps":["trace[1706020066] 'process raft request' (duration: 244.350247ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:43.418923Z","caller":"traceutil/trace.go:171","msg":"trace[48748205] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"442.256569ms","start":"2026-02-10T15:00:42.97665Z","end":"2026-02-10T15:00:43.418907Z","steps":["trace[48748205] 'process raft request' (duration: 441.838588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:43.419042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:42.976632Z","time spent":"442.341921ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:00:43.419307Z","caller":"traceutil/trace.go:171","msg":"trace[1159783483] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"223.863734ms","start":"2026-02-10T15:00:43.195435Z","end":"2026-02-10T15:00:43.419299Z","steps":["trace[1159783483] 'process raft request' (duration: 223.814552ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:43.419372Z","caller":"traceutil/trace.go:171","msg":"trace[1841128163] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"441.843708ms","start":"2026-02-10T15:00:42.977522Z","end":"2026-02-10T15:00:43.419365Z","steps":["trace[1841128163] 'process raft request' (duration: 441.082228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:43.419427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:42.977502Z","time spent":"441.88272ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:00:48.774926Z","caller":"traceutil/trace.go:171","msg":"trace[1948131395] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"449.189336ms","start":"2026-02-10T15:00:48.325722Z","end":"2026-02-10T15:00:48.774911Z","steps":["trace[1948131395] 'process raft request' (duration: 448.714693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:48.775024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:48.325705Z","time spent":"449.264877ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:00:51.599303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.076894ms","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-02-10T15:00:51.599387Z","caller":"traceutil/trace.go:171","msg":"trace[1926427805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:743; }","duration":"145.172877ms","start":"2026-02-10T15:00:51.454193Z","end":"2026-02-10T15:00:51.599366Z","steps":["trace[1926427805] 'range keys from in-memory index tree' (duration: 144.996023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:51.600403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.235223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-10T15:00:51.600436Z","caller":"traceutil/trace.go:171","msg":"trace[604400134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"150.298783ms","start":"2026-02-10T15:00:51.450129Z","end":"2026-02-10T15:00:51.600428Z","steps":["trace[604400134] 'range keys from in-memory index tree' (duration: 148.383113ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:51.710267Z","caller":"traceutil/trace.go:171","msg":"trace[1363171271] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"106.934361ms","start":"2026-02-10T15:00:51.603317Z","end":"2026-02-10T15:00:51.710252Z","steps":["trace[1363171271] 'process raft request' (duration: 106.834018ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:51.715847Z","caller":"traceutil/trace.go:171","msg":"trace[1745050751] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"109.962572ms","start":"2026-02-10T15:00:51.60587Z","end":"2026-02-10T15:00:51.715833Z","steps":["trace[1745050751] 'process raft request' (duration: 109.874358ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:07.055591Z","caller":"traceutil/trace.go:171","msg":"trace[1092481706] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"142.772434ms","start":"2026-02-10T15:01:06.912799Z","end":"2026-02-10T15:01:07.055572Z","steps":["trace[1092481706] 'read index received' (duration: 142.6478ms)","trace[1092481706] 'applied index is now lower than readState.Index' (duration: 123.754µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:07.05574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.925198ms","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-02-10T15:01:07.055768Z","caller":"traceutil/trace.go:171","msg":"trace[1844713922] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"143.00023ms","start":"2026-02-10T15:01:06.912758Z","end":"2026-02-10T15:01:07.055758Z","steps":["trace[1844713922] 'agreement among raft nodes before linearized reading' (duration: 142.881557ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:07.055889Z","caller":"traceutil/trace.go:171","msg":"trace[1232955357] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"179.205822ms","start":"2026-02-10T15:01:06.876664Z","end":"2026-02-10T15:01:07.055869Z","steps":["trace[1232955357] 'process raft request' (duration: 178.79991ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:10.451485Z","caller":"traceutil/trace.go:171","msg":"trace[1737094358] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"241.694662ms","start":"2026-02-10T15:01:10.209766Z","end":"2026-02-10T15:01:10.45146Z","steps":["trace[1737094358] 'read index received' (duration: 241.401275ms)","trace[1737094358] 'applied index is now lower than readState.Index' (duration: 292.477µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:10.45188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.020721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T15:01:10.451959Z","caller":"traceutil/trace.go:171","msg":"trace[1308986242] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:802; }","duration":"242.215546ms","start":"2026-02-10T15:01:10.209728Z","end":"2026-02-10T15:01:10.451944Z","steps":["trace[1308986242] 'agreement among raft nodes before linearized reading' (duration: 241.843426ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:10.452068Z","caller":"traceutil/trace.go:171","msg":"trace[741052631] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"522.325767ms","start":"2026-02-10T15:01:09.929715Z","end":"2026-02-10T15:01:10.45204Z","steps":["trace[741052631] 'process raft request' (duration: 521.500156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:10.452278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:09.929698Z","time spent":"522.484272ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:01:10.846709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.819076ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-02-10T15:01:10.847018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:10.455231Z","time spent":"391.783119ms","remote":"127.0.0.1:56424","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-10T15:01:11.123893Z","caller":"traceutil/trace.go:171","msg":"trace[1424074515] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"274.71926ms","start":"2026-02-10T15:01:10.849155Z","end":"2026-02-10T15:01:11.123874Z","steps":["trace[1424074515] 'process raft request' (duration: 203.652632ms)","trace[1424074515] 'compare' (duration: 70.911463ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:23.527129Z","caller":"traceutil/trace.go:171","msg":"trace[897844430] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"208.272674ms","start":"2026-02-10T15:01:23.318845Z","end":"2026-02-10T15:01:23.527118Z","steps":["trace[897844430] 'process raft request' (duration: 207.063521ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:28.683419Z","caller":"traceutil/trace.go:171","msg":"trace[681584663] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"135.336396ms","start":"2026-02-10T15:01:28.548067Z","end":"2026-02-10T15:01:28.683403Z","steps":["trace[681584663] 'process raft request' (duration: 135.189872ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:29.442457Z","caller":"traceutil/trace.go:171","msg":"trace[1876420829] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"100.661244ms","start":"2026-02-10T15:01:29.341771Z","end":"2026-02-10T15:01:29.442433Z","steps":["trace[1876420829] 'process raft request' (duration: 100.539191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:29.720661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.480796ms","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-02-10T15:01:29.720748Z","caller":"traceutil/trace.go:171","msg":"trace[1435308693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:905; }","duration":"174.618129ms","start":"2026-02-10T15:01:29.54611Z","end":"2026-02-10T15:01:29.720728Z","steps":["trace[1435308693] 'range keys from in-memory index tree' (duration: 174.241759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:29.721109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.232856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T15:01:29.721196Z","caller":"traceutil/trace.go:171","msg":"trace[1997264732] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:905; }","duration":"105.615067ms","start":"2026-02-10T15:01:29.615562Z","end":"2026-02-10T15:01:29.721177Z","steps":["trace[1997264732] 'range keys from in-memory index tree' (duration: 105.078422ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:36.888661Z","caller":"traceutil/trace.go:171","msg":"trace[1923704382] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"170.138261ms","start":"2026-02-10T15:01:36.718505Z","end":"2026-02-10T15:01:36.888643Z","steps":["trace[1923704382] 'process raft request' (duration: 170.010417ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:41.633495Z","caller":"traceutil/trace.go:171","msg":"trace[1129054468] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"106.985803ms","start":"2026-02-10T15:01:41.526486Z","end":"2026-02-10T15:01:41.633472Z","steps":["trace[1129054468] 'read index received' (duration: 106.805419ms)","trace[1129054468] 'applied index is now lower than readState.Index' (duration: 179.334µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:41.633619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.109396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:41.633649Z","caller":"traceutil/trace.go:171","msg":"trace[1149107923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"107.181408ms","start":"2026-02-10T15:01:41.526457Z","end":"2026-02-10T15:01:41.633638Z","steps":["trace[1149107923] 'agreement among raft nodes before linearized reading' (duration: 107.113137ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:41.633716Z","caller":"traceutil/trace.go:171","msg":"trace[1607027172] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"132.898202ms","start":"2026-02-10T15:01:41.500797Z","end":"2026-02-10T15:01:41.633695Z","steps":["trace[1607027172] 'process raft request' (duration: 132.522842ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:42.051709Z","caller":"traceutil/trace.go:171","msg":"trace[1775558156] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"247.6326ms","start":"2026-02-10T15:01:41.803985Z","end":"2026-02-10T15:01:42.051618Z","steps":["trace[1775558156] 'process raft request' (duration: 245.862003ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:58.50734Z","caller":"traceutil/trace.go:171","msg":"trace[99397747] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"393.251547ms","start":"2026-02-10T15:01:58.114065Z","end":"2026-02-10T15:01:58.507317Z","steps":["trace[99397747] 'process raft request' (duration: 393.129434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:58.507464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:58.114053Z","time spent":"393.35281ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:01:58.507869Z","caller":"traceutil/trace.go:171","msg":"trace[396148345] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1075; }","duration":"279.309326ms","start":"2026-02-10T15:01:58.228547Z","end":"2026-02-10T15:01:58.507857Z","steps":["trace[396148345] 'read index received' (duration: 279.306226ms)","trace[396148345] 'applied index is now lower than readState.Index' (duration: 2.62µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:58.507961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.408739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-10T15:01:58.50798Z","caller":"traceutil/trace.go:171","msg":"trace[1286521810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1031; }","duration":"279.46827ms","start":"2026-02-10T15:01:58.228506Z","end":"2026-02-10T15:01:58.507974Z","steps":["trace[1286521810] 'agreement among raft nodes before linearized reading' (duration: 279.383168ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:58.648487Z","caller":"traceutil/trace.go:171","msg":"trace[509630608] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"409.885853ms","start":"2026-02-10T15:01:58.238584Z","end":"2026-02-10T15:01:58.648469Z","steps":["trace[509630608] 'process raft request' (duration: 409.461811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:58.649702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:58.238568Z","time spent":"409.978465ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:01:58.650707Z","caller":"traceutil/trace.go:171","msg":"trace[1940084542] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1075; }","duration":"124.309802ms","start":"2026-02-10T15:01:58.526208Z","end":"2026-02-10T15:01:58.650518Z","steps":["trace[1940084542] 'read index received' (duration: 121.919608ms)","trace[1940084542] 'applied index is now lower than readState.Index' (duration: 2.389554ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:58.656944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.72099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:58.657029Z","caller":"traceutil/trace.go:171","msg":"trace[2062520220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1033; }","duration":"130.801341ms","start":"2026-02-10T15:01:58.526178Z","end":"2026-02-10T15:01:58.656979Z","steps":["trace[2062520220] 'agreement among raft nodes before linearized reading' (duration: 124.580219ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:58.657664Z","caller":"traceutil/trace.go:171","msg":"trace[800734898] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"145.951418ms","start":"2026-02-10T15:01:58.511705Z","end":"2026-02-10T15:01:58.657656Z","steps":["trace[800734898] 'process raft request' (duration: 138.73433ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:30.687319Z","caller":"traceutil/trace.go:171","msg":"trace[1729935435] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"701.129387ms","start":"2026-02-10T15:03:29.986167Z","end":"2026-02-10T15:03:30.687296Z","steps":["trace[1729935435] 'process raft request' (duration: 700.968553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:30.688644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:29.986154Z","time spent":"702.412552ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}