Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.06.2022 10:37
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 16173524 kB
MemAvailable 31304196 kB
Buffers 0 kB
Cached 15486400 kB
SwapCached 2444 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-06-01 10:37:16
DTS_END                         2022-06-01 10:37:16.9020
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/32850
TRN_ID                          220742
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: 9333.88 27612 1120065
customer order: creation 172.66 459 20719
customer order: refuse 14.83 385 1780
order to supplier: creation 45.18 1304 5422
order to supplier: removal 3.38 163969 405
invoice (draft): creation 45.89 3008 5507
invoice (draft): removal 52.08 1000 6250
invoice accept: apply 42.53 25624 5103
invoice accept: cancel 46.94 1239 5633
customer reserve: creation 4011.73 241 481407
customer reserve: removal 4601.53 94 552184
realization accept: apply 83.63 279 10035
realization accept: cancel 83.89 154 10067
payment from customer: creation 60.03 177 7203
payment from customer: removal 5.99 95 719
payment to supplier: creation 47.33 185 5680
payment to supplier: removal 3.38 135 406
service: total inventory turnovers 4.22 3699 506
service: total monetary turnovers 7.83 313 939
service: aggregate perf. data 0.80 21378 96
service: refresh index statistics 0.03 328494 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-06-2022 10:38:04 to 01-06-2022 10:38:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8352
TEST_TIME 2 6664
TEST_TIME 3 5541
TEST_TIME 4 5720
TEST_TIME 5 6053
TEST_TIME 6 6654
TEST_TIME 7 6364
TEST_TIME 8 6533
TEST_TIME 9 6502
TEST_TIME 10 6486
TEST_TIME 11 6576
TEST_TIME 12 6792
TEST_TIME 13 6861
TEST_TIME 14 6841
TEST_TIME 15 7146
TEST_TIME 16 7080
TEST_TIME 17 7311
TEST_TIME 18 7309
TEST_TIME 19 7347
TEST_TIME 20 8098
TEST_TIME 21 8047
TEST_TIME 22 7913
TEST_TIME 23 7737
TEST_TIME 24 7752
TEST_TIME 25 7838
TEST_TIME 26 8035
TEST_TIME 27 8667
TEST_TIME 28 9013
TEST_TIME 29 9856
TEST_TIME 30 9943
TEST_TIME 31 10672
TEST_TIME 32 10871
TEST_TIME 33 11068
TEST_TIME 34 11284
TEST_TIME 35 11174
TEST_TIME 36 11086
TEST_TIME 37 10927
TEST_TIME 38 10830
TEST_TIME 39 10609
TEST_TIME 40 10562
TEST_TIME 41 10526
TEST_TIME 42 10346
TEST_TIME 43 10331
TEST_TIME 44 10220
TEST_TIME 45 10213
TEST_TIME 46 10157
TEST_TIME 47 10118
TEST_TIME 48 10068
TEST_TIME 49 10035
TEST_TIME 50 10117
TEST_TIME 51 10177
TEST_TIME 52 10158
TEST_TIME 53 10067
TEST_TIME 54 10111
TEST_TIME 55 10112
TEST_TIME 56 10147
TEST_TIME 57 10081
TEST_TIME 58 10145
TEST_TIME 59 10054
TEST_TIME 60 10117
TEST_TIME 61 10181
TEST_TIME 62 10176
TEST_TIME 63 10073
TEST_TIME 64 9996
TEST_TIME 65 9917
TEST_TIME 66 9883
TEST_TIME 67 9851
TEST_TIME 68 9813
TEST_TIME 69 9758
TEST_TIME 70 9782
TEST_TIME 71 9803
TEST_TIME 72 9765
TEST_TIME 73 9750
TEST_TIME 74 9719
TEST_TIME 75 9706
TEST_TIME 76 9734
TEST_TIME 77 9769
TEST_TIME 78 9857
TEST_TIME 79 9833
TEST_TIME 80 9898
TEST_TIME 81 9881
TEST_TIME 82 9770
TEST_TIME 83 9715
TEST_TIME 84 9703
TEST_TIME 85 9703
TEST_TIME 86 9645
TEST_TIME 87 9594
TEST_TIME 88 9571
TEST_TIME 89 9494
TEST_TIME 90 9432
TEST_TIME 91 9450
TEST_TIME 92 9515
TEST_TIME 93 9487
TEST_TIME 94 9473
TEST_TIME 95 9534
TEST_TIME 96 9496
TEST_TIME 97 9508
TEST_TIME 98 9508
TEST_TIME 99 9596
TEST_TIME 100 9590
TEST_TIME 101 9653
TEST_TIME 102 9624
TEST_TIME 103 9621
TEST_TIME 104 9601
TEST_TIME 105 9541
TEST_TIME 106 9482
TEST_TIME 107 9495
TEST_TIME 108 9480
TEST_TIME 109 9440
TEST_TIME 110 9422
TEST_TIME 111 9387
TEST_TIME 112 9368
TEST_TIME 113 9399
TEST_TIME 114 9371
TEST_TIME 115 9370
TEST_TIME 116 9353
TEST_TIME 117 9327
TEST_TIME 118 9383
TEST_TIME 119 9427
TEST_TIME 120 9357

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-06-2022 10:38:08 to 01-06-2022 10:38:11.

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 1561865 1561865 0 0.00 0 20417 13 0 0
doc_list_biud 1561854 1561854 0 0.00 0 31503 39 0 0
sp_add_invoice_to_stock 5228 5103 125 2.39 51 217658 25625 0 125
sp_cancel_adding_invoice 5633 5633 0 0.00 6 123095 1240 0 0
sp_cancel_client_order 1780 1780 0 0.00 5 9378 385 0 0
sp_cancel_customer_reserve 552184 552184 0 0.00 1 27926 94 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 3 2209 95 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 4 4071 136 0 0
sp_cancel_supplier_invoice 6257 6250 7 0.11 2 20077 1000 0 7
sp_cancel_supplier_order 405 405 0 0.00 35 1560443 163969 0 0
sp_cancel_write_off 10067 10067 0 0.00 2 7061 154 0 0
sp_client_order 20719 20719 0 0.00 6 33350 460 0 0
sp_customer_reserve 481407 481407 0 0.00 1 155612 241 0 0
sp_fill_shopping_cart 31630 31630 0 0.00 2 9672 76 0 0
sp_fill_shopping_cart_clo_res 481383 481383 0 0.00 0 19518 19 0 0
sp_get_clo_for_invoice 5058 5058 0 0.00 0 24975 261 0 0
sp_kill_cost_storno 16825 16825 0 0.00 0 8892 67 0 0
sp_kill_qstorno_ret_qs2qd 1121090 1121090 0 0.00 0 23611 41 0 0
sp_kill_qty_storno 591409 591409 0 0.00 0 31502 95 0 0
sp_lock_selected_doc 20869 20869 0 0.00 0 8578 6 0 0
sp_make_cost_storno 27975 27975 0 0.00 0 20381 82 0 0
sp_multiply_rows_for_pdistr 27974 27974 0 0.00 0 7722 38 0 0
sp_multiply_rows_for_qdistr 20718 20718 0 0.00 0 11613 191 0 0
sp_pay_from_customer 7203 7203 0 0.00 6 17001 178 0 0
sp_pay_to_supplier 5680 5680 0 0.00 7 13317 185 0 0
sp_payment_common 12883 12883 0 0.00 2 7118 45 0 0
sp_qd_handle_on_cancel_clo 1780 1780 0 0.00 0 9370 316 0 0
sp_qd_handle_on_invoice_upd_sts 10690 10690 0 0.00 0 31498 499 0 0
sp_qd_handle_on_reserve_upd_sts 10033 10033 0 0.00 0 24288 69 0 0
sp_reserve_write_off 10035 10035 0 0.00 9 24570 279 0 0
sp_supplier_invoice 5507 5507 0 0.00 8 101160 3009 0 0
sp_supplier_order 5422 5422 0 0.00 7 35142 1304 0 0
srv_aggregate_perf_data 96 96 0 0.00 2587 163771 21379 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 771 116814 35991 0 0
srv_find_qd_qs_mism 1069646 1069646 0 0.00 0 17009 11 0 0
srv_make_invnt_saldo 506 506 0 0.00 73 66524 3699 0 0
srv_make_money_saldo 939 939 0 0.00 2 10842 313 0 0
srv_recalc_idx_stat 4 4 0 0.00 60825 691130 328494 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5109 5109 0 0.00 1 10703 865 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 1 8062 486 0 0
t$perf-norm:sp_cancel_client_order 1780 1780 0 0.00 0 6440 27 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 6429 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 2221 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 1203 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 819 30 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 98967 6944 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 648 16 0 0
t$perf-norm:sp_client_order 20719 20719 0 0.00 0 14706 36 0 0
t$perf-norm:sp_customer_reserve 1012 1012 0 0.00 0 908 13 0 0
t$perf-norm:sp_pay_from_customer 7203 7203 0 0.00 0 17333 22 0 0
t$perf-norm:sp_pay_to_supplier 5681 5681 0 0.00 0 4007 19 0 0
t$perf-norm:sp_reserve_write_off 10035 10035 0 0.00 0 10195 22 0 0
t$perf-norm:sp_supplier_invoice 5507 5507 0 0.00 0 8526 34 0 0
t$perf-norm:sp_supplier_order 5422 5422 0 0.00 0 4010 31 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 220 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 2832 60 0 0
t$perf-norm:srv_make_invnt_saldo 506 506 0 0.00 0 651 13 0 0
t$perf-norm:srv_make_money_saldo 939 939 0 0.00 0 955 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 8 236 98 0 0
v_add_invoice_to_stock 10358 10108 250 2.41 0 7851 26 0 250
v_all_customers 38400 38400 0 0.00 0 111 3 0 0
v_all_suppliers 30350 30350 0 0.00 0 68 4 0 0
v_all_wares 41426 41426 0 0.00 0 52 4 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 1351 15 0 0
v_cancel_client_order 3560 3560 0 0.00 0 1693 10 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 2132 12 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 1318 10 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 4000 19 0 0
v_cancel_supplier_invoice 804 790 14 1.74 0 2369 25 0 14
v_cancel_supplier_order 810 810 0 0.00 0 1598 11 0 0
v_cancel_write_off 1924 1924 0 0.00 0 6418 26 0 0
v_max_id_clo_ord 5417 5417 0 0.00 0 168 4 0 0
v_max_id_clo_res 1012 1012 0 0.00 0 1556 13 0 0
v_max_id_ord_sup 5497 5497 0 0.00 0 5327 7 0 0
v_max_non_paid_invoice 1419 1419 0 0.00 0 57 7 0 0
v_max_non_paid_realizn 3555 3555 0 0.00 0 3991 8 0 0
v_min_id_clo_ord 5417 5417 0 0.00 0 2042 7 0 0
v_min_id_clo_res 1012 1012 0 0.00 0 7034 14 0 0
v_min_id_ord_sup 5497 5497 0 0.00 0 5073 9 0 0
v_min_non_paid_invoice 5680 5680 0 0.00 0 5998 17 0 0
v_min_non_paid_realizn 7203 7203 0 0.00 0 7114 18 0 0
v_our_firm 10322 10322 0 0.00 0 111 4 0 0
v_reserve_write_off 20066 20066 0 0.00 0 3877 10 0 0
x_lock_depdocs_on_canc_invoice 11488 11488 0 0.00 0 19253 122 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 9242 355 0 0
x_make_qstorno_1000_1200 5422 5422 0 0.00 2 35132 1151 0 0
x_make_qstorno_1200_2000 5507 5507 0 0.00 3 99995 2803 0 0
x_make_qstorno_2100_3300 481403 481403 0 0.00 0 155609 211 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-06-2022 10:38:12 to 01-06-2022 10:38:16.

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
08:07:20 8 8192000000 8803531456 8816353280 8503984 0.103 3 2 4 0 8681344 106352 71008
08:08:21 31936 8192000000 9735428352 10038005760 808202704 8.979 100 88 175 2 837426864 7888800 21335360
08:10:05 20338 8192000000 9778221840 10088345600 851925152 9.419 100 85 170 0 885004112 13026192 20052768
08:11:29 18635 8192000000 9816470080 10138677248 880026016 9.700 100 83 166 1 922761248 23801232 18934000
08:12:51 9633 8192000000 9878896400 10212577280 921340032 10.109 100 86 172 0 985474688 45159856 18974800
08:14:07 3826 8192000000 9921199440 10270285824 965454944 10.542 100 83 165 1 1038342752 55514032 17373776
08:15:14 3358 8192000000 9963466880 10322513920 1004368304 10.921 100 83 161 1 1084096352 63096816 16631232
08:16:19 3668 8192000000 10002262576 10369908736 1034850736 11.215 100 93 173 1 1128627600 75844576 17932288
08:17:25 1140 8192000000 10042631360 10414305280 1057234384 11.430 100 96 188 0 1165912368 89631568 19046416
08:18:28 1020 8192000000 10052771008 10425503744 1062202368 11.478 100 95 184 1 1167623584 86903328 18517888
08:19:33 1046 8192000000 10048186544 10424532992 1068396912 11.537 100 98 192 0 1167943792 81117808 18429072
08:20:37 1214 8192000000 10062669120 10442854400 1074038384 11.591 100 94 184 2 1184045344 91754912 18252048
08:21:40 1272 8192000000 10057447376 10441076736 1080261600 11.650 100 96 184 0 1177671728 78275728 19134400
08:22:42 1311 8192000000 10067631792 10452844544 1084938736 11.695 100 99 193 0 1188673104 83523280 20211088
08:23:45 1469 8192000000 10046536704 10436853760 1088388192 11.727 100 86 172 0 1171011392 65700416 16922784
08:24:47 1199 8192000000 10067443488 10453966848 1093434560 11.775 100 93 175 0 1185560640 73940736 18185344
08:25:49 1428 8192000000 10070396112 10454626304 1099902560 11.837 100 96 190 0 1191280720 72136208 19241952
08:26:52 1242 8192000000 10063470240 10452148224 1104486912 11.880 100 98 181 0 1182324784 59048304 18789568
08:27:54 1182 8192000000 10085639376 10474967040 1099578992 11.834 100 92 174 0 1204315952 87028368 17708592
08:28:56 1131 8192000000 10047638288 10434387968 1093758720 11.778 100 92 175 0 1167456448 55286752 18410976
08:29:57 3883 8192000000 10007931776 10395373568 1074484832 11.595 100 76 139 3 1129562656 40965728 14112096
08:31:04 8240 8192000000 10000653984 10386030592 1054043936 11.399 98 71 137 3 1116329824 48600288 13685600
08:32:17 1104 8192000000 10011052400 10389618688 1051121040 11.371 100 90 174 1 1131004112 61850208 18032864
08:33:20 8937 8192000000 10009939616 10394324992 1052343696 11.383 99 71 140 0 1114909648 48430144 14135808
08:34:34 1461 8192000000 9999205712 10387083264 1057553440 11.433 100 70 136 0 1123352992 50403920 15395632
08:35:37 1549 8192000000 9972469088 10343702528 1031596224 11.184 100 84 151 1 1090853504 43742736 15514544
08:36:40 1443 8192000000 10015358032 10390421504 1049320544 11.354 100 94 187 2 1140327664 71305344 19701776
08:37:44 2921 8192000000 9985688992 10363465728 1046570160 11.328 100 74 128 3 1109952256 51082112 12299984
08:38:52 19305 8192000000 10025249984 10408898560 1062503184 11.480 100 59 112 1 1146582224 72785888 11293152
08:40:22 3469 8192000000 10038479232 10430095360 1084777920 11.693 100 66 118 1 1167838272 69490944 13569408
08:41:55 2093 8192000000 10097928288 10486226944 1098143744 11.820 100 69 136 2 1218244160 106210928 13889488
08:43:03 1605 8192000000 10115052784 10510872576 1111121408 11.943 100 82 159 1 1239298720 110988352 17188960
08:44:09 15737 8192000000 10154269904 10555846656 1124308544 12.068 100 64 127 0 1272046784 134593920 13144320
08:45:27 4999 8192000000 10206617232 10602467328 1137472144 12.192 100 75 142 1 1320499616 168248896 14778576
08:46:39 2650 8192000000 10254598800 10655768576 1150541712 12.315 100 78 155 1 1368473984 200542624 17389648
08:47:46 1892 8192000000 10290795168 10690154496 1157748432 12.382 100 89 170 2 1408004400 231550752 18705216
08:48:52 1336 8192000000 10334977680 10739707904 1169586288 12.493 100 91 169 1 1445833776 256912640 19334848
08:49:56 1298 8192000000 10383483424 10789781504 1173435312 12.529 100 97 186 3 1495271168 301359520 20476336
08:51:00 1125 8192000000 10412207568 10819121152 1178754848 12.579 100 86 168 1 1524888288 326758704 19374736
08:52:06 1063 8192000000 10422834000 10859802624 1188881968 12.673 100 91 178 1 1536247184 326029952 21335264
08:53:10 1104 8192000000 10465227664 10872324096 1196764064 12.746 100 91 175 0 1573018992 355955024 20299904
08:54:16 1238 8192000000 10475435504 10895491072 1191964672 12.702 100 91 175 1 1583922304 372120256 19837376
08:55:21 1059 8192000000 10527403408 10937126912 1198464816 12.762 100 97 190 0 1640852816 420307376 22080624
08:56:24 1543 8192000000 10519016624 10962956288 1185871184 12.645 100 95 190 0 1625922320 416605984 23445152
08:57:28 844 8192000000 10510547536 10954014720 1190459264 12.688 100 97 192 2 1617800064 405669392 21671408
08:58:30 16915 8192000000 10523687280 10956353536 1167674608 12.475 99 48 96 1 1626146432 446314528 12157296
09:00:00 4439 8192000000 10614639760 11041755136 1187354832 12.659 100 59 112 2 1721768096 520330608 14082656
09:01:13 4193 8192000000 10658668608 11110916096 1193424688 12.715 100 58 116 0 1764655152 556267312 14963152
09:02:21 763 8192000000 10681304816 11138924544 1191241600 12.695 100 84 151 1 1783045776 572935648 18868528
09:03:26 1142 8192000000 10668118384 11148759040 1191818560 12.700 100 82 160 1 1768497952 556303248 20376144
09:04:30 3968 8192000000 10618188272 11122597888 1179488144 12.585 100 55 111 1 1724064448 529147104 15429200
09:05:36 15819 8192000000 10500976576 11052363776 1159360528 12.397 99 43 86 0 1651155024 479978848 11815648
09:07:02 23799 8192000000 10364385744 10818932736 1155137552 12.358 100 25 50 0 1452860464 291458832 6264080
09:08:45 32155 8192000000 10203340784 10692894720 1145480192 12.267 99 14 28 1 1299802400 151178304 3143904
09:10:30 57501 8192000000 10104729520 10624110592 1128043072 12.103 99 9 18 0 1231456560 101571136 1842352
09:12:35 41086 8192000000 10076483760 10513219584 1105613824 11.891 97 33 63 0 1153788880 42000080 6174976
09:14:26 116813 8192000000 10065379472 10524979200 1104226592 11.878 97 45 86 2 1151881840 37637328 10017920
09:17:26 36418 8192000000 10098061456 10526945280 1099096320 11.829 97 51 97 0 1171639568 62771792 9771456
09:19:58 106880 8192000000 10117303328 10552631296 1108222976 11.916 97 37 71 0 1186667680 71279504 7165200
09:22:45 61273 8192000000 10152951040 10607910912 1123189920 12.057 98 18 36 0 1253786320 126553472 4042928
09:24:54 34774 8192000000 10125850048 10619895808 1141407648 12.229 100 8 16 1 1235242448 92077536 1757264
09:26:40 90186 8192000000 10064080416 10541604864 1084765008 11.693 96 9 18 0 1136315632 49757984 1792640
09:29:16 25968 8192000000 10084658960 10529222656 1127334320 12.096 100 24 43 0 1175889744 43918160 4637264
09:30:44 13293 8192000000 10096744336 10531532800 1123613600 12.061 100 73 136 0 1180890416 43226224 14050592
09:31:59 26160 8192000000 10091062272 10531676160 1092984848 11.771 97 29 58 0 1144595440 45674768 5935824
09:33:27 29958 8192000000 10043530704 10499125248 1117814576 12.006 100 17 34 0 1177706464 56212640 3679248
09:35:02 30680 8192000000 10122507968 10571628544 1114265216 11.973 99 32 64 0 1185866800 64972032 6629552
09:36:37 48507 8192000000 10157974496 10613702656 1124828720 12.073 99 32 63 3 1226342096 94268464 7244912
09:38:43 81470 8192000000 10111091808 10586312704 1082477536 11.671 95 18 36 0 1158177536 71940464 3759536
09:41:08 81160 8192000000 10083890368 10516721664 1108046416 11.914 98 36 68 2 1148988912 33526880 7415616
09:43:30 62479 8192000000 10100893728 10566516736 1124040176 12.065 99 16 32 0 1197558784 70450800 3067808
09:45:37 63044 8192000000 10131255568 10595569664 1148082384 12.291 100 17 34 1 1214681920 62783680 3815856
09:47:41 45690 8192000000 10085319840 10546139136 1130493680 12.126 99 22 44 0 1217456000 82194512 4767808
09:49:33 55309 8192000000 10106187408 10539356160 1124298624 12.068 98 20 40 0 1176692112 48073136 4320352
09:51:29 31424 8192000000 10126093584 10549342208 1128981488 12.112 99 40 72 2 1207649312 70911440 7756384
09:53:03 95630 8192000000 10125636624 10626277376 1111245968 11.944 98 22 44 0 1213046480 96938512 4862000
09:55:39 74359 8192000000 10098079472 10530545664 1112266160 11.954 98 16 32 0 1169300064 53562624 3471280
09:57:57 98156 8192000000 10110165792 10596110336 1109170944 11.925 97 27 54 2 1177686800 63084656 5431200
10:00:46 53994 8192000000 10079923488 10547609600 1112512432 11.956 98 25 50 1 1143946192 26391664 5042096
10:03:03 44846 8192000000 10024265744 10478485504 1096183920 11.801 97 16 32 1 1139706528 40586560 2936048
10:04:50 35431 8192000000 10092400000 10540417024 1110914032 11.941 98 29 58 0 1166461808 49692240 5855536
10:06:53 69291 8192000000 10119298336 10563547136 1076808608 11.617 95 12 24 2 1172425600 92440016 3176976
10:09:03 100944 8192000000 10082885984 10551943168 1109272112 11.926 99 8 17 0 1186499040 75881776 1345152
10:11:52 21646 8192000000 10142429488 10581151744 1142759792 12.241 100 44 86 2 1224137616 72746896 8630928
10:13:20 32311 8192000000 10135168208 10572632064 1120907824 12.036 98 26 52 1 1211656240 85293104 5455312
10:15:32 41169 8192000000 10127330160 10631737344 1116881936 11.998 98 36 73 1 1235616608 110955472 7779200
10:17:22 61405 8192000000 10141418896 10614050816 1134391984 12.163 99 36 72 0 1234912928 93145040 7375904
10:19:29 53642 8192000000 10095630592 10531188736 1093575728 11.777 96 40 80 1 1145225184 43636160 8013296
10:21:23 77712 8192000000 10089180304 10528575488 1111569552 11.947 98 38 76 0 1183145616 63774880 7801184
10:23:50 55425 8192000000 10090021744 10575110144 1130327568 12.124 100 23 46 0 1225387008 90325056 4734384
10:26:00 55272 8192000000 10066390592 10527952896 1130988800 12.131 100 11 22 0 1169067456 35452768 2625888
10:27:56 70597 8192000000 10074394944 10531647488 1105901296 11.894 98 14 28 0 1164339232 55692752 2745184
10:30:13 65277 8192000000 10059490720 10500927488 1088670832 11.730 98 19 38 0 1142538336 49814672 4052832
10:32:18 32609 8192000000 10089206880 10555027456 1118535232 12.013 99 29 58 1 1186928848 61909296 6484320
10:33:51 29352 8192000000 10124365200 10561175552 1108157280 11.915 98 31 62 0 1208895376 94161072 6577024
10:35:21 25056 8192000000 10126397888 10624942080 1122276944 12.048 100 38 76 0 1285178960 154336752 8565264
10:37:08 16526 8192000000 9175878384 9483870208 314691984 3.699 28 2 4 0 331068544 16137152 239408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 01-06-2022 10:38:18 to 01-06-2022 10:38:34.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-06-2022 10:38:35 to 01-06-2022 10:38:37.

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          221856
MON$OLDEST_ACTIVE               221857
MON$OLDEST_SNAPSHOT             221857
MON$NEXT_TRANSACTION            221857
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                       2052576
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

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

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

01.06.22 10:38:38. 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-06-01 10:38:41 No severe PSQL-related problems occured
01.06.22 10:38:42. End of report.