Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.05.2022 16: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 16466444 kB
MemAvailable 31345464 kB
Buffers 0 kB
Cached 15225624 kB
SwapCached 2440 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.10
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 2022-05-30 16:38:20
DTS_END                         2022-05-30 16:38:20.7060
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/47216
TRN_ID                          238287
ATT_ID                          629
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: 9470.31 20177 1136437
customer order: creation 174.88 360 20985
customer order: refuse 14.92 367 1790
order to supplier: creation 45.81 1277 5497
order to supplier: removal 3.71 94984 445
invoice (draft): creation 46.83 3254 5619
invoice (draft): removal 53.95 800 6474
invoice accept: apply 41.93 18755 5032
invoice accept: cancel 48.53 774 5823
customer reserve: creation 3952.42 182 474290
customer reserve: removal 4789.83 57 574779
realization accept: apply 85.20 177 10224
realization accept: cancel 79.82 100 9578
payment from customer: creation 60.94 106 7313
payment from customer: removal 5.37 94 644
payment to supplier: creation 50.31 124 6037
payment to supplier: removal 3.46 114 415
service: total inventory turnovers 4.95 981 594
service: total monetary turnovers 6.59 25 791
service: aggregate perf. data 0.86 19232 103
service: refresh index statistics 0.03 261775 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-05-2022 16:39:00 to 30-05-2022 16:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5275
TEST_TIME 2 4152
TEST_TIME 3 4968
TEST_TIME 4 5992
TEST_TIME 5 5764
TEST_TIME 6 6289
TEST_TIME 7 6628
TEST_TIME 8 6949
TEST_TIME 9 7087
TEST_TIME 10 6956
TEST_TIME 11 7319
TEST_TIME 12 7347
TEST_TIME 13 7215
TEST_TIME 14 7219
TEST_TIME 15 7416
TEST_TIME 16 7848
TEST_TIME 17 7886
TEST_TIME 18 8023
TEST_TIME 19 8169
TEST_TIME 20 8319
TEST_TIME 21 8587
TEST_TIME 22 8944
TEST_TIME 23 9230
TEST_TIME 24 9657
TEST_TIME 25 9803
TEST_TIME 26 10160
TEST_TIME 27 10744
TEST_TIME 28 10956
TEST_TIME 29 11140
TEST_TIME 30 10974
TEST_TIME 31 10791
TEST_TIME 32 10632
TEST_TIME 33 11099
TEST_TIME 34 10978
TEST_TIME 35 10988
TEST_TIME 36 10726
TEST_TIME 37 10689
TEST_TIME 38 10478
TEST_TIME 39 10380
TEST_TIME 40 10329
TEST_TIME 41 10403
TEST_TIME 42 10343
TEST_TIME 43 10229
TEST_TIME 44 10153
TEST_TIME 45 10065
TEST_TIME 46 10097
TEST_TIME 47 10132
TEST_TIME 48 9996
TEST_TIME 49 9867
TEST_TIME 50 9790
TEST_TIME 51 9869
TEST_TIME 52 9781
TEST_TIME 53 9693
TEST_TIME 54 9634
TEST_TIME 55 9643
TEST_TIME 56 9551
TEST_TIME 57 9466
TEST_TIME 58 9575
TEST_TIME 59 9540
TEST_TIME 60 9617
TEST_TIME 61 9566
TEST_TIME 62 9633
TEST_TIME 63 9651
TEST_TIME 64 9667
TEST_TIME 65 9707
TEST_TIME 66 9659
TEST_TIME 67 9769
TEST_TIME 68 9727
TEST_TIME 69 9770
TEST_TIME 70 9738
TEST_TIME 71 9721
TEST_TIME 72 9756
TEST_TIME 73 9773
TEST_TIME 74 9675
TEST_TIME 75 9661
TEST_TIME 76 9625
TEST_TIME 77 9651
TEST_TIME 78 9609
TEST_TIME 79 9551
TEST_TIME 80 9546
TEST_TIME 81 9562
TEST_TIME 82 9550
TEST_TIME 83 9505
TEST_TIME 84 9502
TEST_TIME 85 9521
TEST_TIME 86 9471
TEST_TIME 87 9521
TEST_TIME 88 9557
TEST_TIME 89 9562
TEST_TIME 90 9605
TEST_TIME 91 9557
TEST_TIME 92 9575
TEST_TIME 93 9715
TEST_TIME 94 9679
TEST_TIME 95 9712
TEST_TIME 96 9678
TEST_TIME 97 9670
TEST_TIME 98 9727
TEST_TIME 99 9683
TEST_TIME 100 9629
TEST_TIME 101 9600
TEST_TIME 102 9538
TEST_TIME 103 9562
TEST_TIME 104 9515
TEST_TIME 105 9521
TEST_TIME 106 9495
TEST_TIME 107 9475
TEST_TIME 108 9416
TEST_TIME 109 9431
TEST_TIME 110 9407
TEST_TIME 111 9420
TEST_TIME 112 9423
TEST_TIME 113 9443
TEST_TIME 114 9465
TEST_TIME 115 9461
TEST_TIME 116 9464
TEST_TIME 117 9445
TEST_TIME 118 9484
TEST_TIME 119 9529
TEST_TIME 120 9477

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-05-2022 16:39:04 to 30-05-2022 16:39:07.

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 1574955 1574955 0 0.00 0 16781 6 0 0
doc_list_biud 1574955 1574955 0 0.00 0 37402 27 0 0
sp_add_invoice_to_stock 5185 5032 153 2.95 23 167515 18755 0 153
sp_cancel_adding_invoice 5823 5823 0 0.00 5 62319 774 0 0
sp_cancel_client_order 1790 1790 0 0.00 4 9303 367 0 0
sp_cancel_customer_reserve 574779 574779 0 0.00 1 37403 58 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 3 6852 95 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 12910 115 0 0
sp_cancel_supplier_invoice 6483 6474 9 0.13 2 13335 800 0 9
sp_cancel_supplier_order 445 445 0 0.00 5 1021554 94984 0 0
sp_cancel_write_off 9578 9578 0 0.00 1 13598 101 0 0
sp_client_order 20985 20985 0 0.00 3 43948 360 0 0
sp_customer_reserve 474290 474290 0 0.00 0 63096 182 0 0
sp_fill_shopping_cart 32092 32092 0 0.00 1 10311 36 0 0
sp_fill_shopping_cart_clo_res 474288 474288 0 0.00 0 12773 13 0 0
sp_get_clo_for_invoice 5019 5019 0 0.00 0 23063 278 0 0
sp_kill_cost_storno 16460 16460 0 0.00 0 11050 55 0 0
sp_kill_qstorno_ret_qs2qd 1166055 1166055 0 0.00 0 37398 31 0 0
sp_kill_qty_storno 614130 614130 0 0.00 0 37400 68 0 0
sp_lock_selected_doc 21118 21118 0 0.00 0 9319 5 0 0
sp_make_cost_storno 28591 28591 0 0.00 0 15003 54 0 0
sp_multiply_rows_for_pdistr 28591 28591 0 0.00 0 7416 28 0 0
sp_multiply_rows_for_qdistr 20985 20985 0 0.00 0 43914 166 0 0
sp_pay_from_customer 7313 7313 0 0.00 4 11822 106 0 0
sp_pay_to_supplier 6037 6037 0 0.00 5 16002 124 0 0
sp_payment_common 13350 13350 0 0.00 1 11809 29 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 9293 351 0 0
sp_qd_handle_on_invoice_upd_sts 10840 10840 0 0.00 0 14597 338 0 0
sp_qd_handle_on_reserve_upd_sts 10224 10224 0 0.00 0 16355 62 0 0
sp_reserve_write_off 10224 10224 0 0.00 6 16467 177 0 0
sp_supplier_invoice 5619 5619 0 0.00 6 94612 3254 0 0
sp_supplier_order 5497 5497 0 0.00 5 48653 1277 0 0
srv_aggregate_perf_data 103 103 0 0.00 2990 234413 19232 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1868 151673 43080 0 0
srv_find_qd_qs_mism 1087343 1087343 0 0.00 0 16778 5 0 0
srv_make_invnt_saldo 594 594 0 0.00 41 53346 981 0 0
srv_make_money_saldo 791 791 0 0.00 1 1322 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 177097 466671 261775 0 0
t$perf-abend:sp_add_invoice_to_stock 153 153 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5033 5033 0 0.00 0 19060 199 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 4137 105 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 3628 16 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 1200 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 348 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 3552 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 12924 58 0 0
t$perf-norm:sp_cancel_supplier_order 445 445 0 0.00 0 50656 1722 0 0
t$perf-norm:sp_cancel_write_off 1052 1052 0 0.00 0 1911 7 0 0
t$perf-norm:sp_client_order 20985 20985 0 0.00 0 16460 19 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 3187 8 0 0
t$perf-norm:sp_pay_from_customer 7313 7313 0 0.00 0 23715 17 0 0
t$perf-norm:sp_pay_to_supplier 6037 6037 0 0.00 0 14963 11 0 0
t$perf-norm:sp_reserve_write_off 10224 10224 0 0.00 0 15302 17 0 0
t$perf-norm:sp_supplier_invoice 5619 5619 0 0.00 0 18057 21 0 0
t$perf-norm:sp_supplier_order 5497 5497 0 0.00 0 13365 25 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 86 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 11 1 0 0
t$perf-norm:srv_make_invnt_saldo 594 594 0 0.00 0 1239 23 0 0
t$perf-norm:srv_make_money_saldo 791 791 0 0.00 0 1287 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10340 10034 306 2.95 0 5533 16 0 306
v_all_customers 38700 38700 0 0.00 0 53 1 0 0
v_all_suppliers 31148 31148 0 0.00 0 121 1 0 0
v_all_wares 41968 41968 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 901 7 0 0
v_cancel_client_order 3580 3580 0 0.00 0 1230 3 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 6839 15 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 1063 4 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 3586 12 0 0
v_cancel_supplier_invoice 852 834 18 2.11 0 2524 23 0 18
v_cancel_supplier_order 890 890 0 0.00 0 789 6 0 0
v_cancel_write_off 2104 2104 0 0.00 0 3015 16 0 0
v_max_id_clo_ord 5492 5492 0 0.00 0 4296 3 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 1997 11 0 0
v_max_id_ord_sup 5615 5615 0 0.00 0 5277 7 0 0
v_max_non_paid_invoice 1569 1569 0 0.00 0 334 3 0 0
v_max_non_paid_realizn 3829 3829 0 0.00 0 3499 5 0 0
v_min_id_clo_ord 5491 5491 0 0.00 0 3037 5 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 17774 24 0 0
v_min_id_ord_sup 5615 5615 0 0.00 0 5148 7 0 0
v_min_non_paid_invoice 6037 6037 0 0.00 0 7218 20 0 0
v_min_non_paid_realizn 7313 7313 0 0.00 0 6067 18 0 0
v_our_firm 10236 10236 0 0.00 0 13 1 0 0
v_reserve_write_off 20446 20446 0 0.00 0 5718 5 0 0
x_lock_depdocs_on_canc_invoice 11880 11880 0 0.00 0 26784 114 0 0
x_lock_depdocs_on_canc_sup_ord 445 445 0 0.00 0 16638 379 0 0
x_make_qstorno_1000_1200 5497 5497 0 0.00 2 48641 1192 0 0
x_make_qstorno_1200_2000 5619 5619 0 0.00 3 90753 3131 0 0
x_make_qstorno_2100_3300 474290 474290 0 0.00 0 63094 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-05-2022 16:39:08 to 30-05-2022 16:39:12.

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
14:08:24 11 8192000000 8807184992 8821313536 11795568 0.143 3 3 5 0 11989728 77248 116912
14:09:25 27151 8192000000 9897865936 10210193408 984508400 10.728 100 85 170 0 1012935504 6198592 22228512
14:10:58 29867 8192000000 9958053872 10281902080 1039405248 11.259 100 90 180 0 1071011680 8633504 22972928
14:12:30 9713 8192000000 9986522592 10321440768 1075944336 11.609 100 85 170 2 1106883056 9884688 21054032
14:13:49 12535 8192000000 10045715680 10397618176 1134178544 12.161 100 78 156 0 1163663408 11322368 18162496
14:15:04 8949 8192000000 10077288720 10432499712 1162088640 12.423 100 86 172 0 1196116864 14523808 19504416
14:16:16 7545 8192000000 10111285872 10479304704 1201316240 12.789 100 85 166 1 1235189776 15307264 18566272
14:17:25 2902 8192000000 10152985920 10527551488 1241976688 13.164 100 89 178 1 1274883536 13435328 19471520
14:18:30 1893 8192000000 10197836208 10573606912 1277757776 13.493 100 90 180 1 1311885232 13907680 20219776
14:19:34 28287 8192000000 10202455360 10597392384 1301735872 13.711 100 52 105 2 1324194096 10852704 11605520
14:21:03 6126 8192000000 10213760496 10614349824 1310722928 13.793 100 36 71 1 1327027680 8360256 7944496
14:22:11 6823 8192000000 10212427920 10626322432 1323031712 13.904 100 24 48 1 1335058032 6721840 5304480
14:23:20 49743 8192000000 10216872160 10639089664 1329432352 13.962 100 27 53 2 1341631008 5659808 6538848
14:25:13 4666 8192000000 10230425088 10647408640 1334277824 14.006 100 28 56 1 1347721584 7430224 6013536
14:26:19 5600 8192000000 10237268256 10656296960 1342438736 14.079 100 19 37 0 1352517984 5384224 4695024
14:27:26 13136 8192000000 10246088208 10661568512 1349769088 14.145 100 24 47 1 1360886784 5418064 5699632
14:28:40 3223 8192000000 10241933216 10658697216 1341910832 14.075 100 58 116 0 1364950000 9970320 13068848
14:29:48 6164 8192000000 10183202368 10583646208 1251605536 13.253 96 27 54 2 1263090592 5868256 5616800
14:30:57 8276 8192000000 10131861472 10534850560 1230245664 13.056 97 21 42 1 1240739952 5077504 5416784
14:32:06 21252 8192000000 10109499616 10503753728 1163552080 12.437 92 28 56 2 1175280400 5619904 6108416
14:33:52 3758 8192000000 10154755600 10551156736 1254733824 13.282 100 39 78 1 1271245584 7783584 8728176
14:34:58 10575 8192000000 10161930352 10574319616 1262513376 13.353 99 15 30 0 1270701408 5153984 3034048
14:36:09 11101 8192000000 10188017072 10598871040 1294433888 13.645 100 14 27 1 1302516496 5224576 2858032
14:37:33 78822 8192000000 10223929024 10637635584 1312802928 13.811 100 30 58 1 1323871440 4664704 6403808
14:40:02 15873 8192000000 10256331872 10669076480 1348563888 14.135 100 46 90 1 1366911424 8181712 10165824
14:41:26 16722 8192000000 10262119440 10680745984 1357615344 14.216 100 50 100 2 1382434528 12054416 12764768
14:42:46 21349 8192000000 10271843680 10693500928 1367440048 14.304 100 43 85 2 1386822496 9425408 9957040
14:44:22 4066 8192000000 10303541152 10717122560 1380296416 14.419 100 63 124 4 1409473984 14315456 14862112
14:45:29 5236 8192000000 10314806752 10733481984 1392748656 14.530 100 64 130 0 1421784784 14231792 14804336
14:46:43 6356 8192000000 10335216832 10753433600 1403200352 14.623 100 73 142 0 1440232784 20550896 16481536
14:47:57 13412 8192000000 10343009408 10773639168 1419460672 14.768 100 57 112 1 1452490624 19625056 13404896
14:49:13 2556 8192000000 10359023936 10779557888 1424860560 14.816 100 79 155 1 1468544368 25451216 18232592
14:50:21 4998 8192000000 10359189408 10776453120 1416543664 14.742 100 70 140 0 1462303648 28350720 17409264
14:51:30 5340 8192000000 10368586080 10791464960 1427779792 14.842 100 60 120 2 1476758192 33359296 15619104
14:52:38 4653 8192000000 10383162672 10802982912 1431293648 14.873 100 75 150 3 1488286656 38860144 18132864
14:53:57 3333 8192000000 10370216144 10781286400 1398752800 14.584 98 82 162 0 1460562880 41437296 20372784
14:55:06 3590 8192000000 10372930144 10780815360 1414830352 14.727 100 91 179 0 1478605184 41674000 22100832
14:56:16 6549 8192000000 10370578848 10785452032 1409378048 14.678 100 73 146 1 1473302688 45297792 18626848
14:57:29 1866 8192000000 10355792464 10763726848 1388849616 14.496 100 79 158 1 1457263024 48404896 20008512
14:58:36 5849 8192000000 10339579520 10757259264 1368140736 14.310 98 50 100 2 1432810672 50878912 13791024
14:59:45 6137 8192000000 10328940960 10744741888 1360735792 14.244 99 50 99 3 1429975600 55808608 13431200
15:00:54 7194 8192000000 10320316672 10746155008 1369339712 14.321 100 33 66 0 1427895584 48320992 10234880
15:02:08 17472 8192000000 10329201904 10757079040 1372883312 14.353 100 40 80 2 1433276016 48879136 11513568
15:03:28 10819 8192000000 10299664480 10728714240 1337090576 14.031 98 40 80 2 1386443344 37829376 11523392
15:04:43 20314 8192000000 10282880832 10722009088 1343194912 14.086 100 26 52 0 1397716048 47063904 7457232
15:07:05 46223 8192000000 10240432912 10693660672 1307640592 13.765 97 34 68 2 1331250032 14577248 9032192
15:08:52 31121 8192000000 10285058672 10718392320 1364290016 14.276 100 33 59 1 1384910800 13953680 6667104
15:10:32 89285 8192000000 10266242112 10728517632 1322863616 13.903 97 11 22 0 1346146784 20658608 2624560
15:13:02 116923 8192000000 10279157904 10725519360 1341075536 14.067 98 6 12 1 1356949456 14931520 942400
15:16:15 54329 8192000000 10294341280 10735128576 1375907984 14.380 100 41 84 0 1395746896 10487808 9351104
15:18:11 46603 8192000000 10278410592 10724511744 1343718800 14.091 97 21 42 1 1358763008 10175232 4868976
15:20:04 47898 8192000000 10277312784 10737377280 1363882400 14.272 99 17 34 0 1378684944 10642560 4159984
15:22:26 49517 8192000000 10296479056 10752253952 1381676192 14.432 100 23 46 1 1404710480 18028608 5005680
15:24:16 65177 8192000000 10281605824 10743271424 1354293312 14.186 98 11 22 0 1371914480 15045920 2575248
15:26:29 73966 8192000000 10282547216 10741665792 1374919840 14.371 99 15 30 1 1387087056 8556368 3610848
15:28:57 7347 8192000000 10280087952 10741116928 1376736592 14.387 100 19 38 1 1395157664 14077440 4343632
15:30:40 75971 8192000000 10272324336 10738085888 1373736128 14.361 99 14 29 0 1389845760 12762864 3346768
15:33:03 87040 8192000000 10302102624 10771206144 1395243040 14.553 100 22 44 0 1419094928 18680880 5171008
15:35:54 75874 8192000000 10290775072 10772795392 1363579680 14.269 98 6 12 0 1390531152 25504608 1446864
15:38:55 31904 8192000000 10291626736 10744070144 1348839808 14.137 97 26 51 1 1359868704 5569888 5459008
15:40:30 41442 8192000000 10298198896 10756096000 1377066704 14.390 99 14 28 0 1391519504 11105504 3347296
15:42:27 52963 8192000000 10293019664 10752294912 1367142688 14.301 98 26 50 3 1381250480 8765920 5341872
15:44:31 18741 8192000000 10289664112 10730639360 1356784928 14.208 99 45 90 1 1377395456 10128736 10481792
15:45:51 49505 8192000000 10294383408 10735767552 1335400544 14.016 97 14 28 1 1352574864 14311408 2862912
15:47:41 25325 8192000000 10261262768 10703908864 1330446336 13.971 98 22 44 1 1342728256 7610672 4671248
15:49:07 53019 8192000000 10280586208 10726203392 1361951248 14.255 100 49 98 2 1387482128 14518192 11012688
15:51:01 55492 8192000000 10270949120 10706014208 1319126256 13.869 97 21 42 1 1333636224 10190384 4319584
15:52:58 75438 8192000000 10267636096 10719858688 1339510160 14.053 98 15 30 0 1355644992 12145792 3989040
15:55:22 28375 8192000000 10268074384 10718732288 1347419184 14.124 99 24 48 1 1366558256 13928416 5210656
15:56:51 73691 8192000000 10262873136 10710024192 1346271264 14.114 99 19 38 0 1369380960 18500704 4608992
15:59:12 71312 8192000000 10245710000 10696478720 1334063600 14.004 99 21 42 0 1354776352 15684224 5028528
16:01:31 51195 8192000000 10247589072 10702790656 1354497312 14.188 100 27 54 1 1369438640 8496864 6444464
16:03:31 83444 8192000000 10289646448 10739572736 1379364000 14.411 100 20 40 1 1394397136 9857408 5175728
16:05:55 61995 8192000000 10267313856 10715348992 1321744416 13.892 96 14 27 1 1334534288 9396208 3393664
16:08:02 79664 8192000000 10279901712 10725683200 1338883216 14.047 97 18 36 1 1352529632 9632400 4014016
16:10:25 60601 8192000000 10294802240 10759774208 1361510496 14.251 98 12 24 1 1384974176 20591216 2872464
16:12:26 94083 8192000000 10301596304 10759352320 1378429840 14.403 99 19 38 1 1397654496 15335888 3888768
16:15:07 23370 8192000000 10295367136 10742120448 1367842944 14.308 98 35 70 1 1383997456 8707984 7446528
16:16:40 151673 8192000000 10304491568 10759565312 1400458656 14.599 100 29 57 1 1419373792 11420256 7494880
16:20:12 33758 8192000000 10328546608 10778505216 1413853168 14.718 100 41 83 1 1434129904 10734336 9542400
16:21:47 42107 8192000000 10302976736 10769510400 1395205600 14.552 99 10 20 0 1410578016 12734448 2637968
16:23:29 65415 8192000000 10282975792 10740334592 1342965392 14.084 96 16 32 0 1354514832 7699520 3849920
16:25:40 74321 8192000000 10297936832 10750668800 1375285152 14.374 99 26 53 1 1393371600 11544656 6541792
16:28:00 89295 8192000000 10297391312 10767310848 1370620192 14.333 98 16 32 1 1383831568 9408944 3802432
16:30:30 47901 8192000000 10292383824 10750844928 1361895728 14.254 98 20 40 1 1379705424 13522512 4287184
16:32:20 44067 8192000000 10285093968 10732154880 1333615184 14.000 96 18 35 1 1347150128 9547760 3987184
16:34:04 46025 8192000000 10290788576 10741903360 1355107824 14.193 98 22 44 0 1370176240 10389552 4678864
16:35:58 71207 8192000000 10257909072 10703220736 1310800624 13.793 95 24 48 0 1331554336 15213216 5540496
16:38:11 16098 8192000000 8931827760 9108295680 103723648 1.250 8 2 4 0 105822720 1738704 360368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-05-2022 16:39:14 to 30-05-2022 16:39:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 153 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-05-2022 16:39:30 to 30-05-2022 16:39:32.

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          239232
MON$OLDEST_ACTIVE               239233
MON$OLDEST_SNAPSHOT             239233
MON$NEXT_TRANSACTION            239233
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                       2021824
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33597 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33597 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33597 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33597 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 ---
:194: 
:195: mscs-test-161.budzdorov.ru    Mon May 30 16:38:33 2022
:196:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:197: 
:198: 
:199: mscs-test-161.budzdorov.ru    Mon May 30 16:38:33 2022
:200:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:201: 
:202: 
:203: mscs-test-161.budzdorov.ru    Mon May 30 16:38:33 2022
:204:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:205: 
--- end of diff output ---

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

30.05.22 16:39:33. 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
2022-05-30 16:39:35 No severe PSQL-related problems occured
30.05.22 16:39:36. End of report.