Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.07.2022 13: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 15408680 kB
MemAvailable 31096260 kB
Buffers 0 kB
Cached 16315132 kB
SwapCached 2504 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-20 13:37:17
DTS_END                         2022-07-20 13:37:17.5810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48178
TRN_ID                          224343
ATT_ID                          633
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: 9351.34 24324 1122161
customer order: creation 175.81 496 21097
customer order: refuse 15.00 398 1800
order to supplier: creation 45.68 1324 5482
order to supplier: removal 3.57 150746 428
invoice (draft): creation 46.24 3157 5549
invoice (draft): removal 52.12 951 6254
invoice accept: apply 42.99 27108 5159
invoice accept: cancel 47.03 1184 5643
customer reserve: creation 4016.69 254 482003
customer reserve: removal 4599.02 90 551882
realization accept: apply 86.03 304 10324
realization accept: cancel 88.53 166 10623
payment from customer: creation 60.05 197 7206
payment from customer: removal 5.97 141 716
payment to supplier: creation 49.37 201 5924
payment to supplier: removal 3.49 152 419
service: total inventory turnovers 4.07 4195 488
service: total monetary turnovers 8.62 354 1034
service: aggregate perf. data 1.03 13860 123
service: refresh index statistics 0.06 281202 7

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-07-2022 13:38:56 to 20-07-2022 13:38:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8139
TEST_TIME 2 7724
TEST_TIME 3 6910
TEST_TIME 4 7516
TEST_TIME 5 6639
TEST_TIME 6 6648
TEST_TIME 7 6799
TEST_TIME 8 6686
TEST_TIME 9 6891
TEST_TIME 10 7103
TEST_TIME 11 7329
TEST_TIME 12 7401
TEST_TIME 13 7710
TEST_TIME 14 8069
TEST_TIME 15 8091
TEST_TIME 16 8288
TEST_TIME 17 8606
TEST_TIME 18 8633
TEST_TIME 19 8775
TEST_TIME 20 8823
TEST_TIME 21 8871
TEST_TIME 22 8933
TEST_TIME 23 9232
TEST_TIME 24 9206
TEST_TIME 25 9359
TEST_TIME 26 9618
TEST_TIME 27 9636
TEST_TIME 28 9717
TEST_TIME 29 9757
TEST_TIME 30 10008
TEST_TIME 31 10012
TEST_TIME 32 10257
TEST_TIME 33 10226
TEST_TIME 34 10606
TEST_TIME 35 10935
TEST_TIME 36 11249
TEST_TIME 37 11488
TEST_TIME 38 11695
TEST_TIME 39 11568
TEST_TIME 40 11386
TEST_TIME 41 11375
TEST_TIME 42 11235
TEST_TIME 43 11082
TEST_TIME 44 10913
TEST_TIME 45 10928
TEST_TIME 46 10720
TEST_TIME 47 10607
TEST_TIME 48 10545
TEST_TIME 49 10438
TEST_TIME 50 10441
TEST_TIME 51 10538
TEST_TIME 52 10403
TEST_TIME 53 10372
TEST_TIME 54 10236
TEST_TIME 55 10188
TEST_TIME 56 10055
TEST_TIME 57 10000
TEST_TIME 58 10253
TEST_TIME 59 10125
TEST_TIME 60 10067
TEST_TIME 61 10082
TEST_TIME 62 10038
TEST_TIME 63 9981
TEST_TIME 64 10023
TEST_TIME 65 10085
TEST_TIME 66 10028
TEST_TIME 67 10055
TEST_TIME 68 10106
TEST_TIME 69 10169
TEST_TIME 70 10269
TEST_TIME 71 10220
TEST_TIME 72 10211
TEST_TIME 73 10093
TEST_TIME 74 10002
TEST_TIME 75 10000
TEST_TIME 76 10002
TEST_TIME 77 9920
TEST_TIME 78 9864
TEST_TIME 79 9839
TEST_TIME 80 9843
TEST_TIME 81 9861
TEST_TIME 82 9818
TEST_TIME 83 9773
TEST_TIME 84 9779
TEST_TIME 85 9814
TEST_TIME 86 9837
TEST_TIME 87 9797
TEST_TIME 88 9804
TEST_TIME 89 9796
TEST_TIME 90 9872
TEST_TIME 91 9830
TEST_TIME 92 9749
TEST_TIME 93 9710
TEST_TIME 94 9707
TEST_TIME 95 9635
TEST_TIME 96 9594
TEST_TIME 97 9576
TEST_TIME 98 9567
TEST_TIME 99 9545
TEST_TIME 100 9484
TEST_TIME 101 9437
TEST_TIME 102 9445
TEST_TIME 103 9461
TEST_TIME 104 9425
TEST_TIME 105 9429
TEST_TIME 106 9386
TEST_TIME 107 9403
TEST_TIME 108 9435
TEST_TIME 109 9468
TEST_TIME 110 9470
TEST_TIME 111 9484
TEST_TIME 112 9482
TEST_TIME 113 9459
TEST_TIME 114 9484
TEST_TIME 115 9551
TEST_TIME 116 9528
TEST_TIME 117 9554
TEST_TIME 118 9487
TEST_TIME 119 9444
TEST_TIME 120 9371

Performance per minute, chart


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

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 1564476 1564476 0 0.00 0 17838 14 0 0
doc_list_biud 1564461 1564461 0 0.00 0 30434 38 0 0
sp_add_invoice_to_stock 5271 5159 112 2.12 32 262183 27109 0 112
sp_cancel_adding_invoice 5643 5643 0 0.00 7 60542 1184 0 0
sp_cancel_client_order 1800 1800 0 0.00 5 10695 399 0 0
sp_cancel_customer_reserve 551882 551882 0 0.00 1 29275 91 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 3 5047 142 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 4 5100 153 0 0
sp_cancel_supplier_invoice 6259 6254 5 0.07 3 13350 952 0 5
sp_cancel_supplier_order 428 428 0 0.00 9 1621091 150747 0 0
sp_cancel_write_off 10623 10623 0 0.00 2 18068 167 0 0
sp_client_order 21097 21097 0 0.00 5 32812 496 0 0
sp_customer_reserve 482003 482003 0 0.00 0 98116 255 0 0
sp_fill_shopping_cart 32115 32115 0 0.00 1 15903 88 0 0
sp_fill_shopping_cart_clo_res 481970 481970 0 0.00 0 14687 17 0 0
sp_get_clo_for_invoice 5122 5122 0 0.00 0 20371 250 0 0
sp_kill_cost_storno 17401 17401 0 0.00 0 17828 72 0 0
sp_kill_qstorno_ret_qs2qd 1121069 1121069 0 0.00 0 29268 39 0 0
sp_kill_qty_storno 592073 592073 0 0.00 0 30433 92 0 0
sp_lock_selected_doc 21372 21372 0 0.00 0 8752 7 0 0
sp_make_cost_storno 28575 28575 0 0.00 0 14404 86 0 0
sp_multiply_rows_for_pdistr 28574 28574 0 0.00 0 9041 40 0 0
sp_multiply_rows_for_qdistr 21097 21097 0 0.00 0 10123 181 0 0
sp_pay_from_customer 7206 7206 0 0.00 5 15233 197 0 0
sp_pay_to_supplier 5924 5924 0 0.00 7 9802 202 0 0
sp_payment_common 13130 13130 0 0.00 2 10560 53 0 0
sp_qd_handle_on_cancel_clo 1800 1800 0 0.00 0 10688 312 0 0
sp_qd_handle_on_invoice_upd_sts 10762 10762 0 0.00 0 30432 548 0 0
sp_qd_handle_on_reserve_upd_sts 10324 10324 0 0.00 0 25635 73 0 0
sp_reserve_write_off 10324 10324 0 0.00 7 25712 305 0 0
sp_supplier_invoice 5549 5549 0 0.00 7 102081 3157 0 0
sp_supplier_order 5482 5482 0 0.00 7 38255 1325 0 0
srv_aggregate_perf_data 123 123 0 0.00 2034 147924 13861 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 766 140823 35991 0 0
srv_find_qd_qs_mism 1071155 1071155 0 0.00 0 10296 10 0 0
srv_make_invnt_saldo 488 488 0 0.00 45 62028 4195 0 0
srv_make_money_saldo 1034 1034 0 0.00 1 11875 354 0 0
srv_recalc_idx_stat 7 7 0 0.00 63302 521733 281203 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5163 5163 0 0.00 1 27108 1078 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 2 7023 479 0 0
t$perf-norm:sp_cancel_client_order 1800 1800 0 0.00 0 21096 45 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 2118 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 1627 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 2717 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 2535 47 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 80872 6941 0 0
t$perf-norm:sp_cancel_write_off 1066 1066 0 0.00 0 1843 20 0 0
t$perf-norm:sp_client_order 21097 21097 0 0.00 0 7973 38 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 7371 25 0 0
t$perf-norm:sp_pay_from_customer 7206 7206 0 0.00 0 20413 25 0 0
t$perf-norm:sp_pay_to_supplier 5924 5924 0 0.00 0 7902 21 0 0
t$perf-norm:sp_reserve_write_off 10324 10324 0 0.00 0 8700 25 0 0
t$perf-norm:sp_supplier_invoice 5549 5549 0 0.00 0 3673 32 0 0
t$perf-norm:sp_supplier_order 5482 5482 0 0.00 0 10844 37 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 23488 214 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 333 12 0 0
t$perf-norm:srv_make_invnt_saldo 488 488 0 0.00 0 403 12 0 0
t$perf-norm:srv_make_money_saldo 1034 1034 0 0.00 0 7932 17 0 0
t$perf-norm:srv_recalc_idx_stat 7 7 0 0.00 2 224 70 0 0
v_add_invoice_to_stock 10458 10234 224 2.14 0 9661 28 0 224
v_all_customers 38495 38495 0 0.00 0 110 4 0 0
v_all_suppliers 31114 31114 0 0.00 0 252 5 0 0
v_all_wares 42188 42188 0 0.00 0 109 5 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 1432 18 0 0
v_cancel_client_order 3600 3600 0 0.00 0 2818 12 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 3875 17 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 1 1825 14 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 5041 21 0 0
v_cancel_supplier_invoice 798 788 10 1.25 0 5434 32 0 10
v_cancel_supplier_order 856 856 0 0.00 0 2159 14 0 0
v_cancel_write_off 2132 2132 0 0.00 0 3300 28 0 0
v_max_id_clo_ord 5480 5480 0 0.00 0 2923 7 0 0
v_max_id_clo_res 966 966 0 0.00 0 2465 16 0 0
v_max_id_ord_sup 5541 5541 0 0.00 0 5703 7 0 0
v_max_non_paid_invoice 1387 1387 0 0.00 0 54 9 0 0
v_max_non_paid_realizn 3798 3798 0 0.00 0 3906 9 0 0
v_min_id_clo_ord 5480 5480 0 0.00 0 1657 8 0 0
v_min_id_clo_res 966 966 0 0.00 0 4840 17 0 0
v_min_id_ord_sup 5541 5541 0 0.00 0 4890 11 0 0
v_min_non_paid_invoice 5923 5923 0 0.00 0 9773 16 0 0
v_min_non_paid_realizn 7205 7205 0 0.00 0 10557 24 0 0
v_our_firm 10508 10508 0 0.00 0 59 5 0 0
v_reserve_write_off 20648 20648 0 0.00 0 2817 12 0 0
x_lock_depdocs_on_canc_invoice 11503 11503 0 0.00 0 14214 122 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 13216 364 0 0
x_make_qstorno_1000_1200 5482 5482 0 0.00 3 38241 1141 0 0
x_make_qstorno_1200_2000 5549 5549 0 0.00 3 92570 2925 0 0
x_make_qstorno_2100_3300 482002 482002 0 0.00 0 98105 223 0 0

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

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:21 3 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:21 9244 8192000000 9690551024 9979645952 771503360 8.607 100 90 180 0 800949088 6985920 22459808
11:09:33 17018 8192000000 9734077568 10034794496 807053152 8.968 100 87 174 0 838896240 10653120 21189968
11:10:56 16979 8192000000 9770332288 10081665024 838648192 9.286 100 85 170 0 877616464 18874528 20093744
11:12:17 14546 8192000000 9838978544 10167717888 898428016 9.883 100 79 153 0 947185632 32300672 16456944
11:13:37 7060 8192000000 9901275168 10245640192 946154576 10.353 100 76 152 1 1017821968 55683152 15984240
11:14:48 3278 8192000000 9961238656 10313994240 988260064 10.765 100 85 170 1 1072545152 66679840 17605248
11:15:54 1513 8192000000 10003098160 10366578688 1015861888 11.032 100 86 165 2 1120184400 87361840 16960672
11:16:57 823 8192000000 10025176992 10390704128 1044394544 11.307 100 100 196 3 1147304192 82688784 20220864
11:17:59 1003 8192000000 10024073312 10394103808 1058212160 11.439 100 93 184 0 1142083056 65425296 18445600
11:19:02 896 8192000000 10048110896 10423414784 1065741424 11.511 100 94 182 5 1172064000 87812016 18510560
11:20:05 1071 8192000000 10038394352 10417225728 1070745840 11.559 100 91 173 4 1160537552 72735712 17056000
11:21:10 1242 8192000000 10067783888 10448957440 1082710656 11.673 100 98 191 2 1188498992 86906304 18882032
11:22:14 1361 8192000000 10073963648 10459779072 1093213840 11.773 100 96 185 0 1200317488 89105472 17998176
11:23:17 1118 8192000000 10075824400 10463277056 1092092608 11.763 100 94 180 1 1195205424 85066992 18045824
11:24:20 920 8192000000 10060782544 10448314368 1100327936 11.841 100 90 175 2 1181712128 63954688 17429504
11:25:21 1196 8192000000 10073027232 10457796608 1099560416 11.833 100 95 184 0 1195722688 77830832 18331440
11:26:24 1081 8192000000 10066619088 10457382912 1103297952 11.869 100 91 172 0 1188180384 67585104 17297328
11:27:27 880 8192000000 10070279056 10465026048 1109051952 11.923 100 86 170 0 1195979584 69003248 17924384
11:28:29 1301 8192000000 10044461072 10431766528 1087200672 11.716 100 94 178 1 1164468400 59859216 17408512
11:29:31 19056 8192000000 10035227728 10432356352 1084126016 11.687 99 60 119 0 1154066976 58477056 11463904
11:30:52 1143 8192000000 10033268544 10417651712 1071686032 11.568 100 82 147 2 1151847872 64765760 15396080
11:31:54 3648 8192000000 10001413264 10392752128 1064129568 11.496 100 64 122 1 1126777520 50507088 12140864
11:33:00 2960 8192000000 9999946624 10382127104 1038319056 11.249 99 74 133 1 1118417376 66466816 13631504
11:34:04 3069 8192000000 9988430784 10371293184 1045645920 11.319 100 79 149 4 1115291952 52752160 16893872
11:35:08 1172 8192000000 9919077712 10273034240 973031488 10.616 97 93 180 0 1040728816 48899776 18797552
11:36:10 855 8192000000 9984428960 10357100544 1031432544 11.182 100 90 171 0 1105564912 57431904 16700464
11:37:12 2626 8192000000 9975022288 10350583808 1037172304 11.237 99 84 153 1 1096417024 44580224 14664496
11:38:23 22167 8192000000 10027381136 10412441600 1066379296 11.517 100 79 152 0 1155651840 74566128 14706416
11:39:49 1402 8192000000 10064580704 10452303872 1095737744 11.797 100 84 161 2 1190542976 77646304 17158928
11:40:54 1045 8192000000 10112332240 10496716800 1108417904 11.917 100 94 182 1 1229014208 101076320 19519984
11:41:58 5183 8192000000 10102496368 10493157376 1113530832 11.966 100 73 146 0 1223841056 94245120 16065104
11:43:07 1950 8192000000 10142505776 10537066496 1122705280 12.053 100 82 155 0 1258538640 117927344 17906016
11:44:12 1581 8192000000 10179103264 10584457216 1134134736 12.160 100 94 173 0 1291759328 139064704 18559888
11:45:19 2968 8192000000 10199938032 10604003328 1143767920 12.251 100 80 160 0 1315131600 153699808 17663872
11:46:26 1153 8192000000 10230230160 10625331200 1152313632 12.331 100 92 173 1 1343148992 172074912 18760448
11:47:32 1171 8192000000 10272340816 10674065408 1164785120 12.448 100 97 185 0 1386731456 201422720 20523616
11:48:36 840 8192000000 10267302736 10674380800 1170430704 12.501 100 93 176 0 1378296288 188968224 18897360
11:49:39 894 8192000000 10284497440 10712489984 1177947008 12.571 100 95 179 0 1397053888 198722432 20384448
11:50:43 1030 8192000000 10296795376 10718580736 1168447040 12.482 100 96 187 1 1405002368 214801264 21754064
11:51:46 1968 8192000000 10314846352 10721165312 1161158640 12.414 100 82 158 0 1423137584 243491456 18487488
11:52:56 920 8192000000 10285470320 10707914752 1161947664 12.422 100 96 189 1 1397153824 213827776 21378384
11:53:59 948 8192000000 10337159152 10755813376 1176020624 12.553 100 97 192 0 1442196768 243543296 22632848
11:55:03 923 8192000000 10333241712 10740948992 1163294240 12.434 99 97 186 0 1442366320 258466000 20606080
11:56:08 948 8192000000 10353061616 10772082688 1171038352 12.507 100 94 181 1 1457611840 265865232 20708256
11:57:13 989 8192000000 10349313664 10763038720 1174278944 12.537 100 95 179 1 1454418208 259426656 20712608
11:58:17 849 8192000000 10365528688 10792484864 1170891760 12.505 100 97 187 3 1470416000 277091696 22432544
11:59:20 752 8192000000 10377408144 10820059136 1161289088 12.415 100 93 179 1 1482089264 297941888 22858288
12:00:24 1480 8192000000 10442008000 10865422336 1174641360 12.540 100 81 156 1 1547743744 353136496 19965888
12:01:30 1552 8192000000 10413291872 10848366592 1172057408 12.516 100 70 139 2 1521469120 331346608 18065104
12:02:36 2197 8192000000 10400181280 10858913792 1170058624 12.497 100 61 108 1 1502017136 319160112 12798400
12:03:40 811 8192000000 10414223360 10848817152 1162449344 12.426 100 97 184 0 1517930304 333200048 22280912
12:04:45 6260 8192000000 10424516896 10851299328 1160634368 12.409 100 51 102 0 1532837536 359658624 12544544
12:05:52 7842 8192000000 10405807968 10865762304 1150588688 12.315 100 46 92 0 1509265632 347146784 11530160
12:07:17 1807 8192000000 10423907792 10882547712 1160586864 12.409 100 55 110 0 1524377936 350862880 12928192
12:08:34 23595 8192000000 10443693648 10893828096 1158003040 12.385 99 34 66 0 1529791632 363330432 8458160
12:10:05 37851 8192000000 10414019760 10896928768 1172812688 12.523 100 28 56 0 1533176320 353181488 7182144
12:11:44 19195 8192000000 10333980272 10812358656 1177907664 12.571 100 36 70 1 1485305184 298904720 8492800
12:13:06 25800 8192000000 10289415248 10774077440 1150745040 12.316 99 20 40 0 1401552080 245772000 5035040
12:14:32 100235 8192000000 10048106048 10487205888 1092551632 11.767 95 12 22 0 1152737072 57994064 2191376
12:17:41 30657 8192000000 10125554096 10565054464 1138653952 12.203 99 61 118 2 1198607600 46736800 13216848
12:19:17 97318 8192000000 10138407760 10599141376 1138255792 12.199 99 38 77 0 1205838416 59738400 7844224
12:22:16 103415 8192000000 10097313408 10518605824 1133112288 12.151 99 55 97 1 1193003664 49182272 10709104
12:25:00 101620 8192000000 10148965408 10587504640 1144569776 12.258 99 40 80 0 1253980624 100899872 8510976
12:27:43 863 8192000000 10121307408 10545471488 1144825968 12.261 100 84 162 3 1218644960 55461760 18357232
12:28:45 105743 8192000000 10090247024 10564390912 1090800176 11.750 95 12 24 0 1114471840 21147744 2523920
12:31:32 84952 8192000000 10161840960 10649174016 1143949568 12.253 99 15 30 0 1232706176 85622128 3134480
12:35:05 59589 8192000000 10096429536 10634088448 1138873936 12.205 99 16 30 1 1257512672 115216832 3421904
12:37:06 47232 8192000000 10107654784 10578354176 1154518976 12.352 100 17 34 0 1223361104 65327792 3514336
12:38:54 39168 8192000000 10117617712 10585739264 1140850032 12.224 99 12 24 1 1203140672 59739936 2550704
12:40:34 27167 8192000000 10153739600 10597851136 1160958752 12.412 100 56 105 0 1261230208 87846784 12424672
12:42:04 37130 8192000000 10091245568 10575020032 1138795776 12.204 99 18 36 1 1191572032 48704560 4071696
12:43:46 17758 8192000000 10136078240 10587111424 1137417456 12.191 99 39 76 0 1225146144 79656048 8072640
12:45:06 64746 8192000000 10145608656 10620289024 1101216912 11.849 96 15 30 0 1207301792 102995984 3088896
12:47:17 93837 8192000000 10104952528 10596741120 1111350032 11.945 97 14 28 0 1185541408 71788032 2403344
12:49:51 119204 8192000000 10110443120 10585407488 1138971824 12.206 99 24 46 1 1208997216 65495968 4529424
12:53:35 56278 8192000000 10061080944 10506235904 1123327680 12.058 98 7 12 1 1163316496 39180912 807904
12:55:32 29212 8192000000 10092885232 10531164160 1137843600 12.195 99 31 59 1 1187894976 43879984 6171392
12:57:05 35497 8192000000 10098356560 10568736768 1146588048 12.277 100 29 58 0 1218670352 66634256 5448048
13:00:15 27342 8192000000 10088166848 10516316160 1121492368 12.041 99 55 106 1 1165942592 32802320 11647904
13:01:43 36769 8192000000 10136729872 10621005824 1099701808 11.835 97 34 68 1 1194107504 87784448 6621248
13:03:22 72016 8192000000 10065721104 10509254656 1104561824 11.881 98 23 47 0 1158923776 49661632 4700320
13:05:38 47550 8192000000 10135107680 10605477888 1129572064 12.117 99 38 76 1 1197450320 60499808 7378448
13:07:28 82205 8192000000 10090721040 10523410432 1097472816 11.814 96 21 42 0 1131276752 29404048 4399888
13:09:51 120577 8192000000 10068329584 10574622720 1087251536 11.717 95 41 81 1 1145905328 50198384 8455408
13:12:52 33297 8192000000 10125585968 10559246336 1143682400 12.250 100 62 123 0 1202175904 44680080 13813424
13:14:27 84182 8192000000 10104110080 10574495744 1123495280 12.060 98 27 55 0 1210050960 80752784 5802896
13:17:05 71682 8192000000 10111463552 10561409024 1103117280 11.867 97 11 22 0 1174792960 69546672 2129008
13:19:17 73201 8192000000 10050547184 10501079040 1119916080 12.026 99 18 34 0 1185735568 62230640 3588848
13:22:29 96838 8192000000 10126708064 10567045120 1086685328 11.711 96 22 44 0 1174079200 83031616 4362256
13:25:06 43626 8192000000 10120084080 10604253184 1141523120 12.230 100 16 32 0 1252476096 107424480 3528496
13:26:57 24743 8192000000 10075835312 10512338944 1133654304 12.156 99 30 57 3 1202768080 63945488 5168288
13:28:24 17810 8192000000 10125033440 10568466432 1151601840 12.325 100 49 98 1 1230934416 69627344 9705232
13:29:44 16804 8192000000 10104417008 10541674496 1123233568 12.058 98 40 80 0 1183207872 52223536 7750768
13:31:07 15361 8192000000 10142532400 10589261824 1128868080 12.111 98 60 118 0 1211545136 70790848 11886208
13:32:42 48597 8192000000 10097133776 10527678464 1109344080 11.926 97 23 44 1 1167308784 53915328 4049376
13:34:34 140820 8192000000 10067410256 10503733248 1099793776 11.836 97 35 70 0 1179622128 73057648 6770704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 20-07-2022 13:39:10 to 20-07-2022 13:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

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

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          221331
MON$OLDEST_ACTIVE               224787
MON$OLDEST_SNAPSHOT             224787
MON$NEXT_TRANSACTION            224787
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                       2113216
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2799 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2799 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2799 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2799 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 ---
:204: 
:205: mscs-test-161.budzdorov.ru    Wed Jul 20 13:37:25 2022
:206:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:207: 
:208: 
:209: mscs-test-161.budzdorov.ru    Wed Jul 20 13:37:35 2022
:210:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:211: 
--- end of diff output ---

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

20.07.22 13:39:30. 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-20 13:39:32 No severe PSQL-related problems occured
20.07.22 13:39:34. End of report.