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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15638592 kB
MemAvailable 30614708 kB
Buffers 0 kB
Cached 16032248 kB
SwapCached 3716 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-10-20 01:37:55
DTS_END                         2021-10-20 01:37:55.5260
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/39294
TRN_ID                          246492
ATT_ID                          736
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: 9537.61 16548 1144513
customer order: creation 184.39 339 22127
customer order: refuse 15.97 369 1916
order to supplier: creation 48.47 1201 5816
order to supplier: removal 3.46 99693 415
invoice (draft): creation 48.05 3414 5766
invoice (draft): removal 53.10 843 6372
invoice accept: apply 44.84 17851 5381
invoice accept: cancel 48.28 848 5793
customer reserve: creation 4074.46 180 488935
customer reserve: removal 4696.74 56 563609
realization accept: apply 89.35 177 10722
realization accept: cancel 92.79 112 11135
payment from customer: creation 62.86 110 7543
payment from customer: removal 6.03 67 723
payment to supplier: creation 51.78 141 6213
payment to supplier: removal 3.84 62 461
service: total inventory turnovers 5.11 1119 613
service: total monetary turnovers 7.18 37 861
service: aggregate perf. data 0.90 19979 108
service: refresh index statistics 0.03 184352 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-10-2021 01:38:36 to 20-10-2021 01:38:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 765
TEST_TIME 2 3471
TEST_TIME 3 4866
TEST_TIME 4 5285
TEST_TIME 5 5487
TEST_TIME 6 5561
TEST_TIME 7 5651
TEST_TIME 8 5566
TEST_TIME 9 6145
TEST_TIME 10 6358
TEST_TIME 11 6703
TEST_TIME 12 7112
TEST_TIME 13 7181
TEST_TIME 14 7324
TEST_TIME 15 7253
TEST_TIME 16 7518
TEST_TIME 17 7509
TEST_TIME 18 7728
TEST_TIME 19 8011
TEST_TIME 20 7840
TEST_TIME 21 8191
TEST_TIME 22 8873
TEST_TIME 23 9244
TEST_TIME 24 9395
TEST_TIME 25 9641
TEST_TIME 26 10206
TEST_TIME 27 10020
TEST_TIME 28 10666
TEST_TIME 29 11336
TEST_TIME 30 11132
TEST_TIME 31 11185
TEST_TIME 32 10915
TEST_TIME 33 10765
TEST_TIME 34 10913
TEST_TIME 35 10813
TEST_TIME 36 10660
TEST_TIME 37 10517
TEST_TIME 38 10519
TEST_TIME 39 10453
TEST_TIME 40 10263
TEST_TIME 41 10070
TEST_TIME 42 10166
TEST_TIME 43 10118
TEST_TIME 44 10051
TEST_TIME 45 9974
TEST_TIME 46 9939
TEST_TIME 47 9938
TEST_TIME 48 9846
TEST_TIME 49 9923
TEST_TIME 50 9993
TEST_TIME 51 9936
TEST_TIME 52 9931
TEST_TIME 53 9847
TEST_TIME 54 9833
TEST_TIME 55 9797
TEST_TIME 56 9840
TEST_TIME 57 9759
TEST_TIME 58 9755
TEST_TIME 59 9634
TEST_TIME 60 9624
TEST_TIME 61 9573
TEST_TIME 62 9549
TEST_TIME 63 9546
TEST_TIME 64 9536
TEST_TIME 65 9598
TEST_TIME 66 9584
TEST_TIME 67 9645
TEST_TIME 68 9664
TEST_TIME 69 9772
TEST_TIME 70 9720
TEST_TIME 71 9790
TEST_TIME 72 9818
TEST_TIME 73 9751
TEST_TIME 74 9742
TEST_TIME 75 9916
TEST_TIME 76 9863
TEST_TIME 77 9766
TEST_TIME 78 9797
TEST_TIME 79 9711
TEST_TIME 80 9759
TEST_TIME 81 9690
TEST_TIME 82 9633
TEST_TIME 83 9582
TEST_TIME 84 9533
TEST_TIME 85 9519
TEST_TIME 86 9491
TEST_TIME 87 9510
TEST_TIME 88 9530
TEST_TIME 89 9539
TEST_TIME 90 9576
TEST_TIME 91 9605
TEST_TIME 92 9606
TEST_TIME 93 9573
TEST_TIME 94 9646
TEST_TIME 95 9622
TEST_TIME 96 9674
TEST_TIME 97 9665
TEST_TIME 98 9720
TEST_TIME 99 9699
TEST_TIME 100 9758
TEST_TIME 101 9759
TEST_TIME 102 9672
TEST_TIME 103 9634
TEST_TIME 104 9581
TEST_TIME 105 9592
TEST_TIME 106 9559
TEST_TIME 107 9522
TEST_TIME 108 9485
TEST_TIME 109 9455
TEST_TIME 110 9424
TEST_TIME 111 9382
TEST_TIME 112 9442
TEST_TIME 113 9455
TEST_TIME 114 9417
TEST_TIME 115 9447
TEST_TIME 116 9519
TEST_TIME 117 9525
TEST_TIME 118 9510
TEST_TIME 119 9515
TEST_TIME 120 9538

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-10-2021 01:38:39 to 20-10-2021 01:38:43.

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 1596937 1596937 0 0.00 0 19104 6 0 0
doc_list_biud 1596937 1596937 0 0.00 0 47607 26 0 0
sp_add_invoice_to_stock 5529 5381 148 2.67 44 180933 17852 0 148
sp_cancel_adding_invoice 5793 5793 0 0.00 5 54104 848 0 0
sp_cancel_client_order 1916 1916 0 0.00 4 31309 370 0 0
sp_cancel_customer_reserve 563609 563609 0 0.00 1 29742 57 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 3 3921 67 0 0
sp_cancel_pay_to_supplier 461 461 0 0.00 3 2423 63 0 0
sp_cancel_supplier_invoice 6385 6372 13 0.20 2 47617 844 0 13
sp_cancel_supplier_order 415 415 0 0.00 8 1156285 99694 0 0
sp_cancel_write_off 11135 11135 0 0.00 1 37447 112 0 0
sp_client_order 22127 22127 0 0.00 3 31579 340 0 0
sp_customer_reserve 488935 488935 0 0.00 0 38255 180 0 0
sp_fill_shopping_cart 33689 33689 0 0.00 1 18729 38 0 0
sp_fill_shopping_cart_clo_res 488934 488934 0 0.00 0 26883 12 0 0
sp_get_clo_for_invoice 5379 5379 0 0.00 0 22027 271 0 0
sp_kill_cost_storno 18112 18112 0 0.00 0 9193 55 0 0
sp_kill_qstorno_ret_qs2qd 1145140 1145140 0 0.00 0 47602 31 0 0
sp_kill_qty_storno 605338 605338 0 0.00 0 47605 68 0 0
sp_lock_selected_doc 22319 22319 0 0.00 0 10497 5 0 0
sp_make_cost_storno 29854 29854 0 0.00 0 19093 49 0 0
sp_multiply_rows_for_pdistr 29854 29854 0 0.00 0 8734 30 0 0
sp_multiply_rows_for_qdistr 22127 22127 0 0.00 0 16408 165 0 0
sp_pay_from_customer 7543 7543 0 0.00 4 16713 111 0 0
sp_pay_to_supplier 6213 6213 0 0.00 4 11130 142 0 0
sp_payment_common 13756 13756 0 0.00 1 9620 21 0 0
sp_qd_handle_on_cancel_clo 1916 1916 0 0.00 0 29807 330 0 0
sp_qd_handle_on_invoice_upd_sts 11169 11169 0 0.00 0 33368 343 0 0
sp_qd_handle_on_reserve_upd_sts 10722 10722 0 0.00 0 24314 65 0 0
sp_reserve_write_off 10722 10722 0 0.00 6 24344 178 0 0
sp_supplier_invoice 5766 5766 0 0.00 6 112865 3415 0 0
sp_supplier_order 5816 5816 0 0.00 5 30944 1201 0 0
srv_aggregate_perf_data 108 108 0 0.00 2587 324133 19980 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1257 149041 44554 0 0
srv_find_qd_qs_mism 1093677 1093677 0 0.00 0 10519 5 0 0
srv_make_invnt_saldo 613 613 0 0.00 35 56258 1120 0 0
srv_make_money_saldo 861 861 0 0.00 1 5552 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 144647 220014 184352 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5381 5381 0 0.00 0 8397 170 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 4945 122 0 0
t$perf-norm:sp_cancel_client_order 1916 1916 0 0.00 0 5607 15 0 0
t$perf-norm:sp_cancel_customer_reserve 749 749 0 0.00 0 738 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 518 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 461 461 0 0.00 0 2144 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 657 12 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 14897 1347 0 0
t$perf-norm:sp_cancel_write_off 1098 1098 0 0.00 0 864 5 0 0
t$perf-norm:sp_client_order 22127 22127 0 0.00 0 8823 17 0 0
t$perf-norm:sp_customer_reserve 1080 1080 0 0.00 0 1558 5 0 0
t$perf-norm:sp_pay_from_customer 7543 7543 0 0.00 0 4799 8 0 0
t$perf-norm:sp_pay_to_supplier 6213 6213 0 0.00 0 2845 8 0 0
t$perf-norm:sp_reserve_write_off 10722 10722 0 0.00 0 7914 12 0 0
t$perf-norm:sp_supplier_invoice 5766 5766 0 0.00 0 5844 19 0 0
t$perf-norm:sp_supplier_order 5816 5816 0 0.00 0 5574 17 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 265 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 610 11 0 0
t$perf-norm:srv_make_invnt_saldo 613 613 0 0.00 0 2806 20 0 0
t$perf-norm:srv_make_money_saldo 861 861 0 0.00 0 2429 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 11046 10750 296 2.67 0 14046 19 0 296
v_all_customers 40158 40158 0 0.00 0 214 1 0 0
v_all_suppliers 31866 31866 0 0.00 0 21 1 0 0
v_all_wares 44252 44252 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 5841 15 0 0
v_cancel_client_order 3832 3832 0 0.00 0 4915 8 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 1496 4 0 0
v_cancel_customer_reserve 1498 1498 0 0.00 0 3014 6 0 0
v_cancel_payment_to_supplier 922 922 0 0.00 0 2323 7 0 0
v_cancel_supplier_invoice 878 852 26 2.96 0 2428 19 0 26
v_cancel_supplier_order 830 830 0 0.00 0 4861 10 0 0
v_cancel_write_off 2196 2196 0 0.00 0 2426 16 0 0
v_max_id_clo_ord 5816 5816 0 0.00 0 712 1 0 0
v_max_id_clo_res 1080 1080 0 0.00 0 5669 17 0 0
v_max_id_ord_sup 5746 5746 0 0.00 0 7018 5 0 0
v_max_non_paid_invoice 1842 1842 0 0.00 0 3158 4 0 0
v_max_non_paid_realizn 4144 4144 0 0.00 0 4046 7 0 0
v_min_id_clo_ord 5816 5816 0 0.00 0 5795 7 0 0
v_min_id_clo_res 1080 1080 0 0.00 0 1487 6 0 0
v_min_id_ord_sup 5746 5746 0 0.00 0 6199 6 0 0
v_min_non_paid_invoice 6213 6213 0 0.00 0 9618 14 0 0
v_min_non_paid_realizn 7543 7543 0 0.00 0 6293 13 0 0
v_our_firm 10892 10892 0 0.00 0 19 1 0 0
v_reserve_write_off 21444 21444 0 0.00 0 6299 7 0 0
x_lock_depdocs_on_canc_invoice 11739 11739 0 0.00 0 20280 122 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 32307 483 0 0
x_make_qstorno_1000_1200 5816 5816 0 0.00 1 30934 1118 0 0
x_make_qstorno_1200_2000 5766 5766 0 0.00 3 106644 3273 0 0
x_make_qstorno_2100_3300 488935 488935 0 0.00 0 33446 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-10-2021 01:38:44 to 20-10-2021 01:38:48.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:07:59 3 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:00 26821 8192000000 9906106272 10218438656 987577136 10.758 100 85 170 2 1016368304 6544048 22247120
23:10:33 23517 8192000000 9953767440 10278387712 1033200528 11.199 100 89 178 0 1065252576 8689696 23362352
23:11:59 14368 8192000000 9977437280 10309881856 1059822640 11.455 100 84 168 0 1091278608 10127344 21328624
23:13:16 13145 8192000000 10025205696 10371846144 1113151616 11.962 100 81 162 0 1144314208 11954160 19208432
23:14:32 7012 8192000000 10077743024 10436288512 1162646880 12.428 100 83 163 1 1194366848 12939792 18780176
23:15:43 5263 8192000000 10119264240 10483417088 1202008672 12.795 100 84 166 1 1234867552 13835008 19023872
23:16:50 2970 8192000000 10146239520 10518859776 1237185760 13.120 100 77 152 2 1266426288 12538720 16701808
23:17:55 1323 8192000000 10187325840 10564968448 1267886512 13.402 100 95 188 1 1302052576 13394032 20772032
23:18:58 1362 8192000000 10202439376 10584768512 1285775104 13.566 100 93 187 0 1320136912 13292848 21068960
23:20:00 1149 8192000000 10198764800 10584137728 1287471120 13.581 100 90 184 1 1315740560 7294448 20974992
23:21:03 4155 8192000000 10188911392 10597720064 1299096816 13.687 100 41 82 2 1315806192 7301376 9408000
23:22:09 8370 8192000000 10191693568 10606759936 1306674256 13.756 100 15 30 1 1315660368 5009856 3976256
23:23:20 10499 8192000000 10203872688 10627137536 1323341648 13.907 100 15 30 3 1332211664 5751392 3118624
23:24:31 46236 8192000000 10231834320 10636808192 1322963840 13.904 100 69 138 5 1345457088 5233216 17260032
23:26:18 9309 8192000000 10223983632 10642571264 1330254464 13.969 100 32 65 1 1344511776 6702416 7554896
23:27:28 7336 8192000000 10220414784 10640912384 1319810752 13.875 99 22 44 0 1330833760 5973360 5049648
23:28:36 17319 8192000000 10188592064 10607894528 1267593024 13.400 96 18 36 0 1277318144 5206896 4518224
23:29:54 5827 8192000000 10163881360 10575880192 1261301936 13.342 98 25 50 1 1273563456 5283344 6978176
23:31:00 7214 8192000000 10130174768 10528251904 1206493952 12.837 96 17 35 1 1215476544 5041392 3941200
23:32:07 62116 8192000000 10129748208 10535067648 1214736384 12.913 96 22 42 1 1229694384 9575440 5382560
23:34:12 4485 8192000000 10160901392 10564337664 1268208352 13.405 99 24 46 2 1279536080 5206192 6121536
23:35:17 6610 8192000000 10187951440 10599743488 1296562960 13.664 100 18 36 0 1305725328 5184048 3978320
23:36:24 8258 8192000000 10200849984 10612080640 1305261904 13.743 100 18 36 0 1319124368 9192736 4669728
23:37:36 85872 8192000000 10226396112 10632941568 1317538256 13.854 99 31 62 2 1330448800 5839104 7071440
23:40:10 11268 8192000000 10260982656 10675249152 1351818528 14.164 100 53 106 1 1374769408 11259552 11691328
23:41:24 16848 8192000000 10282111024 10694926336 1367847632 14.308 100 62 124 0 1394501968 12247376 14406960
23:42:50 12327 8192000000 10298223616 10719637504 1385236320 14.463 100 55 106 2 1412650720 15325056 12089344
23:44:05 24880 8192000000 10313011440 10741002240 1397491392 14.573 100 55 110 0 1430205168 19858944 12854832
23:45:36 7760 8192000000 10330701648 10755756032 1408425616 14.670 100 50 100 1 1445608192 25589136 11593440
23:46:48 4472 8192000000 10337079488 10764218368 1415089280 14.729 100 54 107 0 1454343536 26594880 12659376
23:47:56 1257 8192000000 10355346848 10771148800 1410174464 14.685 100 86 170 0 1460964480 30369776 20420240
23:49:04 2007 8192000000 10352155232 10761191424 1402086400 14.614 99 78 155 1 1452245680 30772448 19386832
23:50:09 7101 8192000000 10343823552 10759835648 1399196880 14.588 100 73 146 0 1447347440 30453392 17697168
23:51:21 2657 8192000000 10318052208 10720055296 1349382160 14.142 98 83 163 1 1405271392 35794752 20094480
23:52:28 5446 8192000000 10333774688 10741858304 1381620032 14.431 100 72 144 1 1438117568 38868144 17629392
23:53:51 1785 8192000000 10347076528 10751877120 1385376224 14.465 100 82 164 2 1447982560 42134064 20472272
23:54:58 6322 8192000000 10313180608 10711678976 1353659232 14.180 99 67 135 2 1416006672 44866096 17481344
23:56:15 2430 8192000000 10352250624 10756546560 1386777616 14.477 100 71 139 1 1454444320 49694416 17972288
23:57:22 4845 8192000000 10358123904 10772193280 1391231680 14.517 100 77 154 3 1464692032 54623728 18836624
23:58:37 11092 8192000000 10367170176 10789601280 1394907680 14.550 100 49 96 2 1477460320 68992496 13560144
23:59:54 10325 8192000000 10357390032 10777923584 1382913360 14.443 99 36 72 1 1452265440 59146736 10205344
00:01:10 16403 8192000000 10354388208 10795864064 1404968192 14.639 100 34 68 1 1470739808 55469824 10301792
00:02:31 12031 8192000000 10341879360 10776784896 1384329856 14.455 99 36 72 2 1439603840 45488240 9785744
00:03:51 52286 8192000000 10322067696 10760486912 1377692912 14.396 100 31 62 0 1427686224 41398912 8594400
00:05:44 29976 8192000000 10298483680 10760593408 1366983072 14.300 99 16 32 2 1405442160 33972544 4486544
00:08:23 58836 8192000000 10303741312 10759663616 1371784016 14.343 99 11 22 0 1387335056 12902144 2648896
00:10:26 90088 8192000000 10307830192 10772717568 1393517504 14.537 100 16 32 0 1413646864 16638992 3490368
00:13:49 20709 8192000000 10326329776 10762100736 1397145472 14.570 100 41 77 2 1416118064 9554608 9417984
00:15:11 30122 8192000000 10312278320 10753253376 1395704768 14.557 100 41 82 0 1416273776 11531280 9037728
00:16:42 66298 8192000000 10307719056 10748452864 1361963696 14.255 98 23 44 1 1380661616 13869984 4827936
00:19:12 95338 8192000000 10300324016 10757816320 1381029648 14.426 99 30 60 1 1402475728 13988496 7457584
00:21:52 80518 8192000000 10304892496 10761293824 1368503408 14.314 98 17 34 1 1392604080 19996656 4104016
00:24:14 34408 8192000000 10293598400 10744786944 1363464912 14.268 98 19 37 2 1377511232 9819312 4227008
00:25:52 78191 8192000000 10306553424 10774867968 1376767776 14.388 98 40 80 3 1401897120 16069104 9060240
00:28:25 43473 8192000000 10300617584 10767450112 1395516896 14.555 100 10 20 0 1419829824 22047648 2265280
00:30:10 34964 8192000000 10299180384 10764251136 1395793488 14.558 100 10 20 0 1411595904 13209856 2592560
00:32:25 87839 8192000000 10270726800 10733260800 1335078896 14.013 96 28 56 0 1349825264 8807376 5938992
00:35:02 37184 8192000000 10273940624 10748432384 1380435872 14.420 100 11 22 0 1397000080 14078976 2485232
00:36:40 55819 8192000000 10266484288 10735325184 1356209008 14.203 98 13 26 1 1370832496 11780912 2842576
00:38:49 48494 8192000000 10261450368 10718126080 1335693744 14.019 97 10 18 2 1346802800 9391616 1717440
00:40:38 63727 8192000000 10274795488 10733248512 1337589920 14.036 97 17 34 1 1354638400 13273152 3775328
00:42:45 8033 8192000000 10281813712 10722385920 1360382528 14.241 99 42 83 1 1376349680 6008832 9958320
00:44:00 95516 8192000000 10262339648 10719862784 1284283776 13.552 94 19 38 2 1300188640 11056464 4848400
00:46:44 75944 8192000000 10289910624 10726129664 1319387792 13.871 97 23 46 1 1336648448 12363216 4897440
00:49:07 69290 8192000000 10270320144 10705215488 1306981552 13.759 96 15 31 1 1323774112 13900400 2892160
00:51:17 36013 8192000000 10288445216 10733268992 1350261328 14.150 99 36 72 0 1382812336 24094976 8456032
00:53:04 144641 8192000000 10247590752 10701180928 1323797072 13.911 97 29 58 0 1341918368 11629952 6491344
00:56:29 37044 8192000000 10277829648 10736308224 1366485888 14.296 100 25 50 0 1390976288 19132560 5357840
00:58:14 39175 8192000000 10268148800 10736365568 1373319968 14.357 100 4 8 0 1381642176 7535664 786544
00:59:53 42186 8192000000 10289659264 10752311296 1392436800 14.528 100 16 32 0 1407286912 10532608 4317504
01:01:44 61417 8192000000 10299663728 10775928832 1398795904 14.584 100 9 18 0 1417955168 16864208 2295056
01:03:46 71506 8192000000 10299425424 10770649088 1381959280 14.434 99 17 34 0 1402383600 16215152 4209168
01:06:02 64599 8192000000 10301768512 10764115968 1383659408 14.449 99 12 24 0 1397696752 10889360 3147984
01:08:07 54050 8192000000 10285143872 10743906304 1315878384 13.839 94 17 34 1 1327137568 7708432 3550752
01:10:12 92601 8192000000 10302876704 10769137664 1369141568 14.319 98 20 40 1 1386746880 12790784 4814528
01:12:45 22300 8192000000 10319616112 10772881408 1403792528 14.629 100 36 70 1 1423018992 11327040 7899424
01:14:13 24847 8192000000 10324610544 10774151168 1397524304 14.573 99 42 84 2 1418540288 11718000 9297984
01:15:40 97543 8192000000 10308592000 10766733312 1352587808 14.171 96 19 38 1 1371131984 14181936 4362240
01:18:21 149041 8192000000 10266765296 10720456704 1300304656 13.698 93 20 40 2 1315887904 11111088 4472160
01:21:57 18382 8192000000 10271712128 10720882688 1361676864 14.252 99 44 85 1 1382187296 9323712 11186720
01:23:23 49962 8192000000 10257022064 10726731776 1364069552 14.274 100 5 10 0 1375237824 10139376 1028896
01:25:13 54345 8192000000 10280353712 10738495488 1372532656 14.350 99 21 40 0 1387025712 9926640 4566416
01:27:08 62107 8192000000 10250234432 10713223168 1322971600 13.904 96 9 18 0 1340041488 15244464 1825424
01:29:20 70277 8192000000 10302609632 10754277376 1372173312 14.347 99 15 31 0 1390314608 14777520 3363776
01:31:33 50351 8192000000 10280749936 10731651072 1323849072 13.912 96 17 34 0 1344365440 16395024 4121344
01:33:28 79100 8192000000 10260508608 10712969216 1257623328 13.308 92 15 30 1 1273537712 12441680 3472704
01:35:48 13848 8192000000 10287700128 10726924288 1351017104 14.157 99 41 82 1 1373626912 13716480 8893328
01:37:06 56473 8192000000 9061594624 9280368640 217475168 2.586 16 4 7 0 219679072 1559184 644720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-10-2021 01:38:50 to 20-10-2021 01:39:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-10-2021 01:39:05 to 20-10-2021 01:39:07.

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          246799
MON$OLDEST_ACTIVE               246800
MON$OLDEST_SNAPSHOT             246800
MON$NEXT_TRANSACTION            246800
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                       2056032
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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'

20.10.21 01:39:08. 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-10-20 01:39:11 No severe PSQL-related problems occured
20.10.21 01:39:12. End of report.