Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.10.2021 01: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 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 15744344 kB
MemAvailable 30688196 kB
Buffers 0 kB
Cached 15966392 kB
SwapCached 3776 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-10-15 01:37:53
DTS_END                         2021-10-15 01:37:53.9600
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/45508
TRN_ID                          243334
ATT_ID                          646
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: 9376.64 22896 1125197
customer order: creation 175.45 344 21054
customer order: refuse 14.93 341 1791
order to supplier: creation 45.78 1325 5494
order to supplier: removal 3.44 109569 413
invoice (draft): creation 46.23 3645 5548
invoice (draft): removal 52.86 862 6343
invoice accept: apply 42.36 18471 5083
invoice accept: cancel 47.69 793 5723
customer reserve: creation 3964.37 180 475724
customer reserve: removal 4691.18 62 562941
realization accept: apply 84.83 188 10179
realization accept: cancel 76.66 102 9199
payment from customer: creation 59.56 94 7147
payment from customer: removal 5.82 69 698
payment to supplier: creation 49.82 116 5978
payment to supplier: removal 3.59 86 431
service: total inventory turnovers 4.82 1175 578
service: total monetary turnovers 6.46 34 775
service: aggregate perf. data 0.78 26733 94
service: refresh index statistics 0.03 293724 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-10-2021 01:39:23 to 15-10-2021 01:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1170
TEST_TIME 2 1930
TEST_TIME 3 2942
TEST_TIME 4 3799
TEST_TIME 5 3781
TEST_TIME 6 3957
TEST_TIME 7 4308
TEST_TIME 8 4976
TEST_TIME 9 5258
TEST_TIME 10 5842
TEST_TIME 11 6292
TEST_TIME 12 6369
TEST_TIME 13 6708
TEST_TIME 14 7021
TEST_TIME 15 6990
TEST_TIME 16 7134
TEST_TIME 17 7478
TEST_TIME 18 7887
TEST_TIME 19 7903
TEST_TIME 20 8037
TEST_TIME 21 8341
TEST_TIME 22 8557
TEST_TIME 23 8430
TEST_TIME 24 8758
TEST_TIME 25 8993
TEST_TIME 26 9019
TEST_TIME 27 9017
TEST_TIME 28 9834
TEST_TIME 29 10644
TEST_TIME 30 10842
TEST_TIME 31 10691
TEST_TIME 32 10730
TEST_TIME 33 10700
TEST_TIME 34 10597
TEST_TIME 35 10484
TEST_TIME 36 10469
TEST_TIME 37 10403
TEST_TIME 38 10338
TEST_TIME 39 10266
TEST_TIME 40 10233
TEST_TIME 41 10096
TEST_TIME 42 10215
TEST_TIME 43 10171
TEST_TIME 44 10049
TEST_TIME 45 10085
TEST_TIME 46 9971
TEST_TIME 47 10145
TEST_TIME 48 10121
TEST_TIME 49 10167
TEST_TIME 50 10040
TEST_TIME 51 9869
TEST_TIME 52 9855
TEST_TIME 53 9790
TEST_TIME 54 9715
TEST_TIME 55 9669
TEST_TIME 56 9642
TEST_TIME 57 9641
TEST_TIME 58 9580
TEST_TIME 59 9588
TEST_TIME 60 9595
TEST_TIME 61 9583
TEST_TIME 62 9499
TEST_TIME 63 9418
TEST_TIME 64 9463
TEST_TIME 65 9420
TEST_TIME 66 9413
TEST_TIME 67 9472
TEST_TIME 68 9438
TEST_TIME 69 9506
TEST_TIME 70 9497
TEST_TIME 71 9508
TEST_TIME 72 9467
TEST_TIME 73 9493
TEST_TIME 74 9602
TEST_TIME 75 9548
TEST_TIME 76 9553
TEST_TIME 77 9583
TEST_TIME 78 9631
TEST_TIME 79 9625
TEST_TIME 80 9588
TEST_TIME 81 9542
TEST_TIME 82 9520
TEST_TIME 83 9500
TEST_TIME 84 9469
TEST_TIME 85 9427
TEST_TIME 86 9395
TEST_TIME 87 9383
TEST_TIME 88 9344
TEST_TIME 89 9365
TEST_TIME 90 9382
TEST_TIME 91 9354
TEST_TIME 92 9352
TEST_TIME 93 9441
TEST_TIME 94 9425
TEST_TIME 95 9437
TEST_TIME 96 9479
TEST_TIME 97 9491
TEST_TIME 98 9486
TEST_TIME 99 9540
TEST_TIME 100 9517
TEST_TIME 101 9543
TEST_TIME 102 9553
TEST_TIME 103 9643
TEST_TIME 104 9653
TEST_TIME 105 9582
TEST_TIME 106 9549
TEST_TIME 107 9509
TEST_TIME 108 9503
TEST_TIME 109 9492
TEST_TIME 110 9448
TEST_TIME 111 9439
TEST_TIME 112 9473
TEST_TIME 113 9442
TEST_TIME 114 9428
TEST_TIME 115 9378
TEST_TIME 116 9400
TEST_TIME 117 9423
TEST_TIME 118 9397
TEST_TIME 119 9384
TEST_TIME 120 9381

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-10-2021 01:39:26 to 15-10-2021 01:39:30.

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 1565754 1565754 0 0.00 0 28061 6 0 0
doc_list_biud 1565754 1565754 0 0.00 0 69826 28 0 0
sp_add_invoice_to_stock 5219 5083 136 2.60 28 182101 18472 0 136
sp_cancel_adding_invoice 5723 5723 0 0.00 5 50729 793 0 0
sp_cancel_client_order 1791 1791 0 0.00 4 12423 341 0 0
sp_cancel_customer_reserve 562941 562941 0 0.00 1 69827 62 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 2657 69 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 2 5037 86 0 0
sp_cancel_supplier_invoice 6348 6343 5 0.07 2 62085 863 0 5
sp_cancel_supplier_order 413 413 0 0.00 7 1043282 109570 0 0
sp_cancel_write_off 9199 9199 0 0.00 1 8272 102 0 0
sp_client_order 21054 21054 0 0.00 3 18019 344 0 0
sp_customer_reserve 475724 475724 0 0.00 0 102941 181 0 0
sp_fill_shopping_cart 32085 32085 0 0.00 1 12681 43 0 0
sp_fill_shopping_cart_clo_res 475717 475717 0 0.00 0 42840 13 0 0
sp_get_clo_for_invoice 5076 5076 0 0.00 0 43730 344 0 0
sp_kill_cost_storno 16051 16051 0 0.00 0 8976 55 0 0
sp_kill_qstorno_ret_qs2qd 1141837 1141837 0 0.00 0 69824 34 0 0
sp_kill_qty_storno 601665 601665 0 0.00 0 69826 73 0 0
sp_lock_selected_doc 21089 21089 0 0.00 0 9020 4 0 0
sp_make_cost_storno 28380 28380 0 0.00 0 14065 50 0 0
sp_multiply_rows_for_pdistr 28380 28380 0 0.00 0 7317 28 0 0
sp_multiply_rows_for_qdistr 21054 21054 0 0.00 0 14618 139 0 0
sp_pay_from_customer 7147 7147 0 0.00 4 8320 94 0 0
sp_pay_to_supplier 5978 5978 0 0.00 5 9934 116 0 0
sp_payment_common 13125 13125 0 0.00 1 7031 24 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 0 12408 320 0 0
sp_qd_handle_on_invoice_upd_sts 10799 10799 0 0.00 0 11729 350 0 0
sp_qd_handle_on_reserve_upd_sts 10179 10179 0 0.00 0 21163 68 0 0
sp_reserve_write_off 10179 10179 0 0.00 6 21177 189 0 0
sp_supplier_invoice 5548 5548 0 0.00 6 92016 3646 0 0
sp_supplier_order 5494 5494 0 0.00 5 73820 1326 0 0
srv_aggregate_perf_data 94 94 0 0.00 2887 555847 26734 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1886 124433 46029 0 0
srv_find_qd_qs_mism 1076588 1076588 0 0.00 0 28060 6 0 0
srv_make_invnt_saldo 578 578 0 0.00 22 51741 1175 0 0
srv_make_money_saldo 775 775 0 0.00 1 5813 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 199704 398957 293725 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5083 5083 0 0.00 0 10911 157 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 11494 135 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 7007 22 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 2028 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 3059 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 419 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 1381 17 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 32098 1804 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 1798 7 0 0
t$perf-norm:sp_client_order 21054 21054 0 0.00 0 8490 19 0 0
t$perf-norm:sp_customer_reserve 1050 1050 0 0.00 0 2973 10 0 0
t$perf-norm:sp_pay_from_customer 7147 7147 0 0.00 0 11808 11 0 0
t$perf-norm:sp_pay_to_supplier 5978 5978 0 0.00 0 7606 14 0 0
t$perf-norm:sp_reserve_write_off 10179 10179 0 0.00 0 10984 16 0 0
t$perf-norm:sp_supplier_invoice 5548 5548 0 0.00 0 7627 20 0 0
t$perf-norm:sp_supplier_order 5494 5494 0 0.00 0 7366 21 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 125 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 67 2 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 998 23 0 0
t$perf-norm:srv_make_money_saldo 775 775 0 0.00 0 2262 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 3 0 0
v_add_invoice_to_stock 10424 10152 272 2.60 0 8381 21 0 272
v_all_customers 37854 37854 0 0.00 0 21 1 0 0
v_all_suppliers 30744 30744 0 0.00 0 39 1 0 0
v_all_wares 42108 42108 0 0.00 0 179 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 7597 16 0 0
v_cancel_client_order 3582 3582 0 0.00 0 1630 5 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 2631 6 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 1669 7 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 1988 7 0 0
v_cancel_supplier_invoice 754 744 10 1.32 0 3467 21 0 10
v_cancel_supplier_order 826 826 0 0.00 0 502 3 0 0
v_cancel_write_off 1976 1976 0 0.00 0 1860 15 0 0
v_max_id_clo_ord 5494 5494 0 0.00 0 4667 2 0 0
v_max_id_clo_res 1050 1050 0 0.00 0 3536 15 0 0
v_max_id_ord_sup 5537 5537 0 0.00 0 4956 7 0 0
v_max_non_paid_invoice 1637 1637 0 0.00 0 3842 7 0 0
v_max_non_paid_realizn 3974 3974 0 0.00 0 4114 7 0 0
v_min_id_clo_ord 5494 5494 0 0.00 0 663 4 0 0
v_min_id_clo_res 1050 1050 0 0.00 0 4219 17 0 0
v_min_id_ord_sup 5537 5537 0 0.00 0 3303 7 0 0
v_min_non_paid_invoice 5978 5978 0 0.00 0 4755 15 0 0
v_min_non_paid_realizn 7147 7147 0 0.00 0 7027 18 0 0
v_our_firm 10600 10600 0 0.00 0 19 1 0 0
v_reserve_write_off 20358 20358 0 0.00 0 5982 6 0 0
x_lock_depdocs_on_canc_invoice 11694 11694 0 0.00 0 29619 137 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 8204 350 0 0
x_make_qstorno_1000_1200 5494 5494 0 0.00 2 73810 1240 0 0
x_make_qstorno_1200_2000 5548 5548 0 0.00 2 86140 3479 0 0
x_make_qstorno_2100_3300 475724 475724 0 0.00 0 102939 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-10-2021 01:39:31 to 15-10-2021 01:39:35.

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:07:57 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:08:58 41228 8192000000 9898578704 10211020800 979573520 10.680 100 82 164 0 1006959984 5857568 21528896
23:10:42 29016 8192000000 9952425440 10274291712 1033332096 11.201 100 94 188 0 1066049744 8633520 24084128
23:12:20 14663 8192000000 9993902304 10333413376 1076772768 11.617 100 79 157 0 1106101728 10360752 18968208
23:13:38 10439 8192000000 10044452720 10393460736 1128360272 12.106 100 78 156 1 1158128288 11579248 18188768
23:14:54 6965 8192000000 10097910224 10460942336 1187875424 12.664 100 82 162 1 1219760096 12863968 19020704
23:16:05 4666 8192000000 10136043296 10504200192 1222772032 12.987 100 85 167 2 1255936912 14866144 18298736
23:17:12 3745 8192000000 10148767904 10532061184 1247279248 13.213 100 68 136 0 1275441408 13018560 15143600
23:18:17 2066 8192000000 10206226560 10591232000 1292330640 13.625 100 93 186 2 1326111264 12490112 21290512
23:19:21 4536 8192000000 10192892080 10598301696 1300333200 13.698 100 41 83 3 1315852048 5892752 9626096
23:20:26 27003 8192000000 10222340400 10623700992 1310554928 13.791 100 67 133 3 1332081328 5598864 15927536
23:21:53 4767 8192000000 10212468832 10629709824 1320438960 13.881 100 31 61 1 1333451760 5572688 7440112
23:23:02 8641 8192000000 10220941168 10641162240 1324070912 13.914 100 20 40 0 1334596352 5724880 4800560
23:24:11 67778 8192000000 10219206800 10645962752 1329381056 13.962 100 17 33 2 1338127296 5329424 3416816
23:26:21 11440 8192000000 10225362000 10648379392 1334543808 14.008 100 29 58 1 1347565280 6415072 6606400
23:27:37 6854 8192000000 10202629040 10620563456 1303722048 13.729 99 26 52 1 1315837616 5734640 6380928
23:28:44 39628 8192000000 10162958576 10575618048 1223359424 12.993 94 32 64 1 1237004032 5920032 7724576
23:30:24 6345 8192000000 10146602528 10543505408 1231781680 13.070 98 38 76 0 1247777392 6964064 9031648
23:31:32 10828 8192000000 10134128432 10530533376 1229200800 13.047 98 27 54 1 1242585088 6724032 6660256
23:32:45 55628 8192000000 10149305424 10551681024 1249460256 13.233 99 23 45 1 1260020784 5147200 5413328
23:34:42 6583 8192000000 10183053568 10589331456 1286124144 13.569 100 36 72 2 1300582096 5817072 8640880
23:35:48 10785 8192000000 10185326192 10599374848 1297353664 13.671 100 22 44 2 1307706464 5305104 5047696
23:37:04 17799 8192000000 10208007648 10622337024 1314015360 13.822 100 21 42 1 1324421024 5793712 4611952
23:38:24 65829 8192000000 10231716032 10647990272 1330287360 13.970 100 33 66 1 1343621792 5965536 7368896
23:40:33 5678 8192000000 10268184512 10678636544 1354544752 14.188 100 56 112 2 1383765440 16425280 12795408
23:41:42 18814 8192000000 10285286832 10700406784 1370898912 14.335 100 52 104 1 1398635360 14832064 12904384
23:43:05 19286 8192000000 10308281568 10726031360 1388580688 14.493 100 51 102 0 1420221152 19454432 12186032
23:44:30 9393 8192000000 10313964080 10733912064 1394228768 14.544 100 52 104 2 1427948304 21042976 12676560
23:45:44 6995 8192000000 10321386768 10740051968 1393927440 14.541 100 62 123 0 1433561952 24751344 14883168
23:46:55 3682 8192000000 10329269968 10753441792 1405869552 14.647 100 55 106 0 1441256816 22554096 12833168
23:48:04 9679 8192000000 10326000864 10744164352 1386833952 14.478 99 71 141 1 1426629456 23471264 16324240
23:49:24 12738 8192000000 10315726368 10728513536 1381872928 14.433 100 77 154 0 1427216864 26675104 18668832
23:50:48 3187 8192000000 10329721664 10753585152 1390370128 14.509 99 53 106 2 1437192448 33995840 12826480
23:51:57 7159 8192000000 10343997616 10755997696 1396677248 14.565 100 75 147 2 1450668432 36035664 17955520
23:53:10 1886 8192000000 10331023632 10731409408 1364711648 14.280 100 77 154 2 1429686816 45124080 19851088
23:54:18 8758 8192000000 10329247088 10744819712 1373808192 14.361 100 64 128 1 1440920304 50565920 16546192
23:55:46 2620 8192000000 10341930112 10752606208 1381391920 14.429 100 84 168 0 1450005184 47047392 21565872
23:56:52 10026 8192000000 10335515168 10749448192 1375329232 14.375 100 58 113 2 1437779872 47478080 14972560
23:58:10 9381 8192000000 10341791120 10757799936 1385104240 14.462 100 49 98 2 1445751840 46354192 14293408
23:59:26 13435 8192000000 10329488400 10753413120 1373572800 14.359 99 47 93 2 1428331264 42344640 12413824
00:00:43 12219 8192000000 10348349312 10770325504 1392375472 14.527 100 45 91 0 1453340896 48173680 12791744
00:02:03 18914 8192000000 10343568848 10777669632 1379293136 14.410 99 34 68 1 1439646432 50839056 9514240
00:03:34 16697 8192000000 10343363728 10773229568 1381426240 14.429 100 30 60 0 1445240512 55092176 8722096
00:04:59 19058 8192000000 10324346128 10768932864 1367588496 14.305 100 30 61 2 1440997952 64227920 9181536
00:06:21 33936 8192000000 10314376720 10774933504 1369959680 14.327 100 12 24 0 1423617904 50141200 3517024
00:07:55 70325 8192000000 10301363952 10736738304 1369553872 14.323 99 44 89 2 1404851840 24655248 10642720
00:10:07 39723 8192000000 10280887280 10731819008 1365367312 14.286 99 13 26 0 1377354096 9071728 2915056
00:11:48 26933 8192000000 10288445248 10732154880 1381130224 14.427 100 25 48 1 1393530720 7138160 5262336
00:13:17 92020 8192000000 10306883920 10758893568 1395034832 14.551 100 12 24 0 1410579392 13132928 2411632
00:15:53 116955 8192000000 10297222608 10755821568 1369354032 14.321 98 16 32 0 1386089056 12961264 3773760
00:18:56 114561 8192000000 10309660224 10772074496 1387548160 14.484 99 8 16 0 1402934656 13557408 1829088
00:21:51 100465 8192000000 10312348784 10761105408 1389168944 14.498 99 13 26 0 1402362288 10514128 2679216
00:24:40 97222 8192000000 10318548160 10782015488 1361618960 14.252 97 3 6 0 1378191472 15985488 587024
00:27:25 124433 8192000000 10306927584 10767155200 1396259696 14.562 100 19 38 0 1414021008 12629248 5132064
00:30:42 25065 8192000000 10319890448 10766663680 1407237888 14.659 100 50 96 1 1428428912 9602000 11589024
00:32:08 52540 8192000000 10301989920 10755629056 1385133760 14.462 99 28 55 0 1404041728 12249136 6658832
00:34:04 41600 8192000000 10312984144 10757533696 1383474080 14.448 99 27 54 0 1398016912 8141184 6401648
00:35:46 25801 8192000000 10299653568 10745450496 1371641936 14.342 99 45 90 0 1398033664 16296016 10095712
00:37:15 88301 8192000000 10262228816 10714644480 1348131936 14.131 99 19 38 1 1364724336 12625488 3966912
00:39:43 82495 8192000000 10259014384 10716672000 1358657456 14.225 100 17 34 1 1375241632 12697760 3886416
00:42:12 5772 8192000000 10243199920 10693881856 1310673392 13.792 97 40 80 1 1328370800 8400896 9296512
00:43:30 60517 8192000000 10252037408 10703380480 1315179280 13.833 97 8 16 1 1327198528 10169680 1849568
00:45:32 26209 8192000000 10270849312 10709819392 1348676128 14.136 99 29 58 1 1364977200 9811456 6489616
00:47:01 34781 8192000000 10238184544 10678808576 1286137280 13.569 96 29 58 0 1303399312 10552224 6709808
00:48:36 26221 8192000000 10263597296 10703556608 1346203392 14.113 100 33 66 0 1364839664 11636880 6999392
00:50:05 25946 8192000000 10288972640 10725801984 1353080288 14.175 99 45 89 1 1379890144 16864304 9945552
00:51:34 60555 8192000000 10258729904 10704470016 1306398144 13.753 96 9 18 1 1322877008 14622272 1856592
00:53:35 50328 8192000000 10270740320 10714836992 1303025616 13.723 96 28 56 2 1329662928 20741056 5896256
00:55:26 70354 8192000000 10280930016 10734911488 1356657152 14.207 99 18 34 0 1371853744 11304896 3891696
00:57:38 110134 8192000000 10285106096 10731454464 1358082272 14.220 99 15 30 2 1375802304 13569376 4150656
01:01:09 26243 8192000000 10292537056 10749075456 1384574352 14.457 100 30 60 2 1402315680 10713200 7028128
01:02:50 91379 8192000000 10287358784 10748506112 1355075200 14.193 98 17 34 0 1372492624 13190864 4226560
01:05:21 100831 8192000000 10290445712 10760970240 1385199552 14.463 100 11 22 1 1405127456 17370896 2557008
01:08:06 38635 8192000000 10281759600 10733662208 1349222160 14.140 97 12 24 0 1357446192 5806080 2417952
01:09:44 71135 8192000000 10301820000 10763632640 1363926624 14.273 98 21 42 1 1380823264 12612416 4284224
01:11:56 23070 8192000000 10317142112 10768039936 1404767152 14.637 100 39 76 0 1423838320 10378784 8692384
01:13:20 81923 8192000000 10320206672 10793324544 1406747408 14.655 100 16 30 0 1426944352 16106384 4090560
01:15:44 80513 8192000000 10312684832 10767749120 1368885152 14.317 97 32 64 1 1387303056 11242688 7175216
01:18:08 51371 8192000000 10322714304 10775605248 1402493664 14.617 100 28 56 1 1420755248 11933280 6328304
01:20:01 96216 8192000000 10302718816 10757677056 1332269120 13.988 95 14 28 0 1353467728 18074320 3124288
01:23:07 66742 8192000000 10297440544 10749566976 1366272352 14.294 98 18 36 1 1376184000 6110096 3801552
01:25:15 22403 8192000000 10291764464 10740224000 1371336640 14.339 99 34 67 1 1387374336 8694288 7343408
01:26:37 67696 8192000000 10258388448 10705428480 1289832384 13.603 94 20 40 1 1306315600 12314080 4169136
01:28:46 43551 8192000000 10255769424 10717110272 1325099616 13.923 97 13 26 0 1340423584 12293632 3030336
01:30:34 73352 8192000000 10254485248 10713645056 1326275104 13.933 97 9 18 1 1343346224 14797008 2274112
01:32:48 88475 8192000000 10243416512 10705526784 1312860576 13.812 97 16 32 0 1332287792 15689504 3737712
01:35:18 38521 8192000000 10279200640 10722930688 1333557616 13.999 98 18 36 1 1347988688 10445168 3985904
01:36:57 51099 8192000000 10260318656 10703056896 1326722384 13.938 98 25 50 3 1349552944 16301600 6528960

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 15-10-2021 01:39:37 to 15-10-2021 01:39:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-10-2021 01:39:52 to 15-10-2021 01:39:54.

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          239360
MON$OLDEST_ACTIVE               243516
MON$OLDEST_SNAPSHOT             243516
MON$NEXT_TRANSACTION            243516
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                       2055584
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:345: 
:346: mscs-test-161.budzdorov.ru    Fri Oct 15 01:37:55 2021
:347:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:348: 
:349: 
:350: mscs-test-161.budzdorov.ru    Fri Oct 15 01:37:58 2021
:351:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:352: 
:353: 
:354: mscs-test-161.budzdorov.ru    Fri Oct 15 01:37:59 2021
:355:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:356: 
:357: 
:358: mscs-test-161.budzdorov.ru    Fri Oct 15 01:38:01 2021
:359:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:360: 
:361: 
:362: mscs-test-161.budzdorov.ru    Fri Oct 15 01:38:03 2021
:363:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:364: 
--- end of diff output ---

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

15.10.21 01:39:55. 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-10-15 01:39:58 No severe PSQL-related problems occured
15.10.21 01:39:59. End of report.