Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.01.2023 01:40
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 15210980 kB
MemAvailable 30422668 kB
Buffers 0 kB
Cached 16520568 kB
SwapCached 5788 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-01-03 01:38:32
DTS_END                         2023-01-03 01:38:32.6830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35442
TRN_ID                          245977
ATT_ID                          722
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: 9392.18 21195 1127062
customer order: creation 180.38 390 21645
customer order: refuse 15.35 330 1842
order to supplier: creation 47.93 1286 5752
order to supplier: removal 3.58 86892 430
invoice (draft): creation 48.64 3515 5837
invoice (draft): removal 53.17 730 6380
invoice accept: apply 43.04 16711 5165
invoice accept: cancel 47.69 818 5723
customer reserve: creation 3965.14 166 475817
customer reserve: removal 4678.43 51 561412
realization accept: apply 88.43 169 10612
realization accept: cancel 86.75 108 10410
payment from customer: creation 62.23 115 7467
payment from customer: removal 6.12 74 734
payment to supplier: creation 49.42 141 5930
payment to supplier: removal 3.43 61 411
service: total inventory turnovers 4.79 1035 575
service: total monetary turnovers 6.79 27 815
service: aggregate perf. data 0.84 22576 101
service: refresh index statistics 0.03 288711 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-01-2023 01:40:49 to 03-01-2023 01:40:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2919
TEST_TIME 2 3606
TEST_TIME 3 4030
TEST_TIME 4 4438
TEST_TIME 5 4371
TEST_TIME 6 4864
TEST_TIME 7 5122
TEST_TIME 8 5188
TEST_TIME 9 5153
TEST_TIME 10 5358
TEST_TIME 11 5270
TEST_TIME 12 5339
TEST_TIME 13 5576
TEST_TIME 14 6306
TEST_TIME 15 6475
TEST_TIME 16 6442
TEST_TIME 17 6587
TEST_TIME 18 6944
TEST_TIME 19 7156
TEST_TIME 20 7547
TEST_TIME 21 8118
TEST_TIME 22 8231
TEST_TIME 23 8590
TEST_TIME 24 8685
TEST_TIME 25 8639
TEST_TIME 26 8729
TEST_TIME 27 8746
TEST_TIME 28 8924
TEST_TIME 29 9663
TEST_TIME 30 9676
TEST_TIME 31 9776
TEST_TIME 32 9699
TEST_TIME 33 9768
TEST_TIME 34 9619
TEST_TIME 35 9737
TEST_TIME 36 9847
TEST_TIME 37 9712
TEST_TIME 38 9538
TEST_TIME 39 9461
TEST_TIME 40 9438
TEST_TIME 41 9441
TEST_TIME 42 9512
TEST_TIME 43 9475
TEST_TIME 44 9415
TEST_TIME 45 9483
TEST_TIME 46 9500
TEST_TIME 47 9442
TEST_TIME 48 9398
TEST_TIME 49 9282
TEST_TIME 50 9220
TEST_TIME 51 9214
TEST_TIME 52 9153
TEST_TIME 53 9231
TEST_TIME 54 9231
TEST_TIME 55 9258
TEST_TIME 56 9285
TEST_TIME 57 9287
TEST_TIME 58 9308
TEST_TIME 59 9512
TEST_TIME 60 9533
TEST_TIME 61 9538
TEST_TIME 62 9527
TEST_TIME 63 9685
TEST_TIME 64 9659
TEST_TIME 65 9628
TEST_TIME 66 9530
TEST_TIME 67 9590
TEST_TIME 68 9562
TEST_TIME 69 9506
TEST_TIME 70 9422
TEST_TIME 71 9379
TEST_TIME 72 9320
TEST_TIME 73 9319
TEST_TIME 74 9288
TEST_TIME 75 9208
TEST_TIME 76 9247
TEST_TIME 77 9208
TEST_TIME 78 9271
TEST_TIME 79 9250
TEST_TIME 80 9229
TEST_TIME 81 9379
TEST_TIME 82 9412
TEST_TIME 83 9414
TEST_TIME 84 9439
TEST_TIME 85 9471
TEST_TIME 86 9500
TEST_TIME 87 9567
TEST_TIME 88 9562
TEST_TIME 89 9549
TEST_TIME 90 9468
TEST_TIME 91 9444
TEST_TIME 92 9429
TEST_TIME 93 9429
TEST_TIME 94 9389
TEST_TIME 95 9366
TEST_TIME 96 9354
TEST_TIME 97 9391
TEST_TIME 98 9369
TEST_TIME 99 9367
TEST_TIME 100 9351
TEST_TIME 101 9460
TEST_TIME 102 9500
TEST_TIME 103 9467
TEST_TIME 104 9490
TEST_TIME 105 9564
TEST_TIME 106 9531
TEST_TIME 107 9533
TEST_TIME 108 9527
TEST_TIME 109 9586
TEST_TIME 110 9678
TEST_TIME 111 9608
TEST_TIME 112 9569
TEST_TIME 113 9527
TEST_TIME 114 9510
TEST_TIME 115 9506
TEST_TIME 116 9476
TEST_TIME 117 9459
TEST_TIME 118 9436
TEST_TIME 119 9422
TEST_TIME 120 9397

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-01-2023 01:40:53 to 03-01-2023 01:40:56.

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 1569545 1569545 0 0.00 0 19861 6 0 0
doc_list_biud 1569543 1569543 0 0.00 0 50162 24 0 0
sp_add_invoice_to_stock 5295 5165 130 2.45 25 169202 16711 0 130
sp_cancel_adding_invoice 5723 5723 0 0.00 4 60224 818 0 0
sp_cancel_client_order 1842 1842 0 0.00 4 13790 330 0 0
sp_cancel_customer_reserve 561412 561412 0 0.00 0 50164 52 0 0
sp_cancel_pay_from_customer 734 734 0 0.00 2 3674 74 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 4090 62 0 0
sp_cancel_supplier_invoice 6388 6380 8 0.12 1 23013 731 0 8
sp_cancel_supplier_order 430 430 0 0.00 10 882537 86892 0 0
sp_cancel_write_off 10410 10410 0 0.00 1 25708 109 0 0
sp_client_order 21645 21645 0 0.00 3 26548 391 0 0
sp_customer_reserve 475817 475817 0 0.00 0 46031 166 0 0
sp_fill_shopping_cart 33233 33233 0 0.00 1 19478 39 0 0
sp_fill_shopping_cart_clo_res 475814 475814 0 0.00 0 13368 13 0 0
sp_get_clo_for_invoice 5158 5158 0 0.00 0 29841 319 0 0
sp_kill_cost_storno 17278 17278 0 0.00 0 8848 52 0 0
sp_kill_qstorno_ret_qs2qd 1140044 1140044 0 0.00 0 50157 29 0 0
sp_kill_qty_storno 601967 601967 0 0.00 0 50162 62 0 0
sp_lock_selected_doc 21880 21880 0 0.00 0 7132 5 0 0
sp_make_cost_storno 29165 29165 0 0.00 0 13334 53 0 0
sp_multiply_rows_for_pdistr 29165 29165 0 0.00 0 6224 27 0 0
sp_multiply_rows_for_qdistr 21645 21645 0 0.00 0 26540 182 0 0
sp_pay_from_customer 7467 7467 0 0.00 4 10582 116 0 0
sp_pay_to_supplier 5930 5930 0 0.00 4 12993 141 0 0
sp_payment_common 13395 13395 0 0.00 1 8388 30 0 0
sp_qd_handle_on_cancel_clo 1842 1842 0 0.00 0 13783 297 0 0
sp_qd_handle_on_invoice_upd_sts 10881 10881 0 0.00 0 22763 316 0 0
sp_qd_handle_on_reserve_upd_sts 10612 10612 0 0.00 0 14344 55 0 0
sp_reserve_write_off 10612 10612 0 0.00 6 14365 170 0 0
sp_supplier_invoice 5837 5837 0 0.00 5 102602 3516 0 0
sp_supplier_order 5752 5752 0 0.00 5 66009 1286 0 0
srv_aggregate_perf_data 101 101 0 0.00 2392 253766 22576 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1430 138253 40414 0 0
srv_find_qd_qs_mism 1078486 1078486 0 0.00 0 19861 5 0 0
srv_make_invnt_saldo 575 575 0 0.00 27 34540 1035 0 0
srv_make_money_saldo 815 815 0 0.00 1 2667 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 149370 500711 288712 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5166 5166 0 0.00 0 14069 189 0 0
t$perf-norm:sp_cancel_adding_invoice 458 458 0 0.00 0 23696 141 0 0
t$perf-norm:sp_cancel_client_order 1842 1842 0 0.00 0 2449 10 0 0
t$perf-norm:sp_cancel_customer_reserve 778 778 0 0.00 0 3437 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 734 734 0 0.00 0 14016 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 802 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 535 12 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 25273 1870 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 9584 19 0 0
t$perf-norm:sp_client_order 21645 21645 0 0.00 0 11353 17 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 3353 8 0 0
t$perf-norm:sp_pay_from_customer 7467 7467 0 0.00 0 10080 13 0 0
t$perf-norm:sp_pay_to_supplier 5930 5930 0 0.00 0 8604 10 0 0
t$perf-norm:sp_reserve_write_off 10612 10612 0 0.00 0 15970 16 0 0
t$perf-norm:sp_supplier_invoice 5837 5837 0 0.00 0 11165 23 0 0
t$perf-norm:sp_supplier_order 5752 5752 0 0.00 0 15107 20 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 161 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 997 27 0 0
t$perf-norm:srv_make_invnt_saldo 575 575 0 0.00 0 2981 25 0 0
t$perf-norm:srv_make_money_saldo 815 815 0 0.00 0 1215 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10576 10316 260 2.45 0 6001 18 0 260
v_all_customers 39270 39270 0 0.00 0 18 1 0 0
v_all_suppliers 31716 31716 0 0.00 0 20 1 0 0
v_all_wares 43290 43290 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 916 916 0 0.00 0 4520 19 0 0
v_cancel_client_order 3684 3684 0 0.00 0 7139 10 0 0
v_cancel_customer_prepayment 1468 1468 0 0.00 0 3645 7 0 0
v_cancel_customer_reserve 1556 1556 0 0.00 0 2359 7 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 291 3 0 0
v_cancel_supplier_invoice 866 850 16 1.84 0 3295 16 0 16
v_cancel_supplier_order 860 860 0 0.00 0 6612 19 0 0
v_cancel_write_off 2064 2064 0 0.00 0 6283 17 0 0
v_max_id_clo_ord 5752 5752 0 0.00 0 583 1 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 2289 15 0 0
v_max_id_ord_sup 5836 5836 0 0.00 0 4391 6 0 0
v_max_non_paid_invoice 1658 1658 0 0.00 0 332 2 0 0
v_max_non_paid_realizn 4080 4080 0 0.00 0 2045 5 0 0
v_min_id_clo_ord 5752 5752 0 0.00 0 19372 25 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 7222 17 0 0
v_min_id_ord_sup 5836 5836 0 0.00 0 7169 8 0 0
v_min_non_paid_invoice 5928 5928 0 0.00 0 8357 25 0 0
v_min_non_paid_realizn 7467 7467 0 0.00 0 8383 20 0 0
v_our_firm 10794 10794 0 0.00 0 22 1 0 0
v_reserve_write_off 21224 21224 0 0.00 0 8428 6 0 0
x_lock_depdocs_on_canc_invoice 11678 11678 0 0.00 0 10618 101 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 17462 346 0 0
x_make_qstorno_1000_1200 5752 5752 0 0.00 1 65983 1197 0 0
x_make_qstorno_1200_2000 5837 5837 0 0.00 2 102593 3382 0 0
x_make_qstorno_2100_3300 475817 475817 0 0.00 0 34226 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-01-2023 01:40:57 to 03-01-2023 01:41:01.

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
23:08:36 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:37 30969 8192000000 9903892400 10214322176 982860032 10.712 100 87 172 1 1012359936 6139472 23360432
23:11:13 19062 8192000000 9968104288 10291970048 1043832640 11.301 100 93 186 0 1076075776 8095696 24147440
23:12:36 14857 8192000000 10004301168 10340564992 1083436928 11.680 100 92 184 0 1116934144 10061008 23436208
23:13:54 7218 8192000000 10030573552 10380152832 1122818800 12.054 100 77 154 0 1152796160 11219200 18758160
23:15:03 5434 8192000000 10089172976 10445918208 1165503024 12.455 100 89 178 0 1200662896 14501680 20658192
23:16:10 2085 8192000000 10115119824 10482692096 1200095312 12.777 100 75 150 0 1230164208 13253920 16814976
23:17:15 2154 8192000000 10138684752 10512248832 1227982320 13.035 100 80 157 1 1257122096 12320400 16819376
23:18:21 1955 8192000000 10180090208 10556538880 1262823424 13.356 100 88 176 2 1298140080 15510704 19805952
23:19:25 1021 8192000000 10199288880 10584264704 1289134320 13.596 100 90 178 0 1322325840 13020544 20170976
23:20:27 17618 8192000000 10194715728 10589184000 1297393056 13.672 100 71 141 1 1321582400 8360992 15828352
23:21:46 5185 8192000000 10189566528 10597519360 1301954496 13.713 100 31 60 3 1314012976 5398512 6659968
23:22:53 10398 8192000000 10202381072 10611220480 1310643232 13.792 100 19 39 0 1320038800 5228064 4167504
23:24:07 8365 8192000000 10199157040 10619441152 1322934608 13.903 100 14 28 0 1332348832 6424112 2990112
23:25:17 20394 8192000000 10221909088 10639335424 1339286384 14.051 100 34 64 0 1352924176 5355232 8282560
23:27:28 3312 8192000000 10237404992 10649944064 1337647216 14.036 100 38 75 2 1351917168 5749360 8520592
23:28:32 5333 8192000000 10218780384 10636488704 1329074256 13.959 100 20 39 1 1339972400 6316688 4581456
23:29:38 9125 8192000000 10188975088 10603798528 1283210032 13.542 98 10 20 1 1290026160 4740384 2075744
23:30:47 39977 8192000000 10143135088 10545160192 1207333648 12.844 95 31 60 3 1219602416 5524864 6743904
23:32:28 7297 8192000000 10146839424 10545266688 1247419024 13.214 99 16 32 0 1256183952 5045232 3719696
23:33:35 7521 8192000000 10145295952 10542047232 1231719936 13.070 98 27 54 0 1251272576 13331040 6221600
23:34:43 11891 8192000000 10169400256 10572230656 1264333168 13.370 99 23 47 1 1275635776 5798704 5503904
23:35:59 37334 8192000000 10177280736 10592514048 1289014560 13.595 100 26 52 0 1299876704 5247296 5614848
23:37:36 6072 8192000000 10204495472 10617102336 1305498624 13.745 100 22 44 1 1320510992 10050176 4962192
23:38:51 20936 8192000000 10233926384 10646388736 1334178992 14.005 100 33 66 0 1346795056 4900288 7715776
23:40:46 15422 8192000000 10261697632 10675998720 1353339920 14.178 100 41 82 1 1371469248 8511808 9617520
23:42:10 38278 8192000000 10273095664 10698002432 1369530592 14.323 100 34 68 0 1388259744 10456576 8272576
23:44:13 12264 8192000000 10282510432 10713452544 1377798256 14.397 100 37 72 0 1399453936 12505168 9150512
23:45:30 14674 8192000000 10311207632 10732535808 1393450832 14.537 100 54 107 0 1419006128 14126352 11428944
23:46:49 21099 8192000000 10322389056 10752671744 1407816960 14.665 100 52 105 0 1436247152 15962832 12467360
23:48:27 9600 8192000000 10342856736 10770460672 1417952256 14.755 100 59 116 0 1453217200 21381600 13883344
23:49:44 10228 8192000000 10346876352 10780057600 1422875664 14.798 100 42 84 0 1457452256 23637696 10938896
23:51:03 10348 8192000000 10351028864 10787434496 1418870192 14.763 99 34 68 0 1453694304 25954944 8869168
23:52:20 1893 8192000000 10370699824 10792837120 1417842368 14.754 99 75 149 1 1466629696 30840096 17947232
23:53:26 1428 8192000000 10358802384 10779140096 1421168304 14.783 100 73 142 2 1462802400 24090016 17544080
23:54:33 11608 8192000000 10347294656 10770874368 1413533344 14.715 100 55 110 1 1454964528 27934704 13496480
23:55:54 2808 8192000000 10352937840 10767179776 1398530576 14.582 99 75 147 3 1448663392 31856240 18276576
23:57:03 2479 8192000000 10355897760 10767036416 1401911504 14.612 100 85 169 1 1458258032 35091408 21255120
23:58:12 1497 8192000000 10343443744 10758754304 1393355936 14.536 100 69 136 1 1447940368 37543632 17040800
23:59:16 7631 8192000000 10319648560 10737762304 1355604144 14.198 98 48 94 1 1401928064 33350560 12973360
00:00:26 11806 8192000000 10308892864 10729287680 1348945712 14.138 98 47 92 1 1395767968 34816736 12005520
00:01:46 2988 8192000000 10286848400 10704326656 1349620352 14.144 99 47 94 0 1385575536 24198576 11756608
00:02:54 31045 8192000000 10266604464 10694737920 1336531392 14.026 99 28 56 2 1367542448 23477920 7533136
00:04:31 13281 8192000000 10280803776 10711441408 1342263936 14.078 98 28 55 0 1373791888 24952688 6575264
00:05:49 29246 8192000000 10274261040 10711375872 1354398048 14.187 100 19 38 0 1388565936 29070912 5096976
00:07:35 34800 8192000000 10278864784 10714841088 1363132176 14.265 100 19 39 1 1389437264 21419696 4885392
00:09:48 57307 8192000000 10270216688 10704392192 1337563728 14.035 98 19 38 0 1351225824 9442640 4219456
00:11:51 21608 8192000000 10282640000 10715107328 1359576704 14.234 99 16 32 1 1371430128 8391792 3461632
00:13:26 59775 8192000000 10278292816 10723663872 1348519424 14.134 98 10 20 0 1363131888 12490784 2121680
00:15:40 100706 8192000000 10299452528 10743250944 1371737984 14.343 99 36 69 0 1393130688 12703920 8688784
00:18:32 71017 8192000000 10286023200 10748477440 1378974096 14.407 100 26 53 2 1409276416 24182096 6120224
00:20:54 7440 8192000000 10293752800 10741100544 1387844320 14.487 100 36 68 0 1403504128 7596672 8063136
00:22:01 32542 8192000000 10308586944 10755305472 1396328064 14.562 100 43 82 0 1419386048 13141616 9916368
00:23:46 26575 8192000000 10303424688 10757476352 1402406608 14.616 100 17 34 0 1414972416 8518752 4047056
00:25:13 25186 8192000000 10303552784 10771689472 1389383552 14.500 99 10 20 0 1404250720 12596448 2270720
00:26:39 16713 8192000000 10306688272 10767179776 1403631920 14.627 100 25 50 2 1421013104 11370304 6010880
00:28:00 76461 8192000000 10300933664 10767044608 1393317808 14.535 99 20 39 0 1405337712 7781328 4238576
00:30:18 57729 8192000000 10276451776 10741841920 1328067680 13.950 95 7 14 0 1343703696 14025200 1610816
00:32:32 32662 8192000000 10289128000 10750906368 1352509568 14.170 97 27 54 0 1370263088 11420000 6333520
00:34:27 43118 8192000000 10304559008 10756698112 1367986816 14.309 98 18 37 1 1391728624 19070240 4671568
00:36:12 12868 8192000000 10302039632 10744639488 1369647040 14.324 100 40 78 0 1400566624 20462848 10456736
00:37:36 28942 8192000000 10280506256 10728476672 1339365872 14.052 97 33 67 0 1357278048 10015472 7896704
00:39:15 38682 8192000000 10289986064 10738872320 1348772368 14.136 98 30 60 0 1375180192 20096128 6311696
00:41:01 76708 8192000000 10268336128 10715373568 1274018240 13.458 93 9 16 0 1292416800 16936224 1462336
00:43:18 94232 8192000000 10296501696 10729385984 1367593136 14.305 100 26 52 0 1391519536 17716096 6210304
00:45:57 29418 8192000000 10263149680 10707959808 1348579328 14.135 99 16 31 0 1360643584 9114848 2949408
00:47:37 78444 8192000000 10255322912 10712354816 1330299232 13.970 97 11 22 0 1341569136 8812672 2457232
00:49:58 20169 8192000000 10244673024 10699079680 1349751744 14.145 100 21 42 1 1362453056 7698064 5003248
00:51:28 47606 8192000000 10250025056 10704515072 1333653424 14.000 98 21 43 0 1346085968 7399216 5033328
00:53:25 75712 8192000000 10255236176 10721071104 1323347392 13.907 97 23 46 0 1338620080 10210256 5062432
00:55:50 31912 8192000000 10263341712 10707820544 1344085936 14.094 99 29 58 0 1362460848 11810640 6564272
00:57:22 37034 8192000000 10263789760 10710765568 1331188064 13.978 98 22 44 0 1347438064 11626768 4623232
00:59:16 76709 8192000000 10278428752 10745425920 1371785136 14.343 100 17 34 1 1402225696 26105120 4335440
01:01:33 33013 8192000000 10296248528 10741477376 1365728416 14.289 99 35 70 0 1382305168 8835360 7741392
01:03:12 118417 8192000000 10303109440 10769625088 1344349120 14.097 97 6 12 0 1363886016 18161056 1375840
01:06:11 56299 8192000000 10293789168 10743181312 1369778640 14.325 99 14 28 0 1384084112 11095056 3210416
01:08:07 138252 8192000000 10297821696 10754531328 1381417568 14.429 99 30 60 0 1401307520 12619376 7270576
01:11:28 37734 8192000000 10275834944 10738741248 1374037408 14.363 99 7 12 0 1380792032 5809200 945424
01:13:07 61002 8192000000 10259118576 10719711232 1316830928 13.848 95 10 19 0 1328287264 9421248 2035088
01:15:45 35175 8192000000 10296143232 10752708608 1381297056 14.428 100 17 34 0 1394264864 9892144 3075664
01:17:24 56119 8192000000 10296248704 10750115840 1362083888 14.256 98 23 46 0 1380877264 13696432 5096944
01:19:20 42556 8192000000 10299502064 10753449984 1389261152 14.499 100 29 56 0 1405643968 9976640 6406176
01:21:04 50935 8192000000 10308830800 10754916352 1367723360 14.307 98 28 56 0 1384516368 9724240 7068768
01:22:58 66780 8192000000 10285453968 10753835008 1332524496 13.990 96 22 42 1 1352829264 15885440 4419328
01:25:06 92394 8192000000 10282517888 10733740032 1336782528 14.028 97 13 26 0 1351048448 11619872 2646048
01:27:47 45855 8192000000 10298339664 10775015424 1364656592 14.279 99 10 20 0 1399891904 32895984 2339328
01:29:34 102722 8192000000 10269889616 10723049472 1349235472 14.141 98 20 40 0 1362217392 8295632 4686288
01:32:18 40361 8192000000 10282586208 10717683712 1321370096 13.889 96 32 65 1 1336051216 7709328 6971792
01:34:00 51668 8192000000 10245370864 10693582848 1306984832 13.759 96 13 26 0 1316326096 6464976 2876288
01:36:08 41296 8192000000 10246303648 10699747328 1330873824 13.975 98 17 33 2 1345540432 10960368 3706240
01:37:52 54434 8192000000 9872935568 10277785600 992513008 10.806 73 9 18 0 1009050640 14697232 1840400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-01-2023 01:41:03 to 03-01-2023 01:41:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-01-2023 01:41:20 to 03-01-2023 01:41:22.

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          245673
MON$OLDEST_ACTIVE               246200
MON$OLDEST_SNAPSHOT             246200
MON$NEXT_TRANSACTION            246200
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                       2045216
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:1591: 
:1592: mscs-test-161.budzdorov.ru    Tue Jan  3 01:38:58 2023
:1593:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1594: 
--- end of diff output ---

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

03.01.23 01:41:23. 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-01-03 01:41:25 No severe PSQL-related problems occured
03.01.23 01:41:27. End of report.