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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15154660 kB
MemAvailable 30373800 kB
Buffers 0 kB
Cached 16536180 kB
SwapCached 5556 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-04 01:38:32
DTS_END                         2023-01-04 01:38:32.2550
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/50722
TRN_ID                          246089
ATT_ID                          646
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: 9512.71 20488 1141525
customer order: creation 177.68 328 21322
customer order: refuse 15.44 305 1853
order to supplier: creation 46.87 1243 5624
order to supplier: removal 3.68 93883 442
invoice (draft): creation 49.16 3128 5899
invoice (draft): removal 53.68 802 6441
invoice accept: apply 43.36 18319 5203
invoice accept: cancel 48.49 779 5819
customer reserve: creation 3961.33 185 475359
customer reserve: removal 4805.33 54 576639
realization accept: apply 88.86 170 10663
realization accept: cancel 81.93 100 9832
payment from customer: creation 62.43 119 7491
payment from customer: removal 6.00 81 720
payment to supplier: creation 52.08 135 6250
payment to supplier: removal 3.50 82 420
service: total inventory turnovers 4.88 972 585
service: total monetary turnovers 7.12 23 854
service: aggregate perf. data 0.88 20431 105
service: refresh index statistics 0.03 268624 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-01-2023 01:39:26 to 04-01-2023 01:39:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6266
TEST_TIME 2 6158
TEST_TIME 3 5929
TEST_TIME 4 5715
TEST_TIME 5 5856
TEST_TIME 6 5866
TEST_TIME 7 5824
TEST_TIME 8 5781
TEST_TIME 9 5759
TEST_TIME 10 6482
TEST_TIME 11 6937
TEST_TIME 12 6761
TEST_TIME 13 6687
TEST_TIME 14 6646
TEST_TIME 15 6769
TEST_TIME 16 6923
TEST_TIME 17 6837
TEST_TIME 18 7014
TEST_TIME 19 7201
TEST_TIME 20 7694
TEST_TIME 21 8083
TEST_TIME 22 8408
TEST_TIME 23 8811
TEST_TIME 24 9313
TEST_TIME 25 10017
TEST_TIME 26 10274
TEST_TIME 27 10517
TEST_TIME 28 10774
TEST_TIME 29 10910
TEST_TIME 30 10836
TEST_TIME 31 10637
TEST_TIME 32 10682
TEST_TIME 33 10599
TEST_TIME 34 10675
TEST_TIME 35 10700
TEST_TIME 36 10730
TEST_TIME 37 10518
TEST_TIME 38 10355
TEST_TIME 39 10307
TEST_TIME 40 10274
TEST_TIME 41 10101
TEST_TIME 42 10116
TEST_TIME 43 10091
TEST_TIME 44 9996
TEST_TIME 45 9984
TEST_TIME 46 10045
TEST_TIME 47 9926
TEST_TIME 48 9843
TEST_TIME 49 9757
TEST_TIME 50 9828
TEST_TIME 51 9695
TEST_TIME 52 9761
TEST_TIME 53 9703
TEST_TIME 54 9642
TEST_TIME 55 9610
TEST_TIME 56 9554
TEST_TIME 57 9549
TEST_TIME 58 9592
TEST_TIME 59 9667
TEST_TIME 60 9733
TEST_TIME 61 9741
TEST_TIME 62 9787
TEST_TIME 63 9854
TEST_TIME 64 9799
TEST_TIME 65 9861
TEST_TIME 66 9894
TEST_TIME 67 9912
TEST_TIME 68 9884
TEST_TIME 69 9908
TEST_TIME 70 10070
TEST_TIME 71 10001
TEST_TIME 72 9944
TEST_TIME 73 9902
TEST_TIME 74 9886
TEST_TIME 75 9841
TEST_TIME 76 9866
TEST_TIME 77 9826
TEST_TIME 78 9753
TEST_TIME 79 9700
TEST_TIME 80 9622
TEST_TIME 81 9691
TEST_TIME 82 9608
TEST_TIME 83 9716
TEST_TIME 84 9715
TEST_TIME 85 9708
TEST_TIME 86 9750
TEST_TIME 87 9708
TEST_TIME 88 9698
TEST_TIME 89 9701
TEST_TIME 90 9674
TEST_TIME 91 9745
TEST_TIME 92 9810
TEST_TIME 93 9795
TEST_TIME 94 9782
TEST_TIME 95 9810
TEST_TIME 96 9777
TEST_TIME 97 9787
TEST_TIME 98 9792
TEST_TIME 99 9752
TEST_TIME 100 9675
TEST_TIME 101 9653
TEST_TIME 102 9608
TEST_TIME 103 9582
TEST_TIME 104 9604
TEST_TIME 105 9607
TEST_TIME 106 9596
TEST_TIME 107 9541
TEST_TIME 108 9506
TEST_TIME 109 9499
TEST_TIME 110 9496
TEST_TIME 111 9510
TEST_TIME 112 9521
TEST_TIME 113 9523
TEST_TIME 114 9531
TEST_TIME 115 9511
TEST_TIME 116 9491
TEST_TIME 117 9546
TEST_TIME 118 9549
TEST_TIME 119 9557
TEST_TIME 120 9519

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-01-2023 01:39:29 to 04-01-2023 01:39:33.

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 1581969 1581969 0 0.00 0 17388 6 0 0
doc_list_biud 1581969 1581969 0 0.00 0 35145 26 0 0
sp_add_invoice_to_stock 5390 5203 187 3.46 38 218953 18320 0 187
sp_cancel_adding_invoice 5819 5819 0 0.00 3 58855 779 0 0
sp_cancel_client_order 1853 1853 0 0.00 4 9048 306 0 0
sp_cancel_customer_reserve 576639 576639 0 0.00 0 23691 55 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 2 10126 81 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 6886 83 0 0
sp_cancel_supplier_invoice 6452 6441 11 0.17 1 18361 802 0 11
sp_cancel_supplier_order 442 442 0 0.00 6 1106412 93883 0 0
sp_cancel_write_off 9832 9832 0 0.00 1 11812 101 0 0
sp_client_order 21322 21322 0 0.00 3 13630 329 0 0
sp_customer_reserve 475359 475359 0 0.00 0 62382 186 0 0
sp_fill_shopping_cart 32842 32842 0 0.00 1 9819 34 0 0
sp_fill_shopping_cart_clo_res 475347 475347 0 0.00 0 14258 12 0 0
sp_get_clo_for_invoice 5186 5186 0 0.00 0 29428 218 0 0
sp_kill_cost_storno 16791 16791 0 0.00 0 11808 51 0 0
sp_kill_qstorno_ret_qs2qd 1169993 1169993 0 0.00 0 23678 30 0 0
sp_kill_qty_storno 616874 616874 0 0.00 0 35144 65 0 0
sp_lock_selected_doc 21886 21886 0 0.00 0 10735 7 0 0
sp_make_cost_storno 29589 29589 0 0.00 0 14314 52 0 0
sp_multiply_rows_for_pdistr 29589 29589 0 0.00 0 9036 25 0 0
sp_multiply_rows_for_qdistr 21322 21322 0 0.00 0 11085 140 0 0
sp_pay_from_customer 7491 7491 0 0.00 3 29969 119 0 0
sp_pay_to_supplier 6250 6250 0 0.00 4 29981 135 0 0
sp_payment_common 13741 13741 0 0.00 1 18594 25 0 0
sp_qd_handle_on_cancel_clo 1853 1853 0 0.00 0 9043 281 0 0
sp_qd_handle_on_invoice_upd_sts 11004 11004 0 0.00 0 35142 328 0 0
sp_qd_handle_on_reserve_upd_sts 10663 10663 0 0.00 0 10820 46 0 0
sp_reserve_write_off 10663 10663 0 0.00 6 12992 170 0 0
sp_supplier_invoice 5899 5899 0 0.00 5 90307 3128 0 0
sp_supplier_order 5624 5624 0 0.00 5 43777 1243 0 0
srv_aggregate_perf_data 105 105 0 0.00 2202 357059 20432 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1349 136974 47037 0 0
srv_find_qd_qs_mism 1091985 1091985 0 0.00 0 17387 6 0 0
srv_make_invnt_saldo 585 585 0 0.00 32 25804 973 0 0
srv_make_money_saldo 854 854 0 0.00 1 927 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 133418 417372 268624 0 0
t$perf-abend:sp_add_invoice_to_stock 187 187 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5203 5203 0 0.00 0 11190 161 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 2270 69 0 0
t$perf-norm:sp_cancel_client_order 1853 1853 0 0.00 0 1630 11 0 0
t$perf-norm:sp_cancel_customer_reserve 740 740 0 0.00 0 534 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 476 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 1656 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 903 11 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 36467 1626 0 0
t$perf-norm:sp_cancel_write_off 1080 1080 0 0.00 0 6018 16 0 0
t$perf-norm:sp_client_order 21322 21322 0 0.00 0 7654 17 0 0
t$perf-norm:sp_customer_reserve 1079 1079 0 0.00 0 6153 16 0 0
t$perf-norm:sp_pay_from_customer 7491 7491 0 0.00 0 8222 10 0 0
t$perf-norm:sp_pay_to_supplier 6250 6250 0 0.00 0 8941 14 0 0
t$perf-norm:sp_reserve_write_off 10663 10663 0 0.00 0 8048 13 0 0
t$perf-norm:sp_supplier_invoice 5899 5899 0 0.00 0 7755 17 0 0
t$perf-norm:sp_supplier_order 5624 5624 0 0.00 0 10915 18 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 390 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 3596 113 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 778 18 0 0
t$perf-norm:srv_make_money_saldo 854 854 0 0.00 0 1481 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10740 10366 374 3.48 0 9688 24 0 374
v_all_customers 38674 38674 0 0.00 0 198 1 0 0
v_all_suppliers 32104 32104 0 0.00 0 28 1 0 0
v_all_wares 42642 42642 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 2611 10 0 0
v_cancel_client_order 3706 3706 0 0.00 0 1327 3 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 1594 5 0 0
v_cancel_customer_reserve 1480 1480 0 0.00 0 2625 9 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 4351 9 0 0
v_cancel_supplier_invoice 824 802 22 2.66 0 8331 24 0 22
v_cancel_supplier_order 884 884 0 0.00 0 1996 8 0 0
v_cancel_write_off 2160 2160 0 0.00 0 3988 18 0 0
v_max_id_clo_ord 5624 5624 0 0.00 0 1433 1 0 0
v_max_id_clo_res 1079 1079 0 0.00 0 7896 21 0 0
v_max_id_ord_sup 5896 5896 0 0.00 0 4927 5 0 0
v_max_non_paid_invoice 1718 1718 0 0.00 0 245 2 0 0
v_max_non_paid_realizn 4230 4230 0 0.00 0 7581 5 0 0
v_min_id_clo_ord 5624 5624 0 0.00 0 2164 6 0 0
v_min_id_clo_res 1079 1079 0 0.00 0 4467 10 0 0
v_min_id_ord_sup 5896 5896 0 0.00 0 5274 6 0 0
v_min_non_paid_invoice 6250 6250 0 0.00 0 18589 20 0 0
v_min_non_paid_realizn 7491 7491 0 0.00 0 6241 13 0 0
v_our_firm 10490 10490 0 0.00 0 17 1 0 0
v_reserve_write_off 21326 21326 0 0.00 0 9694 9 0 0
x_lock_depdocs_on_canc_invoice 11859 11859 0 0.00 0 16320 137 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 13751 373 0 0
x_make_qstorno_1000_1200 5624 5624 0 0.00 1 43768 1174 0 0
x_make_qstorno_1200_2000 5899 5899 0 0.00 2 90289 3007 0 0
x_make_qstorno_2100_3300 475359 475359 0 0.00 0 62381 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-01-2023 01:39:34 to 04-01-2023 01:39:38.

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:36 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:36 23301 8192000000 9887489328 10193629184 973084480 10.617 100 87 174 0 1002196896 6066368 23046048
23:11:11 19084 8192000000 9941344976 10259488768 1026898256 11.139 100 93 186 0 1059198544 8374832 23925456
23:12:38 21227 8192000000 9974536496 10305339392 1056194112 11.420 100 85 170 0 1087006176 9887888 20924176
23:14:08 11686 8192000000 10043456464 10389557248 1132187568 12.142 100 85 170 0 1163264848 10722944 20354336
23:15:24 5098 8192000000 10075744272 10437332992 1173385520 12.528 100 74 148 1 1202234496 11494112 17354864
23:16:32 2277 8192000000 10118062144 10483834880 1205628736 12.829 100 84 169 0 1239977504 15731056 18617712
23:17:35 2028 8192000000 10148557616 10520825856 1236514800 13.114 100 80 159 0 1265949408 12328544 17106064
23:18:38 1868 8192000000 10183240288 10564947968 1270212176 13.424 100 76 150 0 1299027040 12281280 16533584
23:19:43 917 8192000000 10213551952 10595254272 1296384528 13.662 100 92 185 1 1330224720 13336192 20504000
23:20:45 1493 8192000000 10214949488 10611888128 1309926272 13.785 100 56 112 0 1332808720 10566640 12315808
23:21:49 4414 8192000000 10205449952 10620788736 1320240704 13.879 100 31 62 0 1333934560 7110544 6583312
23:22:56 44133 8192000000 10231606640 10644819968 1328757408 13.956 100 37 74 0 1347471120 9739248 8974464
23:24:41 6302 8192000000 10226726704 10645983232 1332868240 13.993 100 27 54 0 1345611968 6999136 5744592
23:25:49 4186 8192000000 10240540944 10658861056 1342006464 14.075 100 30 60 1 1354795280 6427328 6361488
23:26:56 15157 8192000000 10228081840 10650341376 1339109664 14.049 100 18 36 1 1348854608 5907600 3837344
23:28:14 2831 8192000000 10230516544 10637295616 1307714592 13.765 98 66 133 3 1330977712 7708416 15554704
23:29:21 8208 8192000000 10165327872 10577121280 1225592432 13.013 93 21 41 1 1236198912 6246800 4359680
23:30:32 6957 8192000000 10178253056 10580217856 1244515904 13.188 96 25 50 0 1256713392 6346368 5851120
23:31:40 67016 8192000000 10135595040 10537922560 1174877872 12.542 94 26 50 0 1185351840 4842304 5631664
23:33:50 5369 8192000000 10147314480 10543050752 1243282880 13.176 99 32 63 1 1259789984 9402096 7105008
23:34:57 9177 8192000000 10184122416 10591727616 1291488496 13.618 100 30 61 0 1304407216 5667488 7251232
23:36:09 14602 8192000000 10184998128 10596552704 1294851520 13.648 100 24 47 2 1305822624 5449904 5521200
23:37:27 28025 8192000000 10191450336 10608066560 1301727648 13.711 100 22 44 0 1312434352 5393248 5313456
23:39:02 11289 8192000000 10230420096 10642366464 1327902880 13.948 100 37 70 0 1341478736 6218288 7357568
23:40:17 19186 8192000000 10253695632 10670174208 1350135776 14.149 100 48 94 0 1372462912 11417696 10909440
23:41:43 11806 8192000000 10274530064 10695782400 1364065136 14.274 100 41 83 0 1386492896 12403088 10024672
23:43:02 18020 8192000000 10285622672 10709962752 1378512272 14.403 100 45 90 0 1404555536 15243312 10799952
23:44:39 13153 8192000000 10295674592 10724003840 1385929776 14.470 100 34 68 1 1410838704 16296384 8612544
23:46:03 9787 8192000000 10315337216 10742902784 1396313568 14.562 100 45 86 1 1426322816 20057136 9952112
23:47:22 7546 8192000000 10324274176 10751102976 1401098416 14.605 100 57 114 0 1438361456 23504864 13758176
23:48:34 1502 8192000000 10353626144 10771005440 1414011552 14.720 100 82 164 0 1462949152 29535120 19402480
23:49:44 11335 8192000000 10326462592 10755907584 1404150576 14.632 100 46 90 0 1447419760 33125936 10143248
23:50:59 1349 8192000000 10335581120 10751942656 1396203600 14.561 100 64 125 0 1439643680 27827424 15612656
23:52:05 8670 8192000000 10347545504 10769211392 1414823104 14.727 100 64 127 0 1460245040 29921984 15499952
23:53:18 6246 8192000000 10347829280 10768609280 1404831504 14.638 100 60 120 1 1458610672 38265024 15514144
23:54:29 7518 8192000000 10364648320 10772701184 1409945504 14.683 100 81 161 0 1470931392 41309200 19676688
23:55:44 2768 8192000000 10363174880 10770247680 1393317936 14.535 99 84 166 0 1457008800 42606784 21084080
23:56:53 4539 8192000000 10335892848 10748108800 1371702192 14.342 99 67 128 0 1436786080 47107456 17976432
23:58:03 3462 8192000000 10366140832 10770804736 1395678048 14.556 100 73 147 0 1465906240 50712720 19515472
23:59:09 4456 8192000000 10354233072 10770087936 1388043680 14.488 100 66 128 0 1458797808 53398848 17355280
00:00:20 8855 8192000000 10339518176 10756837376 1373899536 14.362 100 48 95 0 1439441600 51804320 13737744
00:01:44 20133 8192000000 10323631408 10759487488 1358592848 14.225 99 34 68 0 1417559888 48693536 10273504
00:03:12 29753 8192000000 10289434112 10730074112 1332000064 13.985 98 24 50 0 1379349648 39784992 7564592
00:04:45 23305 8192000000 10274436176 10720190464 1327698880 13.946 98 31 62 0 1368341712 32484288 8158544
00:06:09 29486 8192000000 10268158080 10716942336 1340730528 14.064 99 15 30 0 1365189472 21057088 3401856
00:07:40 114298 8192000000 10289563696 10730377216 1357566000 14.215 98 11 23 0 1374996784 15293344 2137440
00:10:57 22514 8192000000 10293699392 10729611264 1354305792 14.186 98 37 69 0 1368842592 6946352 7590448
00:12:20 136974 8192000000 10289146368 10738360320 1330867056 13.975 96 8 16 0 1347922736 15507760 1547920
00:15:49 106733 8192000000 10316871088 10754789376 1387604976 14.484 100 38 76 2 1407207408 10481584 9120848
00:18:37 26586 8192000000 10311021472 10748485632 1382359216 14.438 99 63 127 1 1411051456 15006352 13685888
00:20:06 20763 8192000000 10313260464 10759782400 1398353744 14.580 100 41 82 1 1419124752 11841216 8929792
00:21:34 21611 8192000000 10304065728 10754482176 1387206064 14.481 99 35 68 1 1402385344 7256400 7922880
00:22:56 27037 8192000000 10309443728 10764771328 1396185744 14.561 99 41 81 1 1413704512 8332528 9186240
00:24:36 46450 8192000000 10311958320 10775986176 1407360960 14.660 100 10 18 0 1421855760 12060000 2434800
00:26:23 61150 8192000000 10315993952 10774646784 1398057920 14.578 99 10 20 0 1420951824 20544288 2349616
00:28:25 56518 8192000000 10312087392 10785542144 1408213424 14.668 100 13 26 0 1426572288 15194048 3164816
00:30:29 53554 8192000000 10308231360 10770337792 1404742240 14.637 100 20 40 1 1420547344 12044336 3760768
00:32:23 84071 8192000000 10305442800 10777755648 1410134144 14.685 100 12 24 0 1422506496 9774672 2597680
00:34:53 69331 8192000000 10303958832 10769559552 1352403936 14.169 96 34 68 0 1374855200 14363680 8087584
00:37:03 61863 8192000000 10302964384 10776096768 1382723824 14.441 99 29 58 0 1403327392 13883280 6720288
00:39:09 50708 8192000000 10288487376 10735198208 1331550192 13.981 95 15 30 0 1345861072 11138880 3172000
00:41:01 86114 8192000000 10255508224 10695114752 1213813904 12.904 88 17 28 2 1224169792 7419088 2936800
00:43:28 65276 8192000000 10265334288 10712633344 1299839920 13.694 96 19 38 0 1314961280 10863216 4258144
00:45:37 57473 8192000000 10267138528 10697121792 1315365152 13.835 97 17 34 0 1329721872 9817856 4538864
00:47:37 65819 8192000000 10272874160 10740903936 1347831408 14.128 100 21 42 0 1379826352 26768912 5226032
00:49:43 110574 8192000000 10277216736 10721112064 1358924352 14.228 100 23 46 1 1383579856 19434944 5220560
00:52:45 26298 8192000000 10267887312 10707554304 1347892176 14.129 99 34 68 0 1365348496 10132336 7323984
00:54:15 57631 8192000000 10273051680 10727690240 1357705568 14.217 99 14 28 0 1369501776 8903472 2892736
00:56:13 55994 8192000000 10284071376 10732814336 1359772704 14.235 98 30 60 0 1378325024 11650160 6902160
00:58:09 81404 8192000000 10292977232 10752454656 1390870240 14.514 100 20 40 0 1410292384 14195760 5226384
01:00:41 65091 8192000000 10283700192 10758438912 1372578464 14.350 99 13 26 0 1392036560 16130480 3327616
01:02:50 92744 8192000000 10277410176 10735906816 1315373904 13.835 95 15 30 0 1337266560 18319728 3572928
01:05:25 65232 8192000000 10273974672 10728173568 1338583152 14.045 97 40 80 0 1357553264 10127056 8843056
01:07:36 86865 8192000000 10304279616 10758623232 1359163104 14.230 98 23 46 0 1381767520 17352800 5251616
01:10:04 93381 8192000000 10305209472 10768568320 1385179600 14.463 99 22 44 0 1404263568 14446784 4637184
01:12:39 51316 8192000000 10304806384 10756165632 1368495488 14.314 98 18 36 1 1384912752 12646208 3771056
01:14:35 34229 8192000000 10320306240 10762031104 1380230192 14.419 99 37 73 0 1397132736 8727520 8175024
01:16:11 49310 8192000000 10311998816 10765582336 1393865856 14.540 100 23 46 0 1408174016 9471056 4837104
01:18:02 109897 8192000000 10283218928 10741944320 1360290976 14.240 98 19 38 0 1374225008 9662832 4271200
01:21:04 79383 8192000000 10285766768 10750132224 1359636864 14.234 98 18 36 0 1378976256 15224544 4114848
01:23:59 66717 8192000000 10288471600 10748006400 1370414944 14.331 99 21 42 0 1388347104 13269024 4663136
01:26:16 74423 8192000000 10277123120 10745434112 1374379760 14.366 99 13 26 0 1390269520 12452992 3436768
01:28:42 96977 8192000000 10272850304 10740572160 1313742832 13.820 95 6 12 0 1328459648 13336144 1380672
01:31:26 55273 8192000000 10235500352 10674896896 1198181968 12.759 88 21 42 0 1212468432 9610976 4675488
01:33:26 11631 8192000000 10247562496 10681806848 1309942496 13.786 98 43 86 1 1333246768 14273872 9030400
01:34:42 68125 8192000000 10248609616 10704654336 1338431824 14.043 100 34 68 0 1360996768 15118800 7446144
01:36:50 88774 8192000000 10297168752 10730287104 1347754096 14.127 99 20 40 0 1367360672 14883840 4722736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-01-2023 01:39:40 to 04-01-2023 01:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 187 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-01-2023 01:39:56 to 04-01-2023 01:39:58.

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          244531
MON$OLDEST_ACTIVE               246566
MON$OLDEST_SNAPSHOT             246566
MON$NEXT_TRANSACTION            246566
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                       2046368
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:1631: 
:1632: mscs-test-161.budzdorov.ru    Wed Jan  4 01:38:34 2023
:1633:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1634: 
:1635: 
:1636: mscs-test-161.budzdorov.ru    Wed Jan  4 01:38:35 2023
:1637:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1638: 
:1639: 
:1640: mscs-test-161.budzdorov.ru    Wed Jan  4 01:38:39 2023
:1641:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1642: 
:1643: 
:1644: mscs-test-161.budzdorov.ru    Wed Jan  4 01:38:39 2023
:1645:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1646: 
--- end of diff output ---

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

04.01.23 01:39:59. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-01-04 01:40:02 No severe PSQL-related problems occured
04.01.23 01:40:03. End of report.