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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15023820 kB
MemAvailable 30071108 kB
Buffers 0 kB
Cached 16659872 kB
SwapCached 3420 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-12-21 22:38:32
DTS_END                         2022-12-21 22:38:32.1400
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/47078
TRN_ID                          252985
ATT_ID                          677
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: 9416.48 15788 1129978
customer order: creation 181.22 322 21746
customer order: refuse 14.92 312 1790
order to supplier: creation 48.68 1227 5842
order to supplier: removal 3.38 85170 405
invoice (draft): creation 47.05 2990 5646
invoice (draft): removal 50.47 705 6056
invoice accept: apply 43.80 16266 5256
invoice accept: cancel 46.16 711 5539
customer reserve: creation 4101.57 160 492188
customer reserve: removal 4576.71 49 549205
realization accept: apply 88.44 147 10613
realization accept: cancel 80.27 98 9632
payment from customer: creation 61.65 105 7398
payment from customer: removal 5.97 69 716
payment to supplier: creation 51.03 106 6123
payment to supplier: removal 3.48 56 417
service: total inventory turnovers 4.22 2676 506
service: total monetary turnovers 6.46 20 775
service: aggregate perf. data 1.01 14076 121
service: refresh index statistics 0.03 190485 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-12-2022 22:39:19 to 21-12-2022 22:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4088
TEST_TIME 2 4649
TEST_TIME 3 5390
TEST_TIME 4 5702
TEST_TIME 5 5243
TEST_TIME 6 5384
TEST_TIME 7 5153
TEST_TIME 8 5112
TEST_TIME 9 5083
TEST_TIME 10 5296
TEST_TIME 11 5605
TEST_TIME 12 5891
TEST_TIME 13 6541
TEST_TIME 14 6413
TEST_TIME 15 6392
TEST_TIME 16 6597
TEST_TIME 17 7047
TEST_TIME 18 7071
TEST_TIME 19 7355
TEST_TIME 20 7530
TEST_TIME 21 8221
TEST_TIME 22 8100
TEST_TIME 23 8829
TEST_TIME 24 8861
TEST_TIME 25 9225
TEST_TIME 26 9357
TEST_TIME 27 9840
TEST_TIME 28 9978
TEST_TIME 29 10338
TEST_TIME 30 10254
TEST_TIME 31 10090
TEST_TIME 32 10134
TEST_TIME 33 10274
TEST_TIME 34 9994
TEST_TIME 35 9824
TEST_TIME 36 9918
TEST_TIME 37 9807
TEST_TIME 38 9728
TEST_TIME 39 9718
TEST_TIME 40 9689
TEST_TIME 41 9686
TEST_TIME 42 9630
TEST_TIME 43 9611
TEST_TIME 44 9559
TEST_TIME 45 9606
TEST_TIME 46 9540
TEST_TIME 47 9435
TEST_TIME 48 9353
TEST_TIME 49 9355
TEST_TIME 50 9338
TEST_TIME 51 9307
TEST_TIME 52 9392
TEST_TIME 53 9522
TEST_TIME 54 9537
TEST_TIME 55 9557
TEST_TIME 56 9619
TEST_TIME 57 9633
TEST_TIME 58 9605
TEST_TIME 59 9647
TEST_TIME 60 9631
TEST_TIME 61 9686
TEST_TIME 62 9745
TEST_TIME 63 9648
TEST_TIME 64 9520
TEST_TIME 65 9567
TEST_TIME 66 9574
TEST_TIME 67 9538
TEST_TIME 68 9479
TEST_TIME 69 9413
TEST_TIME 70 9366
TEST_TIME 71 9374
TEST_TIME 72 9397
TEST_TIME 73 9410
TEST_TIME 74 9451
TEST_TIME 75 9415
TEST_TIME 76 9411
TEST_TIME 77 9409
TEST_TIME 78 9538
TEST_TIME 79 9539
TEST_TIME 80 9473
TEST_TIME 81 9560
TEST_TIME 82 9536
TEST_TIME 83 9530
TEST_TIME 84 9594
TEST_TIME 85 9513
TEST_TIME 86 9478
TEST_TIME 87 9448
TEST_TIME 88 9442
TEST_TIME 89 9384
TEST_TIME 90 9326
TEST_TIME 91 9300
TEST_TIME 92 9316
TEST_TIME 93 9309
TEST_TIME 94 9333
TEST_TIME 95 9362
TEST_TIME 96 9386
TEST_TIME 97 9385
TEST_TIME 98 9372
TEST_TIME 99 9363
TEST_TIME 100 9390
TEST_TIME 101 9494
TEST_TIME 102 9607
TEST_TIME 103 9618
TEST_TIME 104 9584
TEST_TIME 105 9573
TEST_TIME 106 9516
TEST_TIME 107 9464
TEST_TIME 108 9488
TEST_TIME 109 9457
TEST_TIME 110 9402
TEST_TIME 111 9365
TEST_TIME 112 9387
TEST_TIME 113 9371
TEST_TIME 114 9361
TEST_TIME 115 9410
TEST_TIME 116 9389
TEST_TIME 117 9445
TEST_TIME 118 9471
TEST_TIME 119 9455
TEST_TIME 120 9420

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-12-2022 22:39:22 to 21-12-2022 22:39:26.

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 1584848 1584848 0 0.00 0 29141 5 0 0
doc_list_biud 1584848 1584848 0 0.00 0 41138 22 0 0
sp_add_invoice_to_stock 5345 5256 89 1.66 14 177164 16267 0 89
sp_cancel_adding_invoice 5539 5539 0 0.00 5 72824 711 0 0
sp_cancel_client_order 1790 1790 0 0.00 4 8232 312 0 0
sp_cancel_customer_reserve 549205 549205 0 0.00 0 41142 50 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 2 6613 70 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 1820 57 0 0
sp_cancel_supplier_invoice 6066 6056 10 0.16 1 20158 705 0 10
sp_cancel_supplier_order 405 405 0 0.00 5 1032431 85171 0 0
sp_cancel_write_off 9632 9632 0 0.00 1 28178 98 0 0
sp_client_order 21746 21746 0 0.00 3 25371 322 0 0
sp_customer_reserve 492188 492188 0 0.00 0 40744 161 0 0
sp_fill_shopping_cart 33234 33234 0 0.00 1 14487 37 0 0
sp_fill_shopping_cart_clo_res 492185 492185 0 0.00 0 14828 12 0 0
sp_get_clo_for_invoice 5252 5252 0 0.00 0 26774 273 0 0
sp_kill_cost_storno 16304 16304 0 0.00 0 28176 46 0 0
sp_kill_qstorno_ret_qs2qd 1114503 1114503 0 0.00 0 41128 27 0 0
sp_kill_qty_storno 588492 588492 0 0.00 0 41138 59 0 0
sp_lock_selected_doc 21764 21764 0 0.00 0 8158 5 0 0
sp_make_cost_storno 29386 29386 0 0.00 0 29134 45 0 0
sp_multiply_rows_for_pdistr 29386 29386 0 0.00 0 7008 23 0 0
sp_multiply_rows_for_qdistr 21746 21746 0 0.00 0 12696 150 0 0
sp_pay_from_customer 7398 7398 0 0.00 4 14450 106 0 0
sp_pay_to_supplier 6123 6123 0 0.00 4 12310 107 0 0
sp_payment_common 13521 13521 0 0.00 2 11670 27 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 8222 282 0 0
sp_qd_handle_on_invoice_upd_sts 10791 10791 0 0.00 0 13325 271 0 0
sp_qd_handle_on_reserve_upd_sts 10613 10613 0 0.00 0 13347 49 0 0
sp_reserve_write_off 10613 10613 0 0.00 6 29153 148 0 0
sp_supplier_invoice 5646 5646 0 0.00 7 71556 2990 0 0
sp_supplier_order 5842 5842 0 0.00 4 31698 1228 0 0
srv_aggregate_perf_data 121 121 0 0.00 2927 152784 14077 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2788 117355 45827 0 0
srv_find_qd_qs_mism 1079344 1079344 0 0.00 0 10324 5 0 0
srv_make_invnt_saldo 506 506 0 0.00 43 770002 2676 0 0
srv_make_money_saldo 775 775 0 0.00 1 361 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 117954 257594 190486 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5256 5256 0 0.00 0 14543 138 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 0 3067 89 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 5007 14 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 1612 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 302 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 595 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 974 12 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 18451 1274 0 0
t$perf-norm:sp_cancel_write_off 1046 1046 0 0.00 0 4763 8 0 0
t$perf-norm:sp_client_order 21746 21746 0 0.00 0 7678 13 0 0
t$perf-norm:sp_customer_reserve 999 999 0 0.00 0 1854 6 0 0
t$perf-norm:sp_pay_from_customer 7398 7398 0 0.00 0 21251 10 0 0
t$perf-norm:sp_pay_to_supplier 6123 6123 0 0.00 0 23618 13 0 0
t$perf-norm:sp_reserve_write_off 10613 10613 0 0.00 0 4457 7 0 0
t$perf-norm:sp_supplier_invoice 5646 5646 0 0.00 0 6084 17 0 0
t$perf-norm:sp_supplier_order 5842 5842 0 0.00 0 3147 10 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 425 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 235 7 0 0
t$perf-norm:srv_make_invnt_saldo 506 506 0 0.00 0 725 9 0 0
t$perf-norm:srv_make_money_saldo 775 775 0 0.00 0 3085 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10680 10502 178 1.66 0 6808 19 0 178
v_all_customers 39586 39586 0 0.00 0 21 0 0 0
v_all_suppliers 31996 31996 0 0.00 0 21 1 0 0
v_all_wares 43492 43492 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 1770 8 0 0
v_cancel_client_order 3580 3580 0 0.00 0 4229 6 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 6492 11 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 3848 9 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 1009 4 0 0
v_cancel_supplier_invoice 796 776 20 2.51 0 1158 15 0 20
v_cancel_supplier_order 810 810 0 0.00 0 2345 5 0 0
v_cancel_write_off 2092 2092 0 0.00 0 2584 13 0 0
v_max_id_clo_ord 5841 5841 0 0.00 0 3567 2 0 0
v_max_id_clo_res 999 999 0 0.00 0 2468 15 0 0
v_max_id_ord_sup 5646 5646 0 0.00 0 6485 7 0 0
v_max_non_paid_invoice 1612 1612 0 0.00 0 1482 3 0 0
v_max_non_paid_realizn 3999 3999 0 0.00 0 5974 5 0 0
v_min_id_clo_ord 5841 5841 0 0.00 0 4216 5 0 0
v_min_id_clo_res 999 999 0 0.00 0 4192 6 0 0
v_min_id_ord_sup 5646 5646 0 0.00 0 7386 8 0 0
v_min_non_paid_invoice 6123 6123 0 0.00 0 10245 17 0 0
v_min_non_paid_realizn 7398 7398 0 0.00 0 10800 21 0 0
v_our_firm 10704 10704 0 0.00 0 29 1 0 0
v_reserve_write_off 21226 21226 0 0.00 0 5308 7 0 0
x_lock_depdocs_on_canc_invoice 11207 11207 0 0.00 0 19240 103 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 18477 360 0 0
x_make_qstorno_1000_1200 5842 5842 0 0.00 1 31659 1163 0 0
x_make_qstorno_1200_2000 5646 5646 0 0.00 2 68431 2841 0 0
x_make_qstorno_2100_3300 492188 492188 0 0.00 0 40744 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-12-2022 22:39:27 to 21-12-2022 22:39:31.

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:36 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:37 25190 8192000000 9922186464 10236145664 1005399184 10.931 100 88 176 0 1034222048 6015472 22807392
20:11:10 16610 8192000000 9961656528 10288029696 1049285104 11.354 100 91 182 0 1081193216 8754368 23153744
20:12:35 11140 8192000000 9991419280 10323189760 1075012528 11.600 100 93 186 0 1108134272 10122992 22998752
20:13:51 5549 8192000000 10036632256 10383347712 1122958144 12.055 100 82 164 0 1153178944 10789248 19431552
20:15:02 3525 8192000000 10070643408 10427121664 1161283952 12.415 100 79 156 1 1191195376 12181840 17729584
20:16:07 4749 8192000000 10113866672 10482343936 1212628512 12.893 100 79 156 0 1241306752 11495344 17182896
20:17:15 1623 8192000000 10156867840 10534006784 1247202960 13.213 100 83 165 0 1277774784 12286224 18285600
20:18:19 1201 8192000000 10189510912 10569732096 1276123936 13.478 100 88 174 0 1309123648 13160976 19838736
20:19:22 1949 8192000000 10219542816 10605502464 1303654288 13.728 100 85 162 1 1334151312 13148192 17348832
20:20:25 6645 8192000000 10212751536 10613645312 1312075360 13.805 100 51 101 0 1330994208 6332336 12586512
20:21:32 4160 8192000000 10203725600 10616958976 1317794000 13.857 100 29 59 0 1331513120 7429136 6289984
20:22:40 8642 8192000000 10207630784 10629922816 1326854672 13.939 100 14 29 1 1336050928 5493200 3703056
20:23:51 73205 8192000000 10226481712 10649214976 1334622464 14.009 100 22 45 0 1344724320 4861168 5240688
20:26:05 3530 8192000000 10231964608 10653433856 1341034080 14.067 100 26 51 0 1353196976 6238704 5924192
20:27:12 8908 8192000000 10219108240 10636582912 1310739584 13.793 98 27 54 0 1323813392 6045392 7028416
20:28:22 68769 8192000000 10131844560 10534305792 1109010080 11.923 85 22 43 1 1118130992 4171296 4949616
20:30:35 4287 8192000000 10136968224 10532225024 1211865168 12.886 97 29 61 0 1225618592 5580240 8173184
20:31:40 5003 8192000000 10145340160 10544771072 1244801328 13.190 100 32 64 0 1262946880 10370720 7774832
20:32:47 17283 8192000000 10165251104 10561699840 1256284752 13.296 99 29 56 0 1267859488 5753488 5821248
20:34:05 23129 8192000000 10185613936 10594140160 1288763072 13.593 100 28 56 0 1301571008 6334736 6473200
20:35:32 7543 8192000000 10195761744 10609283072 1305662368 13.747 100 24 46 0 1316738688 5776384 5299936
20:36:43 6938 8192000000 10204642256 10621636608 1314532416 13.827 100 23 46 1 1324717152 4573296 5611440
20:37:51 18342 8192000000 10230233008 10644070400 1328860800 13.957 100 13 24 0 1336569296 4882608 2825888
20:39:15 57354 8192000000 10269614032 10681905152 1349140272 14.140 99 44 88 0 1367285536 8314496 9830768
20:41:19 75315 8192000000 10270066928 10694787072 1351929536 14.165 98 37 73 0 1371411552 11105792 8376224
20:43:39 11836 8192000000 10307028960 10728079360 1391018448 14.515 100 41 82 3 1414622464 13810912 9793104
20:44:53 38284 8192000000 10320089808 10741686272 1386342736 14.473 99 44 86 0 1413843648 17618048 9882864
20:46:40 18073 8192000000 10314786336 10737680384 1394271536 14.544 100 51 102 0 1429104128 22807120 12025472
20:48:00 57235 8192000000 10324680752 10750980096 1345006352 14.103 95 48 94 0 1384785808 28430400 11349056
20:50:06 50431 8192000000 10311758256 10738606080 1352024432 14.166 96 40 80 0 1396663184 33711872 10926880
20:52:12 23896 8192000000 10315141856 10743590912 1378873840 14.406 100 42 82 1 1422431008 33065744 10491424
20:53:43 2785 8192000000 10328358112 10738077696 1353314496 14.177 98 85 167 0 1414397792 40961120 20122176
20:54:56 9815 8192000000 10325912464 10738077696 1361225472 14.248 99 71 140 1 1419171568 40926432 17019664
20:56:11 17022 8192000000 10321606960 10743218176 1372107424 14.346 99 36 72 0 1418195104 36715168 9372512
20:57:41 11066 8192000000 10297887264 10713620480 1341814688 14.074 99 39 78 0 1397630192 45169824 10645680
20:58:54 15259 8192000000 10317820240 10732326912 1344277040 14.096 99 41 82 1 1411163216 55249088 11637088
21:00:15 17365 8192000000 10299842704 10722738176 1343586608 14.090 99 28 56 0 1394679024 43199280 7893136
21:01:35 10146 8192000000 10294285136 10720497664 1341357968 14.070 99 31 62 1 1390485152 41017328 8109856
21:02:51 25639 8192000000 10271617552 10709315584 1339787552 14.055 100 21 42 1 1383448784 37712928 5948304
21:04:20 27763 8192000000 10295642880 10727043072 1356016272 14.202 100 25 51 0 1396020432 34004144 6000016
21:05:53 60129 8192000000 10280632672 10726305792 1328702912 13.955 98 19 38 1 1366617520 33262112 4652496
21:08:01 78165 8192000000 10286090464 10712506368 1356937952 14.210 99 20 41 0 1371765408 10651648 4175808
21:10:21 23486 8192000000 10309712064 10752036864 1384852544 14.460 100 13 26 0 1405623168 17972832 2797792
21:11:51 117355 8192000000 10309041472 10753978368 1391003488 14.515 100 24 48 1 1415030560 18302016 5725056
21:14:55 46009 8192000000 10289021792 10751016960 1384110720 14.453 99 15 30 0 1399630640 12018512 3501408
21:16:43 20723 8192000000 10305495568 10756415488 1399750528 14.593 100 25 48 0 1415243408 9864608 5628272
21:18:07 38453 8192000000 10313461600 10764578816 1407215440 14.659 100 35 68 0 1424599072 9889792 7493840
21:19:48 38217 8192000000 10306240320 10754625536 1393230640 14.535 99 22 44 0 1405327312 7340048 4756624
21:21:29 38561 8192000000 10317565904 10780164096 1413608816 14.716 100 11 22 0 1431694464 14976416 3109232
21:23:14 39989 8192000000 10314273056 10779955200 1389284016 14.499 98 22 44 0 1410110240 15180416 5645808
21:24:54 50515 8192000000 10311723712 10779205632 1411823328 14.700 100 9 18 1 1424538832 10912128 1803376
21:26:46 30401 8192000000 10308901968 10772836352 1411917856 14.701 100 25 51 0 1424771856 7558112 5295888
21:28:24 77506 8192000000 10314642736 10777960448 1378355408 14.402 97 11 22 0 1390222112 9473632 2393072
21:30:44 50018 8192000000 10323695008 10775707648 1396794016 14.566 99 9 19 0 1415139728 16336576 2009136
21:32:35 44691 8192000000 10291520384 10750164992 1344562976 14.099 95 18 37 0 1360461104 11568576 4329552
21:34:20 27943 8192000000 10307976880 10756845568 1390427472 14.510 100 33 66 0 1411852832 14215600 7209760
21:35:53 21041 8192000000 10293546832 10732683264 1350851328 14.155 98 34 68 0 1369149376 10850592 7447456
21:37:14 51857 8192000000 10265041456 10712838144 1298754448 13.684 95 19 38 0 1316430464 13434560 4241456
21:39:13 47673 8192000000 10263440000 10704490496 1297134640 13.669 95 22 44 0 1309284160 7705280 4444240
21:41:01 94245 8192000000 10265010384 10698936320 1268809440 13.411 93 19 39 2 1279611168 6873696 3928032
21:43:37 26080 8192000000 10250246464 10701062144 1324994960 13.922 98 15 30 0 1341487936 13413824 3079152
21:45:05 59297 8192000000 10246143184 10707267584 1315536800 13.836 97 13 26 0 1328845280 10461552 2846928
21:47:11 59086 8192000000 10260080480 10714337280 1349345520 14.142 99 15 30 0 1361226912 8196896 3684496
21:49:19 63007 8192000000 10267244336 10726563840 1342189856 14.077 99 11 20 1 1356842400 12132656 2519888
21:51:27 79442 8192000000 10266402112 10725482496 1355993520 14.201 99 14 28 0 1374819936 15374048 3452368
21:53:53 35004 8192000000 10261887664 10711138304 1330421344 13.971 98 29 58 0 1346778912 9963360 6394208
21:55:30 87647 8192000000 10281596064 10753249280 1374490416 14.367 100 18 36 0 1394196752 15438496 4267840
21:58:00 38274 8192000000 10303818416 10754785280 1385170928 14.463 100 36 71 1 1405075584 11827280 8077376
21:59:53 24988 8192000000 10293894880 10746503168 1370303280 14.330 99 20 40 0 1387063312 12591520 4168512
22:01:27 22954 8192000000 10314686528 10755162112 1375801808 14.379 99 49 97 0 1401367408 14955456 10610144
22:03:21 86204 8192000000 10294949920 10759450624 1389922208 14.505 100 19 38 1 1405002528 10431296 4649024
22:07:03 44803 8192000000 10282422656 10752077824 1391782416 14.522 100 8 16 0 1399718832 6298416 1638000
22:08:50 52881 8192000000 10291113392 10761027584 1397347776 14.571 100 23 46 0 1412578176 9469968 5760432
22:10:47 58261 8192000000 10306876912 10784342016 1391426576 14.519 99 13 26 0 1404821888 10831552 2563760
22:12:46 21427 8192000000 10318377232 10774315008 1393611440 14.538 99 23 46 0 1409113776 9845040 5657296
22:14:09 65955 8192000000 10300878320 10757689344 1355777872 14.199 97 15 30 0 1367774544 8994512 3002160
22:16:22 25709 8192000000 10306208448 10757025792 1383248816 14.446 99 35 70 0 1401194928 9809408 8136704
22:17:49 62897 8192000000 10314682384 10777993216 1398854416 14.585 100 18 36 0 1426619312 23403984 4360912
22:20:01 54541 8192000000 10301004672 10789015552 1345142608 14.104 96 9 18 0 1373861184 26344208 2374368
22:21:55 67646 8192000000 10299475952 10743771136 1309881088 13.785 94 21 40 0 1330534528 16287008 4366432
22:24:15 94533 8192000000 10294443760 10742128640 1367497296 14.305 99 40 78 0 1387053024 11082864 8472864
22:26:50 46345 8192000000 10245119552 10712453120 1300305696 13.698 95 6 12 0 1313572784 11984496 1282592
22:28:42 51775 8192000000 10213056656 10670276608 1285082544 13.559 96 6 12 0 1296515040 9972384 1460112
22:31:32 85097 8192000000 10244913712 10703134720 1278352640 13.498 95 16 32 0 1293544496 11925040 3266816
22:34:04 41193 8192000000 10233205280 10672652288 1293318912 13.634 96 23 46 0 1308948336 10526448 5102976
22:35:47 75036 8192000000 10233395936 10692165632 1282497136 13.536 96 13 26 0 1297663184 11712080 3453968
22:38:02 36491 8192000000 9060815984 9274454016 223080256 2.650 17 2 4 0 224983504 1569376 333872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-12-2022 22:39:33 to 21-12-2022 22:39:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-12-2022 22:39:49 to 21-12-2022 22:39:51.

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          253503
MON$OLDEST_ACTIVE               253504
MON$OLDEST_SNAPSHOT             253504
MON$NEXT_TRANSACTION            253504
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                       2032736
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:990: 
:991: mscs-test-161.budzdorov.ru    Wed Dec 21 22:38:41 2022
:992:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:993: 
--- end of diff output ---

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

21.12.22 22:39:52. 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-12-21 22:39:55 No severe PSQL-related problems occured
21.12.22 22:39:56. End of report.