Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.08.2021 10: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 15772360 kB
MemAvailable 31028688 kB
Buffers 0 kB
Cached 15946712 kB
SwapCached 2584 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-08-19 10:37:04
DTS_END                         2021-08-19 10:37:04.6120
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/39768
TRN_ID                          235941
ATT_ID                          696
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: 9349.13 28349 1121896
customer order: creation 168.23 386 20187
customer order: refuse 14.89 396 1787
order to supplier: creation 46.72 1417 5606
order to supplier: removal 3.72 125935 446
invoice (draft): creation 45.75 3959 5490
invoice (draft): removal 54.04 1037 6485
invoice accept: apply 41.78 19770 5014
invoice accept: cancel 48.58 1014 5830
customer reserve: creation 3889.50 192 466740
customer reserve: removal 4738.83 76 568659
realization accept: apply 82.80 200 9936
realization accept: cancel 84.13 137 10095
payment from customer: creation 59.88 142 7185
payment from customer: removal 5.87 117 704
payment to supplier: creation 49.43 160 5932
payment to supplier: removal 3.43 170 412
service: total inventory turnovers 3.54 4698 425
service: total monetary turnovers 6.98 563 837
service: aggregate perf. data 1.02 13126 122
service: refresh index statistics 0.03 393485 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-08-2021 10:40:06 to 19-08-2021 10:40:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4928
TEST_TIME 2 3488
TEST_TIME 3 4563
TEST_TIME 4 4803
TEST_TIME 5 5215
TEST_TIME 6 5446
TEST_TIME 7 5659
TEST_TIME 8 6019
TEST_TIME 9 6622
TEST_TIME 10 6492
TEST_TIME 11 6988
TEST_TIME 12 6730
TEST_TIME 13 6916
TEST_TIME 14 6890
TEST_TIME 15 7014
TEST_TIME 16 6936
TEST_TIME 17 7006
TEST_TIME 18 7117
TEST_TIME 19 7363
TEST_TIME 20 7387
TEST_TIME 21 7570
TEST_TIME 22 7672
TEST_TIME 23 7761
TEST_TIME 24 7773
TEST_TIME 25 8336
TEST_TIME 26 8714
TEST_TIME 27 8621
TEST_TIME 28 8770
TEST_TIME 29 8881
TEST_TIME 30 9056
TEST_TIME 31 9568
TEST_TIME 32 10284
TEST_TIME 33 10236
TEST_TIME 34 10937
TEST_TIME 35 10875
TEST_TIME 36 10768
TEST_TIME 37 10723
TEST_TIME 38 10569
TEST_TIME 39 10496
TEST_TIME 40 10399
TEST_TIME 41 10383
TEST_TIME 42 10408
TEST_TIME 43 10481
TEST_TIME 44 10514
TEST_TIME 45 10452
TEST_TIME 46 10462
TEST_TIME 47 10372
TEST_TIME 48 10207
TEST_TIME 49 10061
TEST_TIME 50 10025
TEST_TIME 51 9952
TEST_TIME 52 9913
TEST_TIME 53 9923
TEST_TIME 54 9854
TEST_TIME 55 9864
TEST_TIME 56 9907
TEST_TIME 57 9874
TEST_TIME 58 9945
TEST_TIME 59 9864
TEST_TIME 60 9858
TEST_TIME 61 9797
TEST_TIME 62 9758
TEST_TIME 63 9760
TEST_TIME 64 9771
TEST_TIME 65 9745
TEST_TIME 66 9791
TEST_TIME 67 9832
TEST_TIME 68 9849
TEST_TIME 69 9793
TEST_TIME 70 9808
TEST_TIME 71 9920
TEST_TIME 72 9888
TEST_TIME 73 9867
TEST_TIME 74 9946
TEST_TIME 75 9978
TEST_TIME 76 9955
TEST_TIME 77 9875
TEST_TIME 78 9862
TEST_TIME 79 9814
TEST_TIME 80 9752
TEST_TIME 81 9722
TEST_TIME 82 9718
TEST_TIME 83 9670
TEST_TIME 84 9628
TEST_TIME 85 9605
TEST_TIME 86 9603
TEST_TIME 87 9541
TEST_TIME 88 9520
TEST_TIME 89 9439
TEST_TIME 90 9386
TEST_TIME 91 9367
TEST_TIME 92 9373
TEST_TIME 93 9376
TEST_TIME 94 9463
TEST_TIME 95 9428
TEST_TIME 96 9403
TEST_TIME 97 9412
TEST_TIME 98 9468
TEST_TIME 99 9496
TEST_TIME 100 9520
TEST_TIME 101 9519
TEST_TIME 102 9539
TEST_TIME 103 9454
TEST_TIME 104 9417
TEST_TIME 105 9392
TEST_TIME 106 9372
TEST_TIME 107 9339
TEST_TIME 108 9326
TEST_TIME 109 9313
TEST_TIME 110 9282
TEST_TIME 111 9265
TEST_TIME 112 9280
TEST_TIME 113 9283
TEST_TIME 114 9311
TEST_TIME 115 9357
TEST_TIME 116 9400
TEST_TIME 117 9438
TEST_TIME 118 9467
TEST_TIME 119 9431
TEST_TIME 120 9356

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-08-2021 10:40:10 to 19-08-2021 10:40:14.

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 1552888 1552888 0 0.00 0 27373 7 0 0
doc_list_biud 1552887 1552887 0 0.00 0 33853 35 0 0
sp_add_invoice_to_stock 5157 5014 143 2.77 31 255973 19771 0 143
sp_cancel_adding_invoice 5830 5830 0 0.00 6 59077 1014 0 0
sp_cancel_client_order 1787 1787 0 0.00 5 12709 396 0 0
sp_cancel_customer_reserve 568659 568659 0 0.00 1 32732 77 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 3 10536 118 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 2 9196 170 0 0
sp_cancel_supplier_invoice 6493 6485 8 0.12 2 18764 1037 0 8
sp_cancel_supplier_order 446 446 0 0.00 8 1268311 125936 0 0
sp_cancel_write_off 10095 10095 0 0.00 1 22089 137 0 0
sp_client_order 20187 20187 0 0.00 4 21403 387 0 0
sp_customer_reserve 466740 466740 0 0.00 0 52625 192 0 0
sp_fill_shopping_cart 31266 31266 0 0.00 1 16634 43 0 0
sp_fill_shopping_cart_clo_res 466733 466733 0 0.00 0 17559 16 0 0
sp_get_clo_for_invoice 5006 5006 0 0.00 0 25180 358 0 0
sp_kill_cost_storno 17041 17041 0 0.00 0 22079 72 0 0
sp_kill_qstorno_ret_qs2qd 1154344 1154344 0 0.00 0 32725 41 0 0
sp_kill_qty_storno 608244 608244 0 0.00 0 33853 89 0 0
sp_lock_selected_doc 20861 20861 0 0.00 0 10444 6 0 0
sp_make_cost_storno 28059 28059 0 0.00 0 27215 70 0 0
sp_multiply_rows_for_pdistr 28059 28059 0 0.00 0 19287 40 0 0
sp_multiply_rows_for_qdistr 20187 20187 0 0.00 0 13523 190 0 0
sp_pay_from_customer 7185 7185 0 0.00 4 22093 143 0 0
sp_pay_to_supplier 5932 5932 0 0.00 4 24203 161 0 0
sp_payment_common 13117 13117 0 0.00 1 22030 32 0 0
sp_qd_handle_on_cancel_clo 1787 1787 0 0.00 0 12700 367 0 0
sp_qd_handle_on_invoice_upd_sts 10836 10836 0 0.00 0 33852 445 0 0
sp_qd_handle_on_reserve_upd_sts 9936 9936 0 0.00 0 15333 49 0 0
sp_reserve_write_off 9936 9936 0 0.00 7 22487 201 0 0
sp_supplier_invoice 5490 5490 0 0.00 7 107476 3959 0 0
sp_supplier_order 5606 5606 0 0.00 5 29305 1417 0 0
srv_aggregate_perf_data 122 122 0 0.00 2582 147449 13127 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1092 136851 43846 0 0
srv_find_qd_qs_mism 1073461 1073461 0 0.00 0 27361 6 0 0
srv_make_invnt_saldo 425 425 0 0.00 33 116955 4699 0 0
srv_make_money_saldo 837 837 0 0.00 1 13473 564 0 0
srv_recalc_idx_stat 4 4 0 0.00 238686 612246 393486 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5015 5015 0 0.00 0 21898 219 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 0 4080 108 0 0
t$perf-norm:sp_cancel_client_order 1787 1787 0 0.00 0 2870 19 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 1298 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 1142 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 4932 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 1586 22 0 0
t$perf-norm:sp_cancel_supplier_order 446 446 0 0.00 0 19617 1754 0 0
t$perf-norm:sp_cancel_write_off 1044 1044 0 0.00 0 1791 10 0 0
t$perf-norm:sp_client_order 20187 20187 0 0.00 0 7914 18 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 4263 14 0 0
t$perf-norm:sp_pay_from_customer 7185 7185 0 0.00 0 7888 11 0 0
t$perf-norm:sp_pay_to_supplier 5932 5932 0 0.00 0 3516 9 0 0
t$perf-norm:sp_reserve_write_off 9936 9936 0 0.00 0 7651 12 0 0
t$perf-norm:sp_supplier_invoice 5490 5490 0 0.00 0 7948 22 0 0
t$perf-norm:sp_supplier_order 5606 5606 0 0.00 0 4567 19 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 627 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 2504 55 0 0
t$perf-norm:srv_make_invnt_saldo 425 425 0 0.00 0 7353 32 0 0
t$perf-norm:srv_make_money_saldo 837 837 0 0.00 0 972 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10298 10012 286 2.77 0 10900 21 0 286
v_all_customers 37308 37308 0 0.00 0 145 1 0 0
v_all_suppliers 30802 30802 0 0.00 0 23 1 0 0
v_all_wares 40374 40374 0 0.00 0 122 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 1820 9 0 0
v_cancel_client_order 3574 3574 0 0.00 0 2088 6 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 10495 18 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 1971 5 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 1120 8 0 0
v_cancel_supplier_invoice 882 866 16 1.81 0 3342 23 0 16
v_cancel_supplier_order 892 892 0 0.00 0 6709 17 0 0
v_cancel_write_off 2088 2088 0 0.00 0 3542 18 0 0
v_max_id_clo_ord 5594 5594 0 0.00 0 269 1 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 4844 16 0 0
v_max_id_ord_sup 5485 5485 0 0.00 0 6618 6 0 0
v_max_non_paid_invoice 1610 1610 0 0.00 0 1745 3 0 0
v_max_non_paid_realizn 3732 3732 0 0.00 0 2588 4 0 0
v_min_id_clo_ord 5594 5594 0 0.00 0 1568 5 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 1359 7 0 0
v_min_id_ord_sup 5485 5485 0 0.00 0 3988 7 0 0
v_min_non_paid_invoice 5932 5932 0 0.00 0 22025 22 0 0
v_min_non_paid_realizn 7185 7185 0 0.00 0 22007 24 0 0
v_our_firm 9972 9972 0 0.00 0 131 1 0 0
v_reserve_write_off 19872 19872 0 0.00 0 10940 8 0 0
x_lock_depdocs_on_canc_invoice 11882 11882 0 0.00 0 10330 119 0 0
x_lock_depdocs_on_canc_sup_ord 446 446 0 0.00 0 4052 349 0 0
x_make_qstorno_1000_1200 5606 5606 0 0.00 2 28690 1330 0 0
x_make_qstorno_1200_2000 5490 5490 0 0.00 4 106632 3803 0 0
x_make_qstorno_2100_3300 466740 466740 0 0.00 0 52624 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-08-2021 10:40:15 to 19-08-2021 10:40:19.

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
08:07:08 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:09 43538 8192000000 9725290480 10023968768 799454736 8.891 100 87 174 0 829023712 8407744 21161232
08:09:59 20827 8192000000 9767857760 10076999680 844274848 9.343 100 86 172 0 877685792 13341088 20069856
08:11:29 19874 8192000000 9809710976 10132877312 880051312 9.700 100 77 154 1 920688352 23486512 17150528
08:12:51 14009 8192000000 9880321728 10215710720 924879024 10.144 100 82 162 1 988548496 46559792 17109680
08:14:08 9766 8192000000 9949994080 10294382592 963588672 10.524 100 82 164 0 1053679744 72914864 17176208
08:15:20 6026 8192000000 9981542016 10334351360 997091296 10.850 100 88 177 0 1094288144 78600144 18596704
08:16:27 3946 8192000000 9993743984 10357456896 1023907056 11.110 100 90 177 3 1105898640 63973904 18017680
08:17:34 2371 8192000000 10004554800 10371776512 1047423728 11.336 100 86 172 1 1121960928 57098640 17438560
08:18:38 1609 8192000000 10028895296 10396946432 1059820560 11.455 100 95 191 1 1141474416 62123312 19530544
08:19:43 3239 8192000000 10035053920 10407161856 1070091584 11.553 100 90 180 1 1146782256 57654912 19035760
08:20:49 16965 8192000000 10035124464 10426695680 1070856704 11.560 100 61 121 1 1162496272 79638464 12001104
08:22:08 2710 8192000000 10015987728 10414125056 1081150384 11.658 100 57 115 0 1135726848 43013152 11563312
08:23:12 4952 8192000000 10009500400 10405052416 1086660432 11.711 100 40 80 1 1130882640 35469120 8753088
08:24:19 5545 8192000000 10029690144 10422185984 1093335952 11.774 100 44 86 0 1139454496 36442560 9675984
08:25:27 12588 8192000000 10027258720 10420211712 1093667760 11.778 100 55 112 2 1125595040 20212928 11714352
08:26:40 4360 8192000000 10020651648 10430119936 1093841104 11.779 100 34 68 2 1128349632 26940976 7567552
08:27:46 3198 8192000000 9990934416 10386731008 1073678192 11.587 99 42 81 5 1101060848 18419520 8963136
08:28:53 31920 8192000000 10002932880 10385014784 1057074016 11.428 98 63 125 1 1090715712 19548928 14092768
08:30:25 4253 8192000000 9972759712 10358439936 1055182864 11.410 100 48 96 0 1094682800 28504400 10995536
08:31:31 6689 8192000000 9942823808 10331385856 1007829376 10.954 97 37 74 2 1039241264 23346304 8065584
08:32:38 10661 8192000000 9934091600 10325229568 1017269760 11.046 99 24 46 3 1044854976 21840992 5744224
08:33:50 26183 8192000000 9930329808 10326708224 1005379184 10.931 97 17 34 0 1036618448 27798000 3441264
08:35:18 6791 8192000000 9968801904 10370228224 1058021888 11.438 100 51 99 3 1090114304 20683264 11409152
08:36:25 10442 8192000000 9966614272 10375159808 1066111456 11.515 100 34 68 1 1089406784 15897840 7397488
08:37:44 27676 8192000000 10005591088 10401075200 1083455408 11.680 100 40 80 0 1108499936 15749824 9294704
08:39:17 58101 8192000000 10050482704 10452131840 1087905184 11.723 100 51 102 1 1162454336 63603008 10946144
08:41:18 29011 8192000000 10044734336 10453884928 1104515152 11.880 100 39 75 0 1157991104 45084560 8391392
08:42:52 5992 8192000000 10094615168 10495676416 1116305200 11.992 100 62 122 0 1199287680 70353648 12628832
08:44:04 2560 8192000000 10122652112 10519568384 1123832288 12.063 100 79 158 1 1232479760 91755312 16892160
08:45:10 8013 8192000000 10166750896 10578567168 1130961200 12.130 100 62 121 0 1284085152 138712592 14411360
08:46:22 9964 8192000000 10147208448 10582786048 1137713056 12.194 100 54 108 2 1270507776 120123088 12671632
08:47:50 8615 8192000000 10195926544 10612264960 1152923168 12.337 100 56 112 0 1302241680 137004704 12313808
08:49:03 9267 8192000000 10201287712 10615103488 1162515424 12.427 100 53 106 0 1289684896 115953936 11215536
08:50:19 7052 8192000000 10211855520 10642141184 1171229232 12.508 100 48 94 1 1326029936 144288752 10511952
08:51:31 2757 8192000000 10283142384 10686758912 1180667168 12.596 100 89 176 1 1380411280 180282784 19461328
08:52:40 2333 8192000000 10295745200 10702675968 1180593392 12.596 99 89 177 2 1397802736 197664752 19544592
08:53:50 4360 8192000000 10349158320 10751393792 1186167408 12.648 100 89 172 0 1450881936 245899296 18815232
08:55:02 2762 8192000000 10347569040 10766487552 1174596416 12.540 99 84 167 0 1443028352 249130944 19300992
08:56:10 2001 8192000000 10411092560 10813452288 1169247600 12.490 100 91 181 1 1508350624 318553488 20549536
08:57:15 2679 8192000000 10407017552 10811060224 1167677360 12.475 99 78 156 0 1504100816 318072416 18351040
08:58:24 1086 8192000000 10433544848 10832756736 1173492912 12.529 100 88 176 1 1532617488 337737408 21387168
08:59:31 8478 8192000000 10434280784 10845425664 1162492112 12.427 99 57 115 0 1525751440 347704016 15555312
09:00:48 6176 8192000000 10504074832 10926641152 1167114672 12.470 100 66 132 0 1603243408 419825424 16303312
09:02:00 2624 8192000000 10493615312 10948390912 1154283024 12.350 99 68 136 0 1577385280 406051056 17051200
09:03:15 1824 8192000000 10486148240 10911076352 1185470736 12.641 100 66 130 1 1579996320 378954000 15571584
09:04:22 13183 8192000000 10522982544 10955898880 1187636128 12.661 100 47 94 1 1601577376 401945904 11995344
09:05:42 10927 8192000000 10559137632 11019935744 1186060448 12.647 100 36 72 0 1670676048 474841104 9774496
09:07:01 18357 8192000000 10570618944 11039334400 1169667776 12.494 100 36 72 0 1664027008 484853744 9505488
09:09:10 27200 8192000000 10360838096 10851815424 1143655040 12.250 100 34 71 0 1462501536 309856256 8990240
09:10:43 25421 8192000000 10210367184 10680139776 1113011760 11.961 99 29 59 1 1311928528 191742128 7174640
09:12:09 136850 8192000000 10083660176 10564874240 1093613920 11.777 97 5 10 1 1132121840 37578592 929328
09:15:38 100081 8192000000 10093002320 10532651008 1110805584 11.940 98 16 31 1 1133171488 19174752 3191152
09:18:21 62644 8192000000 10118307472 10552365056 1130853968 12.129 99 26 52 0 1202774256 66011952 5908336
09:20:50 95035 8192000000 10087512000 10541363200 1137229760 12.189 99 12 23 1 1161608640 22228192 2150688
09:23:29 58130 8192000000 10082280944 10543333376 1140721568 12.222 99 25 49 2 1176322240 30546528 5054144
09:25:27 96928 8192000000 10152146832 10588069888 1150523424 12.314 99 39 78 2 1202272304 43148608 8600272
09:28:11 57144 8192000000 10162659184 10630561792 1152998704 12.338 99 35 70 2 1246167856 85641040 7528112
09:30:18 40108 8192000000 10146899216 10621923328 1164475024 12.445 100 27 54 0 1236303488 65544720 6283744
09:31:59 31189 8192000000 10166550064 10613944320 1158705104 12.391 99 65 128 1 1255525040 82275504 14544432
09:33:38 22275 8192000000 10166385232 10647904256 1171341824 12.509 100 28 56 1 1277688016 100267312 6078880
09:35:01 44106 8192000000 10116583856 10584502272 1136903888 12.186 98 10 20 0 1205101648 65779712 2418048
09:37:07 57353 8192000000 10106167376 10600353792 1126482512 12.088 97 24 46 1 1211108256 79326656 5299088
09:39:06 55835 8192000000 10127512768 10613522432 1121079840 12.037 98 17 34 0 1190037440 65399376 3558224
09:41:02 46967 8192000000 10099944416 10590408704 1124641264 12.071 98 17 34 1 1168680128 40163824 3875040
09:42:52 83352 8192000000 10127506144 10625073152 1069124400 11.544 93 18 36 0 1150168432 76875008 4169024
09:45:16 83814 8192000000 10066844992 10506153984 1062886320 11.484 93 17 35 0 1113590544 47522704 3181520
09:47:48 72893 8192000000 10067987728 10588577792 1093596352 11.777 97 21 42 0 1193197936 95354048 4247536
09:50:02 37157 8192000000 10069963024 10498637824 1093123104 11.772 97 29 58 1 1125124832 26404032 5597696
09:51:42 78149 8192000000 10070293040 10547392512 1086706208 11.711 96 15 30 0 1139152240 49623184 2822848
09:54:05 130846 8192000000 10066604240 10555662336 1122877728 12.054 99 38 75 2 1192878992 62103872 7897392
09:57:18 45311 8192000000 10093874768 10540806144 1124007280 12.065 99 37 74 2 1183588992 52415040 7166672
09:59:03 52676 8192000000 10057960480 10522796032 1131846736 12.139 100 10 20 0 1175548272 41734992 1966544
10:01:53 66781 8192000000 10085512432 10558103552 1119567248 12.023 99 12 24 0 1195837968 73107552 3163168
10:04:05 35297 8192000000 10043188432 10483326976 1100887760 11.846 97 34 65 3 1136352464 28980672 6484032
10:05:41 91344 8192000000 10029846240 10484314112 1087326624 11.717 97 9 18 0 1107071904 17897360 1847920
10:08:15 56263 8192000000 10093757808 10566303744 1123344128 12.059 99 23 46 0 1207911536 79482768 5084640
10:10:18 42878 8192000000 10112368688 10578432000 1106822080 11.902 97 35 70 2 1208876576 94456048 7598448
10:12:02 48754 8192000000 10068074112 10512056320 1107265136 11.907 98 26 50 0 1142728944 30420416 5043392
10:13:52 54652 8192000000 10135461872 10584113152 1101421792 11.851 97 42 84 1 1225002912 114809168 8771952
10:15:47 24699 8192000000 10094597120 10524942336 1120656704 12.033 99 55 111 0 1177360096 44864608 11838784
10:17:16 58605 8192000000 10109255648 10575818752 1133387104 12.153 99 21 42 1 1180111792 42915488 3809200
10:19:15 127232 8192000000 10077865552 10549948416 1117197472 12.001 97 20 40 0 1157908048 36152944 4557632
10:22:23 79989 8192000000 10052621968 10524778496 1113987760 11.970 98 6 12 1 1126516080 11467136 1061184
10:24:43 39919 8192000000 10067888000 10542157824 1136173776 12.180 100 27 53 0 1171674384 30260880 5239728
10:26:24 45356 8192000000 10094207696 10551451648 1116753296 11.996 98 20 40 0 1177551728 56438112 4360320
10:28:09 44497 8192000000 10143804048 10582839296 1120162080 12.029 98 25 50 1 1189588448 63841280 5585088
10:29:54 62694 8192000000 10109573520 10592022528 1097693040 11.816 96 17 34 1 1182967888 81446912 3827936
10:31:59 51755 8192000000 10108691712 10603085824 1105397296 11.889 98 22 44 1 1196506560 86142304 4966960
10:33:58 91180 8192000000 10113691200 10599874560 1095254224 11.793 97 12 24 1 1163350208 65401824 2694160
10:36:32 84628 8192000000 9162216272 9445658624 309508320 3.640 29 4 7 0 336047936 25717008 822608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-08-2021 10:40:20 to 19-08-2021 10:40:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-08-2021 10:40:37 to 19-08-2021 10:40:39.

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          237430
MON$OLDEST_ACTIVE               237431
MON$OLDEST_SNAPSHOT             237431
MON$NEXT_TRANSACTION            237431
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                       2096512
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             802
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2570 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2570 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2570 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 ---
:263: 
:264: mscs-test-161.budzdorov.ru    Thu Aug 19 10:38:07 2021
:265:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:266: 
:267: 
:268: mscs-test-161.budzdorov.ru    Thu Aug 19 10:38:43 2021
:269:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:270: 
--- end of diff output ---

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

19.08.21 10:40:40. 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-08-19 10:40:42 No severe PSQL-related problems occured
19.08.21 10:40:43. End of report.