Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.10.2022 22:39
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 15723844 kB
MemAvailable 30507508 kB
Buffers 0 kB
Cached 15975516 kB
SwapCached 2124 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-09 22:38:27
DTS_END                         2022-10-09 22:38:27.8190
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(57:17)
IP                              ::1/52648
TRN_ID                          238519
ATT_ID                          700
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: 8851.72 19330 1062206
customer order: creation 169.88 331 20386
customer order: refuse 14.10 368 1692
order to supplier: creation 44.93 1329 5392
order to supplier: removal 3.12 95355 374
invoice (draft): creation 44.96 3471 5395
invoice (draft): removal 50.17 670 6020
invoice accept: apply 40.81 17786 4897
invoice accept: cancel 45.29 754 5435
customer reserve: creation 3724.11 178 446893
customer reserve: removal 4420.60 53 530472
realization accept: apply 83.30 193 9996
realization accept: cancel 84.27 104 10112
payment from customer: creation 57.70 128 6924
payment from customer: removal 5.75 84 690
payment to supplier: creation 47.94 133 5753
payment to supplier: removal 3.43 117 412
service: total inventory turnovers 4.57 874 548
service: total monetary turnovers 5.90 30 708
service: aggregate perf. data 0.86 20091 103
service: refresh index statistics 0.03 244557 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-10-2022 22:39:42 to 09-10-2022 22:39:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2807
TEST_TIME 2 4281
TEST_TIME 3 4439
TEST_TIME 4 4303
TEST_TIME 5 4887
TEST_TIME 6 4795
TEST_TIME 7 5386
TEST_TIME 8 5447
TEST_TIME 9 5543
TEST_TIME 10 5765
TEST_TIME 11 5877
TEST_TIME 12 5758
TEST_TIME 13 5918
TEST_TIME 14 6091
TEST_TIME 15 6444
TEST_TIME 16 6833
TEST_TIME 17 6953
TEST_TIME 18 7175
TEST_TIME 19 7407
TEST_TIME 20 8076
TEST_TIME 21 8604
TEST_TIME 22 9059
TEST_TIME 23 9447
TEST_TIME 24 9525
TEST_TIME 25 9698
TEST_TIME 26 9696
TEST_TIME 27 9954
TEST_TIME 28 9849
TEST_TIME 29 9680
TEST_TIME 30 9900
TEST_TIME 31 9749
TEST_TIME 32 9548
TEST_TIME 33 9468
TEST_TIME 34 9366
TEST_TIME 35 9405
TEST_TIME 36 9256
TEST_TIME 37 9219
TEST_TIME 38 9046
TEST_TIME 39 8993
TEST_TIME 40 8883
TEST_TIME 41 8860
TEST_TIME 42 8810
TEST_TIME 43 8841
TEST_TIME 44 8756
TEST_TIME 45 8689
TEST_TIME 46 8554
TEST_TIME 47 8617
TEST_TIME 48 8740
TEST_TIME 49 8794
TEST_TIME 50 8750
TEST_TIME 51 8821
TEST_TIME 52 9004
TEST_TIME 53 9016
TEST_TIME 54 8960
TEST_TIME 55 9024
TEST_TIME 56 8963
TEST_TIME 57 8922
TEST_TIME 58 9007
TEST_TIME 59 9094
TEST_TIME 60 9155
TEST_TIME 61 9040
TEST_TIME 62 8970
TEST_TIME 63 8923
TEST_TIME 64 8861
TEST_TIME 65 8852
TEST_TIME 66 8840
TEST_TIME 67 8832
TEST_TIME 68 8846
TEST_TIME 69 8753
TEST_TIME 70 8786
TEST_TIME 71 8702
TEST_TIME 72 8732
TEST_TIME 73 8801
TEST_TIME 74 8815
TEST_TIME 75 8844
TEST_TIME 76 8830
TEST_TIME 77 8862
TEST_TIME 78 8845
TEST_TIME 79 8957
TEST_TIME 80 9003
TEST_TIME 81 8959
TEST_TIME 82 9043
TEST_TIME 83 9111
TEST_TIME 84 9138
TEST_TIME 85 9060
TEST_TIME 86 9012
TEST_TIME 87 8988
TEST_TIME 88 8966
TEST_TIME 89 8894
TEST_TIME 90 8836
TEST_TIME 91 8870
TEST_TIME 92 8891
TEST_TIME 93 8865
TEST_TIME 94 8901
TEST_TIME 95 8965
TEST_TIME 96 8985
TEST_TIME 97 8978
TEST_TIME 98 8958
TEST_TIME 99 8958
TEST_TIME 100 9001
TEST_TIME 101 9049
TEST_TIME 102 9042
TEST_TIME 103 9065
TEST_TIME 104 9086
TEST_TIME 105 9090
TEST_TIME 106 9140
TEST_TIME 107 9084
TEST_TIME 108 9043
TEST_TIME 109 9014
TEST_TIME 110 8997
TEST_TIME 111 9013
TEST_TIME 112 8977
TEST_TIME 113 8956
TEST_TIME 114 8968
TEST_TIME 115 8928
TEST_TIME 116 8894
TEST_TIME 117 8878
TEST_TIME 118 8869
TEST_TIME 119 8904
TEST_TIME 120 8854

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-10-2022 22:39:46 to 09-10-2022 22:39:50.

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 1478007 1478007 0 0.00 0 21298 6 0 0
doc_list_biud 1478007 1478007 0 0.00 0 43976 24 0 0
sp_add_invoice_to_stock 4976 4897 79 1.58 18 206374 17786 0 79
sp_cancel_adding_invoice 5435 5435 0 0.00 4 57969 754 0 0
sp_cancel_client_order 1692 1692 0 0.00 5 18228 369 0 0
sp_cancel_customer_reserve 530472 530472 0 0.00 0 43978 53 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 2 6239 84 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 2 8144 117 0 0
sp_cancel_supplier_invoice 6027 6020 7 0.11 1 17859 670 0 7
sp_cancel_supplier_order 374 374 0 0.00 5 903321 95355 0 0
sp_cancel_write_off 10112 10112 0 0.00 1 21299 104 0 0
sp_client_order 20386 20386 0 0.00 3 32571 331 0 0
sp_customer_reserve 446893 446893 0 0.00 0 41026 179 0 0
sp_fill_shopping_cart 31160 31160 0 0.00 1 17846 41 0 0
sp_fill_shopping_cart_clo_res 446891 446891 0 0.00 0 15587 14 0 0
sp_get_clo_for_invoice 4893 4893 0 0.00 0 19728 296 0 0
sp_kill_cost_storno 16649 16649 0 0.00 0 21295 51 0 0
sp_kill_qstorno_ret_qs2qd 1077450 1077450 0 0.00 0 43974 29 0 0
sp_kill_qty_storno 568994 568994 0 0.00 0 43976 63 0 0
sp_lock_selected_doc 20541 20541 0 0.00 0 10654 6 0 0
sp_make_cost_storno 27566 27566 0 0.00 0 15995 58 0 0
sp_multiply_rows_for_pdistr 27566 27566 0 0.00 0 9569 32 0 0
sp_multiply_rows_for_qdistr 20386 20386 0 0.00 0 22047 142 0 0
sp_pay_from_customer 6924 6924 0 0.00 4 16556 129 0 0
sp_pay_to_supplier 5753 5753 0 0.00 4 13522 134 0 0
sp_payment_common 12677 12677 0 0.00 1 14034 38 0 0
sp_qd_handle_on_cancel_clo 1692 1692 0 0.00 0 18223 332 0 0
sp_qd_handle_on_invoice_upd_sts 10328 10328 0 0.00 0 27176 311 0 0
sp_qd_handle_on_reserve_upd_sts 9996 9996 0 0.00 0 11232 63 0 0
sp_reserve_write_off 9996 9996 0 0.00 6 13493 194 0 0
sp_supplier_invoice 5395 5395 0 0.00 6 81219 3471 0 0
sp_supplier_order 5392 5392 0 0.00 4 39074 1329 0 0
srv_aggregate_perf_data 103 103 0 0.00 2861 179711 20091 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2156 147444 46719 0 0
srv_find_qd_qs_mism 1016618 1016618 0 0.00 0 17245 5 0 0
srv_make_invnt_saldo 548 548 0 0.00 30 17211 874 0 0
srv_make_money_saldo 708 708 0 0.00 0 1803 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 123359 335046 244557 0 0
t$perf-abend:sp_add_invoice_to_stock 79 79 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 4897 4897 0 0.00 0 12883 194 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 5160 92 0 0
t$perf-norm:sp_cancel_client_order 1692 1692 0 0.00 0 7343 26 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 5406 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 5008 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 3750 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 1943 17 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 35459 1917 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 1343 7 0 0
t$perf-norm:sp_client_order 20386 20386 0 0.00 0 12564 20 0 0
t$perf-norm:sp_customer_reserve 996 996 0 0.00 0 3236 9 0 0
t$perf-norm:sp_pay_from_customer 6924 6924 0 0.00 0 7313 11 0 0
t$perf-norm:sp_pay_to_supplier 5753 5753 0 0.00 0 6800 11 0 0
t$perf-norm:sp_reserve_write_off 9996 9996 0 0.00 0 11586 16 0 0
t$perf-norm:sp_supplier_invoice 5395 5395 0 0.00 0 6690 18 0 0
t$perf-norm:sp_supplier_order 5392 5392 0 0.00 0 15540 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 318 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 606 10 0 0
t$perf-norm:srv_make_invnt_saldo 548 548 0 0.00 0 993 18 0 0
t$perf-norm:srv_make_money_saldo 708 708 0 0.00 0 9692 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 9942 9784 158 1.58 0 11310 18 0 158
v_all_customers 37350 37350 0 0.00 0 25 1 0 0
v_all_suppliers 29844 29844 0 0.00 0 19 1 0 0
v_all_wares 40772 40772 0 0.00 0 87 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 4129 19 0 0
v_cancel_client_order 3384 3384 0 0.00 0 8970 10 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 2165 7 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 9189 12 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 6054 10 0 0
v_cancel_supplier_invoice 834 820 14 1.67 0 3677 17 0 14
v_cancel_supplier_order 748 748 0 0.00 0 1699 8 0 0
v_cancel_write_off 1910 1910 0 0.00 0 3581 13 0 0
v_max_id_clo_ord 5392 5392 0 0.00 0 4837 3 0 0
v_max_id_clo_res 996 996 0 0.00 0 2812 16 0 0
v_max_id_ord_sup 5382 5382 0 0.00 0 5366 5 0 0
v_max_non_paid_invoice 1605 1605 0 0.00 0 3590 5 0 0
v_max_non_paid_realizn 3656 3656 0 0.00 0 7588 11 0 0
v_min_id_clo_ord 5392 5392 0 0.00 0 10754 9 0 0
v_min_id_clo_res 996 996 0 0.00 0 11788 14 0 0
v_min_id_ord_sup 5382 5382 0 0.00 0 4466 10 0 0
v_min_non_paid_invoice 5753 5753 0 0.00 0 12567 26 0 0
v_min_non_paid_realizn 6924 6924 0 0.00 0 8078 28 0 0
v_our_firm 9958 9958 0 0.00 0 18 1 0 0
v_reserve_write_off 19992 19992 0 0.00 0 6837 7 0 0
x_lock_depdocs_on_canc_invoice 11045 11045 0 0.00 0 20488 117 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 8413 339 0 0
x_make_qstorno_1000_1200 5392 5392 0 0.00 1 39062 1250 0 0
x_make_qstorno_1200_2000 5395 5395 0 0.00 3 68252 3320 0 0
x_make_qstorno_2100_3300 446893 446893 0 0.00 0 41024 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-10-2022 22:39:51 to 09-10-2022 22:39:55.

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
20:08:31 2 8192000000 8798962560 8810430464 5364032 0.065 3 1 2 0 5464960 58144 42784
20:09:32 22985 8192000000 9894794976 10204106752 982840656 10.712 100 92 184 0 1013091376 5595440 24655280
20:10:57 20113 8192000000 9945111568 10269573120 1031204816 11.180 100 87 174 0 1062157152 8292512 22659824
20:12:28 7573 8192000000 9985948912 10314051584 1063225984 11.487 100 87 173 0 1094882848 9799216 21857648
20:13:42 7095 8192000000 10032971808 10376155136 1115527104 11.985 100 86 172 0 1147314240 11161920 20625216
20:14:56 8276 8192000000 10074955088 10430545920 1156420464 12.370 100 90 180 0 1191393376 13877280 21095632
20:16:09 6151 8192000000 10114817520 10481987584 1201648608 12.792 100 76 152 0 1231199936 12449680 17101648
20:17:18 1461 8192000000 10145694240 10518024192 1237649824 13.125 100 78 153 0 1266701088 12076240 16975024
20:18:22 4665 8192000000 10174279920 10555195392 1263145472 13.359 100 81 162 0 1295017280 13857424 18014384
20:19:28 1537 8192000000 10209929088 10597576704 1292394928 13.626 100 91 180 1 1325505552 13384752 19725872
20:20:31 41071 8192000000 10218375216 10617606144 1313624864 13.819 100 55 110 0 1332713504 6084672 13003968
20:22:12 9965 8192000000 10220804160 10632962048 1321598288 13.891 100 29 58 0 1335160544 7117520 6444736
20:23:26 7868 8192000000 10223918576 10641252352 1332446512 13.989 100 23 46 1 1347997648 10013504 5537632
20:24:36 17577 8192000000 10231791376 10653634560 1339426560 14.052 100 21 42 0 1353932496 10101952 4403984
20:25:57 60230 8192000000 10227560448 10655875072 1343057360 14.085 100 36 71 1 1356834944 4911392 8866192
20:27:57 5754 8192000000 10223382400 10645671936 1321818352 13.893 99 23 47 0 1334005952 7469632 4717968
20:29:03 9116 8192000000 10173811840 10581630976 1253910128 13.274 96 15 30 0 1262470496 5162256 3398112
20:30:16 60465 8192000000 10138969312 10531688448 1172264624 12.518 92 36 69 2 1185037664 4197232 8575808
20:32:17 5160 8192000000 10153420208 10551902208 1238316208 13.131 98 36 72 2 1253034336 6453136 8264992
20:33:25 7913 8192000000 10159741824 10568642560 1274119440 13.459 100 17 34 1 1283635264 5230928 4284896
20:34:33 5895 8192000000 10171844800 10583719936 1286047184 13.568 100 19 37 0 1295744576 5293824 4403568
20:35:42 19683 8192000000 10198144288 10597830656 1292544432 13.627 100 52 103 2 1311230432 6779632 11906368
20:37:02 7815 8192000000 10198153088 10607570944 1298621760 13.683 100 17 34 0 1307904016 5234832 4047424
20:38:11 12232 8192000000 10212880160 10628698112 1319260208 13.870 100 23 46 1 1330148240 4963344 5924688
20:39:32 7460 8192000000 10240142816 10650603520 1335566864 14.017 100 40 78 1 1353440336 8903792 8969680
20:40:45 30109 8192000000 10267913920 10679869440 1353194528 14.176 100 40 79 1 1377901568 15163792 9543248
20:42:17 60883 8192000000 10261190016 10690527232 1360655472 14.243 100 36 72 0 1382663584 13355792 8652320
20:44:24 35373 8192000000 10276815680 10703630336 1354918304 14.192 99 20 40 0 1375891920 15260640 5712976
20:46:12 3274 8192000000 10300453920 10718318592 1370747696 14.334 99 55 108 0 1401375136 18245840 12381600
20:47:21 3399 8192000000 10323529888 10743255040 1396134368 14.561 100 63 120 0 1429845104 19846848 13863888
20:48:32 16299 8192000000 10334238000 10759417856 1405205472 14.641 100 53 106 0 1440391520 22414352 12771696
20:49:55 3190 8192000000 10330083776 10742509568 1386379056 14.474 99 70 138 0 1425034592 21940912 16714624
20:51:01 9025 8192000000 10333723696 10756943872 1409347152 14.678 100 44 88 0 1446180768 25070528 11763088
20:52:15 3400 8192000000 10335852608 10736365568 1386952352 14.479 100 89 173 1 1439105408 31196256 20956800
20:53:23 4786 8192000000 10323686208 10739511296 1370080304 14.328 98 54 104 0 1416572256 33320928 13171024
20:54:36 4161 8192000000 10334994224 10743713792 1382716672 14.441 99 78 155 1 1433768224 31934896 19116656
20:55:46 3104 8192000000 10332986272 10742382592 1387563520 14.484 100 65 128 0 1433659984 30224496 15871968
20:56:56 2156 8192000000 10302778528 10706874368 1351139632 14.158 99 75 145 0 1404334512 34475632 18719248
20:58:02 3588 8192000000 10320365968 10731421696 1363353840 14.267 100 77 148 3 1423120432 39918608 19847984
20:59:15 3946 8192000000 10277790080 10696327168 1336904736 14.029 99 49 98 0 1378563296 28723360 12935200
21:00:24 16539 8192000000 10258647648 10685550592 1326844544 13.939 98 24 44 1 1355013152 22024512 6144096
21:01:46 12060 8192000000 10279735248 10709893120 1344333664 14.096 99 28 56 0 1377189808 25311392 7544752
21:03:02 57326 8192000000 10275071552 10715627520 1357512928 14.215 100 31 62 0 1388247040 22248720 8485392
21:05:22 21925 8192000000 10293063696 10726477824 1371277376 14.338 100 36 71 3 1401880368 21161888 9441104
21:06:45 37823 8192000000 10275943536 10725527552 1368787040 14.316 100 18 36 0 1391416304 17978400 4650864
21:08:34 75239 8192000000 10256897136 10696531968 1314890400 13.830 96 19 36 0 1325585792 6762064 3933328
21:10:56 44134 8192000000 10272295808 10703273984 1346696224 14.118 99 37 73 0 1365910864 11217952 7996688
21:12:41 53582 8192000000 10272332464 10727112704 1370290672 14.330 100 17 31 0 1392727600 18154480 4282448
21:14:47 30875 8192000000 10271035984 10728120320 1376722144 14.387 100 15 31 0 1393207408 13142448 3342816
21:16:31 31729 8192000000 10293942464 10738712576 1386938256 14.479 100 46 90 0 1406144480 9123728 10082496
21:18:03 21146 8192000000 10304094112 10741624832 1391162048 14.516 100 68 137 2 1415970128 8925488 15882592
21:19:25 26080 8192000000 10291260432 10744532992 1395690608 14.557 100 18 36 0 1407874768 8263760 3920400
21:20:59 53467 8192000000 10281485920 10746257408 1379835136 14.415 99 17 32 1 1396291616 12815696 3640784
21:22:53 62422 8192000000 10303002112 10761576448 1401371248 14.607 100 30 58 0 1420789040 12910656 6507136
21:24:58 50025 8192000000 10304717376 10769121280 1393562720 14.538 99 21 42 0 1416469776 17971808 4935248
21:27:47 48080 8192000000 10309463504 10763403264 1386823664 14.478 99 15 30 0 1404222000 13965296 3433040
21:29:37 110289 8192000000 10284562928 10745503744 1317167504 13.851 94 10 20 0 1334024992 14798208 2059280
21:32:39 26579 8192000000 10328251664 10764759040 1393457600 14.537 100 36 72 0 1411420016 9745280 8217136
21:34:12 64888 8192000000 10312236128 10750631936 1359013360 14.228 97 27 54 0 1376707984 12176288 5518336
21:36:21 51254 8192000000 10285360304 10738290688 1322357184 13.898 95 10 20 0 1344946208 20092752 2496272
21:38:15 55617 8192000000 10275494192 10727628800 1345998240 14.111 98 26 52 1 1364045312 11953872 6093200
21:40:20 82949 8192000000 10277748640 10726477824 1349052928 14.139 98 18 36 0 1362720784 10042896 3624960
21:42:46 64707 8192000000 10276784816 10732457984 1366468608 14.295 99 18 36 1 1384777280 14165600 4143072
21:46:11 43653 8192000000 10255306912 10702454784 1329206768 13.960 97 22 44 0 1348269408 13937184 5125456
21:49:28 36107 8192000000 10267045408 10717675520 1353581056 14.180 99 13 26 2 1370441472 13367008 3493408
21:51:09 61524 8192000000 10262684864 10719973376 1343042320 14.085 98 17 34 0 1362872384 15712768 4117296
21:53:25 53409 8192000000 10285708208 10724089856 1371810336 14.343 100 23 46 0 1386219776 9194176 5215264
21:55:21 70934 8192000000 10282944704 10723360768 1323756208 13.911 96 19 38 0 1339502976 11741968 4004800
21:57:40 130626 8192000000 10285470848 10722258944 1306100304 13.751 95 10 20 0 1330509392 22485680 1923408
22:01:12 93714 8192000000 10276565856 10718310400 1330152832 13.969 97 23 46 1 1352951440 18138800 4659808
22:03:56 84285 8192000000 10267323632 10705055744 1350983520 14.156 99 33 66 1 1369350032 11296752 7069760
22:06:22 53271 8192000000 10264848432 10715435008 1352844160 14.173 99 8 16 0 1370378752 16052112 1482480
22:08:17 47253 8192000000 10279067504 10730606592 1378499232 14.403 100 13 26 0 1395056672 13630656 2926784
22:10:28 83642 8192000000 10297951712 10748829696 1364238720 14.275 98 7 14 0 1381014272 15488176 1287376
22:13:10 116452 8192000000 10279194736 10735759360 1274407920 13.462 92 15 26 1 1293788512 16471952 2908640
22:16:17 30141 8192000000 10302162672 10739638272 1369893856 14.326 99 42 84 3 1387931168 8714944 9322368
22:17:55 97306 8192000000 10291123984 10759376896 1370657280 14.333 99 12 24 0 1391386096 17793680 2935136
22:20:36 37475 8192000000 10297207472 10744991744 1371059984 14.337 99 18 36 0 1386999040 11928432 4010624
22:22:54 147444 8192000000 10300145408 10763857920 1334655424 14.009 96 8 16 0 1351199168 14851296 1692448
22:26:26 82814 8192000000 10288049376 10735673344 1348077072 14.130 97 27 53 0 1363902608 10015872 5809664
22:29:00 35947 8192000000 10283823680 10743640064 1371237152 14.338 99 16 32 1 1385688528 10390224 4061152
22:30:46 43488 8192000000 10281860976 10739548160 1385779872 14.468 100 7 14 0 1394963760 7066416 2117472
22:32:33 71249 8192000000 10250737856 10703572992 1324364416 13.916 96 19 38 0 1336513808 7617376 4532016
22:35:09 65929 8192000000 10275713040 10736607232 1373515904 14.359 100 9 18 0 1388576480 12787744 2272832
22:37:17 70222 8192000000 10279863552 10734227456 1334213936 14.005 96 15 28 2 1352233600 14506240 3513424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-10-2022 22:39:57 to 09-10-2022 22:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 79 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 09-10-2022 22:40:13 to 09-10-2022 22:40:15.

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          238341
MON$OLDEST_ACTIVE               238711
MON$OLDEST_SNAPSHOT             238711
MON$NEXT_TRANSACTION            238711
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                       2004736
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 ---
:100: 
:101: mscs-test-161.budzdorov.ru    Sun Oct  9 22:38:32 2022
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:103: 
:104: 
:105: mscs-test-161.budzdorov.ru    Sun Oct  9 22:38:32 2022
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:107: 
--- end of diff output ---

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

09.10.22 22:40:16. 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-09 22:40:18 No severe PSQL-related problems occured
09.10.22 22:40:20. End of report.