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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16558900 kB
MemAvailable 31305640 kB
Buffers 0 kB
Cached 15076964 kB
SwapCached 2272 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 01:37:57
DTS_END                         2021-12-31 01:37:57.2290
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50954
TRN_ID                          237889
ATT_ID                          678
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: 9451.00 23208 1134120
customer order: creation 166.75 381 20010
customer order: refuse 14.30 336 1716
order to supplier: creation 44.58 1286 5350
order to supplier: removal 3.65 94786 438
invoice (draft): creation 45.13 3143 5415
invoice (draft): removal 55.97 737 6716
invoice accept: apply 40.91 15870 4909
invoice accept: cancel 50.70 724 6084
customer reserve: creation 3777.15 157 453258
customer reserve: removal 4958.76 54 595051
realization accept: apply 82.16 176 9859
realization accept: cancel 84.23 104 10107
payment from customer: creation 57.98 108 6958
payment from customer: removal 5.91 78 709
payment to supplier: creation 47.64 125 5717
payment to supplier: removal 3.46 48 415
service: total inventory turnovers 4.63 998 556
service: total monetary turnovers 6.28 23 754
service: aggregate perf. data 0.78 25453 94
service: refresh index statistics 0.03 319573 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-12-2021 01:38:45 to 31-12-2021 01:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1395
TEST_TIME 2 1949
TEST_TIME 3 2664
TEST_TIME 4 3766
TEST_TIME 5 4135
TEST_TIME 6 4044
TEST_TIME 7 4493
TEST_TIME 8 4546
TEST_TIME 9 4392
TEST_TIME 10 4391
TEST_TIME 11 5415
TEST_TIME 12 5412
TEST_TIME 13 5602
TEST_TIME 14 5808
TEST_TIME 15 6201
TEST_TIME 16 6311
TEST_TIME 17 6636
TEST_TIME 18 7328
TEST_TIME 19 7947
TEST_TIME 20 8517
TEST_TIME 21 8917
TEST_TIME 22 9822
TEST_TIME 23 10079
TEST_TIME 24 10530
TEST_TIME 25 10367
TEST_TIME 26 10338
TEST_TIME 27 10278
TEST_TIME 28 10168
TEST_TIME 29 9867
TEST_TIME 30 9887
TEST_TIME 31 9796
TEST_TIME 32 9718
TEST_TIME 33 9653
TEST_TIME 34 9669
TEST_TIME 35 9635
TEST_TIME 36 9721
TEST_TIME 37 9800
TEST_TIME 38 9692
TEST_TIME 39 9616
TEST_TIME 40 9640
TEST_TIME 41 9688
TEST_TIME 42 9682
TEST_TIME 43 9551
TEST_TIME 44 9642
TEST_TIME 45 9847
TEST_TIME 46 9840
TEST_TIME 47 9932
TEST_TIME 48 9895
TEST_TIME 49 9831
TEST_TIME 50 9887
TEST_TIME 51 9971
TEST_TIME 52 10067
TEST_TIME 53 10008
TEST_TIME 54 9896
TEST_TIME 55 9785
TEST_TIME 56 9845
TEST_TIME 57 9771
TEST_TIME 58 9723
TEST_TIME 59 9633
TEST_TIME 60 9571
TEST_TIME 61 9549
TEST_TIME 62 9465
TEST_TIME 63 9401
TEST_TIME 64 9397
TEST_TIME 65 9485
TEST_TIME 66 9442
TEST_TIME 67 9399
TEST_TIME 68 9486
TEST_TIME 69 9590
TEST_TIME 70 9626
TEST_TIME 71 9591
TEST_TIME 72 9610
TEST_TIME 73 9721
TEST_TIME 74 9757
TEST_TIME 75 9772
TEST_TIME 76 9802
TEST_TIME 77 9832
TEST_TIME 78 9719
TEST_TIME 79 9623
TEST_TIME 80 9663
TEST_TIME 81 9640
TEST_TIME 82 9590
TEST_TIME 83 9556
TEST_TIME 84 9506
TEST_TIME 85 9461
TEST_TIME 86 9465
TEST_TIME 87 9416
TEST_TIME 88 9478
TEST_TIME 89 9489
TEST_TIME 90 9441
TEST_TIME 91 9467
TEST_TIME 92 9427
TEST_TIME 93 9419
TEST_TIME 94 9503
TEST_TIME 95 9554
TEST_TIME 96 9581
TEST_TIME 97 9569
TEST_TIME 98 9611
TEST_TIME 99 9609
TEST_TIME 100 9643
TEST_TIME 101 9702
TEST_TIME 102 9675
TEST_TIME 103 9588
TEST_TIME 104 9558
TEST_TIME 105 9499
TEST_TIME 106 9500
TEST_TIME 107 9535
TEST_TIME 108 9511
TEST_TIME 109 9468
TEST_TIME 110 9453
TEST_TIME 111 9392
TEST_TIME 112 9378
TEST_TIME 113 9386
TEST_TIME 114 9383
TEST_TIME 115 9380
TEST_TIME 116 9419
TEST_TIME 117 9388
TEST_TIME 118 9438
TEST_TIME 119 9410
TEST_TIME 120 9451

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-12-2021 01:38:49 to 31-12-2021 01:38:52.

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 1555813 1555813 0 0.00 0 18461 5 0 0
doc_list_biud 1555800 1555800 0 0.00 0 20808 27 0 0
sp_add_invoice_to_stock 5086 4909 177 3.48 18 197777 15871 0 177
sp_cancel_adding_invoice 6084 6084 0 0.00 5 61705 725 0 0
sp_cancel_client_order 1716 1716 0 0.00 4 11012 336 0 0
sp_cancel_customer_reserve 595051 595051 0 0.00 1 20810 55 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 3 5275 79 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 2345 49 0 0
sp_cancel_supplier_invoice 6726 6716 10 0.14 1 17386 737 0 10
sp_cancel_supplier_order 438 438 0 0.00 5 1093682 94786 0 0
sp_cancel_write_off 10107 10107 0 0.00 1 9310 105 0 0
sp_client_order 20010 20010 0 0.00 3 61437 381 0 0
sp_customer_reserve 453258 453258 0 0.00 0 142940 157 0 0
sp_fill_shopping_cart 30753 30753 0 0.00 1 13224 33 0 0
sp_fill_shopping_cart_clo_res 453257 453257 0 0.00 0 30421 14 0 0
sp_get_clo_for_invoice 4908 4908 0 0.00 0 29834 306 0 0
sp_kill_cost_storno 17315 17315 0 0.00 0 6777 47 0 0
sp_kill_qstorno_ret_qs2qd 1207363 1207363 0 0.00 0 20806 30 0 0
sp_kill_qty_storno 634879 634879 0 0.00 0 20807 64 0 0
sp_lock_selected_doc 20530 20530 0 0.00 0 9048 7 0 0
sp_make_cost_storno 27442 27442 0 0.00 0 13813 46 0 0
sp_multiply_rows_for_pdistr 27442 27442 0 0.00 0 5970 27 0 0
sp_multiply_rows_for_qdistr 20010 20010 0 0.00 0 56308 161 0 0
sp_pay_from_customer 6958 6958 0 0.00 4 16312 109 0 0
sp_pay_to_supplier 5717 5717 0 0.00 5 16105 126 0 0
sp_payment_common 12675 12675 0 0.00 1 8284 26 0 0
sp_qd_handle_on_cancel_clo 1716 1716 0 0.00 0 11004 312 0 0
sp_qd_handle_on_invoice_upd_sts 10992 10992 0 0.00 0 16007 304 0 0
sp_qd_handle_on_reserve_upd_sts 9859 9859 0 0.00 0 14428 60 0 0
sp_reserve_write_off 9859 9859 0 0.00 6 18468 176 0 0
sp_supplier_invoice 5415 5415 0 0.00 6 80490 3144 0 0
sp_supplier_order 5350 5350 0 0.00 5 32678 1287 0 0
srv_aggregate_perf_data 94 94 0 0.00 2495 372632 25453 0 0
srv_fill_mon_cache_memory 57 57 0 0.00 2457 148878 55578 0 0
srv_find_qd_qs_mism 1088449 1088449 0 0.00 0 18313 5 0 0
srv_make_invnt_saldo 556 556 0 0.00 36 36866 999 0 0
srv_make_money_saldo 754 754 0 0.00 1 2872 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 238457 394465 319574 0 0
t$perf-abend:sp_add_invoice_to_stock 177 177 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4909 4909 0 0.00 0 21965 171 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 5711 101 0 0
t$perf-norm:sp_cancel_client_order 1716 1716 0 0.00 0 7974 17 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 2610 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 1724 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 2256 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 428 7 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 14836 1473 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 5258 15 0 0
t$perf-norm:sp_client_order 20010 20010 0 0.00 0 15515 16 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 6223 13 0 0
t$perf-norm:sp_pay_from_customer 6958 6958 0 0.00 0 12600 12 0 0
t$perf-norm:sp_pay_to_supplier 5717 5717 0 0.00 0 4870 11 0 0
t$perf-norm:sp_reserve_write_off 9859 9859 0 0.00 0 12821 14 0 0
t$perf-norm:sp_supplier_invoice 5415 5415 0 0.00 0 7227 18 0 0
t$perf-norm:sp_supplier_order 5350 5350 0 0.00 0 6176 20 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 2700 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 57 57 0 0.00 0 15 1 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 1987 24 0 0
t$perf-norm:srv_make_money_saldo 754 754 0 0.00 0 871 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 53 15 0 0
v_add_invoice_to_stock 10169 9815 354 3.48 0 7020 20 0 354
v_all_customers 36076 36076 0 0.00 0 145 0 0 0
v_all_suppliers 29994 29994 0 0.00 0 40 0 0 0
v_all_wares 40008 40008 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 2952 11 0 0
v_cancel_client_order 3432 3432 0 0.00 0 3371 5 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 3252 9 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 5951 7 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 447 3 0 0
v_cancel_supplier_invoice 864 844 20 2.31 0 636 14 0 20
v_cancel_supplier_order 876 876 0 0.00 0 15718 21 0 0
v_cancel_write_off 1978 1978 0 0.00 0 3676 14 0 0
v_max_id_clo_ord 5344 5344 0 0.00 0 340 1 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 3890 15 0 0
v_max_id_ord_sup 5405 5405 0 0.00 0 4154 5 0 0
v_max_non_paid_invoice 1469 1469 0 0.00 0 2449 4 0 0
v_max_non_paid_realizn 3806 3806 0 0.00 0 3988 4 0 0
v_min_id_clo_ord 5344 5344 0 0.00 0 1654 4 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 8806 15 0 0
v_min_id_ord_sup 5405 5405 0 0.00 0 4528 5 0 0
v_min_non_paid_invoice 5717 5717 0 0.00 0 7514 22 0 0
v_min_non_paid_realizn 6958 6958 0 0.00 0 4814 16 0 0
v_our_firm 10236 10236 0 0.00 0 17 0 0 0
v_reserve_write_off 19714 19714 0 0.00 0 15928 11 0 0
x_lock_depdocs_on_canc_invoice 12378 12378 0 0.00 0 16429 114 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 9678 306 0 0
x_make_qstorno_1000_1200 5350 5350 0 0.00 1 32670 1225 0 0
x_make_qstorno_1200_2000 5415 5415 0 0.00 3 80475 3019 0 0
x_make_qstorno_2100_3300 453258 453258 0 0.00 0 142890 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-12-2021 01:38:53 to 31-12-2021 01:38:57.

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:01 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:01 7037 8192000000 9880720864 10185232384 966606704 10.554 100 90 180 0 995677152 5196768 23873680
23:10:15 20203 8192000000 9939399920 10256732160 1020168976 11.074 100 91 182 0 1051659904 7639472 23851456
23:11:49 31182 8192000000 9983148096 10316902400 1063973792 11.494 100 84 168 0 1094608576 9343808 21290976
23:13:25 10710 8192000000 10043115648 10391621632 1124297792 12.068 100 81 160 1 1154274032 11144688 18831552
23:14:39 9199 8192000000 10086178032 10445893632 1174339104 12.537 100 78 156 1 1205662608 13122512 18200992
23:15:50 6623 8192000000 10120218192 10494582784 1218162912 12.945 100 65 131 2 1243690176 10717568 14809696
23:16:58 2783 8192000000 10167414384 10546286592 1254407232 13.279 100 87 173 1 1285677504 12475072 18795200
23:18:02 1610 8192000000 10191148096 10571284480 1277244528 13.488 100 87 175 2 1310009152 13279168 19485456
23:19:05 2018 8192000000 10206728992 10588766208 1288491504 13.590 100 84 166 3 1319553024 12130896 18930624
23:20:11 14482 8192000000 10187859584 10595991552 1297264048 13.670 100 32 64 0 1312885136 8383728 7237360
23:21:26 4349 8192000000 10205187664 10605826048 1309929120 13.785 100 29 58 0 1324100992 6522992 7648880
23:22:33 9005 8192000000 10208676048 10618044416 1315605328 13.837 100 23 46 0 1326279104 5776048 4897728
23:23:42 68895 8192000000 10215615184 10636505088 1321348784 13.889 100 35 69 2 1334305328 5057056 7899488
23:25:51 4335 8192000000 10223190800 10641215488 1327032512 13.940 100 32 63 0 1344356144 10153312 7170320
23:26:56 10086 8192000000 10225937712 10641551360 1331907552 13.984 100 23 46 1 1343432160 5735456 5789152
23:28:10 9231 8192000000 10163766976 10566590464 1260656128 13.336 95 23 46 0 1271204608 5451760 5096720
23:29:28 15309 8192000000 10177928944 10586406912 1244642688 13.189 95 14 28 0 1256999584 9314272 3042624
23:30:43 6730 8192000000 10147638592 10555265024 1231528880 13.068 97 29 57 2 1244469472 6646976 6293616
23:31:51 6820 8192000000 10139276560 10534588416 1230826384 13.062 99 30 59 2 1242941632 5492560 6622688
23:33:00 57115 8192000000 10170664208 10559746048 1258285744 13.314 99 47 95 0 1274958960 4826016 11847200
23:34:58 6122 8192000000 10181029008 10589450240 1287765056 13.584 100 28 56 1 1300691008 5977776 6948176
23:36:08 15658 8192000000 10185385936 10601013248 1296928176 13.667 100 24 46 2 1308915760 5495504 6492080
23:37:27 15464 8192000000 10187739584 10605481984 1303983648 13.731 100 23 44 1 1314870112 5986880 4899584
23:38:55 54200 8192000000 10241831760 10654138368 1335069856 14.013 100 40 80 0 1351193456 7554608 8568992
23:40:52 39345 8192000000 10268227856 10689069056 1358300048 14.222 100 45 90 0 1385411920 16078992 11032880
23:42:51 25644 8192000000 10290214576 10716368896 1376756064 14.388 100 39 79 2 1407952704 21626528 9570112
23:44:21 23025 8192000000 10297909136 10714927104 1368075088 14.310 99 46 92 1 1401806224 22664400 11066736
23:45:58 11849 8192000000 10302444624 10727944192 1380301232 14.419 99 41 82 0 1414195536 23574912 10319392
23:47:13 11802 8192000000 10327323152 10751291392 1396585856 14.565 100 51 102 1 1439031008 29679008 12766144
23:48:35 3155 8192000000 10342618688 10766069760 1402631296 14.618 99 58 116 1 1446652032 29176160 14844576
23:49:44 6714 8192000000 10351608240 10775965696 1406124688 14.649 99 56 112 0 1455855248 35457040 14273520
23:50:57 4614 8192000000 10362273968 10780143616 1404030704 14.631 99 63 125 1 1458283760 38544960 15708096
23:52:05 2829 8192000000 10386771936 10799554560 1424785152 14.815 100 86 172 2 1489608432 43684800 21138480
23:53:12 4924 8192000000 10355820576 10769223680 1401238384 14.606 99 70 136 1 1463108800 43668384 18202032
23:54:19 2456 8192000000 10363961488 10777808896 1403047984 14.622 100 75 149 0 1472285744 50579088 18658672
23:55:25 14312 8192000000 10340553536 10772516864 1379417200 14.411 99 44 88 0 1449508992 57814704 12277088
23:56:51 13410 8192000000 10336004192 10765230080 1388925344 14.496 100 38 76 0 1441935664 42552912 10457408
23:58:07 23681 8192000000 10278701408 10701926400 1278803952 13.502 94 27 54 0 1324437376 38079584 7553840
23:59:33 32318 8192000000 10260461200 10705383424 1310587760 13.791 97 22 44 2 1344259648 27800128 5871760
00:01:12 82500 8192000000 10256233104 10696744960 1300027696 13.695 96 9 18 0 1317768144 15609824 2130624
00:03:39 97192 8192000000 10251270544 10674176000 1261308128 13.342 93 20 40 0 1270987216 6051760 3627328
00:06:17 78549 8192000000 10249890768 10689445888 1307421040 13.763 97 45 90 1 1328736832 10759200 10556592
00:09:18 145126 8192000000 10308658688 10755072000 1362865728 14.263 97 31 62 1 1386006992 15029808 8111456
00:12:52 46042 8192000000 10296183696 10748358656 1365575616 14.287 98 21 41 2 1386660448 15957568 5127264
00:15:13 73786 8192000000 10285302672 10732601344 1363140832 14.266 98 17 34 1 1379445392 12453200 3851360
00:17:37 49508 8192000000 10290112336 10750365696 1378258896 14.401 99 11 22 0 1396564896 15458112 2847888
00:19:26 29645 8192000000 10312004304 10757459968 1398478208 14.581 100 44 89 1 1421958720 13310128 10170384
00:20:57 64407 8192000000 10298332720 10752368640 1399097568 14.587 100 31 62 0 1418770416 12384736 7288112
00:23:07 49285 8192000000 10313955648 10761146368 1382267824 14.437 99 14 28 1 1408240480 22059296 3913360
00:24:59 62416 8192000000 10325743312 10757140480 1387992848 14.488 99 18 36 0 1404557568 12380784 4183936
00:27:06 52394 8192000000 10324402016 10776387584 1394410160 14.545 99 29 58 0 1416303744 15613328 6280256
00:29:05 126054 8192000000 10318795952 10779201536 1393544320 14.537 99 11 22 2 1418521600 22736896 2240384
00:32:31 100257 8192000000 10313855504 10772627456 1375707312 14.378 98 22 44 1 1396529024 16129584 4692128
00:35:15 70054 8192000000 10288549520 10748895232 1361052784 14.247 97 16 32 1 1373197824 9137408 3007632
00:37:30 66689 8192000000 10294214832 10751205376 1368731072 14.316 98 8 16 1 1381455904 11078240 1646592
00:39:42 91281 8192000000 10295546448 10748817408 1374265312 14.365 98 17 34 1 1391360880 12575136 4520432
00:43:17 64823 8192000000 10300201328 10756894720 1372335200 14.348 98 20 40 0 1388022080 10850720 4836160
00:45:38 70425 8192000000 10286451024 10746003456 1356859552 14.209 98 14 28 1 1380813776 20358976 3595248
00:47:50 65850 8192000000 10271955360 10713083904 1328850320 13.957 96 31 62 3 1348882064 13120928 6910816
00:50:02 68944 8192000000 10267672288 10730344448 1322678320 13.901 97 10 20 2 1352567920 27632848 2256752
00:52:13 96385 8192000000 10276130240 10712199168 1290355312 13.607 95 15 30 0 1311122960 17545168 3222480
00:54:52 101974 8192000000 10280304944 10718879744 1361201824 14.248 100 9 18 1 1387747920 24637312 1908784
00:57:54 48508 8192000000 10266674960 10712039424 1355910048 14.201 99 24 48 0 1369830496 8025504 5894944
00:59:58 76603 8192000000 10276449184 10734137344 1368174272 14.311 99 12 24 0 1385785136 14941840 2669024
01:02:14 69923 8192000000 10287318880 10737029120 1365476144 14.286 98 29 58 1 1385712352 13121744 7114464
01:04:33 76694 8192000000 10264725280 10730270720 1331378192 13.980 97 19 38 1 1350346288 14535792 4432304
01:06:58 56799 8192000000 10273277696 10714890240 1336137824 14.023 97 19 38 0 1350679856 10281936 4260096
01:08:55 52338 8192000000 10299829024 10744406016 1366451728 14.295 98 19 38 0 1377147840 6972880 3723232
01:10:55 110570 8192000000 10297777648 10758381568 1351373344 14.160 97 10 20 0 1369388176 16497536 1517296
01:14:38 53443 8192000000 10311324528 10765963264 1377394064 14.393 99 20 40 2 1397118864 15329168 4395632
01:16:43 81971 8192000000 10298701232 10741997568 1308719344 13.774 94 15 30 0 1326074528 14177264 3177920
01:19:06 148878 8192000000 10284796944 10732441600 1356555888 14.206 98 23 46 1 1372728304 11232720 4939696
01:22:44 19214 8192000000 10298677696 10735755264 1383181024 14.445 100 33 64 2 1400393280 10078752 7133504
01:24:07 61696 8192000000 10271305824 10739781632 1368202768 14.311 99 12 25 0 1384356544 13288464 2865312
01:26:11 28149 8192000000 10278798544 10729254912 1368126064 14.310 99 13 26 0 1377749504 6959152 2664288
01:27:50 57531 8192000000 10282398768 10746372096 1387838176 14.487 100 8 16 1 1401107712 11335792 1933744
01:29:48 60579 8192000000 10275044080 10732310528 1348129152 14.131 98 7 14 0 1361470624 11694608 1646864
01:31:54 52797 8192000000 10276241008 10728562688 1349531072 14.143 98 10 20 0 1359805072 7959920 2314080
01:33:55 28754 8192000000 10292804496 10742378496 1369977600 14.327 99 27 54 0 1382142528 6427744 5737184
01:36:10 55549 8192000000 10241729888 10682662912 1289988864 13.604 94 28 56 0 1304938144 8298720 6650560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 31-12-2021 01:38:59 to 31-12-2021 01:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 177 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-12-2021 01:39:14 to 31-12-2021 01:39:16.

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          237498
MON$OLDEST_ACTIVE               238246
MON$OLDEST_SNAPSHOT             238246
MON$NEXT_TRANSACTION            238246
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                       2020800
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 ---
:157: 
:158: mscs-test-161.budzdorov.ru    Fri Dec 31 01:38:01 2021
:159:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:160: 
:161: 
:162: mscs-test-161.budzdorov.ru    Fri Dec 31 01:38:07 2021
:163:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:164: 
--- end of diff output ---

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

31.12.21 01:39:17. 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 01:39:19 No severe PSQL-related problems occured
31.12.21 01:39:21. End of report.