Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.10.2022 19: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 15513864 kB
MemAvailable 30514968 kB
Buffers 0 kB
Cached 16180028 kB
SwapCached 2200 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 2022-10-07 19:38:28
DTS_END                         2022-10-07 19:38:28.3470
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/47844
TRN_ID                          238030
ATT_ID                          609
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: 9293.96 19927 1115275
customer order: creation 168.84 433 20261
customer order: refuse 14.75 371 1770
order to supplier: creation 46.11 1288 5533
order to supplier: removal 3.67 97027 440
invoice (draft): creation 46.12 3149 5534
invoice (draft): removal 54.47 737 6536
invoice accept: apply 40.72 16935 4886
invoice accept: cancel 49.03 795 5883
customer reserve: creation 3786.81 167 454417
customer reserve: removal 4789.87 59 574784
realization accept: apply 82.06 158 9847
realization accept: cancel 84.49 96 10139
payment from customer: creation 57.89 101 6947
payment from customer: removal 5.44 92 653
payment to supplier: creation 48.76 110 5851
payment to supplier: removal 3.45 63 414
service: total inventory turnovers 4.28 1024 514
service: total monetary turnovers 6.41 41 769
service: aggregate perf. data 0.78 21077 93
service: refresh index statistics 0.03 254807 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-10-2022 19:39:29 to 07-10-2022 19:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4100
TEST_TIME 2 3816
TEST_TIME 3 4981
TEST_TIME 4 5097
TEST_TIME 5 5060
TEST_TIME 6 5311
TEST_TIME 7 5668
TEST_TIME 8 5553
TEST_TIME 9 5575
TEST_TIME 10 5837
TEST_TIME 11 5903
TEST_TIME 12 6074
TEST_TIME 13 6392
TEST_TIME 14 6394
TEST_TIME 15 6293
TEST_TIME 16 6650
TEST_TIME 17 7096
TEST_TIME 18 7629
TEST_TIME 19 7617
TEST_TIME 20 7928
TEST_TIME 21 8417
TEST_TIME 22 8900
TEST_TIME 23 9839
TEST_TIME 24 9917
TEST_TIME 25 9990
TEST_TIME 26 10032
TEST_TIME 27 10036
TEST_TIME 28 10504
TEST_TIME 29 10177
TEST_TIME 30 10040
TEST_TIME 31 10018
TEST_TIME 32 10155
TEST_TIME 33 10109
TEST_TIME 34 9940
TEST_TIME 35 9862
TEST_TIME 36 9751
TEST_TIME 37 9756
TEST_TIME 38 9968
TEST_TIME 39 9863
TEST_TIME 40 9819
TEST_TIME 41 9657
TEST_TIME 42 9497
TEST_TIME 43 9553
TEST_TIME 44 9574
TEST_TIME 45 9621
TEST_TIME 46 9653
TEST_TIME 47 9508
TEST_TIME 48 9380
TEST_TIME 49 9441
TEST_TIME 50 9445
TEST_TIME 51 9396
TEST_TIME 52 9382
TEST_TIME 53 9432
TEST_TIME 54 9533
TEST_TIME 55 9547
TEST_TIME 56 9537
TEST_TIME 57 9571
TEST_TIME 58 9653
TEST_TIME 59 9662
TEST_TIME 60 9594
TEST_TIME 61 9634
TEST_TIME 62 9712
TEST_TIME 63 9771
TEST_TIME 64 9624
TEST_TIME 65 9612
TEST_TIME 66 9579
TEST_TIME 67 9515
TEST_TIME 68 9432
TEST_TIME 69 9415
TEST_TIME 70 9355
TEST_TIME 71 9427
TEST_TIME 72 9466
TEST_TIME 73 9420
TEST_TIME 74 9454
TEST_TIME 75 9453
TEST_TIME 76 9426
TEST_TIME 77 9533
TEST_TIME 78 9505
TEST_TIME 79 9566
TEST_TIME 80 9549
TEST_TIME 81 9566
TEST_TIME 82 9551
TEST_TIME 83 9564
TEST_TIME 84 9495
TEST_TIME 85 9429
TEST_TIME 86 9423
TEST_TIME 87 9379
TEST_TIME 88 9336
TEST_TIME 89 9352
TEST_TIME 90 9288
TEST_TIME 91 9269
TEST_TIME 92 9247
TEST_TIME 93 9278
TEST_TIME 94 9280
TEST_TIME 95 9316
TEST_TIME 96 9298
TEST_TIME 97 9406
TEST_TIME 98 9358
TEST_TIME 99 9466
TEST_TIME 100 9452
TEST_TIME 101 9494
TEST_TIME 102 9486
TEST_TIME 103 9564
TEST_TIME 104 9529
TEST_TIME 105 9535
TEST_TIME 106 9485
TEST_TIME 107 9421
TEST_TIME 108 9416
TEST_TIME 109 9403
TEST_TIME 110 9370
TEST_TIME 111 9318
TEST_TIME 112 9291
TEST_TIME 113 9330
TEST_TIME 114 9310
TEST_TIME 115 9274
TEST_TIME 116 9269
TEST_TIME 117 9251
TEST_TIME 118 9287
TEST_TIME 119 9287
TEST_TIME 120 9296

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-10-2022 19:39:32 to 07-10-2022 19:39:36.

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 1536487 1536487 0 0.00 0 16930 6 0 0
doc_list_biud 1536486 1536486 0 0.00 0 42715 28 0 0
sp_add_invoice_to_stock 5012 4886 126 2.51 35 158854 16935 0 126
sp_cancel_adding_invoice 5883 5883 0 0.00 5 84002 795 0 0
sp_cancel_client_order 1770 1770 0 0.00 4 18946 372 0 0
sp_cancel_customer_reserve 574784 574784 0 0.00 0 26776 60 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 3 9051 93 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 3482 64 0 0
sp_cancel_supplier_invoice 6545 6536 9 0.13 2 21658 737 0 9
sp_cancel_supplier_order 440 440 0 0.00 7 1144995 97028 0 0
sp_cancel_write_off 10139 10139 0 0.00 1 10956 97 0 0
sp_client_order 20261 20261 0 0.00 3 19531 434 0 0
sp_customer_reserve 454417 454417 0 0.00 0 59510 167 0 0
sp_fill_shopping_cart 31326 31326 0 0.00 1 14788 36 0 0
sp_fill_shopping_cart_clo_res 454414 454414 0 0.00 0 11739 16 0 0
sp_get_clo_for_invoice 4883 4883 0 0.00 0 24953 366 0 0
sp_kill_cost_storno 17089 17089 0 0.00 0 14984 52 0 0
sp_kill_qstorno_ret_qs2qd 1166683 1166683 0 0.00 0 42712 32 0 0
sp_kill_qty_storno 614281 614281 0 0.00 0 42714 69 0 0
sp_lock_selected_doc 20528 20528 0 0.00 0 9043 6 0 0
sp_make_cost_storno 27528 27528 0 0.00 0 16736 50 0 0
sp_multiply_rows_for_pdistr 27527 27527 0 0.00 0 7046 27 0 0
sp_multiply_rows_for_qdistr 20261 20261 0 0.00 0 12527 206 0 0
sp_pay_from_customer 6947 6947 0 0.00 4 19924 101 0 0
sp_pay_to_supplier 5851 5851 0 0.00 4 13165 110 0 0
sp_payment_common 12798 12798 0 0.00 1 11310 21 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 18939 333 0 0
sp_qd_handle_on_invoice_upd_sts 10766 10766 0 0.00 0 25799 302 0 0
sp_qd_handle_on_reserve_upd_sts 9846 9846 0 0.00 0 14324 60 0 0
sp_reserve_write_off 9847 9847 0 0.00 6 14386 158 0 0
sp_supplier_invoice 5534 5534 0 0.00 5 86355 3149 0 0
sp_supplier_order 5533 5533 0 0.00 4 51366 1288 0 0
srv_aggregate_perf_data 94 93 1 1.06 2814 240422 21078 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1408 170953 46299 0 0
srv_find_qd_qs_mism 1068452 1068452 0 0.00 0 16922 5 0 0
srv_make_invnt_saldo 514 514 0 0.00 27 46086 1025 0 0
srv_make_money_saldo 769 769 0 0.00 1 9205 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 101566 374978 254807 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4887 4887 0 0.00 0 11224 174 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 3075 101 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 2962 15 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 4710 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 6572 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 2772 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 724 8 0 0
t$perf-norm:sp_cancel_supplier_order 440 440 0 0.00 0 21706 1498 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 5563 19 0 0
t$perf-norm:sp_client_order 20261 20261 0 0.00 0 7557 17 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 2840 8 0 0
t$perf-norm:sp_pay_from_customer 6947 6947 0 0.00 0 5889 10 0 0
t$perf-norm:sp_pay_to_supplier 5851 5851 0 0.00 0 8894 14 0 0
t$perf-norm:sp_reserve_write_off 9848 9848 0 0.00 0 6214 9 0 0
t$perf-norm:sp_supplier_invoice 5534 5534 0 0.00 0 16579 19 0 0
t$perf-norm:sp_supplier_order 5533 5533 0 0.00 0 7012 16 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 777 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 21 1 0 0
t$perf-norm:srv_make_invnt_saldo 514 514 0 0.00 0 912 15 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 1086 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10018 9766 252 2.51 0 9079 17 0 252
v_all_customers 37044 37044 0 0.00 0 23 1 0 0
v_all_suppliers 30706 30706 0 0.00 0 29 1 0 0
v_all_wares 40522 40522 0 0.00 0 88 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 1234 7 0 0
v_cancel_client_order 3540 3540 0 0.00 0 898 3 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 1279 3 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 1403 4 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 660 4 0 0
v_cancel_supplier_invoice 844 826 18 2.13 0 1530 14 0 18
v_cancel_supplier_order 880 880 0 0.00 0 1109 5 0 0
v_cancel_write_off 1986 1986 0 0.00 0 1703 11 0 0
v_max_id_clo_ord 5533 5533 0 0.00 0 1328 2 0 0
v_max_id_clo_res 993 993 0 0.00 0 3401 24 0 0
v_max_id_ord_sup 5532 5532 0 0.00 0 2454 4 0 0
v_max_non_paid_invoice 1563 1563 0 0.00 0 390 2 0 0
v_max_non_paid_realizn 3730 3730 0 0.00 0 2393 4 0 0
v_min_id_clo_ord 5533 5533 0 0.00 0 5545 6 0 0
v_min_id_clo_res 993 993 0 0.00 0 1404 8 0 0
v_min_id_ord_sup 5532 5532 0 0.00 0 11474 6 0 0
v_min_non_paid_invoice 5851 5851 0 0.00 0 8741 14 0 0
v_min_non_paid_realizn 6947 6947 0 0.00 0 11306 14 0 0
v_our_firm 9912 9912 0 0.00 0 13 1 0 0
v_reserve_write_off 19692 19692 0 0.00 0 2583 4 0 0
x_lock_depdocs_on_canc_invoice 12006 12006 0 0.00 0 13533 107 0 0
x_lock_depdocs_on_canc_sup_ord 440 440 0 0.00 0 3976 311 0 0
x_make_qstorno_1000_1200 5533 5533 0 0.00 1 51315 1215 0 0
x_make_qstorno_1200_2000 5534 5534 0 0.00 3 86347 3023 0 0
x_make_qstorno_2100_3300 454417 454417 0 0.00 0 48531 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-10-2022 19:39:37 to 07-10-2022 19:39:41.

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
17:08:32 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:33 20201 8192000000 9903163968 10213822464 985042720 10.733 100 86 172 0 1014413520 6502064 22868736
17:11:02 25284 8192000000 9954774080 10279809024 1036255248 11.229 100 85 170 0 1066467264 8555632 21656384
17:12:31 16612 8192000000 9996414512 10332954624 1079370800 11.641 100 88 176 0 1111458656 10123840 21964016
17:14:01 6069 8192000000 10050056960 10400342016 1137804416 12.195 100 83 166 0 1168232336 11259776 19168144
17:15:10 5974 8192000000 10081681936 10443808768 1171967600 12.515 100 76 151 0 1201418864 11880720 17570544
17:16:24 2510 8192000000 10119055152 10491961344 1213746032 12.904 100 77 151 0 1242586624 12074320 16766272
17:17:28 2061 8192000000 10166855584 10540756992 1254154144 13.276 100 91 175 2 1285381840 12003728 19223968
17:18:32 1673 8192000000 10196796176 10574442496 1280721936 13.520 100 90 180 2 1314830752 14195968 19912848
17:19:36 35527 8192000000 10198460592 10600423424 1300912176 13.704 100 65 130 0 1325556512 10034912 14609424
17:21:13 4115 8192000000 10210559680 10619473920 1315744416 13.838 100 42 84 1 1338177632 12533488 9899728
17:22:18 7635 8192000000 10208068368 10622111744 1317818208 13.857 100 13 26 0 1326513936 5828560 2867168
17:23:32 35081 8192000000 10225281904 10641031168 1325516016 13.927 100 31 62 0 1338746208 6179088 7051104
17:25:11 5783 8192000000 10230041456 10647875584 1330896960 13.975 100 25 50 1 1343337712 6827680 5613072
17:26:17 7361 8192000000 10227990464 10646896640 1336286704 14.024 100 16 32 0 1345366800 5694560 3385536
17:27:27 51236 8192000000 10200057120 10617389056 1273004560 13.449 96 47 93 1 1289490160 5955056 10530544
17:29:21 4898 8192000000 10168859536 10576220160 1253797312 13.273 97 24 48 1 1264519200 5316576 5405312
17:30:27 8150 8192000000 10144219072 10543214592 1222098448 12.981 97 23 46 0 1233194256 5992416 5103392
17:31:39 10676 8192000000 10143661056 10554359808 1238936848 13.136 98 11 22 0 1246638672 5251904 2449920
17:32:50 35107 8192000000 10149739520 10550480896 1230769856 13.061 97 22 33 2 1238102048 4003712 3328480
17:34:26 5851 8192000000 10179842896 10588631040 1277809856 13.493 99 15 30 0 1286295360 4810336 3675168
17:35:34 14032 8192000000 10199170208 10613551104 1306230128 13.752 100 29 58 2 1318405440 5065088 7110224
17:36:49 19842 8192000000 10195376992 10617507840 1312506304 13.809 100 22 43 0 1324227136 4868784 6852048
17:38:10 23227 8192000000 10218440800 10638942208 1329829520 13.966 100 15 26 0 1337025968 4563968 2632480
17:39:42 46567 8192000000 10260068880 10677530624 1353993136 14.183 100 43 87 0 1374442528 10556640 9892752
17:41:34 28468 8192000000 10274142800 10697424896 1358943904 14.228 99 32 62 0 1379999776 13257568 7798304
17:43:09 21584 8192000000 10294697888 10718117888 1380608048 14.422 100 40 76 1 1404876512 15070432 9198032
17:44:34 2451 8192000000 10312875152 10732220416 1393857568 14.540 100 51 100 0 1424179824 18707392 11614864
17:45:47 4883 8192000000 10323044672 10747990016 1404022784 14.631 100 48 96 0 1436185424 20948544 11214096
17:46:56 20530 8192000000 10343136144 10765619200 1409613872 14.681 100 67 130 1 1451013632 26027952 15371808
17:48:19 3133 8192000000 10348018928 10769604608 1413105536 14.712 100 56 106 1 1455048464 29019568 12923360
17:49:26 5119 8192000000 10353186512 10767921152 1412618048 14.707 100 69 137 0 1460439776 31280832 16540896
17:50:33 3164 8192000000 10353381936 10772557824 1401608592 14.609 99 63 122 0 1450349280 33213008 15527680
17:51:41 5436 8192000000 10329002496 10743427072 1385114672 14.462 100 70 139 0 1438529296 36090496 17324128
17:52:52 2859 8192000000 10345492768 10753474560 1393189536 14.534 100 75 151 1 1451054944 38665088 19200320
17:54:00 5186 8192000000 10332877776 10736590848 1366042704 14.292 98 65 123 0 1427652128 45411392 16198032
17:55:12 6233 8192000000 10343374256 10757062656 1390719248 14.512 100 64 128 1 1449237968 40606496 17912224
17:56:23 1408 8192000000 10342343104 10765996032 1395045584 14.551 100 58 117 0 1450411968 39753040 15613344
17:57:41 21777 8192000000 10334651200 10758832128 1383568928 14.448 100 39 78 0 1439621600 44870784 11181888
17:59:07 19440 8192000000 10319552640 10759622656 1379737040 14.414 100 33 66 1 1434475088 45204624 9533424
18:00:32 14019 8192000000 10307656064 10749280256 1369951168 14.327 99 33 67 1 1412923936 34073744 8899024
18:01:47 36447 8192000000 10291614944 10730885120 1363952320 14.273 99 21 42 0 1388507088 19942048 4612720
18:03:34 29639 8192000000 10308823072 10747801600 1386940720 14.479 100 26 53 1 1411441792 17951504 6549568
18:05:04 24643 8192000000 10294109488 10739896320 1368956928 14.318 99 14 28 0 1395450560 22887360 3606272
18:06:31 123025 8192000000 10277066800 10716270592 1321324224 13.889 96 17 35 0 1335702720 10623424 3755072
18:09:49 83564 8192000000 10283193584 10719223808 1360114688 14.238 99 19 38 0 1373582400 9595632 3872080
18:12:17 59345 8192000000 10297198720 10736332800 1375543088 14.377 99 35 72 0 1395553536 11473232 8537216
18:14:18 36420 8192000000 10305200656 10747412480 1386267024 14.473 100 33 68 0 1406982896 12407056 8308816
18:15:59 14778 8192000000 10316626896 10754924544 1400528192 14.600 100 60 120 2 1427788112 13436416 13823504
18:17:17 48729 8192000000 10304653584 10757210112 1396127312 14.561 100 11 22 0 1408772576 10366400 2278864
18:19:16 22561 8192000000 10307276080 10762174464 1391864336 14.522 99 10 17 1 1412279792 18286976 2128480
18:21:01 72061 8192000000 10315257744 10782392320 1405284864 14.642 100 24 48 0 1433454480 21842656 6326960
18:23:28 24607 8192000000 10328322944 10770694144 1408708784 14.672 100 57 114 1 1436439264 14714928 13015552
18:24:53 78011 8192000000 10312679680 10773250048 1399507888 14.591 99 29 57 2 1417286784 11281680 6497216
18:27:34 53763 8192000000 10293467888 10766393344 1394623600 14.547 100 4 8 0 1415918992 20473536 821856
18:29:28 41123 8192000000 10307885616 10754752512 1369404656 14.322 98 27 54 1 1388907744 13565136 5937952
18:31:12 87789 8192000000 10304250240 10773532672 1390621152 14.511 99 11 22 0 1412918512 19783808 2513552
18:33:48 65085 8192000000 10316824944 10774663168 1385418160 14.465 98 23 44 0 1404467824 14142080 4907584
18:35:53 107765 8192000000 10291699392 10744164352 1289618416 13.601 92 13 26 0 1301364256 9177936 2567904
18:38:41 11812 8192000000 10306656048 10748035072 1382033664 14.435 99 42 82 1 1399068208 8147072 8887472
18:39:53 20501 8192000000 10295284944 10739306496 1369183168 14.320 99 36 72 0 1387407504 10567680 7656656
18:41:17 152432 8192000000 10250703904 10702573568 1323442736 13.908 96 11 22 0 1337409056 11775792 2190528
18:44:51 34057 8192000000 10259497232 10720198656 1349428224 14.142 98 22 42 1 1364180320 10129392 4622704
18:47:12 53361 8192000000 10253582016 10707968000 1320540080 13.882 97 18 36 0 1342126752 17542944 4043728
18:49:06 49670 8192000000 10249250368 10701996032 1292345440 13.626 95 22 43 0 1309937664 12331696 5260528
18:50:59 79593 8192000000 10281982240 10725834752 1333187872 13.996 97 18 35 1 1347455584 10482304 3785408
18:53:19 80003 8192000000 10286786224 10736607232 1367524480 14.305 99 25 50 0 1385722736 12094688 6103568
18:55:44 79394 8192000000 10269252768 10708426752 1300627920 13.701 95 27 54 0 1316912160 10704224 5580016
18:58:04 25628 8192000000 10279753328 10716131328 1352288544 14.168 99 31 62 0 1375893760 17174256 6430960
18:59:30 14085 8192000000 10260884512 10696433664 1335664144 14.018 99 33 66 0 1352911376 9978128 7269104
19:00:50 70132 8192000000 10267627104 10726891520 1345867936 14.110 99 13 26 0 1368583280 19686704 3028640
19:05:07 47307 8192000000 10263835808 10720768000 1372421712 14.349 100 9 18 0 1380583120 6042528 2118880
19:06:55 57063 8192000000 10274790752 10736136192 1355793408 14.200 98 15 30 0 1372988992 13848640 3346944
19:08:54 80922 8192000000 10283073840 10742530048 1386560704 14.475 100 19 38 1 1400781104 9975088 4245312
19:11:15 44196 8192000000 10288048816 10750480384 1388085856 14.489 100 13 26 0 1404981888 13798496 3097536
19:13:01 39871 8192000000 10294350160 10752794624 1361323072 14.249 97 23 46 0 1377868176 10675904 5869200
19:14:54 81957 8192000000 10308010192 10770206720 1386592176 14.475 99 12 24 0 1407002080 17518864 2891040
19:17:30 49989 8192000000 10305189024 10770006016 1387471008 14.483 99 17 34 0 1405494720 13868976 4154736
19:19:25 47042 8192000000 10326187488 10774781952 1411006880 14.693 100 26 51 0 1428014848 11414704 5593264
19:21:12 170953 8192000000 10288724496 10740420608 1277838064 13.493 91 14 28 1 1293144128 12374160 2931904
19:25:04 113688 8192000000 10291371040 10745815040 1344712464 14.100 96 19 38 0 1359724752 10637952 4374336
19:28:01 34426 8192000000 10264397552 10725613568 1367364768 14.303 99 12 24 0 1376595680 6790240 2440672
19:29:39 51922 8192000000 10258687360 10710274048 1332619520 13.991 97 14 28 0 1346774992 10736848 3418624
19:31:32 53560 8192000000 10267463936 10735284224 1351717600 14.163 98 7 14 0 1364762224 11534928 1509696
19:34:22 55508 8192000000 10292842624 10744307712 1349497024 14.143 97 17 34 1 1367301312 13837584 3966704
19:36:26 72803 8192000000 10297942672 10748665856 1361936096 14.255 98 23 46 0 1388737104 21425328 5375680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-10-2022 19:39:43 to 07-10-2022 19:39:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 126 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-10-2022 19:39:59 to 07-10-2022 19:40:01.

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          238029
MON$OLDEST_ACTIVE               238838
MON$OLDEST_SNAPSHOT             238838
MON$NEXT_TRANSACTION            238838
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                       2026400
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.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 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 ---
:363: 
:364: mscs-test-161.budzdorov.ru    Fri Oct  7 19:38:30 2022
:365:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:366: 
:367: 
:368: mscs-test-161.budzdorov.ru    Fri Oct  7 19:38:34 2022
:369:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:370: 
:371: 
:372: mscs-test-161.budzdorov.ru    Fri Oct  7 19:38:42 2022
:373:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:374: 
--- end of diff output ---

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

07.10.22 19:40:02. 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-10-07 19:40:05 No severe PSQL-related problems occured
07.10.22 19:40:06. End of report.