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

Hardware and OS info

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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-18 01:38:11
DTS_END                         2022-04-18 01:38:11.1990
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/48650
TRN_ID                          243092
ATT_ID                          685
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: 9047.75 23111 1085730
customer order: creation 176.57 382 21188
customer order: refuse 15.13 342 1816
order to supplier: creation 46.26 1318 5551
order to supplier: removal 3.42 94132 410
invoice (draft): creation 47.08 3536 5650
invoice (draft): removal 50.36 783 6043
invoice accept: apply 41.98 16396 5037
invoice accept: cancel 45.26 825 5431
customer reserve: creation 3848.12 163 461774
customer reserve: removal 4474.31 56 536917
realization accept: apply 84.33 165 10120
realization accept: cancel 81.76 101 9811
payment from customer: creation 60.78 116 7294
payment from customer: removal 6.03 96 723
payment to supplier: creation 49.85 146 5982
payment to supplier: removal 4.03 76 483
service: total inventory turnovers 4.81 1048 577
service: total monetary turnovers 6.93 20 832
service: aggregate perf. data 0.73 26779 87
service: refresh index statistics 0.03 315731 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-04-2022 01:39:23 to 18-04-2022 01:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6447
TEST_TIME 2 4354
TEST_TIME 3 3983
TEST_TIME 4 3835
TEST_TIME 5 3865
TEST_TIME 6 3999
TEST_TIME 7 4405
TEST_TIME 8 4993
TEST_TIME 9 4900
TEST_TIME 10 5090
TEST_TIME 11 5433
TEST_TIME 12 5354
TEST_TIME 13 5440
TEST_TIME 14 5831
TEST_TIME 15 6465
TEST_TIME 16 6720
TEST_TIME 17 6965
TEST_TIME 18 7405
TEST_TIME 19 8011
TEST_TIME 20 8184
TEST_TIME 21 8576
TEST_TIME 22 8556
TEST_TIME 23 8772
TEST_TIME 24 8982
TEST_TIME 25 8933
TEST_TIME 26 9488
TEST_TIME 27 9956
TEST_TIME 28 10068
TEST_TIME 29 9935
TEST_TIME 30 10150
TEST_TIME 31 9831
TEST_TIME 32 9955
TEST_TIME 33 9933
TEST_TIME 34 9721
TEST_TIME 35 9731
TEST_TIME 36 9658
TEST_TIME 37 9622
TEST_TIME 38 9539
TEST_TIME 39 9469
TEST_TIME 40 9470
TEST_TIME 41 9389
TEST_TIME 42 9302
TEST_TIME 43 9310
TEST_TIME 44 9197
TEST_TIME 45 9102
TEST_TIME 46 9155
TEST_TIME 47 9179
TEST_TIME 48 9118
TEST_TIME 49 9103
TEST_TIME 50 9135
TEST_TIME 51 9295
TEST_TIME 52 9217
TEST_TIME 53 9377
TEST_TIME 54 9388
TEST_TIME 55 9483
TEST_TIME 56 9472
TEST_TIME 57 9617
TEST_TIME 58 9584
TEST_TIME 59 9656
TEST_TIME 60 9571
TEST_TIME 61 9458
TEST_TIME 62 9424
TEST_TIME 63 9327
TEST_TIME 64 9316
TEST_TIME 65 9315
TEST_TIME 66 9338
TEST_TIME 67 9250
TEST_TIME 68 9262
TEST_TIME 69 9282
TEST_TIME 70 9273
TEST_TIME 71 9209
TEST_TIME 72 9324
TEST_TIME 73 9287
TEST_TIME 74 9323
TEST_TIME 75 9282
TEST_TIME 76 9353
TEST_TIME 77 9322
TEST_TIME 78 9412
TEST_TIME 79 9429
TEST_TIME 80 9464
TEST_TIME 81 9532
TEST_TIME 82 9461
TEST_TIME 83 9508
TEST_TIME 84 9406
TEST_TIME 85 9372
TEST_TIME 86 9372
TEST_TIME 87 9322
TEST_TIME 88 9354
TEST_TIME 89 9343
TEST_TIME 90 9311
TEST_TIME 91 9243
TEST_TIME 92 9191
TEST_TIME 93 9197
TEST_TIME 94 9272
TEST_TIME 95 9255
TEST_TIME 96 9238
TEST_TIME 97 9254
TEST_TIME 98 9249
TEST_TIME 99 9319
TEST_TIME 100 9320
TEST_TIME 101 9369
TEST_TIME 102 9370
TEST_TIME 103 9361
TEST_TIME 104 9367
TEST_TIME 105 9386
TEST_TIME 106 9373
TEST_TIME 107 9440
TEST_TIME 108 9427
TEST_TIME 109 9367
TEST_TIME 110 9313
TEST_TIME 111 9283
TEST_TIME 112 9245
TEST_TIME 113 9206
TEST_TIME 114 9166
TEST_TIME 115 9154
TEST_TIME 116 9122
TEST_TIME 117 9093
TEST_TIME 118 9123
TEST_TIME 119 9119
TEST_TIME 120 9051

Performance per minute, chart


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

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 1514132 1514132 0 0.00 0 35549 6 0 0
doc_list_biud 1514131 1514131 0 0.00 0 36085 26 0 0
sp_add_invoice_to_stock 5162 5037 125 2.42 42 145493 16397 0 125
sp_cancel_adding_invoice 5431 5431 0 0.00 6 64191 826 0 0
sp_cancel_client_order 1816 1816 0 0.00 4 13351 342 0 0
sp_cancel_customer_reserve 536917 536917 0 0.00 1 36088 57 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 2 12790 97 0 0
sp_cancel_pay_to_supplier 483 483 0 0.00 2 5495 76 0 0
sp_cancel_supplier_invoice 6053 6043 10 0.16 2 19860 783 0 10
sp_cancel_supplier_order 410 410 0 0.00 6 930826 94133 0 0
sp_cancel_write_off 9811 9811 0 0.00 1 11255 101 0 0
sp_client_order 21188 21188 0 0.00 3 24370 382 0 0
sp_customer_reserve 461774 461774 0 0.00 0 90956 164 0 0
sp_fill_shopping_cart 32387 32387 0 0.00 1 31421 40 0 0
sp_fill_shopping_cart_clo_res 461770 461770 0 0.00 0 12166 13 0 0
sp_get_clo_for_invoice 5032 5032 0 0.00 0 23805 285 0 0
sp_kill_cost_storno 16448 16448 0 0.00 0 10622 50 0 0
sp_kill_qstorno_ret_qs2qd 1090098 1090098 0 0.00 0 35611 31 0 0
sp_kill_qty_storno 575579 575579 0 0.00 0 36085 67 0 0
sp_lock_selected_doc 21168 21168 0 0.00 0 8598 5 0 0
sp_make_cost_storno 28428 28428 0 0.00 0 35532 54 0 0
sp_multiply_rows_for_pdistr 28428 28428 0 0.00 0 17143 31 0 0
sp_multiply_rows_for_qdistr 21188 21188 0 0.00 0 24319 184 0 0
sp_pay_from_customer 7294 7294 0 0.00 4 26248 117 0 0
sp_pay_to_supplier 5982 5982 0 0.00 4 24467 147 0 0
sp_payment_common 13276 13276 0 0.00 1 26233 36 0 0
sp_qd_handle_on_cancel_clo 1816 1816 0 0.00 0 13338 309 0 0
sp_qd_handle_on_invoice_upd_sts 10462 10462 0 0.00 0 23059 310 0 0
sp_qd_handle_on_reserve_upd_sts 10120 10120 0 0.00 0 7484 47 0 0
sp_reserve_write_off 10120 10120 0 0.00 6 19657 166 0 0
sp_supplier_invoice 5650 5650 0 0.00 5 95314 3536 0 0
sp_supplier_order 5551 5551 0 0.00 4 49017 1319 0 0
srv_aggregate_perf_data 87 87 0 0.00 2973 406885 26779 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2383 132321 50294 0 0
srv_find_qd_qs_mism 1037615 1037615 0 0.00 0 16497 5 0 0
srv_make_invnt_saldo 577 577 0 0.00 36 49008 1048 0 0
srv_make_money_saldo 832 832 0 0.00 0 1031 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 265661 359941 315731 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 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 5037 5037 0 0.00 0 10322 137 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 10364 113 0 0
t$perf-norm:sp_cancel_client_order 1816 1816 0 0.00 0 5311 12 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 1118 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 972 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 483 483 0 0.00 0 75 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 12035 50 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 21145 1584 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 2336 8 0 0
t$perf-norm:sp_client_order 21188 21188 0 0.00 0 12957 17 0 0
t$perf-norm:sp_customer_reserve 977 977 0 0.00 0 2384 7 0 0
t$perf-norm:sp_pay_from_customer 7294 7294 0 0.00 0 6357 9 0 0
t$perf-norm:sp_pay_to_supplier 5982 5982 0 0.00 0 6350 8 0 0
t$perf-norm:sp_reserve_write_off 10120 10120 0 0.00 0 12956 12 0 0
t$perf-norm:sp_supplier_invoice 5650 5650 0 0.00 0 8650 15 0 0
t$perf-norm:sp_supplier_order 5551 5551 0 0.00 0 2961 12 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 78 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 57 2 0 0
t$perf-norm:srv_make_invnt_saldo 577 577 0 0.00 0 1157 18 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 6067 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 5 0 0
v_add_invoice_to_stock 10312 10062 250 2.42 0 5783 17 0 250
v_all_customers 38590 38590 0 0.00 0 140 0 0 0
v_all_suppliers 30944 30944 0 0.00 0 17 1 0 0
v_all_wares 42376 42376 0 0.00 0 55 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 1502 9 0 0
v_cancel_client_order 3632 3632 0 0.00 0 6224 9 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 12783 22 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 2031 9 0 0
v_cancel_payment_to_supplier 966 966 0 0.00 0 98 2 0 0
v_cancel_supplier_invoice 904 884 20 2.21 0 5136 24 0 20
v_cancel_supplier_order 820 820 0 0.00 0 2878 9 0 0
v_cancel_write_off 2040 2040 0 0.00 0 3828 16 0 0
v_max_id_clo_ord 5551 5551 0 0.00 0 1196 1 0 0
v_max_id_clo_res 977 977 0 0.00 0 2098 12 0 0
v_max_id_ord_sup 5646 5646 0 0.00 0 4967 5 0 0
v_max_non_paid_invoice 1707 1707 0 0.00 0 482 3 0 0
v_max_non_paid_realizn 3929 3929 0 0.00 0 1774 3 0 0
v_min_id_clo_ord 5551 5551 0 0.00 0 16512 12 0 0
v_min_id_clo_res 977 977 0 0.00 0 5589 12 0 0
v_min_id_ord_sup 5646 5646 0 0.00 0 6346 8 0 0
v_min_non_paid_invoice 5982 5982 0 0.00 0 24434 22 0 0
v_min_non_paid_realizn 7294 7294 0 0.00 0 26191 28 0 0
v_our_firm 10516 10516 0 0.00 0 17 1 0 0
v_reserve_write_off 20240 20240 0 0.00 0 12417 7 0 0
x_lock_depdocs_on_canc_invoice 11032 11032 0 0.00 0 9731 104 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 12188 333 0 0
x_make_qstorno_1000_1200 5551 5551 0 0.00 2 49009 1236 0 0
x_make_qstorno_1200_2000 5650 5650 0 0.00 2 95304 3393 0 0
x_make_qstorno_2100_3300 461774 461774 0 0.00 0 90954 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-04-2022 01:39:31 to 18-04-2022 01:39:35.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:14 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:15 28320 8192000000 9915705472 10230861824 1001865024 10.897 100 87 174 0 1030655424 6168480 22621920
23:10:51 24645 8192000000 9964912000 10290491392 1046092448 11.323 100 90 180 0 1077901136 8970288 22838400
23:12:18 10776 8192000000 10000285104 10337148928 1081690704 11.664 100 84 168 0 1113042544 9826752 21525088
23:13:31 6575 8192000000 10044125072 10392576000 1133416960 12.154 100 83 167 0 1164827184 11867840 19542384
23:14:40 7426 8192000000 10073212944 10431303680 1160328736 12.406 100 79 158 0 1190545952 12346416 17870800
23:15:51 5884 8192000000 10118500192 10489827328 1207119888 12.842 100 71 141 0 1236091888 12808512 16163488
23:17:00 4604 8192000000 10155566240 10535456768 1250848720 13.246 100 74 148 0 1279498400 12373824 16275856
23:18:05 2914 8192000000 10190721760 10571231232 1278920128 13.503 100 78 157 0 1309201520 11742000 18539392
23:19:11 1510 8192000000 10222106832 10604634112 1299632752 13.692 100 95 189 1 1334564544 13398960 21532832
23:20:14 1373 8192000000 10225961680 10612588544 1310074992 13.787 100 91 182 1 1343855520 12862032 20918496
23:21:16 5674 8192000000 10209693728 10617303040 1314713040 13.829 100 39 78 0 1330331408 6487792 9130576
23:22:23 6886 8192000000 10210691312 10626347008 1324004032 13.913 100 27 54 0 1336127168 6367616 5755520
23:23:32 15462 8192000000 10213319312 10636496896 1331385360 13.980 100 19 38 0 1346208880 10719072 4104448
23:24:47 55691 8192000000 10225114320 10650566656 1336295072 14.024 100 32 64 1 1349219104 5877104 7046928
23:26:45 4829 8192000000 10236633648 10657746944 1342580400 14.081 100 27 53 1 1355260672 5699024 6981248
23:27:50 12670 8192000000 10211572720 10626707456 1296003648 13.659 97 6 12 0 1302270608 4864864 1402096
23:29:07 59798 8192000000 10168713136 10572935168 1226972320 13.026 94 25 50 1 1237484960 4229936 6282704
23:31:09 3977 8192000000 10152038688 10555531264 1245313904 13.195 99 33 66 1 1263239024 10395104 7530016
23:32:17 6217 8192000000 10149556560 10551967744 1244739312 13.190 99 33 66 0 1259321664 6679648 7902704
23:33:25 16964 8192000000 10170326064 10580418560 1282968720 13.540 100 28 56 0 1295612544 5849936 6793888
23:34:45 7819 8192000000 10172321184 10592223232 1291831824 13.621 100 32 64 1 1306386336 7105072 7449440
23:35:58 9239 8192000000 10190715520 10609152000 1302799520 13.721 100 20 40 0 1313769552 6445648 4524384
23:37:10 8595 8192000000 10194658032 10617053184 1310627696 13.792 100 15 30 0 1319611808 5108048 3876064
23:38:23 8079 8192000000 10234053872 10642526208 1331439472 13.980 100 40 80 1 1347300576 5507472 10353632
23:39:52 94550 8192000000 10257668928 10681937920 1357434144 14.214 100 28 56 0 1372609952 8396000 6779808
23:42:30 24109 8192000000 10278556608 10706903040 1377556880 14.395 100 42 82 2 1399873104 12089216 10227008
23:44:02 13493 8192000000 10315935152 10734485504 1395818928 14.558 100 63 126 3 1428970832 18552272 14599632
23:45:26 27607 8192000000 10309769824 10740621312 1384189632 14.454 99 47 92 0 1417494240 21969712 11334896
23:47:08 9412 8192000000 10341297872 10761465856 1415476672 14.733 100 62 124 2 1450336528 19564240 15295616
23:48:25 11047 8192000000 10339530064 10767859712 1409657376 14.681 99 54 108 3 1446466384 23391376 13417632
23:49:42 5203 8192000000 10334226896 10755444736 1396599504 14.565 99 64 126 2 1434612496 22911936 15101056
23:50:51 11575 8192000000 10341315136 10766405632 1412897520 14.710 100 54 108 0 1456039328 29462784 13679024
23:52:07 2382 8192000000 10360573472 10778882048 1416445136 14.741 100 77 155 0 1471401856 35110640 19846080
23:53:16 5586 8192000000 10366508128 10781855744 1410255344 14.686 100 74 146 1 1472617792 44209072 18153376
23:54:26 5117 8192000000 10339243808 10761195520 1388019888 14.488 99 53 106 2 1436064544 34613472 13431184
23:55:37 2763 8192000000 10346595264 10756624384 1387355232 14.482 100 73 145 2 1445263712 40156608 17751872
23:56:44 9022 8192000000 10312291568 10738429952 1375231856 14.374 100 36 72 1 1419903936 34209744 10462336
23:57:57 11824 8192000000 10324529040 10753925120 1387254560 14.481 100 39 79 2 1432572880 35338704 9979616
23:59:12 11808 8192000000 10292112448 10722983936 1356915920 14.210 99 31 60 3 1400457920 35417296 8124704
00:00:31 10971 8192000000 10275842944 10697474048 1335853584 14.020 99 34 67 0 1371592512 27285856 8453072
00:01:47 36828 8192000000 10277352048 10713526272 1338603824 14.045 99 25 51 1 1379415360 33799168 7012368
00:04:08 28158 8192000000 10256979392 10695417856 1333978240 14.003 100 21 42 2 1378841520 38667088 6196192
00:05:39 28680 8192000000 10279722768 10718294016 1359094192 14.229 100 34 67 0 1384372832 17055344 8223296
00:07:14 41402 8192000000 10274485648 10708856832 1326690800 13.937 98 20 38 2 1353658976 22178384 4789792
00:08:57 65202 8192000000 10276800080 10701860864 1342758576 14.082 99 39 78 1 1365086816 12401760 9926480
00:11:16 94756 8192000000 10294822656 10725240832 1372374608 14.348 100 29 59 0 1387900464 9438768 6087088
00:13:51 39440 8192000000 10278587552 10725785600 1368462672 14.313 99 28 56 2 1384456528 9746224 6247632
00:15:33 43781 8192000000 10290093632 10738593792 1388103968 14.489 100 26 52 0 1406075168 11513296 6457904
00:17:18 38381 8192000000 10286159792 10743140352 1388409808 14.492 100 6 12 0 1397792464 7717040 1665616
00:18:58 81699 8192000000 10293290592 10749095936 1396368928 14.563 100 14 28 0 1410089232 10796880 2923424
00:21:22 76257 8192000000 10301064816 10770944000 1402950704 14.621 100 18 36 0 1426585200 19305728 4328768
00:23:40 53179 8192000000 10282813888 10749628416 1367504592 14.305 98 14 28 0 1381035968 10538960 2992416
00:25:34 55218 8192000000 10326786944 10772860928 1402010576 14.613 100 36 72 1 1424563984 13474800 9078608
00:27:34 86791 8192000000 10310841872 10774827008 1378308016 14.401 98 12 24 1 1405673200 24374976 2990208
00:30:02 93963 8192000000 10327803584 10779648000 1375947376 14.380 98 13 26 0 1399037136 19773280 3316480
00:32:36 92546 8192000000 10305712320 10760577024 1383543616 14.448 99 18 36 1 1415877184 28084864 4248704
00:35:10 43467 8192000000 10294731568 10754056192 1378673408 14.405 99 17 34 0 1395646048 13300368 3672272
00:37:00 132321 8192000000 10307131952 10757828608 1342935360 14.084 96 15 30 0 1357676432 11863264 2877808
00:40:21 68450 8192000000 10302459456 10748141568 1324797088 13.920 94 29 56 1 1340953984 10035936 6120960
00:42:32 40087 8192000000 10275876624 10738368512 1364841328 14.281 98 23 46 1 1384433712 14334608 5257776
00:44:24 59527 8192000000 10266112704 10727559168 1332658704 13.991 96 11 22 0 1347914096 12279616 2975776
00:46:24 81311 8192000000 10267988000 10726866944 1337376512 14.034 98 7 14 0 1354036896 15398864 1261520
00:48:54 68239 8192000000 10227917936 10665381888 1261924016 13.348 92 20 40 0 1276299904 9615920 4759968
00:51:11 63475 8192000000 10256253088 10697908224 1302742944 13.720 96 29 58 0 1321759360 12901040 6115376
00:53:19 32011 8192000000 10268528512 10702491648 1344459744 14.098 99 32 64 0 1358827088 7939216 6428128
00:54:53 48161 8192000000 10258012496 10701733888 1291426496 13.617 95 16 32 0 1306692112 11642624 3622992
00:56:45 117728 8192000000 10279616272 10729721856 1311007568 13.795 96 7 14 0 1323138512 10905312 1225632
00:59:47 48653 8192000000 10285432288 10723184640 1348755088 14.136 98 23 46 1 1363452080 9346592 5350400
01:01:39 82454 8192000000 10274526400 10710872064 1361423728 14.250 100 33 64 1 1379605504 9546784 8634992
01:04:04 68407 8192000000 10263173152 10722328576 1366050512 14.292 100 19 38 0 1380785200 10931760 3802928
01:06:22 74200 8192000000 10260138192 10726850560 1356440112 14.205 99 9 18 0 1370074544 11503520 2130912
01:08:40 61258 8192000000 10268168032 10725011456 1373653296 14.360 99 15 28 1 1384825840 8504800 2667744
01:10:45 67979 8192000000 10289315584 10750943232 1375631968 14.377 99 8 16 0 1390466960 12870176 1964816
01:12:57 72015 8192000000 10291186448 10745016320 1383373904 14.447 100 13 26 0 1394851904 8560400 2917600
01:15:13 51619 8192000000 10305476560 10753871872 1382564000 14.439 99 18 36 1 1402753200 15440608 4748592
01:17:08 59232 8192000000 10293543312 10767937536 1377845472 14.397 99 21 42 2 1404826768 21466432 5514864
01:19:08 66536 8192000000 10298621936 10751713280 1368967232 14.318 98 26 52 1 1384982992 10333488 5682272
01:21:15 63973 8192000000 10279720336 10725961728 1340729104 14.064 96 27 54 0 1361902784 15226048 5947632
01:23:20 16877 8192000000 10302175520 10746085376 1364063536 14.274 98 33 66 1 1381709824 10466032 7180256
01:24:40 27075 8192000000 10308872480 10748653568 1364646656 14.279 98 43 85 2 1387383104 13491808 9244640
01:26:42 111527 8192000000 10272476384 10720862208 1344658032 14.099 98 30 58 4 1366088016 15009808 6420176
01:29:39 45233 8192000000 10246463152 10702036992 1343862976 14.092 99 5 11 0 1351157856 6104016 1190864
01:31:35 103480 8192000000 10250829904 10711994368 1326621024 13.937 97 10 18 1 1337889312 8999088 2269200
01:34:22 83545 8192000000 10270345760 10719363072 1340804592 14.065 98 24 48 3 1356244560 10149152 5290816
01:36:51 38457 8192000000 10244232544 10689880064 1307551648 13.764 96 15 29 1 1318743440 7941808 3249984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-04-2022 01:39:37 to 18-04-2022 01:39:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 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:01, from 18-04-2022 01:39:53 to 18-04-2022 01:39:54.

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          242352
MON$OLDEST_ACTIVE               244144
MON$OLDEST_SNAPSHOT             244144
MON$NEXT_TRANSACTION            244144
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                       2020864
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:605: 
:606: mscs-test-161.budzdorov.ru    Mon Apr 18 01:38:16 2022
:607:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:608: 
--- end of diff output ---

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

18.04.22 01:39:55. 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-04-18 01:39:58 No severe PSQL-related problems occured
18.04.22 01:39:59. End of report.