Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.11.2021 07:37
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 16647304 kB
MemAvailable 31582108 kB
Buffers 0 kB
Cached 15015592 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 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-11-18 07:37:07
DTS_END                         2021-11-18 07:37:07.0180
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/45826
TRN_ID                          229850
ATT_ID                          695
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: 8920.17 25975 1070421
customer order: creation 168.53 454 20224
customer order: refuse 14.18 392 1701
order to supplier: creation 46.14 1473 5537
order to supplier: removal 3.46 123393 415
invoice (draft): creation 46.56 3788 5587
invoice (draft): removal 50.66 1048 6079
invoice accept: apply 41.70 20304 5004
invoice accept: cancel 45.51 979 5461
customer reserve: creation 3768.43 203 452212
customer reserve: removal 4444.95 74 533394
realization accept: apply 83.82 229 10058
realization accept: cancel 78.67 141 9440
payment from customer: creation 58.38 140 7006
payment from customer: removal 5.53 73 663
payment to supplier: creation 49.08 182 5889
payment to supplier: removal 3.37 89 404
service: total inventory turnovers 3.91 3681 469
service: total monetary turnovers 6.32 582 758
service: aggregate perf. data 0.96 14990 115
service: refresh index statistics 0.04 347281 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-11-2021 07:38:03 to 18-11-2021 07:38:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6016
TEST_TIME 2 6734
TEST_TIME 3 5963
TEST_TIME 4 6073
TEST_TIME 5 5918
TEST_TIME 6 5657
TEST_TIME 7 5590
TEST_TIME 8 5482
TEST_TIME 9 5762
TEST_TIME 10 6258
TEST_TIME 11 6806
TEST_TIME 12 7174
TEST_TIME 13 7154
TEST_TIME 14 7312
TEST_TIME 15 7703
TEST_TIME 16 7744
TEST_TIME 17 7936
TEST_TIME 18 7996
TEST_TIME 19 8354
TEST_TIME 20 8717
TEST_TIME 21 8999
TEST_TIME 22 9111
TEST_TIME 23 9214
TEST_TIME 24 9480
TEST_TIME 25 9468
TEST_TIME 26 9496
TEST_TIME 27 10066
TEST_TIME 28 10323
TEST_TIME 29 10947
TEST_TIME 30 11641
TEST_TIME 31 11511
TEST_TIME 32 11153
TEST_TIME 33 10830
TEST_TIME 34 10724
TEST_TIME 35 10818
TEST_TIME 36 10773
TEST_TIME 37 10718
TEST_TIME 38 10717
TEST_TIME 39 10537
TEST_TIME 40 10409
TEST_TIME 41 10320
TEST_TIME 42 10298
TEST_TIME 43 10207
TEST_TIME 44 10051
TEST_TIME 45 9954
TEST_TIME 46 9947
TEST_TIME 47 9796
TEST_TIME 48 9797
TEST_TIME 49 9810
TEST_TIME 50 9727
TEST_TIME 51 9684
TEST_TIME 52 9578
TEST_TIME 53 9536
TEST_TIME 54 9562
TEST_TIME 55 9561
TEST_TIME 56 9507
TEST_TIME 57 9458
TEST_TIME 58 9691
TEST_TIME 59 9642
TEST_TIME 60 9570
TEST_TIME 61 9548
TEST_TIME 62 9587
TEST_TIME 63 9653
TEST_TIME 64 9556
TEST_TIME 65 9451
TEST_TIME 66 9377
TEST_TIME 67 9349
TEST_TIME 68 9317
TEST_TIME 69 9267
TEST_TIME 70 9240
TEST_TIME 71 9190
TEST_TIME 72 9189
TEST_TIME 73 9135
TEST_TIME 74 9143
TEST_TIME 75 9104
TEST_TIME 76 9137
TEST_TIME 77 9164
TEST_TIME 78 9128
TEST_TIME 79 9161
TEST_TIME 80 9166
TEST_TIME 81 9164
TEST_TIME 82 9274
TEST_TIME 83 9248
TEST_TIME 84 9274
TEST_TIME 85 9256
TEST_TIME 86 9165
TEST_TIME 87 9114
TEST_TIME 88 9105
TEST_TIME 89 9049
TEST_TIME 90 9044
TEST_TIME 91 8993
TEST_TIME 92 9010
TEST_TIME 93 9006
TEST_TIME 94 8977
TEST_TIME 95 8950
TEST_TIME 96 8939
TEST_TIME 97 8961
TEST_TIME 98 9041
TEST_TIME 99 9111
TEST_TIME 100 9080
TEST_TIME 101 9141
TEST_TIME 102 9133
TEST_TIME 103 9156
TEST_TIME 104 9161
TEST_TIME 105 9147
TEST_TIME 106 9068
TEST_TIME 107 9074
TEST_TIME 108 9019
TEST_TIME 109 9018
TEST_TIME 110 8974
TEST_TIME 111 8980
TEST_TIME 112 8940
TEST_TIME 113 8971
TEST_TIME 114 8934
TEST_TIME 115 8968
TEST_TIME 116 8993
TEST_TIME 117 8994
TEST_TIME 118 9002
TEST_TIME 119 8995
TEST_TIME 120 8930

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-11-2021 07:38:07 to 18-11-2021 07:38:10.

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 1488929 1488929 0 0.00 0 29995 8 0 0
doc_list_biud 1488929 1488929 0 0.00 0 81215 34 0 0
sp_add_invoice_to_stock 5160 5004 156 3.02 41 181609 20304 0 156
sp_cancel_adding_invoice 5461 5461 0 0.00 4 69638 979 0 0
sp_cancel_client_order 1701 1701 0 0.00 4 30763 393 0 0
sp_cancel_customer_reserve 533394 533394 0 0.00 1 73568 75 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 2 2473 74 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 4602 90 0 0
sp_cancel_supplier_invoice 6093 6079 14 0.22 2 81255 1048 0 14
sp_cancel_supplier_order 415 415 0 0.00 6 1159858 123394 0 0
sp_cancel_write_off 9440 9440 0 0.00 1 19041 141 0 0
sp_client_order 20224 20224 0 0.00 4 63397 455 0 0
sp_customer_reserve 452212 452212 0 0.00 0 51660 203 0 0
sp_fill_shopping_cart 31345 31345 0 0.00 1 26556 46 0 0
sp_fill_shopping_cart_clo_res 452195 452195 0 0.00 0 22999 17 0 0
sp_get_clo_for_invoice 4987 4987 0 0.00 0 32098 347 0 0
sp_kill_cost_storno 15968 15968 0 0.00 0 19038 76 0 0
sp_kill_qstorno_ret_qs2qd 1082722 1082722 0 0.00 0 81213 40 0 0
sp_kill_qty_storno 571535 571535 0 0.00 0 81214 87 0 0
sp_lock_selected_doc 20656 20656 0 0.00 0 7673 6 0 0
sp_make_cost_storno 27940 27940 0 0.00 0 29991 67 0 0
sp_multiply_rows_for_pdistr 27940 27940 0 0.00 0 12586 39 0 0
sp_multiply_rows_for_qdistr 20224 20224 0 0.00 0 20762 214 0 0
sp_pay_from_customer 7006 7006 0 0.00 4 29686 141 0 0
sp_pay_to_supplier 5889 5889 0 0.00 5 30022 183 0 0
sp_payment_common 12895 12895 0 0.00 1 19554 29 0 0
sp_qd_handle_on_cancel_clo 1701 1701 0 0.00 0 30740 349 0 0
sp_qd_handle_on_invoice_upd_sts 10448 10448 0 0.00 0 32910 420 0 0
sp_qd_handle_on_reserve_upd_sts 10058 10058 0 0.00 0 30343 73 0 0
sp_reserve_write_off 10058 10058 0 0.00 7 30698 230 0 0
sp_supplier_invoice 5587 5587 0 0.00 6 139614 3789 0 0
sp_supplier_order 5537 5537 0 0.00 6 66624 1473 0 0
srv_aggregate_perf_data 115 115 0 0.00 2552 157695 14991 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1177 130609 42736 0 0
srv_find_qd_qs_mism 1023261 1023261 0 0.00 0 22228 7 0 0
srv_make_invnt_saldo 469 469 0 0.00 61 87949 3682 0 0
srv_make_money_saldo 758 758 0 0.00 1 10333 583 0 0
srv_recalc_idx_stat 5 5 0 0.00 149903 513966 347281 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5004 5004 0 0.00 0 11534 194 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 1 6369 106 0 0
t$perf-norm:sp_cancel_client_order 1701 1701 0 0.00 0 26132 30 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 4384 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 720 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 904 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 2980 24 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 31015 1792 0 0
t$perf-norm:sp_cancel_write_off 930 930 0 0.00 0 1589 9 0 0
t$perf-norm:sp_client_order 20224 20224 0 0.00 0 24323 21 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 25975 35 0 0
t$perf-norm:sp_pay_from_customer 7006 7006 0 0.00 0 5394 9 0 0
t$perf-norm:sp_pay_to_supplier 5889 5889 0 0.00 0 4935 10 0 0
t$perf-norm:sp_reserve_write_off 10058 10058 0 0.00 0 23970 12 0 0
t$perf-norm:sp_supplier_invoice 5587 5587 0 0.00 0 6370 22 0 0
t$perf-norm:sp_supplier_order 5537 5537 0 0.00 0 7892 22 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 211 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 7 1 0 0
t$perf-norm:srv_make_invnt_saldo 469 469 0 0.00 0 1390 18 0 0
t$perf-norm:srv_make_money_saldo 758 758 0 0.00 0 897 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 15 5 0 0
v_add_invoice_to_stock 10286 9974 312 3.03 0 5280 19 0 312
v_all_customers 36614 36614 0 0.00 0 20 1 0 0
v_all_suppliers 30948 30948 0 0.00 0 110 1 0 0
v_all_wares 40448 40448 0 0.00 0 76 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 13577 37 0 0
v_cancel_client_order 3402 3402 0 0.00 0 6512 13 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 2465 6 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 819 4 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 1677 11 0 0
v_cancel_supplier_invoice 852 824 28 3.28 0 952 17 0 28
v_cancel_supplier_order 830 830 0 0.00 0 23869 99 0 0
v_cancel_write_off 1860 1860 0 0.00 0 17134 28 0 0
v_max_id_clo_ord 5537 5537 0 0.00 0 1874 2 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 3307 24 0 0
v_max_id_ord_sup 5584 5584 0 0.00 0 6781 5 0 0
v_max_non_paid_invoice 1536 1536 0 0.00 0 941 4 0 0
v_max_non_paid_realizn 3877 3877 0 0.00 0 3202 5 0 0
v_min_id_clo_ord 5537 5537 0 0.00 0 7029 11 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 5584 15 0 0
v_min_id_ord_sup 5584 5584 0 0.00 0 8032 10 0 0
v_min_non_paid_invoice 5889 5889 0 0.00 0 19550 20 0 0
v_min_non_paid_realizn 7006 7006 0 0.00 0 6555 16 0 0
v_our_firm 10092 10092 0 0.00 0 33 1 0 0
v_reserve_write_off 20116 20116 0 0.00 0 22581 13 0 0
x_lock_depdocs_on_canc_invoice 11128 11128 0 0.00 0 15777 124 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 6806 382 0 0
x_make_qstorno_1000_1200 5537 5537 0 0.00 2 66611 1373 0 0
x_make_qstorno_1200_2000 5587 5587 0 0.00 2 139501 3628 0 0
x_make_qstorno_2100_3300 452212 452212 0 0.00 0 51659 183 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-11-2021 07:38:11 to 18-11-2021 07:38:15.

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
05:07:10 4 8192000000 8798579936 8809799680 4478816 0.054 2 1 2 0 4578208 63616 35776
05:08:11 21123 8192000000 9726029968 10023620608 803061264 8.927 100 83 166 0 830509472 7715168 19733040
05:09:42 31340 8192000000 9775016080 10085093376 856233920 9.462 100 92 184 0 890759552 12941088 21584544
05:11:24 14394 8192000000 9813792192 10137645056 890987792 9.809 100 84 168 0 927967216 18351632 18627792
05:12:47 11919 8192000000 9896493296 10230321152 928119216 10.176 100 82 164 0 994511200 48394752 17997232
05:14:06 10909 8192000000 9940331360 10284814336 964380400 10.532 100 78 156 2 1045210816 64429040 16401376
05:15:20 5845 8192000000 9978635280 10333413376 997375360 10.853 100 83 166 0 1090823488 76594448 16853680
05:16:28 4158 8192000000 9977648752 10342252544 1012171488 10.996 100 68 136 0 1099866560 74174080 13520992
05:17:36 2513 8192000000 9997543824 10366205952 1040550880 11.270 100 82 166 0 1112833392 55306704 16975808
05:18:40 2766 8192000000 10047373952 10416017408 1054195984 11.401 100 91 181 0 1156500656 84086608 18218064
05:19:46 1800 8192000000 10022757872 10402246656 1067859504 11.532 100 83 166 2 1143738032 58218512 17660016
05:20:49 5410 8192000000 9990207312 10391482368 1072042976 11.572 100 47 93 2 1106842800 24783024 10016800
05:21:56 6774 8192000000 10002829600 10406744064 1079764720 11.645 100 31 62 1 1108182688 21446848 6971120
05:23:07 8081 8192000000 10010721856 10402619392 1091702096 11.759 100 43 87 0 1127062496 25428896 9931504
05:24:16 11570 8192000000 10017948016 10405347328 1090805120 11.750 100 56 110 4 1115971840 11763008 13403712
05:25:28 3195 8192000000 10033336000 10439598080 1096303408 11.803 100 41 80 1 1148730480 44008496 8418576
05:26:32 4087 8192000000 10013447232 10407215104 1099984080 11.837 100 34 68 1 1125657744 17999392 7674272
05:27:37 30438 8192000000 10013670576 10425450496 1066896896 11.522 97 24 48 0 1098672240 27177648 4597696
05:29:08 7056 8192000000 9998709024 10390573056 1056946144 11.427 98 31 61 1 1098626416 35164496 6515776
05:30:16 7424 8192000000 9966228544 10344534016 1009366784 10.969 96 29 60 1 1038016704 22556560 6093360
05:31:23 51772 8192000000 9948227392 10348433408 998762464 10.867 95 24 50 0 1032988288 28310464 5915360
05:33:18 4260 8192000000 9956472736 10330046464 1015126560 11.025 98 61 120 1 1041540112 12936592 13476960
05:34:24 11380 8192000000 9956603456 10338160640 1047115648 11.333 100 42 84 1 1068501584 11009616 10376320
05:35:38 6431 8192000000 9963400416 10353627136 1055750256 11.416 100 39 78 2 1078356480 13868912 8737312
05:36:46 17772 8192000000 9994943152 10392813568 1060298320 11.459 100 40 80 1 1103101376 34949904 7853152
05:38:13 3323 8192000000 10019723808 10408239104 1074819664 11.598 100 55 110 2 1120009888 34160720 11029504
05:39:25 16765 8192000000 10053338800 10445770752 1090800336 11.750 100 50 100 0 1169067712 67751280 10516096
05:40:46 21229 8192000000 10068966800 10473062400 1107288896 11.907 100 43 86 0 1176322576 59844320 9189360
05:42:23 26947 8192000000 10104483584 10518372352 1119910208 12.026 100 34 68 0 1213022992 85564832 7547952
05:43:52 29821 8192000000 10146044432 10556583936 1129351360 12.115 100 35 70 0 1253935792 116338704 8245728
05:45:30 11860 8192000000 10184813728 10588528640 1137884496 12.196 100 46 92 2 1280610368 132107120 10618752
05:46:50 2157 8192000000 10267283232 10667880448 1160766000 12.410 100 79 152 3 1375042976 198116192 16160784
05:47:56 6922 8192000000 10272998384 10683916288 1173681264 12.531 100 67 135 0 1379772512 191124176 14967072
05:49:08 1814 8192000000 10298454144 10713239552 1185487008 12.641 100 83 161 3 1403359824 200195216 17677600
05:50:21 1648 8192000000 10325132272 10724651008 1183418288 12.622 100 90 180 0 1419258304 215880688 19959328
05:51:28 4903 8192000000 10378158768 10784489472 1184268240 12.630 100 77 152 0 1475540016 273920304 17351472
05:52:37 1177 8192000000 10389930720 10789875712 1172128720 12.517 99 93 186 3 1483723328 290731136 20863472
05:53:46 2783 8192000000 10409010256 10811371520 1167549424 12.474 100 96 188 0 1500323648 311426432 21347792
05:54:56 1249 8192000000 10435977728 10846740480 1179316000 12.584 100 86 164 2 1526962864 328894176 18752688
05:56:04 2650 8192000000 10434975216 10845679616 1152634848 12.334 99 86 170 0 1519389392 346439808 20314736
05:57:14 3405 8192000000 10410831600 10810560512 1171547936 12.511 100 81 161 1 1507865376 317542000 18775440
05:58:19 11092 8192000000 10412095664 10848133120 1160450416 12.407 100 39 78 0 1516415120 346062032 9902672
05:59:33 7047 8192000000 10388201904 10800771072 1151678016 12.325 99 56 110 0 1485791408 321194112 12919280
06:00:41 7081 8192000000 10391901632 10827751424 1153525984 12.343 100 42 84 0 1493734400 329918080 10290336
06:01:51 7223 8192000000 10379137056 10807746560 1140503296 12.220 99 46 92 0 1477497952 325583840 11410816
06:03:05 7624 8192000000 10431513488 10856050688 1149364192 12.304 100 48 93 1 1524718720 363224176 12130352
06:04:22 23387 8192000000 10372644880 10837471232 1125857888 12.082 97 30 59 1 1452209120 318264208 8087024
06:05:50 13570 8192000000 10300575904 10809282560 1124354016 12.068 99 30 60 1 1433603232 301355184 7894032
06:07:21 26735 8192000000 10097912016 10614661120 1118329776 12.011 99 16 32 1 1200473616 78389808 3754032
06:08:50 125782 8192000000 10167077712 10607480832 1139264192 12.209 100 34 68 0 1260077152 113293696 7519264
06:11:57 6981 8192000000 10170227360 10591223808 1137045776 12.188 100 77 153 1 1255646000 101593104 17007120
06:13:09 130609 8192000000 10100743040 10593542144 1102681040 11.863 96 14 28 0 1207771888 102197952 2892896
06:16:43 33876 8192000000 10089063424 10548514816 1120502192 12.032 99 10 20 1 1181599040 59066704 2030144
06:18:18 35274 8192000000 10116554880 10560458752 1113232752 11.963 98 15 30 1 1192266880 75389824 3644304
06:19:54 41223 8192000000 10088244112 10528034816 1106432432 11.899 98 17 34 0 1180786016 70711040 3642544
06:21:42 93112 8192000000 10068587632 10566180864 1108161792 11.915 98 20 40 1 1188633376 75883168 4588416
06:24:25 34232 8192000000 10137127120 10567954432 1140329840 12.219 99 45 90 0 1219989088 69918720 9740528
06:26:05 47088 8192000000 10088786128 10595446784 1144600704 12.259 100 11 22 0 1223677312 76438112 2638496
06:28:00 83734 8192000000 10064228208 10517786624 1137390416 12.191 99 11 22 0 1171708320 32463248 1854656
06:30:30 45154 8192000000 10129037536 10586701824 1159728912 12.401 100 37 74 0 1239207008 71625040 7853056
06:32:17 42905 8192000000 10085440928 10536611840 1126900016 12.092 97 17 34 0 1176357344 45755440 3701888
06:34:04 87272 8192000000 10122231744 10671538176 1118531424 12.013 97 11 22 1 1251781760 130630048 2620288
06:36:36 37477 8192000000 10052485200 10480672768 1083684304 11.683 95 22 42 1 1104851264 17027760 4139200
06:38:15 11888 8192000000 10092936912 10521595904 1123207120 12.057 99 44 88 1 1195451792 63307904 8936768
06:39:29 109610 8192000000 10044635184 10471727104 1031466048 11.183 91 22 45 1 1063428512 27330880 4631584
06:42:22 88132 8192000000 10056094560 10508382208 1106090880 11.895 99 30 60 0 1160291888 47857616 6343392
06:44:59 56368 8192000000 10073821024 10554830848 1103262512 11.869 98 13 26 0 1169119680 63479056 2378112
06:46:56 40961 8192000000 10054780112 10497679360 1091707104 11.759 98 19 38 0 1152891328 56900832 4283392
06:49:22 26016 8192000000 10044778672 10484989952 1113028768 11.961 100 36 69 1 1150274816 29293056 7952992
06:50:56 81160 8192000000 10051522064 10511024128 1100668880 11.844 98 22 44 0 1140926384 35058640 5198864
06:53:18 38778 8192000000 10048477616 10480025600 1074377920 11.594 96 22 44 1 1111389952 32705824 4306208
06:55:01 56313 8192000000 10059077312 10492817408 1084869696 11.694 97 15 30 0 1113155552 25647824 2638032
06:57:03 43819 8192000000 10106786784 10540732416 1114964912 11.979 99 28 56 0 1195954976 75566912 5423152
06:59:15 52864 8192000000 10060420320 10542845952 1108142032 11.915 98 33 64 1 1202070672 87547184 6381456
07:01:14 63837 8192000000 10092911488 10548404224 1127109216 12.094 99 18 36 0 1214072208 83131984 3831008
07:03:18 107153 8192000000 10079764368 10538987520 1132332336 12.143 100 28 56 0 1179526000 41479600 5714064
07:06:09 76817 8192000000 10089829936 10564034560 1137292240 12.190 100 13 26 1 1203939648 63527760 3119648
07:08:27 82967 8192000000 10080966224 10554699776 1130557904 12.127 99 24 48 0 1206862896 70543472 5761520
07:11:05 46532 8192000000 10061704704 10497544192 1105611520 11.891 98 26 52 0 1146863024 35127936 6123568
07:12:53 71961 8192000000 10158093264 10630127616 1128677200 12.109 99 28 58 0 1225910448 91197344 6035904
07:15:17 81063 8192000000 10074963712 10587328512 1124937456 12.074 99 9 19 0 1199981936 72909872 2134608
07:17:38 21451 8192000000 10109022272 10540744704 1124762736 12.072 98 61 122 0 1171622416 34215312 12644368
07:19:00 59319 8192000000 10098687600 10584408064 1109797248 11.930 97 19 38 0 1146944352 33278016 3869088
07:21:00 119513 8192000000 10079959184 10531696640 1110828224 11.940 98 18 36 1 1171742464 57367056 3547184
07:25:00 59716 8192000000 10066837888 10573742080 1144375808 12.257 100 9 18 0 1199405536 53248992 1780736
07:27:04 83476 8192000000 10049049728 10556297216 1136293088 12.181 100 7 14 0 1181978048 44339520 1345440
07:29:37 51947 8192000000 10077856384 10521108480 1062290992 11.478 94 21 42 0 1129217360 62634224 4292144
07:31:39 94557 8192000000 10107587424 10612207616 1094224144 11.783 97 19 38 0 1176574288 77803968 4546176
07:34:36 30387 8192000000 10078852032 10519175168 1093944288 11.780 97 23 46 0 1145830720 47358752 4527680
07:36:06 34039 8192000000 10072158576 10506846208 1109784000 11.930 99 33 66 0 1154732848 38572096 6376752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-11-2021 07:38:17 to 18-11-2021 07:38:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 156 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-11-2021 07:38:33 to 18-11-2021 07:38:35.

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          228170
MON$OLDEST_ACTIVE               231402
MON$OLDEST_SNAPSHOT             231402
MON$NEXT_TRANSACTION            231402
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                       2054176
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             796
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2667 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2667 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2667 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2667 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 ---
:46: 
:47: mscs-test-161.budzdorov.ru    Thu Nov 18 07:37:07 2021
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Thu Nov 18 07:37:11 2021
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
:54: 
:55: mscs-test-161.budzdorov.ru    Thu Nov 18 07:37:11 2021
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:57: 
:58: 
:59: mscs-test-161.budzdorov.ru    Thu Nov 18 07:37:15 2021
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:61: 
:62: 
:63: mscs-test-161.budzdorov.ru    Thu Nov 18 07:37:16 2021
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Thu Nov 18 07:37:17 2021
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:69: 
--- end of diff output ---

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

18.11.21 07:38:36. 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-11-18 07:38:38 No severe PSQL-related problems occured
18.11.21 07:38:40. End of report.