Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.03.2022 01: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 16662176 kB
MemAvailable 31596716 kB
Buffers 0 kB
Cached 15021672 kB
SwapCached 2152 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-03-27 01:38:07
DTS_END                         2022-03-27 01:38:07.2860
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/34838
TRN_ID                          243053
ATT_ID                          657
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: 9507.18 20007 1140862
customer order: creation 176.45 342 21174
customer order: refuse 15.05 333 1806
order to supplier: creation 46.32 1262 5558
order to supplier: removal 3.42 112205 410
invoice (draft): creation 47.53 3762 5703
invoice (draft): removal 53.40 844 6408
invoice accept: apply 42.99 18181 5159
invoice accept: cancel 48.31 843 5797
customer reserve: creation 4031.17 177 483740
customer reserve: removal 4741.48 64 568978
realization accept: apply 86.71 178 10405
realization accept: cancel 81.65 114 9798
payment from customer: creation 61.48 118 7377
payment from customer: removal 5.65 89 678
payment to supplier: creation 49.78 128 5974
payment to supplier: removal 3.33 91 400
service: total inventory turnovers 4.62 1255 554
service: total monetary turnovers 6.93 36 831
service: aggregate perf. data 0.90 21005 108
service: refresh index statistics 0.03 239113 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-03-2022 01:38:59 to 27-03-2022 01:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4556
TEST_TIME 2 5406
TEST_TIME 3 5982
TEST_TIME 4 6254
TEST_TIME 5 6209
TEST_TIME 6 6050
TEST_TIME 7 5691
TEST_TIME 8 5578
TEST_TIME 9 5482
TEST_TIME 10 5735
TEST_TIME 11 6095
TEST_TIME 12 6577
TEST_TIME 13 6716
TEST_TIME 14 6786
TEST_TIME 15 6895
TEST_TIME 16 7445
TEST_TIME 17 7299
TEST_TIME 18 7331
TEST_TIME 19 7852
TEST_TIME 20 7745
TEST_TIME 21 8014
TEST_TIME 22 8796
TEST_TIME 23 8966
TEST_TIME 24 8760
TEST_TIME 25 9270
TEST_TIME 26 9869
TEST_TIME 27 10891
TEST_TIME 28 11400
TEST_TIME 29 11463
TEST_TIME 30 11480
TEST_TIME 31 11387
TEST_TIME 32 11171
TEST_TIME 33 11202
TEST_TIME 34 11209
TEST_TIME 35 10943
TEST_TIME 36 10704
TEST_TIME 37 10675
TEST_TIME 38 10627
TEST_TIME 39 10499
TEST_TIME 40 10434
TEST_TIME 41 10342
TEST_TIME 42 10205
TEST_TIME 43 10088
TEST_TIME 44 10322
TEST_TIME 45 10248
TEST_TIME 46 10379
TEST_TIME 47 10293
TEST_TIME 48 10173
TEST_TIME 49 10124
TEST_TIME 50 9980
TEST_TIME 51 10087
TEST_TIME 52 9995
TEST_TIME 53 9931
TEST_TIME 54 9902
TEST_TIME 55 9763
TEST_TIME 56 9729
TEST_TIME 57 9697
TEST_TIME 58 9655
TEST_TIME 59 9628
TEST_TIME 60 9714
TEST_TIME 61 9673
TEST_TIME 62 9848
TEST_TIME 63 9911
TEST_TIME 64 9842
TEST_TIME 65 9921
TEST_TIME 66 9918
TEST_TIME 67 9895
TEST_TIME 68 9883
TEST_TIME 69 10073
TEST_TIME 70 10051
TEST_TIME 71 10129
TEST_TIME 72 10038
TEST_TIME 73 9964
TEST_TIME 74 9930
TEST_TIME 75 9918
TEST_TIME 76 9877
TEST_TIME 77 9899
TEST_TIME 78 9838
TEST_TIME 79 9790
TEST_TIME 80 9782
TEST_TIME 81 9737
TEST_TIME 82 9699
TEST_TIME 83 9644
TEST_TIME 84 9601
TEST_TIME 85 9624
TEST_TIME 86 9623
TEST_TIME 87 9582
TEST_TIME 88 9618
TEST_TIME 89 9694
TEST_TIME 90 9699
TEST_TIME 91 9710
TEST_TIME 92 9713
TEST_TIME 93 9742
TEST_TIME 94 9748
TEST_TIME 95 9743
TEST_TIME 96 9755
TEST_TIME 97 9794
TEST_TIME 98 9790
TEST_TIME 99 9820
TEST_TIME 100 9849
TEST_TIME 101 9775
TEST_TIME 102 9720
TEST_TIME 103 9673
TEST_TIME 104 9680
TEST_TIME 105 9651
TEST_TIME 106 9637
TEST_TIME 107 9615
TEST_TIME 108 9575
TEST_TIME 109 9513
TEST_TIME 110 9476
TEST_TIME 111 9444
TEST_TIME 112 9477
TEST_TIME 113 9492
TEST_TIME 114 9502
TEST_TIME 115 9493
TEST_TIME 116 9518
TEST_TIME 117 9537
TEST_TIME 118 9520
TEST_TIME 119 9582
TEST_TIME 120 9508

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-03-2022 01:39:03 to 27-03-2022 01:39:06.

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 1587988 1587988 0 0.00 0 16879 6 0 0
doc_list_biud 1587987 1587987 0 0.00 0 67517 29 0 0
sp_add_invoice_to_stock 5302 5159 143 2.69 43 158246 18182 0 143
sp_cancel_adding_invoice 5797 5797 0 0.00 4 63172 843 0 0
sp_cancel_client_order 1806 1806 0 0.00 4 8803 333 0 0
sp_cancel_customer_reserve 568978 568978 0 0.00 1 23909 64 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 3 10415 90 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 5649 92 0 0
sp_cancel_supplier_invoice 6415 6408 7 0.10 1 67589 845 0 7
sp_cancel_supplier_order 410 410 0 0.00 8 1253663 112205 0 0
sp_cancel_write_off 9798 9798 0 0.00 1 9190 114 0 0
sp_client_order 21174 21174 0 0.00 3 13719 342 0 0
sp_customer_reserve 483740 483740 0 0.00 0 62804 178 0 0
sp_fill_shopping_cart 32408 32408 0 0.00 1 15100 39 0 0
sp_fill_shopping_cart_clo_res 483735 483735 0 0.00 0 14462 13 0 0
sp_get_clo_for_invoice 5153 5153 0 0.00 0 15861 289 0 0
sp_kill_cost_storno 16673 16673 0 0.00 0 8399 58 0 0
sp_kill_qstorno_ret_qs2qd 1154572 1154572 0 0.00 0 67515 34 0 0
sp_kill_qty_storno 608751 608751 0 0.00 0 67516 74 0 0
sp_lock_selected_doc 21459 21459 0 0.00 0 5620 5 0 0
sp_make_cost_storno 28906 28906 0 0.00 0 10612 51 0 0
sp_multiply_rows_for_pdistr 28905 28905 0 0.00 0 9652 33 0 0
sp_multiply_rows_for_qdistr 21174 21174 0 0.00 0 11489 146 0 0
sp_pay_from_customer 7377 7377 0 0.00 4 10553 119 0 0
sp_pay_to_supplier 5974 5974 0 0.00 4 10134 129 0 0
sp_payment_common 13351 13351 0 0.00 1 8762 31 0 0
sp_qd_handle_on_cancel_clo 1806 1806 0 0.00 0 8798 307 0 0
sp_qd_handle_on_invoice_upd_sts 10947 10947 0 0.00 0 19178 337 0 0
sp_qd_handle_on_reserve_upd_sts 10404 10404 0 0.00 0 19394 60 0 0
sp_reserve_write_off 10405 10405 0 0.00 6 19444 178 0 0
sp_supplier_invoice 5703 5703 0 0.00 6 80167 3762 0 0
sp_supplier_order 5558 5558 0 0.00 5 28834 1262 0 0
srv_aggregate_perf_data 108 108 0 0.00 2539 513576 21005 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2542 134726 41445 0 0
srv_find_qd_qs_mism 1091166 1091166 0 0.00 0 16877 6 0 0
srv_make_invnt_saldo 554 554 0 0.00 31 152380 1256 0 0
srv_make_money_saldo 831 831 0 0.00 0 7432 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 163295 306502 239113 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 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 5159 5159 0 0.00 0 17457 154 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 5374 125 0 0
t$perf-norm:sp_cancel_client_order 1806 1806 0 0.00 0 8520 18 0 0
t$perf-norm:sp_cancel_customer_reserve 775 775 0 0.00 0 1787 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 5578 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 5303 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 2546 20 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 21091 1536 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 2675 10 0 0
t$perf-norm:sp_client_order 21174 21174 0 0.00 0 5117 16 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 329 3 0 0
t$perf-norm:sp_pay_from_customer 7377 7377 0 0.00 0 3000 6 0 0
t$perf-norm:sp_pay_to_supplier 5974 5974 0 0.00 0 4978 9 0 0
t$perf-norm:sp_reserve_write_off 10405 10405 0 0.00 0 6254 12 0 0
t$perf-norm:sp_supplier_invoice 5703 5703 0 0.00 0 5473 16 0 0
t$perf-norm:sp_supplier_order 5558 5558 0 0.00 0 5884 19 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 349 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1393 23 0 0
t$perf-norm:srv_make_invnt_saldo 554 554 0 0.00 0 1853 29 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 1091 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10586 10300 286 2.70 0 4487 19 0 286
v_all_customers 39228 39228 0 0.00 0 31 1 0 0
v_all_suppliers 30898 30898 0 0.00 0 15 1 0 0
v_all_wares 42348 42348 0 0.00 0 55 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 147 5 0 0
v_cancel_client_order 3612 3612 0 0.00 0 1372 4 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 4402 12 0 0
v_cancel_customer_reserve 1550 1550 0 0.00 0 1379 5 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 2548 12 0 0
v_cancel_supplier_invoice 860 846 14 1.62 0 6003 23 0 14
v_cancel_supplier_order 820 820 0 0.00 0 248 3 0 0
v_cancel_write_off 1986 1986 0 0.00 0 915 11 0 0
v_max_id_clo_ord 5556 5556 0 0.00 0 1317 2 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 1528 11 0 0
v_max_id_ord_sup 5677 5677 0 0.00 0 5898 6 0 0
v_max_non_paid_invoice 1758 1758 0 0.00 0 384 2 0 0
v_max_non_paid_realizn 3753 3753 0 0.00 0 2674 5 0 0
v_min_id_clo_ord 5556 5556 0 0.00 0 3058 7 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 5034 20 0 0
v_min_id_ord_sup 5677 5677 0 0.00 0 6591 9 0 0
v_min_non_paid_invoice 5974 5974 0 0.00 0 6832 21 0 0
v_min_non_paid_realizn 7377 7377 0 0.00 0 7868 23 0 0
v_our_firm 10368 10368 0 0.00 0 14 1 0 0
v_reserve_write_off 20808 20808 0 0.00 0 4497 6 0 0
x_lock_depdocs_on_canc_invoice 11782 11782 0 0.00 0 12953 117 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 6927 428 0 0
x_make_qstorno_1000_1200 5558 5558 0 0.00 1 28776 1184 0 0
x_make_qstorno_1200_2000 5703 5703 0 0.00 3 77111 3611 0 0
x_make_qstorno_2100_3300 483740 483740 0 0.00 0 62804 163 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 27-03-2022 01:39:07 to 27-03-2022 01:39:12.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:11 9 8192000000 8806991776 8821186560 11918528 0.145 3 3 4 0 12102448 76800 107120
23:09:12 29165 8192000000 9922461584 10235105280 1002548528 10.903 100 91 182 1 1032147648 6118928 23480192
23:10:47 25931 8192000000 9958055504 10282074112 1037169472 11.237 100 91 182 0 1069602176 8972176 23460528
23:12:16 21417 8192000000 9984042096 10319409152 1067561472 11.529 100 87 174 1 1100560256 10904672 22094112
23:13:44 12561 8192000000 10045860832 10393055232 1125042304 12.075 100 79 158 1 1155954736 11660480 19251952
23:14:59 11303 8192000000 10091176640 10451238912 1176975184 12.562 100 78 156 1 1207711648 13285872 17450592
23:16:12 6025 8192000000 10134930496 10505842688 1223686528 12.996 100 80 159 2 1255206528 13927040 17592960
23:17:19 3702 8192000000 10169426128 10546765824 1256857056 13.301 100 80 158 1 1287280256 12475584 17947616
23:18:24 2859 8192000000 10201412624 10584846336 1285401440 13.562 100 91 183 0 1319105040 13329328 20374272
23:19:31 23449 8192000000 10212660448 10604589056 1300818400 13.703 100 68 138 0 1326491920 9237440 16436080
23:20:55 8173 8192000000 10207047584 10609664000 1307717760 13.765 100 32 64 1 1321924688 6357888 7849040
23:22:04 5179 8192000000 10207531216 10623086592 1317155312 13.851 100 24 49 0 1329643312 6783024 5704976
23:23:11 20730 8192000000 10224435200 10631077888 1321309728 13.889 100 52 104 3 1339432960 6048400 12074832
23:24:40 14358 8192000000 10212882576 10632863744 1325340944 13.925 100 22 44 0 1336713488 5677136 5695408
23:25:54 7218 8192000000 10226448112 10645217280 1331732384 13.983 100 16 32 1 1344686656 9625424 3328848
23:27:03 7288 8192000000 10218426656 10633080832 1322725392 13.901 100 19 38 0 1333125200 6206736 4193072
23:28:11 26955 8192000000 10180575488 10592743424 1249219664 13.231 95 19 38 1 1258309664 4952976 4137024
23:29:38 3712 8192000000 10169486144 10576461824 1257622048 13.308 97 36 72 0 1272694128 7355056 7717024
23:30:44 7007 8192000000 10140128448 10534715392 1212778432 12.895 96 21 42 2 1223583408 6016704 4788272
23:31:52 6849 8192000000 10137293296 10534797312 1225473872 13.012 98 29 56 1 1237374832 5311792 6589168
23:33:05 31059 8192000000 10157222752 10562760704 1255779344 13.291 99 31 60 2 1268373808 6098832 6495632
23:34:37 6569 8192000000 10172491904 10581221376 1271667632 13.437 99 29 58 1 1284569152 6402432 6499088
23:35:49 7638 8192000000 10188060160 10605477888 1300421888 13.699 100 11 23 0 1308047440 5234832 2390720
23:36:58 13311 8192000000 10198953728 10617946112 1311136128 13.796 100 15 30 0 1319482240 4881200 3464912
23:38:16 17332 8192000000 10234488256 10641018880 1327120800 13.941 100 56 113 2 1346339104 5197776 14020528
23:39:55 16555 8192000000 10253889152 10673569792 1350466016 14.152 100 43 86 1 1370243696 9958448 9819232
23:41:15 5704 8192000000 10277431552 10699186176 1368411232 14.313 100 43 86 2 1394211664 15099104 10701328
23:42:22 7051 8192000000 10280821584 10707955712 1377864416 14.397 100 34 68 1 1401062064 14891632 8306016
23:43:41 12541 8192000000 10301348480 10726350848 1388600256 14.493 100 47 94 4 1415150384 15096880 11453248
23:44:57 21195 8192000000 10317202848 10743156736 1402167424 14.614 100 42 84 1 1428791968 16541344 10083200
23:46:23 8097 8192000000 10329728608 10755125248 1411640528 14.699 100 47 94 1 1444862592 21923232 11298832
23:47:34 3998 8192000000 10358532736 10771673088 1416675328 14.743 100 71 141 1 1460272736 26847760 16749648
23:48:42 7406 8192000000 10353101440 10771087360 1413999280 14.719 100 63 125 3 1460732384 31934752 14798352
23:49:56 4961 8192000000 10345826544 10755764224 1390363920 14.509 100 74 146 0 1451059840 43068544 17627376
23:51:09 7435 8192000000 10362484544 10786066432 1412940032 14.710 100 61 122 0 1471712192 44378320 14393840
23:52:25 2539 8192000000 10368680272 10783002624 1407629568 14.663 100 79 158 0 1475743984 48562912 19551504
23:53:32 3156 8192000000 10383255904 10793914368 1415316976 14.731 100 78 153 1 1489541264 55280112 18944176
23:54:42 4945 8192000000 10392748272 10807611392 1421208688 14.783 100 78 156 2 1496302384 54856304 20237392
23:55:57 8333 8192000000 10392273168 10802040832 1417504256 14.751 100 78 154 0 1495835024 58263360 20067408
23:57:16 3923 8192000000 10406453328 10820419584 1415632048 14.734 99 79 157 2 1502170256 65853024 20685184
23:58:26 3520 8192000000 10416008784 10827202560 1427359392 14.838 100 79 156 1 1511172048 63414496 20398160
23:59:34 4636 8192000000 10411685056 10822303744 1413012928 14.711 100 72 143 2 1508575680 76045136 19517616
00:00:46 3009 8192000000 10365971536 10786287616 1401512432 14.608 99 48 93 2 1469879504 55520160 12846912
00:01:52 27983 8192000000 10369381392 10803326976 1416826720 14.745 100 33 65 2 1483027664 56580416 9620528
00:03:37 16727 8192000000 10350647472 10785882112 1376577968 14.386 99 30 60 1 1451103936 64951328 9574640
00:05:00 22963 8192000000 10322807232 10766594048 1363609952 14.270 100 27 54 3 1431295600 59363200 8322448
00:06:25 14246 8192000000 10288045296 10722902016 1348717376 14.136 98 33 64 1 1366039776 10500560 6821840
00:07:52 90919 8192000000 10259702576 10699214848 1286767776 13.575 95 13 27 0 1300991696 11285744 2938176
00:10:34 53739 8192000000 10277023520 10717945856 1352235136 14.168 99 17 34 1 1370234992 13828608 4171248
00:12:29 134726 8192000000 10283865776 10726686720 1331620432 13.982 97 26 51 1 1348189584 10339728 6229424
00:15:47 78347 8192000000 10292204912 10744672256 1363325568 14.267 99 15 30 0 1384036496 17445856 3265072
00:18:07 48380 8192000000 10287220544 10743226368 1376386848 14.384 100 8 16 0 1395842176 17356256 2099072
00:19:59 22762 8192000000 10293170944 10740871168 1370389232 14.331 99 33 65 0 1391093408 13462864 7241312
00:21:23 41033 8192000000 10333305136 10772303872 1387715040 14.485 100 36 71 1 1420190416 23230624 9244752
00:23:09 55697 8192000000 10317018112 10771492864 1385191552 14.463 100 29 58 0 1421322144 29295904 6834688
00:25:09 72810 8192000000 10300186944 10759245824 1388948928 14.496 99 12 24 0 1400908592 9493504 2466160
00:27:23 61068 8192000000 10307614384 10773622784 1406838416 14.656 100 16 32 1 1424407408 13913648 3655344
00:29:24 70751 8192000000 10303898544 10768592896 1404297936 14.633 100 15 30 1 1420173296 12100576 3774784
00:31:55 61486 8192000000 10271253792 10740350976 1345925568 14.111 96 13 26 1 1361637056 13025472 2686016
00:34:03 62821 8192000000 10282383072 10752585728 1364802848 14.280 98 5 10 0 1373012976 7398192 811936
00:37:02 25649 8192000000 10306572736 10756546560 1378608432 14.404 99 29 58 1 1398328752 12905904 6814416
00:38:32 42773 8192000000 10288207920 10751610880 1324513936 13.918 95 23 46 1 1343851024 13766128 5570960
00:40:54 71873 8192000000 10287151376 10753458176 1344966288 14.102 97 14 28 1 1359319072 11334992 3017792
00:43:16 35694 8192000000 10296163152 10758569984 1366648672 14.297 98 23 46 2 1384819024 12871200 5299152
00:45:00 21656 8192000000 10298598544 10749513728 1359522368 14.233 98 22 44 2 1372793840 8494320 4777152
00:46:22 34003 8192000000 10323307168 10770067456 1393228224 14.535 100 39 78 0 1415098752 13397008 8473520
00:48:07 50231 8192000000 10285132384 10731274240 1326671840 13.937 95 16 32 0 1339407776 9276512 3459424
00:49:58 68311 8192000000 10284165568 10724847616 1325031008 13.922 96 40 80 1 1347749408 13900176 8818224
00:52:13 89210 8192000000 10274406720 10724257792 1294904800 13.649 94 21 42 0 1306728624 7236624 4587200
00:54:45 12439 8192000000 10274187600 10724765696 1371331504 14.339 100 24 48 1 1386444656 9466896 5646256
00:55:58 52795 8192000000 10255295200 10708189184 1314859632 13.830 95 19 38 0 1325934800 6789376 4285792
00:57:51 60530 8192000000 10280993072 10742849536 1365124864 14.283 99 21 42 0 1382831552 12113328 5593360
01:00:09 65869 8192000000 10251188192 10710802432 1343911056 14.093 98 11 22 0 1354482144 8056688 2514400
01:02:17 47321 8192000000 10263930992 10731282432 1370083392 14.328 100 14 28 0 1384780048 11614416 3082240
01:04:07 36281 8192000000 10263975984 10723078144 1335454256 14.016 97 21 42 0 1347272576 7394736 4423584
01:06:13 69492 8192000000 10275217792 10724737024 1334991312 14.012 97 25 50 0 1357095072 16305232 5798528
01:08:56 54758 8192000000 10289436016 10747236352 1378031168 14.399 99 19 38 2 1393800160 11693872 4075120
01:10:54 73333 8192000000 10306426720 10769149952 1401453728 14.608 100 28 54 1 1421503328 13919872 6129728
01:13:19 32954 8192000000 10311701024 10762473472 1403692272 14.628 100 29 58 0 1419263904 9161488 6410144
01:15:34 94116 8192000000 10308829952 10765312000 1353788064 14.182 96 19 38 0 1366612576 8673456 4151056
01:18:18 97885 8192000000 10300079936 10764189696 1363077152 14.265 97 5 10 1 1379060528 14964672 1018704
01:20:56 63691 8192000000 10304373536 10758221824 1396031936 14.560 100 33 65 2 1411789536 8460448 7297152
01:23:09 84378 8192000000 10264650016 10732298240 1318396208 13.862 95 10 20 1 1333126992 12256304 2474480
01:25:34 56129 8192000000 10268372192 10732867584 1362698032 14.262 99 14 28 0 1378272448 12682496 2891920
01:27:30 58780 8192000000 10272287216 10735398912 1356121552 14.203 98 28 56 1 1371464192 9340368 6002272
01:29:29 71651 8192000000 10289374960 10766635008 1386490448 14.475 100 5 10 0 1402004640 14305968 1208224
01:31:52 51358 8192000000 10285799264 10746757120 1352372176 14.169 97 17 34 0 1368325840 12055312 3898352
01:33:45 34588 8192000000 10300360560 10752004096 1363185504 14.266 98 47 94 0 1385760080 11845024 10729552
01:35:39 49710 8192000000 10286526368 10735300608 1347955008 14.129 97 36 71 3 1365509360 9463280 8091072
01:37:33 45051 8192000000 8956244336 9141239808 128391552 1.543 10 2 4 1 130837136 2113504 332080

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-03-2022 01:39:13 to 27-03-2022 01:39:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 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 27-03-2022 01:39:29 to 27-03-2022 01:39:31.

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          243447
MON$OLDEST_ACTIVE               243448
MON$OLDEST_SNAPSHOT             243448
MON$NEXT_TRANSACTION            243448
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                       2036320
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 ---
:533: 
:534: mscs-test-161.budzdorov.ru    Sun Mar 27 01:38:23 2022
:535:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:536: 
:537: 
:538: mscs-test-161.budzdorov.ru    Sun Mar 27 01:38:23 2022
:539:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:540: 
--- end of diff output ---

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

27.03.22 01:39:32. 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-03-27 01:39:35 No severe PSQL-related problems occured
27.03.22 01:39:36. End of report.