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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16703392 kB
MemAvailable 31639464 kB
Buffers 0 kB
Cached 15000516 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-16 01:37:55
DTS_END                         2021-11-16 01:37:55.1210
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/52354
TRN_ID                          239379
ATT_ID                          720
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: 9495.10 18862 1139412
customer order: creation 178.63 341 21435
customer order: refuse 15.50 359 1860
order to supplier: creation 47.13 1412 5655
order to supplier: removal 3.56 98578 427
invoice (draft): creation 47.73 3675 5728
invoice (draft): removal 54.93 799 6592
invoice accept: apply 42.95 16179 5154
invoice accept: cancel 49.93 838 5992
customer reserve: creation 3924.74 161 470969
customer reserve: removal 4820.08 56 578410
realization accept: apply 83.98 174 10077
realization accept: cancel 92.90 107 11148
payment from customer: creation 61.77 129 7412
payment from customer: removal 6.03 72 723
payment to supplier: creation 50.50 157 6060
payment to supplier: removal 3.38 80 406
service: total inventory turnovers 4.28 1133 514
service: total monetary turnovers 6.22 22 746
service: aggregate perf. data 0.83 19919 100
service: refresh index statistics 0.03 233053 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-11-2021 01:40:00 to 16-11-2021 01:40:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1763
TEST_TIME 2 2532
TEST_TIME 3 3484
TEST_TIME 4 4107
TEST_TIME 5 3695
TEST_TIME 6 4033
TEST_TIME 7 3918
TEST_TIME 8 4642
TEST_TIME 9 5083
TEST_TIME 10 5558
TEST_TIME 11 5633
TEST_TIME 12 5953
TEST_TIME 13 6293
TEST_TIME 14 6519
TEST_TIME 15 7021
TEST_TIME 16 7335
TEST_TIME 17 7275
TEST_TIME 18 7855
TEST_TIME 19 7824
TEST_TIME 20 8381
TEST_TIME 21 8410
TEST_TIME 22 8449
TEST_TIME 23 8612
TEST_TIME 24 8637
TEST_TIME 25 9012
TEST_TIME 26 9402
TEST_TIME 27 9680
TEST_TIME 28 10021
TEST_TIME 29 10617
TEST_TIME 30 10811
TEST_TIME 31 10582
TEST_TIME 32 10549
TEST_TIME 33 10398
TEST_TIME 34 10368
TEST_TIME 35 10396
TEST_TIME 36 10180
TEST_TIME 37 10219
TEST_TIME 38 10114
TEST_TIME 39 10048
TEST_TIME 40 9957
TEST_TIME 41 10084
TEST_TIME 42 9911
TEST_TIME 43 9853
TEST_TIME 44 9957
TEST_TIME 45 9937
TEST_TIME 46 9968
TEST_TIME 47 10072
TEST_TIME 48 9975
TEST_TIME 49 9938
TEST_TIME 50 9850
TEST_TIME 51 9756
TEST_TIME 52 9820
TEST_TIME 53 9824
TEST_TIME 54 9783
TEST_TIME 55 9862
TEST_TIME 56 9804
TEST_TIME 57 9825
TEST_TIME 58 9822
TEST_TIME 59 9805
TEST_TIME 60 9761
TEST_TIME 61 9753
TEST_TIME 62 9702
TEST_TIME 63 10040
TEST_TIME 64 10042
TEST_TIME 65 10099
TEST_TIME 66 10142
TEST_TIME 67 10082
TEST_TIME 68 10112
TEST_TIME 69 10000
TEST_TIME 70 9965
TEST_TIME 71 9908
TEST_TIME 72 9831
TEST_TIME 73 9809
TEST_TIME 74 9797
TEST_TIME 75 9735
TEST_TIME 76 9682
TEST_TIME 77 9698
TEST_TIME 78 9657
TEST_TIME 79 9627
TEST_TIME 80 9609
TEST_TIME 81 9574
TEST_TIME 82 9595
TEST_TIME 83 9579
TEST_TIME 84 9609
TEST_TIME 85 9636
TEST_TIME 86 9604
TEST_TIME 87 9627
TEST_TIME 88 9653
TEST_TIME 89 9615
TEST_TIME 90 9699
TEST_TIME 91 9697
TEST_TIME 92 9734
TEST_TIME 93 9670
TEST_TIME 94 9614
TEST_TIME 95 9589
TEST_TIME 96 9520
TEST_TIME 97 9532
TEST_TIME 98 9481
TEST_TIME 99 9477
TEST_TIME 100 9418
TEST_TIME 101 9426
TEST_TIME 102 9454
TEST_TIME 103 9437
TEST_TIME 104 9465
TEST_TIME 105 9440
TEST_TIME 106 9472
TEST_TIME 107 9476
TEST_TIME 108 9499
TEST_TIME 109 9541
TEST_TIME 110 9588
TEST_TIME 111 9605
TEST_TIME 112 9569
TEST_TIME 113 9622
TEST_TIME 114 9650
TEST_TIME 115 9630
TEST_TIME 116 9557
TEST_TIME 117 9538
TEST_TIME 118 9537
TEST_TIME 119 9536
TEST_TIME 120 9503

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-11-2021 01:40:04 to 16-11-2021 01:40:07.

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 1575620 1575620 0 0.00 0 29762 6 0 0
doc_list_biud 1575620 1575620 0 0.00 0 40488 27 0 0
sp_add_invoice_to_stock 5244 5154 90 1.71 25 173421 16180 0 90
sp_cancel_adding_invoice 5992 5992 0 0.00 6 65078 839 0 0
sp_cancel_client_order 1860 1860 0 0.00 4 13250 359 0 0
sp_cancel_customer_reserve 578410 578410 0 0.00 1 39636 57 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 3 4171 72 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 7267 81 0 0
sp_cancel_supplier_invoice 6598 6592 6 0.09 2 20011 799 0 6
sp_cancel_supplier_order 427 427 0 0.00 8 997794 98579 0 0
sp_cancel_write_off 11148 11148 0 0.00 1 12652 107 0 0
sp_client_order 21435 21435 0 0.00 4 36510 342 0 0
sp_customer_reserve 470969 470969 0 0.00 0 81505 161 0 0
sp_fill_shopping_cart 32807 32807 0 0.00 1 25987 41 0 0
sp_fill_shopping_cart_clo_res 470955 470955 0 0.00 0 20577 14 0 0
sp_get_clo_for_invoice 5140 5140 0 0.00 0 35875 279 0 0
sp_kill_cost_storno 18269 18269 0 0.00 0 9350 58 0 0
sp_kill_qstorno_ret_qs2qd 1174987 1174987 0 0.00 0 39577 31 0 0
sp_kill_qty_storno 619646 619646 0 0.00 0 40487 68 0 0
sp_lock_selected_doc 21076 21076 0 0.00 0 7097 4 0 0
sp_make_cost_storno 28689 28689 0 0.00 0 29751 58 0 0
sp_multiply_rows_for_pdistr 28689 28689 0 0.00 0 9868 32 0 0
sp_multiply_rows_for_qdistr 21435 21435 0 0.00 0 15808 155 0 0
sp_pay_from_customer 7412 7412 0 0.00 4 41816 130 0 0
sp_pay_to_supplier 6060 6060 0 0.00 5 13541 157 0 0
sp_payment_common 13472 13472 0 0.00 2 16579 37 0 0
sp_qd_handle_on_cancel_clo 1860 1860 0 0.00 0 13243 334 0 0
sp_qd_handle_on_invoice_upd_sts 11132 11132 0 0.00 0 40485 339 0 0
sp_qd_handle_on_reserve_upd_sts 10077 10077 0 0.00 0 22123 60 0 0
sp_reserve_write_off 10077 10077 0 0.00 6 22144 174 0 0
sp_supplier_invoice 5728 5728 0 0.00 7 120940 3676 0 0
sp_supplier_order 5655 5655 0 0.00 5 38702 1412 0 0
srv_aggregate_perf_data 100 100 0 0.00 3002 322502 19919 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1156 126671 49006 0 0
srv_find_qd_qs_mism 1090342 1090342 0 0.00 0 16896 5 0 0
srv_make_invnt_saldo 514 514 0 0.00 44 57632 1133 0 0
srv_make_money_saldo 746 746 0 0.00 0 318 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 57525 410809 233053 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 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 5155 5155 0 0.00 0 10365 142 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 3704 68 0 0
t$perf-norm:sp_cancel_client_order 1860 1860 0 0.00 0 13886 22 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 5390 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 5226 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 3740 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 9986 32 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 36713 1536 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 2095 9 0 0
t$perf-norm:sp_client_order 21435 21435 0 0.00 0 10034 16 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 1469 4 0 0
t$perf-norm:sp_pay_from_customer 7412 7412 0 0.00 0 9340 13 0 0
t$perf-norm:sp_pay_to_supplier 6060 6060 0 0.00 0 15246 12 0 0
t$perf-norm:sp_reserve_write_off 10077 10077 0 0.00 0 10988 10 0 0
t$perf-norm:sp_supplier_invoice 5728 5728 0 0.00 0 9456 15 0 0
t$perf-norm:sp_supplier_order 5655 5655 0 0.00 0 6056 17 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 198 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 655 11 0 0
t$perf-norm:srv_make_invnt_saldo 514 514 0 0.00 0 1609 27 0 0
t$perf-norm:srv_make_money_saldo 746 746 0 0.00 0 1708 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 10460 10280 180 1.72 0 4379 17 0 180
v_all_customers 39398 39398 0 0.00 0 14 1 0 0
v_all_suppliers 31646 31646 0 0.00 0 20 1 0 0
v_all_wares 42870 42870 0 0.00 0 64 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 113 5 0 0
v_cancel_client_order 3720 3720 0 0.00 0 3224 4 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 4163 9 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 2644 10 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1933 8 0 0
v_cancel_supplier_invoice 780 768 12 1.53 0 3272 20 0 12
v_cancel_supplier_order 854 854 0 0.00 0 777 6 0 0
v_cancel_write_off 1930 1930 0 0.00 0 2462 14 0 0
v_max_id_clo_ord 5655 5655 0 0.00 0 1394 2 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 2977 18 0 0
v_max_id_ord_sup 5717 5717 0 0.00 0 5045 5 0 0
v_max_non_paid_invoice 1609 1609 0 0.00 0 1760 5 0 0
v_max_non_paid_realizn 3795 3795 0 0.00 0 16558 9 0 0
v_min_id_clo_ord 5655 5655 0 0.00 0 13631 9 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 3833 13 0 0
v_min_id_ord_sup 5717 5717 0 0.00 0 8931 16 0 0
v_min_non_paid_invoice 6060 6060 0 0.00 0 9018 24 0 0
v_min_non_paid_realizn 7412 7412 0 0.00 0 8968 31 0 0
v_our_firm 10706 10706 0 0.00 0 168 1 0 0
v_reserve_write_off 20154 20154 0 0.00 0 4412 5 0 0
x_lock_depdocs_on_canc_invoice 12200 12200 0 0.00 0 25245 113 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 9774 378 0 0
x_make_qstorno_1000_1200 5655 5655 0 0.00 1 38663 1329 0 0
x_make_qstorno_1200_2000 5728 5728 0 0.00 3 120914 3527 0 0
x_make_qstorno_2100_3300 470969 470969 0 0.00 0 81504 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-11-2021 01:40:08 to 16-11-2021 01:40:12.

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:58 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:00 30472 8192000000 9910369760 10224824320 988723968 10.769 100 89 178 0 1018092688 6187824 23180896
23:10:36 17978 8192000000 9952170608 10277789696 1038589984 11.251 100 85 170 0 1068665696 8261264 21814448
23:12:05 22806 8192000000 9994738624 10331004928 1080368352 11.651 100 85 171 1 1111026368 9994864 20663152
23:13:32 10949 8192000000 10036534320 10390216704 1126120624 12.085 100 80 160 0 1155876352 10723344 19032384
23:14:49 8304 8192000000 10094047216 10452692992 1173747536 12.532 100 83 166 1 1205989344 13374912 18866896
23:16:00 7519 8192000000 10129853648 10501599232 1216870944 12.933 100 77 154 1 1246854256 12949280 17034032
23:17:10 4239 8192000000 10165282976 10539245568 1250140704 13.240 100 87 174 0 1283104048 13583312 19380032
23:18:17 3066 8192000000 10194205472 10577801216 1277813872 13.493 100 85 170 1 1309760096 12933840 19012384
23:19:22 2039 8192000000 10215600544 10603012096 1299476560 13.690 100 83 166 0 1330923472 12170400 19276512
23:20:25 2489 8192000000 10218303024 10618732544 1310112272 13.787 100 62 124 0 1336496832 12896976 13487584
23:21:29 17880 8192000000 10204755136 10616680448 1316578896 13.846 100 46 90 0 1333226528 5709056 10938576
23:22:54 6468 8192000000 10216523120 10632216576 1323740288 13.911 100 30 58 1 1337380528 7215904 6424336
23:24:01 9226 8192000000 10217354544 10637344768 1329629104 13.964 100 22 44 1 1341622608 6765952 5227552
23:25:13 8062 8192000000 10232847552 10648866816 1335411152 14.016 100 27 55 0 1347313152 5522736 6379264
23:26:22 35962 8192000000 10228984880 10648502272 1326593792 13.936 99 22 44 0 1337428848 5599264 5235792
23:27:58 6047 8192000000 10229598976 10650300416 1331139888 13.977 99 17 34 1 1340449200 5872880 3436432
23:29:07 8245 8192000000 10175758592 10584100864 1276049040 13.477 98 31 62 1 1292886912 10068704 6769168
23:30:16 47993 8192000000 10170168448 10565087232 1233077360 13.082 96 40 81 0 1247670560 5474768 9118432
23:32:04 5595 8192000000 10153507824 10552147968 1244695008 13.189 99 34 67 5 1258931152 5755424 8480720
23:33:10 10916 8192000000 10137628592 10540204032 1228278096 13.038 98 21 42 2 1239239616 5470896 5490624
23:34:22 7878 8192000000 10183583264 10590289920 1288154448 13.587 100 49 98 1 1305613088 5432288 12026352
23:35:38 23379 8192000000 10181039632 10593398784 1293110992 13.633 100 25 50 1 1305086544 6162368 5813184
23:37:02 5200 8192000000 10199042656 10614632448 1309484688 13.781 100 29 58 0 1322336128 6211392 6640048
23:38:13 30059 8192000000 10233928064 10648936448 1332283440 13.988 100 51 101 2 1350986672 6666720 12036512
23:40:02 34300 8192000000 10271718912 10686185472 1358043296 14.220 100 48 94 2 1380928848 11950400 10935152
23:41:38 126670 8192000000 10278358992 10710933504 1371452912 14.340 100 30 60 0 1392869520 13785968 7630640
23:44:54 4233 8192000000 10314216160 10732482560 1389751456 14.504 100 60 120 0 1425973616 22648640 13573520
23:46:04 4768 8192000000 10330061200 10749431808 1404122288 14.632 100 59 118 2 1442005264 23971376 13911600
23:47:11 2075 8192000000 10347591040 10763399168 1411468320 14.697 100 70 138 0 1451160768 22284208 17408240
23:48:23 4118 8192000000 10354382800 10777808896 1423876176 14.807 100 58 116 1 1462380960 24504336 14000448
23:49:31 11411 8192000000 10355574576 10781085696 1422966576 14.799 100 60 120 1 1462544800 25606912 13971312
23:50:53 9933 8192000000 10347458672 10770300928 1409537728 14.680 100 56 113 3 1450498624 27057408 13903488
23:52:06 4040 8192000000 10340685264 10762379264 1400086848 14.596 100 71 143 0 1450820912 33190160 17543904
23:53:16 7213 8192000000 10295954096 10722754560 1356904864 14.210 97 36 70 1 1394644208 28692000 9047344
23:54:27 3455 8192000000 10317811280 10736599040 1368980096 14.318 99 62 123 1 1418199504 33055936 16163472
23:55:36 1155 8192000000 10337162528 10746978304 1376327312 14.384 99 91 179 2 1438748192 39931664 22489216
23:56:44 7173 8192000000 10344581984 10759471104 1374080816 14.364 99 79 154 0 1436724448 43733024 18910608
23:57:57 4800 8192000000 10335685408 10756702208 1394014800 14.542 100 53 106 1 1440380528 32626864 13738864
23:59:05 11512 8192000000 10321538848 10751565824 1376181168 14.382 99 36 72 1 1419965728 33742096 10042464
00:00:25 11836 8192000000 10325675280 10759708672 1383350848 14.446 99 35 69 0 1431822464 38858336 9613280
00:01:43 11347 8192000000 10319060768 10751057920 1362026864 14.256 99 33 66 0 1416823712 45239472 9557376
00:02:56 7798 8192000000 10307395520 10748428288 1360192400 14.239 99 35 69 0 1409027504 39493264 9341840
00:04:21 18071 8192000000 10289056032 10741051392 1356210016 14.203 100 18 36 1 1396622400 35258688 5153696
00:05:42 31907 8192000000 10272152016 10724458496 1340040480 14.058 99 15 30 2 1376909520 32471568 4397472
00:07:21 99185 8192000000 10262917408 10695258112 1304137184 13.733 96 5 10 0 1317504896 12579424 788288
00:10:01 23344 8192000000 10271554464 10708668416 1337747536 14.037 98 38 75 4 1355385456 8189488 9448432
00:11:26 43054 8192000000 10261548464 10704080896 1342901056 14.084 99 11 22 0 1354280000 8479264 2899680
00:13:43 99287 8192000000 10274836336 10726866944 1362049984 14.256 99 28 56 0 1382157056 13316656 6790416
00:16:34 55725 8192000000 10302234752 10749837312 1378058976 14.399 100 26 52 2 1400438864 15673536 6706352
00:18:30 82279 8192000000 10312775600 10757169152 1390420560 14.510 100 18 36 0 1411787264 17071632 4295072
00:20:53 57807 8192000000 10313306496 10777743360 1397640192 14.574 100 23 46 0 1424665488 21357392 5667904
00:22:53 71703 8192000000 10302914368 10781028352 1391671888 14.521 100 13 24 1 1418167024 22484240 4010896
00:25:10 99932 8192000000 10311734448 10779271168 1408671264 14.672 100 13 26 1 1429736576 17195232 3870080
00:27:51 76956 8192000000 10310602816 10779992064 1400814832 14.602 100 14 28 0 1422441024 18272944 3353248
00:30:08 44071 8192000000 10291012016 10750623744 1360290128 14.240 97 19 37 0 1376099216 11553456 4255632
00:31:53 55142 8192000000 10299893584 10785071104 1400998704 14.604 100 12 24 0 1419585152 15749520 2836928
00:33:50 55758 8192000000 10289516128 10754416640 1356604960 14.207 97 10 19 0 1371022272 12230464 2186848
00:36:15 47782 8192000000 10296345904 10746908672 1363171904 14.266 98 32 64 2 1383349632 12940272 7237456
00:38:10 52753 8192000000 10297477424 10750197760 1345645808 14.108 96 15 30 0 1369023840 19910144 3467888
00:40:03 50359 8192000000 10288881680 10757271552 1365286688 14.285 99 11 22 2 1389394016 21617744 2489584
00:41:54 91041 8192000000 10294450208 10759208960 1322637536 13.901 95 8 16 0 1337146800 12977456 1531808
00:44:25 102392 8192000000 10296219088 10739376128 1288144176 13.587 93 22 43 2 1309662128 16932128 4585824
00:47:25 97769 8192000000 10240767088 10685952000 1307227328 13.761 96 40 80 1 1328653952 11808080 9618544
00:50:07 25585 8192000000 10212885872 10642497536 1267189872 13.396 94 28 56 0 1281150944 7466032 6495040
00:51:33 54485 8192000000 10246710208 10693394432 1316835088 13.848 97 16 32 1 1327453632 7020032 3598512
00:53:31 90833 8192000000 10235562448 10693517312 1320002480 13.877 97 11 22 0 1334025552 11341504 2681568
00:56:09 57402 8192000000 10257024016 10712707072 1355664608 14.198 100 18 36 0 1372966176 13266480 4035088
00:58:07 60864 8192000000 10272038000 10711654400 1322539984 13.900 97 22 44 0 1337125344 9038512 5546848
01:00:10 47646 8192000000 10263199328 10706460672 1331732816 13.983 98 14 27 1 1344147552 9052256 3362480
01:01:58 64993 8192000000 10255043200 10707480576 1335778752 14.019 99 16 32 0 1350547216 10834720 3933744
01:04:03 59732 8192000000 10270455616 10718302208 1348687264 14.136 99 16 33 0 1361343248 8720896 3935088
01:06:03 100868 8192000000 10278770704 10735493120 1345384288 14.106 98 10 20 0 1364609808 16569728 2655792
01:08:47 40631 8192000000 10303150400 10749456384 1386721712 14.477 100 20 40 0 1399236272 8593616 3920944
01:10:29 114830 8192000000 10294017888 10757349376 1398354128 14.580 100 26 52 0 1412405984 7812432 6239424
01:13:28 30656 8192000000 10332288560 10771755008 1394811392 14.549 99 35 70 1 1414283072 10932496 8539184
01:15:56 53576 8192000000 10295553712 10762854400 1404209280 14.632 100 5 10 0 1419587040 14198864 1178896
01:17:58 59383 8192000000 10302896064 10771783680 1390375920 14.509 99 7 14 1 1405369488 13484784 1508784
01:20:07 45129 8192000000 10319175040 10776211456 1408376000 14.670 100 26 52 0 1423966000 9502640 6087360
01:21:57 61094 8192000000 10299155824 10751438848 1346162192 14.113 96 18 36 1 1361193792 11206544 3825056
01:24:00 45409 8192000000 10298869792 10752471040 1377649728 14.396 99 18 36 1 1389395488 7733824 4011936
01:25:46 37816 8192000000 10297405120 10737672192 1343392160 14.088 97 35 70 0 1365147376 13044560 8710656
01:27:25 115077 8192000000 10285857872 10734534656 1288025632 13.586 93 14 28 0 1304880256 13942240 2912384
01:30:21 46359 8192000000 10301158048 10748788736 1357982032 14.219 98 13 25 1 1375015232 13915664 3117536
01:32:10 89105 8192000000 10253770480 10711375872 1321366624 13.889 97 5 10 0 1342812704 20577696 868384
01:34:41 72704 8192000000 10287982064 10741067776 1362794384 14.262 100 32 64 0 1389691760 19667552 7229824
01:36:54 58892 8192000000 10256408640 10722111488 1350259680 14.150 99 11 22 0 1365013328 12207232 2546416

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-11-2021 01:40:14 to 16-11-2021 01:40:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 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:01, from 16-11-2021 01:40:30 to 16-11-2021 01:40:31.

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          239378
MON$OLDEST_ACTIVE               241578
MON$OLDEST_SNAPSHOT             241578
MON$NEXT_TRANSACTION            241578
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                       2045216
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.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:405: 
:406: mscs-test-161.budzdorov.ru    Tue Nov 16 01:38:05 2021
:407:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:408: 
:409: 
:410: mscs-test-161.budzdorov.ru    Tue Nov 16 01:38:11 2021
:411:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:412: 
:413: 
:414: mscs-test-161.budzdorov.ru    Tue Nov 16 01:38:12 2021
:415:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:416: 
--- end of diff output ---

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

16.11.21 01:40:32. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-11-16 01:40:35 No severe PSQL-related problems occured
16.11.21 01:40:36. End of report.