Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.12.2021 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 16493876 kB
MemAvailable 31361436 kB
Buffers 0 kB
Cached 15203828 kB
SwapCached 1664 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.9
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 2021-12-31 22:37:58
DTS_END                         2021-12-31 22:37:58.7210
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/36348
TRN_ID                          244163
ATT_ID                          698
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: 9246.28 22663 1109553
customer order: creation 176.94 358 21233
customer order: refuse 14.69 368 1763
order to supplier: creation 46.40 1267 5568
order to supplier: removal 3.21 113002 385
invoice (draft): creation 47.30 3251 5676
invoice (draft): removal 50.93 767 6111
invoice accept: apply 43.42 18082 5210
invoice accept: cancel 46.43 894 5571
customer reserve: creation 3956.49 180 474779
customer reserve: removal 4556.42 63 546770
realization accept: apply 86.68 176 10401
realization accept: cancel 84.28 119 10113
payment from customer: creation 61.06 126 7327
payment from customer: removal 5.22 117 626
payment to supplier: creation 50.33 167 6039
payment to supplier: removal 3.52 94 422
service: total inventory turnovers 5.21 1213 625
service: total monetary turnovers 6.99 33 839
service: aggregate perf. data 0.76 22977 91
service: refresh index statistics 0.03 289996 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-12-2021 22:40:05 to 31-12-2021 22:40:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1681
TEST_TIME 2 1454
TEST_TIME 3 3028
TEST_TIME 4 2918
TEST_TIME 5 3156
TEST_TIME 6 4030
TEST_TIME 7 4535
TEST_TIME 8 4525
TEST_TIME 9 4788
TEST_TIME 10 4960
TEST_TIME 11 5339
TEST_TIME 12 5443
TEST_TIME 13 5660
TEST_TIME 14 6295
TEST_TIME 15 6886
TEST_TIME 16 7055
TEST_TIME 17 7201
TEST_TIME 18 7890
TEST_TIME 19 8198
TEST_TIME 20 8317
TEST_TIME 21 8507
TEST_TIME 22 8754
TEST_TIME 23 8765
TEST_TIME 24 8877
TEST_TIME 25 8722
TEST_TIME 26 8880
TEST_TIME 27 9331
TEST_TIME 28 9572
TEST_TIME 29 10562
TEST_TIME 30 11465
TEST_TIME 31 11138
TEST_TIME 32 10991
TEST_TIME 33 10807
TEST_TIME 34 10732
TEST_TIME 35 10627
TEST_TIME 36 10673
TEST_TIME 37 10528
TEST_TIME 38 10552
TEST_TIME 39 10549
TEST_TIME 40 10469
TEST_TIME 41 10347
TEST_TIME 42 10244
TEST_TIME 43 10169
TEST_TIME 44 10009
TEST_TIME 45 10117
TEST_TIME 46 9913
TEST_TIME 47 9886
TEST_TIME 48 9909
TEST_TIME 49 9860
TEST_TIME 50 9826
TEST_TIME 51 9743
TEST_TIME 52 9643
TEST_TIME 53 9620
TEST_TIME 54 9554
TEST_TIME 55 9604
TEST_TIME 56 9543
TEST_TIME 57 9601
TEST_TIME 58 9565
TEST_TIME 59 9517
TEST_TIME 60 9652
TEST_TIME 61 9676
TEST_TIME 62 9646
TEST_TIME 63 9701
TEST_TIME 64 9664
TEST_TIME 65 9679
TEST_TIME 66 9654
TEST_TIME 67 9754
TEST_TIME 68 9717
TEST_TIME 69 9746
TEST_TIME 70 9663
TEST_TIME 71 9620
TEST_TIME 72 9613
TEST_TIME 73 9553
TEST_TIME 74 9549
TEST_TIME 75 9492
TEST_TIME 76 9480
TEST_TIME 77 9417
TEST_TIME 78 9388
TEST_TIME 79 9338
TEST_TIME 80 9488
TEST_TIME 81 9466
TEST_TIME 82 9479
TEST_TIME 83 9426
TEST_TIME 84 9440
TEST_TIME 85 9466
TEST_TIME 86 9519
TEST_TIME 87 9513
TEST_TIME 88 9474
TEST_TIME 89 9539
TEST_TIME 90 9536
TEST_TIME 91 9588
TEST_TIME 92 9562
TEST_TIME 93 9481
TEST_TIME 94 9439
TEST_TIME 95 9427
TEST_TIME 96 9407
TEST_TIME 97 9367
TEST_TIME 98 9360
TEST_TIME 99 9347
TEST_TIME 100 9306
TEST_TIME 101 9310
TEST_TIME 102 9292
TEST_TIME 103 9356
TEST_TIME 104 9325
TEST_TIME 105 9325
TEST_TIME 106 9331
TEST_TIME 107 9374
TEST_TIME 108 9397
TEST_TIME 109 9400
TEST_TIME 110 9423
TEST_TIME 111 9453
TEST_TIME 112 9443
TEST_TIME 113 9395
TEST_TIME 114 9363
TEST_TIME 115 9363
TEST_TIME 116 9345
TEST_TIME 117 9333
TEST_TIME 118 9308
TEST_TIME 119 9277
TEST_TIME 120 9249

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-12-2021 22:40:09 to 31-12-2021 22:40:12.

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 1549728 1549728 0 0.00 0 38728 7 0 0
doc_list_biud 1549728 1549728 0 0.00 0 93005 28 0 0
sp_add_invoice_to_stock 5320 5210 110 2.06 33 191541 18083 0 110
sp_cancel_adding_invoice 5571 5571 0 0.00 4 84576 894 0 0
sp_cancel_client_order 1763 1763 0 0.00 4 9569 368 0 0
sp_cancel_customer_reserve 546770 546770 0 0.00 1 46319 64 0 0
sp_cancel_pay_from_customer 626 626 0 0.00 2 5694 117 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 8349 94 0 0
sp_cancel_supplier_invoice 6118 6111 7 0.11 1 93014 767 0 7
sp_cancel_supplier_order 385 385 0 0.00 5 1174345 113003 0 0
sp_cancel_write_off 10113 10113 0 0.00 1 8099 119 0 0
sp_client_order 21233 21233 0 0.00 3 38566 359 0 0
sp_customer_reserve 474779 474779 0 0.00 0 47005 181 0 0
sp_fill_shopping_cart 32473 32473 0 0.00 1 20090 42 0 0
sp_fill_shopping_cart_clo_res 474773 474773 0 0.00 0 18478 13 0 0
sp_get_clo_for_invoice 5204 5204 0 0.00 0 27191 284 0 0
sp_kill_cost_storno 16732 16732 0 0.00 0 10432 65 0 0
sp_kill_qstorno_ret_qs2qd 1110149 1110149 0 0.00 0 92999 34 0 0
sp_kill_qty_storno 586318 586318 0 0.00 0 93003 73 0 0
sp_lock_selected_doc 21404 21404 0 0.00 0 8920 6 0 0
sp_make_cost_storno 28971 28971 0 0.00 0 38724 63 0 0
sp_multiply_rows_for_pdistr 28971 28971 0 0.00 0 15017 37 0 0
sp_multiply_rows_for_qdistr 21233 21233 0 0.00 0 35333 149 0 0
sp_pay_from_customer 7327 7327 0 0.00 4 14416 127 0 0
sp_pay_to_supplier 6039 6039 0 0.00 5 38734 168 0 0
sp_payment_common 13366 13366 0 0.00 1 9076 34 0 0
sp_qd_handle_on_cancel_clo 1763 1763 0 0.00 0 9343 327 0 0
sp_qd_handle_on_invoice_upd_sts 10775 10775 0 0.00 0 24904 347 0 0
sp_qd_handle_on_reserve_upd_sts 10401 10401 0 0.00 0 10167 55 0 0
sp_reserve_write_off 10401 10401 0 0.00 6 11973 177 0 0
sp_supplier_invoice 5676 5676 0 0.00 6 89602 3252 0 0
sp_supplier_order 5568 5568 0 0.00 5 70617 1268 0 0
srv_aggregate_perf_data 91 91 0 0.00 3258 317199 22977 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1411 150217 44779 0 0
srv_find_qd_qs_mism 1060818 1060818 0 0.00 0 14281 5 0 0
srv_make_invnt_saldo 625 625 0 0.00 30 176429 1213 0 0
srv_make_money_saldo 839 839 0 0.00 1 2902 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 130554 444765 289996 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5211 5211 0 0.00 0 34924 218 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 3865 110 0 0
t$perf-norm:sp_cancel_client_order 1763 1763 0 0.00 0 2151 13 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 6526 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 626 626 0 0.00 0 724 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 14693 36 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 2309 21 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 32073 1763 0 0
t$perf-norm:sp_cancel_write_off 1058 1058 0 0.00 0 6101 13 0 0
t$perf-norm:sp_client_order 21233 21233 0 0.00 0 20389 24 0 0
t$perf-norm:sp_customer_reserve 1038 1038 0 0.00 0 4804 11 0 0
t$perf-norm:sp_pay_from_customer 7327 7327 0 0.00 0 8914 12 0 0
t$perf-norm:sp_pay_to_supplier 6039 6039 0 0.00 0 9553 13 0 0
t$perf-norm:sp_reserve_write_off 10401 10401 0 0.00 0 10169 14 0 0
t$perf-norm:sp_supplier_invoice 5676 5676 0 0.00 0 9464 22 0 0
t$perf-norm:sp_supplier_order 5568 5568 0 0.00 0 6627 21 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 346 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 6 0 0 0
t$perf-norm:srv_make_invnt_saldo 625 625 0 0.00 0 1625 29 0 0
t$perf-norm:srv_make_money_saldo 839 839 0 0.00 0 1071 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10626 10406 220 2.07 0 5961 19 0 220
v_all_customers 39200 39200 0 0.00 0 71 1 0 0
v_all_suppliers 30918 30918 0 0.00 0 101 1 0 0
v_all_wares 42466 42466 0 0.00 0 87 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 3834 10 0 0
v_cancel_client_order 3526 3526 0 0.00 0 3216 7 0 0
v_cancel_customer_prepayment 1252 1252 0 0.00 0 5668 17 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 3213 8 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 3623 9 0 0
v_cancel_supplier_invoice 848 834 14 1.65 0 3616 19 0 14
v_cancel_supplier_order 770 770 0 0.00 0 1289 5 0 0
v_cancel_write_off 2116 2116 0 0.00 0 3117 18 0 0
v_max_id_clo_ord 5568 5568 0 0.00 0 826 2 0 0
v_max_id_clo_res 1038 1038 0 0.00 0 1462 13 0 0
v_max_id_ord_sup 5672 5672 0 0.00 0 4128 6 0 0
v_max_non_paid_invoice 1820 1820 0 0.00 0 746 3 0 0
v_max_non_paid_realizn 3666 3666 0 0.00 0 4611 5 0 0
v_min_id_clo_ord 5568 5568 0 0.00 0 10169 9 0 0
v_min_id_clo_res 1038 1038 0 0.00 0 6901 26 0 0
v_min_id_ord_sup 5672 5672 0 0.00 0 6684 8 0 0
v_min_non_paid_invoice 6039 6039 0 0.00 0 6042 24 0 0
v_min_non_paid_realizn 7327 7327 0 0.00 0 7388 27 0 0
v_our_firm 10588 10588 0 0.00 0 74 1 0 0
v_reserve_write_off 20802 20802 0 0.00 0 3201 5 0 0
x_lock_depdocs_on_canc_invoice 11265 11265 0 0.00 0 17753 134 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 6780 366 0 0
x_make_qstorno_1000_1200 5568 5568 0 0.00 1 70576 1178 0 0
x_make_qstorno_1200_2000 5676 5676 0 0.00 3 84425 3101 0 0
x_make_qstorno_2100_3300 474779 474779 0 0.00 0 44856 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-12-2021 22:40:13 to 31-12-2021 22:40:17.

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:02 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:03 29669 8192000000 9891984960 10207170560 971545728 10.602 100 82 164 0 999479104 5690352 22243024
20:10:41 19401 8192000000 9937851792 10260361216 1021819472 11.090 100 85 170 0 1052089360 7818080 22451808
20:12:04 21929 8192000000 9992488496 10327154688 1067814752 11.531 100 85 170 1 1098221648 9167280 21239616
20:13:36 15294 8192000000 10052750624 10399891456 1135882800 12.177 100 85 170 1 1167290448 11022240 20385408
20:14:57 8076 8192000000 10095072848 10456858624 1177531296 12.567 100 73 147 1 1205667296 11668752 16467248
20:16:08 5742 8192000000 10131397408 10501193728 1223615808 12.995 100 83 164 0 1254737552 13015776 18105968
20:17:16 1695 8192000000 10156787104 10532302848 1244249904 13.185 100 80 160 1 1277002416 14959872 17792640
20:18:20 1439 8192000000 10208067856 10587533312 1286272496 13.570 100 93 182 1 1320065712 13251632 20541584
20:19:23 1285 8192000000 10227020672 10612150272 1309789904 13.784 100 91 181 1 1343673792 13461200 20422688
20:20:25 9776 8192000000 10208963376 10606915584 1303870720 13.730 100 53 105 3 1323486544 7384864 12230960
20:21:36 8428 8192000000 10196881040 10609451008 1306814560 13.757 100 22 44 1 1317766368 6036032 4915776
20:22:46 10966 8192000000 10213019456 10624196608 1318967088 13.867 100 19 38 2 1329390416 6314112 4109216
20:23:58 11789 8192000000 10212138064 10631868416 1326897952 13.939 100 16 32 0 1336127744 5998416 3231376
20:25:15 74702 8192000000 10244478880 10654666752 1337382032 14.034 100 38 76 2 1352110976 4991152 9737792
20:27:30 4482 8192000000 10224666464 10641346560 1312554464 13.809 98 37 74 1 1328508448 7834912 8119072
20:28:38 5258 8192000000 10203355392 10614517760 1307411808 13.763 100 32 63 0 1324953504 10926816 6614880
20:29:47 39593 8192000000 10150365024 10560692224 1178839472 12.579 91 32 64 2 1190962192 4646336 7476384
20:31:28 6356 8192000000 10132636976 10527559680 1197189296 12.750 95 39 77 0 1212192384 6167392 8835696
20:32:36 8497 8192000000 10143967408 10540789760 1246379984 13.205 100 18 36 0 1256088144 5586848 4121312
20:33:47 48547 8192000000 10178796800 10583306240 1270115136 13.423 99 25 50 0 1281075488 4562704 6397648
20:35:50 3306 8192000000 10194658512 10603143168 1297427808 13.672 100 39 78 1 1313954992 6979744 9547440
20:36:54 6363 8192000000 10195719808 10613563392 1309181344 13.779 100 27 54 3 1320691840 4736720 6773776
20:38:03 51873 8192000000 10217060544 10632241152 1320963808 13.885 100 28 55 0 1332813808 5642864 6207136
20:40:09 8781 8192000000 10269510736 10678755328 1355544272 14.197 100 50 97 2 1381692208 14359360 11788576
20:41:20 53143 8192000000 10277049120 10690854912 1366005776 14.291 100 43 85 2 1391367696 15730848 9631072
20:43:18 9679 8192000000 10283288624 10705195008 1374652848 14.369 100 49 98 1 1405362000 18864128 11845024
20:44:34 15605 8192000000 10301605440 10722971648 1382043376 14.435 100 55 108 0 1415641856 20788672 12809808
20:45:55 3219 8192000000 10326283568 10741223424 1395189200 14.552 100 73 146 2 1435680592 23466160 17025232
20:47:01 16505 8192000000 10323675872 10740203520 1393121280 14.534 100 64 126 2 1432529696 24851440 14556976
20:48:21 13221 8192000000 10327735952 10745966592 1399916560 14.594 100 71 140 2 1443916064 27829104 16170400
20:49:40 9677 8192000000 10323830368 10744999936 1393936544 14.541 100 54 106 2 1435904336 29428672 12539120
20:50:55 6027 8192000000 10346389408 10758307840 1387130944 14.480 99 79 158 0 1441933520 36007888 18794688
20:52:06 1410 8192000000 10350787984 10764632064 1395260320 14.553 100 77 153 1 1449042448 34767360 19014768
20:53:12 3745 8192000000 10313596944 10727337984 1372292896 14.348 100 70 140 2 1421283024 30701744 18288384
20:54:20 1574 8192000000 10330614096 10739175424 1371659952 14.342 99 83 167 4 1426456752 34446112 20350688
20:55:26 2461 8192000000 10330032304 10733694976 1357471344 14.215 98 78 157 2 1420752592 43242320 20038928
20:56:33 2042 8192000000 10325589296 10727489536 1353661392 14.180 99 82 162 2 1416584080 43210192 19712496
20:57:42 5969 8192000000 10330027440 10740957184 1378695088 14.405 100 67 136 2 1433156192 37599584 16861520
20:58:56 13540 8192000000 10334977872 10752454656 1371092576 14.337 99 47 94 0 1432306752 48160384 13053792
21:00:15 3387 8192000000 10347836336 10760577024 1378272928 14.401 99 60 120 3 1442373392 48611520 15488944
21:01:23 10867 8192000000 10351551648 10775556096 1391533680 14.520 100 37 74 0 1455372640 53649408 10189552
21:02:40 3367 8192000000 10382065360 10801643520 1409095728 14.676 100 55 111 0 1483317824 59306336 14915760
21:03:51 21811 8192000000 10373466768 10811047936 1407383744 14.661 100 32 64 0 1484964752 68262624 9318384
21:05:18 23103 8192000000 10342076432 10776449024 1383444112 14.447 100 27 54 1 1447946576 56283920 8218544
21:06:48 32943 8192000000 10295203280 10742665216 1330564064 13.972 98 16 32 0 1390088832 54381296 5143472
21:08:21 124078 8192000000 10287763456 10722512896 1367201376 14.302 99 24 48 0 1383138544 10275664 5661504
21:11:31 83398 8192000000 10300751728 10754371584 1391446464 14.519 100 18 36 1 1409745920 14696704 3602752
21:13:56 41973 8192000000 10294419024 10750660608 1376464016 14.385 99 8 15 1 1390154016 12193200 1496800
21:15:40 20380 8192000000 10305817968 10764513280 1397713456 14.575 100 40 80 4 1421353936 13899680 9740800
21:17:03 43975 8192000000 10288915424 10751340544 1382433632 14.438 99 11 22 1 1393031216 8034864 2562720
21:18:48 46275 8192000000 10298293008 10754834432 1371111952 14.337 98 13 26 1 1387209808 12912368 3185488
21:20:35 13738 8192000000 10304657664 10760773632 1391586032 14.520 100 22 42 1 1416213392 20246096 4381264
21:22:00 96921 8192000000 10300053632 10772271104 1398189600 14.579 100 18 36 1 1419178064 15973152 5015312
21:25:10 88415 8192000000 10283766480 10747011072 1365841792 14.290 98 24 47 1 1383418048 11982352 5593904
21:27:39 52142 8192000000 10277340672 10731802624 1356368944 14.205 98 32 63 0 1373922912 10837536 6716432
21:29:37 63738 8192000000 10296037888 10763948032 1397039936 14.569 100 12 24 2 1414417376 13989792 3387648
21:32:04 82054 8192000000 10291730336 10759155712 1370356992 14.330 98 6 12 0 1379575136 8083472 1134672
21:34:29 53704 8192000000 10316957920 10770444288 1401821920 14.611 100 36 70 0 1425470096 15295056 8353120
21:36:24 71495 8192000000 10289906640 10767028224 1347651008 14.126 96 18 36 1 1369813584 18091408 4071168
21:38:36 74324 8192000000 10287645136 10740727808 1344662800 14.099 97 12 24 0 1357933120 10461616 2808704
21:40:53 37083 8192000000 10257897392 10704949248 1299829504 13.694 94 14 28 0 1310570784 7348752 3392528
21:42:31 21885 8192000000 10269804112 10712891392 1337529328 14.035 98 42 84 1 1359074192 12385776 9159088
21:43:54 19640 8192000000 10264023040 10701230080 1337834496 14.038 99 36 72 0 1358209392 12505968 7868928
21:45:19 55780 8192000000 10255272880 10700500992 1293229760 13.634 95 19 38 1 1311630896 14374224 4026912
21:47:20 102033 8192000000 10260867536 10706018304 1334137536 14.005 98 30 60 1 1350102608 8901392 7063680
21:50:10 38023 8192000000 10268784976 10718818304 1358264432 14.222 100 26 52 2 1375143776 11194768 5684576
21:51:49 43443 8192000000 10238536784 10693517312 1326237488 13.933 98 12 24 0 1339997760 10729584 3030688
21:53:55 101956 8192000000 10270868672 10722127872 1355249360 14.195 99 21 42 1 1376121040 15774112 5097568
21:57:13 37453 8192000000 10255446912 10715254784 1332323744 13.988 98 23 46 1 1355975984 17453200 6199040
21:59:04 47639 8192000000 10276649968 10726428672 1364861632 14.281 100 23 46 1 1379496352 9538128 5096592
22:00:52 39508 8192000000 10288700640 10736136192 1361119568 14.247 99 31 62 1 1378416528 10513712 6783248
22:02:45 66057 8192000000 10287913920 10746929152 1370164624 14.329 99 11 22 0 1386566656 13706688 2695344
22:05:03 26324 8192000000 10287434800 10736582656 1365374320 14.286 99 24 48 1 1383898384 13275920 5248144
22:06:36 93907 8192000000 10289237888 10745901056 1343518400 14.089 97 13 25 0 1360425328 14129600 2777328
22:09:11 150217 8192000000 10276306608 10734608384 1334363520 14.007 96 28 55 1 1350219984 9339312 6517152
22:12:51 47550 8192000000 10271867744 10737774592 1350408640 14.151 97 21 41 4 1364224080 9108272 4707168
22:14:39 43517 8192000000 10270839664 10743549952 1381692560 14.432 100 4 8 0 1392138576 9700560 745456
22:16:28 55034 8192000000 10265622800 10734268416 1360298208 14.240 99 8 16 1 1372348560 10010848 2039504
22:18:27 100315 8192000000 10284273328 10742157312 1379051440 14.408 99 13 26 0 1393188240 11179248 2957552
22:21:12 44407 8192000000 10279013984 10728824832 1362630224 14.261 99 30 60 0 1380247696 10840496 6776976
22:23:00 79434 8192000000 10275286432 10744369152 1366473200 14.295 99 19 38 0 1382779840 12283392 4023248
22:25:33 87363 8192000000 10254423648 10701332480 1270480464 13.426 93 11 22 1 1289707952 16577776 2649712
22:28:54 87984 8192000000 10241816608 10693939200 1292918064 13.631 95 12 24 0 1308945840 13414416 2613360
22:32:11 43500 8192000000 10253981728 10700910592 1342726064 14.082 100 25 50 0 1362461552 13929152 5806336
22:33:55 74329 8192000000 10247931664 10710884352 1347394944 14.124 99 8 16 0 1357414048 8243136 1775968
22:36:10 50842 8192000000 10250722576 10704060416 1325661808 13.928 97 11 20 0 1334944752 6663440 2619504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-12-2021 22:40:19 to 31-12-2021 22:40:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-12-2021 22:40:35 to 31-12-2021 22:40:37.

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          243101
MON$OLDEST_ACTIVE               244291
MON$OLDEST_SNAPSHOT             244291
MON$NEXT_TRANSACTION            244291
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                       2037280
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:220: 
:221: mscs-test-161.budzdorov.ru    Fri Dec 31 22:38:18 2021
:222:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:223: 
:224: 
:225: mscs-test-161.budzdorov.ru    Fri Dec 31 22:38:22 2021
:226:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:227: 
:228: 
:229: mscs-test-161.budzdorov.ru    Fri Dec 31 22:38:23 2021
:230:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:231: 
--- end of diff output ---

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

31.12.21 22:40:38. 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
2021-12-31 22:40:40 No severe PSQL-related problems occured
31.12.21 22:40:41. End of report.