Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.10.2022 16:43
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 15632172 kB
MemAvailable 30524036 kB
Buffers 0 kB
Cached 16072604 kB
SwapCached 2160 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-08 16:42:29
DTS_END                         2022-10-08 16:42:29.9150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33188
TRN_ID                          234574
ATT_ID                          624
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: 9059.70 19745 1087164
customer order: creation 169.88 367 20385
customer order: refuse 14.28 378 1713
order to supplier: creation 44.48 1293 5337
order to supplier: removal 3.30 100955 396
invoice (draft): creation 46.32 3671 5558
invoice (draft): removal 50.44 790 6053
invoice accept: apply 41.81 18658 5017
invoice accept: cancel 45.18 845 5421
customer reserve: creation 3868.06 184 464167
customer reserve: removal 4489.68 58 538761
realization accept: apply 83.38 213 10006
realization accept: cancel 74.91 122 8989
payment from customer: creation 58.31 141 6997
payment from customer: removal 5.53 90 663
payment to supplier: creation 48.68 165 5841
payment to supplier: removal 3.64 88 437
service: total inventory turnovers 4.34 1380 521
service: total monetary turnovers 6.81 33 817
service: aggregate perf. data 0.68 29393 81
service: refresh index statistics 0.03 236072 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-10-2022 16:43:23 to 08-10-2022 16:43:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3620
TEST_TIME 2 3224
TEST_TIME 3 3762
TEST_TIME 4 4781
TEST_TIME 5 5563
TEST_TIME 6 5629
TEST_TIME 7 5719
TEST_TIME 8 5422
TEST_TIME 9 5742
TEST_TIME 10 5646
TEST_TIME 11 5740
TEST_TIME 12 5980
TEST_TIME 13 5991
TEST_TIME 14 6020
TEST_TIME 15 6145
TEST_TIME 16 6128
TEST_TIME 17 6334
TEST_TIME 18 6840
TEST_TIME 19 6942
TEST_TIME 20 7022
TEST_TIME 21 7081
TEST_TIME 22 7196
TEST_TIME 23 7506
TEST_TIME 24 7655
TEST_TIME 25 7735
TEST_TIME 26 7853
TEST_TIME 27 7978
TEST_TIME 28 8059
TEST_TIME 29 8513
TEST_TIME 30 8765
TEST_TIME 31 8937
TEST_TIME 32 9320
TEST_TIME 33 9564
TEST_TIME 34 9580
TEST_TIME 35 9555
TEST_TIME 36 9590
TEST_TIME 37 9535
TEST_TIME 38 9443
TEST_TIME 39 9361
TEST_TIME 40 9336
TEST_TIME 41 9305
TEST_TIME 42 9276
TEST_TIME 43 9234
TEST_TIME 44 9332
TEST_TIME 45 9246
TEST_TIME 46 9158
TEST_TIME 47 9144
TEST_TIME 48 9228
TEST_TIME 49 9095
TEST_TIME 50 8992
TEST_TIME 51 8969
TEST_TIME 52 8928
TEST_TIME 53 9135
TEST_TIME 54 9055
TEST_TIME 55 8985
TEST_TIME 56 8950
TEST_TIME 57 8864
TEST_TIME 58 8797
TEST_TIME 59 8904
TEST_TIME 60 8830
TEST_TIME 61 8804
TEST_TIME 62 8836
TEST_TIME 63 8822
TEST_TIME 64 8803
TEST_TIME 65 8897
TEST_TIME 66 8881
TEST_TIME 67 8914
TEST_TIME 68 8979
TEST_TIME 69 8955
TEST_TIME 70 8979
TEST_TIME 71 9059
TEST_TIME 72 9050
TEST_TIME 73 9063
TEST_TIME 74 9073
TEST_TIME 75 9186
TEST_TIME 76 9102
TEST_TIME 77 9011
TEST_TIME 78 9042
TEST_TIME 79 8992
TEST_TIME 80 8962
TEST_TIME 81 8903
TEST_TIME 82 8874
TEST_TIME 83 8826
TEST_TIME 84 8807
TEST_TIME 85 8889
TEST_TIME 86 8960
TEST_TIME 87 8979
TEST_TIME 88 8972
TEST_TIME 89 8946
TEST_TIME 90 8982
TEST_TIME 91 9056
TEST_TIME 92 9021
TEST_TIME 93 9046
TEST_TIME 94 9091
TEST_TIME 95 9092
TEST_TIME 96 9135
TEST_TIME 97 9150
TEST_TIME 98 9170
TEST_TIME 99 9204
TEST_TIME 100 9189
TEST_TIME 101 9106
TEST_TIME 102 9064
TEST_TIME 103 9049
TEST_TIME 104 9038
TEST_TIME 105 9073
TEST_TIME 106 9031
TEST_TIME 107 9001
TEST_TIME 108 8961
TEST_TIME 109 8943
TEST_TIME 110 8990
TEST_TIME 111 8994
TEST_TIME 112 9007
TEST_TIME 113 9022
TEST_TIME 114 9009
TEST_TIME 115 9047
TEST_TIME 116 9071
TEST_TIME 117 9093
TEST_TIME 118 9094
TEST_TIME 119 9092
TEST_TIME 120 9060

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-10-2022 16:43:27 to 08-10-2022 16:43:30.

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 1515811 1515811 0 0.00 0 49187 7 0 0
doc_list_biud 1515811 1515811 0 0.00 0 79071 26 0 0
sp_add_invoice_to_stock 5097 5017 80 1.56 34 174346 18659 0 80
sp_cancel_adding_invoice 5421 5421 0 0.00 4 62774 845 0 0
sp_cancel_client_order 1713 1713 0 0.00 4 19143 378 0 0
sp_cancel_customer_reserve 538761 538761 0 0.00 0 68105 58 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 2 8277 90 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 4153 89 0 0
sp_cancel_supplier_invoice 6062 6053 9 0.14 2 79077 790 0 9
sp_cancel_supplier_order 396 396 0 0.00 7 1107704 100956 0 0
sp_cancel_write_off 8989 8989 0 0.00 1 20617 122 0 0
sp_client_order 20385 20385 0 0.00 3 29252 367 0 0
sp_customer_reserve 464167 464167 0 0.00 0 42075 184 0 0
sp_fill_shopping_cart 31275 31275 0 0.00 1 19821 40 0 0
sp_fill_shopping_cart_clo_res 464164 464164 0 0.00 0 14933 13 0 0
sp_get_clo_for_invoice 5014 5014 0 0.00 0 32717 274 0 0
sp_kill_cost_storno 15510 15510 0 0.00 0 24445 62 0 0
sp_kill_qstorno_ret_qs2qd 1092960 1092960 0 0.00 0 79069 32 0 0
sp_kill_qty_storno 576353 576353 0 0.00 0 79070 69 0 0
sp_lock_selected_doc 20748 20748 0 0.00 0 11698 6 0 0
sp_make_cost_storno 27858 27858 0 0.00 0 49170 72 0 0
sp_multiply_rows_for_pdistr 27858 27858 0 0.00 0 21760 41 0 0
sp_multiply_rows_for_qdistr 20385 20385 0 0.00 0 29245 142 0 0
sp_pay_from_customer 6997 6997 0 0.00 4 20969 142 0 0
sp_pay_to_supplier 5841 5841 0 0.00 4 29033 165 0 0
sp_payment_common 12838 12838 0 0.00 1 19630 40 0 0
sp_qd_handle_on_cancel_clo 1713 1713 0 0.00 0 19139 343 0 0
sp_qd_handle_on_invoice_upd_sts 10435 10435 0 0.00 0 24329 336 0 0
sp_qd_handle_on_reserve_upd_sts 10006 10006 0 0.00 0 24269 64 0 0
sp_reserve_write_off 10006 10006 0 0.00 7 31229 214 0 0
sp_supplier_invoice 5558 5558 0 0.00 6 84686 3672 0 0
sp_supplier_order 5337 5337 0 0.00 5 81621 1293 0 0
srv_aggregate_perf_data 81 81 0 0.00 2846 543538 29394 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1243 172942 45305 0 0
srv_find_qd_qs_mism 1039116 1039116 0 0.00 0 32197 5 0 0
srv_make_invnt_saldo 521 521 0 0.00 40 154022 1381 0 0
srv_make_money_saldo 817 817 0 0.00 0 5702 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 176270 356710 236072 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5018 5018 0 0.00 0 16392 169 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 1 2456 83 0 0
t$perf-norm:sp_cancel_client_order 1713 1713 0 0.00 0 4675 24 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 1722 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 1191 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 2290 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 1743 22 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 27554 1599 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 3861 18 0 0
t$perf-norm:sp_client_order 20385 20385 0 0.00 0 17541 18 0 0
t$perf-norm:sp_customer_reserve 969 969 0 0.00 0 1045 3 0 0
t$perf-norm:sp_pay_from_customer 6997 6997 0 0.00 0 7793 12 0 0
t$perf-norm:sp_pay_to_supplier 5841 5841 0 0.00 0 16752 13 0 0
t$perf-norm:sp_reserve_write_off 10006 10006 0 0.00 0 9533 13 0 0
t$perf-norm:sp_supplier_invoice 5558 5558 0 0.00 0 16148 20 0 0
t$perf-norm:sp_supplier_order 5337 5337 0 0.00 0 5558 17 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 368 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 135 3 0 0
t$perf-norm:srv_make_invnt_saldo 521 521 0 0.00 0 1088 29 0 0
t$perf-norm:srv_make_money_saldo 817 817 0 0.00 0 2018 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10188 10028 160 1.57 0 6350 18 0 160
v_all_customers 36990 36990 0 0.00 0 28 1 0 0
v_all_suppliers 30372 30372 0 0.00 0 60 1 0 0
v_all_wares 40768 40768 0 0.00 0 59 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 556 8 0 0
v_cancel_client_order 3426 3426 0 0.00 0 2345 6 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 3170 10 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 932 5 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 1781 6 0 0
v_cancel_supplier_invoice 838 820 18 2.14 0 2953 18 0 18
v_cancel_supplier_order 792 792 0 0.00 0 1220 5 0 0
v_cancel_write_off 1988 1988 0 0.00 0 5068 20 0 0
v_max_id_clo_ord 5336 5336 0 0.00 0 243 1 0 0
v_max_id_clo_res 969 969 0 0.00 0 1002 11 0 0
v_max_id_ord_sup 5555 5555 0 0.00 0 3561 4 0 0
v_max_non_paid_invoice 1546 1546 0 0.00 0 1080 3 0 0
v_max_non_paid_realizn 3882 3882 0 0.00 0 9920 8 0 0
v_min_id_clo_ord 5336 5336 0 0.00 0 2863 5 0 0
v_min_id_clo_res 969 969 0 0.00 0 2460 8 0 0
v_min_id_ord_sup 5555 5555 0 0.00 0 6261 11 0 0
v_min_non_paid_invoice 5841 5841 0 0.00 0 19623 28 0 0
v_min_non_paid_realizn 6997 6997 0 0.00 0 19616 32 0 0
v_our_firm 10008 10008 0 0.00 0 26 1 0 0
v_reserve_write_off 20010 20010 0 0.00 0 4940 7 0 0
x_lock_depdocs_on_canc_invoice 11064 11064 0 0.00 0 37047 129 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 19685 453 0 0
x_make_qstorno_1000_1200 5337 5337 0 0.00 1 81616 1218 0 0
x_make_qstorno_1200_2000 5558 5558 0 0.00 3 84668 3527 0 0
x_make_qstorno_2100_3300 464167 464167 0 0.00 0 42074 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-10-2022 16:43:31 to 08-10-2022 16:43:35.

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
14:12:33 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:35 15978 8192000000 9890980240 10201100288 978157104 10.666 100 85 170 0 1006704896 5623504 22924288
14:14:57 16395 8192000000 9945894656 10266484736 1024235168 11.113 100 85 169 1 1057215216 11443712 21536336
14:16:19 13352 8192000000 9969236064 10296217600 1053913248 11.398 100 89 178 1 1085561936 8941328 22707360
14:17:42 19124 8192000000 10012272704 10357981184 1095196736 11.792 100 73 146 0 1122268480 9237888 17833856
14:19:05 5680 8192000000 10064803984 10422865920 1155596896 12.362 100 78 157 0 1184744464 11110368 18037200
14:20:12 4628 8192000000 10116017424 10480930816 1203269792 12.807 100 78 156 0 1233937760 13383648 17284320
14:21:20 3366 8192000000 10140607216 10512519168 1233210528 13.084 100 84 164 0 1264275280 13226944 17837808
14:22:25 9393 8192000000 10172729696 10548527104 1259696000 13.327 100 78 155 0 1289904320 13289968 16918352
14:23:38 1210 8192000000 10205697360 10587226112 1290876560 13.612 100 86 170 0 1322523536 13090688 18556288
14:24:43 1036 8192000000 10226166496 10610475008 1307840416 13.766 100 94 187 1 1342631200 14232432 20558352
14:25:45 1556 8192000000 10228009328 10621464576 1317739264 13.856 100 80 161 1 1345405856 8606544 19060048
14:26:48 6455 8192000000 10206772592 10623717376 1323310208 13.907 100 23 46 0 1334855984 5726144 5819632
14:27:55 13413 8192000000 10213683024 10633568256 1333834528 14.002 100 19 39 0 1344498032 6484784 4178720
14:29:09 33840 8192000000 10230554448 10646327296 1333302496 13.997 100 28 54 0 1344869840 5416288 6151056
14:30:44 5308 8192000000 10234319744 10654023680 1338829872 14.047 100 25 47 2 1353857200 9616928 5410400
14:31:50 5686 8192000000 10239547936 10660315136 1344482368 14.098 100 29 58 0 1357712272 7069920 6159984
14:32:57 10950 8192000000 10225128320 10645639168 1327180576 13.942 99 19 36 1 1337321520 5922864 4218080
14:34:08 28773 8192000000 10186446864 10607599616 1251178208 13.249 95 37 74 1 1265352256 5774160 8399888
14:35:39 4605 8192000000 10162148752 10570158080 1244839040 13.191 97 29 56 0 1257300128 5880704 6580384
14:36:46 7547 8192000000 10144510576 10541510656 1230574336 13.059 98 30 56 1 1242881840 5494336 6813168
14:37:57 64095 8192000000 10158615088 10556895232 1245991728 13.201 99 33 65 0 1259466400 5215168 8259504
14:40:04 4349 8192000000 10170353200 10583433216 1283951616 13.549 100 22 44 0 1295466528 6666480 4848432
14:41:11 6774 8192000000 10186926256 10599100416 1295312672 13.653 100 22 44 1 1306101920 5292736 5496512
14:42:19 15606 8192000000 10223462608 10636521472 1323450448 13.908 100 7 15 0 1328769424 4130096 1188880
14:43:45 10490 8192000000 10249439936 10658189312 1340764688 14.064 100 44 87 0 1357881184 7610624 9505872
14:44:57 42802 8192000000 10252610736 10670268416 1350036240 14.148 100 50 100 1 1370447376 8598432 11812704
14:46:43 26263 8192000000 10266109824 10690256896 1358403760 14.223 100 40 81 0 1378353328 10655104 9294464
14:48:14 6038 8192000000 10295976048 10710532096 1377328288 14.393 100 66 130 0 1406257888 14713280 14216320
14:49:21 14479 8192000000 10306825328 10725445632 1389556144 14.502 100 58 114 2 1418516656 16400464 12560048
14:50:51 14931 8192000000 10308935296 10734624768 1392568208 14.529 100 56 112 0 1424309792 18431424 13310160
14:52:15 25834 8192000000 10306186096 10736664576 1384727024 14.459 99 33 64 0 1407448816 14956752 7765040
14:53:46 7356 8192000000 10329499040 10753392640 1403395888 14.625 100 57 113 1 1438493296 20902368 14195040
14:55:03 10549 8192000000 10332427792 10758979584 1407965440 14.666 100 55 108 1 1443701232 22677440 13058352
14:56:21 6268 8192000000 10348595376 10774360064 1412338064 14.705 100 68 135 1 1458860512 29344320 17178128
14:57:32 11923 8192000000 10332489936 10755428352 1394234480 14.544 99 59 118 0 1435760896 26579040 14947376
14:58:49 7735 8192000000 10344229104 10769076224 1397645440 14.574 99 53 106 0 1447756688 36240320 13870928
14:59:59 2535 8192000000 10369378400 10790506496 1417894464 14.754 100 71 141 0 1471538672 37088192 16556016
15:01:11 2013 8192000000 10346790864 10756931584 1397053360 14.569 100 85 170 0 1447378688 30070048 20255280
15:02:16 2492 8192000000 10330102784 10735747072 1380025664 14.417 100 73 140 1 1431080016 34341248 16713104
15:03:24 26808 8192000000 10295630816 10713432064 1326510352 13.936 97 42 82 2 1373243568 36058096 10675120
15:04:56 2724 8192000000 10317138784 10730524672 1369263792 14.320 100 61 121 1 1422422176 37343024 15815360
15:06:04 1241 8192000000 10326776160 10737754112 1346891760 14.120 98 76 150 4 1405697184 38499008 20306416
15:07:10 16204 8192000000 10288594848 10717372416 1352398512 14.169 99 29 58 0 1394067760 34124624 7544624
15:08:35 4803 8192000000 10334170640 10752995328 1383021936 14.444 100 56 112 1 1435943408 38528304 14393168
15:09:54 14173 8192000000 10307964288 10731532288 1356838560 14.209 99 34 68 0 1406231728 39611456 9781712
15:11:11 21338 8192000000 10309304000 10739671040 1365986560 14.291 100 29 57 0 1422607072 48433760 8186752
15:12:42 25619 8192000000 10285297184 10723041280 1355359168 14.196 100 20 40 0 1396206448 35305392 5541888
15:14:20 16009 8192000000 10301082448 10731503616 1361246400 14.249 100 28 55 1 1401092560 32949392 6896768
15:15:40 19295 8192000000 10277167264 10714284032 1365293216 14.285 100 21 40 0 1383105680 13263008 4549456
15:17:11 18369 8192000000 10283976112 10720714752 1362833888 14.263 99 33 63 0 1382637152 12624352 7178912
15:19:02 32219 8192000000 10280367840 10736857088 1372274320 14.347 100 9 18 0 1392632352 18139376 2218656
15:20:40 65114 8192000000 10287647504 10726920192 1365396352 14.286 99 23 41 1 1379126496 9463424 4266720
15:22:46 119125 8192000000 10295537152 10745704448 1362087664 14.256 99 22 44 0 1385108368 17282880 5737824
15:26:26 104139 8192000000 10293658320 10740953088 1387096560 14.480 100 33 63 0 1407954672 13412464 7445648
15:29:25 121542 8192000000 10300193680 10765438976 1398030864 14.577 100 19 36 1 1418044368 15901856 4111648
15:32:29 36144 8192000000 10316800896 10768637952 1410188592 14.686 100 29 58 0 1426329952 9305840 6835520
15:34:09 60985 8192000000 10314253136 10775814144 1414728368 14.726 100 19 38 0 1431992864 12581664 4682832
15:36:11 23213 8192000000 10292622368 10760269824 1380121200 14.418 99 7 14 1 1389245904 7553168 1571536
15:37:48 105304 8192000000 10299973824 10770890752 1401176960 14.605 100 14 26 1 1421205920 16567264 3461696
15:40:43 54393 8192000000 10296404000 10772492288 1402968112 14.621 100 15 30 0 1420165552 13860064 3337376
15:42:45 81476 8192000000 10271814992 10732847104 1307867472 13.767 93 13 26 0 1319176848 8678176 2631200
15:45:06 55060 8192000000 10314190496 10763616256 1391286464 14.517 99 23 45 0 1404926528 8006432 5633632
15:47:07 48254 8192000000 10298764224 10759602176 1384794368 14.459 99 12 24 0 1398772048 11105904 2871776
15:48:59 33384 8192000000 10291774368 10758189056 1377124928 14.391 99 7 14 0 1387878400 9253744 1499728
15:50:38 52743 8192000000 10279983376 10718105600 1263941888 13.366 91 17 34 1 1277499376 9344912 4212576
15:52:34 95223 8192000000 10279039824 10731532288 1337857744 14.038 97 26 51 0 1360867248 17590896 5418608
15:55:10 42801 8192000000 10278561504 10729697280 1322649536 13.901 96 29 58 0 1340131488 11614592 5867360
15:56:57 172942 8192000000 10279935040 10712104960 1289941280 13.604 94 17 34 0 1311431696 17454496 4035920
16:00:51 30288 8192000000 10283417152 10730205184 1363518032 14.269 100 29 56 0 1380292976 10911232 5863712
16:02:23 53837 8192000000 10259865808 10712211456 1342496992 14.080 98 6 12 0 1355630416 11943216 1190208
16:04:23 63061 8192000000 10281683424 10737991680 1355363792 14.196 98 12 24 0 1372880608 14486256 3030560
16:06:27 80613 8192000000 10263855728 10734317568 1308105584 13.769 95 11 22 0 1330405872 19691584 2608704
16:08:49 91499 8192000000 10269272176 10723749888 1330326320 13.970 97 15 30 0 1343713792 10079136 3308336
16:11:40 73239 8192000000 10293512608 10748911616 1358164240 14.221 98 28 56 1 1384058064 19256112 6637712
16:13:54 42451 8192000000 10289048032 10729369600 1365883248 14.290 99 32 65 2 1380385216 7840080 6661888
16:15:37 47040 8192000000 10290558944 10744217600 1375638896 14.378 99 30 60 0 1396705536 14386672 6679968
16:17:24 69439 8192000000 10304354512 10757738496 1383012848 14.443 99 19 38 0 1397635712 10531328 4091536
16:19:40 71811 8192000000 10276266064 10734571520 1354209328 14.185 98 21 42 0 1371596272 12805984 4580960
16:21:52 120488 8192000000 10296701968 10740383744 1365495808 14.287 98 8 17 0 1378199248 10937200 1766240
16:24:55 72667 8192000000 10303690576 10754494464 1387230144 14.481 99 37 75 2 1406607632 10847056 8530432
16:27:13 56235 8192000000 10282528560 10742579200 1374746112 14.370 99 9 18 0 1384169200 6940720 2482368
16:29:13 52210 8192000000 10296886976 10751746048 1382288832 14.437 99 14 28 0 1397344992 11700496 3355664
16:31:14 91557 8192000000 10303348144 10777554944 1391634112 14.520 100 10 20 0 1415466128 20982208 2849808
16:33:48 30056 8192000000 10294323552 10751959040 1391858688 14.522 100 38 76 0 1409906864 8528784 9519392
16:35:21 83986 8192000000 10269167344 10717646848 1312984864 13.813 95 27 54 0 1329707728 10633840 6089024
16:37:58 56820 8192000000 10260153936 10703847424 1316170320 13.842 96 18 36 0 1331246144 10975840 4099984
16:40:04 17771 8192000000 10277509280 10717372416 1352451280 14.170 99 36 72 0 1370211216 9577968 8181968
16:41:23 61670 8192000000 10271833664 10715308032 1336525664 14.026 98 29 58 0 1357163184 14564896 6072624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-10-2022 16:43:37 to 08-10-2022 16:43:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-10-2022 16:43:54 to 08-10-2022 16:43:56.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          232974
MON$OLDEST_ACTIVE               234940
MON$OLDEST_SNAPSHOT             234940
MON$NEXT_TRANSACTION            234940
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-03 16:41:55.3470
MON$PAGES                       2018624
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Oct  8 16:42:31 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Oct  8 16:42:33 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Oct  8 16:42:44 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Sat Oct  8 16:42:44 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Sat Oct  8 16:42:44 2022
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:25: 
:26: 
:27: mscs-test-161.budzdorov.ru    Sat Oct  8 16:42:44 2022
:28:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:29: 
:30: 
:31: mscs-test-161.budzdorov.ru    Sat Oct  8 16:42:44 2022
:32:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:33: 
:34: 
:35: mscs-test-161.budzdorov.ru    Sat Oct  8 16:42:44 2022
:36:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:37: 
--- end of diff output ---

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

08.10.22 16:43:57. There are 3 logs in /var/tmp/logs-oltp30 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-08 16:43:59 No severe PSQL-related problems occured
08.10.22 16:44:01. End of report.