(base) HwHiAiUser@davinci-mini:~/study/Softplus/AclNNInvocation/output$ msprof op simulator execute_op 
2026-03-23 16:11:56 [INFO]  Op profiling analysis start.
2026-03-23 16:11:56 [INFO]  Running simulation task: Binary Simulation Running, use simulator in LD_LIBRARY_PATH
[INFO] Config file is found, path is /usr/local/Ascend/ascend-toolkit/8.2.RC2/aarch64-linux/simulator/Ascend310B1/lib/config_stars.json.
[TmSim]: Run in serial mode, HW concurrency is:4, actual thread num is: 1

[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 0: chip_id 0 die_id 0, dev_core_id  0, subcore  0 of  2 (CUBE), spr COREID 0x0000 (0)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 0: chip_id 0 die_id 0, dev_core_id  0, subcore  1 of  2  (VEC), spr COREID 0x0018 (24)
[INFO] AicWrapper attach AIC 0, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 1: chip_id 0 die_id 0, dev_core_id  1, subcore  0 of  2 (CUBE), spr COREID 0x0001 (1)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 1: chip_id 0 die_id 0, dev_core_id  1, subcore  1 of  2  (VEC), spr COREID 0x0019 (25)
[INFO] AicWrapper attach AIC 1, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 2: chip_id 0 die_id 0, dev_core_id  2, subcore  0 of  2 (CUBE), spr COREID 0x0002 (2)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 2: chip_id 0 die_id 0, dev_core_id  2, subcore  1 of  2  (VEC), spr COREID 0x001A (26)
[INFO] AicWrapper attach AIC 2, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 3: chip_id 0 die_id 0, dev_core_id  3, subcore  0 of  2 (CUBE), spr COREID 0x0003 (3)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 3: chip_id 0 die_id 0, dev_core_id  3, subcore  1 of  2  (VEC), spr COREID 0x001B (27)
[INFO] AicWrapper attach AIC 3, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 4: chip_id 0 die_id 0, dev_core_id  4, subcore  0 of  2 (CUBE), spr COREID 0x0004 (4)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 4: chip_id 0 die_id 0, dev_core_id  4, subcore  1 of  2  (VEC), spr COREID 0x001C (28)
[INFO] AicWrapper attach AIC 4, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 5: chip_id 0 die_id 0, dev_core_id  5, subcore  0 of  2 (CUBE), spr COREID 0x0005 (5)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 5: chip_id 0 die_id 0, dev_core_id  5, subcore  1 of  2  (VEC), spr COREID 0x001D (29)
[INFO] AicWrapper attach AIC 5, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 6: chip_id 0 die_id 0, dev_core_id  6, subcore  0 of  2 (CUBE), spr COREID 0x0006 (6)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 6: chip_id 0 die_id 0, dev_core_id  6, subcore  1 of  2  (VEC), spr COREID 0x001E (30)
[INFO] AicWrapper attach AIC 6, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 7: chip_id 0 die_id 0, dev_core_id  7, subcore  0 of  2 (CUBE), spr COREID 0x0007 (7)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 7: chip_id 0 die_id 0, dev_core_id  7, subcore  1 of  2  (VEC), spr COREID 0x001F (31)
[INFO] AicWrapper attach AIC 7, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 8: chip_id 0 die_id 0, dev_core_id  8, subcore  0 of  2 (CUBE), spr COREID 0x0008 (8)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 8: chip_id 0 die_id 0, dev_core_id  8, subcore  1 of  2  (VEC), spr COREID 0x0020 (32)
[INFO] AicWrapper attach AIC 8, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 9: chip_id 0 die_id 0, dev_core_id  9, subcore  0 of  2 (CUBE), spr COREID 0x0009 (9)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 9: chip_id 0 die_id 0, dev_core_id  9, subcore  1 of  2  (VEC), spr COREID 0x0021 (33)
[INFO] AicWrapper attach AIC 9, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 10: chip_id 0 die_id 0, dev_core_id 10, subcore  0 of  2 (CUBE), spr COREID 0x000A (10)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 10: chip_id 0 die_id 0, dev_core_id 10, subcore  1 of  2  (VEC), spr COREID 0x0022 (34)
[INFO] AicWrapper attach AIC 10, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 11: chip_id 0 die_id 0, dev_core_id 11, subcore  0 of  2 (CUBE), spr COREID 0x000B (11)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 11: chip_id 0 die_id 0, dev_core_id 11, subcore  1 of  2  (VEC), spr COREID 0x0023 (35)
[INFO] AicWrapper attach AIC 11, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 12: chip_id 0 die_id 0, dev_core_id 12, subcore  0 of  2 (CUBE), spr COREID 0x000C (12)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 12: chip_id 0 die_id 0, dev_core_id 12, subcore  1 of  2  (VEC), spr COREID 0x0024 (36)
[INFO] AicWrapper attach AIC 12, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 13: chip_id 0 die_id 0, dev_core_id 13, subcore  0 of  2 (CUBE), spr COREID 0x000D (13)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 13: chip_id 0 die_id 0, dev_core_id 13, subcore  1 of  2  (VEC), spr COREID 0x0025 (37)
[INFO] AicWrapper attach AIC 13, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 14: chip_id 0 die_id 0, dev_core_id 14, subcore  0 of  2 (CUBE), spr COREID 0x000E (14)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 14: chip_id 0 die_id 0, dev_core_id 14, subcore  1 of  2  (VEC), spr COREID 0x0026 (38)
[INFO] AicWrapper attach AIC 14, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 15: chip_id 0 die_id 0, dev_core_id 15, subcore  0 of  2 (CUBE), spr COREID 0x000F (15)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 15: chip_id 0 die_id 0, dev_core_id 15, subcore  1 of  2  (VEC), spr COREID 0x0027 (39)
[INFO] AicWrapper attach AIC 15, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 16: chip_id 0 die_id 0, dev_core_id 16, subcore  0 of  2 (CUBE), spr COREID 0x0010 (16)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 16: chip_id 0 die_id 0, dev_core_id 16, subcore  1 of  2  (VEC), spr COREID 0x0028 (40)
[INFO] AicWrapper attach AIC 16, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 17: chip_id 0 die_id 0, dev_core_id 17, subcore  0 of  2 (CUBE), spr COREID 0x0011 (17)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 17: chip_id 0 die_id 0, dev_core_id 17, subcore  1 of  2  (VEC), spr COREID 0x0029 (41)
[INFO] AicWrapper attach AIC 17, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 18: chip_id 0 die_id 0, dev_core_id 18, subcore  0 of  2 (CUBE), spr COREID 0x0012 (18)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 18: chip_id 0 die_id 0, dev_core_id 18, subcore  1 of  2  (VEC), spr COREID 0x002A (42)
[INFO] AicWrapper attach AIC 18, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 19: chip_id 0 die_id 0, dev_core_id 19, subcore  0 of  2 (CUBE), spr COREID 0x0013 (19)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 19: chip_id 0 die_id 0, dev_core_id 19, subcore  1 of  2  (VEC), spr COREID 0x002B (43)
[INFO] AicWrapper attach AIC 19, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 20: chip_id 0 die_id 0, dev_core_id 20, subcore  0 of  2 (CUBE), spr COREID 0x0014 (20)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 20: chip_id 0 die_id 0, dev_core_id 20, subcore  1 of  2  (VEC), spr COREID 0x002C (44)
[INFO] AicWrapper attach AIC 20, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 21: chip_id 0 die_id 0, dev_core_id 21, subcore  0 of  2 (CUBE), spr COREID 0x0015 (21)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 21: chip_id 0 die_id 0, dev_core_id 21, subcore  1 of  2  (VEC), spr COREID 0x002D (45)
[INFO] AicWrapper attach AIC 21, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 22: chip_id 0 die_id 0, dev_core_id 22, subcore  0 of  2 (CUBE), spr COREID 0x0016 (22)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 22: chip_id 0 die_id 0, dev_core_id 22, subcore  1 of  2  (VEC), spr COREID 0x002E (46)
[INFO] AicWrapper attach AIC 22, num_vec_core=2, num_subcore=3
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 23: chip_id 0 die_id 0, dev_core_id 23, subcore  0 of  2 (CUBE), spr COREID 0x0017 (23)
[PEM_AIC_LOG] [DEBUG] [0000000000] init_core_spr (196)::     Core 23: chip_id 0 die_id 0, dev_core_id 23, subcore  1 of  2  (VEC), spr COREID 0x002F (47)
[INFO] AicWrapper attach AIC 23, num_vec_core=2, num_subcore=3
[INFO] Chip 0 AIC / Scheduler / Soc periods: 200.0000 / 200.0000 / 105.0000
[INFO] chip 0 die 0 device created
================================================================================
>>>>                                                                        
>>>>                             " PEM MODEL "                                
>>>>             Total no. of 1 chip(s) Model Init Success!                
>>>>                                                                        
================================================================================
[INFO] Model Start Time: 2026-03-23 16:12:00
[DRVSTUB_LOG] driver_api.c:539 sendSwapBuf:swapbuf_base_addr:10000000
[DRVSTUB_LOG] driver_api.c:540 sendSwapBuf:sq:0 swapbuf_addr:10000000
[INFO]  Set device[0] success
[INFO]  Get RunMode[1] success
[INFO]  Init resource success
Read beta from file: 1.000000, threshold: 20.000000
[INFO]  Set input success
[INFO]  Copy input[0] success
[DRVSTUB_LOG] driver_api.c:539 sendSwapBuf:swapbuf_base_addr:10000000
[DRVSTUB_LOG] driver_api.c:540 sendSwapBuf:sq:1 swapbuf_addr:10000040
[INFO]  Create stream success
[INFO]  Execute GetWorkspaceSize success, workspace size 0
[INFO] <ProfInit> Start profiling on kernel: Softplus_a43f2b44ad350a73e19bdedd9cdc18a5_0
[PEM_AIC_LOG] [INFO] [0000000988] kickstart (293):: Core 0 subcore 0 start pc 0x10d69000
[PEM_AIC_LOG] [INFO] [0000009500] aic_done_callback (78):: Core 0 subcore 0 early_end 0 done
[INFO] pem.cc:604 proc_end_pem_ostd_num_is_one @9500 Main CCU executing `END' instr.
[DRVSTUB_LOG] driver_api.c:2196 send_stars_interrupt:get cq_0 base_addr: 10020000
Runtime: 11.0922s
[INFO]  Synchronize stream success
[INFO]  Copy output[0] success
[INFO]  Write output success
[INFO]  Run op success
[INFO] Model Stop Time: 2026-03-23 16:12:12
Model RUN TIME: 11833.1 ms
[INFO] Total tick: 9578
[INFO] Model stopped successfully.
[INFO]  Reset Device success
[INFO]  Destory resource success
2026-03-23 16:12:16 [INFO]  Profiling running finished. All task success.
2026-03-23 16:12:16 [INFO]  Start parse dump file
2026-03-23 16:12:16 [ERROR] thread pool thread number should greater than 0
2026-03-23 16:12:16 [ERROR] thread pool thread number should greater than 0