Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.04.2022 01: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 16349808 kB
MemAvailable 31477408 kB
Buffers 0 kB
Cached 15334552 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-17 01:38:17
DTS_END                         2022-04-17 01:38:17.2640
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/33030
TRN_ID                          244555
ATT_ID                          660
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: 9375.13 22987 1125015
customer order: creation 170.68 348 20482
customer order: refuse 14.49 367 1739
order to supplier: creation 45.21 1389 5425
order to supplier: removal 3.58 108707 429
invoice (draft): creation 45.98 3495 5517
invoice (draft): removal 55.18 825 6622
invoice accept: apply 41.81 18920 5017
invoice accept: cancel 50.17 796 6020
customer reserve: creation 3765.40 191 451848
customer reserve: removal 4875.43 62 585052
realization accept: apply 82.68 191 9921
realization accept: cancel 95.55 108 11466
payment from customer: creation 58.47 121 7016
payment from customer: removal 5.40 114 648
payment to supplier: creation 49.14 144 5897
payment to supplier: removal 3.51 88 421
service: total inventory turnovers 4.75 1339 570
service: total monetary turnovers 6.94 34 833
service: aggregate perf. data 0.73 19441 88
service: refresh index statistics 0.03 303065 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-04-2022 01:39:18 to 17-04-2022 01:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3385
TEST_TIME 2 4247
TEST_TIME 3 4406
TEST_TIME 4 4555
TEST_TIME 5 4725
TEST_TIME 6 5200
TEST_TIME 7 5522
TEST_TIME 8 5560
TEST_TIME 9 5536
TEST_TIME 10 6062
TEST_TIME 11 6440
TEST_TIME 12 6510
TEST_TIME 13 7103
TEST_TIME 14 7458
TEST_TIME 15 7701
TEST_TIME 16 7878
TEST_TIME 17 8065
TEST_TIME 18 8094
TEST_TIME 19 8449
TEST_TIME 20 8556
TEST_TIME 21 8560
TEST_TIME 22 8670
TEST_TIME 23 9101
TEST_TIME 24 9214
TEST_TIME 25 9193
TEST_TIME 26 9153
TEST_TIME 27 9724
TEST_TIME 28 9708
TEST_TIME 29 9994
TEST_TIME 30 11121
TEST_TIME 31 11065
TEST_TIME 32 11103
TEST_TIME 33 10939
TEST_TIME 34 10687
TEST_TIME 35 10688
TEST_TIME 36 10526
TEST_TIME 37 10422
TEST_TIME 38 10517
TEST_TIME 39 10454
TEST_TIME 40 10320
TEST_TIME 41 10249
TEST_TIME 42 10155
TEST_TIME 43 10147
TEST_TIME 44 10076
TEST_TIME 45 9936
TEST_TIME 46 9821
TEST_TIME 47 9730
TEST_TIME 48 9705
TEST_TIME 49 9662
TEST_TIME 50 9522
TEST_TIME 51 9480
TEST_TIME 52 9358
TEST_TIME 53 9315
TEST_TIME 54 9458
TEST_TIME 55 9530
TEST_TIME 56 9429
TEST_TIME 57 9329
TEST_TIME 58 9423
TEST_TIME 59 9408
TEST_TIME 60 9430
TEST_TIME 61 9452
TEST_TIME 62 9428
TEST_TIME 63 9466
TEST_TIME 64 9460
TEST_TIME 65 9515
TEST_TIME 66 9582
TEST_TIME 67 9564
TEST_TIME 68 9579
TEST_TIME 69 9536
TEST_TIME 70 9573
TEST_TIME 71 9604
TEST_TIME 72 9598
TEST_TIME 73 9527
TEST_TIME 74 9729
TEST_TIME 75 9638
TEST_TIME 76 9551
TEST_TIME 77 9525
TEST_TIME 78 9489
TEST_TIME 79 9464
TEST_TIME 80 9412
TEST_TIME 81 9445
TEST_TIME 82 9445
TEST_TIME 83 9449
TEST_TIME 84 9389
TEST_TIME 85 9328
TEST_TIME 86 9300
TEST_TIME 87 9268
TEST_TIME 88 9274
TEST_TIME 89 9285
TEST_TIME 90 9304
TEST_TIME 91 9335
TEST_TIME 92 9335
TEST_TIME 93 9320
TEST_TIME 94 9358
TEST_TIME 95 9445
TEST_TIME 96 9413
TEST_TIME 97 9486
TEST_TIME 98 9502
TEST_TIME 99 9514
TEST_TIME 100 9501
TEST_TIME 101 9581
TEST_TIME 102 9573
TEST_TIME 103 9558
TEST_TIME 104 9556
TEST_TIME 105 9505
TEST_TIME 106 9495
TEST_TIME 107 9490
TEST_TIME 108 9427
TEST_TIME 109 9368
TEST_TIME 110 9326
TEST_TIME 111 9310
TEST_TIME 112 9369
TEST_TIME 113 9387
TEST_TIME 114 9374
TEST_TIME 115 9409
TEST_TIME 116 9396
TEST_TIME 117 9418
TEST_TIME 118 9421
TEST_TIME 119 9413
TEST_TIME 120 9375

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-04-2022 01:39:22 to 17-04-2022 01: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 1544723 1544723 0 0.00 0 25504 7 0 0
doc_list_biud 1544723 1544723 0 0.00 0 58904 30 0 0
sp_add_invoice_to_stock 5143 5017 126 2.44 35 178685 18920 0 126
sp_cancel_adding_invoice 6020 6020 0 0.00 3 99719 797 0 0
sp_cancel_client_order 1739 1739 0 0.00 4 11761 368 0 0
sp_cancel_customer_reserve 585052 585052 0 0.00 1 58905 62 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 3 6264 114 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 5648 89 0 0
sp_cancel_supplier_invoice 6632 6622 10 0.15 2 16502 825 0 10
sp_cancel_supplier_order 429 429 0 0.00 7 962118 108707 0 0
sp_cancel_write_off 11466 11466 0 0.00 1 30772 109 0 0
sp_client_order 20482 20482 0 0.00 3 18771 348 0 0
sp_customer_reserve 451848 451848 0 0.00 0 40660 191 0 0
sp_fill_shopping_cart 31418 31418 0 0.00 1 31028 43 0 0
sp_fill_shopping_cart_clo_res 451844 451844 0 0.00 0 22620 14 0 0
sp_get_clo_for_invoice 5013 5013 0 0.00 0 41109 312 0 0
sp_kill_cost_storno 18555 18555 0 0.00 0 6942 60 0 0
sp_kill_qstorno_ret_qs2qd 1188621 1188621 0 0.00 0 58903 33 0 0
sp_kill_qty_storno 626262 626262 0 0.00 0 58904 72 0 0
sp_lock_selected_doc 20691 20691 0 0.00 0 7451 5 0 0
sp_make_cost_storno 27847 27847 0 0.00 0 15032 57 0 0
sp_multiply_rows_for_pdistr 27847 27847 0 0.00 0 7876 35 0 0
sp_multiply_rows_for_qdistr 20482 20482 0 0.00 0 16654 146 0 0
sp_pay_from_customer 7016 7016 0 0.00 4 13846 121 0 0
sp_pay_to_supplier 5897 5897 0 0.00 5 13814 144 0 0
sp_payment_common 12913 12913 0 0.00 1 7872 28 0 0
sp_qd_handle_on_cancel_clo 1739 1739 0 0.00 0 11730 339 0 0
sp_qd_handle_on_invoice_upd_sts 11033 11033 0 0.00 0 16284 355 0 0
sp_qd_handle_on_reserve_upd_sts 9921 9921 0 0.00 0 11039 56 0 0
sp_reserve_write_off 9921 9921 0 0.00 6 13356 191 0 0
sp_supplier_invoice 5517 5517 0 0.00 6 81296 3496 0 0
sp_supplier_order 5425 5425 0 0.00 5 34090 1389 0 0
srv_aggregate_perf_data 88 88 0 0.00 2472 230550 19442 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1773 131785 43149 0 0
srv_find_qd_qs_mism 1078506 1078506 0 0.00 0 25504 5 0 0
srv_make_invnt_saldo 570 570 0 0.00 44 178711 1339 0 0
srv_make_money_saldo 833 833 0 0.00 0 5126 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 229051 424477 303066 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 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5017 5017 0 0.00 0 6827 171 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 1 8206 93 0 0
t$perf-norm:sp_cancel_client_order 1739 1739 0 0.00 0 3634 19 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 787 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 561 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 900 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 3584 48 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 22596 1708 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 1121 5 0 0
t$perf-norm:sp_client_order 20482 20482 0 0.00 0 5760 20 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 4633 9 0 0
t$perf-norm:sp_pay_from_customer 7016 7016 0 0.00 0 18206 12 0 0
t$perf-norm:sp_pay_to_supplier 5897 5897 0 0.00 0 18219 17 0 0
t$perf-norm:sp_reserve_write_off 9921 9921 0 0.00 0 4765 10 0 0
t$perf-norm:sp_supplier_invoice 5517 5517 0 0.00 0 4856 20 0 0
t$perf-norm:sp_supplier_order 5425 5425 0 0.00 0 6309 26 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 590 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 399 7 0 0
t$perf-norm:srv_make_invnt_saldo 570 570 0 0.00 0 1846 33 0 0
t$perf-norm:srv_make_money_saldo 833 833 0 0.00 0 6901 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10278 10026 252 2.45 0 10466 24 0 252
v_all_customers 37034 37034 0 0.00 0 89 1 0 0
v_all_suppliers 30396 30396 0 0.00 0 20 1 0 0
v_all_wares 40964 40964 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 550 6 0 0
v_cancel_client_order 3478 3478 0 0.00 0 2413 5 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 1299 5 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 1938 5 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 5627 12 0 0
v_cancel_supplier_invoice 776 756 20 2.57 0 1526 17 0 20
v_cancel_supplier_order 858 858 0 0.00 0 4100 12 0 0
v_cancel_write_off 2064 2064 0 0.00 0 10481 22 0 0
v_max_id_clo_ord 5424 5424 0 0.00 0 443 1 0 0
v_max_id_clo_res 984 984 0 0.00 0 3253 17 0 0
v_max_id_ord_sup 5511 5511 0 0.00 0 5510 4 0 0
v_max_non_paid_invoice 1634 1634 0 0.00 0 2722 6 0 0
v_max_non_paid_realizn 3803 3803 0 0.00 0 6125 7 0 0
v_min_id_clo_ord 5424 5424 0 0.00 0 2096 5 0 0
v_min_id_clo_res 984 984 0 0.00 0 2226 11 0 0
v_min_id_ord_sup 5511 5511 0 0.00 0 6854 10 0 0
v_min_non_paid_invoice 5897 5897 0 0.00 0 5533 16 0 0
v_min_non_paid_realizn 7016 7016 0 0.00 0 7866 19 0 0
v_our_firm 10356 10356 0 0.00 0 24 1 0 0
v_reserve_write_off 19842 19842 0 0.00 0 13305 10 0 0
x_lock_depdocs_on_canc_invoice 12264 12264 0 0.00 0 30730 131 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 11700 399 0 0
x_make_qstorno_1000_1200 5425 5425 0 0.00 2 34079 1302 0 0
x_make_qstorno_1200_2000 5517 5517 0 0.00 3 72309 3335 0 0
x_make_qstorno_2100_3300 451848 451848 0 0.00 0 40659 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-04-2022 01:39:27 to 17-04-2022 01: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
23:08:21 3 8192000000 8801015680 8812990464 7029232 0.085 2 2 3 0 7170320 66144 74944
23:09:22 28993 8192000000 9902949616 10214985728 988629712 10.768 100 85 171 1 1017266688 6348224 22288752
23:10:57 7084 8192000000 9942396768 10263433216 1026316352 11.133 100 91 183 0 1058738912 9105168 23317392
23:12:13 19137 8192000000 9972527632 10301358080 1055007120 11.409 100 86 172 0 1087331984 10219104 22105760
23:13:39 6575 8192000000 10030617216 10373976064 1117502528 12.003 100 85 170 0 1148979392 11037792 20439072
23:14:48 10114 8192000000 10073996224 10431430656 1160175952 12.405 100 81 160 0 1191542736 13172128 18194656
23:16:00 6330 8192000000 10120126160 10486136832 1210283136 12.872 100 81 162 0 1241148384 12509840 18355408
23:17:07 2183 8192000000 10170890416 10542198784 1252678672 13.263 100 87 174 0 1286366400 13949344 19738384
23:18:11 1722 8192000000 10204486640 10583912448 1285672240 13.565 100 89 176 0 1317836240 13404032 18759968
23:19:13 14420 8192000000 10208390016 10599931904 1299070384 13.687 100 69 138 2 1325494512 11022160 15401968
23:20:29 5109 8192000000 10193025840 10600652800 1308884880 13.776 100 35 71 2 1323122880 6167520 8070480
23:21:35 13362 8192000000 10200527360 10615267328 1316102048 13.841 100 17 35 0 1325588032 5693088 3792896
23:22:49 6263 8192000000 10209577136 10629419008 1327007920 13.940 100 19 37 0 1341441632 9880384 4553328
23:23:55 58977 8192000000 10216586272 10641932288 1332778816 13.992 100 22 44 1 1342837984 5202976 4856192
23:25:55 8366 8192000000 10233627088 10653466624 1341386272 14.070 100 21 42 1 1351545952 5331296 4828384
23:27:07 5898 8192000000 10227114496 10649649152 1336646896 14.027 100 33 66 0 1350739600 6891216 7201488
23:28:13 58692 8192000000 10188561200 10586509312 1237856416 13.126 93 30 60 3 1250212144 5053888 7301840
23:30:12 5859 8192000000 10122765296 10520719360 1194803808 12.728 95 24 49 0 1206590848 5980464 5806576
23:31:19 4044 8192000000 10150169552 10547392512 1244154672 13.184 99 33 67 4 1257355264 5754960 7445632
23:32:24 6424 8192000000 10166996400 10564890624 1266768592 13.392 100 33 67 2 1280686112 5861344 8056176
23:33:30 34706 8192000000 10175052032 10578956288 1276631248 13.482 100 37 74 2 1290528160 5205408 8691504
23:35:08 8991 8192000000 10196230000 10601140224 1296645248 13.665 100 23 46 1 1308155696 6643360 4867088
23:36:21 9876 8192000000 10202546432 10617262080 1309306080 13.780 100 25 50 0 1320542432 5347520 5888832
23:37:34 15014 8192000000 10221189648 10628825088 1319289200 13.870 100 22 44 0 1330232096 5541888 5401008
23:38:51 62522 8192000000 10239062448 10657759232 1335138944 14.014 100 40 79 1 1349378784 5530720 8709120
23:40:59 22176 8192000000 10277630720 10688299008 1361515728 14.251 100 61 122 0 1386535840 11555504 13464608
23:42:27 11330 8192000000 10286005184 10703101952 1376030512 14.381 100 60 118 4 1401933552 12259600 13643440
23:43:41 11801 8192000000 10306583216 10724122624 1387134400 14.480 100 63 126 0 1416649600 14893840 14621360
23:44:57 26538 8192000000 10308159824 10731933696 1395086208 14.551 100 46 92 1 1420181840 13615312 11480320
23:46:45 13219 8192000000 10335607824 10755207168 1408848704 14.674 100 62 124 2 1445497344 21963152 14685488
23:48:01 9292 8192000000 10332380096 10756915200 1406123456 14.649 100 51 99 4 1443522912 25056224 12343232
23:49:17 1772 8192000000 10335308896 10751176704 1394065808 14.542 99 77 150 1 1437168352 23427456 19675088
23:50:25 3625 8192000000 10324632832 10734592000 1379368656 14.411 99 71 141 2 1423339808 27430208 16540944
23:51:31 2080 8192000000 10330323600 10731945984 1379805904 14.415 100 80 154 0 1431689696 31613680 20270112
23:52:38 7018 8192000000 10328310592 10735255552 1385132128 14.462 100 76 151 0 1431653120 28481280 18039712
23:53:50 3574 8192000000 10309349056 10712494080 1359732576 14.235 100 75 151 0 1410095328 31228880 19133872
23:54:59 6012 8192000000 10306759136 10705874944 1354619856 14.189 99 69 136 1 1405854400 34235408 16999136
23:56:10 3755 8192000000 10316168368 10717237248 1350429712 14.151 99 75 150 3 1406457088 36779728 19247648
23:57:19 3979 8192000000 10325924224 10729873408 1367812320 14.307 100 71 142 0 1426176608 40479216 17885072
23:58:28 3110 8192000000 10332515776 10734682112 1370539136 14.332 100 74 148 0 1431348544 41806144 19003264
23:59:39 2132 8192000000 10366089200 10764636160 1389510688 14.502 100 86 174 1 1457085712 45570768 22004256
00:00:45 8239 8192000000 10352271392 10768465920 1392748560 14.530 100 55 110 2 1455261872 47612464 14900848
00:01:58 9296 8192000000 10341708912 10763223040 1394406272 14.545 100 52 104 1 1450288496 42523232 13358992
00:03:15 9144 8192000000 10344913920 10769395712 1395581168 14.556 100 34 68 1 1450508336 45223072 9704096
00:05:14 20725 8192000000 10339689440 10774675456 1378378960 14.402 100 29 58 3 1443160752 56322272 8459520
00:06:44 30891 8192000000 10322843904 10773864448 1367081376 14.301 100 25 51 2 1430385056 55895040 7408640
00:08:37 57320 8192000000 10275994944 10707877888 1321995248 13.895 96 30 58 2 1346183984 17445552 6743184
00:10:35 87076 8192000000 10280894784 10724163584 1329544784 13.963 97 10 20 0 1343743248 12100352 2098112
00:13:13 22800 8192000000 10289812048 10739539968 1381002448 14.426 100 28 56 1 1400013984 12894656 6116880
00:14:37 72774 8192000000 10299927056 10752253952 1390735456 14.512 100 15 30 0 1409362256 15075088 3551712
00:16:52 85146 8192000000 10298286800 10753196032 1391689968 14.521 100 10 22 0 1406644976 12709152 2245856
00:19:18 30210 8192000000 10296610176 10750287872 1380653152 14.422 99 43 86 0 1397962208 7358224 9950832
00:21:51 38556 8192000000 10293089744 10744520704 1389236160 14.499 100 16 33 0 1402236880 8751472 4249248
00:23:30 80911 8192000000 10310725072 10752831488 1391641088 14.521 100 28 52 0 1405268000 6812832 6814080
00:25:54 69218 8192000000 10305272528 10760347648 1367451328 14.304 98 30 60 1 1386099440 11769312 6878800
00:28:07 53942 8192000000 10300094176 10759188480 1390019648 14.506 100 24 49 1 1411514544 16187056 5307840
00:30:01 36896 8192000000 10325726688 10766663680 1387909936 14.487 99 34 65 2 1410041600 14278832 7852832
00:31:44 47818 8192000000 10314045856 10767486976 1391588480 14.520 100 20 40 2 1419006752 21928016 5490256
00:33:33 25650 8192000000 10278626800 10757926912 1365499424 14.287 99 7 14 0 1391421840 24549776 1372640
00:35:01 29089 8192000000 10288728112 10755833856 1388283616 14.491 100 14 28 0 1406699072 14380592 4034864
00:37:13 79554 8192000000 10291032768 10757570560 1378479792 14.403 99 13 26 0 1395201504 13787136 2934576
00:39:42 27791 8192000000 10287356896 10743537664 1378831040 14.406 99 33 64 2 1395811888 10068720 6912128
00:41:11 51298 8192000000 10248669360 10696450048 1288644688 13.592 93 18 36 1 1298716640 6669488 3402464
00:43:12 78838 8192000000 10274804256 10736754688 1342372416 14.079 98 12 24 0 1362364416 17468480 2523520
00:45:33 63210 8192000000 10271631072 10715222016 1315747504 13.838 96 43 87 0 1338182592 13013280 9421808
00:47:47 33031 8192000000 10259156640 10702999552 1337495312 14.035 98 19 38 0 1350934688 9339536 4099840
00:49:50 125841 8192000000 10279799872 10723950592 1271434128 13.435 93 16 32 0 1290255440 15034576 3786736
00:52:57 108165 8192000000 10270030864 10707615744 1343857200 14.092 99 21 42 0 1359145296 10446864 4841232
00:55:47 61686 8192000000 10281064336 10741465088 1366046176 14.292 99 14 28 0 1387865120 18619520 3199424
00:57:53 76874 8192000000 10286028368 10741112832 1343213424 14.086 97 13 26 0 1363907024 17521200 3172400
01:00:47 40912 8192000000 10264392720 10724638720 1334738448 14.010 97 15 30 0 1352874592 14703840 3432304
01:02:28 72151 8192000000 10288497296 10748583936 1385447328 14.465 100 21 42 2 1401570944 10735152 5388464
01:04:45 69931 8192000000 10296557760 10761633792 1383908576 14.451 100 12 24 1 1405471360 19224880 2337904
01:07:01 50122 8192000000 10293397728 10757423104 1380028336 14.417 99 21 42 0 1400418816 15585168 4805312
01:08:53 58979 8192000000 10294266672 10760085504 1396314464 14.562 100 13 26 0 1407691824 8525696 2851664
01:11:00 35253 8192000000 10292315008 10740584448 1368193248 14.311 98 16 32 0 1382531840 10638400 3700192
01:12:37 66656 8192000000 10288956352 10747912192 1345987264 14.111 97 16 32 0 1363945312 14632144 3325904
01:14:45 24913 8192000000 10312088704 10757894144 1385297648 14.464 100 43 84 3 1408910064 12055904 11556512
01:16:18 106469 8192000000 10308401872 10752704512 1365417264 14.286 98 24 48 2 1381612432 11030528 5164640
01:19:08 57852 8192000000 10296244272 10770300928 1380505728 14.421 100 16 33 1 1407204016 23026704 3671584
01:21:07 50756 8192000000 10308000688 10752397312 1376517584 14.385 99 20 42 0 1391676112 10758928 4399600
01:22:58 131785 8192000000 10269931840 10732425216 1340690768 14.064 97 19 37 0 1355957392 10622688 4643936
01:26:11 37817 8192000000 10251742560 10709868544 1339655712 14.054 98 5 10 0 1352861424 11721328 1484384
01:27:57 78372 8192000000 10311997616 10764783616 1400746848 14.602 100 27 54 3 1419442144 11718080 6977216
01:30:29 61191 8192000000 10281902784 10739539968 1362282480 14.258 99 17 33 2 1383472432 17040144 4149808
01:32:31 78001 8192000000 10283172336 10732101632 1357846192 14.218 99 18 36 1 1374357264 12264608 4246464
01:34:53 28541 8192000000 10260316816 10701516800 1334263040 14.006 97 22 44 1 1347195120 8246032 4686048
01:36:22 26606 8192000000 10256934272 10696630272 1297353056 13.671 96 43 86 0 1318314080 11798304 9162720
01:37:49 12411 8192000000 10260488896 10692370432 1338744128 14.046 100 43 87 0 1359081328 10736896 9600304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-04-2022 01:39:32 to 17-04-2022 01:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 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 17-04-2022 01:39:48 to 17-04-2022 01:39:50.

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          239632
MON$OLDEST_ACTIVE               244705
MON$OLDEST_SNAPSHOT             244705
MON$NEXT_TRANSACTION            244705
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                       2055840
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 ---
:557: 
:558: mscs-test-161.budzdorov.ru    Sun Apr 17 01:38:22 2022
:559:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:560: 
--- end of diff output ---

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

17.04.22 01:39:51. 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-17 01:39:54 No severe PSQL-related problems occured
17.04.22 01:39:55. End of report.