Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.10.2022 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 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 15425728 kB
MemAvailable 30514348 kB
Buffers 0 kB
Cached 16277692 kB
SwapCached 2076 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 2022-10-12 01:38:34
DTS_END                         2022-10-12 01:38:34.7800
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57020
TRN_ID                          238730
ATT_ID                          628
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: 9424.81 24224 1130977
customer order: creation 169.14 409 20297
customer order: refuse 14.11 350 1693
order to supplier: creation 44.75 1329 5370
order to supplier: removal 3.38 108629 405
invoice (draft): creation 44.91 3039 5389
invoice (draft): removal 56.04 709 6725
invoice accept: apply 40.82 17357 4898
invoice accept: cancel 50.69 768 6083
customer reserve: creation 3773.63 171 452835
customer reserve: removal 4926.30 60 591156
realization accept: apply 80.99 188 9719
realization accept: cancel 93.30 114 11196
payment from customer: creation 58.35 132 7002
payment from customer: removal 5.80 81 696
payment to supplier: creation 47.72 180 5726
payment to supplier: removal 3.34 88 401
service: total inventory turnovers 4.42 1265 530
service: total monetary turnovers 6.38 24 765
service: aggregate perf. data 0.73 17252 87
service: refresh index statistics 0.03 332342 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-10-2022 01:39:50 to 12-10-2022 01:39:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1767
TEST_TIME 2 2885
TEST_TIME 3 2727
TEST_TIME 4 3042
TEST_TIME 5 4204
TEST_TIME 6 4082
TEST_TIME 7 3992
TEST_TIME 8 4256
TEST_TIME 9 4445
TEST_TIME 10 4669
TEST_TIME 11 5227
TEST_TIME 12 5569
TEST_TIME 13 5787
TEST_TIME 14 6202
TEST_TIME 15 6235
TEST_TIME 16 6464
TEST_TIME 17 6680
TEST_TIME 18 7439
TEST_TIME 19 7478
TEST_TIME 20 8188
TEST_TIME 21 8321
TEST_TIME 22 9300
TEST_TIME 23 9490
TEST_TIME 24 9805
TEST_TIME 25 9880
TEST_TIME 26 10905
TEST_TIME 27 10763
TEST_TIME 28 10639
TEST_TIME 29 10570
TEST_TIME 30 10541
TEST_TIME 31 10373
TEST_TIME 32 10244
TEST_TIME 33 10210
TEST_TIME 34 10110
TEST_TIME 35 10047
TEST_TIME 36 9934
TEST_TIME 37 10042
TEST_TIME 38 10056
TEST_TIME 39 10151
TEST_TIME 40 10105
TEST_TIME 41 9957
TEST_TIME 42 9877
TEST_TIME 43 9731
TEST_TIME 44 9667
TEST_TIME 45 9571
TEST_TIME 46 9517
TEST_TIME 47 9466
TEST_TIME 48 9413
TEST_TIME 49 9435
TEST_TIME 50 9537
TEST_TIME 51 9460
TEST_TIME 52 9618
TEST_TIME 53 9686
TEST_TIME 54 9679
TEST_TIME 55 9663
TEST_TIME 56 9673
TEST_TIME 57 9918
TEST_TIME 58 9845
TEST_TIME 59 9907
TEST_TIME 60 10064
TEST_TIME 61 10100
TEST_TIME 62 9978
TEST_TIME 63 9879
TEST_TIME 64 9830
TEST_TIME 65 9832
TEST_TIME 66 9729
TEST_TIME 67 9647
TEST_TIME 68 9651
TEST_TIME 69 9595
TEST_TIME 70 9732
TEST_TIME 71 9720
TEST_TIME 72 9715
TEST_TIME 73 9673
TEST_TIME 74 9701
TEST_TIME 75 9752
TEST_TIME 76 9755
TEST_TIME 77 9708
TEST_TIME 78 9761
TEST_TIME 79 9800
TEST_TIME 80 9804
TEST_TIME 81 9799
TEST_TIME 82 9801
TEST_TIME 83 9711
TEST_TIME 84 9717
TEST_TIME 85 9645
TEST_TIME 86 9625
TEST_TIME 87 9578
TEST_TIME 88 9539
TEST_TIME 89 9514
TEST_TIME 90 9463
TEST_TIME 91 9417
TEST_TIME 92 9427
TEST_TIME 93 9527
TEST_TIME 94 9555
TEST_TIME 95 9578
TEST_TIME 96 9569
TEST_TIME 97 9554
TEST_TIME 98 9563
TEST_TIME 99 9619
TEST_TIME 100 9600
TEST_TIME 101 9608
TEST_TIME 102 9660
TEST_TIME 103 9652
TEST_TIME 104 9693
TEST_TIME 105 9737
TEST_TIME 106 9660
TEST_TIME 107 9596
TEST_TIME 108 9552
TEST_TIME 109 9521
TEST_TIME 110 9476
TEST_TIME 111 9495
TEST_TIME 112 9518
TEST_TIME 113 9486
TEST_TIME 114 9424
TEST_TIME 115 9400
TEST_TIME 116 9461
TEST_TIME 117 9432
TEST_TIME 118 9419
TEST_TIME 119 9440
TEST_TIME 120 9428

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-10-2022 01:39:54 to 12-10-2022 01:39:57.

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 1551267 1551267 0 0.00 0 57514 7 0 0
doc_list_biud 1551254 1551254 0 0.00 0 63589 28 0 0
sp_add_invoice_to_stock 4970 4898 72 1.44 30 150442 17358 0 72
sp_cancel_adding_invoice 6083 6083 0 0.00 4 81008 768 0 0
sp_cancel_client_order 1693 1693 0 0.00 4 7540 350 0 0
sp_cancel_customer_reserve 591156 591156 0 0.00 0 63593 60 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 2 5501 81 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 2 12678 89 0 0
sp_cancel_supplier_invoice 6732 6725 7 0.10 1 29250 709 0 7
sp_cancel_supplier_order 405 405 0 0.00 5 1309027 108630 0 0
sp_cancel_write_off 11196 11196 0 0.00 1 9838 114 0 0
sp_client_order 20297 20297 0 0.00 3 35100 410 0 0
sp_customer_reserve 452835 452835 0 0.00 0 94293 171 0 0
sp_fill_shopping_cart 31053 31053 0 0.00 1 34275 42 0 0
sp_fill_shopping_cart_clo_res 452828 452828 0 0.00 0 12921 15 0 0
sp_get_clo_for_invoice 4891 4891 0 0.00 0 28501 286 0 0
sp_kill_cost_storno 18376 18376 0 0.00 0 9495 52 0 0
sp_kill_qstorno_ret_qs2qd 1200638 1200638 0 0.00 0 63581 32 0 0
sp_kill_qty_storno 631858 631858 0 0.00 0 63588 68 0 0
sp_lock_selected_doc 20272 20272 0 0.00 0 8335 5 0 0
sp_make_cost_storno 27338 27338 0 0.00 0 57450 85 0 0
sp_multiply_rows_for_pdistr 27334 27334 0 0.00 0 12930 30 0 0
sp_multiply_rows_for_qdistr 20297 20297 0 0.00 0 35073 200 0 0
sp_pay_from_customer 7002 7002 0 0.00 4 35620 133 0 0
sp_pay_to_supplier 5726 5726 0 0.00 4 34782 180 0 0
sp_payment_common 12725 12725 0 0.00 1 15054 33 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 0 6941 312 0 0
sp_qd_handle_on_invoice_upd_sts 10966 10966 0 0.00 0 22561 292 0 0
sp_qd_handle_on_reserve_upd_sts 9717 9717 0 0.00 0 13789 56 0 0
sp_reserve_write_off 9719 9719 0 0.00 6 35516 188 0 0
sp_supplier_invoice 5389 5389 0 0.00 6 97277 3039 0 0
sp_supplier_order 5370 5370 0 0.00 5 75328 1329 0 0
srv_aggregate_perf_data 87 87 0 0.00 2831 123860 17252 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1447 153049 44243 0 0
srv_find_qd_qs_mism 1084648 1084648 0 0.00 0 21318 5 0 0
srv_make_invnt_saldo 530 530 0 0.00 31 122012 1266 0 0
srv_make_money_saldo 765 765 0 0.00 1 406 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 219279 406658 332342 0 0
t$perf-abend:sp_add_invoice_to_stock 72 72 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4898 4898 0 0.00 0 18752 156 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 7372 117 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 11595 16 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 2136 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 3878 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 4482 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 3689 23 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 39766 1733 0 0
t$perf-norm:sp_cancel_write_off 1024 1024 0 0.00 0 8563 22 0 0
t$perf-norm:sp_client_order 20297 20297 0 0.00 0 12919 16 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 14226 24 0 0
t$perf-norm:sp_pay_from_customer 7002 7002 0 0.00 0 14335 11 0 0
t$perf-norm:sp_pay_to_supplier 5726 5726 0 0.00 0 8289 12 0 0
t$perf-norm:sp_reserve_write_off 9719 9719 0 0.00 0 12272 12 0 0
t$perf-norm:sp_supplier_invoice 5389 5389 0 0.00 0 4307 13 0 0
t$perf-norm:sp_supplier_order 5370 5370 0 0.00 0 11073 15 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 173 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 797 13 0 0
t$perf-norm:srv_make_invnt_saldo 530 530 0 0.00 0 1133 22 0 0
t$perf-norm:srv_make_money_saldo 765 765 0 0.00 0 1476 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 9908 9764 144 1.45 0 12464 17 0 144
v_all_customers 37120 37120 0 0.00 0 31 1 0 0
v_all_suppliers 29732 29732 0 0.00 0 26 1 0 0
v_all_wares 40592 40592 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 12223 22 0 0
v_cancel_client_order 3386 3386 0 0.00 0 2736 5 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 1889 8 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 1486 5 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 12674 20 0 0
v_cancel_supplier_invoice 804 790 14 1.74 0 12566 33 0 14
v_cancel_supplier_order 810 810 0 0.00 0 624 4 0 0
v_cancel_write_off 2048 2048 0 0.00 0 2162 14 0 0
v_max_id_clo_ord 5370 5370 0 0.00 0 8094 3 0 0
v_max_id_clo_res 971 971 0 0.00 0 3668 18 0 0
v_max_id_ord_sup 5387 5387 0 0.00 0 5903 6 0 0
v_max_non_paid_invoice 1615 1615 0 0.00 0 7351 10 0 0
v_max_non_paid_realizn 3630 3630 0 0.00 0 2012 4 0 0
v_min_id_clo_ord 5370 5370 0 0.00 0 33181 41 0 0
v_min_id_clo_res 971 971 0 0.00 0 6793 17 0 0
v_min_id_ord_sup 5387 5387 0 0.00 0 8244 13 0 0
v_min_non_paid_invoice 5724 5724 0 0.00 0 11256 27 0 0
v_min_non_paid_realizn 6999 6999 0 0.00 0 12697 21 0 0
v_our_firm 10210 10210 0 0.00 0 18 1 0 0
v_reserve_write_off 19434 19434 0 0.00 0 6558 6 0 0
x_lock_depdocs_on_canc_invoice 12413 12413 0 0.00 0 20093 111 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 11906 408 0 0
x_make_qstorno_1000_1200 5370 5370 0 0.00 1 44441 1223 0 0
x_make_qstorno_1200_2000 5389 5389 0 0.00 2 93514 2909 0 0
x_make_qstorno_2100_3300 452835 452835 0 0.00 0 94291 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2022 01:39:58 to 12-10-2022 01:40:02.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:38 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:39 24052 8192000000 9894126832 10200616960 973545056 10.621 100 91 182 0 1003802944 5914816 24343072
23:11:14 14127 8192000000 9924738016 10242002944 1007619104 10.952 100 87 174 1 1038354288 7582320 23152864
23:12:37 10075 8192000000 9957671648 10285174784 1040105424 11.266 100 86 172 0 1070933024 8824672 22002928
23:13:58 8010 8192000000 10016137728 10359828480 1105813664 11.893 100 76 150 0 1134442752 9966192 18662896
23:15:10 3859 8192000000 10058956896 10417324032 1152042768 12.329 100 74 147 0 1180506992 11807152 16657072
23:16:23 1929 8192000000 10097003728 10461888512 1189956352 12.683 100 79 158 0 1220856352 13036384 17863616
23:17:28 2362 8192000000 10143226000 10516127744 1226986368 13.026 100 79 156 0 1256955568 12710880 17258320
23:18:32 1972 8192000000 10179656608 10559655936 1263481776 13.362 100 84 169 0 1296455440 14004688 18968976
23:19:35 1217 8192000000 10195359216 10581671936 1288109696 13.587 100 82 163 1 1318715504 12398608 18207200
23:20:37 19546 8192000000 10176055536 10587598848 1292306096 13.625 100 27 55 0 1309142480 10217776 6618608
23:22:02 7770 8192000000 10202548640 10610270208 1309871888 13.785 100 31 62 0 1327708144 10258192 7578064
23:23:12 11032 8192000000 10206755264 10622222336 1320205040 13.879 100 23 46 0 1331085232 5423584 5456608
23:24:23 63744 8192000000 10218610736 10639495168 1330384544 13.971 100 25 49 1 1341081008 5229824 5466640
23:26:32 5290 8192000000 10223778128 10647797760 1333913264 14.002 100 22 44 0 1345203680 5992864 5297552
23:27:37 3030 8192000000 10235982976 10645078016 1329298048 13.961 100 46 92 0 1347644400 7622112 10724240
23:28:41 7096 8192000000 10197532704 10613469184 1296109744 13.660 99 22 44 0 1307314304 6664368 4540192
23:29:48 23920 8192000000 10194429552 10605002752 1261178304 13.341 96 39 79 0 1279976096 10033952 8763840
23:31:12 3588 8192000000 10171345536 10579869696 1274178464 13.460 99 28 52 0 1286612672 6650272 5783936
23:32:18 9090 8192000000 10123886976 10516275200 1217681984 12.940 98 16 33 1 1226414912 5388064 3344864
23:33:28 13847 8192000000 10159432624 10566021120 1259339440 13.324 99 20 40 0 1269057936 5070592 4647904
23:34:45 18557 8192000000 10174809728 10584846336 1284316352 13.552 100 25 50 0 1295592016 5937632 5338032
23:36:07 6417 8192000000 10191290944 10600267776 1296342944 13.662 100 41 81 2 1313560064 7780992 9436128
23:37:19 3321 8192000000 10207337216 10618241024 1307569008 13.764 100 35 70 1 1320397904 5470576 7358320
23:38:23 77696 8192000000 10239047888 10652127232 1329490944 13.963 100 28 56 1 1341320480 4568736 7260800
23:40:42 64873 8192000000 10262684512 10684964864 1351486640 14.161 100 24 46 0 1367165808 9998896 5680272
23:42:49 10454 8192000000 10301293904 10715410432 1374300784 14.366 100 47 91 1 1407063568 22094272 10668512
23:44:07 12373 8192000000 10301799920 10717634560 1376067120 14.381 100 53 106 0 1410185056 21148896 12969040
23:45:28 18824 8192000000 10308379296 10730975232 1384245888 14.454 100 46 92 0 1422183872 27302944 10635040
23:46:51 2489 8192000000 10347191792 10764582912 1404575632 14.636 100 58 116 1 1454535712 35548640 14411440
23:47:58 11336 8192000000 10352766688 10776793088 1411249440 14.695 100 48 94 0 1460815200 37546128 12019632
23:49:15 3800 8192000000 10357798784 10774073344 1409330064 14.678 100 63 125 0 1461607440 36814384 15462992
23:50:28 5120 8192000000 10360240336 10779840512 1412139984 14.703 100 63 120 2 1466448160 38854000 15454176
23:51:39 1820 8192000000 10374822768 10791944192 1419227616 14.766 100 66 127 1 1477552128 42415648 15908864
23:52:45 2249 8192000000 10381570432 10795294720 1415507456 14.733 100 74 147 0 1484005216 50370672 18127088
23:53:52 1445 8192000000 10387188960 10800197632 1417412704 14.750 100 70 138 0 1487932912 53240224 17279984
23:55:02 2802 8192000000 10378834336 10796732416 1401081008 14.605 99 70 139 1 1475943632 56993888 17868736
23:56:13 3094 8192000000 10351811056 10762510336 1384888256 14.460 100 74 148 0 1458556272 54765024 18902992
23:57:19 2510 8192000000 10339828752 10752647168 1363013360 14.264 98 53 106 1 1434639520 56949360 14676800
23:58:27 14271 8192000000 10338088976 10774298624 1383498080 14.448 100 37 74 0 1453388896 59178448 10712368
23:59:46 29049 8192000000 10350104848 10795356160 1383439504 14.447 100 34 68 0 1460956208 67432528 10084176
00:01:19 17865 8192000000 10312376480 10750808064 1356803552 14.209 99 29 58 1 1409132608 44173664 8155392
00:02:38 25212 8192000000 10294478608 10740989952 1341848096 14.074 99 16 32 0 1387585232 41364512 4372624
00:04:04 68405 8192000000 10241722080 10674794496 1263709488 13.364 94 10 20 0 1290771984 24394176 2668320
00:06:14 21417 8192000000 10249738080 10689687552 1331086096 13.977 99 14 28 0 1347384208 12672144 3625968
00:07:49 70495 8192000000 10278217648 10724188160 1357423792 14.214 100 41 85 0 1380845504 14161856 9259856
00:10:06 37627 8192000000 10250927488 10674388992 1298566832 13.682 95 41 78 1 1317562528 9852800 9142896
00:11:45 26843 8192000000 10273277872 10724859904 1353574256 14.180 99 26 52 0 1370907088 11247184 6085648
00:13:13 20681 8192000000 10291409088 10738298880 1358616256 14.225 99 23 44 1 1380689920 17019104 5054560
00:14:34 36198 8192000000 10303855936 10746269696 1388489792 14.492 100 40 80 0 1412569984 13996672 10083520
00:16:23 58502 8192000000 10280502560 10752385024 1371919584 14.344 99 31 62 0 1400842752 21417504 7505664
00:18:26 38501 8192000000 10290765456 10745630720 1389494224 14.501 100 29 56 2 1402743504 7120160 6129120
00:20:05 153049 8192000000 10279057760 10745434112 1376453168 14.385 99 24 48 0 1389774944 7354160 5967616
00:23:43 54132 8192000000 10283802688 10751082496 1381171888 14.427 99 19 38 0 1395842576 10508272 4162416
00:25:45 47163 8192000000 10282665664 10750312448 1378576320 14.404 100 13 25 1 1395387088 14366800 2443968
00:27:32 60507 8192000000 10289349056 10763280384 1382511824 14.439 99 14 28 0 1398062496 12460080 3090592
00:29:33 23405 8192000000 10315625776 10763939840 1398218592 14.579 100 28 56 0 1420693728 16461824 6013312
00:31:10 66813 8192000000 10297573984 10761773056 1392115600 14.525 100 16 32 1 1421738384 25663008 3959776
00:33:33 29696 8192000000 10324849968 10781294592 1390992928 14.515 99 37 75 0 1419995520 21187040 7815552
00:35:06 71717 8192000000 10289736352 10740432896 1321701792 13.892 95 9 19 0 1335477728 11694896 2081040
00:37:22 78978 8192000000 10312601760 10770665472 1363288832 14.267 98 9 18 0 1386477584 20850464 2338288
00:39:41 56193 8192000000 10303749152 10740670464 1336493472 14.026 96 32 64 1 1357009984 13848320 6668192
00:41:39 49800 8192000000 10282117680 10734981120 1366737872 14.298 99 30 60 0 1383094416 9871104 6485440
00:43:31 49345 8192000000 10262553760 10720481280 1323148656 13.905 96 15 30 0 1337259136 10824272 3286208
00:45:21 81396 8192000000 10279425632 10734071808 1348967456 14.138 98 15 30 0 1373025184 20141824 3915904
00:47:53 52201 8192000000 10268381936 10745044992 1357394080 14.214 99 7 14 0 1377009808 18059088 1556640
00:49:46 60288 8192000000 10270737952 10729869312 1347028896 14.121 98 26 52 0 1363030496 9455296 6546304
00:51:46 50639 8192000000 10279550272 10725572608 1337641520 14.036 97 27 54 0 1358870400 15004880 6224000
00:53:43 50283 8192000000 10261536048 10702524416 1274574112 13.463 94 20 40 0 1293341888 14770544 3997232
00:56:11 24907 8192000000 10266383840 10712342528 1349488832 14.143 100 32 64 1 1371915040 15404400 7021808
00:57:42 64527 8192000000 10274355696 10721943552 1348312592 14.132 99 24 48 0 1371353344 17580656 5460096
00:59:51 49366 8192000000 10293747984 10742362112 1358639888 14.225 99 22 44 0 1374753088 11253504 4859696
01:01:40 96097 8192000000 10252326208 10702856192 1311770672 13.802 96 14 28 0 1324216912 9334816 3111424
01:04:31 38790 8192000000 10267894752 10728038400 1374162032 14.364 100 17 34 0 1386042368 7593776 4286560
01:07:25 51305 8192000000 10263464896 10718494720 1354074416 14.184 98 11 22 0 1363614064 6698656 2840992
01:09:18 81372 8192000000 10293620032 10751311872 1380622224 14.422 99 18 34 0 1396726224 12252224 3851776
01:11:40 43376 8192000000 10279036144 10738122752 1359693616 14.235 98 15 30 0 1371553952 8540992 3319344
01:13:24 44003 8192000000 10306790032 10748649472 1373079440 14.355 99 37 73 0 1390744784 8297328 9368016
01:15:08 53381 8192000000 10311442384 10760409088 1364271664 14.276 98 24 48 0 1378974880 9482288 5220928
01:17:02 57264 8192000000 10298340816 10756927488 1375241024 14.374 99 9 18 0 1388677712 11402896 2033792
01:19:06 35562 8192000000 10295369808 10749841408 1368115008 14.310 98 14 29 1 1380093680 9012368 2966304
01:20:51 68440 8192000000 10319579568 10764976128 1358937648 14.228 97 18 36 0 1377036880 14445952 3653280
01:23:10 95221 8192000000 10312676272 10777460736 1397416032 14.572 100 11 22 1 1426448160 26441376 2590752
01:26:27 67298 8192000000 10294898144 10748768256 1361900704 14.254 97 36 72 1 1381780224 12001472 7878048
01:28:59 76514 8192000000 10272746288 10734555136 1334895024 14.011 96 7 15 0 1353257120 16773360 1588736
01:31:16 45062 8192000000 10291455872 10752278528 1374355728 14.366 99 14 28 0 1387401680 9830928 3215024
01:33:38 61635 8192000000 10263816256 10731552768 1304397232 13.735 94 10 20 0 1322717040 15926608 2393200
01:35:42 14507 8192000000 10284687456 10721693696 1361313888 14.249 100 46 90 0 1378303520 6266112 10723520
01:36:57 85591 8192000000 10249082304 10706685952 1263405168 13.361 92 24 46 0 1282352368 13858288 5088912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-10-2022 01:40:04 to 12-10-2022 01:40:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 72 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 12-10-2022 01:40:20 to 12-10-2022 01:40:23.

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          237331
MON$OLDEST_ACTIVE               238842
MON$OLDEST_SNAPSHOT             238842
MON$NEXT_TRANSACTION            238842
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                       2035936
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.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
:229: 
:230: mscs-test-161.budzdorov.ru    Wed Oct 12 01:38:36 2022
:231:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:232: 
:233: 
:234: mscs-test-161.budzdorov.ru    Wed Oct 12 01:38:40 2022
:235:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:236: 
:237: 
:238: mscs-test-161.budzdorov.ru    Wed Oct 12 01:38:50 2022
:239:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:240: 
:241: 
:242: mscs-test-161.budzdorov.ru    Wed Oct 12 01:38:54 2022
:243:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:244: 
:245: 
:246: mscs-test-161.budzdorov.ru    Wed Oct 12 01:38:54 2022
:247:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:248: 
:249: 
:250: mscs-test-161.budzdorov.ru    Wed Oct 12 01:38:55 2022
:251:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:252: 
:253: 
:254: mscs-test-161.budzdorov.ru    Wed Oct 12 01:38:55 2022
:255:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:256: 
--- end of diff output ---

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

12.10.22 01:40:24. 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-10-12 01:40:26 No severe PSQL-related problems occured
12.10.22 01:40:28. End of report.