Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.10.2021 04: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 15953452 kB
MemAvailable 30636888 kB
Buffers 20 kB
Cached 15732708 kB
SwapCached 3704 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-10-22 04:37:08
DTS_END                         2021-10-22 04:37:08.3480
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/49880
TRN_ID                          229316
ATT_ID                          609
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: 8873.37 20383 1064804
customer order: creation 173.14 404 20777
customer order: refuse 15.01 419 1801
order to supplier: creation 46.17 1526 5540
order to supplier: removal 3.28 119139 393
invoice (draft): creation 45.48 3560 5457
invoice (draft): removal 48.38 1028 5805
invoice accept: apply 42.06 21199 5047
invoice accept: cancel 43.43 931 5211
customer reserve: creation 3874.29 209 464915
customer reserve: removal 4298.48 71 515818
realization accept: apply 84.62 191 10154
realization accept: cancel 71.65 142 8598
payment from customer: creation 59.68 141 7161
payment from customer: removal 5.54 101 665
payment to supplier: creation 48.13 154 5776
payment to supplier: removal 3.29 110 395
service: total inventory turnovers 3.73 3989 448
service: total monetary turnovers 6.27 541 752
service: aggregate perf. data 0.72 26231 86
service: refresh index statistics 0.04 227579 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-10-2021 04:40:00 to 22-10-2021 04:40:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6208
TEST_TIME 2 6854
TEST_TIME 3 7335
TEST_TIME 4 6309
TEST_TIME 5 6397
TEST_TIME 6 6934
TEST_TIME 7 6589
TEST_TIME 8 6253
TEST_TIME 9 6383
TEST_TIME 10 6456
TEST_TIME 11 6509
TEST_TIME 12 6565
TEST_TIME 13 6482
TEST_TIME 14 6754
TEST_TIME 15 6707
TEST_TIME 16 6929
TEST_TIME 17 7094
TEST_TIME 18 7287
TEST_TIME 19 7466
TEST_TIME 20 8223
TEST_TIME 21 8407
TEST_TIME 22 8609
TEST_TIME 23 8856
TEST_TIME 24 9360
TEST_TIME 25 9735
TEST_TIME 26 9921
TEST_TIME 27 10079
TEST_TIME 28 10045
TEST_TIME 29 10184
TEST_TIME 30 10196
TEST_TIME 31 10243
TEST_TIME 32 10332
TEST_TIME 33 10198
TEST_TIME 34 9992
TEST_TIME 35 9757
TEST_TIME 36 9618
TEST_TIME 37 9569
TEST_TIME 38 9528
TEST_TIME 39 9429
TEST_TIME 40 9389
TEST_TIME 41 9446
TEST_TIME 42 9588
TEST_TIME 43 9470
TEST_TIME 44 9367
TEST_TIME 45 9344
TEST_TIME 46 9492
TEST_TIME 47 9411
TEST_TIME 48 9388
TEST_TIME 49 9356
TEST_TIME 50 9417
TEST_TIME 51 9394
TEST_TIME 52 9421
TEST_TIME 53 9362
TEST_TIME 54 9257
TEST_TIME 55 9376
TEST_TIME 56 9529
TEST_TIME 57 9418
TEST_TIME 58 9320
TEST_TIME 59 9261
TEST_TIME 60 9232
TEST_TIME 61 9259
TEST_TIME 62 9158
TEST_TIME 63 9077
TEST_TIME 64 9028
TEST_TIME 65 8992
TEST_TIME 66 8993
TEST_TIME 67 9002
TEST_TIME 68 8989
TEST_TIME 69 8982
TEST_TIME 70 9033
TEST_TIME 71 9010
TEST_TIME 72 9013
TEST_TIME 73 9078
TEST_TIME 74 9144
TEST_TIME 75 9165
TEST_TIME 76 9194
TEST_TIME 77 9249
TEST_TIME 78 9205
TEST_TIME 79 9128
TEST_TIME 80 9050
TEST_TIME 81 9024
TEST_TIME 82 9072
TEST_TIME 83 9034
TEST_TIME 84 8967
TEST_TIME 85 8935
TEST_TIME 86 8897
TEST_TIME 87 8895
TEST_TIME 88 8976
TEST_TIME 89 8968
TEST_TIME 90 8962
TEST_TIME 91 8968
TEST_TIME 92 9007
TEST_TIME 93 8977
TEST_TIME 94 8971
TEST_TIME 95 8984
TEST_TIME 96 9086
TEST_TIME 97 9085
TEST_TIME 98 9114
TEST_TIME 99 9068
TEST_TIME 100 9014
TEST_TIME 101 8947
TEST_TIME 102 8974
TEST_TIME 103 8943
TEST_TIME 104 8912
TEST_TIME 105 8874
TEST_TIME 106 8844
TEST_TIME 107 8801
TEST_TIME 108 8827
TEST_TIME 109 8851
TEST_TIME 110 8821
TEST_TIME 111 8799
TEST_TIME 112 8888
TEST_TIME 113 9017
TEST_TIME 114 9007
TEST_TIME 115 9009
TEST_TIME 116 9012
TEST_TIME 117 9019
TEST_TIME 118 8981
TEST_TIME 119 8945
TEST_TIME 120 8878

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-10-2021 04:40:04 to 22-10-2021 04:40:07.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1492791 1492791 0 0.00 0 23266 8 0 0
doc_list_biud 1492791 1492791 0 0.00 0 39302 32 0 0
sp_add_invoice_to_stock 5151 5047 104 2.01 48 321604 21200 0 104
sp_cancel_adding_invoice 5211 5211 0 0.00 5 62133 931 0 0
sp_cancel_client_order 1801 1801 0 0.00 4 15582 419 0 0
sp_cancel_customer_reserve 515818 515818 0 0.00 1 39308 72 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 3 7809 101 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 10119 110 0 0
sp_cancel_supplier_invoice 5809 5805 4 0.06 2 17771 1029 0 4
sp_cancel_supplier_order 393 393 0 0.00 11 1011794 119139 0 0
sp_cancel_write_off 8598 8598 0 0.00 1 15346 143 0 0
sp_client_order 20777 20777 0 0.00 4 38413 404 0 0
sp_customer_reserve 464915 464915 0 0.00 0 81759 210 0 0
sp_fill_shopping_cart 31774 31774 0 0.00 1 29457 44 0 0
sp_fill_shopping_cart_clo_res 464909 464909 0 0.00 0 19856 17 0 0
sp_get_clo_for_invoice 5027 5027 0 0.00 0 25285 320 0 0
sp_kill_cost_storno 14869 14869 0 0.00 0 7797 67 0 0
sp_kill_qstorno_ret_qs2qd 1046432 1046432 0 0.00 0 39282 39 0 0
sp_kill_qty_storno 552802 552802 0 0.00 0 39301 84 0 0
sp_lock_selected_doc 21008 21008 0 0.00 0 10950 7 0 0
sp_make_cost_storno 28113 28113 0 0.00 0 12528 64 0 0
sp_multiply_rows_for_pdistr 28113 28113 0 0.00 0 7750 32 0 0
sp_multiply_rows_for_qdistr 20777 20777 0 0.00 0 38385 200 0 0
sp_pay_from_customer 7161 7161 0 0.00 4 13872 141 0 0
sp_pay_to_supplier 5776 5776 0 0.00 5 15093 154 0 0
sp_payment_common 12937 12937 0 0.00 1 9574 26 0 0
sp_qd_handle_on_cancel_clo 1801 1801 0 0.00 0 15575 384 0 0
sp_qd_handle_on_invoice_upd_sts 10233 10233 0 0.00 0 18161 377 0 0
sp_qd_handle_on_reserve_upd_sts 10154 10154 0 0.00 0 13745 57 0 0
sp_reserve_write_off 10154 10154 0 0.00 7 15473 192 0 0
sp_supplier_invoice 5457 5457 0 0.00 5 68121 3560 0 0
sp_supplier_order 5540 5540 0 0.00 7 40402 1527 0 0
srv_aggregate_perf_data 86 86 0 0.00 3345 736641 26231 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1347 163567 42289 0 0
srv_find_qd_qs_mism 1015801 1015801 0 0.00 0 23265 7 0 0
srv_make_invnt_saldo 448 448 0 0.00 59 96645 3990 0 0
srv_make_money_saldo 752 752 0 0.00 1 17905 541 0 0
srv_recalc_idx_stat 5 5 0 0.00 156445 364306 227579 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5047 5047 0 0.00 0 6363 185 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 3030 125 0 0
t$perf-norm:sp_cancel_client_order 1801 1801 0 0.00 0 1520 11 0 0
t$perf-norm:sp_cancel_customer_reserve 704 704 0 0.00 0 5531 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 1240 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 912 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 452 12 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 27404 1670 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 2109 8 0 0
t$perf-norm:sp_client_order 20777 20777 0 0.00 0 9218 22 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 4977 10 0 0
t$perf-norm:sp_pay_from_customer 7161 7161 0 0.00 0 11233 11 0 0
t$perf-norm:sp_pay_to_supplier 5776 5776 0 0.00 0 4431 8 0 0
t$perf-norm:sp_reserve_write_off 10154 10154 0 0.00 0 8323 11 0 0
t$perf-norm:sp_supplier_invoice 5457 5457 0 0.00 0 4534 21 0 0
t$perf-norm:sp_supplier_order 5540 5540 0 0.00 0 4556 20 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 184 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 30 1 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 4003 25 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 1479 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 370 76 0 0
v_add_invoice_to_stock 10252 10044 208 2.02 0 10960 23 0 208
v_all_customers 37856 37856 0 0.00 0 67 1 0 0
v_all_suppliers 30024 30024 0 0.00 0 34 1 0 0
v_all_wares 41554 41554 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 1268 9 0 0
v_cancel_client_order 3602 3602 0 0.00 0 2685 5 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 3454 9 0 0
v_cancel_customer_reserve 1408 1408 0 0.00 0 3842 10 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 3479 16 0 0
v_cancel_supplier_invoice 886 878 8 0.90 0 8449 31 0 8
v_cancel_supplier_order 786 786 0 0.00 0 405 5 0 0
v_cancel_write_off 2044 2044 0 0.00 0 4192 18 0 0
v_max_id_clo_ord 5540 5540 0 0.00 0 29439 7 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 2035 14 0 0
v_max_id_ord_sup 5457 5457 0 0.00 0 9538 8 0 0
v_max_non_paid_invoice 1761 1761 0 0.00 0 534 3 0 0
v_max_non_paid_realizn 3802 3802 0 0.00 0 3001 3 0 0
v_min_id_clo_ord 5540 5540 0 0.00 0 1608 5 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 8158 11 0 0
v_min_id_ord_sup 5457 5457 0 0.00 0 7957 9 0 0
v_min_non_paid_invoice 5776 5776 0 0.00 0 5453 16 0 0
v_min_non_paid_realizn 7161 7161 0 0.00 0 8966 19 0 0
v_our_firm 10416 10416 0 0.00 0 18 1 0 0
v_reserve_write_off 20308 20308 0 0.00 0 13424 7 0 0
x_lock_depdocs_on_canc_invoice 10577 10577 0 0.00 0 15694 115 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 19597 348 0 0
x_make_qstorno_1000_1200 5540 5540 0 0.00 2 40390 1437 0 0
x_make_qstorno_1200_2000 5457 5457 0 0.00 2 68106 3397 0 0
x_make_qstorno_2100_3300 464915 464915 0 0.00 0 81756 190 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:12 5 8192000000 8798047536 8809205760 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:13 25276 8192000000 9720236000 10017742848 799621696 8.892 100 88 176 0 828223392 7401200 21200496
02:09:50 24504 8192000000 9765694320 10073804800 841742544 9.317 100 90 180 0 873995152 10751728 21500880
02:11:25 9595 8192000000 9815001088 10135433216 888847632 9.788 100 91 182 0 926441168 17364560 20228976
02:12:37 13237 8192000000 9867244208 10201677824 920106448 10.097 100 82 164 0 980157280 41981392 18069440
02:13:52 10422 8192000000 9929783840 10272653312 960015808 10.489 100 80 160 0 1036977424 60563952 16397664
02:15:06 5236 8192000000 9953855904 10310721536 993387328 10.814 100 87 174 0 1079548800 68167824 17993648
02:16:15 4671 8192000000 9987015056 10347585536 1015672512 11.030 100 83 166 0 1099788928 67699744 16416672
02:17:21 3794 8192000000 10010284480 10378059776 1038782880 11.253 100 83 167 0 1124264048 68839088 16642080
02:18:27 2411 8192000000 10015310944 10384371712 1050584000 11.366 100 86 167 1 1137786640 69923136 17279504
02:19:32 3232 8192000000 10027024336 10403033088 1064370800 11.498 100 85 171 3 1141322736 58984928 17967008
02:20:36 8623 8192000000 10034520528 10406932480 1075755312 11.607 100 92 184 0 1145014656 50585648 18673696
02:21:47 5259 8192000000 10012887088 10402152448 1081463056 11.661 100 52 104 2 1120093280 28084432 10545792
02:22:54 6761 8192000000 10000400992 10392215552 1088266272 11.726 100 49 98 2 1115601088 16881216 10453600
02:24:01 69636 8192000000 10009276752 10403516416 1089702896 11.740 100 23 47 0 1124345280 28905536 5736848
02:26:12 5776 8192000000 10031544368 10430574592 1099565104 11.834 100 60 119 2 1140927568 28398848 12963616
02:27:22 6203 8192000000 10011726848 10411536384 1098777584 11.826 100 36 72 0 1118480352 11914128 7788640
02:28:30 9023 8192000000 10019577936 10423578624 1070986416 11.561 98 35 70 2 1113449600 34828272 7634912
02:29:42 7487 8192000000 10011963712 10405195776 1074526960 11.595 99 37 74 1 1116359872 34622224 7210688
02:30:50 3903 8192000000 9960837616 10336219136 1023843104 11.109 98 44 87 0 1048945024 15392048 9709872
02:31:55 33812 8192000000 9942097600 10331185152 953067216 10.421 92 33 66 0 989543648 30230144 6246288
02:33:30 5264 8192000000 9949370224 10314391552 1011843520 10.993 98 67 137 2 1050430880 24236880 14350480
02:34:36 6114 8192000000 9966517488 10360582144 1026256448 11.132 98 43 84 3 1057231152 21853232 9121472
02:35:47 11880 8192000000 9970112000 10352562176 1051000208 11.370 100 27 52 1 1070577840 12812768 6764864
02:36:59 34254 8192000000 9991363808 10379173888 1068611424 11.539 100 41 82 0 1097742960 20645584 8485952
02:38:37 18993 8192000000 10062764016 10454302720 1088451152 11.728 100 54 106 1 1157369760 58472800 10445808
02:40:06 9821 8192000000 10077011024 10476576768 1105817472 11.893 100 45 87 1 1176109232 60901168 9390592
02:41:19 3282 8192000000 10090595968 10481459200 1116842800 11.997 100 67 132 0 1189460352 57628704 14988848
02:42:36 7193 8192000000 10117570176 10517110784 1130294320 12.124 100 61 119 2 1221119376 78586912 12238144
02:43:49 53246 8192000000 10149880096 10560462848 1139269328 12.209 100 39 78 1 1250303632 102233136 8801168
02:45:51 8563 8192000000 10209839712 10614247424 1161329792 12.416 100 67 131 0 1311314352 136520576 13463984
02:47:03 1347 8192000000 10241482352 10641346560 1161723376 12.419 100 79 159 0 1342595392 163373920 17498096
02:48:11 7875 8192000000 10273495184 10675445760 1176808128 12.560 100 73 143 1 1377312816 185914208 14590480
02:49:24 2530 8192000000 10307530096 10713096192 1176735952 12.560 99 81 158 0 1402712416 208427488 17548976
02:50:35 2131 8192000000 10339962848 10747707392 1185099520 12.638 100 81 162 1 1440080096 236896624 18083952
02:51:40 4040 8192000000 10345985424 10760282112 1184406320 12.631 100 63 125 0 1449989056 250132768 15449968
02:52:52 4004 8192000000 10380447168 10792230912 1174531536 12.539 99 75 150 1 1470755008 278378672 17844800
02:54:02 2710 8192000000 10385114224 10808754176 1192855136 12.710 100 85 170 0 1482123296 269505920 19762240
02:55:09 3419 8192000000 10405380672 10833301504 1176962400 12.562 100 80 159 0 1500846608 304737312 19146896
02:56:18 5960 8192000000 10421290464 10840330240 1165112736 12.451 100 73 146 3 1515234464 332625168 17496560
02:57:32 1961 8192000000 10366037984 10783281152 1164281488 12.443 100 84 167 1 1476415408 292624000 19509920
02:58:36 13432 8192000000 10335393888 10759499776 1159300640 12.397 100 43 86 2 1440304320 270226400 10777280
02:59:51 46112 8192000000 10294721168 10753200128 1133533408 12.155 98 23 46 1 1403278272 263241232 6503632
03:01:44 22192 8192000000 10195063200 10657734656 1128387792 12.106 98 25 50 0 1303358080 168568496 6401792
03:03:08 26551 8192000000 10141313776 10569957376 1112396976 11.955 99 29 58 0 1230690720 111512512 6781232
03:04:54 47898 8192000000 10108776048 10531508224 1095770880 11.797 98 26 53 1 1190920272 89078592 6070800
03:06:43 60321 8192000000 10076708208 10549551104 1095682912 11.797 98 7 14 1 1175288592 78328256 1277424
03:08:53 48737 8192000000 10071278832 10528059392 1100288208 11.840 98 29 59 0 1178186528 71486736 6411584
03:11:10 84692 8192000000 10067215456 10487414784 1104056224 11.876 97 38 72 0 1140748944 29297760 7394960
03:14:04 41939 8192000000 10086290496 10510348288 1102652816 11.863 97 40 78 2 1137849728 27057552 8139360
03:15:50 22933 8192000000 10138267504 10566012928 1131318320 12.134 100 26 52 0 1214038288 76925152 5794816
03:17:15 33313 8192000000 10154635104 10584428544 1087816944 11.722 96 46 92 2 1224584112 125970432 10796736
03:18:54 70389 8192000000 10075439712 10554859520 1077199248 11.621 96 17 34 0 1178276432 96833216 4243968
03:21:05 20644 8192000000 10100221360 10537054208 1121505040 12.041 100 19 38 0 1203716608 78232320 3979248
03:22:30 40667 8192000000 10109855440 10565509120 1116215696 11.991 99 11 22 0 1206238464 87817248 2205520
03:24:12 31798 8192000000 10084564112 10508726272 1123381904 12.059 99 39 77 1 1164178640 32397744 8398992
03:25:48 57991 8192000000 10112484112 10548678656 1137175376 12.189 100 14 28 0 1218193088 77946688 3071024
03:27:52 65799 8192000000 10095369888 10554359808 1118183312 12.010 98 16 32 0 1182319040 60793680 3342048
03:29:59 79353 8192000000 10127959200 10587000832 1105968176 11.894 97 14 27 2 1158594704 49898304 2728224
03:32:23 52833 8192000000 10132234576 10644508672 1124267488 12.067 99 24 48 1 1230610272 101403696 4939088
03:34:18 104491 8192000000 10078201072 10531086336 1121989728 12.046 99 23 44 1 1174783552 47905648 4888176
03:37:11 17990 8192000000 10089393200 10557161472 1141485456 12.230 100 23 46 1 1188268832 42117936 4665440
03:38:30 45114 8192000000 10068656496 10567655424 1140363392 12.219 100 2 4 0 1175341312 34902176 75744
03:40:19 39529 8192000000 10076885104 10533957632 1146113040 12.273 100 7 13 1 1186077856 38712384 1252432
03:42:24 40667 8192000000 10053954096 10494246912 1094324112 11.784 97 21 43 1 1135639536 37466288 3849136
03:44:05 52768 8192000000 10072116576 10533965824 1083649184 11.682 96 27 54 1 1137464048 48289200 5525664
03:46:01 15309 8192000000 10063392256 10492342272 1099323376 11.831 98 43 86 1 1155690848 46563536 9803936
03:47:23 31610 8192000000 10065127376 10533208064 1101808928 11.855 99 29 58 2 1173229152 65173936 6246288
03:48:55 52009 8192000000 10098390272 10527539200 1111222928 11.944 99 30 60 0 1166634592 48923552 6488112
03:50:55 66712 8192000000 10076735152 10543841280 1114480144 11.975 99 13 26 0 1199629424 82418896 2730384
03:53:06 39404 8192000000 10091433152 10544291840 1105360128 11.888 98 26 52 0 1174911296 64603456 4947712
03:54:46 33460 8192000000 10050784496 10489843712 1093134400 11.772 98 26 52 0 1151948160 53890624 4923136
03:56:20 92658 8192000000 10054604368 10489364480 1074920304 11.599 97 28 55 0 1134977168 54388256 5668608
03:58:53 22931 8192000000 10077823936 10521501696 1117435296 12.003 100 34 67 1 1175206816 50264368 7507152
04:00:25 65377 8192000000 10049214352 10531598336 1082804304 11.674 97 6 12 0 1132221776 47927776 1489696
04:02:30 42028 8192000000 10050819824 10498756608 1101608608 11.853 99 18 36 1 1141661056 37082304 2970144
04:04:13 67454 8192000000 10066390592 10555179008 1083421696 11.680 98 14 28 0 1176631072 89914544 3294832
04:06:25 71889 8192000000 10076106336 10515726336 1077453200 11.623 97 21 42 0 1111377040 29131168 4792672
04:08:43 71836 8192000000 10069235024 10546769920 1092366896 11.765 97 19 38 0 1163189552 67286240 3536416
04:10:54 59878 8192000000 10110205024 10561753088 1101308288 11.850 97 25 50 0 1176549104 70172000 5068816
04:13:02 163567 8192000000 10070443280 10530131968 1133271056 12.152 99 11 22 0 1224236960 88732880 2233024
04:16:55 109169 8192000000 10084902256 10529607680 1119314560 12.021 98 32 65 0 1160890288 34511696 7064032
04:19:52 70022 8192000000 10068933152 10536751104 1122851120 12.054 98 17 34 0 1157146240 30390208 3904912
04:22:03 71462 8192000000 10094144736 10547904512 1102970512 11.866 96 17 34 0 1167652960 60611056 4071392
04:24:19 53452 8192000000 10182078672 10637430784 1138581072 12.202 99 15 30 1 1268180352 125991152 3608128
04:26:16 50933 8192000000 10174220448 10619375616 1120655136 12.033 97 25 50 0 1256682064 130283072 5743856
04:28:11 69833 8192000000 10238163344 10738421760 1145899232 12.271 99 17 34 0 1317716704 167928864 3888608
04:31:05 25818 8192000000 10084850896 10506706944 1127595904 12.099 99 50 98 0 1160768000 23101072 10071024
04:32:34 21242 8192000000 10076761392 10502242304 1092795824 11.769 96 49 98 0 1149392320 46939872 9656624
04:33:57 81595 8192000000 10105297184 10592800768 1115537488 11.985 99 31 61 0 1211346656 89694400 6114768
04:36:22 44065 8192000000 10045961744 10498924544 1106456864 11.899 98 11 22 0 1140289616 31472176 2360576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-10-2021 04:40:14 to 22-10-2021 04:40:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-10-2021 04:40:30 to 22-10-2021 04:40:32.

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          228739
MON$OLDEST_ACTIVE               229481
MON$OLDEST_SNAPSHOT             229481
MON$NEXT_TRANSACTION            229481
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                       2022464
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2636 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2636 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2636 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2636 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    Fri Oct 22 04:37:21 2021
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:32: 
:33: 
:34: mscs-test-161.budzdorov.ru    Fri Oct 22 04:37:44 2021
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
--- end of diff output ---

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

22.10.21 04:40:33. 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-10-22 04:40:36 No severe PSQL-related problems occured
22.10.21 04:40:37. End of report.