Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.04.2022 16:43
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 16722516 kB
MemAvailable 31530984 kB
Buffers 0 kB
Cached 14974984 kB
SwapCached 2364 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-08 16:42:11
DTS_END                         2022-04-08 16:42:11.0190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45972
TRN_ID                          237144
ATT_ID                          671
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: 9287.03 19821 1114443
customer order: creation 173.04 350 20765
customer order: refuse 14.95 355 1794
order to supplier: creation 45.08 1333 5409
order to supplier: removal 3.63 94847 436
invoice (draft): creation 45.89 3515 5507
invoice (draft): removal 53.79 731 6455
invoice accept: apply 41.00 17269 4920
invoice accept: cancel 48.63 723 5835
customer reserve: creation 3780.49 171 453659
customer reserve: removal 4782.91 58 573949
realization accept: apply 81.54 170 9785
realization accept: cancel 87.42 100 10490
payment from customer: creation 58.80 95 7056
payment from customer: removal 5.64 77 677
payment to supplier: creation 48.42 102 5810
payment to supplier: removal 3.63 70 435
service: total inventory turnovers 4.96 1005 595
service: total monetary turnovers 6.27 26 752
service: aggregate perf. data 0.92 18828 110
service: refresh index statistics 0.03 256587 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-04-2022 16:43:27 to 08-04-2022 16:43:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7517
TEST_TIME 2 7143
TEST_TIME 3 7288
TEST_TIME 4 7411
TEST_TIME 5 6651
TEST_TIME 6 6849
TEST_TIME 7 7027
TEST_TIME 8 7204
TEST_TIME 9 7262
TEST_TIME 10 7140
TEST_TIME 11 7073
TEST_TIME 12 7256
TEST_TIME 13 7182
TEST_TIME 14 7379
TEST_TIME 15 7356
TEST_TIME 16 7550
TEST_TIME 17 8599
TEST_TIME 18 8725
TEST_TIME 19 8727
TEST_TIME 20 8872
TEST_TIME 21 8865
TEST_TIME 22 9258
TEST_TIME 23 9214
TEST_TIME 24 9568
TEST_TIME 25 9739
TEST_TIME 26 9980
TEST_TIME 27 9866
TEST_TIME 28 11710
TEST_TIME 29 11472
TEST_TIME 30 11101
TEST_TIME 31 10828
TEST_TIME 32 10889
TEST_TIME 33 10953
TEST_TIME 34 10679
TEST_TIME 35 10577
TEST_TIME 36 10458
TEST_TIME 37 10333
TEST_TIME 38 10280
TEST_TIME 39 10108
TEST_TIME 40 9994
TEST_TIME 41 10058
TEST_TIME 42 10030
TEST_TIME 43 9981
TEST_TIME 44 10006
TEST_TIME 45 9833
TEST_TIME 46 9740
TEST_TIME 47 9725
TEST_TIME 48 9551
TEST_TIME 49 9516
TEST_TIME 50 9522
TEST_TIME 51 9526
TEST_TIME 52 9495
TEST_TIME 53 9491
TEST_TIME 54 9545
TEST_TIME 55 9489
TEST_TIME 56 9617
TEST_TIME 57 9626
TEST_TIME 58 9638
TEST_TIME 59 9676
TEST_TIME 60 9672
TEST_TIME 61 9771
TEST_TIME 62 9907
TEST_TIME 63 9890
TEST_TIME 64 9745
TEST_TIME 65 9687
TEST_TIME 66 9635
TEST_TIME 67 9583
TEST_TIME 68 9609
TEST_TIME 69 9559
TEST_TIME 70 9523
TEST_TIME 71 9478
TEST_TIME 72 9492
TEST_TIME 73 9608
TEST_TIME 74 9606
TEST_TIME 75 9609
TEST_TIME 76 9627
TEST_TIME 77 9704
TEST_TIME 78 9712
TEST_TIME 79 9735
TEST_TIME 80 9696
TEST_TIME 81 9706
TEST_TIME 82 9618
TEST_TIME 83 9616
TEST_TIME 84 9582
TEST_TIME 85 9542
TEST_TIME 86 9473
TEST_TIME 87 9422
TEST_TIME 88 9426
TEST_TIME 89 9404
TEST_TIME 90 9403
TEST_TIME 91 9416
TEST_TIME 92 9418
TEST_TIME 93 9399
TEST_TIME 94 9450
TEST_TIME 95 9506
TEST_TIME 96 9528
TEST_TIME 97 9525
TEST_TIME 98 9547
TEST_TIME 99 9512
TEST_TIME 100 9494
TEST_TIME 101 9589
TEST_TIME 102 9599
TEST_TIME 103 9586
TEST_TIME 104 9570
TEST_TIME 105 9509
TEST_TIME 106 9462
TEST_TIME 107 9456
TEST_TIME 108 9402
TEST_TIME 109 9380
TEST_TIME 110 9403
TEST_TIME 111 9348
TEST_TIME 112 9334
TEST_TIME 113 9332
TEST_TIME 114 9318
TEST_TIME 115 9336
TEST_TIME 116 9381
TEST_TIME 117 9379
TEST_TIME 118 9360
TEST_TIME 119 9362
TEST_TIME 120 9290

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-04-2022 16:43:31 to 08-04-2022 16:43:34.

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 1535228 1535228 0 0.00 0 21374 5 0 0
doc_list_biud 1535228 1535228 0 0.00 0 33333 27 0 0
sp_add_invoice_to_stock 5020 4920 100 1.99 30 241065 17269 0 100
sp_cancel_adding_invoice 5835 5835 0 0.00 5 51330 723 0 0
sp_cancel_client_order 1794 1794 0 0.00 4 21500 355 0 0
sp_cancel_customer_reserve 573949 573949 0 0.00 1 33334 58 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 2 7797 77 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 4643 71 0 0
sp_cancel_supplier_invoice 6461 6455 6 0.09 2 19146 731 0 6
sp_cancel_supplier_order 436 436 0 0.00 5 946210 94848 0 0
sp_cancel_write_off 10490 10490 0 0.00 1 16215 101 0 0
sp_client_order 20765 20765 0 0.00 3 46281 350 0 0
sp_customer_reserve 453659 453659 0 0.00 0 45661 172 0 0
sp_fill_shopping_cart 31650 31650 0 0.00 1 14450 37 0 0
sp_fill_shopping_cart_clo_res 453651 453651 0 0.00 0 19278 13 0 0
sp_get_clo_for_invoice 4912 4912 0 0.00 0 20420 297 0 0
sp_kill_cost_storno 17437 17437 0 0.00 0 10801 49 0 0
sp_kill_qstorno_ret_qs2qd 1165279 1165279 0 0.00 0 33330 31 0 0
sp_kill_qty_storno 613656 613656 0 0.00 0 33333 67 0 0
sp_lock_selected_doc 20477 20477 0 0.00 0 4636 3 0 0
sp_make_cost_storno 27563 27563 0 0.00 0 21362 44 0 0
sp_multiply_rows_for_pdistr 27563 27563 0 0.00 0 7931 25 0 0
sp_multiply_rows_for_qdistr 20765 20765 0 0.00 0 46273 164 0 0
sp_pay_from_customer 7056 7056 0 0.00 4 12781 96 0 0
sp_pay_to_supplier 5810 5810 0 0.00 5 11246 102 0 0
sp_payment_common 12866 12866 0 0.00 1 6853 22 0 0
sp_qd_handle_on_cancel_clo 1794 1794 0 0.00 0 21492 328 0 0
sp_qd_handle_on_invoice_upd_sts 10747 10747 0 0.00 0 30964 297 0 0
sp_qd_handle_on_reserve_upd_sts 9785 9785 0 0.00 0 18293 65 0 0
sp_reserve_write_off 9785 9785 0 0.00 6 18336 171 0 0
sp_supplier_invoice 5507 5507 0 0.00 8 101722 3516 0 0
sp_supplier_order 5409 5409 0 0.00 4 74803 1333 0 0
srv_aggregate_perf_data 110 110 0 0.00 2370 552157 18829 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1152 163415 45970 0 0
srv_find_qd_qs_mism 1067466 1067466 0 0.00 0 18048 5 0 0
srv_make_invnt_saldo 595 595 0 0.00 29 56438 1006 0 0
srv_make_money_saldo 752 752 0 0.00 0 3965 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 144989 378353 256587 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4920 4920 0 0.00 0 7846 170 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 0 6103 128 0 0
t$perf-norm:sp_cancel_client_order 1794 1794 0 0.00 0 1190 8 0 0
t$perf-norm:sp_cancel_customer_reserve 643 643 0 0.00 0 858 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 5961 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 2796 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 376 11 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 29293 1397 0 0
t$perf-norm:sp_cancel_write_off 971 971 0 0.00 0 5976 9 0 0
t$perf-norm:sp_client_order 20765 20765 0 0.00 0 18061 20 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 13335 32 0 0
t$perf-norm:sp_pay_from_customer 7056 7056 0 0.00 0 11025 16 0 0
t$perf-norm:sp_pay_to_supplier 5810 5810 0 0.00 0 13308 18 0 0
t$perf-norm:sp_reserve_write_off 9785 9785 0 0.00 0 14153 15 0 0
t$perf-norm:sp_supplier_invoice 5507 5507 0 0.00 0 5546 19 0 0
t$perf-norm:sp_supplier_order 5409 5409 0 0.00 0 8388 15 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 259 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 4 1 0 0
t$perf-norm:srv_make_invnt_saldo 595 595 0 0.00 0 3833 29 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 1717 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10024 9824 200 1.99 0 6533 20 0 200
v_all_customers 37832 37832 0 0.00 0 24 0 0 0
v_all_suppliers 30058 30058 0 0.00 0 100 1 0 0
v_all_wares 41530 41530 0 0.00 0 96 1 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 3370 13 0 0
v_cancel_client_order 3588 3588 0 0.00 0 5850 9 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 1890 5 0 0
v_cancel_customer_reserve 1286 1286 0 0.00 0 3540 9 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 1365 4 0 0
v_cancel_supplier_invoice 790 778 12 1.51 0 3114 20 0 12
v_cancel_supplier_order 872 872 0 0.00 0 6571 25 0 0
v_cancel_write_off 1942 1942 0 0.00 0 9351 20 0 0
v_max_id_clo_ord 5409 5409 0 0.00 0 300 1 0 0
v_max_id_clo_res 971 971 0 0.00 0 1653 12 0 0
v_max_id_ord_sup 5476 5476 0 0.00 0 6858 4 0 0
v_max_non_paid_invoice 1666 1666 0 0.00 0 471 2 0 0
v_max_non_paid_realizn 3722 3722 0 0.00 0 4701 5 0 0
v_min_id_clo_ord 5409 5409 0 0.00 0 4460 9 0 0
v_min_id_clo_res 971 971 0 0.00 0 5376 17 0 0
v_min_id_ord_sup 5476 5476 0 0.00 0 3250 5 0 0
v_min_non_paid_invoice 5810 5810 0 0.00 0 5041 13 0 0
v_min_non_paid_realizn 7056 7056 0 0.00 0 6851 19 0 0
v_our_firm 10366 10366 0 0.00 0 12 1 0 0
v_reserve_write_off 19570 19570 0 0.00 0 9545 9 0 0
x_lock_depdocs_on_canc_invoice 11901 11901 0 0.00 0 16405 104 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 13179 345 0 0
x_make_qstorno_1000_1200 5409 5409 0 0.00 1 74792 1258 0 0
x_make_qstorno_1200_2000 5507 5507 0 0.00 3 98854 3373 0 0
x_make_qstorno_2100_3300 453659 453659 0 0.00 0 45660 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-04-2022 16:43:35 to 08-04-2022 16:43:39.

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:12:14 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:15 17806 8192000000 9899839536 10207707136 984352256 10.727 100 93 185 1 1014931696 5699776 24879664
14:14:47 30363 8192000000 9949317408 10268405760 1029213696 11.161 100 88 176 0 1059848944 7710000 22925248
14:16:21 13889 8192000000 9973639856 10301825024 1058607888 11.443 100 85 170 0 1088893664 8918112 21367664
14:17:38 11173 8192000000 10033806544 10380025856 1117430096 12.003 100 82 165 1 1147861744 10366064 20065584
14:18:51 13965 8192000000 10083108352 10442981376 1173993072 12.534 100 77 154 1 1203752256 12174320 17584864
14:20:07 6203 8192000000 10134538016 10508382208 1222544160 12.985 100 74 149 1 1251611312 13073920 15993232
14:21:16 3823 8192000000 10170452624 10548031488 1255939360 13.293 100 84 166 1 1288679104 14289408 18450336
14:22:22 2669 8192000000 10186954000 10572369920 1280707040 13.519 100 94 189 2 1315396960 12901024 21788896
14:23:29 1861 8192000000 10212277312 10598957056 1296940624 13.667 100 88 176 0 1329367584 12306320 20120640
14:24:35 1965 8192000000 10215869328 10606997504 1307337936 13.762 100 79 158 1 1335246416 10241456 17667024
14:25:38 6255 8192000000 10202590768 10608697344 1309419712 13.781 100 41 81 3 1327142576 8741504 8981360
14:26:46 3659 8192000000 10207805840 10622427136 1321888416 13.894 100 38 76 0 1338517440 8218784 8410240
14:27:50 59292 8192000000 10236349360 10648252416 1337000064 14.030 100 35 69 1 1350625856 5258288 8367504
14:29:51 4866 8192000000 10239300288 10659385344 1341848576 14.074 100 33 66 1 1355982752 6218544 7915632
14:31:02 7300 8192000000 10245425120 10662940672 1349757312 14.145 100 30 60 3 1361797104 5222400 6817392
14:32:13 60190 8192000000 10196111008 10614452224 1257904336 13.311 95 27 53 1 1269436240 5801360 5730544
14:34:19 5972 8192000000 10190765424 10606247936 1301889216 13.712 100 25 50 0 1314833024 6839120 6104688
14:35:26 7771 8192000000 10136179040 10531991552 1208068016 12.851 97 23 46 1 1219370544 5749008 5553520
14:36:38 71947 8192000000 10149199840 10554183680 1208467760 12.855 95 24 48 0 1218905504 4461344 5976400
14:38:55 7706 8192000000 10177064896 10579546112 1274896064 13.466 100 39 78 1 1290256080 6196320 9163696
14:40:07 11259 8192000000 10180909248 10599247872 1295867136 13.658 100 28 55 1 1307870192 5026048 6977008
14:41:20 71077 8192000000 10203328304 10617729024 1305657296 13.747 100 40 79 2 1320640016 5163840 9818880
14:43:44 14826 8192000000 10252328096 10663239680 1343019536 14.085 100 59 117 0 1365037056 9222800 12794720
14:45:17 9647 8192000000 10283065056 10693664768 1362749104 14.262 100 63 125 1 1391034608 13864992 14420512
14:46:30 21696 8192000000 10276480832 10704601088 1373717040 14.360 100 34 65 2 1393490496 12623872 7149584
14:48:06 3330 8192000000 10314745440 10728632320 1387313200 14.482 100 72 142 2 1420745488 17048624 16383664
14:49:12 2456 8192000000 10300500976 10719404032 1384567824 14.457 100 63 124 3 1417257232 18258848 14430560
14:50:25 9352 8192000000 10309897440 10731855872 1375832304 14.379 99 45 88 1 1406570512 19896912 10841296
14:51:40 13413 8192000000 10316799968 10742190080 1391880672 14.523 100 51 102 1 1426174432 21330672 12963088
14:52:59 8669 8192000000 10336770720 10755203072 1406404688 14.652 100 60 119 0 1445465904 24589792 14471424
14:54:13 6105 8192000000 10323854240 10741686272 1383559376 14.448 99 64 128 3 1427306288 27517456 16229456
14:55:25 13373 8192000000 10335253056 10754011136 1386131504 14.471 98 54 108 1 1432507072 32980528 13395040
14:56:42 5227 8192000000 10360478592 10786971648 1415105856 14.729 100 60 120 2 1467974128 37525520 15342752
14:57:53 7732 8192000000 10374855936 10793709568 1410998800 14.693 100 65 128 1 1472955200 45193088 16763312
14:59:12 1396 8192000000 10378796976 10790465536 1405243984 14.642 100 88 173 1 1481108864 53018480 22846400
15:00:17 11503 8192000000 10333778592 10766499840 1397000224 14.568 100 47 94 1 1446320832 37466048 11854560
15:01:33 14466 8192000000 10326788720 10757009408 1369286960 14.321 98 26 52 1 1412126928 35632016 7207952
15:02:57 5707 8192000000 10326972640 10748715008 1366279520 14.294 99 51 99 0 1423858416 44815264 12763632
15:04:06 14650 8192000000 10323140400 10743771136 1327579824 13.945 96 34 68 1 1387028080 49582384 9865872
15:05:54 12107 8192000000 10327247616 10750541824 1364923536 14.282 99 37 74 2 1428525632 53623440 9978656
15:07:11 16480 8192000000 10330507312 10757685248 1373630496 14.360 100 31 62 0 1433004576 50736160 8637920
15:09:09 34230 8192000000 10298199584 10736812032 1326516784 13.936 99 15 30 2 1390651232 59393808 4740640
15:10:46 38699 8192000000 10244399024 10681884672 1331737248 13.983 99 7 14 0 1347340144 13764128 1838768
15:12:25 76442 8192000000 10267038960 10693955584 1323386816 13.907 98 41 79 1 1343354672 10430496 9537360
15:14:42 110186 8192000000 10253224512 10708307968 1340521648 14.062 99 20 40 1 1355751712 10860672 4369392
15:17:32 45355 8192000000 10258638944 10709049344 1347727632 14.127 99 13 26 0 1357312944 6925424 2659888
15:19:18 67677 8192000000 10285500880 10734825472 1374064928 14.363 100 22 45 0 1393246896 13937344 5244624
15:21:27 77861 8192000000 10292786160 10747957248 1385820064 14.469 100 25 50 1 1412905664 20400752 6684848
15:23:45 1152 8192000000 10296732096 10732785664 1379456576 14.412 100 67 136 3 1403869072 8989920 15422576
15:24:47 46549 8192000000 10277659968 10731331584 1366863648 14.299 99 25 49 0 1383560832 10860880 5836304
15:26:34 56350 8192000000 10282142704 10740674560 1385456288 14.465 100 22 45 0 1402820768 11754752 5609728
15:28:35 8126 8192000000 10311468272 10750410752 1392753120 14.530 100 53 106 0 1417079696 11887824 12438752
15:29:45 55401 8192000000 10301849040 10762043392 1397109440 14.569 100 10 20 0 1410467424 11118368 2239616
15:32:41 33564 8192000000 10305189504 10767790080 1404070704 14.631 100 32 63 0 1419695328 8481968 7142656
15:34:21 24305 8192000000 10299803584 10759606272 1401804304 14.611 100 26 52 0 1415231360 7856752 5570304
15:35:46 58960 8192000000 10306573856 10774765568 1410298032 14.687 100 19 38 0 1425369360 10732576 4338752
15:37:45 47992 8192000000 10317207600 10776055808 1412432176 14.706 100 22 44 0 1431371632 13989664 4949792
15:39:42 41371 8192000000 10304454288 10763120640 1387150544 14.480 99 17 34 0 1404607520 13491440 3965536
15:41:26 163415 8192000000 10321287696 10782015488 1333465936 13.998 94 13 26 1 1355309760 18283728 3560096
15:45:19 110466 8192000000 10258830080 10696138752 1327002144 13.940 95 23 46 0 1343312064 10903888 5406032
15:48:12 79421 8192000000 10270292048 10738147328 1361365712 14.250 98 14 28 0 1377510096 12214448 3929936
15:50:32 65400 8192000000 10259674656 10719395840 1305794240 13.748 95 10 20 0 1320585552 11912672 2878640
15:52:41 58845 8192000000 10270501232 10717896704 1312569856 13.809 95 15 30 0 1331223472 14974672 3678944
15:54:40 86834 8192000000 10252173504 10717687808 1320529040 13.881 96 13 26 0 1345353888 21151408 3673440
15:57:09 34859 8192000000 10275680400 10715844608 1333912896 14.002 97 42 85 0 1353256688 9875632 9468160
15:58:47 117096 8192000000 10255866352 10720018432 1272555248 13.445 94 15 30 0 1293327264 17085408 3686608
16:01:46 42444 8192000000 10243564784 10673815552 1239725744 13.144 92 25 50 1 1254508096 9061520 5720832
16:03:30 8817 8192000000 10251257824 10677948416 1331147600 13.978 100 43 86 1 1355057888 14605632 9304656
16:04:41 69703 8192000000 10233714800 10674335744 1318759104 13.865 99 28 55 1 1333740320 8595904 6385312
16:07:01 35894 8192000000 10231897072 10680139776 1323503024 13.908 99 21 42 0 1339922208 11681680 4737504
16:08:41 49397 8192000000 10257803184 10709430272 1355984496 14.201 100 8 16 0 1367980768 9917728 2078544
16:11:05 69810 8192000000 10255158048 10714750976 1350731360 14.154 99 14 28 0 1371439680 17607648 3100672
16:13:21 46940 8192000000 10280556208 10735013888 1369395328 14.322 99 19 38 0 1381331904 7873408 4063168
16:15:10 69986 8192000000 10313381680 10773725184 1406001248 14.648 100 33 66 3 1424880608 10887984 7991376
16:17:21 90084 8192000000 10313324688 10775629824 1409352848 14.678 100 12 24 0 1424520544 12391104 2776592
16:19:52 60821 8192000000 10296024784 10756280320 1373576944 14.359 98 7 14 0 1384887904 9567376 1743584
16:21:53 43201 8192000000 10334829232 10781372416 1407355856 14.660 100 24 48 1 1427206144 14494576 5355712
16:23:52 110989 8192000000 10325082000 10778071040 1386276928 14.473 98 21 42 2 1405860608 14205504 5378176
16:26:51 118689 8192000000 10318332016 10772701184 1387342704 14.482 98 38 76 0 1407837088 11971760 8522624
16:29:51 76989 8192000000 10306597936 10771165184 1389892224 14.505 98 12 24 0 1405564688 12496720 3175744
16:32:08 42921 8192000000 10313379968 10785976320 1409002032 14.675 100 15 28 0 1427547728 14902816 3642880
16:33:52 47890 8192000000 10289006192 10750496768 1384120048 14.453 98 15 31 2 1404133808 16507632 3506128
16:35:41 56668 8192000000 10300650576 10762981376 1367759392 14.307 98 21 42 2 1382153696 9597344 4796960
16:37:46 57867 8192000000 10260186256 10726535168 1317089168 13.850 96 15 31 0 1341660016 20715056 3855792
16:39:45 28690 8192000000 10278779824 10722004992 1327669808 13.946 96 38 77 0 1346522304 9589248 9263248
16:41:19 56453 8192000000 9774417232 10151182336 874625552 9.646 65 9 16 1 887213408 10518000 2069856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-04-2022 16:43:41 to 08-04-2022 16:43:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-04-2022 16:43:56 to 08-04-2022 16:43:58.

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          234070
MON$OLDEST_ACTIVE               237349
MON$OLDEST_SNAPSHOT             237349
MON$NEXT_TRANSACTION            237349
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                       2018560
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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Apr  8 16:42:20 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Apr  8 16:42:28 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Apr  8 16:42:31 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Fri Apr  8 16:42:31 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Fri Apr  8 16:42:31 2022
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:25: 
--- end of diff output ---

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

08.04.22 16:43:59. 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-08 16:44:02 No severe PSQL-related problems occured
08.04.22 16:44:03. End of report.