Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.02.2023 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 15504280 kB
MemAvailable 30404532 kB
Buffers 0 kB
Cached 16184628 kB
SwapCached 3140 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 2023-02-20 22:38:38
DTS_END                         2023-02-20 22:38:38.3130
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/51596
TRN_ID                          252587
ATT_ID                          701
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: 9210.33 19377 1105240
customer order: creation 191.03 374 22923
customer order: refuse 15.50 319 1860
order to supplier: creation 51.64 1275 6197
order to supplier: removal 3.43 84032 411
invoice (draft): creation 50.01 2990 6001
invoice (draft): removal 49.72 746 5966
invoice accept: apply 43.96 17463 5275
invoice accept: cancel 44.73 741 5368
customer reserve: creation 4043.10 175 485172
customer reserve: removal 4406.04 51 528725
realization accept: apply 93.23 159 11188
realization accept: cancel 75.08 114 9010
payment from customer: creation 65.65 103 7878
payment from customer: removal 6.40 69 768
payment to supplier: creation 54.21 149 6505
payment to supplier: removal 3.92 59 470
service: total inventory turnovers 4.71 1296 565
service: total monetary turnovers 7.00 39 840
service: aggregate perf. data 0.95 18451 114
service: refresh index statistics 0.03 258937 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-02-2023 22:39:41 to 20-02-2023 22:39:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1827
TEST_TIME 2 3760
TEST_TIME 3 4296
TEST_TIME 4 4066
TEST_TIME 5 4291
TEST_TIME 6 5404
TEST_TIME 7 5558
TEST_TIME 8 5924
TEST_TIME 9 6295
TEST_TIME 10 6885
TEST_TIME 11 7014
TEST_TIME 12 7309
TEST_TIME 13 7230
TEST_TIME 14 7379
TEST_TIME 15 7467
TEST_TIME 16 7471
TEST_TIME 17 7623
TEST_TIME 18 8131
TEST_TIME 19 8039
TEST_TIME 20 8266
TEST_TIME 21 8619
TEST_TIME 22 9517
TEST_TIME 23 9328
TEST_TIME 24 9698
TEST_TIME 25 10316
TEST_TIME 26 10763
TEST_TIME 27 10565
TEST_TIME 28 10588
TEST_TIME 29 10525
TEST_TIME 30 10307
TEST_TIME 31 10227
TEST_TIME 32 10225
TEST_TIME 33 10134
TEST_TIME 34 10075
TEST_TIME 35 9914
TEST_TIME 36 9845
TEST_TIME 37 9731
TEST_TIME 38 9668
TEST_TIME 39 9597
TEST_TIME 40 9457
TEST_TIME 41 9344
TEST_TIME 42 9489
TEST_TIME 43 9442
TEST_TIME 44 9406
TEST_TIME 45 9490
TEST_TIME 46 9493
TEST_TIME 47 9517
TEST_TIME 48 9487
TEST_TIME 49 9409
TEST_TIME 50 9400
TEST_TIME 51 9656
TEST_TIME 52 9617
TEST_TIME 53 9733
TEST_TIME 54 9624
TEST_TIME 55 9737
TEST_TIME 56 9750
TEST_TIME 57 9609
TEST_TIME 58 9509
TEST_TIME 59 9429
TEST_TIME 60 9421
TEST_TIME 61 9341
TEST_TIME 62 9325
TEST_TIME 63 9289
TEST_TIME 64 9247
TEST_TIME 65 9275
TEST_TIME 66 9274
TEST_TIME 67 9318
TEST_TIME 68 9305
TEST_TIME 69 9330
TEST_TIME 70 9379
TEST_TIME 71 9379
TEST_TIME 72 9353
TEST_TIME 73 9378
TEST_TIME 74 9312
TEST_TIME 75 9474
TEST_TIME 76 9430
TEST_TIME 77 9487
TEST_TIME 78 9447
TEST_TIME 79 9338
TEST_TIME 80 9349
TEST_TIME 81 9279
TEST_TIME 82 9253
TEST_TIME 83 9254
TEST_TIME 84 9184
TEST_TIME 85 9150
TEST_TIME 86 9156
TEST_TIME 87 9196
TEST_TIME 88 9165
TEST_TIME 89 9256
TEST_TIME 90 9223
TEST_TIME 91 9219
TEST_TIME 92 9293
TEST_TIME 93 9309
TEST_TIME 94 9323
TEST_TIME 95 9369
TEST_TIME 96 9382
TEST_TIME 97 9382
TEST_TIME 98 9469
TEST_TIME 99 9468
TEST_TIME 100 9481
TEST_TIME 101 9471
TEST_TIME 102 9387
TEST_TIME 103 9371
TEST_TIME 104 9320
TEST_TIME 105 9304
TEST_TIME 106 9269
TEST_TIME 107 9294
TEST_TIME 108 9268
TEST_TIME 109 9278
TEST_TIME 110 9234
TEST_TIME 111 9192
TEST_TIME 112 9204
TEST_TIME 113 9166
TEST_TIME 114 9215
TEST_TIME 115 9220
TEST_TIME 116 9234
TEST_TIME 117 9234
TEST_TIME 118 9247
TEST_TIME 119 9255
TEST_TIME 120 9211

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-02-2023 22:39:44 to 20-02-2023 22:39:48.

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 1555249 1555249 0 0.00 0 35528 6 0 0
doc_list_biud 1555246 1555246 0 0.00 0 30691 23 0 0
sp_add_invoice_to_stock 5404 5275 129 2.38 17 203287 17463 0 129
sp_cancel_adding_invoice 5368 5368 0 0.00 3 67889 742 0 0
sp_cancel_client_order 1860 1860 0 0.00 4 10599 320 0 0
sp_cancel_customer_reserve 528725 528725 0 0.00 0 18384 52 0 0
sp_cancel_pay_from_customer 768 768 0 0.00 2 5414 69 0 0
sp_cancel_pay_to_supplier 470 470 0 0.00 2 1854 60 0 0
sp_cancel_supplier_invoice 5975 5966 9 0.15 1 14664 747 0 9
sp_cancel_supplier_order 411 411 0 0.00 6 1064712 84033 0 0
sp_cancel_write_off 9010 9010 0 0.00 1 13368 114 0 0
sp_client_order 22923 22923 0 0.00 3 72170 374 0 0
sp_customer_reserve 485172 485172 0 0.00 0 68458 175 0 0
sp_fill_shopping_cart 35104 35104 0 0.00 1 10139 36 0 0
sp_fill_shopping_cart_clo_res 485170 485170 0 0.00 0 26420 13 0 0
sp_get_clo_for_invoice 5272 5272 0 0.00 0 20484 302 0 0
sp_kill_cost_storno 15616 15616 0 0.00 0 13085 49 0 0
sp_kill_qstorno_ret_qs2qd 1072837 1072837 0 0.00 0 18344 28 0 0
sp_kill_qty_storno 567798 567798 0 0.00 0 30690 62 0 0
sp_lock_selected_doc 22714 22714 0 0.00 0 10195 5 0 0
sp_make_cost_storno 30843 30843 0 0.00 0 15701 52 0 0
sp_multiply_rows_for_pdistr 30842 30842 0 0.00 0 7744 27 0 0
sp_multiply_rows_for_qdistr 22922 22922 0 0.00 0 72129 170 0 0
sp_pay_from_customer 7878 7878 0 0.00 4 11044 104 0 0
sp_pay_to_supplier 6505 6505 0 0.00 5 13452 150 0 0
sp_payment_common 14383 14383 0 0.00 1 12219 29 0 0
sp_qd_handle_on_cancel_clo 1860 1860 0 0.00 0 10593 287 0 0
sp_qd_handle_on_invoice_upd_sts 10638 10638 0 0.00 0 30688 284 0 0
sp_qd_handle_on_reserve_upd_sts 11188 11188 0 0.00 0 17775 48 0 0
sp_reserve_write_off 11188 11188 0 0.00 6 17785 159 0 0
sp_supplier_invoice 6001 6001 0 0.00 5 91737 2991 0 0
sp_supplier_order 6197 6197 0 0.00 4 49818 1276 0 0
srv_aggregate_perf_data 114 114 0 0.00 2339 285488 18451 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 692 117611 45996 0 0
srv_find_qd_qs_mism 1053721 1053721 0 0.00 0 35528 6 0 0
srv_make_invnt_saldo 565 565 0 0.00 24 171297 1297 0 0
srv_make_money_saldo 840 840 0 0.00 1 12414 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 133416 397518 258937 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 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 5275 5275 0 0.00 0 19360 181 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 0 4399 87 0 0
t$perf-norm:sp_cancel_client_order 1860 1860 0 0.00 0 2160 8 0 0
t$perf-norm:sp_cancel_customer_reserve 772 772 0 0.00 0 343 4 0 0
t$perf-norm:sp_cancel_pay_from_customer 768 768 0 0.00 0 1107 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 470 470 0 0.00 0 420 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 5610 19 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 37978 1339 0 0
t$perf-norm:sp_cancel_write_off 1095 1095 0 0.00 0 3831 10 0 0
t$perf-norm:sp_client_order 22923 22923 0 0.00 0 7125 13 0 0
t$perf-norm:sp_customer_reserve 1118 1118 0 0.00 0 1904 5 0 0
t$perf-norm:sp_pay_from_customer 7878 7878 0 0.00 0 7726 8 0 0
t$perf-norm:sp_pay_to_supplier 6505 6505 0 0.00 0 4035 8 0 0
t$perf-norm:sp_reserve_write_off 11188 11188 0 0.00 0 9562 10 0 0
t$perf-norm:sp_supplier_invoice 6001 6001 0 0.00 0 12435 20 0 0
t$perf-norm:sp_supplier_order 6197 6197 0 0.00 0 4798 15 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 1707 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 90 3 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 3611 23 0 0
t$perf-norm:srv_make_money_saldo 840 840 0 0.00 0 3248 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10794 10536 258 2.39 0 5663 20 0 258
v_all_customers 41574 41574 0 0.00 0 145 0 0 0
v_all_suppliers 33682 33682 0 0.00 0 21 1 0 0
v_all_wares 45844 45844 0 0.00 0 56 1 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 5917 14 0 0
v_cancel_client_order 3720 3720 0 0.00 0 1998 3 0 0
v_cancel_customer_prepayment 1536 1536 0 0.00 0 1560 7 0 0
v_cancel_customer_reserve 1544 1544 0 0.00 0 1296 6 0 0
v_cancel_payment_to_supplier 940 940 0 0.00 0 1831 8 0 0
v_cancel_supplier_invoice 884 866 18 2.03 0 2126 17 0 18
v_cancel_supplier_order 822 822 0 0.00 0 281 3 0 0
v_cancel_write_off 2190 2190 0 0.00 0 7409 18 0 0
v_max_id_clo_ord 6194 6194 0 0.00 0 648 1 0 0
v_max_id_clo_res 1118 1118 0 0.00 0 2067 13 0 0
v_max_id_ord_sup 5988 5988 0 0.00 0 5756 7 0 0
v_max_non_paid_invoice 1846 1846 0 0.00 0 591 3 0 0
v_max_non_paid_realizn 4211 4211 0 0.00 0 4086 6 0 0
v_min_id_clo_ord 6194 6194 0 0.00 0 3864 5 0 0
v_min_id_clo_res 1118 1118 0 0.00 0 2003 14 0 0
v_min_id_ord_sup 5988 5988 0 0.00 0 5733 8 0 0
v_min_non_paid_invoice 6505 6505 0 0.00 0 12213 21 0 0
v_min_non_paid_realizn 7878 7878 0 0.00 0 9906 23 0 0
v_our_firm 11604 11604 0 0.00 0 19 1 0 0
v_reserve_write_off 22376 22376 0 0.00 0 6726 6 0 0
x_lock_depdocs_on_canc_invoice 10901 10901 0 0.00 0 17690 111 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 8453 295 0 0
x_make_qstorno_1000_1200 6197 6197 0 0.00 1 49811 1208 0 0
x_make_qstorno_1200_2000 6001 6001 0 0.00 2 91715 2853 0 0
x_make_qstorno_2100_3300 485172 485172 0 0.00 0 68457 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-02-2023 22:39:49 to 20-02-2023 22:39:53.

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:42 3 8192000000 8800251952 8811937792 6619920 0.080 2 2 2 0 6721376 58672 42784
20:09:43 28289 8192000000 9893130960 10206699520 981051392 10.694 100 88 176 0 1010924144 6146400 23726352
20:11:21 19716 8192000000 9961189664 10282889216 1036909664 11.235 100 86 172 0 1066675776 7560480 22205632
20:12:46 13665 8192000000 9992322512 10324570112 1073276928 11.583 100 85 170 0 1103245936 8827920 21141088
20:14:03 7894 8192000000 10039219632 10387955712 1125431552 12.078 100 81 162 0 1154992608 9967664 19593392
20:15:17 13053 8192000000 10087685232 10446766080 1171784672 12.514 100 79 158 0 1200875120 11062576 18027872
20:16:33 4341 8192000000 10124841072 10491154432 1208875312 12.859 100 82 164 0 1240800624 13395248 18530064
20:17:39 2456 8192000000 10148969184 10524127232 1238646352 13.134 100 83 163 1 1269585664 12732320 18206992
20:18:43 4055 8192000000 10178607424 10556170240 1264130608 13.368 100 80 158 0 1295875888 13351360 18393920
20:19:49 1394 8192000000 10204927776 10587627520 1288173024 13.588 100 84 169 0 1319906112 13806336 17926752
20:20:53 2294 8192000000 10204243744 10597117952 1298560208 13.682 100 79 157 0 1328740576 11464784 18715584
20:21:57 2798 8192000000 10193048592 10598305792 1301075344 13.705 100 39 78 0 1318197072 8634960 8486768
20:23:04 12574 8192000000 10186556912 10607673344 1309868688 13.785 100 7 14 0 1317430224 5957792 1603744
20:24:18 39816 8192000000 10223904624 10633887744 1324434144 13.917 100 50 98 0 1339533840 4165040 10934656
20:26:01 4690 8192000000 10222584768 10637807616 1325489536 13.926 100 43 85 0 1342249168 6941136 9818496
20:27:10 9331 8192000000 10216183728 10638962688 1328957712 13.958 100 9 18 0 1336332480 5289936 2084832
20:28:22 47581 8192000000 10210270736 10632278016 1310257408 13.788 98 36 72 0 1324458928 5698304 8503216
20:30:14 6374 8192000000 10187877984 10596057088 1258359760 13.315 96 27 54 0 1270123760 5640944 6123056
20:31:21 6154 8192000000 10159895680 10559967232 1242263008 13.167 97 23 47 0 1253042096 5248192 5530896
20:32:30 62705 8192000000 10115584576 10517524480 1118348624 12.011 89 25 50 0 1130643328 5731024 6563680
20:34:33 5712 8192000000 10160436272 10564038656 1256609568 13.299 99 30 60 0 1271778528 8095184 7073776
20:35:39 8921 8192000000 10193337504 10601496576 1294957056 13.649 100 17 34 0 1304800240 5417552 4425632
20:36:49 16422 8192000000 10199684960 10614411264 1313487072 13.818 100 33 67 0 1326093616 5742624 6863920
20:38:06 25961 8192000000 10215211920 10632151040 1322549600 13.900 100 21 41 0 1332192304 5535728 4106976
20:39:36 29690 8192000000 10253483376 10667220992 1350684656 14.154 100 47 92 1 1370360608 9600656 10075296
20:41:32 10457 8192000000 10281973456 10699497472 1370844384 14.335 100 44 86 1 1392637904 11599936 10193584
20:42:48 20511 8192000000 10283555344 10707820544 1377233696 14.392 100 45 85 0 1402101648 15073376 9794576
20:44:13 9441 8192000000 10308224960 10728411136 1392483536 14.528 100 46 90 0 1421660288 17855984 11320768
20:45:24 5102 8192000000 10325991856 10744573952 1401853456 14.611 100 63 126 1 1433376208 17600880 13921872
20:46:32 1864 8192000000 10330868368 10738806784 1393424544 14.536 99 81 161 1 1432165984 19978816 18762624
20:47:45 7615 8192000000 10335633344 10758307840 1411536544 14.698 100 51 102 0 1447296432 23662128 12097760
20:48:56 689 8192000000 10362861872 10780692480 1425325488 14.820 100 85 170 0 1472443888 27001120 20117280
20:49:59 1529 8192000000 10363251488 10771595264 1411780464 14.700 99 88 174 2 1464157760 31763024 20614272
20:51:04 7657 8192000000 10368867824 10788470784 1426185248 14.828 100 64 128 1 1475505088 33701264 15618576
20:52:26 5139 8192000000 10353328032 10768801792 1401376800 14.607 100 69 135 1 1458562512 39731584 17454128
20:53:37 7229 8192000000 10363840544 10779820032 1409064896 14.676 100 68 132 2 1467533792 42503632 15965264
20:54:49 1853 8192000000 10366171600 10775613440 1405709008 14.646 100 88 175 2 1467763824 38919408 23135408
20:55:56 9896 8192000000 10356161136 10775785472 1389995792 14.506 99 59 118 0 1454427024 48732544 15698688
20:57:13 11690 8192000000 10349747632 10778947584 1400633360 14.601 100 38 74 1 1457365168 46593920 10137888
20:58:41 24792 8192000000 10311306176 10732568576 1342259312 14.078 99 36 72 1 1405298352 52409008 10630032
21:00:14 9787 8192000000 10313944976 10739822592 1337097024 14.031 98 39 78 0 1394234432 46463760 10673648
21:01:27 16631 8192000000 10288806160 10719825920 1355883552 14.200 100 17 34 0 1398374416 37802720 4688144
21:02:46 29679 8192000000 10276519232 10716872704 1347884640 14.128 100 16 31 1 1389300448 36843408 4572400
21:04:30 58819 8192000000 10244821712 10686189568 1331226176 13.978 98 21 43 0 1346988640 10777312 4985152
21:07:13 103547 8192000000 10256406608 10692395008 1322585744 13.900 97 29 59 1 1339068848 9661744 6821360
21:10:01 37451 8192000000 10291147280 10718187520 1342694928 14.082 97 37 75 0 1360364320 9246720 8422672
21:11:51 73173 8192000000 10275922160 10721357824 1330977920 13.976 96 12 24 0 1344358016 10905584 2474512
21:14:05 26721 8192000000 10285713472 10741018624 1381377104 14.429 100 8 16 0 1399437920 16530832 1529984
21:15:32 22765 8192000000 10276420896 10729443328 1379734736 14.414 100 9 18 0 1393277120 11381984 2160400
21:16:56 86168 8192000000 10297774928 10741985280 1374002608 14.363 99 28 56 0 1394816416 13738832 7074976
21:19:29 100486 8192000000 10277455520 10732523520 1376816304 14.388 99 14 29 0 1401283472 21131920 3335248
21:22:10 42072 8192000000 10297182256 10749378560 1382839408 14.442 99 20 40 2 1398197760 10854112 4504240
21:23:59 50847 8192000000 10314470304 10761908224 1397998896 14.577 100 22 45 0 1410545248 7771568 4774784
21:25:52 45151 8192000000 10318950288 10756567040 1379886720 14.416 98 44 88 1 1398150320 8630624 9632976
21:27:38 28741 8192000000 10326302832 10773258240 1405646672 14.645 100 32 65 0 1432515552 19576016 7292864
21:29:10 62812 8192000000 10321402384 10786947072 1405409568 14.643 100 15 31 0 1436937440 27609104 3918768
21:31:21 71464 8192000000 10308349696 10761682944 1377935088 14.398 98 7 15 0 1400617056 21195472 1486496
21:33:39 85543 8192000000 10280454464 10733174784 1339974896 14.057 96 16 31 1 1357453232 14137536 3340800
21:36:10 38086 8192000000 10287260256 10727788544 1346103376 14.112 97 41 81 1 1362114352 7047040 8963936
21:38:13 29622 8192000000 10267875776 10720858112 1370945616 14.336 100 28 56 0 1385837120 8934032 5957472
21:39:50 39414 8192000000 10250150816 10712035328 1328897264 13.957 97 8 16 0 1343051856 12514400 1640192
21:41:43 37450 8192000000 10230318864 10675650560 1284470208 13.554 95 19 38 0 1298764224 10127008 4167008
21:43:21 51888 8192000000 10266926992 10707329024 1344912384 14.102 99 23 46 0 1360776144 10801472 5062288
21:45:21 67143 8192000000 10254651552 10690592768 1295683456 13.656 95 18 37 0 1309919744 10241632 3994656
21:47:29 43412 8192000000 10266446432 10700513280 1330474848 13.971 98 25 51 0 1345284240 8437440 6371952
21:49:19 60854 8192000000 10275988896 10718507008 1337080960 14.031 98 19 38 1 1351363312 9968080 4314272
21:51:28 60844 8192000000 10288677232 10735022080 1347284960 14.123 98 20 38 0 1362810128 11424640 4100528
21:53:29 117611 8192000000 10270545952 10718420992 1293220288 13.634 94 14 27 0 1312871584 16266352 3384944
21:56:27 88104 8192000000 10282410208 10732494848 1380116448 14.418 100 17 34 0 1392957488 8820896 4020144
21:59:03 65623 8192000000 10291764816 10737790976 1362870240 14.263 98 42 83 2 1389910160 17200752 9839168
22:01:11 64185 8192000000 10270464800 10733125632 1369186752 14.320 99 13 24 0 1386663136 14457552 3018832
22:03:15 61027 8192000000 10272455248 10734043136 1345366224 14.106 97 22 44 0 1364978432 13836032 5776176
22:05:19 48394 8192000000 10286076144 10737557504 1371529328 14.341 99 12 24 0 1382401456 8166080 2706048
22:07:08 85253 8192000000 10297766816 10740748288 1343354160 14.088 97 14 28 0 1357671696 11047888 3269648
22:09:34 76604 8192000000 10295374864 10744860672 1372998912 14.354 99 34 66 1 1387880064 7142208 7738944
22:12:03 51623 8192000000 10298127808 10747351040 1375365440 14.375 99 34 68 0 1392806656 9884592 7556624
22:13:55 108031 8192000000 10307957536 10746470400 1340130352 14.059 96 13 26 0 1355628144 12850032 2647760
22:16:48 84140 8192000000 10293187968 10743545856 1342775232 14.082 96 13 26 0 1359877856 13906416 3196208
22:19:12 116559 8192000000 10279353552 10735673344 1331840064 13.984 96 15 30 0 1349494368 14463904 3190400
22:23:45 32264 8192000000 10283420432 10737324032 1372638640 14.351 99 19 37 0 1391587120 14958560 3989920
22:25:17 43180 8192000000 10282869024 10752602112 1370416432 14.331 99 10 20 0 1391762032 18957216 2388384
22:27:03 69086 8192000000 10226209664 10684506112 1269194736 13.414 93 15 30 0 1286334912 13089888 4050288
22:30:30 49915 8192000000 10224705968 10674352128 1289971088 13.604 96 7 14 0 1298615792 7074960 1569744
22:32:23 70451 8192000000 10237879744 10680815616 1250690592 13.245 93 9 18 0 1264670832 12176304 1803936
22:34:34 51197 8192000000 10247937824 10692333568 1296415744 13.663 96 14 28 0 1308217712 8744544 3057424
22:36:26 52166 8192000000 10267865264 10707365888 1339613536 14.054 99 28 56 0 1355863360 10142032 6107792
22:38:27 26428 8192000000 9155928960 9404264448 316830544 3.723 24 7 14 0 324601936 6328656 1442736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-02-2023 22:39:55 to 20-02-2023 22:40:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 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 20-02-2023 22:40:11 to 20-02-2023 22:40:13.

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          250603
MON$OLDEST_ACTIVE               252909
MON$OLDEST_SNAPSHOT             252909
MON$NEXT_TRANSACTION            252909
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                       2005760
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:332: 
:333: mscs-test-161.budzdorov.ru    Mon Feb 20 22:38:57 2023
:334:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:335: 
--- end of diff output ---

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

20.02.23 22:40:14. 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
2023-02-20 22:40:17 No severe PSQL-related problems occured
20.02.23 22:40:18. End of report.