2024-08-13 14:39:09,551 INFO [train_multi_KD3.py:1187] (1/4) Training started
2024-08-13 14:39:09,551 INFO [train_multi_KD3.py:1197] (1/4) Device: cuda:1
2024-08-13 14:39:09,553 INFO [train_multi_KD3.py:1212] (1/4) Using dtype=torch.bfloat16
2024-08-13 14:39:09,553 INFO [train_multi_KD3.py:1214] (1/4) {'best_train_loss': inf, 'best_valid_loss': inf, 'best_train_epoch': -1, 'best_valid_epoch': -1, 'batch_idx_train': 0, 'log_interval': 50, 'reset_interval': 200, 'valid_interval': 3000, 'feature_dim': 80, 'subsampling_factor': 4, 'warm_step': 2000, 'env_info': {'k2-version': '1.24.3', 'k2-build-type': 'Release', 'k2-with-cuda': True, 'k2-git-sha1': 'e400fa3b456faf8afe0ee5bfe572946b4921a3db', 'k2-git-date': 'Sat Jul 15 04:21:50 2023', 'lhotse-version': '1.16.0', 'torch-version': '2.0.1+cu117', 'torch-cuda-available': True, 'torch-cuda-version': '11.7', 'python-version': '3.9', 'icefall-git-branch': 'multi_KD_with_wenet', 'icefall-git-sha1': 'a6c2f7a4-dirty', 'icefall-git-date': 'Thu Aug 8 16:21:21 2024', 'icefall-path': '/xy/mnt/yangxiaoyu/workspace/icefall_multi_KD', 'k2-path': '/root/anaconda3/lib/python3.9/site-packages/k2/__init__.py', 'lhotse-path': '/root/anaconda3/lib/python3.9/site-packages/lhotse/__init__.py', 'hostname': 'NGK_xiaoyu'}, 'world_size': 4, 'master_port': 13440, 'tensorboard': True, 'num_epochs': 35, 'start_epoch': 16, 'start_batch': 0, 'exp_dir': PosixPath('multi_KD/exp_causal1_delta6KD_LS1_5fold+wenetspech0_0fold+as_unbalanced1+vox_1_vox2_base_lr_0.045_use_beats_1_scale_1.0_use_ecapa_1_layer_2_scale_10.0_1_scale_1.0_specaug0_musan0_with_task_ID_stop_early1_share_asr1_md1500_amp_bf16'), 'bpe_model': 'data/lang_bpe_500/bpe.model', 'base_lr': 0.045, 'lr_batches': 7500, 'lr_epochs': 3.5, 'ref_duration': 600, 'seed': 42, 'print_diagnostics': False, 'inf_check': False, 'save_every_n': 4000, 'keep_last_k': 30, 'average_period': 200, 'stop_early': True, 'use_fp16': False, 'use_bf16': True, 'share_asr': True, 'beats_loss_scale': 1.0, 'ecapa_loss_scale': 10.0, 'whisper_loss_scale': 1.0, 'whisper_cb_loss_scale': 0.01, 'repeat_librispeech': 5, 'repeat_wenetspeech': 0, 'num_encoder_layers': '2,2,3,4,3,2', 'downsampling_factor': '1,2,4,8,4,2', 'feedforward_dim': '512,768,1024,1536,1024,768', 'num_heads': '4,4,4,8,4,4', 'encoder_dim': '192,256,384,512,384,256', 'query_head_dim': '32', 'value_head_dim': '12', 'pos_head_dim': '4', 'pos_dim': 48, 'encoder_unmasked_dim': '192,192,256,256,256,192', 'cnn_module_kernel': '31,31,15,15,15,31', 'decoder_dim': 512, 'joiner_dim': 512, 'causal': True, 'chunk_size': '16,32,64,-1', 'left_context_frames': '64,128,256,-1', 'use_transducer': True, 'use_ctc': False, 'speaker_input_idx': 2, 'whisper_dim': 1280, 'use_task_id': True, 'num_codebooks': 32, 'mvq_kd_layer_idx': -1, 'use_subsampled_output': True, 'delta_t': 6, 'full_libri': True, 'mini_libri': False, 'use_libriheavy': False, 'libriheavy_subset': 'small', 'use_librispeech': True, 'use_wenetspeech': False, 'use_audioset': True, 'audioset_subset': 'unbalanced', 'use_voxceleb': True, 'voxceleb_subset': 'vox2', 'use_fma': False, 'fma_subset': 'large', 'manifest_dir': PosixPath('data/fbank_LSVoxAs_with_whisper_large-v3_with_taskID'), 'max_duration': 1500, 'bucketing_sampler': False, 'num_buckets': 30, 'concatenate_cuts': False, 'duration_factor': 1.0, 'gap': 1.0, 'on_the_fly_feats': False, 'shuffle': True, 'drop_last': True, 'return_cuts': True, 'num_workers': 1, 'enable_spec_aug': False, 'spec_aug_time_warp_factor': 80, 'enable_musan': False, 'enable_audioset': False, 'use_musan_separately': False, 'input_strategy': 'PrecomputedFeatures', 'drop_features': False, 'return_audio': False, 'use_beats': True, 'use_ecapa': True, 'use_whisper': True, 'whisper_mvq': False, 'beats_ckpt': 'data/models/BEATs/BEATs_iter3_plus_AS2M_finetuned_on_AS2M_cpt2.pt', 'whisper_version': 'large-v3', 'use_mert': False, 'blank_id': 0, 'vocab_size': 500, 'dtype': torch.bfloat16, 'use_amp': True}
2024-08-13 14:39:09,553 INFO [train_multi_KD3.py:1216] (1/4) About to create model
2024-08-13 14:39:09,918 INFO [model_shift.py:142] (1/4) Delta_t: 6 when computing the distillation loss
2024-08-13 14:39:09,922 INFO [train_multi_KD3.py:1220] (1/4) Number of model parameters: 66484678
2024-08-13 14:39:09,923 INFO [checkpoint.py:112] (1/4) Loading checkpoint from multi_KD/exp_causal1_delta6KD_LS1_5fold+wenetspech0_0fold+as_unbalanced1+vox_1_vox2_base_lr_0.045_use_beats_1_scale_1.0_use_ecapa_1_layer_2_scale_10.0_1_scale_1.0_specaug0_musan0_with_task_ID_stop_early1_share_asr1_md1500_amp_bf16/epoch-15.pt
2024-08-13 14:39:17,807 INFO [train_multi_KD3.py:1235] (1/4) Using DDP
2024-08-13 14:39:19,594 INFO [train_multi_KD3.py:1247] (1/4) Loading optimizer state dict
2024-08-13 14:39:20,014 INFO [train_multi_KD3.py:1255] (1/4) Loading scheduler state dict
2024-08-13 14:39:20,014 INFO [kd_datamodule.py:690] (1/4) About to get train 960 cuts
2024-08-13 14:39:20,120 INFO [train_multi_KD3.py:1306] (1/4) Getting audioset cuts
2024-08-13 14:39:20,120 INFO [kd_datamodule.py:900] (1/4) About to get the audioset cuts for KD.
2024-08-13 14:39:20,142 INFO [kd_datamodule.py:869] (1/4) About to get the voxceleb cuts.
2024-08-13 14:39:20,154 INFO [kd_datamodule.py:880] (1/4) Adding voxceleb2 cuts.
2024-08-13 14:39:20,166 INFO [train_multi_KD3.py:1320] (1/4) Using mux to combine Librispeech: True, WenetSpeech: False, audioset: True and voxceleb: True
2024-08-13 14:39:29,055 INFO [train_multi_KD3.py:1322] (1/4) Using mux to combine [CutSet(len=1406195) [underlying data type: <class 'lhotse.lazy.LazyRepeater'>], CutSet(len=1904746) [underlying data type: <class 'lhotse.lazy.LazyIteratorChain'>], CutSet(len=1187704) [underlying data type: <class 'lhotse.lazy.LazyIteratorChain'>]]
2024-08-13 14:39:29,056 INFO [train_multi_KD3.py:1323] (1/4) Using weights: [1406195, 1904746, 1187704]
2024-08-13 14:39:29,056 INFO [train_multi_KD3.py:1332] (1/4) CutSet(len=4498645) [underlying data type: <class 'lhotse.lazy.LazyIteratorMultiplexer'>]
2024-08-13 14:39:29,056 INFO [kd_datamodule.py:449] (1/4) Disable MUSAN
2024-08-13 14:39:29,056 INFO [kd_datamodule.py:489] (1/4) Disable SpecAugment
2024-08-13 14:39:29,057 INFO [kd_datamodule.py:491] (1/4) About to create train dataset
2024-08-13 14:39:29,059 INFO [kd_datamodule.py:528] (1/4) Using SimpleCutSampler
2024-08-13 14:39:29,060 INFO [kd_datamodule.py:536] (1/4) About to create train dataloader
2024-08-13 14:39:29,060 INFO [kd_datamodule.py:763] (1/4) About to get dev-clean cuts
2024-08-13 14:39:29,065 INFO [kd_datamodule.py:781] (1/4) About to get dev-other cuts
2024-08-13 14:39:29,068 INFO [kd_datamodule.py:570] (1/4) About to create dev dataset
2024-08-13 14:39:29,365 INFO [kd_datamodule.py:591] (1/4) About to create dev dataloader
2024-08-13 14:39:29,365 INFO [kd_datamodule.py:840] (1/4) About to get the test set of voxceleb1 set.