Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.11.2021 16: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 16829384 kB
MemAvailable 31579600 kB
Buffers 0 kB
Cached 14874740 kB
SwapCached 0 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.8
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 2021-11-26 16:37:59
DTS_END                         2021-11-26 16:37:59.9510
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49146
TRN_ID                          234491
ATT_ID                          672
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: 9250.47 23523 1110056
customer order: creation 166.36 397 19963
customer order: refuse 14.79 330 1775
order to supplier: creation 44.95 1385 5394
order to supplier: removal 3.62 110609 434
invoice (draft): creation 43.96 3335 5275
invoice (draft): removal 53.79 1010 6455
invoice accept: apply 40.38 17415 4846
invoice accept: cancel 48.95 802 5874
customer reserve: creation 3710.22 172 445226
customer reserve: removal 4828.15 63 579378
realization accept: apply 82.08 185 9849
realization accept: cancel 86.91 110 10429
payment from customer: creation 57.19 121 6863
payment from customer: removal 6.08 89 730
payment to supplier: creation 48.23 122 5788
payment to supplier: removal 3.05 110 366
service: total inventory turnovers 4.63 1110 555
service: total monetary turnovers 6.20 28 744
service: aggregate perf. data 0.90 17223 108
service: refresh index statistics 0.03 315851 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-11-2021 16:40:12 to 26-11-2021 16:40:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3715
TEST_TIME 2 3624
TEST_TIME 3 4428
TEST_TIME 4 4577
TEST_TIME 5 4196
TEST_TIME 6 4517
TEST_TIME 7 4501
TEST_TIME 8 4605
TEST_TIME 9 4894
TEST_TIME 10 4965
TEST_TIME 11 5242
TEST_TIME 12 5554
TEST_TIME 13 5927
TEST_TIME 14 5916
TEST_TIME 15 6081
TEST_TIME 16 6077
TEST_TIME 17 6320
TEST_TIME 18 6777
TEST_TIME 19 7043
TEST_TIME 20 7468
TEST_TIME 21 7743
TEST_TIME 22 7907
TEST_TIME 23 7944
TEST_TIME 24 7876
TEST_TIME 25 8764
TEST_TIME 26 9336
TEST_TIME 27 9547
TEST_TIME 28 10650
TEST_TIME 29 10594
TEST_TIME 30 10814
TEST_TIME 31 10623
TEST_TIME 32 10552
TEST_TIME 33 10351
TEST_TIME 34 10169
TEST_TIME 35 10022
TEST_TIME 36 10146
TEST_TIME 37 10078
TEST_TIME 38 9914
TEST_TIME 39 9696
TEST_TIME 40 9873
TEST_TIME 41 9827
TEST_TIME 42 9660
TEST_TIME 43 9601
TEST_TIME 44 9506
TEST_TIME 45 9553
TEST_TIME 46 9546
TEST_TIME 47 9405
TEST_TIME 48 9259
TEST_TIME 49 9298
TEST_TIME 50 9397
TEST_TIME 51 9491
TEST_TIME 52 9454
TEST_TIME 53 9618
TEST_TIME 54 9589
TEST_TIME 55 9572
TEST_TIME 56 9627
TEST_TIME 57 9557
TEST_TIME 58 9783
TEST_TIME 59 9724
TEST_TIME 60 9758
TEST_TIME 61 9830
TEST_TIME 62 9729
TEST_TIME 63 9633
TEST_TIME 64 9608
TEST_TIME 65 9582
TEST_TIME 66 9550
TEST_TIME 67 9487
TEST_TIME 68 9440
TEST_TIME 69 9396
TEST_TIME 70 9382
TEST_TIME 71 9318
TEST_TIME 72 9321
TEST_TIME 73 9258
TEST_TIME 74 9254
TEST_TIME 75 9254
TEST_TIME 76 9247
TEST_TIME 77 9315
TEST_TIME 78 9304
TEST_TIME 79 9294
TEST_TIME 80 9322
TEST_TIME 81 9327
TEST_TIME 82 9438
TEST_TIME 83 9404
TEST_TIME 84 9425
TEST_TIME 85 9455
TEST_TIME 86 9425
TEST_TIME 87 9338
TEST_TIME 88 9364
TEST_TIME 89 9321
TEST_TIME 90 9286
TEST_TIME 91 9216
TEST_TIME 92 9187
TEST_TIME 93 9195
TEST_TIME 94 9225
TEST_TIME 95 9296
TEST_TIME 96 9298
TEST_TIME 97 9314
TEST_TIME 98 9312
TEST_TIME 99 9289
TEST_TIME 100 9272
TEST_TIME 101 9358
TEST_TIME 102 9393
TEST_TIME 103 9464
TEST_TIME 104 9449
TEST_TIME 105 9455
TEST_TIME 106 9468
TEST_TIME 107 9496
TEST_TIME 108 9482
TEST_TIME 109 9459
TEST_TIME 110 9458
TEST_TIME 111 9405
TEST_TIME 112 9425
TEST_TIME 113 9371
TEST_TIME 114 9384
TEST_TIME 115 9350
TEST_TIME 116 9307
TEST_TIME 117 9267
TEST_TIME 118 9241
TEST_TIME 119 9242
TEST_TIME 120 9263
TEST_TIME 121 9186

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-11-2021 16:40:16 to 26-11-2021 16:40:19.

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 1522909 1522909 0 0.00 0 14436 6 0 0
doc_list_biud 1522905 1522905 0 0.00 0 65905 31 0 0
sp_add_invoice_to_stock 4937 4846 91 1.84 29 185845 17415 0 91
sp_cancel_adding_invoice 5874 5874 0 0.00 5 73189 802 0 0
sp_cancel_client_order 1775 1775 0 0.00 4 11393 330 0 0
sp_cancel_customer_reserve 579378 579378 0 0.00 1 65907 64 0 0
sp_cancel_pay_from_customer 730 730 0 0.00 3 4467 90 0 0
sp_cancel_pay_to_supplier 366 366 0 0.00 2 9816 111 0 0
sp_cancel_supplier_invoice 6459 6455 4 0.06 2 39261 1011 0 4
sp_cancel_supplier_order 434 434 0 0.00 11 1181417 110609 0 0
sp_cancel_write_off 10429 10429 0 0.00 1 9803 110 0 0
sp_client_order 19963 19963 0 0.00 3 28058 398 0 0
sp_customer_reserve 445226 445226 0 0.00 0 107695 173 0 0
sp_fill_shopping_cart 30627 30627 0 0.00 1 13440 36 0 0
sp_fill_shopping_cart_clo_res 445207 445207 0 0.00 0 15602 15 0 0
sp_get_clo_for_invoice 4825 4825 0 0.00 0 24003 328 0 0
sp_kill_cost_storno 17399 17399 0 0.00 0 7161 55 0 0
sp_kill_qstorno_ret_qs2qd 1176074 1176074 0 0.00 0 65903 35 0 0
sp_kill_qty_storno 619018 619018 0 0.00 0 65905 76 0 0
sp_lock_selected_doc 20431 20431 0 0.00 0 7665 7 0 0
sp_make_cost_storno 27325 27325 0 0.00 0 14260 52 0 0
sp_multiply_rows_for_pdistr 27324 27324 0 0.00 0 7464 29 0 0
sp_multiply_rows_for_qdistr 19963 19963 0 0.00 0 12209 177 0 0
sp_pay_from_customer 6863 6863 0 0.00 4 14652 121 0 0
sp_pay_to_supplier 5788 5788 0 0.00 4 10416 123 0 0
sp_payment_common 12651 12651 0 0.00 1 10602 28 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 0 11382 308 0 0
sp_qd_handle_on_invoice_upd_sts 10698 10698 0 0.00 0 30150 345 0 0
sp_qd_handle_on_reserve_upd_sts 9849 9849 0 0.00 0 17663 60 0 0
sp_reserve_write_off 9849 9849 0 0.00 6 19092 185 0 0
sp_supplier_invoice 5275 5275 0 0.00 6 60837 3336 0 0
sp_supplier_order 5394 5394 0 0.00 5 45269 1385 0 0
srv_aggregate_perf_data 109 108 1 0.91 2687 146979 17224 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 2988 132697 45232 0 0
srv_find_qd_qs_mism 1064101 1064101 0 0.00 0 10184 5 0 0
srv_make_invnt_saldo 555 555 0 0.00 44 122334 1110 0 0
srv_make_money_saldo 744 744 0 0.00 1 3920 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 204480 449835 315851 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4846 4846 0 0.00 0 15197 150 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 0 6592 81 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 6216 23 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 6912 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 730 730 0 0.00 0 8211 28 0 0
t$perf-norm:sp_cancel_pay_to_supplier 366 366 0 0.00 0 2899 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 3159 23 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 27642 1806 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 5980 14 0 0
t$perf-norm:sp_client_order 19963 19963 0 0.00 0 10514 17 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 1956 7 0 0
t$perf-norm:sp_pay_from_customer 6863 6863 0 0.00 0 8542 10 0 0
t$perf-norm:sp_pay_to_supplier 5788 5788 0 0.00 0 10540 15 0 0
t$perf-norm:sp_reserve_write_off 9849 9849 0 0.00 0 6449 12 0 0
t$perf-norm:sp_supplier_invoice 5275 5275 0 0.00 0 15900 22 0 0
t$perf-norm:sp_supplier_order 5394 5394 0 0.00 0 1802 13 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 474 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 388 8 0 0
t$perf-norm:srv_make_invnt_saldo 555 555 0 0.00 0 1845 31 0 0
t$perf-norm:srv_make_money_saldo 744 744 0 0.00 0 1521 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 9830 9648 182 1.85 0 5550 25 0 182
v_all_customers 35854 35854 0 0.00 0 26 1 0 0
v_all_suppliers 29878 29878 0 0.00 0 16 1 0 0
v_all_wares 39922 39922 0 0.00 0 52 1 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 588 7 0 0
v_cancel_client_order 3550 3550 0 0.00 0 1843 5 0 0
v_cancel_customer_prepayment 1460 1460 0 0.00 0 3585 8 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 2520 5 0 0
v_cancel_payment_to_supplier 732 732 0 0.00 0 9786 29 0 0
v_cancel_supplier_invoice 758 750 8 1.05 0 5703 34 0 8
v_cancel_supplier_order 868 868 0 0.00 0 820 5 0 0
v_cancel_write_off 2064 2064 0 0.00 0 3675 19 0 0
v_max_id_clo_ord 5392 5392 0 0.00 0 208 1 0 0
v_max_id_clo_res 990 990 0 0.00 0 1600 9 0 0
v_max_id_ord_sup 5273 5273 0 0.00 0 4289 4 0 0
v_max_non_paid_invoice 1514 1514 0 0.00 0 2763 4 0 0
v_max_non_paid_realizn 3762 3762 0 0.00 0 10598 7 0 0
v_min_id_clo_ord 5392 5392 0 0.00 0 1826 5 0 0
v_min_id_clo_res 990 990 0 0.00 0 5764 23 0 0
v_min_id_ord_sup 5273 5273 0 0.00 0 5675 5 0 0
v_min_non_paid_invoice 5788 5788 0 0.00 0 6490 15 0 0
v_min_non_paid_realizn 6863 6863 0 0.00 0 7148 20 0 0
v_our_firm 10270 10270 0 0.00 0 11 1 0 0
v_reserve_write_off 19698 19698 0 0.00 0 10224 8 0 0
x_lock_depdocs_on_canc_invoice 11954 11954 0 0.00 0 21584 119 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 21375 434 0 0
x_make_qstorno_1000_1200 5394 5394 0 0.00 2 45251 1309 0 0
x_make_qstorno_1200_2000 5275 5275 0 0.00 3 60818 3207 0 0
x_make_qstorno_2100_3300 445225 445225 0 0.00 0 107694 156 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 26-11-2021 16:40:20 to 26-11-2021 16:40:25.

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
14:08:03 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:04 33190 8192000000 9916282976 10229805056 998742688 10.866 100 91 182 0 1029694576 6608400 24343488
14:10:42 20342 8192000000 9957542736 10282795008 1037645248 11.242 100 88 176 0 1069369152 9230752 22493152
14:12:05 21263 8192000000 9987363440 10321231872 1065542928 11.509 100 87 172 2 1097914720 10801376 21570416
14:13:30 14311 8192000000 10036699376 10380361728 1116301536 11.992 100 82 163 1 1146961376 11316640 19343200
14:14:47 6463 8192000000 10075815024 10434777088 1162064064 12.423 100 74 145 0 1190533248 11805808 16663376
14:15:57 4968 8192000000 10122527392 10489794560 1208931232 12.859 100 83 167 1 1241198208 13997760 18269216
14:17:03 2924 8192000000 10158693728 10532859904 1243901472 13.182 100 83 168 1 1276116064 13539648 18674944
14:18:08 1869 8192000000 10195342736 10577620992 1281452752 13.526 100 90 179 2 1314298608 12765392 20080464
14:19:12 1420 8192000000 10221677344 10603225088 1297087152 13.669 100 95 191 0 1331579376 13053856 21438368
14:20:14 3526 8192000000 10198511376 10601111552 1304233904 13.734 100 47 94 1 1325549504 11024848 10290752
14:21:18 6278 8192000000 10198430800 10608934912 1308584704 13.773 100 24 48 1 1320460976 6863376 5012896
14:22:28 54560 8192000000 10203522272 10622324736 1315660576 13.837 100 36 72 1 1330259424 5428064 9170784
14:24:24 6317 8192000000 10212662912 10630021120 1323293824 13.907 100 26 52 2 1336599696 6909792 6396080
14:25:31 7939 8192000000 10210830704 10632101888 1324699504 13.919 100 10 20 0 1332202944 5311328 2192112
14:26:40 10143 8192000000 10214698624 10631720960 1316019968 13.841 99 18 34 0 1326251264 6073216 4158080
14:27:52 15782 8192000000 10235771712 10636546048 1310374000 13.789 99 66 134 0 1333443424 7199136 15870288
14:29:08 5642 8192000000 10183985648 10590420992 1269764048 13.419 98 35 70 1 1283580640 5742880 8073712
14:30:14 5073 8192000000 10146635600 10547605504 1229232016 13.047 97 28 55 3 1241343440 5402256 6709168
14:31:21 10733 8192000000 10131371520 10533785600 1201815616 12.793 95 31 60 0 1214950928 5911728 7223584
14:32:32 16707 8192000000 10121547408 10520686592 1194151744 12.722 95 25 50 0 1205583584 5849664 5582176
14:33:49 8022 8192000000 10154547760 10560704512 1236873568 13.117 97 32 65 1 1250629088 6191376 7564144
14:34:58 7963 8192000000 10174831152 10586451968 1286255392 13.570 100 17 34 4 1295802832 5541696 4005744
14:36:06 7775 8192000000 10178937056 10596999168 1295641936 13.656 100 21 42 3 1306006128 5643264 4720928
14:37:15 31818 8192000000 10188078304 10606895104 1300533056 13.700 100 26 53 2 1313012096 6640864 5838176
14:38:55 23403 8192000000 10242201952 10650365952 1333232304 13.996 100 65 130 1 1357324128 9867824 14224000
14:40:28 25320 8192000000 10265063696 10687270912 1362081760 14.256 100 40 78 0 1382403424 11076336 9245328
14:41:59 14500 8192000000 10283499376 10697744384 1355461856 14.197 98 44 88 0 1377540672 11841504 10237312
14:43:16 9460 8192000000 10301470688 10718478336 1384858512 14.460 100 54 108 1 1416165920 17503008 13804400
14:44:31 7679 8192000000 10316129904 10734018560 1396789424 14.566 100 50 100 2 1427027648 18842208 11396016
14:45:47 10472 8192000000 10328038096 10750050304 1402854000 14.620 100 45 89 0 1437226624 23995504 10377120
14:47:01 9599 8192000000 10337332208 10759901184 1402001680 14.613 99 53 106 3 1440761440 25164768 13594992
14:48:15 2987 8192000000 10373016160 10788569088 1425234896 14.819 100 79 158 1 1479037728 34441440 19361392
14:49:20 5598 8192000000 10354669744 10776035328 1407647424 14.663 99 59 118 2 1459244912 36811136 14786352
14:50:32 7949 8192000000 10363570736 10784116736 1393867104 14.540 98 62 124 0 1452003920 41318384 16818432
14:51:47 10356 8192000000 10348857504 10776817664 1399592560 14.591 99 51 102 2 1455039376 42104736 13342080
14:53:06 4236 8192000000 10383724272 10799915008 1421521760 14.786 100 79 157 0 1486407728 45011264 19874704
14:54:16 4353 8192000000 10358431776 10772926464 1390819360 14.513 99 71 141 0 1460451360 49931280 19700720
14:55:26 5136 8192000000 10371496560 10790785024 1403735216 14.628 100 59 118 0 1476338112 56515168 16087728
14:56:38 4282 8192000000 10369638624 10785714176 1405446608 14.643 100 63 124 2 1477563600 55621568 16495424
14:57:54 3072 8192000000 10369700128 10785308672 1398537552 14.582 100 57 112 2 1472347280 58385936 15423792
14:59:01 10532 8192000000 10349134768 10769313792 1382355680 14.438 99 38 76 1 1445526592 52474560 10696352
15:00:28 18800 8192000000 10360348384 10786656256 1392604656 14.529 100 47 93 0 1467529744 60323056 14602032
15:01:49 4316 8192000000 10366595392 10790109184 1392073120 14.524 100 56 111 1 1477230496 69103728 16053648
15:02:57 24988 8192000000 10341249648 10780721152 1383907232 14.451 100 32 64 0 1451054016 57942896 9203888
15:04:25 32185 8192000000 10321175328 10770857984 1347318976 14.123 98 20 40 1 1409227408 55439408 6469024
15:06:33 34354 8192000000 10279387200 10720432128 1331538000 13.981 97 36 72 1 1359075232 18938176 8599056
15:08:08 18036 8192000000 10269564400 10706604032 1350375312 14.151 100 38 74 4 1365982976 6920736 8686928
15:09:27 89839 8192000000 10263388112 10718134272 1357104688 14.211 99 12 24 1 1370590624 10839536 2646400
15:12:26 53502 8192000000 10279197680 10722754560 1348042048 14.130 98 18 36 0 1368505056 16454576 4008432
15:14:37 59284 8192000000 10288026128 10753667072 1387140160 14.480 100 14 28 1 1402859568 11709040 4010368
15:16:55 112545 8192000000 10270553600 10734587904 1343180608 14.086 97 21 41 0 1359219760 11510544 4528608
15:19:51 12165 8192000000 10306170096 10743803904 1384134768 14.454 100 67 132 1 1409740192 9095856 16509568
15:21:04 42393 8192000000 10286835056 10734784512 1366377520 14.295 99 32 64 0 1384220944 9599584 8243840
15:22:52 47980 8192000000 10296146096 10752720896 1376911520 14.389 99 15 30 0 1392496944 12198144 3387280
15:24:50 88019 8192000000 10303179184 10760933376 1368126048 14.310 98 21 42 1 1393054016 19718736 5209232
15:28:20 53725 8192000000 10300384576 10760466432 1377847648 14.397 99 17 34 0 1397946016 15587408 4510960
15:30:14 67305 8192000000 10302099232 10754748416 1374571088 14.368 98 19 39 1 1390974224 12125456 4277680
15:32:25 32348 8192000000 10313385888 10762043392 1382499952 14.439 99 41 82 1 1408369088 16515088 9354048
15:33:58 34612 8192000000 10312705296 10761465856 1383837968 14.451 99 34 68 0 1407913584 16043808 8031808
15:35:39 113705 8192000000 10328681632 10768760832 1362871696 14.263 97 12 24 0 1379079392 13164736 3042960
15:38:39 100868 8192000000 10285112128 10741071872 1350553456 14.152 96 14 28 1 1363171744 9835968 2782320
15:41:39 65015 8192000000 10307506720 10765131776 1397155280 14.570 100 29 58 0 1417613008 14212064 6245664
15:43:45 48388 8192000000 10264106304 10723831808 1343765984 14.091 97 9 16 1 1351890176 6649856 1474336
15:45:34 61129 8192000000 10273211104 10729168896 1344539360 14.098 97 18 36 1 1357356960 8271536 4546064
15:47:35 50732 8192000000 10272451440 10739351552 1369033856 14.318 99 11 22 0 1379013056 7321440 2657760
15:49:26 60741 8192000000 10264629632 10735955968 1363684640 14.270 99 13 26 2 1376353216 9559040 3109536
15:51:28 52192 8192000000 10288756544 10743271424 1370486032 14.331 99 12 24 0 1382246160 9130736 2629392
15:53:21 45741 8192000000 10275470064 10738393088 1368222288 14.311 99 14 28 0 1383370672 11342352 3806032
15:55:10 25963 8192000000 10263111664 10716561408 1340425184 14.061 98 24 48 2 1354123120 7768608 5929328
15:57:00 45318 8192000000 10262150848 10713198592 1341117104 14.067 99 40 80 0 1363739392 13912432 8709856
15:58:46 56152 8192000000 10245465392 10711703552 1325436128 13.926 98 19 37 3 1347243168 17223696 4583344
16:00:50 75852 8192000000 10256890000 10708000768 1310032800 13.786 96 19 38 0 1324398864 9924976 4441088
16:03:06 25136 8192000000 10286444656 10721841152 1355202560 14.194 99 52 102 3 1375567600 8510912 11854128
16:04:34 116159 8192000000 10284268096 10735390720 1371356384 14.339 100 29 58 2 1388904864 10886976 6661504
16:07:42 58113 8192000000 10274165872 10732707840 1355368512 14.196 98 8 16 0 1367408144 9963424 2076208
16:09:41 72764 8192000000 10287968016 10755256320 1361870368 14.254 98 13 26 2 1383614560 18532992 3211200
16:11:54 41941 8192000000 10285449184 10746187776 1341344608 14.070 97 13 26 1 1363790144 19423984 3021552
16:13:41 68379 8192000000 10290535968 10739699712 1360087152 14.238 98 11 22 0 1374626000 11982416 2556432
16:15:55 43579 8192000000 10307741520 10753835008 1390440768 14.510 100 37 74 0 1409046336 10810160 7795408
16:17:42 45687 8192000000 10311559472 10755850240 1349995712 14.147 97 44 88 2 1378380336 18028368 10356256
16:20:00 114120 8192000000 10279656496 10730700800 1333022592 13.994 97 19 39 0 1355302304 18539920 3739792
16:22:56 132697 8192000000 10298207488 10754404352 1317306704 13.852 95 5 10 0 1330021072 11722096 992272
16:26:20 126891 8192000000 10288850640 10752520192 1370569744 14.332 99 22 44 1 1394244384 18158688 5515952
16:29:41 63533 8192000000 10284188256 10755207168 1362065008 14.256 98 4 8 0 1374156992 11035360 1056624
16:31:44 39945 8192000000 10265918384 10723495936 1350190864 14.149 98 11 23 0 1360489680 7610896 2687920
16:33:26 63061 8192000000 10258250640 10711707648 1338979200 14.048 98 16 32 0 1350141456 7685952 3476304
16:35:31 68785 8192000000 10273913472 10732302336 1336758896 14.028 97 13 25 0 1354431920 14939456 2733568
16:37:42 26836 8192000000 9202565488 9474514944 371463072 4.337 28 3 6 0 376291792 4428464 400256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-11-2021 16:40:26 to 26-11-2021 16:40:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 91 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-11-2021 16:40:42 to 26-11-2021 16:40:44.

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          234742
MON$OLDEST_ACTIVE               234743
MON$OLDEST_SNAPSHOT             234743
MON$NEXT_TRANSACTION            234743
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                       2023296
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:382: 
:383: mscs-test-161.budzdorov.ru    Fri Nov 26 16:38:22 2021
:384:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:385: 
--- end of diff output ---

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

26.11.21 16:40:45. 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
2021-11-26 16:40:47 No severe PSQL-related problems occured
26.11.21 16:40:49. End of report.