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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15363328 kB
MemAvailable 30400440 kB
Buffers 0 kB
Cached 16375776 kB
SwapCached 4424 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-07 22:38:35
DTS_END                         2023-01-07 22:38:35.4710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54154
TRN_ID                          241162
ATT_ID                          671
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: 9068.38 23870 1088206
customer order: creation 178.08 353 21370
customer order: refuse 15.31 364 1837
order to supplier: creation 47.44 1214 5693
order to supplier: removal 3.51 100652 421
invoice (draft): creation 47.28 3231 5673
invoice (draft): removal 51.79 790 6215
invoice accept: apply 41.78 16832 5014
invoice accept: cancel 45.60 792 5472
customer reserve: creation 3835.59 165 460271
customer reserve: removal 4503.70 62 540444
realization accept: apply 86.69 184 10403
realization accept: cancel 79.18 100 9501
payment from customer: creation 59.99 111 7199
payment from customer: removal 6.22 80 746
payment to supplier: creation 49.75 143 5970
payment to supplier: removal 3.72 77 446
service: total inventory turnovers 4.70 1335 564
service: total monetary turnovers 7.19 27 863
service: aggregate perf. data 0.83 20791 100
service: refresh index statistics 0.03 330094 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-01-2023 22:40:50 to 07-01-2023 22:40:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2074
TEST_TIME 2 3074
TEST_TIME 3 3937
TEST_TIME 4 5317
TEST_TIME 5 4839
TEST_TIME 6 5090
TEST_TIME 7 4920
TEST_TIME 8 4934
TEST_TIME 9 4837
TEST_TIME 10 5432
TEST_TIME 11 5782
TEST_TIME 12 5943
TEST_TIME 13 6320
TEST_TIME 14 6573
TEST_TIME 15 6778
TEST_TIME 16 6901
TEST_TIME 17 7302
TEST_TIME 18 7607
TEST_TIME 19 8176
TEST_TIME 20 8135
TEST_TIME 21 8549
TEST_TIME 22 8589
TEST_TIME 23 8651
TEST_TIME 24 8610
TEST_TIME 25 8537
TEST_TIME 26 8701
TEST_TIME 27 8938
TEST_TIME 28 9853
TEST_TIME 29 10404
TEST_TIME 30 10702
TEST_TIME 31 10596
TEST_TIME 32 10394
TEST_TIME 33 10149
TEST_TIME 34 9948
TEST_TIME 35 9813
TEST_TIME 36 9692
TEST_TIME 37 9698
TEST_TIME 38 9678
TEST_TIME 39 9638
TEST_TIME 40 9532
TEST_TIME 41 9573
TEST_TIME 42 9464
TEST_TIME 43 9427
TEST_TIME 44 9473
TEST_TIME 45 9387
TEST_TIME 46 9231
TEST_TIME 47 9182
TEST_TIME 48 9170
TEST_TIME 49 9087
TEST_TIME 50 9048
TEST_TIME 51 9000
TEST_TIME 52 9044
TEST_TIME 53 9068
TEST_TIME 54 9099
TEST_TIME 55 9115
TEST_TIME 56 9124
TEST_TIME 57 9164
TEST_TIME 58 9166
TEST_TIME 59 9252
TEST_TIME 60 9362
TEST_TIME 61 9338
TEST_TIME 62 9354
TEST_TIME 63 9476
TEST_TIME 64 9496
TEST_TIME 65 9452
TEST_TIME 66 9396
TEST_TIME 67 9321
TEST_TIME 68 9356
TEST_TIME 69 9289
TEST_TIME 70 9259
TEST_TIME 71 9280
TEST_TIME 72 9261
TEST_TIME 73 9256
TEST_TIME 74 9210
TEST_TIME 75 9169
TEST_TIME 76 9134
TEST_TIME 77 9051
TEST_TIME 78 9038
TEST_TIME 79 9020
TEST_TIME 80 9025
TEST_TIME 81 8990
TEST_TIME 82 9035
TEST_TIME 83 9015
TEST_TIME 84 9064
TEST_TIME 85 9119
TEST_TIME 86 9164
TEST_TIME 87 9133
TEST_TIME 88 9235
TEST_TIME 89 9221
TEST_TIME 90 9213
TEST_TIME 91 9268
TEST_TIME 92 9259
TEST_TIME 93 9180
TEST_TIME 94 9209
TEST_TIME 95 9208
TEST_TIME 96 9163
TEST_TIME 97 9242
TEST_TIME 98 9222
TEST_TIME 99 9197
TEST_TIME 100 9161
TEST_TIME 101 9162
TEST_TIME 102 9245
TEST_TIME 103 9231
TEST_TIME 104 9261
TEST_TIME 105 9319
TEST_TIME 106 9311
TEST_TIME 107 9331
TEST_TIME 108 9353
TEST_TIME 109 9368
TEST_TIME 110 9305
TEST_TIME 111 9270
TEST_TIME 112 9242
TEST_TIME 113 9208
TEST_TIME 114 9200
TEST_TIME 115 9212
TEST_TIME 116 9194
TEST_TIME 117 9166
TEST_TIME 118 9161
TEST_TIME 119 9119
TEST_TIME 120 9070

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-01-2023 22:40:54 to 07-01-2023 22:40:57.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1516565 1516565 0 0.00 0 16412 6 0 0
doc_list_biud 1516565 1516565 0 0.00 0 43687 28 0 0
sp_add_invoice_to_stock 5112 5014 98 1.91 45 200045 16833 0 98
sp_cancel_adding_invoice 5472 5472 0 0.00 4 84252 792 0 0
sp_cancel_client_order 1837 1837 0 0.00 4 10094 365 0 0
sp_cancel_customer_reserve 540444 540444 0 0.00 0 43688 62 0 0
sp_cancel_pay_from_customer 746 746 0 0.00 3 3574 81 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 3 4837 77 0 0
sp_cancel_supplier_invoice 6219 6215 4 0.06 1 34615 790 0 4
sp_cancel_supplier_order 421 421 0 0.00 5 885835 100653 0 0
sp_cancel_write_off 9501 9501 0 0.00 1 11333 100 0 0
sp_client_order 21370 21370 0 0.00 3 32288 353 0 0
sp_customer_reserve 460271 460271 0 0.00 0 46721 166 0 0
sp_fill_shopping_cart 32720 32720 0 0.00 1 11556 37 0 0
sp_fill_shopping_cart_clo_res 460267 460267 0 0.00 0 20782 15 0 0
sp_get_clo_for_invoice 5009 5009 0 0.00 0 30127 345 0 0
sp_kill_cost_storno 16165 16165 0 0.00 0 9004 58 0 0
sp_kill_qstorno_ret_qs2qd 1097025 1097025 0 0.00 0 43685 34 0 0
sp_kill_qty_storno 579302 579302 0 0.00 0 43687 72 0 0
sp_lock_selected_doc 21386 21386 0 0.00 0 6764 4 0 0
sp_make_cost_storno 28581 28581 0 0.00 0 11472 54 0 0
sp_multiply_rows_for_pdistr 28581 28581 0 0.00 0 9622 29 0 0
sp_multiply_rows_for_qdistr 21370 21370 0 0.00 0 13521 151 0 0
sp_pay_from_customer 7199 7199 0 0.00 4 10696 111 0 0
sp_pay_to_supplier 5970 5970 0 0.00 5 11653 144 0 0
sp_payment_common 13169 13169 0 0.00 1 11587 28 0 0
sp_qd_handle_on_cancel_clo 1837 1837 0 0.00 0 10088 334 0 0
sp_qd_handle_on_invoice_upd_sts 10481 10481 0 0.00 0 22793 314 0 0
sp_qd_handle_on_reserve_upd_sts 10403 10403 0 0.00 0 11957 60 0 0
sp_reserve_write_off 10403 10403 0 0.00 6 11971 184 0 0
sp_supplier_invoice 5673 5673 0 0.00 7 67822 3231 0 0
sp_supplier_order 5693 5693 0 0.00 5 29241 1215 0 0
srv_aggregate_perf_data 100 100 0 0.00 2744 321810 20791 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 967 127339 44844 0 0
srv_find_qd_qs_mism 1040762 1040762 0 0.00 0 16411 4 0 0
srv_make_invnt_saldo 564 564 0 0.00 18 144231 1335 0 0
srv_make_money_saldo 863 863 0 0.00 0 1295 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 221451 535519 330094 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5014 5014 0 0.00 0 14567 181 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 4472 132 0 0
t$perf-norm:sp_cancel_client_order 1837 1837 0 0.00 0 8112 21 0 0
t$perf-norm:sp_cancel_customer_reserve 732 732 0 0.00 0 2588 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 746 746 0 0.00 0 3607 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 2126 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 1718 22 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 34873 1845 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 7489 17 0 0
t$perf-norm:sp_client_order 21370 21370 0 0.00 0 13350 21 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 9264 30 0 0
t$perf-norm:sp_pay_from_customer 7199 7199 0 0.00 0 11963 15 0 0
t$perf-norm:sp_pay_to_supplier 5970 5970 0 0.00 0 12302 19 0 0
t$perf-norm:sp_reserve_write_off 10403 10403 0 0.00 0 10147 13 0 0
t$perf-norm:sp_supplier_invoice 5673 5673 0 0.00 0 5081 21 0 0
t$perf-norm:sp_supplier_order 5693 5693 0 0.00 0 6974 23 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 282 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 385 8 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 1046 25 0 0
t$perf-norm:srv_make_money_saldo 863 863 0 0.00 0 1337 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10214 10018 196 1.91 0 7319 20 0 196
v_all_customers 38760 38760 0 0.00 0 15 1 0 0
v_all_suppliers 31614 31614 0 0.00 0 29 1 0 0
v_all_wares 42740 42740 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 3810 14 0 0
v_cancel_client_order 3674 3674 0 0.00 0 3109 7 0 0
v_cancel_customer_prepayment 1492 1492 0 0.00 0 3213 6 0 0
v_cancel_customer_reserve 1464 1464 0 0.00 0 1967 7 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 4437 8 0 0
v_cancel_supplier_invoice 888 880 8 0.90 0 1568 18 0 8
v_cancel_supplier_order 842 842 0 0.00 0 1053 8 0 0
v_cancel_write_off 1914 1914 0 0.00 0 6973 26 0 0
v_max_id_clo_ord 5693 5693 0 0.00 0 11508 3 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 5799 30 0 0
v_max_id_ord_sup 5657 5657 0 0.00 0 6147 7 0 0
v_max_non_paid_invoice 1513 1513 0 0.00 0 11583 12 0 0
v_max_non_paid_realizn 3899 3899 0 0.00 0 2377 6 0 0
v_min_id_clo_ord 5693 5693 0 0.00 0 2958 7 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 4861 13 0 0
v_min_id_ord_sup 5657 5657 0 0.00 0 4482 6 0 0
v_min_non_paid_invoice 5970 5970 0 0.00 0 5806 18 0 0
v_min_non_paid_realizn 7199 7199 0 0.00 0 8146 18 0 0
v_our_firm 10580 10580 0 0.00 0 16 1 0 0
v_reserve_write_off 20806 20806 0 0.00 0 8454 8 0 0
x_lock_depdocs_on_canc_invoice 11247 11247 0 0.00 0 15390 125 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 7825 335 0 0
x_make_qstorno_1000_1200 5693 5693 0 0.00 1 29219 1135 0 0
x_make_qstorno_1200_2000 5673 5673 0 0.00 2 66188 3084 0 0
x_make_qstorno_2100_3300 460271 460271 0 0.00 0 46719 148 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 07-01-2023 22:40:58 to 07-01-2023 22:41:03.

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:39 82 8192000000 8843842912 8870535168 35851168 0.435 9 8 10 0 36267664 138112 278384
20:09:40 23957 8192000000 9892942416 10201636864 973738736 10.623 100 87 174 0 1002490544 5908640 22843168
20:11:14 18054 8192000000 9948926048 10267496448 1029509584 11.164 100 93 186 0 1061697872 8087168 24101120
20:12:46 12950 8192000000 9974441328 10310213632 1067904736 11.532 100 83 166 0 1097524240 9013616 20605888
20:14:15 7824 8192000000 10047447424 10397007872 1132186288 12.142 100 73 146 0 1159280592 9853664 17240640
20:15:25 5541 8192000000 10104990576 10467041280 1187503808 12.660 100 81 162 1 1218054960 12305280 18245872
20:16:34 2509 8192000000 10137933520 10509045760 1231308096 13.066 100 86 168 1 1262403216 12556048 18539072
20:17:40 1903 8192000000 10167613472 10542981120 1252102304 13.258 100 87 170 1 1285480480 14413120 18965056
20:18:44 1461 8192000000 10190213088 10574381056 1279299200 13.507 100 89 178 1 1312458240 12459504 20699536
20:19:46 1761 8192000000 10209027408 10594054144 1296130400 13.660 100 89 177 0 1328489136 12862016 19496720
20:20:50 17784 8192000000 10212483392 10610225152 1305263120 13.743 100 67 134 0 1331647232 12382384 14001728
20:22:09 2427 8192000000 10205021504 10614984704 1315567728 13.837 100 51 102 1 1335461504 8541808 11351968
20:23:16 7979 8192000000 10208330608 10620751872 1320831856 13.884 100 24 48 1 1332605968 6532720 5241392
20:24:26 10013 8192000000 10205095552 10625994752 1324585024 13.918 100 14 28 0 1333267808 5530032 3152752
20:25:38 18010 8192000000 10235083376 10643709952 1330444368 13.971 100 62 119 5 1350915584 6151808 14319408
20:26:57 4213 8192000000 10222947232 10647875584 1336706704 14.028 100 28 54 0 1349086864 5072960 7307200
20:28:02 10463 8192000000 10219143184 10638802944 1321635920 13.892 99 15 31 0 1330210752 5234288 3340544
20:29:14 28236 8192000000 10198372928 10611871744 1297743584 13.675 99 27 54 0 1309059296 4591392 6724320
20:30:47 7544 8192000000 10169222992 10569564160 1248598080 13.225 98 26 51 2 1260584320 5992096 5994144
20:31:56 3908 8192000000 10140140016 10540871680 1228667120 13.042 98 16 32 0 1238236976 5803968 3765888
20:33:02 11689 8192000000 10154733616 10541719552 1238192256 13.130 98 24 48 0 1249865264 6252400 5420608
20:34:14 21847 8192000000 10130435696 10529054720 1197182544 12.750 95 25 49 0 1208215248 5198960 5833744
20:35:37 7901 8192000000 10169681824 10579054592 1278298464 13.497 100 21 41 0 1288364000 5522832 4542704
20:36:46 6701 8192000000 10190678064 10598199296 1294353888 13.644 100 35 70 0 1309092176 6555408 8182880
20:37:53 50521 8192000000 10204246864 10622377984 1309535568 13.782 100 21 43 3 1319364560 4938416 4890576
20:39:57 15663 8192000000 10236983072 10653429760 1335657744 14.018 100 46 92 0 1353404176 6729296 11017136
20:41:18 23829 8192000000 10268876368 10682441728 1356430032 14.205 100 54 108 0 1379843488 11523056 11890400
20:43:34 26708 8192000000 10285160496 10711482368 1375471472 14.376 100 27 54 1 1395735184 14083152 6180560
20:45:04 9122 8192000000 10300169824 10724708352 1385884048 14.469 100 45 90 0 1412926304 16624208 10418048
20:46:22 18079 8192000000 10305909984 10734870528 1393638864 14.538 100 47 92 2 1422864752 17967840 11258048
20:47:45 4169 8192000000 10326147728 10748653568 1400870016 14.603 100 61 122 0 1437470752 22165552 14435184
20:48:54 9544 8192000000 10324137440 10756911104 1406092096 14.649 100 44 87 0 1438257504 21248160 10917248
20:50:10 10751 8192000000 10317274192 10748354560 1397784528 14.575 100 42 84 1 1426461712 18900624 9776560
20:51:29 2599 8192000000 10332762432 10759749632 1399815760 14.593 100 61 120 1 1442187216 27539296 14832160
20:52:41 1922 8192000000 10352778112 10764066816 1399552304 14.591 99 76 152 0 1447944912 30119328 18273280
20:53:45 2140 8192000000 10333906368 10744967168 1384879008 14.460 100 72 144 0 1436232048 33522160 17830880
20:54:53 2214 8192000000 10343326256 10755260416 1391615648 14.520 100 86 167 2 1449506592 37130960 20759984
20:55:59 965 8192000000 10350370720 10769387520 1396646816 14.565 100 69 138 0 1452473504 38462080 17364608
20:57:04 6028 8192000000 10325673792 10726637568 1365459376 14.286 99 75 142 3 1420550384 37235904 17855104
20:58:15 11341 8192000000 10335962608 10759094272 1398427376 14.581 100 39 78 0 1440457440 31415248 10614816
20:59:36 1368 8192000000 10340629728 10742718464 1375443312 14.376 100 85 170 2 1435808416 38654240 21710864
21:00:47 19535 8192000000 10342680240 10763767808 1397299984 14.571 100 46 90 0 1449583584 38988032 13295568
21:02:13 3694 8192000000 10348090272 10764787712 1385639232 14.467 99 61 120 0 1440763136 39711504 15412400
21:03:21 6963 8192000000 10343074176 10771644416 1393394512 14.536 100 37 74 0 1446453744 43028960 10030272
21:04:29 18736 8192000000 10333624256 10770202624 1373914320 14.362 99 35 70 0 1435685872 51619520 10152032
21:06:03 15690 8192000000 10307330144 10759655424 1353865280 14.182 99 19 38 0 1410247312 50455360 5926672
21:07:24 37602 8192000000 10290320784 10745696256 1352973792 14.174 99 16 32 1 1391758736 34871520 3913424
21:09:01 71902 8192000000 10229698336 10664480768 1263019072 13.358 93 10 20 0 1275469760 10375344 2075344
21:11:14 70991 8192000000 10258872192 10683092992 1339375008 14.052 99 46 92 1 1359252656 10284928 9592720
21:13:26 14094 8192000000 10240538208 10672197632 1320762064 13.884 98 36 72 1 1336803168 8128608 7912496
21:14:41 100910 8192000000 10273377120 10734628864 1358429376 14.223 100 21 37 0 1381494752 18885328 4180048
21:17:23 83785 8192000000 10293270512 10731327488 1361107280 14.247 99 18 36 0 1376958032 11487280 4363472
21:19:48 50056 8192000000 10271384800 10718601216 1358929808 14.228 99 16 32 0 1372898144 10349056 3619280
21:21:42 96117 8192000000 10255600480 10704424960 1327190240 13.942 96 15 30 2 1345743072 13877680 4675152
21:24:28 116642 8192000000 10298986304 10750656512 1377579984 14.395 100 21 42 0 1395756320 13624480 4551856
21:27:33 51895 8192000000 10284568144 10749956096 1391381600 14.518 100 9 18 0 1406978400 13685488 1911312
21:29:25 58187 8192000000 10286670256 10760359936 1387732208 14.486 100 8 16 0 1403095904 13505792 1857904
21:31:34 49163 8192000000 10291646320 10738925568 1368851696 14.317 98 20 40 0 1382778368 9214784 4711888
21:33:26 69638 8192000000 10297258784 10753519616 1366033488 14.291 98 4 8 0 1376829392 9878768 917136
21:35:38 88008 8192000000 10280395792 10741633024 1355144528 14.194 97 14 28 0 1374521376 16208208 3168640
21:38:07 73009 8192000000 10306341872 10758389760 1366566736 14.296 98 10 20 0 1385364448 16693168 2104544
21:40:20 100431 8192000000 10301487600 10771693568 1331791472 13.983 95 16 32 0 1349901936 14548880 3561584
21:43:01 59087 8192000000 10273093808 10719043584 1293486848 13.636 93 14 29 0 1306191664 9503888 3200928
21:45:07 127338 8192000000 10286743072 10744729600 1346842336 14.119 97 30 60 2 1362755392 8730400 7182656
21:48:16 10420 8192000000 10273115328 10737029120 1371513360 14.341 100 13 26 0 1390597200 15794032 3289808
21:49:28 71062 8192000000 10245824112 10699456512 1313823712 13.821 96 16 31 0 1327807744 10906560 3077472
21:51:40 30885 8192000000 10257606544 10704818176 1345833824 14.110 99 31 58 2 1359726992 7543728 6349440
21:53:12 35906 8192000000 10272069376 10715889664 1361340160 14.249 100 16 32 0 1370353488 5512480 3500848
21:54:54 81021 8192000000 10255338480 10697355264 1329026384 13.958 98 20 39 1 1340192032 7007968 4157680
21:57:15 50966 8192000000 10241113728 10700546048 1327150224 13.941 98 5 10 2 1338343408 10005808 1187376
21:59:07 43269 8192000000 10274307888 10716307456 1326870192 13.939 97 28 55 2 1343146480 10298976 5977312
22:00:57 43040 8192000000 10274040784 10721427456 1323625360 13.910 97 29 59 0 1342906656 12904176 6377120
22:02:41 47756 8192000000 10258944176 10705211392 1317054608 13.850 97 25 50 0 1339358448 15991648 6312192
22:04:33 28880 8192000000 10265556176 10702135296 1319638528 13.873 97 30 57 0 1333028560 7084512 6305520
22:06:09 58859 8192000000 10261406480 10713858048 1349266480 14.141 100 22 44 0 1374046384 20124224 4655680
22:08:09 78277 8192000000 10290969824 10742398976 1362902256 14.263 99 21 42 0 1381621792 13782880 4936656
22:10:28 120072 8192000000 10282202640 10733576192 1361294992 14.249 98 26 52 0 1382994864 15644224 6055648
22:13:30 53116 8192000000 10272711712 10735521792 1369114208 14.319 99 4 8 0 1375745264 6027536 603520
22:15:24 56910 8192000000 10282185392 10754002944 1371736432 14.343 99 20 41 0 1390503648 14496800 4270416
22:17:24 29128 8192000000 10299321328 10738573312 1372293328 14.348 99 34 68 1 1386653920 6786976 7573616
22:19:00 46957 8192000000 10307928688 10750656512 1391167264 14.516 100 31 62 0 1412578960 13761376 7650320
22:20:48 35565 8192000000 10289239840 10744811520 1363038704 14.265 98 11 22 1 1373455088 8079440 2336944
22:22:26 106315 8192000000 10294905152 10767015936 1375567648 14.377 99 14 28 0 1399901968 21005664 3328656
22:25:14 51280 8192000000 10310149936 10772914176 1394287744 14.544 100 30 60 0 1412845456 11364688 7193024
22:27:08 32494 8192000000 10299440688 10754740224 1354909136 14.192 97 11 22 0 1372774704 15759952 2105616
22:28:48 54879 8192000000 10261021296 10714648576 1355532992 14.197 99 15 30 1 1367658960 8570080 3555888
22:30:43 56770 8192000000 10260370992 10711089152 1343430224 14.088 98 18 34 0 1357371968 10038544 3903200
22:32:44 49565 8192000000 10264273712 10722017280 1363491760 14.269 100 7 14 0 1377751856 12599056 1661040
22:34:45 63930 8192000000 10256198832 10708127744 1336476512 14.026 98 14 28 0 1350444320 11033872 2933936
22:36:52 41828 8192000000 10227179184 10670460928 1275429232 13.471 94 15 30 1 1286561472 7944416 3187824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-01-2023 22:41:04 to 07-01-2023 22:41:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-01-2023 22:41:21 to 07-01-2023 22:41:23.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          241161
MON$OLDEST_ACTIVE               243685
MON$OLDEST_SNAPSHOT             243685
MON$NEXT_TRANSACTION            243685
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                       2021440
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.33652 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33652 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33652 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 ---
:84: 
:85: mscs-test-161.budzdorov.ru    Sat Jan  7 22:38:37 2023
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Sat Jan  7 22:38:38 2023
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:91: 
:92: 
:93: mscs-test-161.budzdorov.ru    Sat Jan  7 22:38:38 2023
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:95: 
:96: 
:97: mscs-test-161.budzdorov.ru    Sat Jan  7 22:38:43 2023
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:99: 
--- end of diff output ---

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

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