Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.12.2022 16:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14816208 kB
MemAvailable 30047612 kB
Buffers 0 kB
Cached 16864284 kB
SwapCached 3496 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-12-17 16:38:34
DTS_END                         2022-12-17 16:38:34.4700
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/39732
TRN_ID                          236555
ATT_ID                          675
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: 9076.67 20951 1089200
customer order: creation 164.89 379 19787
customer order: refuse 14.33 326 1720
order to supplier: creation 43.79 1266 5255
order to supplier: removal 3.39 105310 407
invoice (draft): creation 44.84 3387 5381
invoice (draft): removal 52.19 760 6263
invoice accept: apply 40.26 19137 4831
invoice accept: cancel 47.46 786 5695
customer reserve: creation 3760.61 188 451273
customer reserve: removal 4619.74 62 554369
realization accept: apply 79.44 198 9533
realization accept: cancel 82.08 115 9850
payment from customer: creation 56.40 117 6768
payment from customer: removal 5.50 73 660
payment to supplier: creation 46.80 135 5616
payment to supplier: removal 3.40 108 408
service: total inventory turnovers 4.60 1258 552
service: total monetary turnovers 6.13 28 736
service: aggregate perf. data 0.77 18859 92
service: refresh index statistics 0.03 266524 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-12-2022 16:39:20 to 17-12-2022 16:39:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3188
TEST_TIME 2 3267
TEST_TIME 3 3806
TEST_TIME 4 3525
TEST_TIME 5 3388
TEST_TIME 6 4142
TEST_TIME 7 3972
TEST_TIME 8 4319
TEST_TIME 9 5010
TEST_TIME 10 4942
TEST_TIME 11 5371
TEST_TIME 12 5626
TEST_TIME 13 5756
TEST_TIME 14 6005
TEST_TIME 15 6216
TEST_TIME 16 6827
TEST_TIME 17 7033
TEST_TIME 18 7042
TEST_TIME 19 7638
TEST_TIME 20 7957
TEST_TIME 21 8066
TEST_TIME 22 8304
TEST_TIME 23 8424
TEST_TIME 24 8391
TEST_TIME 25 8875
TEST_TIME 26 8888
TEST_TIME 27 8808
TEST_TIME 28 8997
TEST_TIME 29 9403
TEST_TIME 30 9999
TEST_TIME 31 10400
TEST_TIME 32 10334
TEST_TIME 33 10095
TEST_TIME 34 10745
TEST_TIME 35 10685
TEST_TIME 36 10481
TEST_TIME 37 10374
TEST_TIME 38 10291
TEST_TIME 39 10074
TEST_TIME 40 9993
TEST_TIME 41 9925
TEST_TIME 42 9926
TEST_TIME 43 9805
TEST_TIME 44 9728
TEST_TIME 45 9611
TEST_TIME 46 9556
TEST_TIME 47 9489
TEST_TIME 48 9524
TEST_TIME 49 9424
TEST_TIME 50 9382
TEST_TIME 51 9313
TEST_TIME 52 9179
TEST_TIME 53 9171
TEST_TIME 54 9174
TEST_TIME 55 9097
TEST_TIME 56 9002
TEST_TIME 57 9103
TEST_TIME 58 9063
TEST_TIME 59 9076
TEST_TIME 60 9067
TEST_TIME 61 9028
TEST_TIME 62 9143
TEST_TIME 63 9156
TEST_TIME 64 9199
TEST_TIME 65 9272
TEST_TIME 66 9293
TEST_TIME 67 9280
TEST_TIME 68 9289
TEST_TIME 69 9288
TEST_TIME 70 9324
TEST_TIME 71 9279
TEST_TIME 72 9264
TEST_TIME 73 9231
TEST_TIME 74 9129
TEST_TIME 75 9142
TEST_TIME 76 9109
TEST_TIME 77 9142
TEST_TIME 78 9142
TEST_TIME 79 9133
TEST_TIME 80 9069
TEST_TIME 81 9042
TEST_TIME 82 9078
TEST_TIME 83 9002
TEST_TIME 84 9005
TEST_TIME 85 9002
TEST_TIME 86 8975
TEST_TIME 87 9022
TEST_TIME 88 9032
TEST_TIME 89 9033
TEST_TIME 90 9036
TEST_TIME 91 9125
TEST_TIME 92 9160
TEST_TIME 93 9134
TEST_TIME 94 9127
TEST_TIME 95 9211
TEST_TIME 96 9194
TEST_TIME 97 9182
TEST_TIME 98 9189
TEST_TIME 99 9250
TEST_TIME 100 9288
TEST_TIME 101 9250
TEST_TIME 102 9182
TEST_TIME 103 9146
TEST_TIME 104 9125
TEST_TIME 105 9103
TEST_TIME 106 9074
TEST_TIME 107 9034
TEST_TIME 108 9001
TEST_TIME 109 8946
TEST_TIME 110 8921
TEST_TIME 111 8913
TEST_TIME 112 9010
TEST_TIME 113 8998
TEST_TIME 114 8994
TEST_TIME 115 9045
TEST_TIME 116 9062
TEST_TIME 117 9023
TEST_TIME 118 9081
TEST_TIME 119 9126
TEST_TIME 120 9078

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-12-2022 16:39:24 to 17-12-2022 16:39:27.

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 1506852 1506852 0 0.00 0 11960 6 0 0
doc_list_biud 1506852 1506852 0 0.00 0 25434 28 0 0
sp_add_invoice_to_stock 4879 4831 48 0.98 42 226599 19137 0 48
sp_cancel_adding_invoice 5695 5695 0 0.00 5 66082 786 0 0
sp_cancel_client_order 1720 1720 0 0.00 4 8472 326 0 0
sp_cancel_customer_reserve 554369 554369 0 0.00 0 25436 62 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 3 8839 74 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 5332 109 0 0
sp_cancel_supplier_invoice 6265 6263 2 0.03 1 19628 761 0 2
sp_cancel_supplier_order 407 407 0 0.00 6 916950 105310 0 0
sp_cancel_write_off 9850 9850 0 0.00 1 10488 116 0 0
sp_client_order 19787 19787 0 0.00 3 26632 380 0 0
sp_customer_reserve 451273 451273 0 0.00 0 59683 189 0 0
sp_fill_shopping_cart 30409 30409 0 0.00 1 16173 39 0 0
sp_fill_shopping_cart_clo_res 451268 451268 0 0.00 0 13519 15 0 0
sp_get_clo_for_invoice 4826 4826 0 0.00 0 24458 301 0 0
sp_kill_cost_storno 16613 16613 0 0.00 0 9147 57 0 0
sp_kill_qstorno_ret_qs2qd 1125258 1125258 0 0.00 0 25432 33 0 0
sp_kill_qty_storno 592663 592663 0 0.00 0 25434 72 0 0
sp_lock_selected_doc 19959 19959 0 0.00 0 7708 5 0 0
sp_make_cost_storno 26743 26743 0 0.00 0 11936 55 0 0
sp_multiply_rows_for_pdistr 26743 26743 0 0.00 0 11212 27 0 0
sp_multiply_rows_for_qdistr 19787 19787 0 0.00 0 13076 155 0 0
sp_pay_from_customer 6768 6768 0 0.00 4 11808 118 0 0
sp_pay_to_supplier 5616 5616 0 0.00 4 12317 135 0 0
sp_payment_common 12384 12384 0 0.00 1 12293 37 0 0
sp_qd_handle_on_cancel_clo 1720 1720 0 0.00 0 8439 299 0 0
sp_qd_handle_on_invoice_upd_sts 10521 10521 0 0.00 0 22688 320 0 0
sp_qd_handle_on_reserve_upd_sts 9533 9533 0 0.00 0 19970 76 0 0
sp_reserve_write_off 9533 9533 0 0.00 6 20036 198 0 0
sp_supplier_invoice 5381 5381 0 0.00 5 97667 3387 0 0
sp_supplier_order 5255 5255 0 0.00 5 23658 1267 0 0
srv_aggregate_perf_data 92 92 0 0.00 2935 107164 18860 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1921 257159 47831 0 0
srv_find_qd_qs_mism 1043039 1043039 0 0.00 0 10664 6 0 0
srv_make_invnt_saldo 552 552 0 0.00 36 32711 1259 0 0
srv_make_money_saldo 736 736 0 0.00 0 1523 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 140554 491348 266525 0 0
t$perf-abend:sp_add_invoice_to_stock 48 48 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4831 4831 0 0.00 0 19489 177 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 8217 128 0 0
t$perf-norm:sp_cancel_client_order 1720 1720 0 0.00 0 12070 30 0 0
t$perf-norm:sp_cancel_customer_reserve 617 617 0 0.00 0 12460 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 2818 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 1441 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 1234 17 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 42593 1826 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 14254 20 0 0
t$perf-norm:sp_client_order 19787 19787 0 0.00 0 12453 20 0 0
t$perf-norm:sp_customer_reserve 927 927 0 0.00 0 4768 10 0 0
t$perf-norm:sp_pay_from_customer 6768 6768 0 0.00 0 11237 13 0 0
t$perf-norm:sp_pay_to_supplier 5616 5616 0 0.00 0 6980 15 0 0
t$perf-norm:sp_reserve_write_off 9533 9533 0 0.00 0 9822 19 0 0
t$perf-norm:sp_supplier_invoice 5381 5381 0 0.00 0 12802 27 0 0
t$perf-norm:sp_supplier_order 5255 5255 0 0.00 0 12407 19 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 297 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 393 12 0 0
t$perf-norm:srv_make_invnt_saldo 552 552 0 0.00 0 847 19 0 0
t$perf-norm:srv_make_money_saldo 736 736 0 0.00 0 4578 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 9744 9648 96 0.98 0 4756 19 0 96
v_all_customers 36232 36232 0 0.00 0 201 1 0 0
v_all_suppliers 29284 29284 0 0.00 0 21 1 0 0
v_all_wares 39574 39574 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 719 7 0 0
v_cancel_client_order 3440 3440 0 0.00 0 5648 7 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 3609 8 0 0
v_cancel_customer_reserve 1234 1234 0 0.00 0 2962 8 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 5324 10 0 0
v_cancel_supplier_invoice 806 802 4 0.49 0 3650 23 0 4
v_cancel_supplier_order 814 814 0 0.00 0 1766 5 0 0
v_cancel_write_off 1998 1998 0 0.00 0 5657 21 0 0
v_max_id_clo_ord 5250 5250 0 0.00 0 176 1 0 0
v_max_id_clo_res 927 927 0 0.00 0 887 8 0 0
v_max_id_ord_sup 5372 5372 0 0.00 0 4951 3 0 0
v_max_non_paid_invoice 1596 1596 0 0.00 0 2492 4 0 0
v_max_non_paid_realizn 3531 3531 0 0.00 0 5836 6 0 0
v_min_id_clo_ord 5250 5250 0 0.00 0 4871 8 0 0
v_min_id_clo_res 927 927 0 0.00 0 2968 12 0 0
v_min_id_ord_sup 5372 5372 0 0.00 0 9449 8 0 0
v_min_non_paid_invoice 5616 5616 0 0.00 0 12289 28 0 0
v_min_non_paid_realizn 6768 6768 0 0.00 0 9918 28 0 0
v_our_firm 9816 9816 0 0.00 0 22 1 0 0
v_reserve_write_off 19066 19066 0 0.00 0 5096 7 0 0
x_lock_depdocs_on_canc_invoice 11557 11557 0 0.00 0 21104 124 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 12356 399 0 0
x_make_qstorno_1000_1200 5255 5255 0 0.00 1 23604 1184 0 0
x_make_qstorno_1200_2000 5381 5381 0 0.00 2 97563 3253 0 0
x_make_qstorno_2100_3300 451273 451273 0 0.00 0 59671 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-12-2022 16:39:28 to 17-12-2022 16:39:32.

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:08:38 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:39 27935 8192000000 9914164448 10224648192 984492192 10.728 100 82 164 0 1012214352 6228832 21493328
14:11:10 19039 8192000000 9949992752 10273292288 1035071008 11.217 100 88 176 0 1066383936 8598624 22714304
14:12:34 17087 8192000000 9980206576 10313781248 1060444784 11.461 100 78 155 0 1089730016 9725232 19560000
14:13:55 13535 8192000000 10025602016 10371153920 1116670272 11.996 100 85 170 0 1147127440 10182480 20274688
14:15:11 7692 8192000000 10076740992 10437636096 1169559776 12.493 100 76 151 1 1198043504 11370336 17113392
14:16:21 2503 8192000000 10132980000 10503348224 1225440864 13.012 100 79 159 0 1255479072 12252208 17786000
14:17:25 1610 8192000000 10179054400 10556796928 1264244432 13.369 100 85 170 0 1297374176 14466848 18662896
14:18:29 1352 8192000000 10202373552 10585632768 1285026128 13.559 100 88 175 1 1318099072 13621696 19451248
14:19:32 9818 8192000000 10200185968 10591469568 1293218416 13.634 100 62 124 0 1317930832 10562640 14149776
14:20:42 6456 8192000000 10189345120 10599714816 1303378256 13.726 100 25 50 1 1315447184 6802336 5266592
14:21:52 58957 8192000000 10205371568 10621722624 1314422864 13.826 100 25 48 1 1324722064 4708080 5591120
14:23:51 4608 8192000000 10230656864 10640859136 1327819168 13.947 100 39 79 0 1343419936 7173920 8426848
14:24:59 3639 8192000000 10229905456 10642640896 1330205376 13.969 100 35 67 1 1344295328 6782304 7307648
14:26:07 62378 8192000000 10231160032 10646016000 1322531632 13.900 99 26 53 1 1334321536 5854528 5935376
14:28:12 7878 8192000000 10209366368 10628390912 1303375296 13.726 98 30 58 1 1315480288 5527200 6577792
14:29:21 6367 8192000000 10188864816 10599186432 1289961456 13.604 99 17 32 0 1303553776 9483008 4109312
14:30:28 16109 8192000000 10144818944 10538786816 1217511536 12.939 97 26 48 2 1228938656 5820000 5607120
14:31:44 3828 8192000000 10128445472 10520989696 1197236064 12.751 96 34 68 1 1212418384 7742352 7439968
14:32:48 5035 8192000000 10162234432 10558283776 1254418128 13.279 99 29 58 1 1265966320 5100032 6448160
14:33:54 8992 8192000000 10146578800 10546573312 1236095152 13.110 98 25 51 0 1248322160 6025440 6201568
14:35:03 9879 8192000000 10177509824 10590126080 1290810080 13.612 100 23 44 0 1300964544 5005168 5149296
14:36:19 20279 8192000000 10184687632 10597687296 1294745024 13.647 100 24 48 0 1305138176 4893424 5499728
14:37:43 5414 8192000000 10197931312 10611871744 1304101360 13.733 100 31 63 0 1317487760 5910544 7475856
14:38:52 49200 8192000000 10236061712 10645999616 1335600288 14.018 100 38 75 1 1350272496 5940832 8731376
14:40:49 15387 8192000000 10273558128 10685493248 1356788096 14.209 100 45 90 1 1376580464 9824672 9967696
14:42:11 45706 8192000000 10284767616 10704056320 1373320000 14.357 100 46 93 0 1399931824 15664736 10947088
14:44:29 30507 8192000000 10292576272 10718904320 1378290192 14.401 100 29 58 1 1411075488 25429152 7356144
14:46:08 4730 8192000000 10319922608 10734997504 1388340448 14.491 100 76 152 0 1430504000 23560992 18602560
14:47:18 3965 8192000000 10315419840 10735845376 1391689440 14.521 100 55 108 1 1429624592 24907712 13027440
14:48:26 8027 8192000000 10337336608 10755276800 1409072048 14.676 100 57 114 0 1450401776 27590240 13739488
14:49:40 13329 8192000000 10334185328 10757419008 1399179696 14.588 100 58 116 1 1442614752 29090624 14344432
14:50:58 5446 8192000000 10333167504 10752704512 1396938640 14.568 100 60 120 0 1442426496 31391760 14096096
14:52:12 6016 8192000000 10327968880 10744295424 1385360224 14.464 100 64 127 3 1436944832 36320432 15264176
14:53:23 2516 8192000000 10311867584 10712018944 1354354672 14.187 98 72 139 0 1412971888 42006576 16610640
14:54:29 1919 8192000000 10332583936 10745356288 1374929952 14.371 100 68 136 0 1440886624 48219728 17736944
14:55:41 3831 8192000000 10333889216 10747498496 1378790272 14.406 100 67 132 0 1440024816 44751200 16483344
14:56:52 2806 8192000000 10359887328 10773278720 1393632768 14.538 100 82 161 1 1468237648 52362096 22242784
14:58:00 4185 8192000000 10341070880 10757296128 1385437088 14.465 100 66 131 0 1447234736 45325840 16471808
14:59:11 12813 8192000000 10330618464 10749087744 1381061776 14.426 100 61 122 1 1439076816 42522256 15492784
15:00:27 4747 8192000000 10315766992 10738368512 1366148160 14.293 99 36 73 1 1410019456 34317008 9554288
15:01:38 12041 8192000000 10329409888 10753024000 1370223616 14.329 99 36 72 1 1422750592 42102304 10424672
15:03:00 3297 8192000000 10354001424 10774831104 1388205296 14.490 100 54 107 1 1452270272 50258384 13806592
15:04:05 22681 8192000000 10313705600 10750189568 1358108080 14.220 98 26 52 1 1406275408 40795264 7372064
15:05:41 14050 8192000000 10304185216 10735673344 1356172144 14.203 99 24 46 0 1407820096 45192272 6455680
15:06:59 33280 8192000000 10307428048 10744324096 1363579392 14.269 100 23 46 1 1420431520 50106816 6745312
15:08:33 69362 8192000000 10284974128 10727419904 1294309760 13.643 95 12 24 0 1338746096 41229632 3206704
15:10:45 47601 8192000000 10318611136 10746789888 1366134416 14.292 99 17 34 1 1394291040 24178832 3977792
15:12:38 91288 8192000000 10290465808 10738778112 1355361600 14.196 98 6 12 0 1372385680 15997520 1026560
15:15:09 29513 8192000000 10300690336 10730119168 1374003136 14.363 99 64 116 1 1392791568 5292496 13495936
15:16:40 16582 8192000000 10287722448 10736033792 1382401264 14.438 100 21 43 0 1394795232 7762000 4631968
15:18:16 71742 8192000000 10287477536 10743463936 1386183792 14.472 100 17 34 0 1402615648 12492736 3939120
15:20:28 51987 8192000000 10288434896 10737934336 1377844544 14.397 99 20 40 0 1396309888 13914864 4550480
15:22:20 45166 8192000000 10294751568 10748432384 1382255760 14.437 99 19 38 0 1394512064 8220608 4035696
15:24:11 129023 8192000000 10306752064 10764587008 1404739440 14.637 100 18 36 0 1423684160 14434384 4510336
15:27:29 83749 8192000000 10301688496 10767036416 1406656224 14.654 100 18 35 0 1419740352 8437200 4646928
15:29:54 31207 8192000000 10308787552 10775875584 1414089408 14.720 100 15 29 0 1427072608 9503968 3479232
15:31:26 55261 8192000000 10300819728 10761490432 1375147760 14.373 97 21 42 0 1393907456 13817216 4942480
15:33:28 55180 8192000000 10305431664 10755899392 1376298880 14.383 98 15 27 0 1392905952 13480624 3126448
15:35:25 46469 8192000000 10290702224 10760953856 1396234800 14.561 100 13 24 0 1414009712 14913056 2861856
15:38:04 65182 8192000000 10292163184 10750808064 1366749600 14.298 98 12 24 0 1386030416 16568784 2712032
15:40:16 56075 8192000000 10291476752 10760142848 1382298320 14.437 99 16 32 0 1394625456 8934880 3392256
15:42:12 74983 8192000000 10304161536 10770137088 1346178512 14.113 96 15 30 0 1369006672 19150240 3677920
15:44:34 80941 8192000000 10299334400 10747400192 1309079424 13.778 94 19 38 0 1323505664 10702800 3723440
15:46:58 96137 8192000000 10270309168 10718490624 1304833808 13.739 94 29 58 0 1323466960 12317152 6316000
15:49:35 11140 8192000000 10268455792 10702061568 1337407840 14.034 99 54 107 1 1360155968 10559600 12188528
15:50:48 11978 8192000000 10264768000 10697306112 1328711136 13.956 98 39 78 0 1348398016 11355456 8331424
15:52:03 91819 8192000000 10255543456 10711306240 1343250032 14.087 99 44 88 0 1364361104 10646176 10464896
15:54:39 21785 8192000000 10259602288 10710016000 1343064928 14.085 99 27 51 0 1361172288 12438528 5668832
15:56:01 110523 8192000000 10239351632 10701590528 1279919696 13.512 95 6 12 0 1301606096 20217472 1468928
15:58:51 62676 8192000000 10256403072 10706259968 1347912976 14.129 100 18 36 0 1365671904 12770512 4988416
16:00:59 79427 8192000000 10251794896 10704805888 1303469296 13.727 96 26 50 0 1319426272 10807376 5149600
16:03:20 58802 8192000000 10258891760 10718396416 1337615232 14.036 99 12 24 0 1353259840 13148368 2496240
16:05:20 50006 8192000000 10280135920 10716622848 1327708816 13.946 97 38 74 1 1344945904 8710112 8526976
16:07:11 51436 8192000000 10272516400 10723418112 1349039216 14.139 99 10 20 0 1362705776 11727312 1939248
16:09:07 54594 8192000000 10267531072 10732187648 1319473744 13.872 96 12 24 0 1338441280 16448688 2518848
16:11:51 10316 8192000000 10296285632 10734551040 1376543728 14.386 100 49 98 0 1398715904 11163296 11008880
16:13:05 83734 8192000000 10279521904 10736979968 1356406000 14.205 98 21 43 0 1373134800 12079904 4648896
16:15:28 43773 8192000000 10299688992 10745421824 1369727104 14.325 99 32 62 0 1388436864 11637328 7072432
16:17:19 257159 8192000000 10298607568 10747867136 1369818464 14.325 98 26 52 0 1389925296 14179856 5926976
16:23:14 48982 8192000000 10273783760 10739613696 1370782880 14.334 99 4 8 0 1379828736 8333648 712208
16:25:15 67361 8192000000 10277854608 10723790848 1312190640 13.806 94 15 30 0 1324571888 8445728 3935520
16:27:26 75930 8192000000 10273303984 10731102208 1327287360 13.943 96 22 42 0 1345773488 14083856 4402272
16:29:48 120188 8192000000 10259990976 10715918336 1306786208 13.757 95 18 36 0 1329816016 18434848 4594960
16:32:51 47169 8192000000 10263019056 10713853952 1319905568 13.876 96 17 34 1 1333056224 9197040 3953616
16:34:56 58469 8192000000 10291500624 10725421056 1343202416 14.086 98 38 77 0 1366690592 14984576 8503600
16:36:55 98054 8192000000 10290011952 10734333952 1329585568 13.963 97 11 22 0 1348372096 16563232 2223296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-12-2022 16:39:34 to 17-12-2022 16:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 48 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-12-2022 16:39:50 to 17-12-2022 16:39:52.

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          236222
MON$OLDEST_ACTIVE               237127
MON$OLDEST_SNAPSHOT             237127
MON$NEXT_TRANSACTION            237127
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                       2053344
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:768: 
:769: mscs-test-161.budzdorov.ru    Sat Dec 17 16:38:44 2022
:770:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:771: 
:772: 
:773: mscs-test-161.budzdorov.ru    Sat Dec 17 16:38:44 2022
:774:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:775: 
--- end of diff output ---

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

17.12.22 16:39:54. 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-12-17 16:39:56 No severe PSQL-related problems occured
17.12.22 16:39:58. End of report.