Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.05.2022 22:40
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 16391972 kB
MemAvailable 31413276 kB
Buffers 0 kB
Cached 15355904 kB
SwapCached 2464 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-27 22:38:19
DTS_END                         2022-05-27 22:38:19.8960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33302
TRN_ID                          236599
ATT_ID                          667
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: 9436.70 19806 1132404
customer order: creation 169.45 365 20334
customer order: refuse 14.33 386 1720
order to supplier: creation 44.98 1271 5398
order to supplier: removal 3.73 96184 448
invoice (draft): creation 44.59 3480 5351
invoice (draft): removal 55.48 701 6657
invoice accept: apply 40.95 18284 4914
invoice accept: cancel 50.66 750 6079
customer reserve: creation 3770.53 181 452464
customer reserve: removal 4948.80 57 593856
realization accept: apply 81.31 213 9757
realization accept: cancel 83.94 112 10073
payment from customer: creation 58.90 112 7068
payment from customer: removal 5.56 109 667
payment to supplier: creation 48.00 127 5760
payment to supplier: removal 3.49 161 419
service: total inventory turnovers 4.60 1100 552
service: total monetary turnovers 6.57 31 788
service: aggregate perf. data 0.79 14927 95
service: refresh index statistics 0.03 257569 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-05-2022 22:40:37 to 27-05-2022 22:40:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8122
TEST_TIME 2 5947
TEST_TIME 3 5176
TEST_TIME 4 5514
TEST_TIME 5 5528
TEST_TIME 6 5576
TEST_TIME 7 5317
TEST_TIME 8 5585
TEST_TIME 9 5550
TEST_TIME 10 5784
TEST_TIME 11 6176
TEST_TIME 12 6419
TEST_TIME 13 6910
TEST_TIME 14 6995
TEST_TIME 15 7321
TEST_TIME 16 7327
TEST_TIME 17 7548
TEST_TIME 18 8019
TEST_TIME 19 8676
TEST_TIME 20 8896
TEST_TIME 21 9092
TEST_TIME 22 9042
TEST_TIME 23 9334
TEST_TIME 24 9511
TEST_TIME 25 9797
TEST_TIME 26 9759
TEST_TIME 27 10385
TEST_TIME 28 11267
TEST_TIME 29 11103
TEST_TIME 30 11169
TEST_TIME 31 10889
TEST_TIME 32 10682
TEST_TIME 33 10450
TEST_TIME 34 10444
TEST_TIME 35 10566
TEST_TIME 36 10354
TEST_TIME 37 10273
TEST_TIME 38 10284
TEST_TIME 39 10091
TEST_TIME 40 10197
TEST_TIME 41 10207
TEST_TIME 42 10145
TEST_TIME 43 10088
TEST_TIME 44 9952
TEST_TIME 45 9912
TEST_TIME 46 9751
TEST_TIME 47 9687
TEST_TIME 48 9656
TEST_TIME 49 9616
TEST_TIME 50 9604
TEST_TIME 51 9658
TEST_TIME 52 9615
TEST_TIME 53 9658
TEST_TIME 54 9646
TEST_TIME 55 9639
TEST_TIME 56 9703
TEST_TIME 57 9755
TEST_TIME 58 9855
TEST_TIME 59 9935
TEST_TIME 60 9888
TEST_TIME 61 9956
TEST_TIME 62 9996
TEST_TIME 63 9902
TEST_TIME 64 9837
TEST_TIME 65 9846
TEST_TIME 66 9751
TEST_TIME 67 9791
TEST_TIME 68 9716
TEST_TIME 69 9659
TEST_TIME 70 9587
TEST_TIME 71 9558
TEST_TIME 72 9564
TEST_TIME 73 9631
TEST_TIME 74 9593
TEST_TIME 75 9611
TEST_TIME 76 9585
TEST_TIME 77 9630
TEST_TIME 78 9746
TEST_TIME 79 9722
TEST_TIME 80 9711
TEST_TIME 81 9758
TEST_TIME 82 9812
TEST_TIME 83 9884
TEST_TIME 84 9798
TEST_TIME 85 9979
TEST_TIME 86 9959
TEST_TIME 87 9857
TEST_TIME 88 9815
TEST_TIME 89 9828
TEST_TIME 90 9762
TEST_TIME 91 9773
TEST_TIME 92 9735
TEST_TIME 93 9673
TEST_TIME 94 9698
TEST_TIME 95 9697
TEST_TIME 96 9724
TEST_TIME 97 9692
TEST_TIME 98 9729
TEST_TIME 99 9765
TEST_TIME 100 9799
TEST_TIME 101 9813
TEST_TIME 102 9786
TEST_TIME 103 9834
TEST_TIME 104 9852
TEST_TIME 105 9822
TEST_TIME 106 9824
TEST_TIME 107 9820
TEST_TIME 108 9818
TEST_TIME 109 9731
TEST_TIME 110 9726
TEST_TIME 111 9711
TEST_TIME 112 9683
TEST_TIME 113 9631
TEST_TIME 114 9602
TEST_TIME 115 9621
TEST_TIME 116 9593
TEST_TIME 117 9557
TEST_TIME 118 9546
TEST_TIME 119 9519
TEST_TIME 120 9445

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-05-2022 22:40:40 to 27-05-2022 22:40:44.

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 1551643 1551643 0 0.00 0 20588 6 0 0
doc_list_biud 1551640 1551640 0 0.00 0 31022 28 0 0
sp_add_invoice_to_stock 5115 4914 201 3.92 31 274997 18284 0 201
sp_cancel_adding_invoice 6079 6079 0 0.00 5 58310 751 0 0
sp_cancel_client_order 1720 1720 0 0.00 4 10719 387 0 0
sp_cancel_customer_reserve 593856 593856 0 0.00 1 31023 58 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 14095 109 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 10008 162 0 0
sp_cancel_supplier_invoice 6680 6657 23 0.34 2 12275 702 0 23
sp_cancel_supplier_order 448 448 0 0.00 6 1104105 96184 0 0
sp_cancel_write_off 10073 10073 0 0.00 1 9501 113 0 0
sp_client_order 20334 20334 0 0.00 4 37346 366 0 0
sp_customer_reserve 452464 452464 0 0.00 0 81451 181 0 0
sp_fill_shopping_cart 31082 31082 0 0.00 1 10244 37 0 0
sp_fill_shopping_cart_clo_res 452454 452454 0 0.00 0 16466 15 0 0
sp_get_clo_for_invoice 4901 4901 0 0.00 0 23889 303 0 0
sp_kill_cost_storno 17238 17238 0 0.00 0 9999 52 0 0
sp_kill_qstorno_ret_qs2qd 1204890 1204890 0 0.00 0 28327 31 0 0
sp_kill_qty_storno 633491 633491 0 0.00 0 31022 67 0 0
sp_lock_selected_doc 20416 20416 0 0.00 0 14091 7 0 0
sp_make_cost_storno 27486 27486 0 0.00 0 20576 65 0 0
sp_multiply_rows_for_pdistr 27486 27486 0 0.00 0 10006 30 0 0
sp_multiply_rows_for_qdistr 20334 20334 0 0.00 0 15911 159 0 0
sp_pay_from_customer 7068 7068 0 0.00 4 11611 112 0 0
sp_pay_to_supplier 5760 5760 0 0.00 4 19422 127 0 0
sp_payment_common 12828 12828 0 0.00 1 9855 27 0 0
sp_qd_handle_on_cancel_clo 1720 1720 0 0.00 0 9482 346 0 0
sp_qd_handle_on_invoice_upd_sts 10980 10980 0 0.00 0 20598 318 0 0
sp_qd_handle_on_reserve_upd_sts 9757 9757 0 0.00 0 17935 66 0 0
sp_reserve_write_off 9757 9757 0 0.00 6 19676 214 0 0
sp_supplier_invoice 5351 5351 0 0.00 6 135888 3481 0 0
sp_supplier_order 5398 5398 0 0.00 6 29983 1272 0 0
srv_aggregate_perf_data 95 95 0 0.00 2986 129114 14927 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1716 190616 46040 0 0
srv_find_qd_qs_mism 1085612 1085612 0 0.00 0 16995 5 0 0
srv_make_invnt_saldo 552 552 0 0.00 42 74627 1100 0 0
srv_make_money_saldo 788 788 0 0.00 1 3931 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 115768 424477 257570 0 0
t$perf-abend:sp_add_invoice_to_stock 201 201 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 23 23 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4914 4914 0 0.00 0 14536 203 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 9131 159 0 0
t$perf-norm:sp_cancel_client_order 1720 1720 0 0.00 0 5693 21 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 6398 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 2884 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 5095 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 2448 24 0 0
t$perf-norm:sp_cancel_supplier_order 448 448 0 0.00 0 26500 1819 0 0
t$perf-norm:sp_cancel_write_off 960 960 0 0.00 0 2221 6 0 0
t$perf-norm:sp_client_order 20334 20334 0 0.00 0 10482 22 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 5713 13 0 0
t$perf-norm:sp_pay_from_customer 7068 7068 0 0.00 0 8103 13 0 0
t$perf-norm:sp_pay_to_supplier 5760 5760 0 0.00 0 3880 10 0 0
t$perf-norm:sp_reserve_write_off 9757 9757 0 0.00 0 12035 16 0 0
t$perf-norm:sp_supplier_invoice 5351 5351 0 0.00 0 8038 20 0 0
t$perf-norm:sp_supplier_order 5398 5398 0 0.00 0 4157 17 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 342 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 7656 127 0 0
t$perf-norm:srv_make_invnt_saldo 552 552 0 0.00 0 3633 26 0 0
t$perf-norm:srv_make_money_saldo 788 788 0 0.00 0 2991 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 35 13 0 0
v_add_invoice_to_stock 10202 9800 402 3.94 0 13904 23 0 402
v_all_customers 37318 37318 0 0.00 0 17 1 0 0
v_all_suppliers 29940 29940 0 0.00 0 19 1 0 0
v_all_wares 40668 40668 0 0.00 0 103 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 3542 9 0 0
v_cancel_client_order 3440 3440 0 0.00 0 10587 12 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 4434 14 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 4902 13 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 6666 26 0 0
v_cancel_supplier_invoice 898 852 46 5.12 0 437 13 0 46
v_cancel_supplier_order 896 896 0 0.00 0 3001 9 0 0
v_cancel_write_off 1920 1920 0 0.00 0 9454 22 0 0
v_max_id_clo_ord 5398 5398 0 0.00 0 1109 2 0 0
v_max_id_clo_res 939 939 0 0.00 0 2858 10 0 0
v_max_id_ord_sup 5350 5350 0 0.00 0 5143 4 0 0
v_max_non_paid_invoice 1538 1538 0 0.00 0 1947 4 0 0
v_max_non_paid_realizn 3658 3658 0 0.00 0 4471 5 0 0
v_min_id_clo_ord 5398 5398 0 0.00 0 3301 6 0 0
v_min_id_clo_res 939 939 0 0.00 0 1366 5 0 0
v_min_id_ord_sup 5350 5350 0 0.00 0 3675 7 0 0
v_min_non_paid_invoice 5760 5760 0 0.00 0 9794 19 0 0
v_min_non_paid_realizn 7068 7068 0 0.00 0 9849 23 0 0
v_our_firm 10170 10170 0 0.00 0 15 1 0 0
v_reserve_write_off 19514 19514 0 0.00 0 10591 10 0 0
x_lock_depdocs_on_canc_invoice 12310 12310 0 0.00 0 18843 118 0 0
x_lock_depdocs_on_canc_sup_ord 448 448 0 0.00 0 9213 343 0 0
x_make_qstorno_1000_1200 5398 5398 0 0.00 1 29970 1190 0 0
x_make_qstorno_1200_2000 5351 5351 0 0.00 3 135773 3351 0 0
x_make_qstorno_2100_3300 452464 452464 0 0.00 0 81449 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-05-2022 22:40:45 to 27-05-2022 22:40:49.

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:23 13 8192000000 8799288800 8810692608 5315664 0.064 2 1 2 0 5415264 56816 42784
20:09:24 15841 8192000000 9898475136 10204368896 975298944 10.638 100 92 183 1 1005302896 5960320 24043632
20:10:43 20020 8192000000 9942875536 10263400448 1024334176 11.114 100 86 174 0 1054347200 8092224 21920800
20:12:10 13068 8192000000 9980865072 10310062080 1062068416 11.476 100 86 172 0 1093188448 10278480 20841552
20:13:27 15231 8192000000 10018404432 10364637184 1108180416 11.915 100 84 168 0 1140366416 12269328 19916672
20:14:46 9778 8192000000 10067990352 10420834304 1149810544 12.308 100 80 160 1 1180932384 12285952 18835888
20:16:00 9002 8192000000 10114211536 10482765824 1196336016 12.742 100 75 150 0 1225628272 12782752 16509504
20:17:11 2945 8192000000 10155825712 10528505856 1242594528 13.170 100 80 160 1 1272505648 12206176 17704944
20:18:16 3363 8192000000 10184333328 10562936832 1265264864 13.378 100 88 175 2 1298424544 14349920 18809760
20:19:21 1607 8192000000 10211989088 10599100416 1297644576 13.674 100 94 188 0 1333963088 15080672 21237840
20:20:23 42853 8192000000 10209082496 10613022720 1308777360 13.775 100 41 82 1 1324309232 6031472 9500400
20:22:09 11989 8192000000 10195675952 10617405440 1315983264 13.840 100 19 38 0 1325349408 5002864 4363280
20:23:22 7619 8192000000 10219161888 10634366976 1327873488 13.948 100 21 42 0 1338879600 6334512 4671600
20:24:31 6468 8192000000 10227584608 10647023616 1334504992 14.008 100 20 40 0 1348567152 9951952 4110208
20:25:44 60256 8192000000 10235351168 10657525760 1343580080 14.090 100 30 60 2 1355944992 5182944 7181968
20:27:47 6524 8192000000 10220762400 10638077952 1328355120 13.952 100 26 52 1 1341223248 6812976 6055152
20:28:54 2768 8192000000 10206836448 10628112384 1321986320 13.895 100 22 43 0 1333216944 6306704 4923920
20:29:58 6220 8192000000 10163590272 10572566528 1240743760 13.153 96 22 44 3 1250776768 5349808 4683200
20:31:05 21590 8192000000 10120626672 10530361344 1169735968 12.494 92 23 46 0 1179886144 4493824 5656352
20:32:27 6221 8192000000 10145468640 10549800960 1233227408 13.084 98 29 58 1 1247121024 7487584 6406032
20:33:33 3168 8192000000 10159073664 10562547712 1265001248 13.376 100 25 52 0 1276166816 4800032 6365536
20:34:37 12090 8192000000 10184166368 10592964608 1290852064 13.612 100 18 37 0 1300354272 5925520 3576688
20:35:51 25710 8192000000 10178714944 10599641088 1284530672 13.554 99 17 34 0 1294091568 5525712 4035184
20:37:18 6596 8192000000 10203848032 10615693312 1305745168 13.747 100 36 73 0 1320921664 6374928 8801568
20:38:30 4068 8192000000 10231340976 10640478208 1327851344 13.948 100 41 82 1 1343881728 6666464 9363920
20:39:54 9945 8192000000 10257687712 10673438720 1350536896 14.152 100 60 118 2 1378394688 13284336 14573456
20:41:22 58803 8192000000 10284057856 10708586496 1367965632 14.309 100 43 86 1 1394933248 15832240 11135376
20:43:24 24832 8192000000 10295459312 10721492992 1382116320 14.435 100 38 76 0 1410571712 19835184 8620208
20:44:52 6966 8192000000 10311941536 10736742400 1378893456 14.407 99 44 86 0 1417108144 27740400 10474288
20:46:11 14252 8192000000 10318787440 10743123968 1388901088 14.496 100 52 103 0 1432225056 30900144 12423824
20:47:35 5821 8192000000 10338442496 10761719808 1407005568 14.657 100 62 124 1 1453274192 31796288 14472336
20:48:47 8193 8192000000 10341948576 10762457088 1401724736 14.610 100 61 121 0 1449346304 32978112 14643456
20:49:59 5612 8192000000 10341632560 10760413184 1399936960 14.594 100 57 114 0 1448327072 34204960 14185152
20:51:13 2891 8192000000 10360995088 10770890752 1403928768 14.630 99 84 167 1 1466966128 41637456 21399904
20:52:22 1949 8192000000 10360512784 10777292800 1408561072 14.671 99 66 130 2 1462819328 38115104 16143152
20:53:28 1716 8192000000 10351549744 10763173888 1392024384 14.524 100 76 151 0 1453953264 42882544 19046336
20:54:35 2545 8192000000 10374268672 10785849344 1406697648 14.655 100 82 164 0 1476195920 48600080 20898192
20:55:47 2499 8192000000 10364632576 10777370624 1396494816 14.564 100 79 159 3 1469267440 51193312 21579312
20:56:56 2353 8192000000 10346468496 10752286720 1383166464 14.445 100 83 168 0 1453739856 49932128 20641264
20:58:04 8863 8192000000 10330643360 10753232896 1383118720 14.444 100 46 92 1 1439069984 43731664 12219600
20:59:19 11185 8192000000 10319708720 10744602624 1381955712 14.434 100 28 56 0 1424549376 34788832 7804832
21:00:35 3315 8192000000 10340165312 10754138112 1381532608 14.430 100 74 146 1 1437365312 38524656 17308048
21:01:42 14685 8192000000 10314135568 10748379136 1378806528 14.406 100 32 65 0 1425704400 38330592 8567280
21:03:04 10524 8192000000 10339839728 10774491136 1394854096 14.549 100 32 64 1 1443763488 40114496 8794896
21:04:18 19295 8192000000 10332245584 10770542592 1380886032 14.424 99 25 50 0 1425901840 37794320 7221488
21:05:45 36438 8192000000 10290562016 10748710912 1338710352 14.046 98 16 32 2 1384133504 40444768 4978384
21:07:23 113737 8192000000 10254488512 10690457600 1254052624 13.275 92 13 26 0 1272438800 15091488 3294688
21:10:29 118850 8192000000 10247812672 10681651200 1316312048 13.843 97 33 66 0 1337261712 13393056 7556608
21:14:23 67717 8192000000 10268760704 10700906496 1333873104 14.002 98 34 67 2 1354501008 13045408 7582496
21:16:36 37184 8192000000 10277395248 10718945280 1367318032 14.303 100 21 42 1 1391510880 19330368 4862480
21:18:22 26055 8192000000 10277019472 10730680320 1373874016 14.362 100 45 88 1 1400209616 16051552 10284048
21:19:56 16480 8192000000 10274792608 10733678592 1372571968 14.350 100 8 16 0 1392712992 18472112 1668912
21:21:13 31010 8192000000 10281006608 10738745344 1381764176 14.432 100 20 40 0 1399330768 12971744 4594848
21:22:49 80351 8192000000 10274595424 10739490816 1385037808 14.462 100 25 50 0 1399471296 8883968 5549520
21:25:25 57685 8192000000 10299806128 10756796416 1392618144 14.529 100 33 65 1 1412261504 12694768 6948592
21:28:08 53946 8192000000 10296893488 10762940416 1392890560 14.532 100 8 16 0 1409497344 15095248 1511536
21:30:04 29538 8192000000 10291387248 10745573376 1381547248 14.430 99 17 34 0 1391761040 6655040 3558752
21:31:38 57870 8192000000 10306314928 10757144576 1373594864 14.359 98 26 52 3 1392334336 12244688 6494784
21:33:37 53718 8192000000 10286707840 10739318784 1326922288 13.939 95 12 23 0 1344727584 15033728 2771568
21:35:39 190616 8192000000 10266014496 10716000256 1170682288 12.503 84 10 20 0 1190347856 17661600 2003968
21:39:53 52600 8192000000 10279381488 10735161344 1362027968 14.256 99 12 24 0 1373776928 9239520 2509440
21:41:47 126685 8192000000 10275001712 10735480832 1322612320 13.900 96 27 54 1 1340183568 11354080 6217168
21:45:07 51207 8192000000 10255825168 10716753920 1333545312 13.999 97 18 36 0 1349402848 11819792 4037744
21:46:59 86931 8192000000 10255084976 10710102016 1339221968 14.050 98 12 24 0 1350100928 8083616 2795344
21:49:26 61345 8192000000 10266674704 10731196416 1371913664 14.344 100 13 26 0 1386964128 12062864 2987600
21:51:29 47247 8192000000 10252727328 10710081536 1327274240 13.943 97 13 28 1 1337134192 7023136 2836816
21:53:24 54386 8192000000 10270861600 10730070016 1354104176 14.184 99 18 36 0 1373991456 15586000 4301280
21:55:19 60471 8192000000 10277866720 10733330432 1352668528 14.171 98 21 42 3 1377306896 20016560 4621808
21:57:58 37014 8192000000 10267551680 10712387584 1316817424 13.848 96 19 38 1 1331359024 10114192 4427408
21:59:43 41483 8192000000 10245394112 10691264512 1315539824 13.836 98 41 82 3 1339239632 14068656 9631152
22:01:34 59412 8192000000 10264435920 10694025216 1310661120 13.792 97 19 37 0 1331559152 15966144 4931888
22:03:33 86816 8192000000 10265420416 10726076416 1353626944 14.180 100 14 28 0 1370108144 13791264 2689936
22:06:07 78173 8192000000 10288554336 10737405952 1362956784 14.264 99 27 54 2 1383741424 14808544 5976096
22:08:36 40945 8192000000 10274247440 10719686656 1360863376 14.245 98 22 38 2 1376372608 10749344 4759888
22:10:18 47963 8192000000 10286838976 10754588672 1376157184 14.382 99 11 22 0 1393796000 15167152 2471664
22:12:11 81747 8192000000 10282374400 10744360960 1357944288 14.219 98 13 27 0 1376654576 15752832 2957456
22:14:36 40917 8192000000 10294716688 10746318848 1371746560 14.343 99 24 48 1 1392683872 15122704 5814608
22:16:33 71233 8192000000 10299739440 10766520320 1389756592 14.504 100 17 32 1 1414064672 19578832 4729248
22:18:48 40265 8192000000 10308424416 10763194368 1387175472 14.481 100 29 58 2 1407414992 14162960 6076560
22:20:35 40478 8192000000 10314517120 10756534272 1364773280 14.280 98 37 74 0 1384956320 11764640 8418400
22:22:26 50819 8192000000 10285950144 10729246720 1329151424 13.959 96 22 44 0 1347102176 13269504 4681248
22:24:27 15136 8192000000 10298823776 10736762880 1349238144 14.141 98 54 108 2 1374550240 12564720 12747376
22:25:44 152887 8192000000 10264966912 10712633344 1330360880 13.970 97 33 64 1 1355646640 18202400 7083360
22:29:19 39524 8192000000 10255823680 10718289920 1352983888 14.174 99 6 12 2 1361335952 6744624 1607440
22:31:09 97250 8192000000 10288003552 10748088320 1380131120 14.418 100 13 27 0 1391009120 7757856 3120144
22:33:53 95072 8192000000 10277695840 10739748864 1357453696 14.214 98 11 22 0 1374956960 15037696 2465568
22:36:28 50877 8192000000 10281286944 10748370944 1381517424 14.430 100 22 44 0 1399429840 13604592 4307824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-05-2022 22:40:51 to 27-05-2022 22:41:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 201 v_add_invoice_to_stock 335544517
except 23 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-05-2022 22:41:07 to 27-05-2022 22:41:09.

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          236598
MON$OLDEST_ACTIVE               237353
MON$OLDEST_SNAPSHOT             237353
MON$NEXT_TRANSACTION            237353
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                       2038752
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 ---
:16: 
:17: mscs-test-161.budzdorov.ru    Fri May 27 22:38:27 2022
:18:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:19: 
:20: 
:21: mscs-test-161.budzdorov.ru    Fri May 27 22:38:41 2022
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Fri May 27 22:38:44 2022
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:27: 
--- end of diff output ---

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

27.05.22 22:41:10. 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-27 22:41:12 No severe PSQL-related problems occured
27.05.22 22:41:13. End of report.