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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16333364 kB
MemAvailable 31342076 kB
Buffers 20 kB
Cached 15258988 kB
SwapCached 444 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-12-22 04:37:11
DTS_END                         2021-12-22 04:37:11.3060
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38722
TRN_ID                          226491
ATT_ID                          626
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: 9299.83 24470 1115979
customer order: creation 163.06 426 19567
customer order: refuse 13.78 367 1654
order to supplier: creation 42.45 1375 5094
order to supplier: removal 3.29 132594 395
invoice (draft): creation 43.02 3641 5162
invoice (draft): removal 53.18 1083 6382
invoice accept: apply 39.63 18489 4756
invoice accept: cancel 48.23 933 5788
customer reserve: creation 3827.33 174 459280
customer reserve: removal 4779.73 72 573568
realization accept: apply 79.96 191 9595
realization accept: cancel 83.22 117 9986
payment from customer: creation 57.12 126 6854
payment from customer: removal 5.45 86 654
payment to supplier: creation 46.79 158 5615
payment to supplier: removal 3.15 104 378
service: total inventory turnovers 3.48 3908 418
service: total monetary turnovers 6.10 464 732
service: aggregate perf. data 0.81 16371 97
service: refresh index statistics 0.03 308711 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-12-2021 04:38:25 to 22-12-2021 04:38:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5777
TEST_TIME 2 4559
TEST_TIME 3 4628
TEST_TIME 4 4444
TEST_TIME 5 4769
TEST_TIME 6 4390
TEST_TIME 7 4864
TEST_TIME 8 4778
TEST_TIME 9 4710
TEST_TIME 10 4912
TEST_TIME 11 5107
TEST_TIME 12 5254
TEST_TIME 13 5561
TEST_TIME 14 5573
TEST_TIME 15 5656
TEST_TIME 16 6018
TEST_TIME 17 6296
TEST_TIME 18 6544
TEST_TIME 19 6739
TEST_TIME 20 6715
TEST_TIME 21 6918
TEST_TIME 22 7113
TEST_TIME 23 7613
TEST_TIME 24 7881
TEST_TIME 25 8312
TEST_TIME 26 8625
TEST_TIME 27 9557
TEST_TIME 28 10108
TEST_TIME 29 10596
TEST_TIME 30 10626
TEST_TIME 31 10750
TEST_TIME 32 10529
TEST_TIME 33 10379
TEST_TIME 34 10333
TEST_TIME 35 10416
TEST_TIME 36 10476
TEST_TIME 37 10477
TEST_TIME 38 10419
TEST_TIME 39 10278
TEST_TIME 40 10122
TEST_TIME 41 9996
TEST_TIME 42 9975
TEST_TIME 43 10099
TEST_TIME 44 9923
TEST_TIME 45 9833
TEST_TIME 46 9847
TEST_TIME 47 9802
TEST_TIME 48 9873
TEST_TIME 49 9822
TEST_TIME 50 9803
TEST_TIME 51 9823
TEST_TIME 52 9911
TEST_TIME 53 9858
TEST_TIME 54 9746
TEST_TIME 55 9703
TEST_TIME 56 9626
TEST_TIME 57 9553
TEST_TIME 58 9454
TEST_TIME 59 9477
TEST_TIME 60 9422
TEST_TIME 61 9381
TEST_TIME 62 9364
TEST_TIME 63 9495
TEST_TIME 64 9440
TEST_TIME 65 9486
TEST_TIME 66 9669
TEST_TIME 67 9695
TEST_TIME 68 9656
TEST_TIME 69 9703
TEST_TIME 70 9854
TEST_TIME 71 9874
TEST_TIME 72 9868
TEST_TIME 73 9787
TEST_TIME 74 9744
TEST_TIME 75 9668
TEST_TIME 76 9667
TEST_TIME 77 9650
TEST_TIME 78 9579
TEST_TIME 79 9548
TEST_TIME 80 9558
TEST_TIME 81 9545
TEST_TIME 82 9567
TEST_TIME 83 9592
TEST_TIME 84 9587
TEST_TIME 85 9563
TEST_TIME 86 9598
TEST_TIME 87 9616
TEST_TIME 88 9593
TEST_TIME 89 9729
TEST_TIME 90 9721
TEST_TIME 91 9767
TEST_TIME 92 9726
TEST_TIME 93 9645
TEST_TIME 94 9615
TEST_TIME 95 9587
TEST_TIME 96 9572
TEST_TIME 97 9520
TEST_TIME 98 9518
TEST_TIME 99 9495
TEST_TIME 100 9489
TEST_TIME 101 9484
TEST_TIME 102 9435
TEST_TIME 103 9591
TEST_TIME 104 9603
TEST_TIME 105 9601
TEST_TIME 106 9571
TEST_TIME 107 9584
TEST_TIME 108 9683
TEST_TIME 109 9616
TEST_TIME 110 9554
TEST_TIME 111 9506
TEST_TIME 112 9468
TEST_TIME 113 9476
TEST_TIME 114 9416
TEST_TIME 115 9379
TEST_TIME 116 9329
TEST_TIME 117 9296
TEST_TIME 118 9389
TEST_TIME 119 9376
TEST_TIME 120 9304

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-12-2021 04:38:28 to 22-12-2021 04:38:32.

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 1538858 1538858 0 0.00 0 18323 7 0 0
doc_list_biud 1538856 1538856 0 0.00 0 68488 34 0 0
sp_add_invoice_to_stock 4834 4756 78 1.61 39 188915 18489 0 78
sp_cancel_adding_invoice 5788 5788 0 0.00 5 94472 934 0 0
sp_cancel_client_order 1654 1654 0 0.00 5 17623 368 0 0
sp_cancel_customer_reserve 573568 573568 0 0.00 1 68494 72 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 3 5566 87 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 3 8309 105 0 0
sp_cancel_supplier_invoice 6389 6382 7 0.10 2 28601 1084 0 7
sp_cancel_supplier_order 395 395 0 0.00 6 1303251 132594 0 0
sp_cancel_write_off 9986 9986 0 0.00 1 6795 117 0 0
sp_client_order 19567 19567 0 0.00 4 29894 427 0 0
sp_customer_reserve 459280 459280 0 0.00 0 93939 175 0 0
sp_fill_shopping_cart 29814 29814 0 0.00 1 19402 38 0 0
sp_fill_shopping_cart_clo_res 459272 459272 0 0.00 0 38960 19 0 0
sp_get_clo_for_invoice 4748 4748 0 0.00 0 27153 380 0 0
sp_kill_cost_storno 16806 16806 0 0.00 0 7667 67 0 0
sp_kill_qstorno_ret_qs2qd 1163899 1163899 0 0.00 0 68447 39 0 0
sp_kill_qty_storno 612116 612116 0 0.00 0 68486 84 0 0
sp_lock_selected_doc 19897 19897 0 0.00 0 7786 5 0 0
sp_make_cost_storno 26812 26812 0 0.00 0 17098 61 0 0
sp_multiply_rows_for_pdistr 26812 26812 0 0.00 0 15822 37 0 0
sp_multiply_rows_for_qdistr 19567 19567 0 0.00 0 18181 220 0 0
sp_pay_from_customer 6854 6854 0 0.00 4 15828 127 0 0
sp_pay_to_supplier 5615 5615 0 0.00 5 21092 158 0 0
sp_payment_common 12469 12469 0 0.00 1 8003 23 0 0
sp_qd_handle_on_cancel_clo 1654 1654 0 0.00 0 17617 332 0 0
sp_qd_handle_on_invoice_upd_sts 10536 10536 0 0.00 0 31404 377 0 0
sp_qd_handle_on_reserve_upd_sts 9595 9595 0 0.00 0 18927 67 0 0
sp_reserve_write_off 9595 9595 0 0.00 7 18963 191 0 0
sp_supplier_invoice 5162 5162 0 0.00 6 120682 3642 0 0
sp_supplier_order 5094 5094 0 0.00 5 44429 1375 0 0
srv_aggregate_perf_data 97 97 0 0.00 2964 121046 16372 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2125 143361 45836 0 0
srv_find_qd_qs_mism 1068743 1068743 0 0.00 0 10023 5 0 0
srv_make_invnt_saldo 418 418 0 0.00 57 93875 3909 0 0
srv_make_money_saldo 732 732 0 0.00 1 10777 464 0 0
srv_recalc_idx_stat 4 4 0 0.00 162656 482552 308712 0 0
t$perf-abend:sp_add_invoice_to_stock 78 78 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4757 4757 0 0.00 0 15837 155 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 8167 111 0 0
t$perf-norm:sp_cancel_client_order 1654 1654 0 0.00 0 3182 16 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 1821 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 1815 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 729 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 6396 29 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 26587 1901 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 3279 9 0 0
t$perf-norm:sp_client_order 19567 19567 0 0.00 0 15028 19 0 0
t$perf-norm:sp_customer_reserve 996 996 0 0.00 0 571 5 0 0
t$perf-norm:sp_pay_from_customer 6854 6854 0 0.00 0 13951 11 0 0
t$perf-norm:sp_pay_to_supplier 5615 5615 0 0.00 0 7408 13 0 0
t$perf-norm:sp_reserve_write_off 9595 9595 0 0.00 0 11761 16 0 0
t$perf-norm:sp_supplier_invoice 5162 5162 0 0.00 0 7192 21 0 0
t$perf-norm:sp_supplier_order 5094 5094 0 0.00 0 30064 25 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 199 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 30 1 0 0
t$perf-norm:srv_make_invnt_saldo 418 418 0 0.00 0 6154 29 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 3717 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 9652 9496 156 1.61 0 7806 19 0 156
v_all_customers 35616 35616 0 0.00 0 40 1 0 0
v_all_suppliers 29254 29254 0 0.00 0 100 1 0 0
v_all_wares 39134 39134 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 2446 14 0 0
v_cancel_client_order 3308 3308 0 0.00 0 2439 5 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 5561 13 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 2775 12 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 4406 14 0 0
v_cancel_supplier_invoice 772 758 14 1.81 0 4481 22 0 14
v_cancel_supplier_order 790 790 0 0.00 0 2775 11 0 0
v_cancel_write_off 1950 1950 0 0.00 0 4991 16 0 0
v_max_id_clo_ord 5094 5094 0 0.00 0 1499 2 0 0
v_max_id_clo_res 996 996 0 0.00 0 3929 24 0 0
v_max_id_ord_sup 5153 5153 0 0.00 0 4832 4 0 0
v_max_non_paid_invoice 1235 1235 0 0.00 0 87 2 0 0
v_max_non_paid_realizn 3530 3530 0 0.00 0 1676 4 0 0
v_min_id_clo_ord 5094 5094 0 0.00 0 4924 7 0 0
v_min_id_clo_res 996 996 0 0.00 0 833 4 0 0
v_min_id_ord_sup 5153 5153 0 0.00 0 6281 8 0 0
v_min_non_paid_invoice 5615 5615 0 0.00 0 6260 17 0 0
v_min_non_paid_realizn 6854 6854 0 0.00 0 6012 15 0 0
v_our_firm 10166 10166 0 0.00 0 38 1 0 0
v_reserve_write_off 19190 19190 0 0.00 0 5382 6 0 0
x_lock_depdocs_on_canc_invoice 11791 11791 0 0.00 0 15934 108 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 5233 311 0 0
x_make_qstorno_1000_1200 5094 5094 0 0.00 2 44412 1304 0 0
x_make_qstorno_1200_2000 5162 5162 0 0.00 3 120581 3499 0 0
x_make_qstorno_2100_3300 459279 459279 0 0.00 0 93937 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-12-2021 04:38:33 to 22-12-2021 04:38: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
02:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:15 30217 8192000000 9718461392 10015543296 794792752 8.844 100 86 172 0 823603856 7322080 21489024
02:09:50 27076 8192000000 9767483760 10073350144 839007680 9.290 100 90 180 0 872580480 12264224 21308576
02:11:25 15772 8192000000 9802517504 10121662464 869933600 9.599 100 83 166 0 912046256 23203376 18909280
02:12:43 16428 8192000000 9874712512 10208468992 917350224 10.070 100 78 153 2 982166672 48270176 16546272
02:14:04 13040 8192000000 9910871312 10259611648 959197184 10.481 100 83 166 2 1036446688 59705264 17544240
02:15:19 6277 8192000000 9960162304 10315866112 998133728 10.860 100 80 160 1 1085578480 71192336 16252416
02:16:28 3895 8192000000 9984677440 10348433408 1024463744 11.115 100 82 163 0 1107749264 65986864 17298656
02:17:34 2438 8192000000 10022754400 10389901312 1043079440 11.294 100 89 178 0 1141938208 80992304 17866464
02:18:38 1887 8192000000 10045821600 10414608384 1061130720 11.467 100 88 174 0 1157008176 78429424 17448032
02:19:42 3878 8192000000 10025948912 10408034304 1071185648 11.563 100 70 139 1 1139337664 53868272 14283744
02:20:48 21466 8192000000 9981595984 10377773056 1072968480 11.580 100 25 50 0 1121551040 43458416 5124144
02:22:13 8890 8192000000 9996373104 10392281088 1080891760 11.656 100 38 74 1 1131266112 42778096 7596256
02:23:24 6536 8192000000 10009988128 10403999744 1089128336 11.734 100 44 88 1 1126792192 28082816 9581040
02:24:31 9580 8192000000 9988505680 10386026496 1094456944 11.785 100 33 66 3 1119385184 17354912 7573328
02:25:41 11451 8192000000 10017400064 10411868160 1097876400 11.817 100 52 105 1 1120695808 11871536 10947872
02:26:54 3017 8192000000 10031658064 10422059008 1103860912 11.874 100 54 103 1 1127950064 12264944 11824208
02:27:59 5210 8192000000 10018738608 10411782144 1095518064 11.795 100 35 70 2 1123541376 19893712 8129600
02:29:06 63650 8192000000 9965014096 10349068288 992441696 10.805 91 43 85 2 1024862160 21875136 10545328
02:31:10 8602 8192000000 9930579072 10309914624 987578848 10.758 95 26 52 0 1024156144 30943328 5633968
02:32:20 5903 8192000000 9915790688 10291048448 976626784 10.651 96 48 95 0 1006195440 17904112 11664544
02:33:27 27887 8192000000 9949505392 10354388992 1025059440 11.121 98 40 75 4 1047971344 13888736 9023168
02:35:18 7962 8192000000 9972947072 10367107072 1054030832 11.399 100 41 82 0 1088082800 24666080 9385888
02:36:27 6092 8192000000 9989955120 10386563072 1062832768 11.484 100 49 99 3 1097704336 24348048 10523520
02:37:40 29396 8192000000 10014119456 10404843520 1093893168 11.780 100 55 111 0 1123482496 17483392 12105936
02:39:19 112365 8192000000 10040435008 10469494784 1102268000 11.859 100 35 68 0 1182190656 71983120 7939536
02:42:16 42956 8192000000 10084745584 10499436544 1122484480 12.050 100 37 74 1 1187794080 57015136 8294464
02:44:09 28110 8192000000 10139741616 10555998208 1141315008 12.228 100 39 78 1 1231570064 81548512 8706544
02:45:43 9388 8192000000 10193825216 10607337472 1154385472 12.351 100 46 90 2 1294721248 129911232 10424544
02:46:57 9160 8192000000 10242357056 10659737600 1160764000 12.410 100 51 102 2 1342081056 169535744 11781312
02:48:15 7543 8192000000 10282047248 10696179712 1173077872 12.526 100 54 107 0 1377797056 192119664 12599520
02:49:30 6369 8192000000 10301029552 10712424448 1176028960 12.553 100 70 138 1 1397380768 206061472 15290336
02:50:44 2172 8192000000 10330669232 10736537600 1197250688 12.751 100 86 173 2 1430237136 213496352 19490096
02:51:53 2839 8192000000 10389854144 10805710848 1207476960 12.846 100 81 162 1 1498263632 271835072 18951600
02:53:03 2122 8192000000 10430881376 10855231488 1210892896 12.877 100 84 168 1 1534685472 303390128 20402448
02:54:09 3386 8192000000 10440867824 10855448576 1210424080 12.873 100 71 142 0 1534900064 307560976 16915008
02:55:21 3071 8192000000 10494243952 10914828288 1207726784 12.848 100 70 140 0 1587692048 363586624 16378640
02:56:31 14278 8192000000 10498290784 10932461568 1189085280 12.675 99 40 80 0 1587121264 387919312 10116672
02:57:51 9940 8192000000 10529497120 10958356480 1177573040 12.568 100 40 81 0 1624465056 436405312 10486704
02:59:13 14202 8192000000 10548442144 10981101568 1184972720 12.637 100 43 86 1 1648139840 451655264 11511856
03:00:35 18965 8192000000 10483425904 10980683776 1154478000 12.352 98 43 86 3 1591391680 425218256 11695424
03:01:58 20594 8192000000 10440914496 10955010048 1141975264 12.234 99 39 78 1 1586233184 433251968 11005952
03:03:41 29174 8192000000 10444683952 10919288832 1119125552 12.019 97 35 70 1 1569874992 440892000 9857440
03:05:17 20932 8192000000 10228958384 10755284992 1143179376 12.245 100 26 52 0 1397689280 247841312 6668592
03:06:39 43170 8192000000 10124521856 10629980160 1116900032 11.998 98 11 22 0 1219141984 99652080 2589872
03:08:23 89864 8192000000 10054906272 10491236352 1096913216 11.808 97 15 30 0 1132863216 32763792 3186208
03:10:56 53325 8192000000 10051683936 10496311296 1102089936 11.857 98 36 70 0 1123052128 13273216 7688976
03:12:50 95948 8192000000 10052525408 10497073152 1049949712 11.360 94 7 14 0 1085209936 34098304 1161920
03:15:30 48839 8192000000 10071316608 10493222912 1112971264 11.961 99 34 68 1 1146169024 25667952 7529808
03:18:02 71717 8192000000 10114529472 10536460288 1132013616 12.140 100 50 97 1 1201442224 59132096 10296512
03:20:22 109693 8192000000 10066924880 10511417344 1113195584 11.963 98 22 44 1 1161667104 43097072 5374448
03:23:15 72800 8192000000 10140695392 10598481920 1130025024 12.122 99 25 50 0 1219714160 83823152 5865984
03:25:32 31192 8192000000 10118271872 10578427904 1143750928 12.251 100 28 56 1 1233630208 83503696 6375584
03:27:05 40644 8192000000 10103068064 10572861440 1100325280 11.841 96 26 53 0 1196511632 90271296 5915056
03:28:46 28064 8192000000 10097887088 10571198464 1138175216 12.198 100 12 24 1 1213507920 72394432 2938272
03:32:05 60692 8192000000 10061754368 10527899648 1142963936 12.243 100 14 28 2 1195748736 49972400 2812400
03:34:20 21909 8192000000 10079104304 10531287040 1127469968 12.098 99 28 56 0 1170718944 36871360 6377616
03:35:44 84923 8192000000 10071817488 10575704064 1145418304 12.266 100 14 27 1 1194008976 45856224 2734448
03:38:10 38243 8192000000 10078908544 10530840576 1152619424 12.334 100 27 54 0 1196068480 37295632 6153424
03:39:53 38273 8192000000 10109837648 10579394560 1133973376 12.159 98 32 64 0 1210906160 70326880 6605904
03:42:08 29527 8192000000 10133712608 10573594624 1131866400 12.139 98 22 44 1 1222542848 86219408 4457040
03:43:53 41136 8192000000 10074359264 10525642752 1119069392 12.018 98 13 26 0 1153809920 31809984 2930544
03:45:39 41366 8192000000 10172403488 10617339904 1121775216 12.044 97 30 59 1 1203833760 75920464 6138080
03:47:42 41035 8192000000 10091840784 10614390784 1137999504 12.197 99 15 30 2 1222924992 81676416 3249072
03:49:24 24488 8192000000 10083312256 10512044032 1080332400 11.651 94 40 80 0 1128506352 39488720 8685232
03:50:49 121496 8192000000 10087727776 10526134272 1098502240 11.823 97 30 60 0 1185542640 80877568 6162832
03:53:58 26808 8192000000 10077799504 10504368128 1098750448 11.826 98 39 78 0 1151874928 45203424 7921056
03:55:29 51379 8192000000 10071486608 10505076736 1080694976 11.654 97 13 25 0 1146822512 63335024 2792512
03:57:21 52338 8192000000 10066065328 10579456000 1105018448 11.885 100 11 22 0 1164225472 56617232 2589792
03:59:14 54781 8192000000 10057223712 10513506304 1098119200 11.820 99 8 16 0 1162629536 62760080 1750256
04:01:25 39046 8192000000 10059924832 10490130432 1122699376 12.052 100 42 84 1 1180399392 48819312 8880704
04:03:08 61074 8192000000 10126161952 10560491520 1115381312 11.983 99 30 60 1 1191742560 70118944 6242304
04:05:39 58818 8192000000 10117864400 10603159552 1105769392 11.892 98 11 22 1 1190808384 82599040 2439952
04:07:38 108402 8192000000 10053962960 10485854208 1071689360 11.568 94 9 18 1 1100797456 27493776 1614320
04:10:27 76532 8192000000 10097033536 10555559936 1118968064 12.017 98 29 58 2 1185708496 60912880 5827552
04:14:26 60960 8192000000 10093921744 10563190784 1133232176 12.152 99 22 44 0 1207291168 68719552 5339440
04:16:28 57677 8192000000 10149914560 10583506944 1125469536 12.079 98 30 60 0 1217458736 84573984 7415216
04:18:33 66854 8192000000 10215492528 10664046592 1157156640 12.377 100 26 52 3 1283982368 121157168 5668560
04:20:40 71210 8192000000 10071602576 10533175296 1122375696 12.049 99 12 24 0 1188099936 63373904 2350336
04:22:55 26284 8192000000 10098106304 10524598272 1143915984 12.252 100 35 69 0 1192855264 42075648 6863632
04:24:22 34252 8192000000 10154719264 10622799872 1140536176 12.221 99 41 82 1 1218226992 69395568 8295248
04:26:06 143358 8192000000 10063154320 10512449536 1120744096 12.034 98 29 57 2 1151442928 24610032 6088800
04:29:30 65803 8192000000 10061960880 10525577216 1090827728 11.751 97 14 28 0 1150830400 57135392 2867280
04:31:58 63383 8192000000 10093236656 10543968256 1112077664 11.952 98 19 37 0 1172419120 56308848 4032608
04:34:07 71224 8192000000 10111811008 10626633728 1131939808 12.140 100 10 20 0 1242371104 108412144 2019152
04:36:19 36210 8192000000 10066404032 10506838016 1086708464 11.711 96 14 28 0 1126183744 36332400 3142880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 22-12-2021 04:38:39 to 22-12-2021 04:38:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 78 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-12-2021 04:38:54 to 22-12-2021 04:38:56.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          224423
MON$OLDEST_ACTIVE               226832
MON$OLDEST_SNAPSHOT             226832
MON$NEXT_TRANSACTION            226832
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-30 07:30:08.6030 GMT
MON$PAGES                       2054880
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2693 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2693 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2693 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2693 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:29: 
:30: mscs-test-161.budzdorov.ru    Wed Dec 22 04:37:16 2021
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:32: 
:33: 
:34: mscs-test-161.budzdorov.ru    Wed Dec 22 04:37:16 2021
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Wed Dec 22 04:37:16 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Wed Dec 22 04:37:16 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
--- end of diff output ---

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

22.12.21 04:38:57. There are 3 logs in /var/tmp/logs-oltp40 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-12-22 04:39:00 No severe PSQL-related problems occured
22.12.21 04:39:01. End of report.