{"level":"warn","ts":"2026-05-06T01:11:16.903581Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T01:11:16.9038Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-05-06T01:11:16.903884Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T01:11:16.903894Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-05-06T01:11:16.903913Z","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-05-06T01:11:16.904464Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-05-06T01:11:16.90455Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-05-06T01:11:16.909741Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.978125ms"} {"level":"info","ts":"2026-05-06T01:11:16.919319Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-05-06T01:11:16.919399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T01:11:16.91942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-05-06T01:11:16.919439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T01:11:16.919453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-05-06T01:11:16.91949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-05-06T01:11:16.925825Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T01:11:16.926999Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T01:11:16.928037Z","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-05-06T01:11:16.930983Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T01:11:16.931886Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T01:11:16.932108Z","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-05-06T01:11:16.932158Z","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-05-06T01:11:16.932168Z","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-05-06T01:11:16.937045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-05-06T01:11:16.938048Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-05-06T01:11:16.93838Z","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-05-06T01:11:16.938571Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-05-06T01:11:16.938601Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-05-06T01:11:16.938614Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T01:11:16.938654Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T01:11:17.019903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T01:11:17.01996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T01:11:17.019994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-05-06T01:11:17.02001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-05-06T01:11:17.020017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-05-06T01:11:17.020029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-05-06T01:11:17.020039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-05-06T01:11:17.021232Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:11:17.022015Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T01:11:17.022033Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T01:11:17.022109Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T01:11:17.022428Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:11:17.022539Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:11:17.022576Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:11:17.022728Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T01:11:17.022837Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T01:11:17.0271Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T01:11:17.028117Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"warn","ts":"2026-05-06T01:11:19.28189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.429872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T01:11:19.281998Z","caller":"traceutil/trace.go:171","msg":"trace[1886490170] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"520.615884ms","start":"2026-05-06T01:11:18.761363Z","end":"2026-05-06T01:11:19.281979Z","steps":["trace[1886490170] 'range keys from in-memory index tree' (duration: 520.348641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:19.282048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.761347Z","time spent":"520.692166ms","remote":"127.0.0.1:55044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-06T01:11:19.282074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.299678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T01:11:19.282143Z","caller":"traceutil/trace.go:171","msg":"trace[1808056045] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:0; response_revision:3; }","duration":"512.41171ms","start":"2026-05-06T01:11:18.769719Z","end":"2026-05-06T01:11:19.282131Z","steps":["trace[1808056045] 'range keys from in-memory index tree' (duration: 512.185397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:19.282177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.769692Z","time spent":"512.476511ms","remote":"127.0.0.1:54936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-05-06T01:11:19.282342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.12306ms","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":"warn","ts":"2026-05-06T01:11:19.282343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.294083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T01:11:19.282383Z","caller":"traceutil/trace.go:171","msg":"trace[1296972793] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:3; }","duration":"510.175241ms","start":"2026-05-06T01:11:18.772192Z","end":"2026-05-06T01:11:19.282367Z","steps":["trace[1296972793] 'range keys from in-memory index tree' (duration: 509.744496ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:19.282404Z","caller":"traceutil/trace.go:171","msg":"trace[1559814025] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"521.387814ms","start":"2026-05-06T01:11:18.761003Z","end":"2026-05-06T01:11:19.282391Z","steps":["trace[1559814025] 'range keys from in-memory index tree' (duration: 521.217733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:19.28245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.76099Z","time spent":"521.444635ms","remote":"127.0.0.1:55044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-06T01:11:19.282467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.571973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-06T01:11:19.282412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.772176Z","time spent":"510.228922ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-05-06T01:11:19.282574Z","caller":"traceutil/trace.go:171","msg":"trace[2029783892] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"509.713595ms","start":"2026-05-06T01:11:18.77284Z","end":"2026-05-06T01:11:19.282553Z","steps":["trace[2029783892] 'range keys from in-memory index tree' (duration: 509.505203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:19.282625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.772831Z","time spent":"509.784546ms","remote":"127.0.0.1:55044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-06T01:11:19.282619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.353578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T01:11:19.282693Z","caller":"traceutil/trace.go:171","msg":"trace[1254098483] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:3; }","duration":"558.460379ms","start":"2026-05-06T01:11:18.724213Z","end":"2026-05-06T01:11:19.282673Z","steps":["trace[1254098483] 'range keys from in-memory index tree' (duration: 558.285497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:19.282743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.724195Z","time spent":"558.537811ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":27,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-06T01:11:19.28254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.583146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T01:11:19.28296Z","caller":"traceutil/trace.go:171","msg":"trace[2071329933] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"536.043292ms","start":"2026-05-06T01:11:18.746847Z","end":"2026-05-06T01:11:19.28289Z","steps":["trace[2071329933] 'range keys from in-memory index tree' (duration: 535.342953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:19.283064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.746835Z","time spent":"536.213564ms","remote":"127.0.0.1:55044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-06T01:11:19.283301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.011156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-06T01:11:19.283373Z","caller":"traceutil/trace.go:171","msg":"trace[1883739573] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"509.242379ms","start":"2026-05-06T01:11:18.774118Z","end":"2026-05-06T01:11:19.28336Z","steps":["trace[1883739573] 'process raft request' (duration: 320.707097ms)","trace[1883739573] 'compare' (duration: 186.936893ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:11:19.283428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.774101Z","time spent":"509.30473ms","remote":"127.0.0.1:55368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:11:19.507805Z","caller":"traceutil/trace.go:171","msg":"trace[1103698239] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"729.349474ms","start":"2026-05-06T01:11:18.778428Z","end":"2026-05-06T01:11:19.507777Z","steps":["trace[1103698239] 'process raft request' (duration: 726.956543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:19.507956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.77841Z","time spent":"729.475776ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:11:19.507941Z","caller":"traceutil/trace.go:171","msg":"trace[439245137] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"729.350454ms","start":"2026-05-06T01:11:18.778557Z","end":"2026-05-06T01:11:19.507907Z","steps":["trace[439245137] 'process raft request' (duration: 729.168372ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:19.508006Z","caller":"traceutil/trace.go:171","msg":"trace[1832507146] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:6; }","duration":"684.324108ms","start":"2026-05-06T01:11:18.823665Z","end":"2026-05-06T01:11:19.507989Z","steps":["trace[1832507146] 'read index received' (duration: 271.173252ms)","trace[1832507146] 'applied index is now lower than readState.Index' (duration: 413.149686ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:11:19.508029Z","caller":"traceutil/trace.go:171","msg":"trace[1369045369] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"729.356365ms","start":"2026-05-06T01:11:18.778611Z","end":"2026-05-06T01:11:19.507967Z","steps":["trace[1369045369] 'process raft request' (duration: 729.152692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:19.508107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.778551Z","time spent":"729.465595ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:11:19.50816Z","caller":"traceutil/trace.go:171","msg":"trace[1367066599] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"718.853ms","start":"2026-05-06T01:11:18.7893Z","end":"2026-05-06T01:11:19.508153Z","steps":["trace[1367066599] 'process raft request' (duration: 718.657208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:19.508226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.789281Z","time spent":"718.90785ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:11:19.508282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.778601Z","time spent":"729.555127ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:11:19.508132Z","caller":"traceutil/trace.go:171","msg":"trace[1825772109] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"728.120659ms","start":"2026-05-06T01:11:18.780003Z","end":"2026-05-06T01:11:19.508124Z","steps":["trace[1825772109] 'process raft request' (duration: 727.897226ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:19.508348Z","caller":"traceutil/trace.go:171","msg":"trace[186910905] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"725.959551ms","start":"2026-05-06T01:11:18.782377Z","end":"2026-05-06T01:11:19.508337Z","steps":["trace[186910905] 'process raft request' (duration: 725.548836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:19.508416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.782368Z","time spent":"726.019002ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3157,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:11:19.508441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.779989Z","time spent":"728.402123ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:11:19.508095Z","caller":"traceutil/trace.go:171","msg":"trace[1165025495] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"729.435575ms","start":"2026-05-06T01:11:18.77865Z","end":"2026-05-06T01:11:19.508086Z","steps":["trace[1165025495] 'process raft request' (duration: 729.203302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:19.508597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.778645Z","time spent":"729.933812ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:11:19.508675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.984317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T01:11:19.508839Z","caller":"traceutil/trace.go:171","msg":"trace[454529919] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"685.187049ms","start":"2026-05-06T01:11:18.823631Z","end":"2026-05-06T01:11:19.508818Z","steps":["trace[454529919] 'agreement among raft nodes before linearized reading' (duration: 684.662402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:19.509185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:18.823619Z","time spent":"685.550784ms","remote":"127.0.0.1:55044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-06T01:11:19.512558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.470369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T01:11:19.51262Z","caller":"traceutil/trace.go:171","msg":"trace[679589297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13; }","duration":"227.564041ms","start":"2026-05-06T01:11:19.28504Z","end":"2026-05-06T01:11:19.512604Z","steps":["trace[679589297] 'agreement among raft nodes before linearized reading' (duration: 227.390019ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:19.512813Z","caller":"traceutil/trace.go:171","msg":"trace[1812119452] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"227.47642ms","start":"2026-05-06T01:11:19.285326Z","end":"2026-05-06T01:11:19.512803Z","steps":["trace[1812119452] 'process raft request' (duration: 226.963013ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:19.512936Z","caller":"traceutil/trace.go:171","msg":"trace[339057602] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"227.356998ms","start":"2026-05-06T01:11:19.285571Z","end":"2026-05-06T01:11:19.512928Z","steps":["trace[339057602] 'process raft request' (duration: 226.784461ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:19.513257Z","caller":"traceutil/trace.go:171","msg":"trace[1227771231] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"225.92388ms","start":"2026-05-06T01:11:19.287316Z","end":"2026-05-06T01:11:19.51324Z","steps":["trace[1227771231] 'process raft request' (duration: 225.062109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:19.513397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.669214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T01:11:19.513447Z","caller":"traceutil/trace.go:171","msg":"trace[379887681] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:13; }","duration":"174.735165ms","start":"2026-05-06T01:11:19.338695Z","end":"2026-05-06T01:11:19.51343Z","steps":["trace[379887681] 'agreement among raft nodes before linearized reading' (duration: 174.669825ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:44.979648Z","caller":"traceutil/trace.go:171","msg":"trace[572411461] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"123.706305ms","start":"2026-05-06T01:11:44.855916Z","end":"2026-05-06T01:11:44.979623Z","steps":["trace[572411461] 'process raft request' (duration: 116.679897ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:44.980679Z","caller":"traceutil/trace.go:171","msg":"trace[629700736] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"122.216966ms","start":"2026-05-06T01:11:44.858436Z","end":"2026-05-06T01:11:44.980653Z","steps":["trace[629700736] 'process raft request' (duration: 121.136773ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:44.981749Z","caller":"traceutil/trace.go:171","msg":"trace[1565562603] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"122.501961ms","start":"2026-05-06T01:11:44.859233Z","end":"2026-05-06T01:11:44.981735Z","steps":["trace[1565562603] 'process raft request' (duration: 121.039002ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:50.817177Z","caller":"traceutil/trace.go:171","msg":"trace[470593683] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"149.644773ms","start":"2026-05-06T01:11:50.667509Z","end":"2026-05-06T01:11:50.817154Z","steps":["trace[470593683] 'process raft request' (duration: 148.997286ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:51.183325Z","caller":"traceutil/trace.go:171","msg":"trace[483026641] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"301.068046ms","start":"2026-05-06T01:11:50.882228Z","end":"2026-05-06T01:11:51.183296Z","steps":["trace[483026641] 'process raft request' (duration: 300.785093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:51.183482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:50.882202Z","time spent":"301.197328ms","remote":"127.0.0.1:55414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:11:51.860696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.537503ms","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-05-06T01:11:51.860773Z","caller":"traceutil/trace.go:171","msg":"trace[2034578770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"388.646284ms","start":"2026-05-06T01:11:51.47211Z","end":"2026-05-06T01:11:51.860756Z","steps":["trace[2034578770] 'range keys from in-memory index tree' (duration: 388.461502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:51.860995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:51.472099Z","time spent":"388.888007ms","remote":"127.0.0.1:55180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T01:11:51.861234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.89237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:11:51.86138Z","caller":"traceutil/trace.go:171","msg":"trace[1499352757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"546.049393ms","start":"2026-05-06T01:11:51.315307Z","end":"2026-05-06T01:11:51.861356Z","steps":["trace[1499352757] 'range keys from in-memory index tree' (duration: 545.805989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:51.861463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:51.315271Z","time spent":"546.179134ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T01:11:51.861323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.262946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T01:11:51.861564Z","caller":"traceutil/trace.go:171","msg":"trace[184206115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"460.58505ms","start":"2026-05-06T01:11:51.400961Z","end":"2026-05-06T01:11:51.861546Z","steps":["trace[184206115] 'range keys from in-memory index tree' (duration: 460.197715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:51.86127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:51.279448Z","time spent":"581.819447ms","remote":"127.0.0.1:60186","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-06T01:11:51.86161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:51.400951Z","time spent":"460.646141ms","remote":"127.0.0.1:55180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T01:11:51.864018Z","caller":"traceutil/trace.go:171","msg":"trace[1649240926] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"288.601841ms","start":"2026-05-06T01:11:51.575374Z","end":"2026-05-06T01:11:51.863976Z","steps":["trace[1649240926] 'process raft request' (duration: 287.402696ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:13:56.66838Z","caller":"traceutil/trace.go:171","msg":"trace[2111977106] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"172.959307ms","start":"2026-05-06T01:13:56.495398Z","end":"2026-05-06T01:13:56.668358Z","steps":["trace[2111977106] 'process raft request' (duration: 172.836114ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:13:56.672506Z","caller":"traceutil/trace.go:171","msg":"trace[257926066] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1324; }","duration":"171.897142ms","start":"2026-05-06T01:13:56.500591Z","end":"2026-05-06T01:13:56.672488Z","steps":["trace[257926066] 'read index received' (duration: 167.589371ms)","trace[257926066] 'applied index is now lower than readState.Index' (duration: 4.307191ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:13:56.672575Z","caller":"traceutil/trace.go:171","msg":"trace[350421898] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"175.23886ms","start":"2026-05-06T01:13:56.497299Z","end":"2026-05-06T01:13:56.672538Z","steps":["trace[350421898] 'process raft request' (duration: 175.065816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:13:56.672666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.066777ms","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-05-06T01:13:56.672692Z","caller":"traceutil/trace.go:171","msg":"trace[1087805702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1274; }","duration":"172.155799ms","start":"2026-05-06T01:13:56.500529Z","end":"2026-05-06T01:13:56.672685Z","steps":["trace[1087805702] 'agreement among raft nodes before linearized reading' (duration: 172.029686ms)"],"step_count":1}