Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.09.2021 22: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15994356 kB
MemAvailable 30812320 kB
Buffers 0 kB
Cached 15675632 kB
SwapCached 416 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.8
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-09-15 22:37:47
DTS_END                         2021-09-15 22:37:47.4550
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43034
TRN_ID                          247265
ATT_ID                          655
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: 9380.67 19888 1125681
customer order: creation 182.65 360 21918
customer order: refuse 15.24 344 1829
order to supplier: creation 47.77 1313 5732
order to supplier: removal 3.54 99365 425
invoice (draft): creation 49.18 3615 5901
invoice (draft): removal 52.86 900 6343
invoice accept: apply 43.84 16130 5261
invoice accept: cancel 47.23 858 5667
customer reserve: creation 4018.15 160 482178
customer reserve: removal 4613.02 60 553563
realization accept: apply 88.14 170 10577
realization accept: cancel 83.97 98 10076
payment from customer: creation 61.97 105 7436
payment from customer: removal 6.12 81 734
payment to supplier: creation 51.20 127 6144
payment to supplier: removal 3.78 59 454
service: total inventory turnovers 4.48 1266 538
service: total monetary turnovers 6.63 30 795
service: aggregate perf. data 0.88 19302 106
service: refresh index statistics 0.03 253407 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-09-2021 22:39:05 to 15-09-2021 22:39:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3960
TEST_TIME 2 4299
TEST_TIME 3 4064
TEST_TIME 4 4361
TEST_TIME 5 4147
TEST_TIME 6 4700
TEST_TIME 7 4898
TEST_TIME 8 5437
TEST_TIME 9 5826
TEST_TIME 10 5839
TEST_TIME 11 6202
TEST_TIME 12 6632
TEST_TIME 13 6916
TEST_TIME 14 7528
TEST_TIME 15 7723
TEST_TIME 16 7951
TEST_TIME 17 8301
TEST_TIME 18 8389
TEST_TIME 19 8389
TEST_TIME 20 8480
TEST_TIME 21 9026
TEST_TIME 22 9095
TEST_TIME 23 9502
TEST_TIME 24 9447
TEST_TIME 25 9472
TEST_TIME 26 9508
TEST_TIME 27 9987
TEST_TIME 28 10123
TEST_TIME 29 10663
TEST_TIME 30 10795
TEST_TIME 31 10960
TEST_TIME 32 10787
TEST_TIME 33 10804
TEST_TIME 34 10507
TEST_TIME 35 10475
TEST_TIME 36 10469
TEST_TIME 37 10525
TEST_TIME 38 10300
TEST_TIME 39 10207
TEST_TIME 40 10123
TEST_TIME 41 9988
TEST_TIME 42 10224
TEST_TIME 43 10206
TEST_TIME 44 10101
TEST_TIME 45 9996
TEST_TIME 46 9870
TEST_TIME 47 9689
TEST_TIME 48 9737
TEST_TIME 49 9813
TEST_TIME 50 9860
TEST_TIME 51 9871
TEST_TIME 52 9819
TEST_TIME 53 9878
TEST_TIME 54 9882
TEST_TIME 55 9854
TEST_TIME 56 9892
TEST_TIME 57 9860
TEST_TIME 58 9885
TEST_TIME 59 9930
TEST_TIME 60 9942
TEST_TIME 61 9858
TEST_TIME 62 9726
TEST_TIME 63 9647
TEST_TIME 64 9644
TEST_TIME 65 9597
TEST_TIME 66 9530
TEST_TIME 67 9466
TEST_TIME 68 9420
TEST_TIME 69 9446
TEST_TIME 70 9495
TEST_TIME 71 9493
TEST_TIME 72 9482
TEST_TIME 73 9500
TEST_TIME 74 9589
TEST_TIME 75 9641
TEST_TIME 76 9694
TEST_TIME 77 9662
TEST_TIME 78 9621
TEST_TIME 79 9628
TEST_TIME 80 9748
TEST_TIME 81 9754
TEST_TIME 82 9675
TEST_TIME 83 9619
TEST_TIME 84 9611
TEST_TIME 85 9545
TEST_TIME 86 9540
TEST_TIME 87 9509
TEST_TIME 88 9509
TEST_TIME 89 9469
TEST_TIME 90 9402
TEST_TIME 91 9369
TEST_TIME 92 9418
TEST_TIME 93 9415
TEST_TIME 94 9453
TEST_TIME 95 9472
TEST_TIME 96 9463
TEST_TIME 97 9553
TEST_TIME 98 9514
TEST_TIME 99 9502
TEST_TIME 100 9513
TEST_TIME 101 9552
TEST_TIME 102 9566
TEST_TIME 103 9556
TEST_TIME 104 9559
TEST_TIME 105 9627
TEST_TIME 106 9617
TEST_TIME 107 9601
TEST_TIME 108 9515
TEST_TIME 109 9523
TEST_TIME 110 9564
TEST_TIME 111 9542
TEST_TIME 112 9506
TEST_TIME 113 9460
TEST_TIME 114 9414
TEST_TIME 115 9444
TEST_TIME 116 9428
TEST_TIME 117 9428
TEST_TIME 118 9406
TEST_TIME 119 9405
TEST_TIME 120 9384

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-09-2021 22:39:08 to 15-09-2021 22:39: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 1571247 1571247 0 0.00 0 17655 6 0 0
doc_list_biud 1571246 1571246 0 0.00 0 69949 27 0 0
sp_add_invoice_to_stock 5327 5261 66 1.23 32 156627 16130 0 66
sp_cancel_adding_invoice 5667 5667 0 0.00 5 69639 858 0 0
sp_cancel_client_order 1829 1829 0 0.00 5 12656 344 0 0
sp_cancel_customer_reserve 553563 553563 0 0.00 1 69952 60 0 0
sp_cancel_pay_from_customer 734 734 0 0.00 3 8329 82 0 0
sp_cancel_pay_to_supplier 454 454 0 0.00 2 3348 60 0 0
sp_cancel_supplier_invoice 6350 6343 7 0.11 2 18817 900 0 7
sp_cancel_supplier_order 425 425 0 0.00 10 1089450 99365 0 0
sp_cancel_write_off 10076 10076 0 0.00 1 6991 99 0 0
sp_client_order 21918 21918 0 0.00 3 63448 361 0 0
sp_customer_reserve 482178 482178 0 0.00 0 33823 160 0 0
sp_fill_shopping_cart 33525 33525 0 0.00 1 13900 37 0 0
sp_fill_shopping_cart_clo_res 482171 482171 0 0.00 0 15323 13 0 0
sp_get_clo_for_invoice 5253 5253 0 0.00 0 25948 281 0 0
sp_kill_cost_storno 16931 16931 0 0.00 0 10248 55 0 0
sp_kill_qstorno_ret_qs2qd 1123970 1123970 0 0.00 0 69947 33 0 0
sp_kill_qty_storno 593732 593732 0 0.00 0 69949 71 0 0
sp_lock_selected_doc 21876 21876 0 0.00 0 8321 6 0 0
sp_make_cost_storno 29410 29410 0 0.00 0 17143 47 0 0
sp_multiply_rows_for_pdistr 29410 29410 0 0.00 0 6114 27 0 0
sp_multiply_rows_for_qdistr 21918 21918 0 0.00 0 63440 175 0 0
sp_pay_from_customer 7436 7436 0 0.00 4 18909 105 0 0
sp_pay_to_supplier 6144 6144 0 0.00 5 22457 128 0 0
sp_payment_common 13580 13580 0 0.00 1 22447 31 0 0
sp_qd_handle_on_cancel_clo 1829 1829 0 0.00 1 12647 312 0 0
sp_qd_handle_on_invoice_upd_sts 10920 10920 0 0.00 0 27036 325 0 0
sp_qd_handle_on_reserve_upd_sts 10576 10576 0 0.00 0 14121 59 0 0
sp_reserve_write_off 10577 10577 0 0.00 6 14196 171 0 0
sp_supplier_invoice 5901 5901 0 0.00 6 85566 3615 0 0
sp_supplier_order 5732 5732 0 0.00 5 21641 1314 0 0
srv_aggregate_perf_data 106 106 0 0.00 3782 160996 19303 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1816 141634 42527 0 0
srv_find_qd_qs_mism 1075118 1075118 0 0.00 0 17653 5 0 0
srv_make_invnt_saldo 538 538 0 0.00 54 55574 1266 0 0
srv_make_money_saldo 795 795 0 0.00 1 2117 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 146854 391453 253407 0 0
t$perf-abend:sp_add_invoice_to_stock 66 66 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 5261 5261 0 0.00 0 14240 137 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 0 2595 93 0 0
t$perf-norm:sp_cancel_client_order 1829 1829 0 0.00 0 2014 12 0 0
t$perf-norm:sp_cancel_customer_reserve 752 752 0 0.00 0 1387 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 734 734 0 0.00 0 897 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 454 454 0 0.00 0 1996 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 1415 11 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 19681 1613 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 3641 8 0 0
t$perf-norm:sp_client_order 21918 21918 0 0.00 0 15378 15 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 14854 18 0 0
t$perf-norm:sp_pay_from_customer 7436 7436 0 0.00 0 7050 10 0 0
t$perf-norm:sp_pay_to_supplier 6144 6144 0 0.00 0 11332 8 0 0
t$perf-norm:sp_reserve_write_off 10577 10577 0 0.00 0 7656 9 0 0
t$perf-norm:sp_supplier_invoice 5901 5901 0 0.00 0 16264 24 0 0
t$perf-norm:sp_supplier_order 5732 5732 0 0.00 0 7064 17 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 118 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 834 39 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 750 14 0 0
t$perf-norm:srv_make_money_saldo 795 795 0 0.00 0 810 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10632 10500 132 1.24 0 7535 20 0 132
v_all_customers 39700 39700 0 0.00 0 24 1 0 0
v_all_suppliers 31892 31892 0 0.00 0 109 1 0 0
v_all_wares 43836 43836 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 1955 10 0 0
v_cancel_client_order 3658 3658 0 0.00 0 3379 8 0 0
v_cancel_customer_prepayment 1468 1468 0 0.00 0 2071 5 0 0
v_cancel_customer_reserve 1504 1504 0 0.00 0 4005 8 0 0
v_cancel_payment_to_supplier 908 908 0 0.00 0 456 3 0 0
v_cancel_supplier_invoice 828 814 14 1.69 0 891 14 0 14
v_cancel_supplier_order 850 850 0 0.00 0 963 5 0 0
v_cancel_write_off 2020 2020 0 0.00 0 5905 16 0 0
v_max_id_clo_ord 5726 5726 0 0.00 0 2246 2 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 1733 11 0 0
v_max_id_ord_sup 5879 5879 0 0.00 0 5847 3 0 0
v_max_non_paid_invoice 1803 1803 0 0.00 0 524 2 0 0
v_max_non_paid_realizn 3984 3984 0 0.00 0 2936 4 0 0
v_min_id_clo_ord 5726 5726 0 0.00 0 3330 7 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 6088 18 0 0
v_min_id_ord_sup 5879 5879 0 0.00 0 8943 10 0 0
v_min_non_paid_invoice 6144 6144 0 0.00 0 22443 23 0 0
v_min_non_paid_realizn 7436 7436 0 0.00 0 8539 15 0 0
v_our_firm 11040 11040 0 0.00 0 22 1 0 0
v_reserve_write_off 21152 21152 0 0.00 0 5532 7 0 0
x_lock_depdocs_on_canc_invoice 11603 11603 0 0.00 0 14449 110 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 10714 381 0 0
x_make_qstorno_1000_1200 5732 5732 0 0.00 2 21612 1240 0 0
x_make_qstorno_1200_2000 5901 5901 0 0.00 3 85558 3472 0 0
x_make_qstorno_2100_3300 482178 482178 0 0.00 0 33821 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-09-2021 22:39:13 to 15-09-2021 22:39: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:07:51 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:52 13295 8192000000 10363580464 10682224640 1420224608 14.775 100 84 168 0 1453449616 6241376 26983632
20:10:11 19634 8192000000 10446351856 10774929408 1495018800 15.433 100 92 184 0 1532607424 8308944 29279680
20:11:41 14667 8192000000 10472886112 10813218816 1533040192 15.763 100 83 166 0 1569238224 10289088 25908944
20:12:59 13341 8192000000 10555284064 10915532800 1621496288 16.523 100 79 158 0 1655404496 10583440 23324768
20:14:16 9632 8192000000 10643112064 11015315456 1702406224 17.205 100 74 148 1 1735617888 11762720 21448944
20:15:29 4846 8192000000 10707266960 11076059136 1759232304 17.678 100 85 166 2 1795997536 13341216 23424016
20:16:35 2876 8192000000 10753055056 11137294336 1812892464 18.120 100 83 166 0 1849598720 12631344 24074912
20:17:39 2053 8192000000 10800088192 11183218688 1849731072 18.420 100 91 185 0 1889462480 14037312 25694096
20:18:42 1008 8192000000 10826421344 11213320192 1882279504 18.684 100 96 195 0 1922405280 12935760 27190016
20:19:44 5241 8192000000 10794070784 11216125952 1878859808 18.656 100 29 57 1 1895315088 8155088 8300192
20:20:49 9865 8192000000 10796303504 11224096768 1889415280 18.741 100 22 44 1 1902170480 5986592 6768608
20:22:00 5243 8192000000 10796397504 11227082752 1893480992 18.774 100 26 52 2 1906111728 5257936 7372800
20:23:09 57396 8192000000 10808054352 11245260800 1900584512 18.831 100 25 50 0 1912954816 5232608 7137696
20:25:09 4167 8192000000 10836733408 11262316544 1912973584 18.931 100 42 84 0 1931395408 6749296 11672528
20:26:14 5050 8192000000 10799737152 11233787904 1889767744 18.744 100 22 43 3 1901910960 5822864 6320352
20:27:21 22162 8192000000 10744866160 11174187008 1756790848 17.658 93 16 32 1 1766477952 4917776 4769328
20:28:47 11676 8192000000 10769128464 11201966080 1859660704 18.501 100 27 54 4 1874368192 7339888 7367600
20:30:01 5961 8192000000 10732980880 11163037696 1811209808 18.106 99 21 42 2 1823042832 6234464 5598560
20:31:12 8520 8192000000 10709212320 11126317056 1753270960 17.629 97 19 37 2 1763920848 5015888 5634000
20:32:25 43756 8192000000 10733241824 11145666560 1775868896 17.815 97 33 65 2 1790923952 4933360 10121696
20:34:15 7398 8192000000 10773530560 11185590272 1839252640 18.335 99 39 77 1 1856122960 6770848 10099472
20:35:22 9493 8192000000 10785478304 11218882560 1877469920 18.645 100 38 77 2 1895820624 7457488 10893216
20:36:33 10493 8192000000 10798083456 11234566144 1885561248 18.710 100 26 52 3 1903365440 10607504 7196688
20:37:47 42381 8192000000 10853074224 11271651328 1914064416 18.939 100 34 68 1 1929437168 6113760 9258992
20:39:40 26206 8192000000 10857490128 11293130752 1926494160 19.039 99 46 92 2 1947390640 7931312 12965168
20:41:12 32518 8192000000 10886618640 11319754752 1926429712 19.038 98 39 78 0 1952006416 14586368 10990336
20:42:47 12875 8192000000 10920100832 11353141248 1980309936 19.467 100 47 93 1 2012451472 18426688 13714848
20:44:09 12621 8192000000 10930536400 11369439232 1994680320 19.581 100 39 77 0 2024057248 16970864 12406064
20:45:30 11718 8192000000 10955262208 11387424768 2010067840 19.702 100 56 112 3 2046521472 19382704 17070928
20:46:57 1815 8192000000 10973656864 11385520128 2003627088 19.651 100 84 168 1 2048479456 20794544 24057824
20:48:05 6098 8192000000 10971242528 11398369280 2008693152 19.691 100 69 138 3 2054378832 25090304 20595376
20:49:16 3499 8192000000 10978247232 11397865472 2006927264 19.677 100 83 166 2 2059543280 28507904 24108112
20:50:26 2459 8192000000 10963928304 11372867584 1987462640 19.524 99 89 177 1 2043839040 30796416 25579984
20:51:29 3315 8192000000 10925631760 11348688896 1934996576 19.107 98 79 159 0 1995230112 35192000 25041536
20:52:35 9432 8192000000 10932416192 11370074112 1972311968 19.404 100 40 80 1 2015964944 30929008 12723968
20:53:53 2937 8192000000 10975985344 11400663040 1998737376 19.613 100 70 140 2 2055197536 34522176 21937984
20:55:00 14575 8192000000 10923932144 11357700096 1935360848 19.110 98 37 73 1 1977179584 30436896 11381840
20:56:18 9010 8192000000 10895256800 11326992384 1910525168 18.911 98 40 80 0 1963413088 39119392 13768528
20:57:31 11725 8192000000 10892067312 11323514880 1892126752 18.763 98 44 88 1 1953345872 46101728 15117392
20:58:49 5494 8192000000 10904255024 11331772416 1931605312 19.080 100 51 102 0 1984378384 35734992 17038080
21:00:00 8682 8192000000 10899875152 11337428992 1933677856 19.096 100 41 82 2 1984475552 37943376 12854320
21:01:09 17030 8192000000 10894315248 11342012416 1934121152 19.100 100 19 38 0 1974714032 34253120 6339760
21:02:27 33646 8192000000 10888251200 11342356480 1908049840 18.891 99 22 44 0 1957704320 41785376 7869104
21:04:19 11966 8192000000 10909265296 11358806016 1919007248 18.979 99 35 70 0 1980638320 49568432 12062640
21:05:33 36264 8192000000 10896762800 11355906048 1941703072 19.160 100 18 36 2 1982804368 34888496 6212800
21:07:15 59002 8192000000 10900428384 11350147072 1931377760 19.078 99 26 51 0 1956634992 17386144 7871088
21:09:18 98788 8192000000 10898418384 11369402368 1978007216 19.449 100 23 46 0 1993893440 9453424 6432800
21:12:26 23054 8192000000 10917784800 11372597248 1988669696 19.533 100 22 44 2 2002601200 8273552 5657952
21:14:02 27408 8192000000 10915780176 11361005568 1960859200 19.313 98 39 78 2 1979931104 8324704 10747200
21:15:30 41725 8192000000 10911618848 11373375488 1972150208 19.403 99 14 28 1 1985269536 8585888 4533440
21:17:14 25834 8192000000 10941788928 11403010048 2008699808 19.691 100 21 42 1 2025283872 10467872 6116192
21:18:40 35050 8192000000 10953941824 11421061120 2026881152 19.834 100 43 85 0 2053497664 14100048 12516464
21:20:23 29969 8192000000 10929908272 11410567168 1999119136 19.616 100 9 18 1 2015675520 14053872 2502512
21:22:12 31175 8192000000 10898394224 11375013888 1976966832 19.441 99 7 14 0 1988589328 9299408 2323088
21:23:44 68804 8192000000 10929669312 11398201344 1995644352 19.588 100 18 34 1 2011901696 10686544 5570800
21:26:03 77525 8192000000 10901397008 11382513664 1904364464 18.861 95 9 18 0 1921853440 14831408 2657568
21:28:35 48537 8192000000 10930505328 11388801024 1983233344 19.490 100 13 26 1 1997496720 10689984 3573392
21:30:27 39329 8192000000 10931210832 11395305472 1985678912 19.510 100 19 35 2 1999939104 9172432 5087760
21:32:18 22417 8192000000 10937619472 11399475200 1988062096 19.528 99 44 88 2 2007923360 6620976 13240288
21:33:58 77562 8192000000 10896978880 11361140736 1896630560 18.799 95 23 42 2 1910460448 8333296 5496592
21:36:24 87237 8192000000 10885056272 11353157632 1859256528 18.497 95 15 30 0 1873988528 10750016 3981984
21:39:00 100761 8192000000 10869366352 11327959040 1910198720 18.908 98 28 56 2 1925724448 7908080 7617648
21:41:48 49310 8192000000 10821681824 11280498688 1852464496 18.442 95 36 70 1 1869845568 8141520 9239552
21:43:37 74631 8192000000 10842703632 11319001088 1864161088 18.537 96 14 28 1 1880792032 12386336 4244608
21:45:58 67798 8192000000 10835485680 11317813248 1874076832 18.617 97 12 24 0 1893057840 15638224 3342784
21:48:07 76541 8192000000 10881627376 11342426112 1905495296 18.870 98 29 56 2 1926900816 12598928 8806592
21:50:34 79372 8192000000 10883329632 11364548608 1941985088 19.163 100 10 20 0 1960567488 15903440 2678960
21:52:59 59915 8192000000 10884404400 11364515840 1927119984 19.044 98 31 61 1 1950305296 14803760 8381552
21:54:59 37350 8192000000 10892032432 11343708160 1918504912 18.975 98 46 92 3 1944738864 13535856 12698096
21:56:37 75778 8192000000 10892566912 11380379648 1938773584 19.137 99 14 28 1 1962389344 19806208 3809552
21:58:53 141634 8192000000 10895660000 11365642240 1949126240 19.220 99 23 46 0 1968059344 12382192 6550912
22:02:15 16138 8192000000 10904109808 11372044288 1970689296 19.391 100 34 68 3 1991223632 11199136 9335200
22:03:31 39202 8192000000 10912450832 11380604928 1949620384 19.223 98 38 76 0 1973459872 13364256 10475232
22:05:11 55966 8192000000 10905789072 11392737280 1986041456 19.513 100 13 26 0 2005305664 15396432 3867776
22:07:12 82873 8192000000 10909648016 11390992384 1988206272 19.530 100 11 22 0 2003488384 12218096 3064016
22:09:37 56164 8192000000 10916935920 11408654336 1979164688 19.458 99 5 10 0 1993006272 12632592 1208992
22:11:33 44946 8192000000 10933080432 11408486400 1948870688 19.217 97 15 30 0 1971183040 18502944 3809408
22:13:19 34507 8192000000 10926100720 11404050432 2000141824 19.624 100 19 38 2 2019630784 13423072 6065888
22:14:54 38201 8192000000 10911090112 11373600768 1905186688 18.868 95 28 54 1 1921366768 8626448 7553632
22:16:35 20399 8192000000 10927757248 11383955456 1969271312 19.380 99 32 64 1 1987471872 9380880 8819680
22:18:00 65090 8192000000 10910363728 11373350912 1955353264 19.269 99 24 46 0 1973074880 11662656 6058960
22:20:06 45581 8192000000 10887087456 11345297408 1889941520 18.745 96 21 42 0 1905489632 9800960 5747152
22:21:56 81374 8192000000 10888219536 11373137920 1908778912 18.897 97 19 38 0 1929101136 14263248 6058976
22:24:19 112029 8192000000 10880691056 11362377728 1943787120 19.177 100 23 46 0 1970733984 19810400 7136464
22:27:28 31132 8192000000 10870587056 11360464896 1928742272 19.057 99 14 28 0 1952210816 18546608 4921936
22:28:59 46588 8192000000 10829389840 11313274880 1859068528 18.496 96 6 13 0 1871029792 10440544 1520720
22:30:47 41177 8192000000 10862881376 11339968512 1939804832 19.145 100 8 16 0 1953388000 11074704 2508464
22:32:30 82411 8192000000 10836829600 11318943744 1889124832 18.739 98 16 32 0 1906794288 12676736 4992720
22:34:55 45691 8192000000 10837037888 11305009152 1848889056 18.413 95 16 32 0 1860339856 7120976 4329824
22:36:48 37015 8192000000 10840401248 11310280704 1881209904 18.675 97 29 58 0 1898404832 8951312 8243616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 66 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 15-09-2021 22:39:35 to 15-09-2021 22:39: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          245662
MON$OLDEST_ACTIVE               247750
MON$OLDEST_SNAPSHOT             247750
MON$NEXT_TRANSACTION            247750
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                       2040992
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33496 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33496 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33496 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33496 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Wed Sep 15 22:37:48 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Wed Sep 15 22:37:54 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
--- end of diff output ---

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

15.09.21 22:39: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-09-15 22:39:40 No severe PSQL-related problems occured
15.09.21 22:39:42. End of report.