Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.06.2022 22: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 16317196 kB
MemAvailable 31209788 kB
Buffers 0 kB
Cached 15363052 kB
SwapCached 2460 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-06-19 22:38:23
DTS_END                         2022-06-19 22:38:23.6130
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48356
TRN_ID                          235272
ATT_ID                          614
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: 9445.08 23270 1133409
customer order: creation 164.23 384 19707
customer order: refuse 14.07 336 1688
order to supplier: creation 44.53 1237 5343
order to supplier: removal 3.62 100258 434
invoice (draft): creation 44.40 3066 5328
invoice (draft): removal 56.78 784 6814
invoice accept: apply 40.22 18110 4826
invoice accept: cancel 51.22 663 6146
customer reserve: creation 3730.28 178 447633
customer reserve: removal 5002.82 55 600338
realization accept: apply 80.42 177 9650
realization accept: cancel 87.06 104 10447
payment from customer: creation 57.92 125 6950
payment from customer: removal 5.25 79 630
payment to supplier: creation 47.02 141 5642
payment to supplier: removal 3.32 104 398
service: total inventory turnovers 4.57 1343 548
service: total monetary turnovers 6.58 20 789
service: aggregate perf. data 0.78 23596 94
service: refresh index statistics 0.03 314647 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-06-2022 22:39:43 to 19-06-2022 22:39:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7358
TEST_TIME 2 7169
TEST_TIME 3 6290
TEST_TIME 4 6340
TEST_TIME 5 6177
TEST_TIME 6 6139
TEST_TIME 7 5856
TEST_TIME 8 6366
TEST_TIME 9 6265
TEST_TIME 10 6290
TEST_TIME 11 6418
TEST_TIME 12 6433
TEST_TIME 13 6715
TEST_TIME 14 7050
TEST_TIME 15 7246
TEST_TIME 16 7610
TEST_TIME 17 7922
TEST_TIME 18 7908
TEST_TIME 19 8272
TEST_TIME 20 8196
TEST_TIME 21 8068
TEST_TIME 22 8812
TEST_TIME 23 8692
TEST_TIME 24 8732
TEST_TIME 25 9354
TEST_TIME 26 9802
TEST_TIME 27 10958
TEST_TIME 28 10765
TEST_TIME 29 10668
TEST_TIME 30 10524
TEST_TIME 31 10363
TEST_TIME 32 10204
TEST_TIME 33 10146
TEST_TIME 34 10053
TEST_TIME 35 10132
TEST_TIME 36 10116
TEST_TIME 37 10051
TEST_TIME 38 9933
TEST_TIME 39 9978
TEST_TIME 40 9854
TEST_TIME 41 9791
TEST_TIME 42 9680
TEST_TIME 43 9671
TEST_TIME 44 9577
TEST_TIME 45 9430
TEST_TIME 46 9351
TEST_TIME 47 9363
TEST_TIME 48 9439
TEST_TIME 49 9325
TEST_TIME 50 9338
TEST_TIME 51 9473
TEST_TIME 52 9471
TEST_TIME 53 9574
TEST_TIME 54 9615
TEST_TIME 55 9559
TEST_TIME 56 9549
TEST_TIME 57 9807
TEST_TIME 58 9912
TEST_TIME 59 9938
TEST_TIME 60 9969
TEST_TIME 61 9823
TEST_TIME 62 9785
TEST_TIME 63 9728
TEST_TIME 64 9682
TEST_TIME 65 9652
TEST_TIME 66 9657
TEST_TIME 67 9597
TEST_TIME 68 9531
TEST_TIME 69 9612
TEST_TIME 70 9546
TEST_TIME 71 9500
TEST_TIME 72 9508
TEST_TIME 73 9533
TEST_TIME 74 9658
TEST_TIME 75 9683
TEST_TIME 76 9719
TEST_TIME 77 9729
TEST_TIME 78 9806
TEST_TIME 79 9822
TEST_TIME 80 9810
TEST_TIME 81 9797
TEST_TIME 82 9698
TEST_TIME 83 9677
TEST_TIME 84 9626
TEST_TIME 85 9595
TEST_TIME 86 9626
TEST_TIME 87 9575
TEST_TIME 88 9575
TEST_TIME 89 9551
TEST_TIME 90 9470
TEST_TIME 91 9451
TEST_TIME 92 9443
TEST_TIME 93 9451
TEST_TIME 94 9499
TEST_TIME 95 9490
TEST_TIME 96 9480
TEST_TIME 97 9589
TEST_TIME 98 9563
TEST_TIME 99 9529
TEST_TIME 100 9665
TEST_TIME 101 9643
TEST_TIME 102 9613
TEST_TIME 103 9633
TEST_TIME 104 9714
TEST_TIME 105 9715
TEST_TIME 106 9678
TEST_TIME 107 9593
TEST_TIME 108 9581
TEST_TIME 109 9611
TEST_TIME 110 9566
TEST_TIME 111 9568
TEST_TIME 112 9515
TEST_TIME 113 9489
TEST_TIME 114 9474
TEST_TIME 115 9433
TEST_TIME 116 9451
TEST_TIME 117 9521
TEST_TIME 118 9492
TEST_TIME 119 9514
TEST_TIME 120 9453

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-06-2022 22:39:47 to 19-06-2022 22:39:50.

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 1548339 1548339 0 0.00 0 22779 6 0 0
doc_list_biud 1548339 1548339 0 0.00 0 62446 27 0 0
sp_add_invoice_to_stock 4966 4826 140 2.81 20 167311 18110 0 140
sp_cancel_adding_invoice 6146 6146 0 0.00 4 48143 663 0 0
sp_cancel_client_order 1688 1688 0 0.00 3 9835 337 0 0
sp_cancel_customer_reserve 600338 600338 0 0.00 1 27162 56 0 0
sp_cancel_pay_from_customer 630 630 0 0.00 2 5871 80 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 8542 104 0 0
sp_cancel_supplier_invoice 6829 6814 15 0.21 1 62459 785 0 15
sp_cancel_supplier_order 434 434 0 0.00 8 973226 100258 0 0
sp_cancel_write_off 10447 10447 0 0.00 1 9901 105 0 0
sp_client_order 19707 19707 0 0.00 3 63011 384 0 0
sp_customer_reserve 447633 447633 0 0.00 0 131239 179 0 0
sp_fill_shopping_cart 30372 30372 0 0.00 1 23927 43 0 0
sp_fill_shopping_cart_clo_res 447619 447619 0 0.00 0 13450 15 0 0
sp_get_clo_for_invoice 4812 4812 0 0.00 0 32881 352 0 0
sp_kill_cost_storno 17621 17621 0 0.00 0 5855 51 0 0
sp_kill_qstorno_ret_qs2qd 1218371 1218371 0 0.00 0 62440 31 0 0
sp_kill_qty_storno 640329 640329 0 0.00 0 62445 65 0 0
sp_lock_selected_doc 19999 19999 0 0.00 0 8383 5 0 0
sp_make_cost_storno 27054 27054 0 0.00 0 17392 55 0 0
sp_multiply_rows_for_pdistr 27054 27054 0 0.00 0 8833 31 0 0
sp_multiply_rows_for_qdistr 19707 19707 0 0.00 0 59680 165 0 0
sp_pay_from_customer 6950 6950 0 0.00 4 18493 126 0 0
sp_pay_to_supplier 5642 5642 0 0.00 5 17947 142 0 0
sp_payment_common 12592 12592 0 0.00 1 9893 31 0 0
sp_qd_handle_on_cancel_clo 1688 1688 0 0.00 0 9823 304 0 0
sp_qd_handle_on_invoice_upd_sts 10958 10958 0 0.00 0 14474 294 0 0
sp_qd_handle_on_reserve_upd_sts 9650 9650 0 0.00 0 12735 48 0 0
sp_reserve_write_off 9650 9650 0 0.00 6 13984 178 0 0
sp_supplier_invoice 5328 5328 0 0.00 5 64883 3066 0 0
sp_supplier_order 5343 5343 0 0.00 5 43396 1237 0 0
srv_aggregate_perf_data 94 94 0 0.00 2480 468420 23597 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2419 153532 49181 0 0
srv_find_qd_qs_mism 1087528 1087528 0 0.00 0 22779 5 0 0
srv_make_invnt_saldo 548 548 0 0.00 49 179180 1344 0 0
srv_make_money_saldo 789 789 0 0.00 0 636 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 88297 501210 314648 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4826 4826 0 0.00 0 11437 194 0 0
t$perf-norm:sp_cancel_adding_invoice 370 370 0 0.00 0 6039 124 0 0
t$perf-norm:sp_cancel_client_order 1688 1688 0 0.00 0 3608 16 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 4308 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 630 630 0 0.00 0 2109 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 480 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 2310 31 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 42301 1772 0 0
t$perf-norm:sp_cancel_write_off 951 951 0 0.00 0 4531 16 0 0
t$perf-norm:sp_client_order 19707 19707 0 0.00 0 13764 20 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 1325 4 0 0
t$perf-norm:sp_pay_from_customer 6950 6950 0 0.00 0 3644 10 0 0
t$perf-norm:sp_pay_to_supplier 5642 5642 0 0.00 0 12922 18 0 0
t$perf-norm:sp_reserve_write_off 9650 9650 0 0.00 0 9064 13 0 0
t$perf-norm:sp_supplier_invoice 5328 5328 0 0.00 0 6500 22 0 0
t$perf-norm:sp_supplier_order 5343 5343 0 0.00 0 7482 19 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 264 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 650 17 0 0
t$perf-norm:srv_make_invnt_saldo 548 548 0 0.00 0 1036 21 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 4174 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 9904 9624 280 2.82 0 7126 19 0 280
v_all_customers 36412 36412 0 0.00 0 88 1 0 0
v_all_suppliers 29522 29522 0 0.00 0 16 1 0 0
v_all_wares 39414 39414 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 740 740 0 0.00 0 1965 10 0 0
v_cancel_client_order 3376 3376 0 0.00 0 3863 8 0 0
v_cancel_customer_prepayment 1260 1260 0 0.00 0 3874 9 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 4471 10 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 3376 8 0 0
v_cancel_supplier_invoice 790 760 30 3.79 0 3795 20 0 30
v_cancel_supplier_order 868 868 0 0.00 0 712 4 0 0
v_cancel_write_off 1902 1902 0 0.00 0 3726 16 0 0
v_max_id_clo_ord 5343 5343 0 0.00 0 430 1 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 3957 12 0 0
v_max_id_ord_sup 5322 5322 0 0.00 0 5753 6 0 0
v_max_non_paid_invoice 1546 1546 0 0.00 0 655 3 0 0
v_max_non_paid_realizn 3468 3468 0 0.00 0 3227 8 0 0
v_min_id_clo_ord 5343 5343 0 0.00 0 16017 11 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 2992 6 0 0
v_min_id_ord_sup 5322 5322 0 0.00 0 7848 9 0 0
v_min_non_paid_invoice 5642 5642 0 0.00 0 5720 19 0 0
v_min_non_paid_realizn 6950 6950 0 0.00 0 6706 24 0 0
v_our_firm 9966 9966 0 0.00 0 14 1 0 0
v_reserve_write_off 19300 19300 0 0.00 0 4648 7 0 0
x_lock_depdocs_on_canc_invoice 12580 12580 0 0.00 0 13905 128 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 10285 351 0 0
x_make_qstorno_1000_1200 5343 5343 0 0.00 1 43364 1146 0 0
x_make_qstorno_1200_2000 5328 5328 0 0.00 2 62958 2921 0 0
x_make_qstorno_2100_3300 447633 447633 0 0.00 0 131162 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-06-2022 22:39:51 to 19-06-2022 22:39:55.

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:26 3 8192000000 8800833936 8812793856 6886560 0.083 2 1 2 0 7000896 67184 47152
20:09:27 21457 8192000000 9886435872 10190528512 969885424 10.586 100 89 178 0 1000089232 6197856 24005952
20:10:52 26636 8192000000 9923703264 10238492672 1006791760 10.944 100 95 190 1 1040509024 8239920 25477344
20:12:27 17225 8192000000 9966461824 10294665216 1045466944 11.317 100 84 168 0 1076465408 9689344 21309120
20:13:48 12767 8192000000 10018842768 10362089472 1104825808 11.883 100 77 154 0 1133665712 10097760 18742144
20:15:04 9637 8192000000 10073727312 10433753088 1166967216 12.468 100 66 132 0 1192041104 9951680 15122208
20:16:15 5273 8192000000 10128268944 10499309568 1217276400 12.936 100 83 166 1 1248383936 11995888 19111648
20:17:24 3890 8192000000 10165440272 10538979328 1249004480 13.229 100 89 178 0 1282340176 14183520 19152176
20:18:29 2260 8192000000 10191611440 10569285632 1273120720 13.450 100 91 180 3 1306936656 13616912 20199024
20:19:33 2228 8192000000 10207083136 10590175232 1287247728 13.579 100 92 182 0 1321816192 13609328 20959136
20:20:37 5137 8192000000 10197252656 10596237312 1296357488 13.662 100 51 102 0 1317294112 9290704 11645920
20:21:45 61717 8192000000 10199073968 10610716672 1305696912 13.747 100 27 55 1 1317876432 5619392 6560128
20:23:49 10630 8192000000 10203082544 10622267392 1313968160 13.822 100 21 42 0 1325529424 6771008 4790256
20:25:00 7680 8192000000 10214713376 10634248192 1325999840 13.931 100 26 52 0 1338232784 6203008 6029936
20:26:14 46908 8192000000 10233296560 10648907776 1321520336 13.890 99 20 40 0 1332981056 5089440 6371280
20:28:17 7342 8192000000 10193441504 10607472640 1272291392 13.443 96 23 46 0 1282632368 5548560 4792416
20:29:27 10338 8192000000 10189600368 10601975808 1271985984 13.440 97 16 32 1 1281155008 5217008 3952016
20:30:41 12089 8192000000 10145522656 10543407104 1210587312 12.875 96 25 50 1 1222394976 6250512 5557152
20:31:55 71601 8192000000 10132982288 10529431552 1141733936 12.232 90 37 74 1 1154376064 4733952 7908176
20:34:07 7567 8192000000 10174799488 10577473536 1278026576 13.495 100 29 58 0 1294396928 10263824 6106528
20:35:15 8259 8192000000 10201684000 10608730112 1302835696 13.721 100 30 60 0 1314792032 5048816 6907520
20:36:24 60250 8192000000 10195472832 10618937344 1310092528 13.787 100 29 57 1 1323977056 6293328 7591200
20:38:25 16898 8192000000 10237308736 10656333824 1339294656 14.051 100 50 98 4 1361006224 10067968 11643600
20:39:53 13348 8192000000 10267565024 10684317696 1358571168 14.225 100 52 104 0 1381202512 10891632 11739712
20:41:13 45151 8192000000 10282266432 10696007680 1370999488 14.336 100 61 121 5 1397500032 11987648 14512896
20:43:02 10867 8192000000 10294515216 10713948160 1387560208 14.484 100 61 119 0 1414077648 13201920 13315520
20:44:20 14261 8192000000 10314360640 10737401856 1400280352 14.597 100 55 110 0 1427632912 13948096 13404464
20:45:39 28549 8192000000 10323152656 10752540672 1394739184 14.548 99 43 86 0 1426122944 20988224 10395536
20:47:16 15135 8192000000 10312648896 10736603136 1390258528 14.508 99 52 104 1 1420402272 16906000 13237744
20:48:38 31034 8192000000 10320563328 10748162048 1403171520 14.623 100 36 70 1 1430277856 18533712 8572624
20:50:15 5446 8192000000 10339945520 10762584064 1409659232 14.681 100 68 134 2 1449154720 22842352 16653136
20:51:24 2415 8192000000 10362727488 10781360128 1423933952 14.808 100 77 152 0 1471501104 28212032 19355120
20:52:31 2920 8192000000 10346234832 10758688768 1395149424 14.552 98 84 166 0 1446185424 29945408 21090592
20:53:40 4268 8192000000 10361488544 10775494656 1404949280 14.639 99 78 153 0 1457692688 33783280 18960128
20:54:51 4862 8192000000 10365540064 10783203328 1416166432 14.739 100 74 148 0 1470232880 34664496 19401952
20:56:01 7566 8192000000 10337504576 10754027520 1367163728 14.302 97 71 141 0 1425435904 39557824 18714352
20:57:16 3163 8192000000 10385942304 10808328192 1423253008 14.802 100 69 138 0 1487576976 45882352 18441616
20:58:24 10287 8192000000 10358584480 10789822464 1412406640 14.705 100 44 88 2 1472177088 48022880 11747568
20:59:43 9199 8192000000 10364261728 10792349696 1402637600 14.618 100 50 100 0 1474847136 58046080 14163456
21:00:56 21835 8192000000 10339065696 10770190336 1369476432 14.322 98 27 54 0 1422194992 44890688 7827872
21:02:25 15510 8192000000 10323846128 10757730304 1366416384 14.295 100 31 61 2 1428343984 53296304 8631296
21:04:18 42206 8192000000 10271156992 10713792512 1285168144 13.560 96 17 34 0 1346679232 56241760 5269328
21:06:06 28531 8192000000 10258171520 10690822144 1346033968 14.112 100 26 51 2 1361224320 7880176 7310176
21:07:39 31008 8192000000 10237940960 10665910272 1314010320 13.822 99 23 46 1 1329639440 9691360 5937760
21:09:24 91205 8192000000 10255789616 10694725632 1333110816 13.995 98 28 55 0 1350340432 11783344 5446272
21:11:59 11321 8192000000 10274916144 10705629184 1341792528 14.074 98 59 115 2 1368959696 13588400 13578768
21:13:13 85536 8192000000 10271099584 10724741120 1345567264 14.108 98 21 42 0 1365468048 15199312 4701472
21:15:40 153532 8192000000 10265858112 10722156544 1345758240 14.109 98 21 43 1 1360147312 9669632 4719440
21:19:14 54690 8192000000 10271186336 10729218048 1372914400 14.353 100 16 32 0 1384716048 8120224 3681424
21:21:10 91773 8192000000 10277457984 10745122816 1381760640 14.432 100 17 33 0 1400594512 15526816 3307056
21:23:45 68344 8192000000 10283518096 10748104704 1390952304 14.514 100 17 34 1 1405367008 10346624 4068080
21:26:03 62377 8192000000 10285493808 10754723840 1375301680 14.375 99 9 18 0 1388837328 11620976 1914672
21:28:11 44525 8192000000 10315722336 10768773120 1389160320 14.498 99 26 51 0 1406247520 11560448 5526752
21:30:24 47211 8192000000 10304410432 10767507456 1388755488 14.495 99 23 43 1 1411065808 17514800 4795520
21:32:18 58132 8192000000 10308060864 10768740352 1403688352 14.628 100 16 32 1 1419209552 11887856 3633344
21:34:19 77718 8192000000 10320719648 10797035520 1392860848 14.531 99 13 26 0 1416449328 20071312 3517168
21:36:51 140473 8192000000 10311302480 10779721728 1382845296 14.442 98 9 18 0 1411864880 26703280 2316304
21:40:13 117930 8192000000 10305272768 10761441280 1349020192 14.139 96 32 63 1 1370779696 14638752 7120752
21:43:11 62162 8192000000 10298374736 10764488704 1398459168 14.581 100 15 30 0 1417285568 15862640 2963760
21:45:13 44924 8192000000 10302396016 10768621568 1408355008 14.669 100 10 20 0 1417942352 7188192 2399152
21:46:58 40428 8192000000 10285519952 10738864128 1360128096 14.239 98 15 30 2 1372400768 8660736 3611936
21:48:45 73774 8192000000 10291310336 10731380736 1347246816 14.123 98 33 65 0 1369563312 14842192 7474304
21:51:05 51066 8192000000 10268518368 10712150016 1295041600 13.650 94 21 42 1 1315082800 14856736 5184464
21:53:11 66846 8192000000 10259979856 10706636800 1289325152 13.598 95 20 39 0 1307637040 13919392 4392496
21:55:24 43457 8192000000 10271447856 10707853312 1324132192 13.914 97 41 82 0 1353158496 19542320 9483984
21:57:08 119901 8192000000 10266622640 10707542016 1263088704 13.358 93 12 24 0 1283991552 18539584 2363264
22:00:55 38331 8192000000 10241061936 10679508992 1299494528 13.691 96 24 48 1 1311224848 6462944 5267376
22:02:35 32687 8192000000 10236896848 10672218112 1331049680 13.977 99 27 54 0 1345255280 7927232 6278368
22:04:09 38159 8192000000 10229406528 10682806272 1308657776 13.774 97 24 49 0 1322267888 8231792 5378320
22:05:49 50896 8192000000 10243239872 10693685248 1337662640 14.036 99 12 24 0 1349080784 8844800 2573344
22:07:50 61889 8192000000 10247027408 10697773056 1345022928 14.103 99 9 18 1 1353033392 6265904 1744560
22:10:04 61922 8192000000 10265315664 10722009088 1354337808 14.186 99 7 14 0 1364770000 8574784 1857408
22:12:08 47367 8192000000 10275135840 10726797312 1350358224 14.151 98 14 28 1 1363349392 9812448 3178720
22:14:03 46213 8192000000 10298499680 10748796928 1384027072 14.453 100 40 80 2 1406102432 12696320 9379040
22:15:58 58788 8192000000 10314789488 10756218880 1395960144 14.559 100 26 50 1 1414758400 13235104 5563152
22:18:01 58578 8192000000 10309706528 10787250176 1397897264 14.576 100 16 32 0 1424184400 22657376 3629760
22:20:06 46513 8192000000 10324273024 10777407488 1414784096 14.726 100 32 64 1 1434054848 12106720 7164032
22:21:53 117089 8192000000 10324049728 10791079936 1391134400 14.516 98 8 16 0 1408990112 15673232 2182480
22:24:50 105278 8192000000 10305470352 10765053952 1390418256 14.510 99 20 40 0 1411130928 16273552 4439120
22:27:47 31599 8192000000 10300189408 10769764352 1400448032 14.599 100 10 20 1 1414946080 12482272 2015776
22:29:19 93577 8192000000 10304261872 10765017088 1368083168 14.310 97 13 26 0 1387959312 16676304 3199840
22:31:53 70473 8192000000 10324495968 10786906112 1413236784 14.713 100 18 36 0 1438627136 20864608 4525744
22:35:16 76088 8192000000 10295158448 10751447040 1354391632 14.187 96 25 50 0 1370653360 10284208 5977520
22:37:51 41935 8192000000 9065371168 9290035200 225486368 2.678 17 4 6 1 229103232 2689232 927632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-06-2022 22:39:57 to 19-06-2022 22:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-06-2022 22:40:12 to 19-06-2022 22:40:14.

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          235604
MON$OLDEST_ACTIVE               235605
MON$OLDEST_SNAPSHOT             235605
MON$NEXT_TRANSACTION            235605
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                       2020544
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
--- end of diff output ---

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

19.06.22 22:40:15. 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-06-19 22:40:18 No severe PSQL-related problems occured
19.06.22 22:40:19. End of report.