Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.04.2022 07:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16370064 kB
MemAvailable 31569864 kB
Buffers 0 kB
Cached 15363876 kB
SwapCached 2344 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-11 07:37:13
DTS_END                         2022-04-11 07:37:13.1310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44302
TRN_ID                          219746
ATT_ID                          636
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9165.99 21548 1099919
customer order: creation 168.76 454 20251
customer order: refuse 14.92 379 1790
order to supplier: creation 45.04 1439 5405
order to supplier: removal 3.65 156346 438
invoice (draft): creation 44.66 3023 5359
invoice (draft): removal 52.87 993 6344
invoice accept: apply 40.83 25901 4900
invoice accept: cancel 47.28 1145 5674
customer reserve: creation 3861.05 245 463326
customer reserve: removal 4601.26 97 552151
realization accept: apply 81.01 269 9721
realization accept: cancel 76.89 166 9227
payment from customer: creation 58.53 171 7024
payment from customer: removal 6.00 107 720
payment to supplier: creation 47.45 179 5694
payment to supplier: removal 3.32 121 398
service: total inventory turnovers 4.03 3794 484
service: total monetary turnovers 7.45 350 894
service: aggregate perf. data 0.96 13358 115
service: refresh index statistics 0.03 222430 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-04-2022 07:39:00 to 11-04-2022 07:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7961
TEST_TIME 2 8347
TEST_TIME 3 7440
TEST_TIME 4 6987
TEST_TIME 5 6358
TEST_TIME 6 6118
TEST_TIME 7 6298
TEST_TIME 8 6463
TEST_TIME 9 6493
TEST_TIME 10 6277
TEST_TIME 11 6539
TEST_TIME 12 6666
TEST_TIME 13 7009
TEST_TIME 14 7127
TEST_TIME 15 7140
TEST_TIME 16 7277
TEST_TIME 17 7483
TEST_TIME 18 7535
TEST_TIME 19 7767
TEST_TIME 20 7942
TEST_TIME 21 8135
TEST_TIME 22 8207
TEST_TIME 23 8358
TEST_TIME 24 8388
TEST_TIME 25 8598
TEST_TIME 26 8501
TEST_TIME 27 9036
TEST_TIME 28 9480
TEST_TIME 29 9844
TEST_TIME 30 10702
TEST_TIME 31 10905
TEST_TIME 32 11129
TEST_TIME 33 11171
TEST_TIME 34 11015
TEST_TIME 35 11038
TEST_TIME 36 10960
TEST_TIME 37 10871
TEST_TIME 38 10802
TEST_TIME 39 10649
TEST_TIME 40 10672
TEST_TIME 41 10540
TEST_TIME 42 10639
TEST_TIME 43 10522
TEST_TIME 44 10580
TEST_TIME 45 10452
TEST_TIME 46 10375
TEST_TIME 47 10315
TEST_TIME 48 10193
TEST_TIME 49 10214
TEST_TIME 50 10093
TEST_TIME 51 10011
TEST_TIME 52 9956
TEST_TIME 53 9911
TEST_TIME 54 9856
TEST_TIME 55 9961
TEST_TIME 56 9917
TEST_TIME 57 9872
TEST_TIME 58 9841
TEST_TIME 59 9697
TEST_TIME 60 9699
TEST_TIME 61 9593
TEST_TIME 62 9702
TEST_TIME 63 9687
TEST_TIME 64 9640
TEST_TIME 65 9723
TEST_TIME 66 9713
TEST_TIME 67 9665
TEST_TIME 68 9693
TEST_TIME 69 9696
TEST_TIME 70 9827
TEST_TIME 71 9790
TEST_TIME 72 9840
TEST_TIME 73 9841
TEST_TIME 74 9762
TEST_TIME 75 9666
TEST_TIME 76 9669
TEST_TIME 77 9620
TEST_TIME 78 9567
TEST_TIME 79 9520
TEST_TIME 80 9455
TEST_TIME 81 9399
TEST_TIME 82 9364
TEST_TIME 83 9394
TEST_TIME 84 9444
TEST_TIME 85 9407
TEST_TIME 86 9463
TEST_TIME 87 9457
TEST_TIME 88 9418
TEST_TIME 89 9491
TEST_TIME 90 9584
TEST_TIME 91 9585
TEST_TIME 92 9551
TEST_TIME 93 9617
TEST_TIME 94 9572
TEST_TIME 95 9481
TEST_TIME 96 9418
TEST_TIME 97 9433
TEST_TIME 98 9398
TEST_TIME 99 9329
TEST_TIME 100 9293
TEST_TIME 101 9314
TEST_TIME 102 9297
TEST_TIME 103 9279
TEST_TIME 104 9276
TEST_TIME 105 9226
TEST_TIME 106 9238
TEST_TIME 107 9214
TEST_TIME 108 9246
TEST_TIME 109 9275
TEST_TIME 110 9257
TEST_TIME 111 9271
TEST_TIME 112 9327
TEST_TIME 113 9302
TEST_TIME 114 9290
TEST_TIME 115 9261
TEST_TIME 116 9281
TEST_TIME 117 9228
TEST_TIME 118 9214
TEST_TIME 119 9252
TEST_TIME 120 9180

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-04-2022 07:39:03 to 11-04-2022 07:39:07.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1525552 1525552 0 0.00 0 19623 14 0 0
doc_list_biud 1525542 1525542 0 0.00 0 57654 41 0 0
sp_add_invoice_to_stock 5007 4900 107 2.13 55 270198 25901 0 107
sp_cancel_adding_invoice 5674 5674 0 0.00 6 102274 1146 0 0
sp_cancel_client_order 1790 1790 0 0.00 5 12077 379 0 0
sp_cancel_customer_reserve 552151 552151 0 0.00 1 57664 97 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 4 4746 108 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 4 2655 121 0 0
sp_cancel_supplier_invoice 6351 6344 7 0.11 2 28796 993 0 7
sp_cancel_supplier_order 438 438 0 0.00 8 1375833 156346 0 0
sp_cancel_write_off 9227 9227 0 0.00 2 9970 166 0 0
sp_client_order 20251 20251 0 0.00 5 35494 455 0 0
sp_customer_reserve 463326 463326 0 0.00 0 92222 246 0 0
sp_fill_shopping_cart 31007 31007 0 0.00 2 10281 76 0 0
sp_fill_shopping_cart_clo_res 463312 463312 0 0.00 0 18588 19 0 0
sp_get_clo_for_invoice 4875 4875 0 0.00 0 27265 246 0 0
sp_kill_cost_storno 16019 16019 0 0.00 0 12033 71 0 0
sp_kill_qstorno_ret_qs2qd 1120311 1120311 0 0.00 0 57647 43 0 0
sp_kill_qty_storno 590216 590216 0 0.00 0 57653 98 0 0
sp_lock_selected_doc 20412 20412 0 0.00 0 6342 6 0 0
sp_make_cost_storno 27314 27314 0 0.00 0 14287 79 0 0
sp_multiply_rows_for_pdistr 27312 27312 0 0.00 0 18495 39 0 0
sp_multiply_rows_for_qdistr 20251 20251 0 0.00 0 16307 185 0 0
sp_pay_from_customer 7024 7024 0 0.00 5 10171 171 0 0
sp_pay_to_supplier 5694 5694 0 0.00 7 18512 180 0 0
sp_payment_common 12717 12717 0 0.00 2 10146 42 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 12071 312 0 0
sp_qd_handle_on_invoice_upd_sts 10547 10547 0 0.00 1 27309 471 0 0
sp_qd_handle_on_reserve_upd_sts 9719 9719 0 0.00 0 18503 66 0 0
sp_reserve_write_off 9721 9721 0 0.00 9 18601 270 0 0
sp_supplier_invoice 5359 5359 0 0.00 8 63350 3024 0 0
sp_supplier_order 5405 5405 0 0.00 8 46421 1439 0 0
srv_aggregate_perf_data 115 115 0 0.00 2454 201768 13359 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 841 142213 40076 0 0
srv_find_qd_qs_mism 1050971 1050971 0 0.00 0 19620 11 0 0
srv_make_invnt_saldo 484 484 0 0.00 60 121594 3795 0 0
srv_make_money_saldo 894 894 0 0.00 1 14534 351 0 0
srv_recalc_idx_stat 4 4 0 0.00 76312 414858 222430 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4900 4900 0 0.00 1 13846 821 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 2 6736 380 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 3081 23 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 4179 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 651 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 2880 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 1682 50 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 70340 6064 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 1882 16 0 0
t$perf-norm:sp_client_order 20251 20251 0 0.00 0 8076 34 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 662 13 0 0
t$perf-norm:sp_pay_from_customer 7024 7024 0 0.00 0 12216 20 0 0
t$perf-norm:sp_pay_to_supplier 5694 5694 0 0.00 0 10309 21 0 0
t$perf-norm:sp_reserve_write_off 9721 9721 0 0.00 0 6593 24 0 0
t$perf-norm:sp_supplier_invoice 5359 5359 0 0.00 0 15061 33 0 0
t$perf-norm:sp_supplier_order 5405 5405 0 0.00 0 3774 29 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 847 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 763 16 0 0
t$perf-norm:srv_make_invnt_saldo 484 484 0 0.00 0 548 12 0 0
t$perf-norm:srv_make_money_saldo 894 894 0 0.00 0 173 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 198 76 0 0
v_add_invoice_to_stock 9956 9742 214 2.14 0 4100 23 0 214
v_all_customers 36816 36816 0 0.00 0 95 3 0 0
v_all_suppliers 30080 30080 0 0.00 0 65 4 0 0
v_all_wares 40500 40500 0 0.00 0 86 3 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 894 13 0 0
v_cancel_client_order 3580 3580 0 0.00 0 6152 11 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 1210 10 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 731 10 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 2646 16 0 0
v_cancel_supplier_invoice 744 730 14 1.88 0 313 20 0 14
v_cancel_supplier_order 876 876 0 0.00 0 151 8 0 0
v_cancel_write_off 2078 2078 0 0.00 0 1698 19 0 0
v_max_id_clo_ord 5403 5403 0 0.00 0 2747 5 0 0
v_max_id_clo_res 981 981 0 0.00 0 3667 14 0 0
v_max_id_ord_sup 5354 5354 0 0.00 0 4601 8 0 0
v_max_non_paid_invoice 1411 1411 0 0.00 0 245 7 0 0
v_max_non_paid_realizn 3727 3727 0 0.00 0 3252 8 0 0
v_min_id_clo_ord 5403 5403 0 0.00 0 4272 8 0 0
v_min_id_clo_res 981 981 0 0.00 0 1475 9 0 0
v_min_id_ord_sup 5354 5354 0 0.00 0 10267 10 0 0
v_min_non_paid_invoice 5694 5694 0 0.00 0 5426 13 0 0
v_min_non_paid_realizn 7023 7023 0 0.00 0 6639 18 0 0
v_our_firm 10276 10276 0 0.00 0 123 4 0 0
v_reserve_write_off 19438 19438 0 0.00 0 5857 9 0 0
x_lock_depdocs_on_canc_invoice 11653 11653 0 0.00 0 17940 134 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 14807 354 0 0
x_make_qstorno_1000_1200 5405 5405 0 0.00 3 42101 1285 0 0
x_make_qstorno_1200_2000 5359 5359 0 0.00 4 60316 2821 0 0
x_make_qstorno_2100_3300 463325 463325 0 0.00 0 92212 216 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-04-2022 07:39:08 to 11-04-2022 07:39:12.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:16 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:17 16133 8192000000 9712525056 10009923584 791201968 8.807 100 87 173 1 820930896 7351232 22377696
05:09:38 17434 8192000000 9749369968 10053292032 823779776 9.137 100 91 182 0 857366592 11563344 22023472
05:11:03 11410 8192000000 9782931216 10103361536 859776480 9.498 100 76 152 0 896589984 19091696 17721808
05:12:19 12395 8192000000 9861276144 10190213120 908456896 9.982 100 82 163 1 961684080 35030608 18196576
05:13:36 6128 8192000000 9926682016 10267828224 954079824 10.431 100 86 172 0 1034176816 61874752 18222240
05:14:47 2783 8192000000 9947912192 10300080128 990473920 10.786 100 84 168 0 1065113200 57429376 17209904
05:15:53 1178 8192000000 9993098384 10352791552 1022331152 11.095 100 93 179 1 1108600336 67765168 18504016
05:16:56 863 8192000000 10027843728 10397573120 1042868992 11.292 100 95 179 1 1147714096 86493824 18351280
05:17:58 1030 8192000000 10028952928 10404732928 1061443888 11.470 100 94 178 1 1149785280 70658000 17683392
05:19:00 1250 8192000000 10074162976 10454908928 1073235728 11.583 100 96 188 0 1194121584 102479648 18406208
05:20:02 1079 8192000000 10034960384 10412445696 1081390336 11.661 100 88 162 2 1159623312 62120608 16112368
05:21:04 962 8192000000 10051014624 10432540672 1086110736 11.706 100 95 185 1 1173174064 68774528 18288800
05:22:06 1144 8192000000 10054318240 10439925760 1087787296 11.722 100 96 186 0 1175246304 67592608 19866400
05:23:08 1206 8192000000 10054456768 10437910528 1093558096 11.776 100 93 177 0 1172422432 61297920 17566416
05:24:11 1037 8192000000 10070328288 10459488256 1098768704 11.826 100 94 175 1 1195107344 76362752 19975888
05:25:13 1212 8192000000 10054698656 10445668352 1100038288 11.838 100 93 184 3 1177810544 58800832 18971424
05:26:15 1230 8192000000 10070047760 10459414528 1105773424 11.892 100 97 189 3 1191526704 67475200 18278080
05:27:17 973 8192000000 10053622096 10444238848 1103312688 11.869 100 89 173 2 1174143232 53898544 16932000
05:28:19 2806 8192000000 10053908672 10453544960 1105161280 11.887 100 57 110 1 1181962016 64840928 11959808
05:29:23 874 8192000000 10040891264 10429235200 1097986320 11.819 100 88 177 0 1159099904 43249296 17864288
05:30:25 1667 8192000000 10036922032 10425356288 1073202208 11.583 99 73 142 0 1153456016 65834480 14419328
05:31:27 1138 8192000000 10016278576 10396442624 1063837392 11.493 100 89 173 2 1138291728 55717232 18737104
05:32:30 1045 8192000000 9996098160 10371751936 1034131568 11.208 100 78 148 1 1122478112 73081056 15265488
05:33:31 5012 8192000000 9989395568 10373419008 1051723456 11.377 100 67 130 4 1114759424 50305120 12730848
05:34:37 3626 8192000000 9969848080 10351120384 1030979568 11.178 98 61 99 3 1086556032 45669184 9907280
05:35:45 1015 8192000000 9990613904 10365952000 1036103792 11.227 100 84 148 2 1106050032 55809264 14136976
05:36:48 2387 8192000000 9949716496 10337382400 1020707328 11.079 99 55 104 0 1075536768 44448336 10381104
05:37:55 6163 8192000000 9999778704 10376564736 1064201120 11.497 100 78 139 2 1122731232 45133840 13396272
05:39:04 1344 8192000000 10081726608 10458300416 1079438608 11.642 100 86 155 1 1193913872 98517120 15958144
05:40:11 6242 8192000000 10050192224 10430664704 1097347376 11.812 100 94 162 5 1163329264 50657968 15323920
05:41:26 3451 8192000000 10089144224 10476351488 1104862816 11.884 100 70 135 0 1211251104 91863600 14524688
05:42:33 17535 8192000000 10133803168 10528817152 1119583952 12.023 100 72 138 0 1252407888 118340144 14483792
05:43:55 1112 8192000000 10168632864 10555727872 1125804176 12.082 100 91 176 2 1283337984 137999728 19534080
05:45:05 3260 8192000000 10192412528 10598625280 1140459872 12.220 100 60 110 2 1316475792 163405424 12610496
05:46:12 3132 8192000000 10247842464 10645590016 1157590624 12.381 100 94 187 1 1365046640 186222432 21233584
05:47:20 2213 8192000000 10254938592 10656272384 1171280560 12.509 100 81 153 2 1370628272 180387744 18959968
05:48:28 1265 8192000000 10320739104 10730364928 1178824544 12.579 100 96 180 1 1431777936 231275184 21678208
05:49:33 834 8192000000 10331838816 10741911552 1184464688 12.632 100 87 168 3 1446552384 241740064 20347632
05:50:37 1472 8192000000 10354834560 10772316160 1185466896 12.641 100 95 184 0 1463040720 256786192 20787632
05:51:41 1048 8192000000 10398492384 10806292480 1202742976 12.802 100 96 192 0 1506265376 282874416 20647984
05:52:46 1201 8192000000 10441568064 10865475584 1204428288 12.817 100 98 191 1 1550177616 323905536 21843792
05:53:50 1969 8192000000 10464857952 10875674624 1208823552 12.858 100 83 160 2 1574497008 346719392 18954064
05:54:57 3377 8192000000 10483693632 10912751616 1218276336 12.946 100 79 156 0 1602949712 366163184 18510192
05:56:05 942 8192000000 10497894768 10937057280 1206190144 12.834 100 91 174 2 1601077808 373686400 21201264
05:57:10 1642 8192000000 10522769264 10942939136 1196704432 12.746 100 92 181 0 1634257072 416514624 21038016
05:58:16 845 8192000000 10507766112 10948542464 1205110496 12.824 100 72 138 0 1614804752 391588736 18105520
05:59:20 1771 8192000000 10545931920 10980139008 1206151664 12.833 100 80 156 1 1652217488 426920848 19144976
06:00:26 6356 8192000000 10552256880 10989211648 1204320560 12.816 100 63 124 0 1656602672 436775120 15506992
06:01:34 19461 8192000000 10589675088 11058528256 1190119344 12.684 99 43 86 0 1684716304 482908480 11688480
06:02:57 18995 8192000000 10551321968 11006210048 1167693856 12.475 99 36 70 1 1645212304 467530560 9987888
06:04:25 6332 8192000000 10538439872 11071700992 1176405648 12.557 100 49 95 1 1645525952 455836672 13283632
06:05:41 24632 8192000000 10411346640 10906783744 1157633552 12.381 99 43 84 0 1517191472 348069392 11488528
06:07:14 25019 8192000000 10312816496 10842411008 1155339968 12.360 100 27 53 0 1425062496 263155776 6566752
06:08:41 17404 8192000000 10151424704 10596962304 1133861744 12.158 99 49 97 2 1247919488 103246032 10811712
06:09:59 70730 8192000000 10068538816 10538250240 1102436768 11.861 96 11 19 0 1153570496 49322128 1811600
06:12:16 94942 8192000000 10090793504 10560503808 1121790480 12.044 98 14 28 0 1194117184 69594704 2732000
06:15:03 58066 8192000000 10116452032 10596564992 1126454560 12.088 98 14 28 0 1199370512 69901680 3014272
06:17:02 28960 8192000000 10140247360 10588770304 1117214912 12.001 97 52 99 1 1202510432 74750784 10544736
06:18:35 71205 8192000000 10102479600 10564386816 1114984256 11.980 97 29 50 0 1155644128 35965472 4694400
06:20:49 75778 8192000000 10069862544 10506129408 1127716784 12.100 99 31 60 0 1160725856 26407952 6601120
06:23:09 35144 8192000000 10109407456 10571141120 1106974144 11.904 97 11 21 0 1201011808 91756304 2281360
06:24:45 95585 8192000000 10134970768 10592542720 1135810880 12.176 99 36 66 2 1189400624 46191888 7397856
06:27:24 68620 8192000000 10076646944 10512424960 1105776864 11.892 97 43 82 1 1136342848 21730896 8835088
06:29:34 29266 8192000000 10128808256 10581651456 1125939552 12.083 98 42 84 0 1209953680 75114160 8899968
06:31:06 7792 8192000000 10140671328 10570362880 1148197312 12.293 100 76 138 1 1241356496 78917472 14241712
06:32:15 61409 8192000000 10086813712 10603302912 1122442800 12.050 98 18 35 1 1211197280 84773248 3981232
06:34:20 79230 8192000000 10076665792 10533498880 1121248000 12.039 98 12 24 0 1161746944 37425280 3073664
06:36:39 39861 8192000000 10075779616 10526748672 1120964352 12.036 99 28 52 1 1168130880 42206528 4960000
06:38:26 45697 8192000000 10119128128 10564202496 1104293392 11.878 97 19 38 2 1170559904 62988416 3278096
06:40:19 49428 8192000000 10079022560 10527002624 1120889552 12.035 98 28 56 0 1159521008 33070656 5560800
06:42:17 24253 8192000000 10111042144 10578149376 1137470768 12.192 100 31 62 2 1204345504 60296864 6577872
06:43:51 48171 8192000000 10153462816 10589986816 1147825616 12.289 100 31 60 0 1218256864 64425776 6005472
06:45:40 142184 8192000000 10070570144 10558664704 1032814048 11.196 90 9 18 0 1084147536 49299072 2034416
06:49:59 56526 8192000000 10086523488 10528792576 1116372384 11.993 98 17 34 1 1155332368 35651776 3308208
06:51:56 109434 8192000000 10059603152 10504462336 1116015248 11.989 99 22 44 1 1175346480 54860736 4470496
06:54:46 26846 8192000000 10098855120 10546790400 1145710576 12.269 100 34 64 0 1199504592 46635168 7158848
06:56:14 56305 8192000000 10117564304 10573131776 1125278672 12.077 99 18 36 0 1209171136 79832752 4059712
06:58:11 85473 8192000000 10194008304 10645057536 1115201680 11.982 97 19 38 1 1251472528 131637200 4633648
07:00:37 67985 8192000000 10182256304 10621190144 1124495696 12.069 99 37 72 2 1255448736 121847312 9105728
07:03:15 41714 8192000000 10106982448 10554826752 1124883408 12.073 99 23 46 0 1207267888 77448640 4935840
07:05:04 27015 8192000000 10131951776 10564493312 1109878304 11.931 98 35 71 0 1209583488 92339296 7365888
07:06:31 46438 8192000000 10147955392 10595352576 1124576256 12.070 99 33 64 1 1223530864 92287488 6667120
07:08:28 44553 8192000000 10081044128 10507370496 1115842416 11.988 98 44 84 2 1154713728 29729248 9142064
07:10:25 67527 8192000000 10094245120 10533494784 1110756384 11.940 97 28 56 0 1182867072 66563632 5547056
07:12:39 86094 8192000000 10094760960 10552020992 1134383152 12.163 100 38 71 2 1211282336 69728592 7170592
07:15:16 56405 8192000000 10072275280 10545733632 1130125008 12.123 99 8 16 0 1165188384 32930752 2132624
07:17:23 113043 8192000000 10097835264 10619637760 1143204736 12.246 100 23 46 0 1218579312 69517488 5857088
07:20:17 102798 8192000000 10077748784 10532155392 1079035200 11.638 94 26 52 1 1127571664 43000624 5535840
07:23:02 36644 8192000000 10121030080 10578337792 1140828032 12.223 100 29 58 0 1236799376 89260864 6710480
07:24:39 36849 8192000000 10123544640 10564947968 1100387456 11.841 98 35 70 2 1209413856 101452896 7573504
07:26:22 31433 8192000000 10068933536 10500153344 1127984176 12.102 100 47 87 1 1176317792 39532448 8801168
07:27:55 39000 8192000000 10126655008 10555535360 1111881136 11.950 98 39 78 1 1207109072 86990960 8236976
07:29:34 71153 8192000000 10153378912 10615607296 1127817088 12.101 99 43 85 0 1206102528 70279712 8005728
07:31:47 138725 8192000000 10071568784 10554097664 1120769280 12.034 99 32 64 1 1211277312 82642960 7865072
07:35:12 77490 8192000000 10100404960 10596208640 1122341488 12.049 99 9 18 0 1208598336 84127024 2129824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-04-2022 07:39:14 to 11-04-2022 07:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-04-2022 07:39:31 to 11-04-2022 07:39:33.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          219554
MON$OLDEST_ACTIVE               219874
MON$OLDEST_SNAPSHOT             219874
MON$NEXT_TRANSACTION            219874
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2063968
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2757 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2757 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2757 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2757 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

11.04.22 07:39:34. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-04-11 07:39:36 No severe PSQL-related problems occured
11.04.22 07:39:38. End of report.