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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15225052 kB
MemAvailable 30368924 kB
Buffers 0 kB
Cached 16463672 kB
SwapCached 4096 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.11
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 2023-01-14 22:38:34
DTS_END                         2023-01-14 22:38:34.5100
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/48942
TRN_ID                          250797
ATT_ID                          726
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: 9367.13 22902 1124056
customer order: creation 176.25 358 21150
customer order: refuse 15.75 358 1890
order to supplier: creation 47.68 1294 5721
order to supplier: removal 3.49 92438 419
invoice (draft): creation 48.94 3563 5873
invoice (draft): removal 51.23 770 6148
invoice accept: apply 44.83 16763 5379
invoice accept: cancel 46.48 827 5577
customer reserve: creation 4052.33 168 486280
customer reserve: removal 4577.94 55 549353
realization accept: apply 87.59 191 10511
realization accept: cancel 79.58 108 9550
payment from customer: creation 61.76 116 7411
payment from customer: removal 5.87 122 704
payment to supplier: creation 51.81 137 6217
payment to supplier: removal 3.67 116 440
service: total inventory turnovers 4.50 1175 540
service: total monetary turnovers 6.52 29 782
service: aggregate perf. data 0.89 18912 107
service: refresh index statistics 0.03 320537 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-01-2023 22:39:45 to 14-01-2023 22:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3773
TEST_TIME 2 3591
TEST_TIME 3 4094
TEST_TIME 4 4682
TEST_TIME 5 4508
TEST_TIME 6 4479
TEST_TIME 7 4700
TEST_TIME 8 4559
TEST_TIME 9 4657
TEST_TIME 10 5170
TEST_TIME 11 5612
TEST_TIME 12 6209
TEST_TIME 13 6390
TEST_TIME 14 6741
TEST_TIME 15 6948
TEST_TIME 16 7532
TEST_TIME 17 7427
TEST_TIME 18 7533
TEST_TIME 19 7765
TEST_TIME 20 8026
TEST_TIME 21 8032
TEST_TIME 22 8454
TEST_TIME 23 8647
TEST_TIME 24 9311
TEST_TIME 25 9258
TEST_TIME 26 9580
TEST_TIME 27 10201
TEST_TIME 28 10239
TEST_TIME 29 10061
TEST_TIME 30 10051
TEST_TIME 31 10188
TEST_TIME 32 10150
TEST_TIME 33 10224
TEST_TIME 34 10123
TEST_TIME 35 9982
TEST_TIME 36 9812
TEST_TIME 37 10001
TEST_TIME 38 9838
TEST_TIME 39 9783
TEST_TIME 40 9766
TEST_TIME 41 9756
TEST_TIME 42 9675
TEST_TIME 43 9845
TEST_TIME 44 9846
TEST_TIME 45 9741
TEST_TIME 46 9733
TEST_TIME 47 9675
TEST_TIME 48 9588
TEST_TIME 49 9545
TEST_TIME 50 9530
TEST_TIME 51 9491
TEST_TIME 52 9464
TEST_TIME 53 9501
TEST_TIME 54 9520
TEST_TIME 55 9497
TEST_TIME 56 9475
TEST_TIME 57 9478
TEST_TIME 58 9600
TEST_TIME 59 9696
TEST_TIME 60 9756
TEST_TIME 61 9807
TEST_TIME 62 9807
TEST_TIME 63 9913
TEST_TIME 64 9949
TEST_TIME 65 9891
TEST_TIME 66 9868
TEST_TIME 67 9768
TEST_TIME 68 9707
TEST_TIME 69 9664
TEST_TIME 70 9671
TEST_TIME 71 9592
TEST_TIME 72 9566
TEST_TIME 73 9524
TEST_TIME 74 9442
TEST_TIME 75 9445
TEST_TIME 76 9490
TEST_TIME 77 9467
TEST_TIME 78 9526
TEST_TIME 79 9506
TEST_TIME 80 9503
TEST_TIME 81 9611
TEST_TIME 82 9646
TEST_TIME 83 9653
TEST_TIME 84 9637
TEST_TIME 85 9706
TEST_TIME 86 9721
TEST_TIME 87 9699
TEST_TIME 88 9602
TEST_TIME 89 9614
TEST_TIME 90 9559
TEST_TIME 91 9570
TEST_TIME 92 9518
TEST_TIME 93 9471
TEST_TIME 94 9492
TEST_TIME 95 9481
TEST_TIME 96 9475
TEST_TIME 97 9473
TEST_TIME 98 9515
TEST_TIME 99 9551
TEST_TIME 100 9538
TEST_TIME 101 9566
TEST_TIME 102 9522
TEST_TIME 103 9524
TEST_TIME 104 9645
TEST_TIME 105 9629
TEST_TIME 106 9619
TEST_TIME 107 9602
TEST_TIME 108 9594
TEST_TIME 109 9533
TEST_TIME 110 9490
TEST_TIME 111 9506
TEST_TIME 112 9457
TEST_TIME 113 9436
TEST_TIME 114 9444
TEST_TIME 115 9395
TEST_TIME 116 9391
TEST_TIME 117 9375
TEST_TIME 118 9345
TEST_TIME 119 9420
TEST_TIME 120 9375

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-01-2023 22:39:49 to 14-01-2023 22:39:52.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1573831 1573831 0 0.00 0 16598 6 0 0
doc_list_biud 1573830 1573830 0 0.00 0 45630 25 0 0
sp_add_invoice_to_stock 5467 5379 88 1.60 36 200583 16764 0 88
sp_cancel_adding_invoice 5577 5577 0 0.00 4 63675 827 0 0
sp_cancel_client_order 1890 1890 0 0.00 4 15303 359 0 0
sp_cancel_customer_reserve 549353 549353 0 0.00 0 26364 55 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 2 15585 123 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 10241 117 0 0
sp_cancel_supplier_invoice 6152 6148 4 0.06 2 12638 771 0 4
sp_cancel_supplier_order 419 419 0 0.00 5 1003554 92438 0 0
sp_cancel_write_off 9550 9550 0 0.00 1 9312 109 0 0
sp_client_order 21150 21150 0 0.00 3 20733 358 0 0
sp_customer_reserve 486280 486280 0 0.00 0 51848 169 0 0
sp_fill_shopping_cart 32740 32740 0 0.00 1 14440 41 0 0
sp_fill_shopping_cart_clo_res 486258 486258 0 0.00 0 17622 14 0 0
sp_get_clo_for_invoice 5357 5357 0 0.00 0 24361 286 0 0
sp_kill_cost_storno 16271 16271 0 0.00 0 9310 55 0 0
sp_kill_qstorno_ret_qs2qd 1114823 1114823 0 0.00 0 26312 30 0 0
sp_kill_qty_storno 588804 588804 0 0.00 0 45629 66 0 0
sp_lock_selected_doc 21939 21939 0 0.00 0 10347 7 0 0
sp_make_cost_storno 29495 29495 0 0.00 0 11743 46 0 0
sp_multiply_rows_for_pdistr 29495 29495 0 0.00 0 8043 30 0 0
sp_multiply_rows_for_qdistr 21150 21150 0 0.00 0 13512 167 0 0
sp_pay_from_customer 7411 7411 0 0.00 4 11161 116 0 0
sp_pay_to_supplier 6217 6217 0 0.00 4 18036 137 0 0
sp_payment_common 13628 13628 0 0.00 1 11127 33 0 0
sp_qd_handle_on_cancel_clo 1890 1890 0 0.00 0 13693 306 0 0
sp_qd_handle_on_invoice_upd_sts 10933 10933 0 0.00 0 45628 333 0 0
sp_qd_handle_on_reserve_upd_sts 10511 10511 0 0.00 0 11977 64 0 0
sp_reserve_write_off 10511 10511 0 0.00 6 18205 192 0 0
sp_supplier_invoice 5873 5873 0 0.00 5 100602 3564 0 0
sp_supplier_order 5721 5721 0 0.00 5 36778 1295 0 0
srv_aggregate_perf_data 107 107 0 0.00 2352 112043 18912 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1559 119687 40097 0 0
srv_find_qd_qs_mism 1073937 1073937 0 0.00 0 16598 5 0 0
srv_make_invnt_saldo 540 540 0 0.00 42 72744 1176 0 0
srv_make_money_saldo 782 782 0 0.00 1 1477 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 205283 542254 320537 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5379 5379 0 0.00 0 10300 191 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 1 6574 95 0 0
t$perf-norm:sp_cancel_client_order 1890 1890 0 0.00 0 2396 12 0 0
t$perf-norm:sp_cancel_customer_reserve 750 750 0 0.00 0 2530 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 5797 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 13677 56 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 237 6 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 24164 1714 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 2925 9 0 0
t$perf-norm:sp_client_order 21150 21150 0 0.00 0 6526 16 0 0
t$perf-norm:sp_customer_reserve 1068 1068 0 0.00 0 7560 13 0 0
t$perf-norm:sp_pay_from_customer 7411 7411 0 0.00 0 6877 12 0 0
t$perf-norm:sp_pay_to_supplier 6217 6217 0 0.00 0 19074 11 0 0
t$perf-norm:sp_reserve_write_off 10511 10511 0 0.00 0 7400 15 0 0
t$perf-norm:sp_supplier_invoice 5873 5873 0 0.00 0 14676 20 0 0
t$perf-norm:sp_supplier_order 5721 5721 0 0.00 0 5984 15 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 788 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 409 12 0 0
t$perf-norm:srv_make_invnt_saldo 540 540 0 0.00 0 4617 40 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 1283 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 5 0 0
v_add_invoice_to_stock 10888 10712 176 1.61 0 7606 21 0 176
v_all_customers 38858 38858 0 0.00 0 89 1 0 0
v_all_suppliers 31810 31810 0 0.00 0 35 1 0 0
v_all_wares 42300 42300 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 688 7 0 0
v_cancel_client_order 3780 3780 0 0.00 0 15259 11 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 15532 28 0 0
v_cancel_customer_reserve 1500 1500 0 0.00 0 5993 8 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 4690 17 0 0
v_cancel_supplier_invoice 836 828 8 0.95 0 3539 17 0 8
v_cancel_supplier_order 838 838 0 0.00 0 499 5 0 0
v_cancel_write_off 2016 2016 0 0.00 0 6018 23 0 0
v_max_id_clo_ord 5718 5718 0 0.00 0 1394 1 0 0
v_max_id_clo_res 1068 1068 0 0.00 0 3525 21 0 0
v_max_id_ord_sup 5872 5872 0 0.00 0 9278 6 0 0
v_max_non_paid_invoice 1901 1901 0 0.00 0 1112 3 0 0
v_max_non_paid_realizn 3943 3943 0 0.00 0 2371 4 0 0
v_min_id_clo_ord 5718 5718 0 0.00 0 5914 8 0 0
v_min_id_clo_res 1068 1068 0 0.00 0 3093 15 0 0
v_min_id_ord_sup 5872 5872 0 0.00 0 5475 11 0 0
v_min_non_paid_invoice 6217 6217 0 0.00 0 9964 30 0 0
v_min_non_paid_realizn 7411 7411 0 0.00 1 11123 22 0 0
v_our_firm 10378 10378 0 0.00 0 23 1 0 0
v_reserve_write_off 21022 21022 0 0.00 0 18144 13 0 0
x_lock_depdocs_on_canc_invoice 11311 11311 0 0.00 0 23930 124 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 8171 329 0 0
x_make_qstorno_1000_1200 5721 5721 0 0.00 1 36768 1222 0 0
x_make_qstorno_1200_2000 5873 5873 0 0.00 2 100001 3409 0 0
x_make_qstorno_2100_3300 486280 486280 0 0.00 0 51846 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-01-2023 22:39:53 to 14-01-2023 22:39:57.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:37 5 8192000000 8804942928 8818298880 10497744 0.127 3 3 3 0 10642016 69328 74944
20:09:38 29823 8192000000 9916002736 10228805632 991984560 10.801 100 82 164 0 1019763520 6358848 21420112
20:11:13 19296 8192000000 9962246208 10282856448 1041240000 11.277 100 90 180 0 1073231808 8659280 23332528
20:12:36 7593 8192000000 9973320736 10301956096 1060661792 11.463 100 93 186 0 1093869728 9393216 23814720
20:13:49 10888 8192000000 10037046096 10383355904 1114775552 11.978 100 78 154 0 1143413184 10405392 18232240
20:15:04 8138 8192000000 10077683600 10436968448 1167704176 12.475 100 76 152 0 1197071808 11672096 17695536
20:16:16 3526 8192000000 10139087648 10506387456 1216419936 12.929 100 86 172 0 1248707552 13112928 19174688
20:17:22 2443 8192000000 10156508768 10536259584 1250470352 13.243 100 80 159 1 1281311280 12941888 17899040
20:18:26 810 8192000000 10195829648 10573598720 1276826416 13.484 100 94 188 0 1311538432 14238432 20473584
20:19:28 977 8192000000 10216927600 10604191744 1306264848 13.752 100 86 173 0 1338087776 11879456 19943472
20:20:29 13914 8192000000 10197842960 10616336384 1314797504 13.830 100 26 52 2 1327105056 6623920 5683632
20:21:45 12507 8192000000 10212815376 10626035712 1318679744 13.865 100 22 42 1 1330188272 6300544 5207984
20:22:59 13297 8192000000 10226022624 10645225472 1332969024 13.994 100 18 36 1 1347135360 10498336 3668000
20:24:15 63287 8192000000 10238441872 10659823616 1339024672 14.049 100 24 45 0 1349841552 5007872 5809008
20:26:23 6053 8192000000 10233720544 10661269504 1348748720 14.136 100 27 53 0 1360803072 6483952 5570400
20:27:32 6604 8192000000 10234134080 10658721792 1332808976 13.993 99 13 27 0 1340962480 5286048 2867456
20:28:39 20615 8192000000 10197621952 10614120448 1284290384 13.552 97 22 44 0 1294532080 5222336 5019360
20:30:34 6407 8192000000 10162705664 10561933312 1231303232 13.066 96 32 64 0 1244628224 6041632 7283360
20:31:42 8164 8192000000 10126312000 10525175808 1185421872 12.641 95 21 42 0 1195716032 5583584 4710576
20:32:53 9399 8192000000 10160562736 10567585792 1249557984 13.234 98 22 44 0 1261096896 6490608 5048304
20:34:02 37964 8192000000 10170211344 10579197952 1281417152 13.526 100 35 70 0 1299349888 9309776 8622960
20:35:42 4978 8192000000 10195218288 10605084672 1301218656 13.706 100 21 43 0 1313055888 6743616 5093616
20:36:49 6408 8192000000 10200102128 10619269120 1313324112 13.816 100 21 42 0 1323750720 5597504 4829104
20:37:57 9071 8192000000 10218676496 10635042816 1319161728 13.869 100 10 21 1 1330277024 9233440 1881856
20:39:09 29614 8192000000 10243857536 10654326784 1337504304 14.035 100 56 113 0 1357835216 6915328 13415584
20:40:46 15224 8192000000 10266470512 10680922112 1355273312 14.195 100 54 108 0 1382278480 14357808 12647360
20:42:09 31144 8192000000 10269447408 10700955648 1371861760 14.344 100 35 69 0 1390720240 10746496 8111984
20:43:44 6247 8192000000 10295959360 10720104448 1387273584 14.482 100 48 94 0 1411813232 12284224 12255424
20:44:54 11891 8192000000 10312195200 10735693824 1396971984 14.568 100 44 86 0 1422551520 15499312 10080224
20:46:11 8943 8192000000 10320282976 10744713216 1405608752 14.645 100 43 86 1 1431287376 15164352 10514272
20:47:27 7936 8192000000 10327274288 10753269760 1407627168 14.663 100 47 94 0 1440224768 20695296 11902304
20:48:45 3275 8192000000 10325062016 10743037952 1395630272 14.556 100 64 129 1 1435346352 23686320 16029760
20:49:55 7471 8192000000 10327033312 10744283136 1393359296 14.536 100 55 106 0 1432922384 25906784 13656304
20:51:07 9395 8192000000 10325249120 10743197696 1373418656 14.358 98 63 123 1 1416560160 27429376 15712128
20:52:22 3111 8192000000 10314205792 10720382976 1365186944 14.284 100 81 162 0 1418195136 33008032 20000160
20:53:37 2306 8192000000 10310527568 10714218496 1363500688 14.269 100 73 146 0 1414504080 33264816 17738576
20:54:46 1556 8192000000 10316564976 10726125568 1367604608 14.306 100 67 136 0 1419269936 34687280 16978048
20:55:54 2752 8192000000 10319770720 10723622912 1369464256 14.322 100 76 148 0 1421285952 33191344 18630352
20:57:03 2192 8192000000 10296917792 10700644352 1344951808 14.102 99 78 157 0 1400819600 35457136 20410656
20:58:13 8441 8192000000 10311701024 10729877504 1364964944 14.282 100 55 110 0 1419633200 41018800 13649456
20:59:34 4256 8192000000 10309870880 10723950592 1359111280 14.229 100 47 92 0 1410377728 38872608 12393840
21:00:43 12597 8192000000 10300618704 10722103296 1359847168 14.236 100 34 66 1 1404668032 35918992 8901872
21:02:03 13803 8192000000 10299099536 10735067136 1364376912 14.277 100 30 58 0 1409831408 36489328 8965168
21:03:18 24010 8192000000 10274472448 10700845056 1344018752 14.094 99 23 46 0 1373680288 23400144 6261392
21:04:43 45587 8192000000 10268273936 10716876800 1335349840 14.015 99 20 40 0 1367244416 26083744 5810832
21:06:33 42934 8192000000 10294020048 10716868608 1364357392 14.276 100 27 55 1 1381385568 10827904 6200272
21:08:17 28314 8192000000 10287794784 10725347328 1371292848 14.339 100 29 58 1 1388668608 10743312 6632448
21:10:20 43515 8192000000 10298932272 10742140928 1382750096 14.441 100 25 50 0 1399334976 11071680 5513200
21:12:23 58370 8192000000 10298697040 10744221696 1375320752 14.375 99 33 67 1 1397424720 14503648 7600320
21:14:56 64019 8192000000 10306938832 10780413952 1397105760 14.569 100 31 62 0 1428504560 24278736 7120064
21:17:01 20500 8192000000 10316596112 10767478784 1404879472 14.638 100 16 32 0 1418881968 10533152 3469344
21:18:24 9215 8192000000 10341374976 10774589440 1409218448 14.677 100 74 148 0 1437568896 11350400 17000048
21:19:38 27721 8192000000 10331960896 10783666176 1406337376 14.651 99 40 81 2 1424302688 8367008 9598304
21:21:16 35426 8192000000 10310728800 10774425600 1411947712 14.701 100 17 31 1 1432379120 16057376 4374032
21:23:01 28259 8192000000 10302360912 10764668928 1405694576 14.646 100 11 22 0 1414662704 6780208 2187920
21:24:34 28943 8192000000 10303357440 10760994816 1397330688 14.571 100 19 38 0 1410875904 9247616 4297600
21:26:08 109693 8192000000 10322325024 10785087488 1410895648 14.692 100 19 37 0 1427174608 12313056 3965904
21:28:58 42579 8192000000 10319335616 10790547456 1407423872 14.661 99 15 30 0 1424974576 14224976 3325728
21:31:19 68424 8192000000 10272713328 10729250816 1298870112 13.685 92 5 8 1 1305958704 6573552 515040
21:33:30 27234 8192000000 10292875808 10751664128 1354364432 14.187 97 31 62 0 1375920048 13761776 7793840
21:35:04 53882 8192000000 10304345104 10763198464 1374070736 14.364 99 15 30 0 1396017200 18050544 3895920
21:37:13 62799 8192000000 10279883536 10732748800 1315473472 13.836 95 19 39 0 1347087600 26928624 4685504
21:39:17 46151 8192000000 10288357744 10734825472 1330857856 13.975 96 22 44 0 1350889264 15121792 4909616
21:41:03 67559 8192000000 10270068320 10724298752 1310111568 13.787 95 13 24 1 1326581104 14122032 2347504
21:43:12 24714 8192000000 10293113696 10729095168 1340222944 14.059 97 40 78 0 1356261504 6867504 9171056
21:44:36 119687 8192000000 10267068448 10716934144 1327463456 13.944 97 32 64 1 1344749536 10407136 6878944
21:47:54 55159 8192000000 10238234208 10695610368 1342368528 14.079 98 20 40 0 1355125696 7600320 5156848
21:50:34 52333 8192000000 10260375024 10723164160 1343568032 14.090 98 14 28 0 1356958176 10049056 3341088
21:52:31 48383 8192000000 10266680032 10730213376 1354710880 14.190 99 17 34 0 1374140976 15397408 4032688
21:54:33 20079 8192000000 10263062704 10716983296 1329505856 13.963 97 23 46 0 1343228256 8578784 5143616
21:55:53 60448 8192000000 10255937472 10719485952 1334426528 14.007 98 12 24 0 1346950880 9734864 2789488
21:57:54 65872 8192000000 10285336960 10740224000 1358681632 14.226 99 19 39 0 1376392064 13261040 4449392
22:00:01 67666 8192000000 10293051232 10759258112 1377029504 14.390 100 7 14 0 1395211072 16514416 1667152
22:02:08 51597 8192000000 10302941456 10754625536 1361938320 14.255 98 31 63 0 1384240992 14033728 8268944
22:04:00 72135 8192000000 10302563360 10754592768 1377674352 14.396 99 26 52 0 1393358272 10153408 5530512
22:06:12 97311 8192000000 10283014304 10734817280 1337513616 14.035 96 33 64 0 1356619920 11436144 7670160
22:09:06 70542 8192000000 10281963248 10751242240 1379395776 14.411 99 14 28 0 1391855184 9027072 3432336
22:11:30 59037 8192000000 10277104688 10747281408 1372693472 14.351 99 11 22 1 1379602416 5181728 1727216
22:13:31 22516 8192000000 10283918304 10741100544 1381338112 14.429 100 19 38 0 1391159232 5875808 3945312
22:14:55 49050 8192000000 10307254672 10761871360 1389332400 14.500 99 24 48 0 1402867536 8425168 5109968
22:16:44 47640 8192000000 10292954832 10752147456 1378690992 14.405 99 19 38 0 1391936256 8729184 4516080
22:18:33 53069 8192000000 10292439216 10755678208 1364449888 14.277 98 14 28 0 1379043472 11616928 2976656
22:20:27 18722 8192000000 10287740928 10735063040 1353419200 14.178 98 42 84 0 1371582208 9018272 9144736
22:21:50 43811 8192000000 10288770528 10763038720 1367764848 14.307 99 19 38 0 1391048880 19076624 4207408
22:23:38 93391 8192000000 10297609856 10743484416 1335274624 14.015 96 13 24 1 1352305744 14489152 2541968
22:26:11 83146 8192000000 10267369808 10719625216 1342717376 14.082 97 28 57 1 1363491424 14631568 6142480
22:28:47 43059 8192000000 10272256736 10722705408 1338177216 14.041 98 34 67 0 1360305968 14726368 7402384
22:30:34 51601 8192000000 10258977712 10725310464 1356931616 14.210 99 11 22 0 1369550048 10157296 2461136
22:32:26 100194 8192000000 10255000448 10708385792 1318495568 13.863 96 20 40 0 1332605856 8848192 5262096
22:35:51 67009 8192000000 10276729024 10723815424 1316091776 13.841 96 11 22 0 1336216048 17763136 2361136
22:38:15 30955 8192000000 9000515408 9207013376 173250544 2.071 13 3 6 0 176272832 2376432 645856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-01-2023 22:39:59 to 14-01-2023 22:40:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-01-2023 22:40:16 to 14-01-2023 22:40:18.

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          251954
MON$OLDEST_ACTIVE               251955
MON$OLDEST_SNAPSHOT             251955
MON$NEXT_TRANSACTION            251955
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2037280
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33653 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33653 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33653 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 ---
:232: 
:233: mscs-test-161.budzdorov.ru    Sat Jan 14 22:38:49 2023
:234:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:235: 
--- end of diff output ---

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

14.01.23 22:40:19. 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
2023-01-14 22:40:21 No severe PSQL-related problems occured
14.01.23 22:40:23. End of report.