{"level":"warn","ts":"2026-04-07T00:05:49.388627Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:05:49.388849Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T00:05:49.389091Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:05:49.389119Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-07T00:05:49.389148Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T00:05:49.390177Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-04-07T00:05:49.390328Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T00:05:49.395124Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.473874ms"} {"level":"info","ts":"2026-04-07T00:05:49.401809Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-04-07T00:05:49.401884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:05:49.401936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-04-07T00:05:49.401952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:05:49.401962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-04-07T00:05:49.402022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-04-07T00:05:49.40773Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:05:49.409243Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:05:49.410587Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T00:05:49.413705Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:05:49.413851Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:05:49.413975Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:05:49.413996Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:05:49.413924Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:05:49.417884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-04-07T00:05:49.418053Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-07T00:05:49.42061Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T00:05:49.420713Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-07T00:05:49.420792Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-07T00:05:49.420976Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:05:49.421009Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:05:50.103378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:05:50.103436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:05:50.103516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-04-07T00:05:50.103543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:05:50.103555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-07T00:05:50.103605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-04-07T00:05:50.103645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-07T00:05:50.104837Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:05:50.105754Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:05:50.10592Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:05:50.10595Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:05:50.106258Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:05:50.106385Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:05:50.106427Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:05:50.109044Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-04-07T00:05:50.109276Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:05:50.109328Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:05:50.10958Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:06:08.520993Z","caller":"traceutil/trace.go:171","msg":"trace[2064513126] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"121.66644ms","start":"2026-04-07T00:06:08.399303Z","end":"2026-04-07T00:06:08.520969Z","steps":["trace[2064513126] 'process raft request' (duration: 121.486216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:08.808573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.591596ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T00:06:23.681628Z","caller":"traceutil/trace.go:171","msg":"trace[698124247] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"162.746589ms","start":"2026-04-07T00:06:23.518852Z","end":"2026-04-07T00:06:23.681599Z","steps":["trace[698124247] 'process raft request' (duration: 162.632026ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:23.689653Z","caller":"traceutil/trace.go:171","msg":"trace[231332750] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"157.652311ms","start":"2026-04-07T00:06:23.531965Z","end":"2026-04-07T00:06:23.689617Z","steps":["trace[231332750] 'process raft request' (duration: 155.711996ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:23.690637Z","caller":"traceutil/trace.go:171","msg":"trace[1895541739] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"162.325229ms","start":"2026-04-07T00:06:23.528213Z","end":"2026-04-07T00:06:23.690538Z","steps":["trace[1895541739] 'process raft request' (duration: 159.300979ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:23.691072Z","caller":"traceutil/trace.go:171","msg":"trace[852027750] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"161.882449ms","start":"2026-04-07T00:06:23.52898Z","end":"2026-04-07T00:06:23.690862Z","steps":["trace[852027750] 'process raft request' (duration: 158.648314ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:23.691084Z","caller":"traceutil/trace.go:171","msg":"trace[528060797] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"157.595009ms","start":"2026-04-07T00:06:23.533339Z","end":"2026-04-07T00:06:23.690934Z","steps":["trace[528060797] 'process raft request' (duration: 154.403575ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:23.691446Z","caller":"traceutil/trace.go:171","msg":"trace[1614905331] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"157.879196ms","start":"2026-04-07T00:06:23.533299Z","end":"2026-04-07T00:06:23.691178Z","steps":["trace[1614905331] 'process raft request' (duration: 154.414826ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:23.691494Z","caller":"traceutil/trace.go:171","msg":"trace[548765346] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"157.001556ms","start":"2026-04-07T00:06:23.534469Z","end":"2026-04-07T00:06:23.691471Z","steps":["trace[548765346] 'process raft request' (duration: 153.3099ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:23.691695Z","caller":"traceutil/trace.go:171","msg":"trace[805454770] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"153.194128ms","start":"2026-04-07T00:06:23.537978Z","end":"2026-04-07T00:06:23.691172Z","steps":["trace[805454770] 'process raft request' (duration: 150.361462ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:23.691946Z","caller":"traceutil/trace.go:171","msg":"trace[29858736] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"154.812525ms","start":"2026-04-07T00:06:23.537115Z","end":"2026-04-07T00:06:23.691928Z","steps":["trace[29858736] 'process raft request' (duration: 150.69815ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:23.697677Z","caller":"traceutil/trace.go:171","msg":"trace[289557023] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"121.281521ms","start":"2026-04-07T00:06:23.576383Z","end":"2026-04-07T00:06:23.697664Z","steps":["trace[289557023] 'process raft request' (duration: 121.071316ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:23.698003Z","caller":"traceutil/trace.go:171","msg":"trace[671370592] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:490; }","duration":"107.331739ms","start":"2026-04-07T00:06:23.590646Z","end":"2026-04-07T00:06:23.697977Z","steps":["trace[671370592] 'read index received' (duration: 90.985982ms)","trace[671370592] 'applied index is now lower than readState.Index' (duration: 16.344887ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:06:23.698115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.463512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T00:06:23.69814Z","caller":"traceutil/trace.go:171","msg":"trace[239796412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:486; }","duration":"107.524474ms","start":"2026-04-07T00:06:23.590608Z","end":"2026-04-07T00:06:23.698132Z","steps":["trace[239796412] 'agreement among raft nodes before linearized reading' (duration: 107.445052ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:23.698343Z","caller":"traceutil/trace.go:171","msg":"trace[158523921] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"134.403324ms","start":"2026-04-07T00:06:23.557839Z","end":"2026-04-07T00:06:23.692243Z","steps":["trace[158523921] 'process raft request' (duration: 133.920942ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:25.890605Z","caller":"traceutil/trace.go:171","msg":"trace[179605346] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"160.62396ms","start":"2026-04-07T00:06:25.729965Z","end":"2026-04-07T00:06:25.890589Z","steps":["trace[179605346] 'process raft request' (duration: 160.524087ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:30.130559Z","caller":"traceutil/trace.go:171","msg":"trace[657512235] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"123.453701ms","start":"2026-04-07T00:06:30.007069Z","end":"2026-04-07T00:06:30.130522Z","steps":["trace[657512235] 'process raft request' (duration: 111.739831ms)","trace[657512235] 'compare' (duration: 11.595488ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:30.352153Z","caller":"traceutil/trace.go:171","msg":"trace[360849269] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"214.37622ms","start":"2026-04-07T00:06:30.137759Z","end":"2026-04-07T00:06:30.352135Z","steps":["trace[360849269] 'process raft request' (duration: 212.587699ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:30.833823Z","caller":"traceutil/trace.go:171","msg":"trace[814397621] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"186.355594ms","start":"2026-04-07T00:06:30.647444Z","end":"2026-04-07T00:06:30.833799Z","steps":["trace[814397621] 'read index received' (duration: 186.222271ms)","trace[814397621] 'applied index is now lower than readState.Index' (duration: 132.173µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:30.833864Z","caller":"traceutil/trace.go:171","msg":"trace[1224317040] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"416.347876ms","start":"2026-04-07T00:06:30.417488Z","end":"2026-04-07T00:06:30.833836Z","steps":["trace[1224317040] 'process raft request' (duration: 416.177422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:30.834228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.755243ms","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-04-07T00:06:30.834331Z","caller":"traceutil/trace.go:171","msg":"trace[1804724897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"186.870546ms","start":"2026-04-07T00:06:30.647403Z","end":"2026-04-07T00:06:30.834274Z","steps":["trace[1804724897] 'agreement among raft nodes before linearized reading' (duration: 186.515998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:30.83495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:30.417463Z","time spent":"416.465789ms","remote":"127.0.0.1:43324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:06:31.028452Z","caller":"traceutil/trace.go:171","msg":"trace[1087675162] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"184.130222ms","start":"2026-04-07T00:06:30.8443Z","end":"2026-04-07T00:06:31.02843Z","steps":["trace[1087675162] 'read index received' (duration: 153.132946ms)","trace[1087675162] 'applied index is now lower than readState.Index' (duration: 30.996406ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:31.028519Z","caller":"traceutil/trace.go:171","msg":"trace[982570934] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"185.450023ms","start":"2026-04-07T00:06:30.84305Z","end":"2026-04-07T00:06:31.0285Z","steps":["trace[982570934] 'process raft request' (duration: 154.436326ms)","trace[982570934] 'compare' (duration: 30.790951ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:06:31.028578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.259686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-07T00:06:31.028609Z","caller":"traceutil/trace.go:171","msg":"trace[72088894] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:588; }","duration":"184.318207ms","start":"2026-04-07T00:06:30.844282Z","end":"2026-04-07T00:06:31.028601Z","steps":["trace[72088894] 'agreement among raft nodes before linearized reading' (duration: 184.241335ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:33.284504Z","caller":"traceutil/trace.go:171","msg":"trace[262543488] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"106.572631ms","start":"2026-04-07T00:06:33.177883Z","end":"2026-04-07T00:06:33.284455Z","steps":["trace[262543488] 'process raft request' (duration: 106.347336ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:48.229743Z","caller":"traceutil/trace.go:171","msg":"trace[1093989872] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"133.400676ms","start":"2026-04-07T00:06:48.096318Z","end":"2026-04-07T00:06:48.229719Z","steps":["trace[1093989872] 'process raft request' (duration: 132.69432ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:48.439454Z","caller":"traceutil/trace.go:171","msg":"trace[850350153] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"205.711918ms","start":"2026-04-07T00:06:48.233713Z","end":"2026-04-07T00:06:48.439425Z","steps":["trace[850350153] 'process raft request' (duration: 188.911309ms)","trace[850350153] 'compare' (duration: 16.541773ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:48.4395Z","caller":"traceutil/trace.go:171","msg":"trace[311868909] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"205.733439ms","start":"2026-04-07T00:06:48.233727Z","end":"2026-04-07T00:06:48.43946Z","steps":["trace[311868909] 'process raft request' (duration: 205.625666ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:43.308432Z","caller":"traceutil/trace.go:171","msg":"trace[2098544363] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"170.442723ms","start":"2026-04-07T00:07:43.137954Z","end":"2026-04-07T00:07:43.308397Z","steps":["trace[2098544363] 'process raft request' (duration: 152.771144ms)","trace[2098544363] 'compare' (duration: 17.510595ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:00.077036Z","caller":"traceutil/trace.go:171","msg":"trace[1833237829] linearizableReadLoop","detail":"{readStateIndex:1186; appliedIndex:1185; }","duration":"272.121635ms","start":"2026-04-07T00:07:59.804889Z","end":"2026-04-07T00:08:00.07701Z","steps":["trace[1833237829] 'read index received' (duration: 271.930511ms)","trace[1833237829] 'applied index is now lower than readState.Index' (duration: 190.114µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:00.077198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.29781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:00.077432Z","caller":"traceutil/trace.go:171","msg":"trace[1737266061] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"466.91892ms","start":"2026-04-07T00:07:59.610484Z","end":"2026-04-07T00:08:00.077403Z","steps":["trace[1737266061] 'process raft request' (duration: 466.360637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:00.077742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:59.610458Z","time spent":"467.040354ms","remote":"127.0.0.1:43356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:08:00.077994Z","caller":"traceutil/trace.go:171","msg":"trace[800298688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1141; }","duration":"272.389832ms","start":"2026-04-07T00:07:59.804831Z","end":"2026-04-07T00:08:00.077221Z","steps":["trace[800298688] 'agreement among raft nodes before linearized reading' (duration: 272.28959ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:15.098177Z","caller":"traceutil/trace.go:171","msg":"trace[985092831] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"286.701255ms","start":"2026-04-07T00:08:14.811455Z","end":"2026-04-07T00:08:15.098156Z","steps":["trace[985092831] 'read index received' (duration: 286.530601ms)","trace[985092831] 'applied index is now lower than readState.Index' (duration: 169.854µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:15.098479Z","caller":"traceutil/trace.go:171","msg":"trace[1513370634] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"312.793062ms","start":"2026-04-07T00:08:14.785674Z","end":"2026-04-07T00:08:15.098467Z","steps":["trace[1513370634] 'process raft request' (duration: 312.370063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:15.098571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:14.785655Z","time spent":"312.852313ms","remote":"127.0.0.1:43356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:08:15.098752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.298349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:15.098781Z","caller":"traceutil/trace.go:171","msg":"trace[153369940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1204; }","duration":"287.360601ms","start":"2026-04-07T00:08:14.811411Z","end":"2026-04-07T00:08:15.098772Z","steps":["trace[153369940] 'agreement among raft nodes before linearized reading' (duration: 287.314439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:15.099037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.043016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5329"} {"level":"info","ts":"2026-04-07T00:08:15.099067Z","caller":"traceutil/trace.go:171","msg":"trace[397238721] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1204; }","duration":"282.090717ms","start":"2026-04-07T00:08:14.816966Z","end":"2026-04-07T00:08:15.099057Z","steps":["trace[397238721] 'agreement among raft nodes before linearized reading' (duration: 281.966504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:15.099327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.719696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T00:08:15.099354Z","caller":"traceutil/trace.go:171","msg":"trace[538059412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1204; }","duration":"204.758407ms","start":"2026-04-07T00:08:14.894587Z","end":"2026-04-07T00:08:15.099346Z","steps":["trace[538059412] 'agreement among raft nodes before linearized reading' (duration: 204.678455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:15.099508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.229717ms","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-04-07T00:08:15.099535Z","caller":"traceutil/trace.go:171","msg":"trace[778940579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1204; }","duration":"205.287189ms","start":"2026-04-07T00:08:14.894241Z","end":"2026-04-07T00:08:15.099528Z","steps":["trace[778940579] 'agreement among raft nodes before linearized reading' (duration: 205.218467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:15.099682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.631556ms","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-04-07T00:08:15.099721Z","caller":"traceutil/trace.go:171","msg":"trace[2008434291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1204; }","duration":"229.711098ms","start":"2026-04-07T00:08:14.869989Z","end":"2026-04-07T00:08:15.099701Z","steps":["trace[2008434291] 'agreement among raft nodes before linearized reading' (duration: 229.641816ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:15.269273Z","caller":"traceutil/trace.go:171","msg":"trace[403568834] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"165.469312ms","start":"2026-04-07T00:08:15.103778Z","end":"2026-04-07T00:08:15.269247Z","steps":["trace[403568834] 'process raft request' (duration: 165.35154ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:15.337672Z","caller":"traceutil/trace.go:171","msg":"trace[468606945] linearizableReadLoop","detail":"{readStateIndex:1256; appliedIndex:1252; }","duration":"198.195184ms","start":"2026-04-07T00:08:15.139456Z","end":"2026-04-07T00:08:15.337651Z","steps":["trace[468606945] 'read index received' (duration: 129.684729ms)","trace[468606945] 'applied index is now lower than readState.Index' (duration: 68.509575ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:15.33768Z","caller":"traceutil/trace.go:171","msg":"trace[1362268544] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"233.784382ms","start":"2026-04-07T00:08:15.103841Z","end":"2026-04-07T00:08:15.337625Z","steps":["trace[1362268544] 'process raft request' (duration: 233.596448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:15.337854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.383129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-5svd9\" ","response":"range_response_count:1 size:11053"} {"level":"info","ts":"2026-04-07T00:08:15.337898Z","caller":"traceutil/trace.go:171","msg":"trace[110452705] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-5svd9; range_end:; response_count:1; response_revision:1208; }","duration":"198.491211ms","start":"2026-04-07T00:08:15.139397Z","end":"2026-04-07T00:08:15.337888Z","steps":["trace[110452705] 'agreement among raft nodes before linearized reading' (duration: 198.339828ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:15.337938Z","caller":"traceutil/trace.go:171","msg":"trace[462366935] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"231.929919ms","start":"2026-04-07T00:08:15.105991Z","end":"2026-04-07T00:08:15.337921Z","steps":["trace[462366935] 'process raft request' (duration: 231.559641ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:15.338105Z","caller":"traceutil/trace.go:171","msg":"trace[793544853] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"231.710374ms","start":"2026-04-07T00:08:15.106382Z","end":"2026-04-07T00:08:15.338092Z","steps":["trace[793544853] 'process raft request' (duration: 231.213413ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:15.544123Z","caller":"traceutil/trace.go:171","msg":"trace[1690266573] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"145.033097ms","start":"2026-04-07T00:08:15.399054Z","end":"2026-04-07T00:08:15.544087Z","steps":["trace[1690266573] 'process raft request' (duration: 90.672971ms)","trace[1690266573] 'compare' (duration: 54.212823ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:16.145916Z","caller":"traceutil/trace.go:171","msg":"trace[1521052191] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1261; }","duration":"156.288999ms","start":"2026-04-07T00:08:15.989609Z","end":"2026-04-07T00:08:16.145898Z","steps":["trace[1521052191] 'read index received' (duration: 156.177056ms)","trace[1521052191] 'applied index is now lower than readState.Index' (duration: 111.323µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:16.145936Z","caller":"traceutil/trace.go:171","msg":"trace[716477000] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"219.616103ms","start":"2026-04-07T00:08:15.9263Z","end":"2026-04-07T00:08:16.145917Z","steps":["trace[716477000] 'process raft request' (duration: 219.498281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:16.146045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.405282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:16.146078Z","caller":"traceutil/trace.go:171","msg":"trace[1486540962] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1214; }","duration":"156.502224ms","start":"2026-04-07T00:08:15.989567Z","end":"2026-04-07T00:08:16.146069Z","steps":["trace[1486540962] 'agreement among raft nodes before linearized reading' (duration: 156.405292ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:16.61081Z","caller":"traceutil/trace.go:171","msg":"trace[304348613] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"131.073231ms","start":"2026-04-07T00:08:16.479709Z","end":"2026-04-07T00:08:16.610783Z","steps":["trace[304348613] 'process raft request' (duration: 71.043393ms)","trace[304348613] 'compare' (duration: 59.817143ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:16.92669Z","caller":"traceutil/trace.go:171","msg":"trace[1435302125] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"196.259739ms","start":"2026-04-07T00:08:16.730408Z","end":"2026-04-07T00:08:16.926667Z","steps":["trace[1435302125] 'read index received' (duration: 164.038539ms)","trace[1435302125] 'applied index is now lower than readState.Index' (duration: 32.22029ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:16.927187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.208665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:16.927228Z","caller":"traceutil/trace.go:171","msg":"trace[1959179232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1222; }","duration":"122.269337ms","start":"2026-04-07T00:08:16.804946Z","end":"2026-04-07T00:08:16.927215Z","steps":["trace[1959179232] 'agreement among raft nodes before linearized reading' (duration: 122.220925ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:16.926858Z","caller":"traceutil/trace.go:171","msg":"trace[1195886917] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"306.264531ms","start":"2026-04-07T00:08:16.620565Z","end":"2026-04-07T00:08:16.92683Z","steps":["trace[1195886917] 'process raft request' (duration: 273.855246ms)","trace[1195886917] 'compare' (duration: 31.730859ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:16.927467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:16.620543Z","time spent":"306.805913ms","remote":"127.0.0.1:43356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:08:16.927008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.590287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T00:08:16.927603Z","caller":"traceutil/trace.go:171","msg":"trace[1762671211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1222; }","duration":"197.216921ms","start":"2026-04-07T00:08:16.730359Z","end":"2026-04-07T00:08:16.927575Z","steps":["trace[1762671211] 'agreement among raft nodes before linearized reading' (duration: 196.521465ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:29.343788Z","caller":"traceutil/trace.go:171","msg":"trace[465474702] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1356; }","duration":"194.960269ms","start":"2026-04-07T00:08:29.148807Z","end":"2026-04-07T00:08:29.343767Z","steps":["trace[465474702] 'read index received' (duration: 194.860886ms)","trace[465474702] 'applied index is now lower than readState.Index' (duration: 98.493µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:29.343831Z","caller":"traceutil/trace.go:171","msg":"trace[276596655] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"305.40546ms","start":"2026-04-07T00:08:29.038395Z","end":"2026-04-07T00:08:29.3438Z","steps":["trace[276596655] 'process raft request' (duration: 305.244637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:29.344048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:29.03837Z","time spent":"305.592374ms","remote":"127.0.0.1:43356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:08:29.344051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.230505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-07T00:08:29.344121Z","caller":"traceutil/trace.go:171","msg":"trace[1934490680] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1306; }","duration":"195.387818ms","start":"2026-04-07T00:08:29.148714Z","end":"2026-04-07T00:08:29.344101Z","steps":["trace[1934490680] 'agreement among raft nodes before linearized reading' (duration: 195.163403ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:43.128966Z","caller":"traceutil/trace.go:171","msg":"trace[771869969] linearizableReadLoop","detail":"{readStateIndex:2315; appliedIndex:2314; }","duration":"108.657933ms","start":"2026-04-07T00:11:43.020287Z","end":"2026-04-07T00:11:43.128945Z","steps":["trace[771869969] 'read index received' (duration: 108.56709ms)","trace[771869969] 'applied index is now lower than readState.Index' (duration: 89.573µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:43.129095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.784365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:11:43.129127Z","caller":"traceutil/trace.go:171","msg":"trace[1361206794] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2224; }","duration":"108.858208ms","start":"2026-04-07T00:11:43.02026Z","end":"2026-04-07T00:11:43.129118Z","steps":["trace[1361206794] 'agreement among raft nodes before linearized reading' (duration: 108.779716ms)"],"step_count":1}