Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.09.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 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 15866540 kB
MemAvailable 30886368 kB
Buffers 0 kB
Cached 15837852 kB
SwapCached 396 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-09-09 01:37:48
DTS_END                         2021-09-09 01:37:48.6230
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49650
TRN_ID                          245219
ATT_ID                          650
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: 9403.62 22480 1128434
customer order: creation 173.62 369 20834
customer order: refuse 15.41 343 1849
order to supplier: creation 46.68 1348 5601
order to supplier: removal 3.43 112927 411
invoice (draft): creation 47.69 3647 5723
invoice (draft): removal 54.91 766 6589
invoice accept: apply 42.09 18913 5051
invoice accept: cancel 49.15 879 5898
customer reserve: creation 3865.90 188 463908
customer reserve: removal 4797.97 65 575756
realization accept: apply 85.17 204 10220
realization accept: cancel 87.17 111 10460
payment from customer: creation 60.25 123 7230
payment from customer: removal 6.28 102 753
payment to supplier: creation 51.56 136 6187
payment to supplier: removal 3.38 75 406
service: total inventory turnovers 4.76 1204 571
service: total monetary turnovers 7.32 30 878
service: aggregate perf. data 0.88 18375 105
service: refresh index statistics 0.03 289800 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-09-2021 01:39:52 to 09-09-2021 01:39:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3373
TEST_TIME 2 2823
TEST_TIME 3 2778
TEST_TIME 4 4152
TEST_TIME 5 4327
TEST_TIME 6 4794
TEST_TIME 7 5213
TEST_TIME 8 5479
TEST_TIME 9 5864
TEST_TIME 10 6010
TEST_TIME 11 5952
TEST_TIME 12 6032
TEST_TIME 13 6279
TEST_TIME 14 6591
TEST_TIME 15 6987
TEST_TIME 16 7268
TEST_TIME 17 7413
TEST_TIME 18 7503
TEST_TIME 19 7764
TEST_TIME 20 7888
TEST_TIME 21 8049
TEST_TIME 22 8002
TEST_TIME 23 8151
TEST_TIME 24 8224
TEST_TIME 25 8228
TEST_TIME 26 8221
TEST_TIME 27 8504
TEST_TIME 28 8564
TEST_TIME 29 8501
TEST_TIME 30 8667
TEST_TIME 31 8627
TEST_TIME 32 8871
TEST_TIME 33 9316
TEST_TIME 34 10313
TEST_TIME 35 10459
TEST_TIME 36 10386
TEST_TIME 37 10248
TEST_TIME 38 10202
TEST_TIME 39 10242
TEST_TIME 40 10190
TEST_TIME 41 10170
TEST_TIME 42 10206
TEST_TIME 43 10094
TEST_TIME 44 9937
TEST_TIME 45 9860
TEST_TIME 46 9761
TEST_TIME 47 9692
TEST_TIME 48 9664
TEST_TIME 49 9553
TEST_TIME 50 9644
TEST_TIME 51 9807
TEST_TIME 52 9720
TEST_TIME 53 9702
TEST_TIME 54 9578
TEST_TIME 55 9425
TEST_TIME 56 9451
TEST_TIME 57 9398
TEST_TIME 58 9348
TEST_TIME 59 9451
TEST_TIME 60 9427
TEST_TIME 61 9519
TEST_TIME 62 9641
TEST_TIME 63 9613
TEST_TIME 64 9695
TEST_TIME 65 9730
TEST_TIME 66 9758
TEST_TIME 67 9751
TEST_TIME 68 9732
TEST_TIME 69 9826
TEST_TIME 70 9801
TEST_TIME 71 9783
TEST_TIME 72 9741
TEST_TIME 73 9636
TEST_TIME 74 9621
TEST_TIME 75 9561
TEST_TIME 76 9708
TEST_TIME 77 9631
TEST_TIME 78 9588
TEST_TIME 79 9581
TEST_TIME 80 9505
TEST_TIME 81 9440
TEST_TIME 82 9462
TEST_TIME 83 9458
TEST_TIME 84 9463
TEST_TIME 85 9518
TEST_TIME 86 9502
TEST_TIME 87 9448
TEST_TIME 88 9616
TEST_TIME 89 9613
TEST_TIME 90 9704
TEST_TIME 91 9698
TEST_TIME 92 9695
TEST_TIME 93 9690
TEST_TIME 94 9658
TEST_TIME 95 9605
TEST_TIME 96 9571
TEST_TIME 97 9513
TEST_TIME 98 9522
TEST_TIME 99 9492
TEST_TIME 100 9454
TEST_TIME 101 9426
TEST_TIME 102 9387
TEST_TIME 103 9398
TEST_TIME 104 9415
TEST_TIME 105 9391
TEST_TIME 106 9414
TEST_TIME 107 9391
TEST_TIME 108 9358
TEST_TIME 109 9416
TEST_TIME 110 9472
TEST_TIME 111 9491
TEST_TIME 112 9479
TEST_TIME 113 9554
TEST_TIME 114 9557
TEST_TIME 115 9554
TEST_TIME 116 9499
TEST_TIME 117 9459
TEST_TIME 118 9414
TEST_TIME 119 9414
TEST_TIME 120 9404

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-09-2021 01:39:55 to 09-09-2021 01:39:59.

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 1559215 1559215 0 0.00 0 22432 6 0 0
doc_list_biud 1559210 1559210 0 0.00 0 30440 30 0 0
sp_add_invoice_to_stock 5135 5051 84 1.63 53 253535 18913 0 84
sp_cancel_adding_invoice 5898 5898 0 0.00 5 63208 880 0 0
sp_cancel_client_order 1849 1849 0 0.00 4 14782 343 0 0
sp_cancel_customer_reserve 575756 575756 0 0.00 1 22958 65 0 0
sp_cancel_pay_from_customer 753 753 0 0.00 3 10063 102 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 2 5287 76 0 0
sp_cancel_supplier_invoice 6601 6589 12 0.18 2 13167 767 0 12
sp_cancel_supplier_order 411 411 0 0.00 7 1049228 112928 0 0
sp_cancel_write_off 10460 10460 0 0.00 1 21301 111 0 0
sp_client_order 20834 20834 0 0.00 3 25795 370 0 0
sp_customer_reserve 463908 463908 0 0.00 0 81556 188 0 0
sp_fill_shopping_cart 32157 32157 0 0.00 1 22460 44 0 0
sp_fill_shopping_cart_clo_res 463908 463908 0 0.00 0 14525 14 0 0
sp_get_clo_for_invoice 5050 5050 0 0.00 0 31550 271 0 0
sp_kill_cost_storno 17517 17517 0 0.00 0 5976 59 0 0
sp_kill_qstorno_ret_qs2qd 1168972 1168972 0 0.00 0 22929 35 0 0
sp_kill_qty_storno 616230 616230 0 0.00 0 30440 75 0 0
sp_lock_selected_doc 21324 21324 0 0.00 0 9545 6 0 0
sp_make_cost_storno 28687 28687 0 0.00 0 15841 58 0 0
sp_multiply_rows_for_pdistr 28682 28682 0 0.00 0 11302 32 0 0
sp_multiply_rows_for_qdistr 20834 20834 0 0.00 0 14989 156 0 0
sp_pay_from_customer 7230 7230 0 0.00 4 13285 123 0 0
sp_pay_to_supplier 6187 6187 0 0.00 5 27865 136 0 0
sp_payment_common 13415 13415 0 0.00 1 15730 25 0 0
sp_qd_handle_on_cancel_clo 1849 1849 0 0.00 0 14772 310 0 0
sp_qd_handle_on_invoice_upd_sts 10945 10945 0 0.00 0 30440 348 0 0
sp_qd_handle_on_reserve_upd_sts 10220 10220 0 0.00 0 28435 77 0 0
sp_reserve_write_off 10220 10220 0 0.00 6 32995 205 0 0
sp_supplier_invoice 5723 5723 0 0.00 6 84886 3647 0 0
sp_supplier_order 5601 5601 0 0.00 5 36441 1348 0 0
srv_aggregate_perf_data 105 105 0 0.00 2949 125173 18376 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1658 144234 45825 0 0
srv_find_qd_qs_mism 1080433 1080433 0 0.00 0 22421 5 0 0
srv_make_invnt_saldo 571 571 0 0.00 43 48878 1205 0 0
srv_make_money_saldo 878 878 0 0.00 1 1074 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 179497 485086 289800 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5051 5051 0 0.00 0 21849 198 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 1 2558 97 0 0
t$perf-norm:sp_cancel_client_order 1849 1849 0 0.00 0 2329 12 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 1634 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 753 753 0 0.00 0 4321 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 3462 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 4502 26 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 26396 1737 0 0
t$perf-norm:sp_cancel_write_off 1052 1052 0 0.00 0 21517 30 0 0
t$perf-norm:sp_client_order 20834 20834 0 0.00 0 21443 21 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 3360 9 0 0
t$perf-norm:sp_pay_from_customer 7230 7230 0 0.00 0 22145 14 0 0
t$perf-norm:sp_pay_to_supplier 6187 6187 0 0.00 0 22118 19 0 0
t$perf-norm:sp_reserve_write_off 10220 10220 0 0.00 0 14126 15 0 0
t$perf-norm:sp_supplier_invoice 5723 5723 0 0.00 0 8710 23 0 0
t$perf-norm:sp_supplier_order 5601 5601 0 0.00 0 6012 21 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 343 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 74 4 0 0
t$perf-norm:srv_make_invnt_saldo 571 571 0 0.00 0 1079 21 0 0
t$perf-norm:srv_make_money_saldo 878 878 0 0.00 0 1833 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 6 0 0
v_add_invoice_to_stock 10254 10086 168 1.63 0 6849 20 0 168
v_all_customers 37876 37876 0 0.00 0 28 1 0 0
v_all_suppliers 31670 31670 0 0.00 0 22 1 0 0
v_all_wares 41668 41668 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 3593 18 0 0
v_cancel_client_order 3698 3698 0 0.00 0 6554 8 0 0
v_cancel_customer_prepayment 1506 1506 0 0.00 0 6569 14 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 2037 7 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 750 5 0 0
v_cancel_supplier_invoice 886 862 24 2.70 0 3863 27 0 24
v_cancel_supplier_order 822 822 0 0.00 0 1164 6 0 0
v_cancel_write_off 2104 2104 0 0.00 0 3893 24 0 0
v_max_id_clo_ord 5601 5601 0 0.00 0 495 1 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 7556 20 0 0
v_max_id_ord_sup 5722 5722 0 0.00 0 5149 4 0 0
v_max_non_paid_invoice 1675 1675 0 0.00 0 3206 5 0 0
v_max_non_paid_realizn 3922 3922 0 0.00 0 1690 4 0 0
v_min_id_clo_ord 5601 5601 0 0.00 0 7428 9 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 5763 17 0 0
v_min_id_ord_sup 5722 5722 0 0.00 0 5403 13 0 0
v_min_non_paid_invoice 6187 6187 0 0.00 0 15716 15 0 0
v_min_non_paid_realizn 7227 7227 0 0.00 0 4327 11 0 0
v_our_firm 10402 10402 0 0.00 0 19 1 0 0
v_reserve_write_off 20440 20440 0 0.00 0 4353 7 0 0
x_lock_depdocs_on_canc_invoice 12056 12056 0 0.00 0 13097 125 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 10981 360 0 0
x_make_qstorno_1000_1200 5601 5601 0 0.00 2 36424 1248 0 0
x_make_qstorno_1200_2000 5723 5723 0 0.00 3 82981 3499 0 0
x_make_qstorno_2100_3300 463908 463908 0 0.00 0 81554 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-09-2021 01:40:00 to 09-09-2021 01:40:04.

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:52 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:52 28810 8192000000 10381209136 10700156928 1423978896 14.808 100 91 182 1 1459898320 5978880 29940544
23:10:27 19269 8192000000 10434481376 10761736192 1482127984 15.320 100 91 182 0 1520994208 9058976 29807248
23:11:48 16419 8192000000 10463380976 10802798592 1520131360 15.651 100 86 172 0 1558221952 10538912 27551680
23:13:15 11642 8192000000 10563026640 10921345024 1626529200 16.565 100 77 152 1 1659039168 9614496 22895472
23:14:29 7479 8192000000 10655911744 11022938112 1709225744 17.262 100 81 162 1 1745636576 12736848 23673984
23:15:38 4443 8192000000 10719245520 11094360064 1773140544 17.793 100 89 178 1 1812798480 14838144 24819792
23:16:44 3663 8192000000 10743717968 11126333440 1808187152 18.081 100 82 163 2 1845099840 14311472 22601216
23:17:50 2581 8192000000 10794042624 11181674496 1857958736 18.487 100 91 181 3 1895672096 11706832 26006528
23:18:54 16278 8192000000 10815135136 11210846208 1878577200 18.654 100 71 140 0 1911180512 12369104 20234208
23:20:11 10006 8192000000 10782772128 11210358784 1879537504 18.661 100 33 65 2 1894732944 5649392 9546048
23:21:24 5928 8192000000 10795468128 11221438464 1887079952 18.722 100 27 54 0 1901997200 6689328 8227920
23:22:33 10395 8192000000 10801234016 11231309824 1888705456 18.735 100 20 39 1 1899969680 5643680 5620544
23:23:45 14007 8192000000 10791540208 11229822976 1879389728 18.660 99 16 32 2 1890448224 6745008 4313488
23:25:00 27741 8192000000 10852126400 11251740672 1908341312 18.893 100 68 135 2 1933843840 5635088 19867440
23:26:28 4348 8192000000 10814820544 11243102208 1895208416 18.788 100 23 45 0 1907423552 6038016 6177120
23:27:33 9288 8192000000 10780740288 11210059776 1824761872 18.217 97 20 38 4 1835516736 5076480 5678384
23:28:47 36542 8192000000 10786156880 11176841216 1813997840 18.129 97 69 140 0 1839702848 5526640 20178368
23:30:24 4522 8192000000 10713565456 11118776320 1772493904 17.788 99 34 67 1 1788351408 6013712 9843792
23:31:30 6615 8192000000 10698440896 11114315776 1759951952 17.684 98 13 27 1 1768466880 4696528 3818400
23:32:37 8015 8192000000 10731060912 11152093184 1816135072 18.146 100 29 58 4 1830221696 5499040 8587584
23:33:45 37152 8192000000 10746549936 11180843008 1839964656 18.341 100 26 52 1 1853894448 5917744 8012048
23:35:24 8062 8192000000 10773370000 11202826240 1860714288 18.509 100 25 50 0 1874518192 6183280 7620624
23:36:33 10216 8192000000 10784378816 11217190912 1876357936 18.636 100 25 50 2 1889137872 5277296 7502640
23:37:46 62198 8192000000 10819412928 11250466816 1884507840 18.701 99 32 64 1 1899323792 6134160 8681792
23:40:06 25764 8192000000 10856216032 11285327872 1931921536 19.082 100 58 116 1 1957208592 8998672 16288384
23:41:36 9198 8192000000 10888608384 11307077632 1944830336 19.185 100 50 98 1 1970061840 11350640 13880864
23:42:52 16624 8192000000 10890348480 11312226304 1952494656 19.246 100 58 116 1 1983820304 14551888 16773760
23:44:13 8533 8192000000 10904300576 11329490944 1949288368 19.221 99 58 116 1 1981817552 15518976 17010208
23:45:25 2333 8192000000 10934926240 11342311424 1973560912 19.414 100 90 180 0 2016019216 15868016 26590288
23:46:29 20668 8192000000 10915098816 11342553088 1958550368 19.295 99 55 108 1 1988338192 14218192 15569632
23:47:59 16645 8192000000 10919398992 11355414528 1975870048 19.432 100 47 94 1 2009118736 18696688 14552000
23:49:20 2896 8192000000 10949591552 11378667520 1995686560 19.589 100 69 134 2 2037946352 22165152 20094640
23:50:26 3772 8192000000 10925963072 11349651456 1969197296 19.379 100 63 125 3 2010569216 22747792 18624128
23:51:33 3479 8192000000 10934708448 11346452480 1961741616 19.320 100 70 140 3 2010802928 28420080 20641232
23:52:42 1648 8192000000 10944224256 11357335552 1958229776 19.292 99 87 170 1 2013416944 29292464 25894704
23:53:48 3882 8192000000 10904204480 11322204160 1928132944 19.052 99 72 143 3 1976294800 26937248 21224608
23:55:00 5156 8192000000 10898542768 11309428736 1926848544 19.042 99 69 138 1 1977789792 30310176 20631072
23:56:10 3634 8192000000 10887578016 11292913664 1901542512 18.839 99 76 152 0 1962801328 37820400 23438416
23:57:20 5986 8192000000 10915659728 11345530880 1945811168 19.193 100 57 112 2 2004232624 40206704 18214752
23:58:31 9171 8192000000 10929417760 11354615808 1954593264 19.263 100 61 122 2 2017252128 43197248 19461616
23:59:52 9366 8192000000 10963289008 11389804544 1980669104 19.470 100 57 114 0 2049806480 50738400 18398976
00:01:05 6518 8192000000 10950989376 11382550528 1967713360 19.367 100 41 83 0 2031168656 49405984 14049312
00:02:19 13521 8192000000 10953697392 11396558848 1976169008 19.434 100 39 78 4 2041774128 52600160 13004960
00:03:40 11032 8192000000 11007332096 11441717248 2005392528 19.665 100 38 76 1 2081384624 62815280 13176816
00:04:53 4655 8192000000 10995675728 11420774400 1996141168 19.592 99 73 146 1 2071516368 52593856 22781344
00:06:00 29711 8192000000 10989507104 11439431680 2006644928 19.675 100 36 72 0 2076244496 57360048 12239520
00:07:37 7286 8192000000 10985026816 11441360896 1999464288 19.619 100 33 66 2 2067650704 57210560 10975856
00:08:56 22654 8192000000 10948306128 11403309056 1966508880 19.358 99 26 52 0 2043033840 66864640 9660320
00:10:22 22075 8192000000 10952435776 11418976256 1958429072 19.294 99 27 54 1 2039458688 70838512 10191104
00:11:48 133446 8192000000 10898278912 11370110976 1879863392 18.664 95 6 12 0 1904553728 23190656 1499680
00:15:02 103796 8192000000 10910394592 11385520128 1964285696 19.340 99 22 44 0 1988475104 18448432 5740976
00:17:47 89248 8192000000 10925238448 11399643136 1975044928 19.425 99 20 40 0 2008217104 26701664 6470512
00:20:20 119742 8192000000 10898920448 11347324928 1906003168 18.875 96 26 51 1 1919428160 6172944 7252048
00:23:20 42862 8192000000 10891168880 11346534400 1954973264 19.266 99 39 77 0 1972275376 6769920 10532192
00:25:04 26861 8192000000 10915752160 11383984128 1984694304 19.502 100 24 48 0 2000099280 8380848 7024128
00:26:44 85861 8192000000 10915755088 11384397824 1979574880 19.461 100 41 83 0 2009126672 17260624 12291168
00:29:10 64881 8192000000 10877087456 11350880256 1934994176 19.107 98 16 32 0 1951821504 11941248 4886080
00:31:16 51523 8192000000 10890144880 11374567424 1955439312 19.270 99 7 14 0 1968029040 10898656 1691072
00:33:15 102766 8192000000 10899117696 11397312512 1962832544 19.329 99 9 18 0 1982008912 16390336 2786032
00:36:08 79531 8192000000 10909408256 11392065536 1941317904 19.157 98 7 14 0 1962907872 19938320 1651648
00:38:39 73324 8192000000 10895617312 11376721920 1930082032 19.068 97 20 40 0 1948558768 12899472 5577264
00:40:57 51664 8192000000 10920929408 11381534720 1892657008 18.767 95 13 25 2 1910400064 14279728 3463328
00:42:52 69197 8192000000 10890777328 11348856832 1830689856 18.265 93 12 24 1 1847117424 13054752 3372816
00:45:02 51489 8192000000 10890262256 11338690560 1915053056 18.947 99 22 43 0 1933184832 11768752 6363024
00:47:01 106736 8192000000 10895112800 11342872576 1824659984 18.216 93 31 59 1 1844784784 11669280 8455520
00:49:51 99333 8192000000 10870773520 11338309632 1866865952 18.559 96 28 55 1 1884430432 9925056 7639424
00:52:31 33075 8192000000 10881090080 11348541440 1929941504 19.066 99 29 58 0 1961045296 22929104 8174688
00:54:04 51980 8192000000 10846492144 11331354624 1868014464 18.568 96 12 23 0 1882595280 11425088 3155728
00:55:56 72629 8192000000 10874741184 11353395200 1938369520 19.134 99 14 28 0 1951252576 8176080 4706976
00:58:11 67228 8192000000 10884669680 11373199360 1926742816 19.041 98 6 12 1 1939929600 11383792 1802992
01:00:18 83861 8192000000 10902097648 11370856448 1907756192 18.889 97 16 32 0 1933847072 21784832 4306048
01:02:46 35082 8192000000 10904867040 11374350336 1951408976 19.238 99 22 44 2 1967028464 9856560 5762928
01:04:21 41024 8192000000 10911363312 11377283072 1962980304 19.330 100 23 47 0 1985005200 15626080 6398816
01:06:08 43733 8192000000 10922643872 11393404928 1954211552 19.260 99 19 38 0 1983422512 23542416 5668544
01:07:55 144233 8192000000 10920327408 11385692160 1885693456 18.711 95 9 18 1 1902749872 14678320 2378096
01:11:22 12724 8192000000 10924114240 11371589632 1968449216 19.373 100 50 101 0 1993731536 11390624 13891696
01:12:35 103767 8192000000 10885048864 11362500608 1931477152 19.079 98 20 40 0 1945177456 8337664 5362640
01:15:20 41601 8192000000 10890967360 11383193600 1975631984 19.430 100 10 20 0 1988648384 10270384 2746016
01:18:06 67363 8192000000 10890916224 11368898560 1964458976 19.341 100 14 28 1 1977631568 8980016 4192576
01:20:16 63431 8192000000 10888912320 11373203456 1941791136 19.161 98 11 22 0 1954924912 9890944 3242832
01:22:21 64611 8192000000 10871490784 11350851584 1936658048 19.120 98 25 50 0 1953085520 9250640 7176832
01:24:29 36406 8192000000 10905139504 11372417024 1918122016 18.972 97 13 26 1 1934258096 12621008 3515072
01:26:11 84051 8192000000 10884587856 11379417088 1858440256 18.491 94 16 32 1 1885198208 22407536 4350416
01:28:37 37206 8192000000 10873195136 11332972544 1871611888 18.597 96 22 44 0 1891064192 13531696 5920608
01:30:14 51985 8192000000 10844399824 11318640640 1896256832 18.796 98 14 28 0 1911046992 10740576 4049584
01:32:51 114591 8192000000 10860890112 11327770624 1855627584 18.468 95 26 52 1 1870431840 7791632 7012624
01:35:46 24043 8192000000 10858033776 11319078912 1925137568 19.028 100 31 61 1 1942400656 8784128 8478960
01:37:11 23165 8192000000 10849822592 11324461056 1934183424 19.100 100 19 38 0 1946094128 7090288 4820416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-09-2021 01:40:06 to 09-09-2021 01:40:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 09-09-2021 01:40:22 to 09-09-2021 01:40:23.

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          243466
MON$OLDEST_ACTIVE               245328
MON$OLDEST_SNAPSHOT             245328
MON$NEXT_TRANSACTION            245328
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                       2062496
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.33493 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33493 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33493 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33493 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 ---
:85: 
:86: mscs-test-161.budzdorov.ru    Thu Sep  9 01:37:52 2021
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:88: 
:89: 
:90: mscs-test-161.budzdorov.ru    Thu Sep  9 01:37:53 2021
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:92: 
:93: 
:94: mscs-test-161.budzdorov.ru    Thu Sep  9 01:38:08 2021
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:96: 
:97: 
:98: mscs-test-161.budzdorov.ru    Thu Sep  9 01:38:08 2021
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:100: 
:101: 
:102: mscs-test-161.budzdorov.ru    Thu Sep  9 01:38:08 2021
:103:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:104: 
:105: 
:106: mscs-test-161.budzdorov.ru    Thu Sep  9 01:38:08 2021
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:108: 
:109: 
:110: mscs-test-161.budzdorov.ru    Thu Sep  9 01:38:08 2021
:111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:112: 
:113: 
:114: mscs-test-161.budzdorov.ru    Thu Sep  9 01:38:08 2021
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:116: 
--- end of diff output ---

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

09.09.21 01:40:25. 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-09-09 01:40:27 No severe PSQL-related problems occured
09.09.21 01:40:28. End of report.