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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16718536 kB
MemAvailable 31556136 kB
Buffers 0 kB
Cached 14971040 kB
SwapCached 2228 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-04-03 22:38:12
DTS_END                         2022-04-03 22:38:12.0430
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/43172
TRN_ID                          240497
ATT_ID                          718
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: 9449.67 24085 1133961
customer order: creation 175.03 345 21003
customer order: refuse 14.96 315 1795
order to supplier: creation 47.61 1363 5713
order to supplier: removal 3.58 98737 429
invoice (draft): creation 47.09 3270 5651
invoice (draft): removal 54.48 781 6538
invoice accept: apply 42.73 18381 5128
invoice accept: cancel 49.05 817 5886
customer reserve: creation 3905.73 184 468687
customer reserve: removal 4807.73 57 576928
realization accept: apply 85.13 164 10215
realization accept: cancel 82.12 127 9854
payment from customer: creation 61.94 113 7433
payment from customer: removal 5.80 82 696
payment to supplier: creation 50.43 111 6051
payment to supplier: removal 3.44 61 413
service: total inventory turnovers 5.03 1136 603
service: total monetary turnovers 7.02 34 842
service: aggregate perf. data 0.77 24105 92
service: refresh index statistics 0.03 331513 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 03-04-2022 22:39:13 to 03-04-2022 22:39:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6272
TEST_TIME 2 5437
TEST_TIME 3 5439
TEST_TIME 4 5747
TEST_TIME 5 6217
TEST_TIME 6 6631
TEST_TIME 7 6296
TEST_TIME 8 6282
TEST_TIME 9 6840
TEST_TIME 10 6957
TEST_TIME 11 7537
TEST_TIME 12 7615
TEST_TIME 13 7897
TEST_TIME 14 8133
TEST_TIME 15 8471
TEST_TIME 16 8580
TEST_TIME 17 8821
TEST_TIME 18 8721
TEST_TIME 19 9110
TEST_TIME 20 9446
TEST_TIME 21 9188
TEST_TIME 22 9067
TEST_TIME 23 9405
TEST_TIME 24 9719
TEST_TIME 25 9978
TEST_TIME 26 10330
TEST_TIME 27 11353
TEST_TIME 28 11108
TEST_TIME 29 11082
TEST_TIME 30 11123
TEST_TIME 31 10925
TEST_TIME 32 10673
TEST_TIME 33 10686
TEST_TIME 34 10624
TEST_TIME 35 10452
TEST_TIME 36 10397
TEST_TIME 37 10329
TEST_TIME 38 10362
TEST_TIME 39 10300
TEST_TIME 40 10322
TEST_TIME 41 10237
TEST_TIME 42 10280
TEST_TIME 43 10196
TEST_TIME 44 10106
TEST_TIME 45 10031
TEST_TIME 46 9922
TEST_TIME 47 9956
TEST_TIME 48 9830
TEST_TIME 49 9737
TEST_TIME 50 9771
TEST_TIME 51 9714
TEST_TIME 52 9756
TEST_TIME 53 9760
TEST_TIME 54 9735
TEST_TIME 55 9838
TEST_TIME 56 9813
TEST_TIME 57 10038
TEST_TIME 58 10034
TEST_TIME 59 9971
TEST_TIME 60 10054
TEST_TIME 61 10114
TEST_TIME 62 10153
TEST_TIME 63 10001
TEST_TIME 64 9936
TEST_TIME 65 9901
TEST_TIME 66 9792
TEST_TIME 67 9760
TEST_TIME 68 9778
TEST_TIME 69 9807
TEST_TIME 70 9768
TEST_TIME 71 9719
TEST_TIME 72 9761
TEST_TIME 73 9747
TEST_TIME 74 9796
TEST_TIME 75 9789
TEST_TIME 76 9754
TEST_TIME 77 9759
TEST_TIME 78 9860
TEST_TIME 79 9933
TEST_TIME 80 9915
TEST_TIME 81 9918
TEST_TIME 82 9819
TEST_TIME 83 9790
TEST_TIME 84 9714
TEST_TIME 85 9701
TEST_TIME 86 9653
TEST_TIME 87 9669
TEST_TIME 88 9645
TEST_TIME 89 9590
TEST_TIME 90 9600
TEST_TIME 91 9592
TEST_TIME 92 9615
TEST_TIME 93 9606
TEST_TIME 94 9606
TEST_TIME 95 9613
TEST_TIME 96 9616
TEST_TIME 97 9656
TEST_TIME 98 9720
TEST_TIME 99 9712
TEST_TIME 100 9758
TEST_TIME 101 9770
TEST_TIME 102 9736
TEST_TIME 103 9723
TEST_TIME 104 9792
TEST_TIME 105 9772
TEST_TIME 106 9716
TEST_TIME 107 9681
TEST_TIME 108 9633
TEST_TIME 109 9659
TEST_TIME 110 9628
TEST_TIME 111 9579
TEST_TIME 112 9563
TEST_TIME 113 9511
TEST_TIME 114 9448
TEST_TIME 115 9492
TEST_TIME 116 9455
TEST_TIME 117 9445
TEST_TIME 118 9474
TEST_TIME 119 9527
TEST_TIME 120 9457

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-04-2022 22:39:17 to 03-04-2022 22:39:20.

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 1567811 1567811 0 0.00 0 26733 6 0 0
doc_list_biud 1567810 1567810 0 0.00 0 25358 27 0 0
sp_add_invoice_to_stock 5213 5128 85 1.63 31 189868 18382 0 85
sp_cancel_adding_invoice 5886 5886 0 0.00 5 63374 818 0 0
sp_cancel_client_order 1795 1795 0 0.00 4 10352 315 0 0
sp_cancel_customer_reserve 576928 576928 0 0.00 1 17111 58 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 3 7498 83 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 3239 62 0 0
sp_cancel_supplier_invoice 6545 6538 7 0.10 2 15713 782 0 7
sp_cancel_supplier_order 429 429 0 0.00 5 897909 98738 0 0
sp_cancel_write_off 9854 9854 0 0.00 1 16359 128 0 0
sp_client_order 21003 21003 0 0.00 3 51173 345 0 0
sp_customer_reserve 468687 468687 0 0.00 0 141628 184 0 0
sp_fill_shopping_cart 32351 32351 0 0.00 1 16337 36 0 0
sp_fill_shopping_cart_clo_res 468671 468671 0 0.00 0 23328 14 0 0
sp_get_clo_for_invoice 5108 5108 0 0.00 0 16078 269 0 0
sp_kill_cost_storno 16849 16849 0 0.00 0 7737 63 0 0
sp_kill_qstorno_ret_qs2qd 1170677 1170677 0 0.00 0 17075 31 0 0
sp_kill_qty_storno 616753 616753 0 0.00 0 25358 68 0 0
sp_lock_selected_doc 21195 21195 0 0.00 0 12080 5 0 0
sp_make_cost_storno 28807 28807 0 0.00 0 16043 45 0 0
sp_multiply_rows_for_pdistr 28807 28807 0 0.00 0 6072 25 0 0
sp_multiply_rows_for_qdistr 21003 21003 0 0.00 0 10613 165 0 0
sp_pay_from_customer 7433 7433 0 0.00 4 16561 114 0 0
sp_pay_to_supplier 6051 6051 0 0.00 4 12306 112 0 0
sp_payment_common 13484 13484 0 0.00 1 8307 23 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 0 10345 284 0 0
sp_qd_handle_on_invoice_upd_sts 10994 10994 0 0.00 0 25357 331 0 0
sp_qd_handle_on_reserve_upd_sts 10215 10215 0 0.00 0 13298 59 0 0
sp_reserve_write_off 10215 10215 0 0.00 7 13318 165 0 0
sp_supplier_invoice 5651 5651 0 0.00 7 88079 3270 0 0
sp_supplier_order 5713 5713 0 0.00 5 44989 1363 0 0
srv_aggregate_perf_data 92 92 0 0.00 2791 339997 24106 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2060 148865 47481 0 0
srv_find_qd_qs_mism 1084999 1084999 0 0.00 0 26733 5 0 0
srv_make_invnt_saldo 603 603 0 0.00 43 69931 1136 0 0
srv_make_money_saldo 842 842 0 0.00 1 4954 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 222982 516369 331514 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 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 5130 5130 0 0.00 0 27576 190 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 9971 135 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 1689 10 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 5117 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 2680 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 1405 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 547 10 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 24851 1759 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 1823 7 0 0
t$perf-norm:sp_client_order 21003 21003 0 0.00 0 7668 19 0 0
t$perf-norm:sp_customer_reserve 1045 1045 0 0.00 0 4530 14 0 0
t$perf-norm:sp_pay_from_customer 7433 7433 0 0.00 0 8572 10 0 0
t$perf-norm:sp_pay_to_supplier 6051 6051 0 0.00 0 3917 10 0 0
t$perf-norm:sp_reserve_write_off 10215 10215 0 0.00 0 7904 12 0 0
t$perf-norm:sp_supplier_invoice 5651 5651 0 0.00 0 13943 23 0 0
t$perf-norm:sp_supplier_order 5713 5713 0 0.00 0 2873 15 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 114 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 56 1 0 0
t$perf-norm:srv_make_invnt_saldo 603 603 0 0.00 0 5146 37 0 0
t$perf-norm:srv_make_money_saldo 842 842 0 0.00 0 964 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10384 10214 170 1.63 0 9732 21 0 170
v_all_customers 38544 38544 0 0.00 0 19 1 0 0
v_all_suppliers 31552 31552 0 0.00 0 23 1 0 0
v_all_wares 42004 42004 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 4979 20 0 0
v_cancel_client_order 3590 3590 0 0.00 0 4203 6 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 1059 5 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 4246 10 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 818 5 0 0
v_cancel_supplier_invoice 878 864 14 1.59 0 3601 19 0 14
v_cancel_supplier_order 858 858 0 0.00 0 1504 7 0 0
v_cancel_write_off 2002 2002 0 0.00 0 2605 12 0 0
v_max_id_clo_ord 5713 5713 0 0.00 0 173 1 0 0
v_max_id_clo_res 1045 1045 0 0.00 0 2006 14 0 0
v_max_id_ord_sup 5635 5635 0 0.00 0 4392 5 0 0
v_max_non_paid_invoice 1622 1622 0 0.00 0 3239 4 0 0
v_max_non_paid_realizn 3942 3942 0 0.00 0 3943 6 0 0
v_min_id_clo_ord 5713 5713 0 0.00 0 942 4 0 0
v_min_id_clo_res 1045 1045 0 0.00 0 2188 6 0 0
v_min_id_ord_sup 5634 5634 0 0.00 0 10100 9 0 0
v_min_non_paid_invoice 6051 6051 0 0.00 0 6563 14 0 0
v_min_non_paid_realizn 7433 7433 0 0.00 0 8305 16 0 0
v_our_firm 10442 10442 0 0.00 0 25 1 0 0
v_reserve_write_off 20430 20430 0 0.00 0 6866 7 0 0
x_lock_depdocs_on_canc_invoice 11992 11992 0 0.00 0 13956 118 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 19798 406 0 0
x_make_qstorno_1000_1200 5713 5713 0 0.00 2 44980 1283 0 0
x_make_qstorno_1200_2000 5651 5651 0 0.00 3 85622 3145 0 0
x_make_qstorno_2100_3300 468687 468687 0 0.00 0 141626 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-04-2022 22:39:21 to 03-04-2022 22:39:25.

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:15 7 8192000000 8806977552 8820858880 11344032 0.138 3 3 4 0 11527952 76800 107120
20:09:17 23477 8192000000 9888709888 10196619264 967235568 10.560 100 87 173 1 996792528 6165664 23391296
20:10:44 28851 8192000000 9943220192 10263101440 1022629152 11.097 100 90 179 1 1053972352 8430016 22913184
20:12:17 17207 8192000000 9978456368 10308083712 1060376672 11.460 100 88 176 1 1091940064 9476864 22086528
20:13:42 11563 8192000000 10019143904 10361839616 1108055616 11.914 100 81 161 1 1138656160 11265264 19335280
20:15:00 6296 8192000000 10076175696 10434682880 1166358592 12.463 100 79 157 1 1196378736 12075056 17945088
20:16:09 4670 8192000000 10147066720 10515914752 1227344016 13.030 100 90 179 1 1260383216 13102720 19936480
20:17:17 2494 8192000000 10169236896 10544885760 1258129216 13.313 100 85 167 1 1290616416 14544480 17942720
20:18:21 1771 8192000000 10197397360 10577088512 1283558976 13.545 100 89 178 0 1316812032 13295040 19958016
20:19:25 1323 8192000000 10219496288 10605682688 1301713664 13.711 100 91 183 0 1334432368 12392336 20326368
20:20:26 3406 8192000000 10199788288 10605076480 1305070176 13.741 100 45 91 0 1325575760 10336240 10169344
20:21:31 4675 8192000000 10197384208 10611089408 1314924400 13.831 100 29 57 1 1327449600 6185744 6339456
20:22:37 8694 8192000000 10203728848 10622271488 1320187408 13.878 100 17 34 0 1329200144 5069152 3943584
20:23:46 12857 8192000000 10217005504 10632941568 1330252192 13.969 100 38 77 2 1344903760 5823408 8828160
20:24:59 28289 8192000000 10247836976 10650521600 1333525456 13.999 100 67 136 0 1355686784 6118704 16042624
20:26:28 5973 8192000000 10237605904 10655588352 1340388400 14.061 100 38 76 1 1356409776 7310928 8710448
20:27:39 10825 8192000000 10214295760 10629599232 1291682768 13.620 97 27 53 1 1303119248 5414784 6021696
20:28:55 9143 8192000000 10210776464 10628800512 1321437072 13.890 100 19 38 1 1332120784 6453248 4230464
20:30:08 4911 8192000000 10165250736 10570940416 1270111072 13.423 98 21 42 0 1281048256 6210336 4726848
20:31:14 6500 8192000000 10140533488 10539896832 1224192256 13.000 97 17 34 0 1232951312 5099952 3659104
20:32:23 59194 8192000000 10138765424 10532003840 1188115024 12.666 95 35 69 2 1200902864 4975520 7812320
20:34:30 7486 8192000000 10166789184 10569297920 1261223520 13.341 99 22 44 0 1272592032 6555552 4812960
20:35:40 11376 8192000000 10184531136 10596278272 1289401488 13.599 100 25 49 0 1305416208 9789600 6225120
20:36:51 8588 8192000000 10193687792 10604273664 1298734304 13.684 100 18 36 3 1308648080 5145056 4768720
20:38:03 56657 8192000000 10221836784 10631229440 1314324080 13.825 100 42 80 2 1328117712 5050640 8742992
20:40:04 21131 8192000000 10251518288 10662600704 1341777040 14.073 100 54 106 1 1364158224 10408032 11973152
20:41:30 18297 8192000000 10266734544 10683084800 1355050064 14.193 100 46 92 1 1378399232 13064624 10284544
20:42:55 34939 8192000000 10284242080 10701987840 1370509520 14.332 100 44 88 0 1398909216 18159280 10240416
20:44:33 15237 8192000000 10294903104 10718412800 1382417568 14.438 100 46 91 1 1409630336 15940096 11272672
20:45:58 4569 8192000000 10326668976 10743324672 1395621632 14.556 100 64 129 0 1432764528 22415312 14727584
20:47:06 3606 8192000000 10340884896 10755481600 1404375776 14.634 100 83 166 3 1452615216 28188160 20051280
20:48:15 2190 8192000000 10325477744 10736717824 1370816224 14.334 98 82 165 2 1418076208 28066048 19193936
20:49:21 3057 8192000000 10355088096 10765660160 1393519696 14.537 99 79 158 0 1451132912 38558320 19054896
20:50:29 2057 8192000000 10342346128 10752991232 1397477680 14.573 100 77 153 2 1451970256 35765776 18726800
20:51:42 2646 8192000000 10368760368 10783694848 1411577360 14.698 100 77 151 3 1468829536 38228736 19023440
20:52:49 2749 8192000000 10358795872 10775810048 1404294720 14.633 100 81 157 3 1467845760 43864592 19686448
20:53:56 2506 8192000000 10373144464 10784460800 1412884976 14.710 100 92 184 0 1479922304 44357200 22680128
20:55:04 2173 8192000000 10332990528 10748506112 1367395056 14.304 99 58 115 0 1430322656 47908816 15018784
20:56:12 3510 8192000000 10326897536 10730614784 1358825952 14.227 100 77 154 1 1428628000 49995248 19806800
20:57:20 3454 8192000000 10357875728 10766295040 1381115440 14.427 100 75 150 1 1457370336 56354672 19900224
20:58:32 4104 8192000000 10355465072 10770853888 1390047552 14.506 100 62 124 0 1455352224 49097136 16207536
20:59:47 13415 8192000000 10351314480 10770542592 1392033104 14.524 100 49 98 2 1452201232 46649008 13519120
21:01:02 12683 8192000000 10346913728 10770997248 1381910368 14.434 100 50 101 1 1454323632 58297968 14115296
21:02:33 20466 8192000000 10314215808 10750181376 1365699440 14.288 100 28 55 2 1424717488 50697264 8320784
21:03:55 35798 8192000000 10290146224 10725015552 1331219664 13.978 98 16 32 0 1384660048 48663712 4776672
21:05:32 21879 8192000000 10264034464 10710806528 1345541776 14.107 99 14 28 0 1377578736 27395456 4641504
21:07:01 79926 8192000000 10285102320 10720980992 1321905408 13.894 97 20 40 1 1341102528 14666288 4530832
21:09:21 115536 8192000000 10272638800 10728370176 1371801264 14.343 100 25 50 0 1385172928 7550432 5821232
21:12:18 148865 8192000000 10277599840 10727268352 1307739712 13.766 95 15 30 1 1319185440 8371552 3074176
21:15:47 36189 8192000000 10303610784 10752000000 1387166080 14.481 100 35 70 0 1412007360 16296080 8545200
21:17:42 52778 8192000000 10272293904 10735710208 1356312000 14.204 98 12 25 0 1382550768 23483536 2755232
21:19:41 52997 8192000000 10284741104 10749493248 1380343472 14.420 100 12 24 1 1403208080 19775040 3089568
21:21:35 30285 8192000000 10284006832 10742210560 1380156176 14.418 100 18 36 0 1391132112 7013696 3962240
21:23:22 85276 8192000000 10282575568 10735542272 1375590272 14.377 99 16 32 0 1392155376 12618544 3946560
21:26:06 52882 8192000000 10275639712 10744967168 1356305600 14.204 98 2 4 0 1368738352 12168160 264592
21:27:59 56253 8192000000 10289655488 10749730816 1385470656 14.465 100 20 40 2 1399399696 9441984 4487056
21:29:56 46877 8192000000 10308081392 10763632640 1366768160 14.298 98 26 52 0 1387085584 14484800 5832624
21:31:44 51605 8192000000 10310885824 10765336576 1386198960 14.472 99 16 32 1 1409684000 19485504 3999536
21:33:46 34312 8192000000 10323750992 10785546240 1406314000 14.651 100 15 30 1 1431207152 20709152 4184000
21:35:31 76417 8192000000 10296666640 10761539584 1369333264 14.321 98 4 8 0 1383090672 12976480 780928
21:37:50 42606 8192000000 10306693872 10755305472 1363502080 14.269 98 43 86 0 1392646576 19557616 9586880
21:39:32 135852 8192000000 10270854400 10717782016 1302632656 13.719 94 16 32 0 1318099040 12233680 3232704
21:42:49 77929 8192000000 10286488224 10751340544 1370618208 14.333 99 13 26 0 1379848816 6570736 2659872
21:46:08 64554 8192000000 10277024048 10728673280 1366729904 14.298 99 16 32 1 1380280576 9916464 3634208
21:48:13 25262 8192000000 10278751760 10720313344 1345499168 14.107 98 37 72 1 1364793200 10881488 8412544
21:49:45 53152 8192000000 10265694560 10710044672 1310321952 13.789 96 36 72 1 1334611648 15386688 8903008
21:51:43 46437 8192000000 10279762976 10727477248 1347055024 14.121 98 28 57 1 1366779296 13511328 6212944
21:53:37 33549 8192000000 10284436592 10721366016 1338055072 14.040 98 28 56 1 1354557568 10173696 6328800
21:55:13 14099 8192000000 10294247344 10724253696 1365282064 14.285 100 43 86 0 1385762448 11219424 9260960
21:56:29 69996 8192000000 10294978432 10761728000 1353969696 14.183 98 11 22 0 1373168848 16596112 2603040
21:58:39 64127 8192000000 10297494592 10749472768 1350591536 14.153 97 10 20 0 1368177504 15539856 2046112
22:00:44 99557 8192000000 10275005488 10725056512 1356805056 14.209 98 20 40 1 1371713776 9735152 5173568
22:03:50 43691 8192000000 10293451248 10758586368 1394196480 14.543 100 14 28 0 1407553984 10102176 3255328
22:05:35 84147 8192000000 10277043712 10733686784 1323735984 13.911 95 13 26 0 1337122256 10491408 2894864
22:08:02 56162 8192000000 10273839904 10736140288 1367038768 14.301 98 19 38 0 1380874800 9617360 4218672
22:09:59 45593 8192000000 10283793952 10739818496 1359532464 14.233 97 14 28 1 1370583472 8100176 2950832
22:11:47 76668 8192000000 10322677456 10764361728 1398033520 14.577 100 22 44 0 1416337008 12819600 5483888
22:14:10 54735 8192000000 10318925952 10769707008 1397620976 14.574 99 24 48 2 1418830256 15716416 5492864
22:16:11 104216 8192000000 10305600544 10758840320 1348605712 14.135 96 7 14 0 1362730016 12481184 1643120
22:19:10 71274 8192000000 10319388144 10775470080 1361871520 14.254 97 15 30 0 1381687728 16960976 2855232
22:21:25 68386 8192000000 10314306784 10785980416 1366806256 14.298 97 21 42 0 1391343648 19954960 4582432
22:23:33 138426 8192000000 10284295520 10743930880 1345528432 14.107 96 17 34 1 1357973712 8586912 3858368
22:26:52 9371 8192000000 10292460880 10740047872 1380832016 14.424 100 33 66 0 1397372432 9277456 7262960
22:28:02 68512 8192000000 10269405920 10735005696 1342125392 14.077 97 10 20 0 1351784208 7665824 1992992
22:30:11 58324 8192000000 10281405152 10739601408 1371239328 14.338 99 11 22 1 1381825408 7998512 2587568
22:32:13 63220 8192000000 10292713920 10746818560 1361663056 14.252 98 17 34 0 1385980832 20229568 4088208
22:34:17 43794 8192000000 10281056960 10731520000 1358690192 14.226 98 22 45 0 1377064688 13461616 4912880
22:36:19 117774 8192000000 9565270144 9913110528 684078672 7.706 50 11 22 0 695291280 8904224 2308384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-04-2022 22:39:27 to 03-04-2022 22:39:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 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:02, from 03-04-2022 22:39:43 to 03-04-2022 22:39:45.

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          240346
MON$OLDEST_ACTIVE               240792
MON$OLDEST_SNAPSHOT             240792
MON$NEXT_TRANSACTION            240792
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                       2023232
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:976: 
:977: mscs-test-161.budzdorov.ru    Sun Apr  3 22:38:20 2022
:978:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:979: 
:980: 
:981: mscs-test-161.budzdorov.ru    Sun Apr  3 22:38:20 2022
:982:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:983: 
--- end of diff output ---

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

03.04.22 22:39:46. 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-04-03 22:39:48 No severe PSQL-related problems occured
03.04.22 22:39:50. End of report.