Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.02.2023 01:40
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 15400448 kB
MemAvailable 30429252 kB
Buffers 0 kB
Cached 16332668 kB
SwapCached 3348 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.11
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 2023-02-16 01:38:41
DTS_END                         2023-02-16 01:38:41.7580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/32898
TRN_ID                          242613
ATT_ID                          663
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: 9431.60 20598 1131792
customer order: creation 177.99 366 21359
customer order: refuse 15.17 336 1820
order to supplier: creation 47.19 1271 5663
order to supplier: removal 3.38 98007 405
invoice (draft): creation 48.23 3380 5788
invoice (draft): removal 52.40 721 6288
invoice accept: apply 43.80 16759 5256
invoice accept: cancel 47.10 742 5652
customer reserve: creation 4079.03 165 489484
customer reserve: removal 4616.91 57 554029
realization accept: apply 88.31 176 10597
realization accept: cancel 79.13 105 9496
payment from customer: creation 61.11 105 7333
payment from customer: removal 6.02 96 722
payment to supplier: creation 50.25 130 6030
payment to supplier: removal 3.50 93 420
service: total inventory turnovers 5.03 998 603
service: total monetary turnovers 6.20 38 744
service: aggregate perf. data 0.83 19885 99
service: refresh index statistics 0.03 268532 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-02-2023 01:40:24 to 16-02-2023 01:40:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4533
TEST_TIME 2 4098
TEST_TIME 3 4349
TEST_TIME 4 4434
TEST_TIME 5 4938
TEST_TIME 6 5751
TEST_TIME 7 5591
TEST_TIME 8 5569
TEST_TIME 9 5875
TEST_TIME 10 6151
TEST_TIME 11 6503
TEST_TIME 12 6240
TEST_TIME 13 6417
TEST_TIME 14 6606
TEST_TIME 15 6743
TEST_TIME 16 6810
TEST_TIME 17 7113
TEST_TIME 18 7334
TEST_TIME 19 7699
TEST_TIME 20 7698
TEST_TIME 21 7801
TEST_TIME 22 8094
TEST_TIME 23 8570
TEST_TIME 24 9067
TEST_TIME 25 9074
TEST_TIME 26 9514
TEST_TIME 27 9876
TEST_TIME 28 10420
TEST_TIME 29 10706
TEST_TIME 30 10571
TEST_TIME 31 10269
TEST_TIME 32 10159
TEST_TIME 33 10190
TEST_TIME 34 10199
TEST_TIME 35 10028
TEST_TIME 36 9795
TEST_TIME 37 9957
TEST_TIME 38 9868
TEST_TIME 39 9811
TEST_TIME 40 10056
TEST_TIME 41 10046
TEST_TIME 42 9934
TEST_TIME 43 9840
TEST_TIME 44 9768
TEST_TIME 45 9755
TEST_TIME 46 9636
TEST_TIME 47 9704
TEST_TIME 48 9665
TEST_TIME 49 9625
TEST_TIME 50 9569
TEST_TIME 51 9549
TEST_TIME 52 9477
TEST_TIME 53 9417
TEST_TIME 54 9451
TEST_TIME 55 9434
TEST_TIME 56 9449
TEST_TIME 57 9450
TEST_TIME 58 9616
TEST_TIME 59 9589
TEST_TIME 60 9649
TEST_TIME 61 9716
TEST_TIME 62 9746
TEST_TIME 63 9799
TEST_TIME 64 9733
TEST_TIME 65 9897
TEST_TIME 66 9897
TEST_TIME 67 9923
TEST_TIME 68 9911
TEST_TIME 69 9813
TEST_TIME 70 9723
TEST_TIME 71 9694
TEST_TIME 72 9754
TEST_TIME 73 9679
TEST_TIME 74 9688
TEST_TIME 75 9640
TEST_TIME 76 9549
TEST_TIME 77 9567
TEST_TIME 78 9547
TEST_TIME 79 9554
TEST_TIME 80 9554
TEST_TIME 81 9656
TEST_TIME 82 9668
TEST_TIME 83 9700
TEST_TIME 84 9745
TEST_TIME 85 9740
TEST_TIME 86 9683
TEST_TIME 87 9748
TEST_TIME 88 9778
TEST_TIME 89 9781
TEST_TIME 90 9803
TEST_TIME 91 9775
TEST_TIME 92 9704
TEST_TIME 93 9660
TEST_TIME 94 9680
TEST_TIME 95 9629
TEST_TIME 96 9599
TEST_TIME 97 9568
TEST_TIME 98 9600
TEST_TIME 99 9566
TEST_TIME 100 9614
TEST_TIME 101 9620
TEST_TIME 102 9628
TEST_TIME 103 9638
TEST_TIME 104 9601
TEST_TIME 105 9664
TEST_TIME 106 9728
TEST_TIME 107 9739
TEST_TIME 108 9738
TEST_TIME 109 9719
TEST_TIME 110 9750
TEST_TIME 111 9692
TEST_TIME 112 9659
TEST_TIME 113 9618
TEST_TIME 114 9616
TEST_TIME 115 9602
TEST_TIME 116 9566
TEST_TIME 117 9539
TEST_TIME 118 9505
TEST_TIME 119 9485
TEST_TIME 120 9438

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-02-2023 01:40:28 to 16-02-2023 01:40:31.

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 1582507 1582507 0 0.00 0 19919 6 0 0
doc_list_biud 1582507 1582507 0 0.00 0 24909 25 0 0
sp_add_invoice_to_stock 5419 5256 163 3.00 35 160115 16760 0 163
sp_cancel_adding_invoice 5652 5652 0 0.00 4 64083 742 0 0
sp_cancel_client_order 1820 1820 0 0.00 4 8979 336 0 0
sp_cancel_customer_reserve 554029 554029 0 0.00 0 24916 58 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 3 8319 96 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 2 4167 93 0 0
sp_cancel_supplier_invoice 6307 6288 19 0.30 1 15988 722 0 19
sp_cancel_supplier_order 405 405 0 0.00 5 975447 98008 0 0
sp_cancel_write_off 9496 9496 0 0.00 1 12017 106 0 0
sp_client_order 21359 21359 0 0.00 3 32863 367 0 0
sp_customer_reserve 489484 489484 0 0.00 0 100830 165 0 0
sp_fill_shopping_cart 32804 32804 0 0.00 1 14510 37 0 0
sp_fill_shopping_cart_clo_res 489482 489482 0 0.00 0 17637 13 0 0
sp_get_clo_for_invoice 5247 5247 0 0.00 0 21280 314 0 0
sp_kill_cost_storno 16290 16290 0 0.00 0 16721 52 0 0
sp_kill_qstorno_ret_qs2qd 1124247 1124247 0 0.00 0 24897 31 0 0
sp_kill_qty_storno 593533 593533 0 0.00 0 24905 67 0 0
sp_lock_selected_doc 21832 21832 0 0.00 0 8292 7 0 0
sp_make_cost_storno 29206 29206 0 0.00 0 19867 47 0 0
sp_multiply_rows_for_pdistr 29206 29206 0 0.00 0 8314 29 0 0
sp_multiply_rows_for_qdistr 21359 21359 0 0.00 0 16132 167 0 0
sp_pay_from_customer 7333 7333 0 0.00 4 14253 106 0 0
sp_pay_to_supplier 6030 6030 0 0.00 4 17601 131 0 0
sp_payment_common 13363 13363 0 0.00 1 17546 27 0 0
sp_qd_handle_on_cancel_clo 1820 1820 0 0.00 0 8973 315 0 0
sp_qd_handle_on_invoice_upd_sts 10898 10898 0 0.00 0 13599 287 0 0
sp_qd_handle_on_reserve_upd_sts 10597 10597 0 0.00 0 21514 65 0 0
sp_reserve_write_off 10597 10597 0 0.00 6 21532 177 0 0
sp_supplier_invoice 5788 5788 0 0.00 8 104974 3381 0 0
sp_supplier_order 5663 5663 0 0.00 4 97246 1271 0 0
srv_aggregate_perf_data 99 99 0 0.00 3295 238690 19885 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1652 128138 42188 0 0
srv_find_qd_qs_mism 1080771 1080771 0 0.00 0 17320 5 0 0
srv_make_invnt_saldo 603 603 0 0.00 23 64266 999 0 0
srv_make_money_saldo 744 744 0 0.00 0 10253 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 215704 334487 268532 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5256 5256 0 0.00 0 10960 171 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 1942 68 0 0
t$perf-norm:sp_cancel_client_order 1820 1820 0 0.00 0 1320 12 0 0
t$perf-norm:sp_cancel_customer_reserve 738 738 0 0.00 0 1326 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 2422 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 267 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 2328 26 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 31869 1530 0 0
t$perf-norm:sp_cancel_write_off 1044 1044 0 0.00 0 3394 12 0 0
t$perf-norm:sp_client_order 21360 21360 0 0.00 0 7128 18 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 2898 7 0 0
t$perf-norm:sp_pay_from_customer 7333 7333 0 0.00 0 5367 8 0 0
t$perf-norm:sp_pay_to_supplier 6030 6030 0 0.00 0 14891 13 0 0
t$perf-norm:sp_reserve_write_off 10597 10597 0 0.00 0 9531 15 0 0
t$perf-norm:sp_supplier_invoice 5788 5788 0 0.00 0 5973 22 0 0
t$perf-norm:sp_supplier_order 5663 5663 0 0.00 0 3382 14 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 419 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 603 603 0 0.00 0 3640 23 0 0
t$perf-norm:srv_make_money_saldo 744 744 0 0.00 0 3366 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10816 10490 326 3.01 0 7699 18 0 326
v_all_customers 38734 38734 0 0.00 0 136 0 0 0
v_all_suppliers 31576 31576 0 0.00 0 19 1 0 0
v_all_wares 42718 42718 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 302 6 0 0
v_cancel_client_order 3640 3640 0 0.00 0 2157 5 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 4465 10 0 0
v_cancel_customer_reserve 1476 1476 0 0.00 0 3902 9 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 4147 13 0 0
v_cancel_supplier_invoice 894 856 38 4.25 0 6386 28 0 38
v_cancel_supplier_order 810 810 0 0.00 0 284 3 0 0
v_cancel_write_off 2088 2088 0 0.00 0 3961 16 0 0
v_max_id_clo_ord 5663 5663 0 0.00 0 1068 1 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 2612 22 0 0
v_max_id_ord_sup 5782 5782 0 0.00 0 7282 7 0 0
v_max_non_paid_invoice 1687 1687 0 0.00 0 1707 3 0 0
v_max_non_paid_realizn 4041 4041 0 0.00 0 1172 4 0 0
v_min_id_clo_ord 5663 5663 0 0.00 0 2616 4 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 8076 25 0 0
v_min_id_ord_sup 5782 5782 0 0.00 0 5665 6 0 0
v_min_non_paid_invoice 6030 6030 0 0.00 0 17543 22 0 0
v_min_non_paid_realizn 7333 7333 0 0.00 0 5636 18 0 0
v_our_firm 10568 10568 0 0.00 0 19 1 0 0
v_reserve_write_off 21194 21194 0 0.00 0 4569 6 0 0
x_lock_depdocs_on_canc_invoice 11512 11512 0 0.00 0 26921 109 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 5121 310 0 0
x_make_qstorno_1000_1200 5663 5663 0 0.00 1 97232 1207 0 0
x_make_qstorno_1200_2000 5788 5788 0 0.00 3 104919 3234 0 0
x_make_qstorno_2100_3300 489484 489484 0 0.00 0 100829 149 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 16-02-2023 01:40:32 to 16-02-2023 01:40:37.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:45 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:46 18296 8192000000 9889147840 10198753280 970063504 10.587 100 84 168 0 998977168 5937600 22976064
23:11:11 15773 8192000000 9933193904 10253733888 1017352304 11.046 100 86 172 0 1047545344 7501696 22691344
23:12:33 17258 8192000000 9973369680 10301046784 1056038896 11.419 100 87 174 0 1086878096 9246176 21593024
23:13:57 8885 8192000000 10020063616 10362122240 1102009504 11.857 100 79 156 0 1130662912 10172496 18480912
23:15:09 8668 8192000000 10065883008 10421215232 1153149472 12.339 100 74 148 0 1181522352 11197536 17175344
23:16:21 6864 8192000000 10114493392 10480889856 1201913568 12.794 100 78 156 0 1231547584 12553856 17080160
23:17:30 2274 8192000000 10144496320 10514563072 1234377040 13.094 100 87 174 0 1266812208 13631328 18803840
23:18:35 1297 8192000000 10177354016 10554449920 1261349152 13.342 100 84 168 1 1292722784 12405344 18968288
23:19:38 2149 8192000000 10201789952 10584666112 1285754640 13.566 100 87 175 0 1319592304 14099408 19738256
23:20:42 19032 8192000000 10176717968 10588479488 1292133808 13.624 100 37 72 1 1306961056 6403888 8423360
23:22:01 6197 8192000000 10192371760 10598641664 1299034656 13.686 100 39 78 0 1315610192 7410368 9165168
23:23:10 10825 8192000000 10192187824 10609414144 1306485040 13.754 100 12 25 1 1314554032 5742592 2326400
23:24:21 39122 8192000000 10210397584 10625536000 1314962736 13.831 100 35 66 1 1327968688 4348512 8657440
23:26:04 3967 8192000000 10216451600 10633494528 1324537024 13.918 100 26 52 0 1336024944 6153776 5334144
23:27:10 7952 8192000000 10227154000 10644402176 1327060864 13.941 100 16 32 0 1339891536 9250368 3580304
23:28:18 5564 8192000000 10248263072 10657501184 1339445088 14.052 100 47 94 1 1357162912 7248864 10468960
23:29:26 11808 8192000000 10212187840 10638811136 1329676896 13.964 100 22 44 0 1340530656 5181168 5672592
23:30:38 11792 8192000000 10144084224 10545725440 1208926464 12.859 95 23 46 2 1218870496 4585840 5358192
23:31:54 14449 8192000000 10119435760 10517549056 1187543232 12.660 95 24 48 0 1198754640 5556912 5654496
23:33:11 42273 8192000000 10140650608 10547752960 1252281936 13.259 100 36 71 0 1266268352 5836256 8150160
23:34:54 5836 8192000000 10169009232 10575142912 1267479520 13.399 99 26 52 1 1278945088 5190832 6274736
23:36:01 11312 8192000000 10182418208 10593914880 1288960208 13.595 100 13 24 0 1296400592 5113088 2327296
23:37:13 10152 8192000000 10194025296 10608128000 1300072224 13.696 100 19 36 1 1314063584 9684112 4307248
23:38:28 48471 8192000000 10218078464 10630098944 1319818240 13.875 100 29 58 0 1331872672 5478848 6575584
23:40:22 8699 8192000000 10242646256 10658123776 1344453088 14.098 100 34 66 0 1359792864 7700784 7638992
23:41:42 17112 8192000000 10272119024 10687238144 1366039824 14.292 100 54 106 0 1389106016 10459328 12606864
23:43:08 14548 8192000000 10279590560 10699747328 1374574096 14.368 100 46 92 1 1397150416 11925840 10650480
23:44:31 17033 8192000000 10289323424 10714681344 1384177760 14.454 100 41 82 0 1404010576 10146912 9685904
23:45:52 4889 8192000000 10318286704 10740678656 1398006880 14.577 100 57 113 1 1428725280 17524192 13194208
23:47:01 8587 8192000000 10321855632 10746077184 1404282800 14.633 100 56 112 0 1438881024 20765440 13832784
23:48:22 2428 8192000000 10352316880 10775470080 1421296800 14.784 100 70 140 0 1461545184 22697776 17550608
23:49:30 1650 8192000000 10352801856 10769408000 1411890560 14.701 100 72 143 1 1457341696 28963568 16487568
23:50:38 11549 8192000000 10345152912 10769682432 1405233072 14.642 99 45 90 1 1442281840 25580928 11467840
23:51:54 10600 8192000000 10345450496 10771161088 1399784144 14.593 99 47 94 1 1444775072 32781616 12209312
23:53:09 1868 8192000000 10363101616 10780913664 1399193664 14.588 99 70 132 1 1452423488 36280672 16949152
23:54:21 4331 8192000000 10363472784 10779131904 1409553424 14.680 100 76 149 1 1466226832 37150976 19522432
23:55:33 2552 8192000000 10362597872 10776182784 1409547808 14.680 100 78 155 2 1467374272 38446624 19379840
23:56:39 6222 8192000000 10347740400 10767441920 1396651088 14.565 100 58 116 0 1452588928 41039936 14897904
23:57:49 3288 8192000000 10321660304 10735542272 1370498064 14.332 98 65 128 1 1427074592 39582512 16994016
23:59:03 6712 8192000000 10337455312 10758270976 1378240432 14.401 99 50 100 1 1431083872 39909872 12933568
00:00:23 3424 8192000000 10337472128 10758217728 1380639152 14.422 100 55 109 2 1442495776 47228656 14627968
00:01:30 11221 8192000000 10328949440 10769063936 1374439600 14.367 100 30 59 0 1435749872 53056368 8253904
00:02:46 24129 8192000000 10314872592 10748727296 1368408256 14.313 98 22 44 0 1407459184 33040384 6010544
00:04:13 25970 8192000000 10326032128 10769952768 1376193952 14.383 99 26 52 0 1422373824 38457248 7722624
00:05:41 37635 8192000000 10270580624 10714820608 1323670384 13.910 98 15 30 0 1355400320 27348640 4381296
00:07:44 54232 8192000000 10275608912 10706092032 1325258304 13.924 97 32 64 0 1343455680 11253264 6944112
00:09:40 80631 8192000000 10255055984 10677084160 1329609680 13.964 98 44 89 1 1348487936 8802768 10075488
00:12:03 37720 8192000000 10287541648 10725167104 1368383552 14.313 100 34 68 1 1385779312 10180224 7215536
00:13:51 113528 8192000000 10285791120 10728460288 1336243744 14.024 97 34 69 0 1357282544 11619424 9419376
00:16:52 33355 8192000000 10306957856 10739933184 1384258896 14.455 100 30 60 0 1404735616 12853296 7623424
00:18:27 31046 8192000000 10281349792 10741346304 1379474064 14.412 100 32 64 0 1406635424 19538960 7622400
00:20:06 62449 8192000000 10289823056 10739789824 1371588704 14.341 99 18 37 3 1384029984 7535552 4905728
00:22:10 44629 8192000000 10279808560 10738642944 1375288944 14.374 99 12 24 0 1387861872 9729200 2843728
00:24:00 81529 8192000000 10303204528 10758299648 1375418640 14.376 99 27 54 0 1388441552 7426784 5596128
00:26:35 31373 8192000000 10293037264 10768433152 1391229264 14.517 100 10 20 0 1409459920 16222960 2007696
00:28:24 70552 8192000000 10293072688 10768326656 1401796528 14.611 100 16 32 0 1414341712 8961600 3583584
00:31:10 64131 8192000000 10294694992 10767171584 1402847248 14.620 100 15 28 0 1415224496 9413760 2963488
00:33:18 36076 8192000000 10297009120 10755264512 1376441296 14.385 98 29 58 0 1390565904 7917584 6207024
00:34:57 29226 8192000000 10305711376 10755014656 1391842624 14.522 100 33 66 0 1409437856 10403216 7192016
00:36:29 63641 8192000000 10283852640 10749530112 1352822080 14.173 98 16 33 0 1373031408 16473136 3736192
00:38:35 47708 8192000000 10272346192 10733821952 1337475584 14.035 97 13 26 0 1359851232 19560928 2814720
00:40:23 34850 8192000000 10279492944 10724884480 1332286512 13.988 97 28 56 0 1353179840 14969024 5924304
00:41:59 67821 8192000000 10295759744 10738765824 1359814400 14.236 99 11 22 0 1375372576 13393952 2164224
00:44:15 48670 8192000000 10264206048 10718793728 1338046720 14.040 98 27 54 0 1355080608 11249456 5784432
00:46:04 112135 8192000000 10213667312 10650619904 1263091936 13.358 94 23 46 1 1279763680 10731824 5939920
00:48:57 6569 8192000000 10225693184 10659237888 1319653984 13.874 99 29 58 0 1335144752 8868784 6621984
00:50:06 38768 8192000000 10237582944 10693967872 1326267600 13.933 99 23 46 0 1351843552 20615392 4960560
00:51:47 67034 8192000000 10238163600 10693242880 1321015856 13.886 98 12 24 0 1333623936 10167184 2440896
00:54:00 96645 8192000000 10260769040 10707304448 1344615680 14.099 99 17 34 0 1360158688 11799472 3743536
00:57:58 58618 8192000000 10269507504 10710204416 1359261952 14.231 100 20 40 0 1376218560 11798480 5158128
00:59:58 25552 8192000000 10255517936 10699198464 1312257168 13.807 97 16 32 0 1323390720 7539104 3594448
01:01:43 84921 8192000000 10261314848 10700500992 1308606208 13.773 96 17 34 0 1327774080 15397024 3770848
01:04:17 52557 8192000000 10275736704 10715684864 1340801904 14.065 98 20 40 0 1356266112 11016784 4447424
01:06:14 55495 8192000000 10283561632 10735263744 1375061360 14.372 100 15 30 0 1393081216 14908848 3111008
01:08:12 68866 8192000000 10297308032 10747625472 1384266656 14.455 100 31 62 0 1405556688 14251248 7038784
01:10:30 58222 8192000000 10296036224 10738532352 1377538528 14.395 99 33 62 1 1392037584 6933296 7565760
01:12:33 48332 8192000000 10278810080 10744086528 1375124160 14.373 99 17 34 0 1386912768 8426976 3361632
01:14:24 46798 8192000000 10293083648 10756055040 1400794880 14.602 100 11 23 0 1410688272 7753968 2139424
01:16:16 52853 8192000000 10282970944 10744446976 1356777792 14.208 97 16 32 0 1370191968 9887680 3526496
01:18:09 46158 8192000000 10308909152 10766864384 1395221104 14.552 100 18 36 0 1411084816 11403952 4459760
01:20:08 89897 8192000000 10280863824 10736300032 1298994752 13.686 93 12 24 0 1317301152 15385200 2921200
01:22:38 84512 8192000000 10294170752 10748268544 1360496000 14.242 98 21 42 0 1382229136 16748032 4985104
01:25:03 43265 8192000000 10279517680 10721198080 1306596464 13.755 95 20 40 1 1324703744 13645232 4462048
01:26:52 38073 8192000000 10276034656 10720092160 1335002768 14.012 98 36 73 0 1356802016 14303696 7495552
01:28:31 128138 8192000000 10236260272 10692317184 1308065104 13.769 97 21 42 0 1325578176 12567552 4945520
01:31:45 8528 8192000000 10267879536 10706780160 1355048800 14.193 100 47 93 2 1376829440 11119184 10661456
01:33:00 46167 8192000000 10248080128 10712047616 1334434384 14.007 98 11 21 0 1350185840 13593024 2158432
01:34:47 42571 8192000000 10253157104 10711437312 1357721904 14.217 100 6 12 0 1367448224 8545568 1180752
01:36:30 77575 8192000000 10259292944 10725163008 1365677424 14.288 100 23 44 2 1380108944 9362480 5069040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-02-2023 01:40:39 to 16-02-2023 01:40:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-02-2023 01:40:55 to 16-02-2023 01:40:57.

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          242173
MON$OLDEST_ACTIVE               243294
MON$OLDEST_SNAPSHOT             243294
MON$NEXT_TRANSACTION            243294
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                       2018496
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Thu Feb 16 01:38:42 2023
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Thu Feb 16 01:38:43 2023
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Thu Feb 16 01:38:45 2023
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Thu Feb 16 01:38:45 2023
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Thu Feb 16 01:38:55 2023
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
--- end of diff output ---

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

16.02.23 01:40:58. 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
2023-02-16 01:41:01 No severe PSQL-related problems occured
16.02.23 01:41:02. End of report.