Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.10.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 15269332 kB
MemAvailable 30546096 kB
Buffers 0 kB
Cached 16467960 kB
SwapCached 2080 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-13 13:37:19
DTS_END                         2022-10-13 13:37:19.3980
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/38510
TRN_ID                          230233
ATT_ID                          657
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: 9058.73 23303 1087047
customer order: creation 177.98 455 21358
customer order: refuse 15.08 375 1810
order to supplier: creation 47.28 1300 5674
order to supplier: removal 3.52 163509 422
invoice (draft): creation 47.98 2937 5757
invoice (draft): removal 48.63 1054 5835
invoice accept: apply 43.67 26140 5240
invoice accept: cancel 43.58 1359 5230
customer reserve: creation 4003.10 252 480372
customer reserve: removal 4328.85 104 519462
realization accept: apply 87.27 283 10472
realization accept: cancel 77.79 179 9335
payment from customer: creation 61.07 160 7328
payment from customer: removal 5.67 131 680
payment to supplier: creation 50.68 176 6082
payment to supplier: removal 3.42 128 410
service: total inventory turnovers 4.44 3654 533
service: total monetary turnovers 7.63 355 916
service: aggregate perf. data 1.05 14348 126
service: refresh index statistics 0.04 249168 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-10-2022 13:38:50 to 13-10-2022 13:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9845
TEST_TIME 2 7663
TEST_TIME 3 6778
TEST_TIME 4 7313
TEST_TIME 5 7008
TEST_TIME 6 6770
TEST_TIME 7 6962
TEST_TIME 8 6702
TEST_TIME 9 6575
TEST_TIME 10 6588
TEST_TIME 11 6870
TEST_TIME 12 6851
TEST_TIME 13 6939
TEST_TIME 14 7220
TEST_TIME 15 7414
TEST_TIME 16 7624
TEST_TIME 17 7532
TEST_TIME 18 7787
TEST_TIME 19 7744
TEST_TIME 20 7962
TEST_TIME 21 7886
TEST_TIME 22 7948
TEST_TIME 23 8183
TEST_TIME 24 8562
TEST_TIME 25 8452
TEST_TIME 26 8487
TEST_TIME 27 8722
TEST_TIME 28 8835
TEST_TIME 29 8952
TEST_TIME 30 9051
TEST_TIME 31 9245
TEST_TIME 32 9688
TEST_TIME 33 11151
TEST_TIME 34 11215
TEST_TIME 35 11166
TEST_TIME 36 10975
TEST_TIME 37 10899
TEST_TIME 38 10744
TEST_TIME 39 10605
TEST_TIME 40 10563
TEST_TIME 41 10420
TEST_TIME 42 10261
TEST_TIME 43 10243
TEST_TIME 44 10147
TEST_TIME 45 10066
TEST_TIME 46 10176
TEST_TIME 47 10148
TEST_TIME 48 10102
TEST_TIME 49 10064
TEST_TIME 50 9901
TEST_TIME 51 9838
TEST_TIME 52 9773
TEST_TIME 53 9873
TEST_TIME 54 9872
TEST_TIME 55 9865
TEST_TIME 56 9866
TEST_TIME 57 9894
TEST_TIME 58 9883
TEST_TIME 59 9799
TEST_TIME 60 9843
TEST_TIME 61 9909
TEST_TIME 62 9961
TEST_TIME 63 9928
TEST_TIME 64 9942
TEST_TIME 65 10013
TEST_TIME 66 9929
TEST_TIME 67 9845
TEST_TIME 68 9838
TEST_TIME 69 9829
TEST_TIME 70 9778
TEST_TIME 71 9710
TEST_TIME 72 9641
TEST_TIME 73 9536
TEST_TIME 74 9540
TEST_TIME 75 9486
TEST_TIME 76 9453
TEST_TIME 77 9488
TEST_TIME 78 9440
TEST_TIME 79 9417
TEST_TIME 80 9425
TEST_TIME 81 9462
TEST_TIME 82 9408
TEST_TIME 83 9444
TEST_TIME 84 9497
TEST_TIME 85 9529
TEST_TIME 86 9551
TEST_TIME 87 9536
TEST_TIME 88 9504
TEST_TIME 89 9425
TEST_TIME 90 9448
TEST_TIME 91 9410
TEST_TIME 92 9399
TEST_TIME 93 9336
TEST_TIME 94 9304
TEST_TIME 95 9259
TEST_TIME 96 9288
TEST_TIME 97 9302
TEST_TIME 98 9263
TEST_TIME 99 9282
TEST_TIME 100 9294
TEST_TIME 101 9281
TEST_TIME 102 9313
TEST_TIME 103 9347
TEST_TIME 104 9393
TEST_TIME 105 9436
TEST_TIME 106 9400
TEST_TIME 107 9445
TEST_TIME 108 9388
TEST_TIME 109 9318
TEST_TIME 110 9311
TEST_TIME 111 9282
TEST_TIME 112 9240
TEST_TIME 113 9228
TEST_TIME 114 9174
TEST_TIME 115 9190
TEST_TIME 116 9175
TEST_TIME 117 9162
TEST_TIME 118 9152
TEST_TIME 119 9142
TEST_TIME 120 9084

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-10-2022 13:38:53 to 13-10-2022 13:38:57.

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 1530200 1530200 0 0.00 0 17517 14 0 0
doc_list_biud 1530182 1530182 0 0.00 0 68717 41 0 0
sp_add_invoice_to_stock 5366 5240 126 2.34 52 223885 26140 0 126
sp_cancel_adding_invoice 5230 5230 0 0.00 6 98877 1359 0 0
sp_cancel_client_order 1810 1810 0 0.00 5 14178 376 0 0
sp_cancel_customer_reserve 519462 519462 0 0.00 1 28517 105 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 2858 132 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 4 6051 129 0 0
sp_cancel_supplier_invoice 5843 5835 8 0.13 2 68745 1054 0 8
sp_cancel_supplier_order 422 422 0 0.00 7 1442599 163510 0 0
sp_cancel_write_off 9335 9335 0 0.00 2 15681 180 0 0
sp_client_order 21358 21358 0 0.00 5 26381 456 0 0
sp_customer_reserve 480372 480372 0 0.00 0 104013 253 0 0
sp_fill_shopping_cart 32779 32779 0 0.00 1 37518 80 0 0
sp_fill_shopping_cart_clo_res 480332 480332 0 0.00 0 24618 19 0 0
sp_get_clo_for_invoice 5192 5192 0 0.00 0 23822 273 0 0
sp_kill_cost_storno 15655 15655 0 0.00 0 17509 76 0 0
sp_kill_qstorno_ret_qs2qd 1054516 1054516 0 0.00 0 68711 45 0 0
sp_kill_qty_storno 557754 557754 0 0.00 0 68714 104 0 0
sp_lock_selected_doc 21554 21554 0 0.00 0 6738 9 0 0
sp_make_cost_storno 29072 29072 0 0.00 0 16709 72 0 0
sp_multiply_rows_for_pdistr 29069 29069 0 0.00 0 11201 37 0 0
sp_multiply_rows_for_qdistr 21355 21355 0 0.00 0 10863 176 0 0
sp_pay_from_customer 7328 7328 0 0.00 5 11379 160 0 0
sp_pay_to_supplier 6082 6082 0 0.00 6 16720 176 0 0
sp_payment_common 13409 13409 0 0.00 2 7926 42 0 0
sp_qd_handle_on_cancel_clo 1810 1810 0 0.00 0 14170 304 0 0
sp_qd_handle_on_invoice_upd_sts 10420 10420 0 0.00 0 26471 527 0 0
sp_qd_handle_on_reserve_upd_sts 10470 10470 0 0.00 0 26797 74 0 0
sp_reserve_write_off 10472 10472 0 0.00 8 26991 284 0 0
sp_supplier_invoice 5757 5757 0 0.00 8 84609 2938 0 0
sp_supplier_order 5674 5674 0 0.00 7 47877 1301 0 0
srv_aggregate_perf_data 126 126 0 0.00 2375 127724 14349 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 748 118135 32712 0 0
srv_find_qd_qs_mism 1036736 1036736 0 0.00 0 11302 12 0 0
srv_make_invnt_saldo 533 533 0 0.00 50 68654 3655 0 0
srv_make_money_saldo 916 916 0 0.00 1 16531 355 0 0
srv_recalc_idx_stat 5 5 0 0.00 47773 489085 249169 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 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5245 5245 0 0.00 1 16352 916 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 1 6474 417 0 0
t$perf-norm:sp_cancel_client_order 1810 1810 0 0.00 0 5351 30 0 0
t$perf-norm:sp_cancel_customer_reserve 730 730 0 0.00 0 1620 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 2287 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 803 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 706 29 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 94506 7042 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 557 17 0 0
t$perf-norm:sp_client_order 21359 21359 0 0.00 0 8350 35 0 0
t$perf-norm:sp_customer_reserve 1057 1057 0 0.00 0 862 13 0 0
t$perf-norm:sp_pay_from_customer 7328 7328 0 0.00 0 7538 20 0 0
t$perf-norm:sp_pay_to_supplier 6082 6082 0 0.00 0 4073 18 0 0
t$perf-norm:sp_reserve_write_off 10473 10473 0 0.00 0 6134 21 0 0
t$perf-norm:sp_supplier_invoice 5757 5757 0 0.00 0 7017 30 0 0
t$perf-norm:sp_supplier_order 5674 5674 0 0.00 0 5732 31 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 779 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 838 18 0 0
t$perf-norm:srv_make_invnt_saldo 533 533 0 0.00 0 653 13 0 0
t$perf-norm:srv_make_money_saldo 916 916 0 0.00 0 158 3 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 12 204 96 0 0
v_add_invoice_to_stock 10618 10366 252 2.37 0 5544 25 0 252
v_all_customers 38944 38944 0 0.00 0 272 4 0 0
v_all_suppliers 31880 31880 0 0.00 0 99 4 0 0
v_all_wares 42706 42706 0 0.00 0 98 4 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 1900 21 0 0
v_cancel_client_order 3620 3620 0 0.00 0 1540 9 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 1732 17 0 0
v_cancel_customer_reserve 1460 1460 0 0.00 0 3398 16 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 6017 21 0 0
v_cancel_supplier_invoice 810 794 16 1.97 0 398 20 0 16
v_cancel_supplier_order 844 844 0 0.00 0 868 12 0 0
v_cancel_write_off 2024 2024 0 0.00 0 2526 24 0 0
v_max_id_clo_ord 5672 5672 0 0.00 0 3346 6 0 0
v_max_id_clo_res 1057 1057 0 0.00 0 1675 17 0 0
v_max_id_ord_sup 5753 5753 0 0.00 0 6896 8 0 0
v_max_non_paid_invoice 1567 1567 0 0.00 0 384 8 0 0
v_max_non_paid_realizn 3847 3847 0 0.00 0 3806 10 0 0
v_min_id_clo_ord 5672 5672 0 0.00 0 4403 8 0 0
v_min_id_clo_res 1057 1057 0 0.00 0 1464 9 0 0
v_min_id_ord_sup 5753 5753 0 0.00 0 8147 12 0 0
v_min_non_paid_invoice 6082 6082 0 0.00 0 7921 11 0 0
v_min_non_paid_realizn 7327 7327 0 0.00 0 7170 12 0 0
v_our_firm 10722 10722 0 0.00 0 103 5 0 0
v_reserve_write_off 20940 20940 0 0.00 0 3473 11 0 0
x_lock_depdocs_on_canc_invoice 10668 10668 0 0.00 0 10937 134 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 8704 367 0 0
x_make_qstorno_1000_1200 5674 5674 0 0.00 2 40900 1130 0 0
x_make_qstorno_1200_2000 5757 5757 0 0.00 4 84486 2737 0 0
x_make_qstorno_2100_3300 480371 480371 0 0.00 0 104009 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-10-2022 13:38:58 to 13-10-2022 13:39:02.

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:23 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:24 28310 8192000000 9716142768 10012946432 789584512 8.791 100 91 181 0 819686864 7856336 22246016
11:09:57 11149 8192000000 9752482208 10060902400 829811168 9.197 100 86 172 0 863387808 12467968 21108672
11:11:27 16388 8192000000 9811023776 10133168128 873740528 9.637 100 82 164 0 915649584 23446176 18462880
11:12:46 8317 8192000000 9861440256 10195025920 916816096 10.065 100 81 157 0 972493168 38486384 17190688
11:14:01 2928 8192000000 9931212304 10275618816 955129504 10.441 100 82 158 1 1047761568 75809888 16822176
11:15:07 1206 8192000000 9950933088 10302279680 986596272 10.748 100 91 176 0 1067289472 62419456 18273744
11:16:11 3517 8192000000 9991287664 10351988736 1013636240 11.011 100 79 150 2 1105943088 77398176 14908672
11:17:17 1090 8192000000 10019098272 10392756224 1046600880 11.328 100 94 182 0 1136462064 71043008 18818176
11:18:20 1288 8192000000 10042702400 10418266112 1057554256 11.433 100 92 180 2 1161457792 86365616 17537920
11:19:23 1061 8192000000 10048945728 10431848448 1071267296 11.564 100 93 178 2 1169440336 79539792 18633248
11:20:25 1006 8192000000 10017779536 10399617024 1075241328 11.602 100 90 178 2 1142163600 49025328 17896944
11:21:27 975 8192000000 10049034304 10433691648 1079072176 11.639 100 98 189 0 1171089040 71896272 20120592
11:22:29 4474 8192000000 10056813040 10435002368 1081973232 11.666 100 91 169 2 1171207872 72995200 16239440
11:23:34 1808 8192000000 10050411376 10435858432 1094015456 11.781 100 87 158 2 1173165456 62619936 16530064
11:24:37 4905 8192000000 10039364528 10430676992 1093526240 11.776 100 74 134 2 1162034352 55229376 13278736
11:25:43 1035 8192000000 10052548176 10443251712 1096279328 11.802 100 91 178 1 1174532400 59325456 18927616
11:26:46 1153 8192000000 10060490144 10451894272 1096196608 11.802 100 91 167 3 1182715632 68476000 18043024
11:27:48 1338 8192000000 10076906736 10469007360 1091386448 11.756 100 93 181 0 1195640912 85584096 18670368
11:28:50 1121 8192000000 10051074192 10436857856 1087355840 11.718 100 89 170 3 1170307344 65784928 17166576
11:29:52 1192 8192000000 10021952160 10398601216 1055871840 11.417 99 87 164 1 1142390464 69153504 17365120
11:30:54 1260 8192000000 10047038800 10430738432 1075963760 11.609 100 90 169 1 1172266960 78620592 17682608
11:31:57 1091 8192000000 10014099312 10394447872 1055690320 11.415 100 94 182 2 1136741296 61328112 19722864
11:32:59 1125 8192000000 10017582720 10397929472 1054033904 11.399 100 95 171 3 1140173456 67083904 19055648
11:34:01 4143 8192000000 9961279456 10339893248 1030468944 11.173 100 65 125 0 1100003920 56945856 12589120
11:35:06 1072 8192000000 9983201472 10355638272 1027334304 11.143 100 92 173 1 1104647152 59528400 17784448
11:36:08 1078 8192000000 10015032576 10395070464 1053243584 11.392 100 95 179 4 1136476416 63070208 20162624
11:37:11 1168 8192000000 9995213248 10374094848 1034155536 11.208 99 74 137 1 1112732464 64345088 14231840
11:38:19 37757 8192000000 10019961552 10412457984 1063746944 11.492 99 59 114 0 1145698432 70736048 11215440
11:40:01 1833 8192000000 10047047872 10434543616 1093457024 11.776 100 75 130 0 1157627408 51818320 12352064
11:41:07 2586 8192000000 10093350976 10477416448 1096886128 11.808 99 86 162 1 1206026448 90995728 18144592
11:42:12 4859 8192000000 10110698640 10499313664 1117304928 12.002 100 78 155 0 1224158640 90837856 16015856
11:43:21 2375 8192000000 10151949008 10545238016 1125334992 12.077 100 80 151 1 1265713040 123488608 16889440
11:44:36 1702 8192000000 10167960256 10559045632 1127336544 12.096 100 81 151 1 1279470480 136551280 15582656
11:45:49 14394 8192000000 10184242960 10580193280 1138139168 12.198 100 79 151 0 1297729520 143193664 16396688
11:47:11 15677 8192000000 10208286688 10612842496 1144934816 12.262 100 70 140 0 1329866480 169831776 15099888
11:48:29 893 8192000000 10264873840 10663366656 1156022432 12.366 100 96 190 2 1380626688 202570800 22033456
11:49:33 892 8192000000 10286874144 10692853760 1165850496 12.458 100 90 169 2 1402368288 214715440 21802352
11:50:38 4003 8192000000 10320940880 10732724224 1167879360 12.477 100 90 174 1 1426538576 239483216 19176000
11:51:48 2383 8192000000 10336391424 10742915072 1177949040 12.571 100 86 160 1 1449205216 252833312 18422864
11:52:54 884 8192000000 10358068336 10773647360 1180052352 12.591 100 95 182 1 1468030752 265018608 22959792
11:53:57 766 8192000000 10377848368 10795212800 1179488224 12.585 100 95 182 1 1484169520 282934192 21747104
11:55:02 793 8192000000 10412833632 10836996096 1188124896 12.666 100 94 182 0 1520323280 310609040 21589344
11:56:08 978 8192000000 10424767376 10850054144 1193626288 12.717 100 91 181 0 1530066656 315050384 21389984
11:57:12 742 8192000000 10468258160 10888777728 1197338672 12.752 100 97 193 5 1575611776 355052368 23220736
11:58:17 1523 8192000000 10508997728 10916257792 1192696256 12.708 100 94 181 1 1610431600 395743360 21991984
11:59:23 1156 8192000000 10532860496 10960867328 1179586416 12.586 100 94 181 1 1636730576 435295952 21848208
12:00:27 1260 8192000000 10497702336 10989432832 1184949472 12.636 100 98 194 0 1601500544 392537952 24013120
12:01:30 813 8192000000 10502391152 10926784512 1184234192 12.630 100 84 165 1 1610619136 406868384 19516560
12:02:34 11148 8192000000 10535572768 10973380608 1171970512 12.515 99 58 116 0 1645419584 458468464 14980608
12:03:59 11253 8192000000 10586479296 11039805440 1190686976 12.690 100 60 118 0 1687530160 481288624 15554560
12:05:12 3372 8192000000 10557776800 11001901056 1185153888 12.638 100 55 109 0 1658438016 459818112 13466016
12:06:22 24086 8192000000 10576692208 11026796544 1161539152 12.418 98 34 68 0 1668251296 497681600 9030544
12:07:48 22027 8192000000 10520575888 11001999360 1151438736 12.323 100 37 74 0 1680259952 518527648 10293568
12:09:11 15584 8192000000 10421182688 10917572608 1143232800 12.246 100 32 64 0 1548314960 396169472 8912688
12:10:59 69580 8192000000 10050223040 10496098304 1098009792 11.819 96 18 34 0 1134719168 33182176 3527200
12:13:14 27083 8192000000 10065014672 10482978816 1112118912 11.952 99 44 82 2 1145711424 24493776 9098736
12:14:44 59031 8192000000 10094108336 10548191232 1126816928 12.091 99 14 25 0 1196639376 67226576 2595872
12:17:21 44002 8192000000 10085675968 10517946368 1135867344 12.177 99 47 80 2 1172421552 27710928 8843280
12:19:07 55920 8192000000 10105760352 10534334464 1109495744 11.928 96 52 93 2 1171474304 52331184 9647376
12:21:03 13890 8192000000 10138436864 10583207936 1155091600 12.357 100 35 66 2 1234769056 71953216 7724240
12:22:26 17164 8192000000 10159382272 10615914496 1134181728 12.161 98 52 92 1 1245337520 100477712 10678080
12:23:46 31488 8192000000 10110493904 10571255808 1134563136 12.164 99 23 46 0 1210015168 70937472 4514560
12:25:18 46234 8192000000 10099424560 10568835072 1130452880 12.126 99 12 24 0 1204858080 71536000 2869200
12:27:06 91572 8192000000 10116416000 10573705216 1132802960 12.148 99 20 40 0 1189296672 52241632 4252080
12:29:41 71612 8192000000 10104763344 10581471232 1152357840 12.332 100 19 38 1 1243616096 86714640 4543616
12:31:58 25993 8192000000 10100239488 10533400576 1134392336 12.163 99 42 84 1 1202792464 59343952 9056176
12:33:25 20828 8192000000 10101155264 10540744704 1136394352 12.182 99 36 72 0 1192678224 48917776 7366096
12:34:53 51633 8192000000 10116401984 10554580992 1102241568 11.859 97 25 49 0 1171725616 64575296 4908752
12:36:50 118134 8192000000 10104004304 10580934656 1110250304 11.935 97 22 42 0 1172110320 57837280 4022736
12:39:53 43811 8192000000 10101510240 10540015616 1129574256 12.117 99 32 64 0 1191075184 55556512 5944416
12:41:45 74329 8192000000 10144391696 10628382720 1142029568 12.235 99 23 42 2 1255177184 108374880 4772736
12:45:01 50900 8192000000 10103121616 10586628096 1112455648 11.956 97 18 36 0 1195284000 78900528 3927824
12:46:56 56603 8192000000 10080240784 10537099264 1118146736 12.009 98 17 34 1 1145677792 24093280 3437776
12:50:02 89769 8192000000 10105332640 10582421504 1109664800 11.929 97 14 28 0 1172702608 60027424 3010384
12:53:03 82490 8192000000 10063229504 10554957824 1130163232 12.123 99 11 20 1 1206435680 73619744 2652704
12:55:46 37094 8192000000 10105857488 10547548160 1123927392 12.064 98 25 50 0 1193104496 63920320 5256784
12:57:23 53955 8192000000 10088869056 10543013888 1101539968 11.852 97 28 53 0 1180988384 74352928 5095488
12:59:17 7057 8192000000 10094406320 10514915328 1116472864 11.994 100 55 110 0 1182997600 54814272 11710464
13:00:25 16335 8192000000 10139269632 10575740928 1130544928 12.126 100 56 110 0 1228791248 87660544 10585776
13:01:44 7796 8192000000 10120536432 10567090176 1133120224 12.151 100 40 80 0 1224300672 82972976 8207472
13:02:55 82292 8192000000 10065899824 10516783104 1116507552 11.994 98 15 28 0 1163366048 43993872 2864624
13:05:20 7937 8192000000 10118438064 10537975808 1135626816 12.174 100 73 144 0 1210062096 59535888 14899392
13:06:29 87794 8192000000 10088735312 10546188288 1120202000 12.029 98 28 56 0 1186533744 60988000 5343744
13:08:58 71448 8192000000 10054267072 10540802048 1133406960 12.153 99 8 16 1 1188222288 53234416 1580912
13:11:12 61270 8192000000 10096255232 10536153088 1122555408 12.051 98 16 27 0 1183121696 57995632 2570656
13:13:23 59874 8192000000 10078929744 10519838720 1134186336 12.161 99 22 44 0 1182848368 44056880 4605152
13:15:23 20498 8192000000 10143680592 10582429696 1155231040 12.359 100 37 70 1 1242521152 79106416 8183696
13:16:52 34469 8192000000 10153580768 10601123840 1143972512 12.253 99 26 52 0 1232865936 83401040 5492384
13:18:32 48662 8192000000 10133637808 10597421056 1099019504 11.828 96 44 87 0 1197262320 88948464 9294352
13:20:26 17163 8192000000 10135350208 10584870912 1131893840 12.139 100 52 104 0 1210435824 67852480 10689504
13:21:45 11499 8192000000 10116749776 10578386944 1128876384 12.111 100 45 90 0 1240416400 102371376 9168640
13:23:00 37660 8192000000 10099182864 10543902720 1133969120 12.159 100 21 42 0 1179536864 41414528 4153216
13:24:39 65315 8192000000 10072116048 10515976192 1120134976 12.028 98 28 56 0 1161096544 34934944 6026624
13:26:45 64551 8192000000 10062729600 10536980480 1121218656 12.039 98 39 76 1 1174700912 45259744 8222512
13:28:50 62799 8192000000 10084601792 10542809088 1122261424 12.048 99 11 22 0 1176871808 52269760 2340624
13:30:55 70358 8192000000 10088291328 10551324672 1126330592 12.087 99 17 32 0 1181968400 52395168 3242640
13:33:08 52730 8192000000 10063346464 10516250624 1107086416 11.905 97 16 32 1 1160891632 50118592 3686624
13:35:07 31918 8192000000 10098756112 10557968384 1132621808 12.146 99 34 68 0 1203110144 62994736 7493600
13:36:40 16779 8192000000 10151753792 10596122624 1134669040 12.165 99 44 88 0 1243297216 99196080 9432096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

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

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

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          222950
MON$OLDEST_ACTIVE               230744
MON$OLDEST_SNAPSHOT             230744
MON$NEXT_TRANSACTION            230744
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                       2062752
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2858 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2858 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2858 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2858 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 ---
:128: 
:129: mscs-test-161.budzdorov.ru    Thu Oct 13 13:37:27 2022
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:131: 
:132: 
:133: mscs-test-161.budzdorov.ru    Thu Oct 13 13:37:28 2022
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:135: 
--- end of diff output ---

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

13.10.22 13:39:24. 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-13 13:39:27 No severe PSQL-related problems occured
13.10.22 13:39:28. End of report.