Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.10.2022 13:39
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 15033000 kB
MemAvailable 30562668 kB
Buffers 0 kB
Cached 16701100 kB
SwapCached 2124 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.3
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-10-10 13:37:21
DTS_END                         2022-10-10 13:37:21.6310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48688
TRN_ID                          231773
ATT_ID                          653
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: 9207.15 27150 1104858
customer order: creation 180.39 454 21647
customer order: refuse 15.70 393 1884
order to supplier: creation 48.81 1240 5857
order to supplier: removal 3.47 156355 416
invoice (draft): creation 48.19 2891 5783
invoice (draft): removal 49.18 928 5902
invoice accept: apply 44.13 26008 5296
invoice accept: cancel 44.63 1208 5355
customer reserve: creation 4076.67 249 489200
customer reserve: removal 4394.43 97 527331
realization accept: apply 85.98 274 10318
realization accept: cancel 80.18 182 9621
payment from customer: creation 61.56 177 7387
payment from customer: removal 6.05 111 726
payment to supplier: creation 50.88 178 6105
payment to supplier: removal 3.43 175 412
service: total inventory turnovers 4.13 3969 496
service: total monetary turnovers 8.26 339 991
service: aggregate perf. data 1.05 14822 126
service: refresh index statistics 0.04 332941 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-10-2022 13:39:39 to 10-10-2022 13:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8019
TEST_TIME 2 6364
TEST_TIME 3 5656
TEST_TIME 4 5756
TEST_TIME 5 5232
TEST_TIME 6 5785
TEST_TIME 7 6195
TEST_TIME 8 6488
TEST_TIME 9 7094
TEST_TIME 10 7487
TEST_TIME 11 7446
TEST_TIME 12 7693
TEST_TIME 13 7637
TEST_TIME 14 7687
TEST_TIME 15 8000
TEST_TIME 16 7835
TEST_TIME 17 7995
TEST_TIME 18 8252
TEST_TIME 19 8278
TEST_TIME 20 8330
TEST_TIME 21 8361
TEST_TIME 22 8423
TEST_TIME 23 8491
TEST_TIME 24 8514
TEST_TIME 25 8533
TEST_TIME 26 8933
TEST_TIME 27 8969
TEST_TIME 28 8951
TEST_TIME 29 8897
TEST_TIME 30 9316
TEST_TIME 31 9657
TEST_TIME 32 10188
TEST_TIME 33 10763
TEST_TIME 34 11619
TEST_TIME 35 11478
TEST_TIME 36 11439
TEST_TIME 37 11361
TEST_TIME 38 11185
TEST_TIME 39 10978
TEST_TIME 40 10789
TEST_TIME 41 10920
TEST_TIME 42 10795
TEST_TIME 43 10680
TEST_TIME 44 10540
TEST_TIME 45 10480
TEST_TIME 46 10520
TEST_TIME 47 10479
TEST_TIME 48 10443
TEST_TIME 49 10380
TEST_TIME 50 10436
TEST_TIME 51 10345
TEST_TIME 52 10186
TEST_TIME 53 10131
TEST_TIME 54 10124
TEST_TIME 55 10038
TEST_TIME 56 9947
TEST_TIME 57 9948
TEST_TIME 58 9891
TEST_TIME 59 9870
TEST_TIME 60 9899
TEST_TIME 61 9856
TEST_TIME 62 9801
TEST_TIME 63 9753
TEST_TIME 64 9947
TEST_TIME 65 9938
TEST_TIME 66 10040
TEST_TIME 67 10094
TEST_TIME 68 10154
TEST_TIME 69 10118
TEST_TIME 70 10050
TEST_TIME 71 10001
TEST_TIME 72 9960
TEST_TIME 73 9886
TEST_TIME 74 9881
TEST_TIME 75 9842
TEST_TIME 76 9758
TEST_TIME 77 9783
TEST_TIME 78 9796
TEST_TIME 79 9775
TEST_TIME 80 9731
TEST_TIME 81 9725
TEST_TIME 82 9723
TEST_TIME 83 9680
TEST_TIME 84 9745
TEST_TIME 85 9711
TEST_TIME 86 9753
TEST_TIME 87 9823
TEST_TIME 88 9782
TEST_TIME 89 9772
TEST_TIME 90 9800
TEST_TIME 91 9783
TEST_TIME 92 9691
TEST_TIME 93 9660
TEST_TIME 94 9672
TEST_TIME 95 9631
TEST_TIME 96 9551
TEST_TIME 97 9518
TEST_TIME 98 9497
TEST_TIME 99 9444
TEST_TIME 100 9438
TEST_TIME 101 9444
TEST_TIME 102 9411
TEST_TIME 103 9383
TEST_TIME 104 9432
TEST_TIME 105 9403
TEST_TIME 106 9449
TEST_TIME 107 9436
TEST_TIME 108 9486
TEST_TIME 109 9468
TEST_TIME 110 9486
TEST_TIME 111 9525
TEST_TIME 112 9581
TEST_TIME 113 9601
TEST_TIME 114 9520
TEST_TIME 115 9468
TEST_TIME 116 9442
TEST_TIME 117 9415
TEST_TIME 118 9370
TEST_TIME 119 9312
TEST_TIME 120 9239

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-10-2022 13:39:42 to 10-10-2022 13:39:46.

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 1554899 1554899 0 0.00 0 31772 14 0 0
doc_list_biud 1554883 1554883 0 0.00 0 46163 38 0 0
sp_add_invoice_to_stock 5422 5296 126 2.32 80 187592 26008 0 126
sp_cancel_adding_invoice 5355 5355 0 0.00 6 87095 1209 0 0
sp_cancel_client_order 1884 1884 0 0.00 6 9566 393 0 0
sp_cancel_customer_reserve 527331 527331 0 0.00 1 46187 98 0 0
sp_cancel_pay_from_customer 726 726 0 0.00 3 2680 111 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 4 17604 176 0 0
sp_cancel_supplier_invoice 5914 5902 12 0.20 3 15196 928 0 12
sp_cancel_supplier_order 416 416 0 0.00 13 1557518 156355 0 0
sp_cancel_write_off 9621 9621 0 0.00 2 17748 183 0 0
sp_client_order 21647 21647 0 0.00 6 23110 454 0 0
sp_customer_reserve 489200 489200 0 0.00 0 111860 250 0 0
sp_fill_shopping_cart 33277 33277 0 0.00 1 28662 82 0 0
sp_fill_shopping_cart_clo_res 489159 489159 0 0.00 0 12463 17 0 0
sp_get_clo_for_invoice 5246 5246 0 0.00 0 28665 244 0 0
sp_kill_cost_storno 16114 16114 0 0.00 0 9463 71 0 0
sp_kill_qstorno_ret_qs2qd 1070601 1070601 0 0.00 0 46136 41 0 0
sp_kill_qty_storno 566072 566072 0 0.00 0 46156 97 0 0
sp_lock_selected_doc 21763 21763 0 0.00 0 11271 11 0 0
sp_make_cost_storno 29056 29056 0 0.00 0 12641 73 0 0
sp_multiply_rows_for_pdistr 29055 29055 0 0.00 0 14939 37 0 0
sp_multiply_rows_for_qdistr 21647 21647 0 0.00 0 14730 168 0 0
sp_pay_from_customer 7387 7387 0 0.00 5 15081 178 0 0
sp_pay_to_supplier 6105 6105 0 0.00 6 12161 178 0 0
sp_payment_common 13492 13492 0 0.00 2 8663 49 0 0
sp_qd_handle_on_cancel_clo 1884 1884 0 0.00 0 9531 307 0 0
sp_qd_handle_on_invoice_upd_sts 10600 10600 0 0.00 0 27516 518 0 0
sp_qd_handle_on_reserve_upd_sts 10318 10318 0 0.00 0 15082 61 0 0
sp_reserve_write_off 10318 10318 0 0.00 8 17093 275 0 0
sp_supplier_invoice 5783 5783 0 0.00 8 84626 2892 0 0
sp_supplier_order 5857 5857 0 0.00 8 83064 1240 0 0
srv_aggregate_perf_data 126 126 0 0.00 2512 171333 14823 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 816 114055 35595 0 0
srv_find_qd_qs_mism 1053183 1053183 0 0.00 0 31729 11 0 0
srv_make_invnt_saldo 496 496 0 0.00 41 51930 3970 0 0
srv_make_money_saldo 991 991 0 0.00 1 8710 340 0 0
srv_recalc_idx_stat 5 5 0 0.00 218381 441355 332942 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5302 5302 0 0.00 0 12140 899 0 0
t$perf-norm:sp_cancel_adding_invoice 465 465 0 0.00 1 5334 398 0 0
t$perf-norm:sp_cancel_client_order 1884 1884 0 0.00 0 4199 30 0 0
t$perf-norm:sp_cancel_customer_reserve 769 769 0 0.00 0 2907 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 726 726 0 0.00 0 1847 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 335 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 467 467 0 0.00 0 1321 44 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 71601 6433 0 0
t$perf-norm:sp_cancel_write_off 1065 1065 0 0.00 0 2997 25 0 0
t$perf-norm:sp_client_order 21648 21648 0 0.00 0 9048 32 0 0
t$perf-norm:sp_customer_reserve 1099 1099 0 0.00 0 1037 14 0 0
t$perf-norm:sp_pay_from_customer 7387 7387 0 0.00 0 8086 20 0 0
t$perf-norm:sp_pay_to_supplier 6105 6105 0 0.00 0 7563 19 0 0
t$perf-norm:sp_reserve_write_off 10318 10318 0 0.00 0 10178 22 0 0
t$perf-norm:sp_supplier_invoice 5783 5783 0 0.00 0 1532 28 0 0
t$perf-norm:sp_supplier_order 5857 5857 0 0.00 0 3348 30 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 298 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 1128 20 0 0
t$perf-norm:srv_make_invnt_saldo 496 496 0 0.00 0 724 13 0 0
t$perf-norm:srv_make_money_saldo 991 991 0 0.00 0 10766 18 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 258 84 0 0
v_add_invoice_to_stock 10734 10482 252 2.34 0 4184 25 0 252
v_all_customers 39324 39324 0 0.00 0 106 4 0 0
v_all_suppliers 32354 32354 0 0.00 0 106 5 0 0
v_all_wares 43286 43286 0 0.00 0 141 4 0 0
v_cancel_adding_invoice 930 930 0 0.00 0 2449 16 0 0
v_cancel_client_order 3768 3768 0 0.00 0 5460 11 0 0
v_cancel_customer_prepayment 1452 1452 0 0.00 0 1957 13 0 0
v_cancel_customer_reserve 1538 1538 0 0.00 0 104 10 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 2379 14 0 0
v_cancel_supplier_invoice 958 934 24 2.50 0 2656 26 0 24
v_cancel_supplier_order 832 832 0 0.00 0 1071 13 0 0
v_cancel_write_off 2130 2130 0 0.00 0 1175 25 0 0
v_max_id_clo_ord 5855 5855 0 0.00 0 1912 6 0 0
v_max_id_clo_res 1099 1099 0 0.00 0 5727 18 0 0
v_max_id_ord_sup 5777 5777 0 0.00 0 5543 8 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 413 9 0 0
v_max_non_paid_realizn 3884 3884 0 0.00 0 317 7 0 0
v_min_id_clo_ord 5855 5855 0 0.00 0 1932 9 0 0
v_min_id_clo_res 1099 1099 0 0.00 0 6031 25 0 0
v_min_id_ord_sup 5777 5777 0 0.00 0 6335 14 0 0
v_min_non_paid_invoice 6105 6105 0 0.00 0 6197 16 0 0
v_min_non_paid_realizn 7387 7387 0 0.00 0 8656 23 0 0
v_our_firm 10968 10968 0 0.00 0 62 5 0 0
v_reserve_write_off 20636 20636 0 0.00 0 4568 10 0 0
x_lock_depdocs_on_canc_invoice 10790 10790 0 0.00 0 12571 117 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 10581 356 0 0
x_make_qstorno_1000_1200 5857 5857 0 0.00 2 83047 1063 0 0
x_make_qstorno_1200_2000 5783 5783 0 0.00 4 84561 2683 0 0
x_make_qstorno_2100_3300 489198 489198 0 0.00 0 111747 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-10-2022 13:39:47 to 10-10-2022 13:39:51.

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
11:07:25 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:27 22072 8192000000 9726484816 10028847104 809333360 8.991 100 85 169 0 837469120 7200864 20934896
11:09:53 16824 8192000000 9774189072 10083516416 845553920 9.356 100 91 182 0 878221808 11503840 21164048
11:11:13 13881 8192000000 9797022752 10113691648 866063776 9.561 100 89 177 0 905230000 18710768 20455456
11:12:35 8417 8192000000 9859148592 10189537280 901819344 9.916 100 83 163 1 968597184 49477040 17300800
11:13:54 4294 8192000000 9916376192 10262446080 953314608 10.424 100 92 177 1 1029547216 56700416 19532192
11:15:03 1192 8192000000 9963656976 10317238272 1000824640 10.887 100 93 185 0 1090705872 70557664 19323568
11:16:07 1634 8192000000 10004441456 10369069056 1030073072 11.169 100 91 177 1 1124888400 77644672 17170656
11:17:11 1284 8192000000 10013234784 10388226048 1050799744 11.368 100 89 171 1 1134643904 66966032 16878128
11:18:13 1229 8192000000 10050649520 10424717312 1066655328 11.520 100 98 194 0 1168145952 81869920 19620704
11:19:16 1017 8192000000 10054725344 10440179712 1074119200 11.591 100 87 171 1 1172738176 81635952 16983024
11:20:18 1127 8192000000 10017955776 10399797248 1079567664 11.643 100 90 172 3 1142903392 45561648 17774080
11:21:20 5917 8192000000 10039441984 10431275008 1079919664 11.647 100 68 133 0 1158294832 65099584 13275584
11:22:27 937 8192000000 10061176592 10446385152 1088502208 11.728 100 96 180 2 1178022208 71104752 18415248
11:23:29 1555 8192000000 10048130592 10439553024 1093246944 11.774 100 70 131 0 1166494368 59704688 13542736
11:24:31 961 8192000000 10064049168 10450984960 1094995824 11.790 100 94 183 2 1184278480 70451536 18831120
11:25:34 1203 8192000000 10046915904 10440167424 1099813696 11.836 100 72 121 3 1165557424 53742544 12001184
11:26:37 965 8192000000 10064370096 10456088576 1093035904 11.772 99 91 176 0 1181560960 69520688 19004368
11:27:40 1021 8192000000 10040620848 10431676416 1098328048 11.822 100 93 178 0 1163667808 45878720 19461040
11:28:42 1181 8192000000 10050454352 10439970816 1088574896 11.729 100 91 170 2 1167769856 61648064 17546896
11:29:44 1209 8192000000 10022198816 10402795520 1076704624 11.616 99 93 179 1 1148074112 52055184 19314304
11:30:47 1041 8192000000 10039302288 10419843072 1071385776 11.565 99 91 181 0 1160495984 70456304 18653904
11:31:49 1178 8192000000 10020706464 10402344960 1053023600 11.390 100 97 179 3 1143913712 71510896 19379216
11:32:51 1173 8192000000 10021176928 10394189824 1044826896 11.311 100 97 184 3 1144180160 80282064 19071200
11:33:53 1111 8192000000 9980401808 10347970560 1013770880 11.012 100 95 172 3 1103667200 72542848 17353472
11:34:55 849 8192000000 10002956864 10381205504 1042417120 11.288 100 84 164 5 1130305040 70452240 17435680
11:35:56 1872 8192000000 9995135184 10376933376 1047188704 11.334 100 73 133 0 1120046064 59247504 13609856
11:36:59 844 8192000000 10002645488 10381950976 1048900112 11.350 100 89 171 1 1123972288 56466560 18605616
11:38:08 1548 8192000000 10006032464 10388389888 1058128912 11.439 100 73 134 4 1135171216 64364176 12678128
11:39:17 21447 8192000000 10043382832 10433527808 1074871088 11.599 100 64 123 0 1166220304 79099408 12249808
11:40:46 1723 8192000000 10076425552 10456125440 1085812848 11.703 100 92 171 1 1191519104 88757872 16948384
11:41:55 16675 8192000000 10079318480 10481278976 1093306992 11.774 100 49 98 2 1188550192 84638784 10604416
11:43:19 4485 8192000000 10116436096 10515652608 1108260976 11.916 100 83 156 1 1231426160 107737376 15427808
11:44:27 1199 8192000000 10144935312 10536169472 1114518736 11.975 100 91 168 1 1256830256 125599776 16711744
11:45:31 1590 8192000000 10155803632 10565509120 1133982320 12.159 100 84 159 0 1274905136 123783056 17139760
11:46:35 1055 8192000000 10223203504 10621534208 1149499360 12.305 100 92 176 1 1332746480 163262992 19984128
11:47:40 979 8192000000 10233806432 10650697728 1157933360 12.384 100 94 186 2 1347741744 170376352 19432032
11:48:43 1401 8192000000 10261900688 10679955456 1169948000 12.496 100 85 170 1 1367862240 179610304 18303936
11:49:49 1244 8192000000 10265419648 10666061824 1177399744 12.566 100 94 179 1 1375301120 177491360 20410016
11:50:54 3461 8192000000 10293017984 10711085056 1180101440 12.591 100 84 161 1 1393654992 196242704 17310848
11:52:00 810 8192000000 10304447008 10743775232 1188726048 12.672 100 96 183 0 1410764368 200843216 21195104
11:53:04 10287 8192000000 10330360544 10735271936 1181687440 12.606 100 79 142 0 1426531312 228968624 15875248
11:54:18 1221 8192000000 10353541664 10772459520 1195641600 12.736 100 92 182 1 1463192704 245766784 21784320
11:55:21 828 8192000000 10375387504 10781007872 1185379024 12.640 100 97 195 0 1483829776 276013984 22436768
11:56:23 1409 8192000000 10411732944 10820685824 1196593712 12.745 100 95 188 1 1520787872 302322144 21872016
11:57:27 1713 8192000000 10428906384 10828705792 1159739488 12.401 100 87 171 0 1535928224 356130528 20058208
11:58:31 1125 8192000000 10456073376 10871336960 1173827712 12.533 100 95 186 3 1565841088 369113312 22900064
11:59:35 1428 8192000000 10456957824 10881855488 1177016480 12.562 100 82 145 1 1561319312 367568160 16734672
12:00:37 2448 8192000000 10489681552 10903203840 1161856752 12.421 99 80 153 0 1597166128 416741520 18567856
12:01:45 1404 8192000000 10541350064 10980110336 1182359296 12.612 100 87 170 0 1643252816 439390304 21503216
12:02:48 958 8192000000 10509581296 10970296320 1170721744 12.504 100 68 136 1 1612478800 424521024 17236032
12:03:57 11396 8192000000 10560060816 11007393792 1190486592 12.688 100 58 116 0 1667663664 462433136 14743936
12:05:10 17863 8192000000 10592462800 11060076544 1160811136 12.411 98 49 99 0 1679957792 506289712 12856944
12:07:36 39885 8192000000 10553981552 11068735488 1173679232 12.531 100 38 76 0 1684199744 500316784 10203728
12:09:18 25573 8192000000 10443778288 11024658432 1156831168 12.374 100 27 54 2 1585303648 420484368 7988112
12:11:41 52392 8192000000 10047054656 10489430016 1083049536 11.677 96 12 22 1 1115690768 29701312 2939920
12:13:44 114052 8192000000 10046850064 10476371968 1074135680 11.592 96 22 44 1 1104512288 25757792 4618816
12:16:39 53331 8192000000 10071884480 10502787072 1114287904 11.973 99 58 102 2 1165138096 41410896 9439296
12:18:40 54217 8192000000 10075380688 10501255168 1117556960 12.004 98 51 90 1 1149071232 19088608 12425664
12:20:34 92150 8192000000 10132400064 10572845056 1108016400 11.914 97 15 27 0 1168499888 57810656 2672832
12:23:14 12041 8192000000 10138841600 10573213696 1140619296 12.221 100 59 118 0 1231999184 79494368 11885520
12:24:31 55571 8192000000 10096600016 10552037376 1109882832 11.931 98 17 30 0 1183362224 70019232 3460160
12:26:32 40347 8192000000 10096432448 10557816832 1103994624 11.876 98 23 45 0 1174913888 65651152 5268112
12:28:15 92959 8192000000 10077446560 10536198144 1125974416 12.083 99 24 48 0 1178291312 47396784 4920112
12:30:55 73330 8192000000 10080556816 10602864640 1125480960 12.079 99 9 16 0 1181175856 54045856 1649040
12:33:10 90423 8192000000 10058898320 10561519616 1089940544 11.742 96 8 16 0 1145093984 53194656 1958784
12:35:41 12131 8192000000 10092652496 10522992640 1114217120 11.972 98 59 116 0 1175442144 49499520 11725504
12:36:56 18502 8192000000 10117126928 10560376832 1114376016 11.974 98 46 90 0 1200109776 76650480 9083280
12:38:18 19574 8192000000 10109675344 10557046784 1125888704 12.083 100 29 58 0 1207038272 74839296 6310272
12:39:42 22574 8192000000 10191031488 10628411392 1135181552 12.170 100 34 66 0 1279822912 137711680 6929680
12:41:06 24211 8192000000 10150177888 10649333760 1142119568 12.235 100 25 47 0 1305077536 157557808 5400160
12:42:32 43699 8192000000 10163793888 10616193024 1120441392 12.031 98 30 58 2 1275229024 148088848 6698784
12:44:23 37085 8192000000 10099456320 10530062336 1114753888 11.977 99 41 80 0 1158668432 35849360 8065184
12:46:02 52315 8192000000 10066139360 10534477824 1071625696 11.568 96 32 60 1 1147329248 69493344 6210208
12:47:56 53814 8192000000 10054888832 10484625408 1070221920 11.554 96 40 78 1 1111197456 33127936 7847600
12:49:52 39024 8192000000 10083907920 10516803584 1102448208 11.861 99 50 100 0 1176693888 63961184 10284496
12:51:32 63356 8192000000 10091132816 10579394560 1111522096 11.947 99 13 24 0 1204663648 90271664 2869888
12:53:42 56763 8192000000 10068780624 10566815744 1131335104 12.134 100 16 32 0 1208331440 73420176 3576160
12:55:39 93584 8192000000 10081103056 10547179520 1115104256 11.981 98 14 28 0 1158011872 40355024 2552592
12:58:13 21514 8192000000 10087885568 10529984512 1121877392 12.045 98 37 72 1 1172736608 43198944 7660272
12:59:37 47246 8192000000 10093082448 10536222720 1093338592 11.774 96 32 66 0 1164169472 63735408 7095472
13:01:45 27801 8192000000 10117502240 10557341696 1128876864 12.111 100 44 88 0 1201646800 63775008 8994928
13:03:15 74727 8192000000 10102631600 10591195136 1144346752 12.256 100 26 50 0 1216514752 66946304 5221696
13:05:36 68099 8192000000 10103542160 10555600896 1106002304 11.895 97 35 68 0 1167056832 53940192 7114336
13:07:50 34654 8192000000 10120626432 10573680640 1141431136 12.229 100 24 48 0 1226963456 80322928 5209392
13:09:25 106438 8192000000 10082845232 10587791360 1130922176 12.130 99 16 32 0 1212573792 78479184 3172432
13:12:41 57250 8192000000 10066594416 10514706432 1132654448 12.146 99 21 40 0 1159015360 21801232 4559680
13:14:38 89039 8192000000 10069243504 10564104192 1133539472 12.155 99 12 24 0 1184379440 48279616 2560352
13:17:09 35528 8192000000 10063373936 10509000704 1124756272 12.072 98 29 59 0 1174219248 42452960 7010016
13:18:46 24309 8192000000 10094886320 10536067072 1119974848 12.027 99 39 73 1 1197125760 68859440 8291472
13:20:13 28825 8192000000 10133739728 10569334784 1121022416 12.037 99 33 66 0 1208566624 80247616 7296592
13:21:44 33987 8192000000 10125155808 10568232960 1116701920 11.996 98 36 70 0 1195367664 71365664 7300080
13:23:18 56766 8192000000 10094587088 10551734272 1110124896 11.934 98 20 40 0 1184324064 69824416 4374752
13:25:19 22377 8192000000 10108151024 10543439872 1131183168 12.133 99 34 68 0 1203146416 64990064 6973184
13:26:44 66248 8192000000 10167715888 10624847872 1093277376 11.774 97 40 76 0 1197446048 96559424 7609248
13:29:02 80024 8192000000 10083859632 10576179200 1106793696 11.902 98 25 50 0 1185115808 73077744 5244368
13:31:23 93759 8192000000 10026427520 10473500672 1083468496 11.681 96 30 60 0 1111540720 22015616 6056608
13:33:58 52842 8192000000 10060002640 10530115584 1123103088 12.056 100 18 36 0 1174850448 48489952 3257408
13:35:52 62900 8192000000 10083754032 10536382464 1129777408 12.119 100 15 30 0 1186767200 53564400 3425392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-10-2022 13:39:53 to 10-10-2022 13:40:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-10-2022 13:40:10 to 10-10-2022 13:40:12.

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          232581
MON$OLDEST_ACTIVE               232582
MON$OLDEST_SNAPSHOT             232582
MON$NEXT_TRANSACTION            232582
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                       2087904
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             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2856 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2856 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2856 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2856 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'

10.10.22 13:40:14. 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-10-10 13:40:16 No severe PSQL-related problems occured
10.10.22 13:40:18. End of report.