Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.07.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 16008356 kB
MemAvailable 31074744 kB
Buffers 0 kB
Cached 15736660 kB
SwapCached 2552 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-07-24 07:37:15
DTS_END                         2022-07-24 07:37:15.7710
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/49762
TRN_ID                          208623
ATT_ID                          634
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: 8714.63 22757 1045755
customer order: creation 161.40 482 19368
customer order: refuse 13.99 455 1679
order to supplier: creation 43.71 1433 5245
order to supplier: removal 3.38 166133 406
invoice (draft): creation 43.14 3296 5177
invoice (draft): removal 47.83 957 5739
invoice accept: apply 39.96 29678 4795
invoice accept: cancel 43.48 1254 5218
customer reserve: creation 3756.15 280 450738
customer reserve: removal 4284.39 102 514127
realization accept: apply 80.72 307 9687
realization accept: cancel 74.91 189 8989
payment from customer: creation 55.53 193 6664
payment from customer: removal 4.90 147 588
payment to supplier: creation 45.55 200 5466
payment to supplier: removal 3.27 118 392
service: total inventory turnovers 3.91 4362 469
service: total monetary turnovers 7.41 389 889
service: aggregate perf. data 0.95 14510 114
service: refresh index statistics 0.04 230660 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-07-2022 07:38:58 to 24-07-2022 07:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10781
TEST_TIME 2 7703
TEST_TIME 3 6508
TEST_TIME 4 6313
TEST_TIME 5 6612
TEST_TIME 6 6455
TEST_TIME 7 6624
TEST_TIME 8 6619
TEST_TIME 9 7019
TEST_TIME 10 6962
TEST_TIME 11 7105
TEST_TIME 12 7021
TEST_TIME 13 7277
TEST_TIME 14 7243
TEST_TIME 15 7341
TEST_TIME 16 7289
TEST_TIME 17 7168
TEST_TIME 18 7317
TEST_TIME 19 7230
TEST_TIME 20 7262
TEST_TIME 21 7340
TEST_TIME 22 7251
TEST_TIME 23 7397
TEST_TIME 24 7570
TEST_TIME 25 7906
TEST_TIME 26 8123
TEST_TIME 27 8115
TEST_TIME 28 8255
TEST_TIME 29 8554
TEST_TIME 30 8770
TEST_TIME 31 9646
TEST_TIME 32 9549
TEST_TIME 33 9822
TEST_TIME 34 9938
TEST_TIME 35 10104
TEST_TIME 36 10638
TEST_TIME 37 10452
TEST_TIME 38 10486
TEST_TIME 39 10479
TEST_TIME 40 10251
TEST_TIME 41 10020
TEST_TIME 42 9867
TEST_TIME 43 9712
TEST_TIME 44 9613
TEST_TIME 45 9617
TEST_TIME 46 9629
TEST_TIME 47 9564
TEST_TIME 48 9595
TEST_TIME 49 9501
TEST_TIME 50 9446
TEST_TIME 51 9353
TEST_TIME 52 9310
TEST_TIME 53 9240
TEST_TIME 54 9162
TEST_TIME 55 9183
TEST_TIME 56 9097
TEST_TIME 57 9144
TEST_TIME 58 9049
TEST_TIME 59 9080
TEST_TIME 60 9116
TEST_TIME 61 9067
TEST_TIME 62 9243
TEST_TIME 63 9187
TEST_TIME 64 9254
TEST_TIME 65 9315
TEST_TIME 66 9334
TEST_TIME 67 9315
TEST_TIME 68 9235
TEST_TIME 69 9139
TEST_TIME 70 9068
TEST_TIME 71 9102
TEST_TIME 72 9053
TEST_TIME 73 8990
TEST_TIME 74 8933
TEST_TIME 75 8863
TEST_TIME 76 8841
TEST_TIME 77 8822
TEST_TIME 78 8781
TEST_TIME 79 8765
TEST_TIME 80 8766
TEST_TIME 81 8824
TEST_TIME 82 8881
TEST_TIME 83 8836
TEST_TIME 84 8879
TEST_TIME 85 8860
TEST_TIME 86 8905
TEST_TIME 87 8887
TEST_TIME 88 8935
TEST_TIME 89 8983
TEST_TIME 90 8979
TEST_TIME 91 8923
TEST_TIME 92 8871
TEST_TIME 93 8851
TEST_TIME 94 8823
TEST_TIME 95 8760
TEST_TIME 96 8772
TEST_TIME 97 8752
TEST_TIME 98 8765
TEST_TIME 99 8753
TEST_TIME 100 8759
TEST_TIME 101 8770
TEST_TIME 102 8793
TEST_TIME 103 8757
TEST_TIME 104 8839
TEST_TIME 105 8817
TEST_TIME 106 8833
TEST_TIME 107 8879
TEST_TIME 108 8923
TEST_TIME 109 8947
TEST_TIME 110 8946
TEST_TIME 111 8934
TEST_TIME 112 8884
TEST_TIME 113 8884
TEST_TIME 114 8840
TEST_TIME 115 8807
TEST_TIME 116 8796
TEST_TIME 117 8790
TEST_TIME 118 8787
TEST_TIME 119 8812
TEST_TIME 120 8745

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-07-2022 07:39:02 to 24-07-2022 07:39:05.

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 1458231 1458231 0 0.00 0 36780 15 0 0
doc_list_biud 1458216 1458216 0 0.00 0 35484 42 0 0
sp_add_invoice_to_stock 4895 4795 100 2.04 53 242875 29679 0 100
sp_cancel_adding_invoice 5218 5218 0 0.00 5 98359 1255 0 0
sp_cancel_client_order 1679 1679 0 0.00 6 11557 456 0 0
sp_cancel_customer_reserve 514127 514127 0 0.00 1 25053 103 0 0
sp_cancel_pay_from_customer 588 588 0 0.00 4 2039 147 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 4 1671 119 0 0
sp_cancel_supplier_invoice 5749 5739 10 0.17 3 14348 958 0 10
sp_cancel_supplier_order 406 406 0 0.00 7 1390711 166134 0 0
sp_cancel_write_off 8989 8989 0 0.00 1 13056 190 0 0
sp_client_order 19368 19368 0 0.00 5 49731 483 0 0
sp_customer_reserve 450738 450738 0 0.00 0 78728 280 0 0
sp_fill_shopping_cart 29777 29777 0 0.00 1 19462 90 0 0
sp_fill_shopping_cart_clo_res 450705 450705 0 0.00 0 12526 19 0 0
sp_get_clo_for_invoice 4751 4751 0 0.00 0 17110 294 0 0
sp_kill_cost_storno 15187 15187 0 0.00 0 12693 82 0 0
sp_kill_qstorno_ret_qs2qd 1043388 1043388 0 0.00 0 25037 44 0 0
sp_kill_qty_storno 550593 550593 0 0.00 0 35482 102 0 0
sp_lock_selected_doc 19824 19824 0 0.00 0 8279 7 0 0
sp_make_cost_storno 26566 26566 0 0.00 0 36769 80 0 0
sp_multiply_rows_for_pdistr 26565 26565 0 0.00 0 9602 44 0 0
sp_multiply_rows_for_qdistr 19368 19368 0 0.00 0 28410 166 0 0
sp_pay_from_customer 6664 6664 0 0.00 5 33151 194 0 0
sp_pay_to_supplier 5466 5466 0 0.00 7 10144 201 0 0
sp_payment_common 12129 12129 0 0.00 2 32979 51 0 0
sp_qd_handle_on_cancel_clo 1679 1679 0 0.00 0 11545 382 0 0
sp_qd_handle_on_invoice_upd_sts 9969 9969 0 0.00 0 35481 541 0 0
sp_qd_handle_on_reserve_upd_sts 9684 9684 0 0.00 0 13904 71 0 0
sp_reserve_write_off 9687 9687 0 0.00 8 14024 307 0 0
sp_supplier_invoice 5177 5177 0 0.00 8 77940 3297 0 0
sp_supplier_order 5245 5245 0 0.00 7 46283 1433 0 0
srv_aggregate_perf_data 114 114 0 0.00 1985 153034 14510 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 700 109779 33062 0 0
srv_find_qd_qs_mism 997866 997866 0 0.00 0 18901 12 0 0
srv_make_invnt_saldo 469 469 0 0.00 46 161540 4362 0 0
srv_make_money_saldo 889 889 0 0.00 1 11819 390 0 0
srv_recalc_idx_stat 5 5 0 0.00 28808 409963 230661 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4800 4800 0 0.00 1 10645 1010 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 1 5716 478 0 0
t$perf-norm:sp_cancel_client_order 1679 1679 0 0.00 0 4314 38 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 942 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 588 588 0 0.00 0 1668 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 636 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 5015 56 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 93442 7299 0 0
t$perf-norm:sp_cancel_write_off 934 934 0 0.00 0 4071 26 0 0
t$perf-norm:sp_client_order 19369 19369 0 0.00 0 12233 41 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 887 16 0 0
t$perf-norm:sp_pay_from_customer 6664 6664 0 0.00 0 4330 20 0 0
t$perf-norm:sp_pay_to_supplier 5466 5466 0 0.00 0 4665 23 0 0
t$perf-norm:sp_reserve_write_off 9688 9688 0 0.00 0 6581 30 0 0
t$perf-norm:sp_supplier_invoice 5177 5177 0 0.00 0 6287 32 0 0
t$perf-norm:sp_supplier_order 5245 5245 0 0.00 0 6634 33 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 807 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 1430 35 0 0
t$perf-norm:srv_make_invnt_saldo 469 469 0 0.00 0 4770 22 0 0
t$perf-norm:srv_make_money_saldo 889 889 0 0.00 0 5769 14 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 264 68 0 0
v_add_invoice_to_stock 9690 9490 200 2.06 0 7944 25 0 200
v_all_customers 35339 35339 0 0.00 0 132 4 0 0
v_all_suppliers 28778 28778 0 0.00 0 63 5 0 0
v_all_wares 38728 38728 0 0.00 0 71 4 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 1899 20 0 0
v_cancel_client_order 3358 3358 0 0.00 0 3495 12 0 0
v_cancel_customer_prepayment 1176 1176 0 0.00 0 1828 15 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 1926 11 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 1632 15 0 0
v_cancel_supplier_invoice 744 724 20 2.68 0 2492 31 0 20
v_cancel_supplier_order 812 812 0 0.00 0 427 10 0 0
v_cancel_write_off 1868 1868 0 0.00 0 2253 24 0 0
v_max_id_clo_ord 5243 5243 0 0.00 0 1717 6 0 0
v_max_id_clo_res 989 989 0 0.00 0 4682 19 0 0
v_max_id_ord_sup 5169 5169 0 0.00 0 5184 9 0 0
v_max_non_paid_invoice 1489 1489 0 0.00 0 98 7 0 0
v_max_non_paid_realizn 3443 3443 0 0.00 0 32970 19 0 0
v_min_id_clo_ord 5243 5243 0 0.00 0 1801 10 0 0
v_min_id_clo_res 989 989 0 0.00 0 1961 11 0 0
v_min_id_ord_sup 5169 5169 0 0.00 0 7950 15 0 0
v_min_non_paid_invoice 5466 5466 0 0.00 0 4773 20 0 0
v_min_non_paid_realizn 6663 6663 0 0.00 0 6599 19 0 0
v_our_firm 9828 9828 0 0.00 0 109 5 0 0
v_reserve_write_off 19368 19368 0 0.00 0 8716 14 0 0
x_lock_depdocs_on_canc_invoice 10595 10595 0 0.00 0 16481 126 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 4823 377 0 0
x_make_qstorno_1000_1200 5245 5245 0 0.00 2 46265 1256 0 0
x_make_qstorno_1200_2000 5177 5177 0 0.00 4 74414 3037 0 0
x_make_qstorno_2100_3300 450737 450737 0 0.00 0 78727 248 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-07-2022 07:39:06 to 24-07-2022 07:39:10.

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:19 6 8192000000 8809529968 8824745984 13187984 0.160 5 4 5 0 13452576 152048 112544
05:08:20 26246 8192000000 9722928864 10021183488 798714848 8.883 100 89 178 0 828182352 7656256 21811248
05:09:54 14485 8192000000 9762202544 10069114880 833454640 9.234 100 89 176 0 866159968 11478224 21227104
05:11:12 16637 8192000000 9790752896 10108751872 859950928 9.500 100 83 166 0 897288704 17950192 19387584
05:12:35 8878 8192000000 9843350352 10175983616 912807168 10.025 100 70 140 0 953686576 25114064 15765344
05:13:49 6669 8192000000 9894135472 10237681664 953692128 10.427 100 80 158 0 1006419872 35208768 17518976
05:14:59 2835 8192000000 9958069776 10309152768 986016384 10.743 100 95 187 0 1075831824 70589200 19226240
05:16:07 1578 8192000000 9976897200 10335744000 1008414432 10.960 100 85 164 0 1099890096 74633712 16841952
05:17:11 1986 8192000000 9999287264 10366242816 1026496960 11.135 100 87 164 0 1114781840 72626048 15658832
05:18:16 1276 8192000000 9995899616 10365849600 1046208368 11.324 100 94 185 0 1122471680 57881264 18382048
05:19:19 1111 8192000000 10031086512 10406961152 1057032352 11.428 100 94 176 1 1155508560 81168816 17307392
05:20:23 9369 8192000000 10051489328 10424995840 1066315744 11.517 100 83 156 1 1161256128 80939616 14000768
05:21:34 1761 8192000000 10044706000 10431574016 1071771888 11.569 100 97 184 2 1161302864 72067040 17463936
05:22:37 925 8192000000 10049464528 10435940352 1081911440 11.666 100 94 176 0 1168086576 68055232 18119904
05:23:40 1119 8192000000 10052596752 10439344128 1089968464 11.742 100 92 179 1 1172599424 65134432 17496528
05:24:42 1312 8192000000 10049545632 10443026432 1091668016 11.759 100 82 159 0 1172758880 65170352 15920512
05:25:44 672 8192000000 10056121312 10447265792 1093901024 11.780 100 83 157 0 1177230752 67427728 15902000
05:26:45 2225 8192000000 10063877504 10458923008 1095792560 11.798 100 76 145 1 1181406256 70398944 15214752
05:27:49 2574 8192000000 10031920016 10427490304 1098096384 11.820 100 63 120 0 1151132608 41030016 12006208
05:28:52 5701 8192000000 10033247408 10428559360 1100209488 11.840 100 57 109 2 1155806288 44158272 11438528
05:29:59 1666 8192000000 10065333856 10456981504 1087883696 11.723 100 82 151 2 1184092672 80313728 15895248
05:31:02 1213 8192000000 10057974448 10444419072 1082635456 11.673 100 93 170 2 1176443408 75742832 18065120
05:32:04 1296 8192000000 10020832064 10399539200 1067528992 11.528 100 96 184 1 1147206496 59617888 20059616
05:33:07 1291 8192000000 10055470208 10438320128 1079859824 11.646 100 94 182 0 1177449744 78358256 19231664
05:34:11 3618 8192000000 9984780032 10368159744 1024238064 11.113 97 66 129 2 1087995424 49357200 14400160
05:35:15 4009 8192000000 9980983616 10365435904 1029981024 11.168 99 66 124 1 1097012848 53039728 13992096
05:36:20 1309 8192000000 10006685696 10377216000 1030856688 11.177 100 96 183 1 1120418528 70418992 19142848
05:37:25 1615 8192000000 10014867472 10392301568 1055926848 11.417 100 85 152 2 1136797552 66082160 14788544
05:38:32 906 8192000000 10008150544 10387697664 1061893024 11.475 100 91 166 1 1128898032 50702112 16302896
05:39:35 1559 8192000000 10041357840 10416103424 1069455840 11.547 100 88 167 0 1155079168 68961664 16661664
05:40:43 9874 8192000000 10071514320 10462179328 1085491504 11.700 100 67 134 0 1188573792 88731760 14350528
05:42:04 6956 8192000000 10086179616 10480001024 1083931088 11.685 99 68 132 0 1202463600 104409488 14123024
05:43:13 14934 8192000000 10104928208 10502746112 1111225072 11.944 100 60 117 3 1231928128 108657744 12045312
05:44:32 3364 8192000000 10160230384 10565410816 1117039488 11.999 100 75 145 2 1275673488 144174400 14459600
05:45:44 4111 8192000000 10151714496 10549645312 1125997584 12.084 100 69 133 0 1261759616 121902368 13859664
05:46:55 2477 8192000000 10175992672 10570862592 1138046320 12.197 100 86 166 2 1293655664 138183024 17426320
05:48:03 1136 8192000000 10200920736 10596429824 1153233424 12.340 100 88 172 0 1317815472 146174160 18407888
05:49:07 1915 8192000000 10229452816 10632605696 1165032000 12.450 100 76 144 0 1344038448 161721264 17285184
05:50:12 2709 8192000000 10266518496 10677882880 1173653760 12.531 100 87 171 1 1382171504 189241344 19276400
05:51:18 2282 8192000000 10302388128 10711883776 1182741936 12.616 100 79 147 0 1413610736 213926560 16942240
05:52:32 3812 8192000000 10318479568 10742149120 1186471392 12.651 100 79 156 0 1430496976 225589744 18435840
05:53:41 1181 8192000000 10370008032 10773975040 1192362816 12.705 100 94 178 3 1477173728 262502880 22308032
05:54:47 930 8192000000 10384195808 10814038016 1204538896 12.818 100 86 168 1 1499812272 275394112 19879264
05:55:55 773 8192000000 10425123072 10835550208 1210253408 12.871 100 98 188 0 1532769024 300182832 22332784
05:57:02 1264 8192000000 10488116032 10896977920 1208567200 12.856 100 91 180 1 1593809872 363672272 21570400
05:58:09 1056 8192000000 10505455664 10918313984 1213070320 12.898 100 97 189 0 1611807168 376553072 22183776
05:59:17 1625 8192000000 10527514048 10938040320 1207878352 12.849 100 93 176 2 1637020624 407556000 21586272
06:00:21 1680 8192000000 10541585296 10983903232 1206323664 12.835 100 94 185 0 1645572688 416075040 23173984
06:01:25 697 8192000000 10544043280 11000315904 1209147072 12.861 100 97 192 0 1650653264 419313360 22192832
06:02:29 858 8192000000 10543062320 10996662272 1208613664 12.856 100 93 185 1 1646412416 414088496 23710256
06:03:33 4025 8192000000 10546997008 10989236224 1203562592 12.809 100 64 125 0 1649608752 430119616 15926544
06:04:43 10515 8192000000 10535690656 10996805632 1182323184 12.612 99 44 88 0 1640415360 446116464 11975712
06:05:56 9567 8192000000 10536240448 11020591104 1179744512 12.588 100 71 134 1 1650993184 453837696 17410976
06:07:09 9032 8192000000 10505097552 10978717696 1177544032 12.567 100 51 102 0 1612006096 421571392 12890672
06:08:20 34257 8192000000 10294320944 10740137984 1127669568 12.099 97 34 67 0 1420124512 284242144 8212800
06:09:55 22468 8192000000 10276074624 10754625536 1121780672 12.044 97 32 63 0 1386251760 256743584 7727504
06:11:29 44815 8192000000 10242211056 10708000768 1149639888 12.306 100 15 30 0 1352549936 199600784 3309264
06:13:29 34320 8192000000 10073325328 10581544960 1120378096 12.031 99 13 27 0 1209178672 86135072 2665504
06:15:05 6850 8192000000 10106273424 10524729344 1140058000 12.216 100 70 129 1 1205347680 51293792 13995888
06:16:14 109779 8192000000 10064405264 10534932480 1132624128 12.146 99 17 32 0 1162007888 25879216 3504544
06:19:14 38927 8192000000 10063732560 10504548352 1112679712 11.958 97 31 60 1 1138048960 19572208 5797040
06:20:57 15698 8192000000 10167529968 10593591296 1145581648 12.268 100 62 123 1 1250401376 92468944 12350784
06:22:13 59782 8192000000 10129646048 10572537856 1116612816 11.995 98 25 48 1 1232364688 110284736 5467136
06:24:14 32230 8192000000 10107559840 10559401984 1122626848 12.052 99 12 24 0 1198385936 73219744 2539344
06:25:48 58080 8192000000 10092824944 10551832576 1119630112 12.023 98 14 26 0 1175664464 53430256 2604096
06:27:46 73753 8192000000 10107195904 10572357632 1129410480 12.116 99 26 52 0 1200426272 65049600 5966192
06:31:11 54661 8192000000 10067740448 10552979456 1124321568 12.068 100 15 30 0 1206631792 79000624 3309600
06:33:12 21825 8192000000 10059756784 10486624256 1081134400 11.658 96 32 60 1 1119433504 31377056 6922048
06:34:39 53818 8192000000 10085876896 10518634496 1088046592 11.724 97 17 33 1 1145907184 54333088 3527504
06:36:35 49186 8192000000 10128279904 10573287424 1121291984 12.039 99 26 52 0 1227132960 100493808 5347168
06:38:26 71380 8192000000 10110625408 10600185856 1130016768 12.122 99 27 54 0 1246202560 110471856 5713936
06:40:40 39536 8192000000 10132895488 10576154624 1114182352 11.972 98 30 60 0 1217496896 96873792 6440752
06:42:20 70975 8192000000 10096843616 10604589056 1142964448 12.243 100 50 98 1 1241562464 88550288 10047728
06:44:50 30180 8192000000 10127045072 10568740864 1159461792 12.398 100 37 72 0 1225828400 58978688 7387920
06:46:26 48064 8192000000 10147653616 10591940608 1164498096 12.445 100 36 72 1 1244391216 72164368 7728752
06:48:16 61962 8192000000 10109078704 10610733056 1150926752 12.318 99 15 30 0 1220689296 66476224 3286320
06:50:51 53956 8192000000 10081809696 10545053696 1133923184 12.158 98 18 36 1 1174436944 36539840 3973920
06:52:45 41685 8192000000 10068701952 10546237440 1129968544 12.121 98 9 19 0 1180157712 48202640 1986528
06:55:18 56943 8192000000 10103184480 10567684096 1116639264 11.995 96 24 48 0 1188950816 66211712 6099840
06:57:16 53594 8192000000 10152909456 10598453248 1109665888 11.929 96 27 52 0 1192459168 77648976 5144304
06:59:10 52830 8192000000 10099572368 10573320192 1131137024 12.132 99 30 59 0 1191275856 54319888 5818944
07:01:03 24481 8192000000 10092040496 10533564416 1117914560 12.007 98 37 74 0 1176150608 51133120 7102928
07:02:29 7399 8192000000 10112961136 10537283584 1121104496 12.037 100 65 128 1 1211733584 77715648 12913440
07:03:39 23890 8192000000 10129686896 10595893248 1119189392 12.019 99 36 72 1 1228860928 101991504 7680032
07:05:10 64058 8192000000 10095749968 10567757824 1102172400 11.858 98 24 48 0 1194185968 86992544 5021024
07:07:45 91028 8192000000 10083009312 10525204480 1121551584 12.042 100 37 73 0 1177068560 47915600 7601376
07:10:28 39584 8192000000 10096329584 10569801728 1126514464 12.088 99 32 62 0 1208395168 75381952 6498752
07:12:09 86887 8192000000 10083662192 10606067712 1138915408 12.205 100 6 12 0 1209042384 68848432 1278544
07:14:50 60336 8192000000 10090052640 10575740928 1113958032 11.970 97 15 30 0 1172344736 55526064 2860640
07:16:53 64371 8192000000 10119187120 10603020288 1132599920 12.146 99 27 48 0 1231465456 93761440 5104096
07:19:00 53505 8192000000 10106132464 10576326656 1142488128 12.239 99 15 28 0 1200275040 54299168 3487744
07:20:53 35796 8192000000 10116225040 10589675520 1117935120 12.007 97 34 66 0 1190160688 65453376 6772192
07:22:30 59436 8192000000 10120465408 10590687232 1118887504 12.016 98 39 78 0 1198339472 71540208 7911760
07:24:34 72823 8192000000 10126500704 10615283712 1129582976 12.117 100 32 64 1 1262983024 127111328 6288720
07:27:00 51684 8192000000 10095304880 10550910976 1126000736 12.084 99 22 43 0 1162585456 31630640 4954080
07:28:52 106899 8192000000 10081042816 10553630720 1134122848 12.160 100 18 34 1 1178485328 41018608 3343872
07:31:43 29709 8192000000 10101126624 10542682112 1111269536 11.944 98 37 70 2 1175336976 56680912 7386528
07:33:26 49152 8192000000 10107671312 10573885440 1120039840 12.027 99 9 19 0 1205418448 83313056 2065552
07:35:50 66718 8192000000 10054984160 10553282560 1122649120 12.052 99 16 31 0 1206452560 79972784 3830656

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-07-2022 07:39:12 to 24-07-2022 07:39:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

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

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          208338
MON$OLDEST_ACTIVE               209151
MON$OLDEST_SNAPSHOT             209151
MON$NEXT_TRANSACTION            209151
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                       2042080
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             759
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2807 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2807 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2807 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2807 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 ---
:34: 
:35: mscs-test-161.budzdorov.ru    Sun Jul 24 07:37:30 2022
:36:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:37: 
:38: 
:39: mscs-test-161.budzdorov.ru    Sun Jul 24 07:37:30 2022
:40:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:41: 
--- end of diff output ---

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

24.07.22 07:39:32. 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-07-24 07:39:34 No severe PSQL-related problems occured
24.07.22 07:39:36. End of report.