Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.11.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 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 16930888 kB
MemAvailable 31580308 kB
Buffers 20 kB
Cached 14740852 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-22 04:37:08
DTS_END                         2021-11-22 04:37:08.1310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50366
TRN_ID                          227343
ATT_ID                          659
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: 8507.98 24929 1020957
customer order: creation 165.74 425 19889
customer order: refuse 14.02 342 1682
order to supplier: creation 43.96 1456 5275
order to supplier: removal 3.08 122356 369
invoice (draft): creation 45.23 3908 5427
invoice (draft): removal 46.62 1051 5594
invoice accept: apply 39.81 21042 4777
invoice accept: cancel 42.37 1015 5084
customer reserve: creation 3681.31 208 441757
customer reserve: removal 4155.36 72 498643
realization accept: apply 81.57 195 9788
realization accept: cancel 65.63 148 7875
payment from customer: creation 56.11 138 6733
payment from customer: removal 5.55 89 666
payment to supplier: creation 47.93 138 5752
payment to supplier: removal 3.25 94 390
service: total inventory turnovers 3.74 3633 449
service: total monetary turnovers 5.85 604 702
service: aggregate perf. data 0.83 15217 100
service: refresh index statistics 0.04 326447 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-11-2021 04:39:32 to 22-11-2021 04:39:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6887
TEST_TIME 2 5118
TEST_TIME 3 4642
TEST_TIME 4 4704
TEST_TIME 5 5663
TEST_TIME 6 5792
TEST_TIME 7 5738
TEST_TIME 8 5614
TEST_TIME 9 5915
TEST_TIME 10 6058
TEST_TIME 11 6203
TEST_TIME 12 6159
TEST_TIME 13 6562
TEST_TIME 14 6734
TEST_TIME 15 6871
TEST_TIME 16 6855
TEST_TIME 17 6930
TEST_TIME 18 7343
TEST_TIME 19 7666
TEST_TIME 20 7974
TEST_TIME 21 8129
TEST_TIME 22 8186
TEST_TIME 23 8206
TEST_TIME 24 8421
TEST_TIME 25 8911
TEST_TIME 26 9963
TEST_TIME 27 10035
TEST_TIME 28 10141
TEST_TIME 29 9807
TEST_TIME 30 9583
TEST_TIME 31 9518
TEST_TIME 32 9378
TEST_TIME 33 9304
TEST_TIME 34 9351
TEST_TIME 35 9253
TEST_TIME 36 9075
TEST_TIME 37 8899
TEST_TIME 38 8763
TEST_TIME 39 8778
TEST_TIME 40 8572
TEST_TIME 41 8863
TEST_TIME 42 8958
TEST_TIME 43 9002
TEST_TIME 44 8871
TEST_TIME 45 9003
TEST_TIME 46 9029
TEST_TIME 47 8998
TEST_TIME 48 8929
TEST_TIME 49 8959
TEST_TIME 50 8936
TEST_TIME 51 8964
TEST_TIME 52 8921
TEST_TIME 53 8933
TEST_TIME 54 9039
TEST_TIME 55 8922
TEST_TIME 56 8790
TEST_TIME 57 8705
TEST_TIME 58 8699
TEST_TIME 59 8695
TEST_TIME 60 8643
TEST_TIME 61 8584
TEST_TIME 62 8562
TEST_TIME 63 8493
TEST_TIME 64 8502
TEST_TIME 65 8612
TEST_TIME 66 8644
TEST_TIME 67 8756
TEST_TIME 68 8718
TEST_TIME 69 8866
TEST_TIME 70 8825
TEST_TIME 71 8820
TEST_TIME 72 8886
TEST_TIME 73 8911
TEST_TIME 74 8947
TEST_TIME 75 8876
TEST_TIME 76 8794
TEST_TIME 77 8743
TEST_TIME 78 8736
TEST_TIME 79 8680
TEST_TIME 80 8754
TEST_TIME 81 8689
TEST_TIME 82 8634
TEST_TIME 83 8602
TEST_TIME 84 8607
TEST_TIME 85 8626
TEST_TIME 86 8616
TEST_TIME 87 8645
TEST_TIME 88 8646
TEST_TIME 89 8643
TEST_TIME 90 8716
TEST_TIME 91 8744
TEST_TIME 92 8716
TEST_TIME 93 8748
TEST_TIME 94 8832
TEST_TIME 95 8856
TEST_TIME 96 8819
TEST_TIME 97 8754
TEST_TIME 98 8715
TEST_TIME 99 8683
TEST_TIME 100 8651
TEST_TIME 101 8604
TEST_TIME 102 8576
TEST_TIME 103 8577
TEST_TIME 104 8611
TEST_TIME 105 8584
TEST_TIME 106 8648
TEST_TIME 107 8619
TEST_TIME 108 8597
TEST_TIME 109 8584
TEST_TIME 110 8609
TEST_TIME 111 8714
TEST_TIME 112 8688
TEST_TIME 113 8707
TEST_TIME 114 8700
TEST_TIME 115 8680
TEST_TIME 116 8617
TEST_TIME 117 8605
TEST_TIME 118 8589
TEST_TIME 119 8580
TEST_TIME 120 8513

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-11-2021 04:39:35 to 22-11-2021 04:39:38.

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 1428650 1428650 0 0.00 0 31534 8 0 0
doc_list_biud 1428644 1428644 0 0.00 0 68749 32 0 0
sp_add_invoice_to_stock 4879 4777 102 2.09 27 229617 21043 0 102
sp_cancel_adding_invoice 5084 5084 0 0.00 6 81832 1015 0 0
sp_cancel_client_order 1682 1682 0 0.00 5 10413 343 0 0
sp_cancel_customer_reserve 498643 498643 0 0.00 1 68751 73 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 2915 89 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 2 6488 94 0 0
sp_cancel_supplier_invoice 5602 5594 8 0.14 2 28481 1052 0 8
sp_cancel_supplier_order 369 369 0 0.00 5 1175571 122356 0 0
sp_cancel_write_off 7875 7875 0 0.00 1 12341 148 0 0
sp_client_order 19889 19889 0 0.00 4 28343 426 0 0
sp_customer_reserve 441757 441757 0 0.00 0 147689 208 0 0
sp_fill_shopping_cart 30583 30583 0 0.00 1 23783 47 0 0
sp_fill_shopping_cart_clo_res 441749 441749 0 0.00 0 23028 22 0 0
sp_get_clo_for_invoice 4769 4769 0 0.00 0 41801 424 0 0
sp_kill_cost_storno 14015 14015 0 0.00 0 16035 70 0 0
sp_kill_qstorno_ret_qs2qd 1011124 1011124 0 0.00 0 64184 40 0 0
sp_kill_qty_storno 533803 533803 0 0.00 0 68748 85 0 0
sp_lock_selected_doc 20081 20081 0 0.00 0 9981 5 0 0
sp_make_cost_storno 27041 27041 0 0.00 0 15500 67 0 0
sp_multiply_rows_for_pdistr 27041 27041 0 0.00 0 7375 34 0 0
sp_multiply_rows_for_qdistr 19889 19889 0 0.00 0 21259 223 0 0
sp_pay_from_customer 6733 6733 0 0.00 4 13878 139 0 0
sp_pay_to_supplier 5752 5752 0 0.00 5 15510 139 0 0
sp_payment_common 12485 12485 0 0.00 1 7436 30 0 0
sp_qd_handle_on_cancel_clo 1682 1682 0 0.00 0 10408 323 0 0
sp_qd_handle_on_invoice_upd_sts 9852 9852 0 0.00 0 42220 369 0 0
sp_qd_handle_on_reserve_upd_sts 9788 9788 0 0.00 0 23481 57 0 0
sp_reserve_write_off 9788 9788 0 0.00 7 23502 196 0 0
sp_supplier_invoice 5427 5427 0 0.00 7 92945 3908 0 0
sp_supplier_order 5275 5275 0 0.00 6 36014 1456 0 0
srv_aggregate_perf_data 100 100 0 0.00 2717 226385 15217 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 3031 185578 46197 0 0
srv_find_qd_qs_mism 974460 974460 0 0.00 0 31439 7 0 0
srv_make_invnt_saldo 449 449 0 0.00 63 103731 3634 0 0
srv_make_money_saldo 702 702 0 0.00 1 26749 605 0 0
srv_recalc_idx_stat 5 5 0 0.00 262042 415424 326447 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 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 4777 4777 0 0.00 0 14625 167 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 5502 104 0 0
t$perf-norm:sp_cancel_client_order 1682 1682 0 0.00 0 5339 19 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 2327 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 3935 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 10812 43 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 838 19 0 0
t$perf-norm:sp_cancel_supplier_order 369 369 0 0.00 0 18037 1444 0 0
t$perf-norm:sp_cancel_write_off 964 964 0 0.00 0 655 7 0 0
t$perf-norm:sp_client_order 19889 19889 0 0.00 0 15112 20 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 3954 13 0 0
t$perf-norm:sp_pay_from_customer 6733 6733 0 0.00 0 7826 9 0 0
t$perf-norm:sp_pay_to_supplier 5752 5752 0 0.00 0 10126 13 0 0
t$perf-norm:sp_reserve_write_off 9788 9788 0 0.00 0 11456 11 0 0
t$perf-norm:sp_supplier_invoice 5427 5427 0 0.00 0 18289 25 0 0
t$perf-norm:sp_supplier_order 5275 5275 0 0.00 0 7578 20 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 195 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 486 9 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 8172 44 0 0
t$perf-norm:srv_make_money_saldo 702 702 0 0.00 0 1551 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 9740 9536 204 2.09 0 9330 17 0 204
v_all_customers 35910 35910 0 0.00 0 19 1 0 0
v_all_suppliers 29502 29502 0 0.00 0 20 1 0 0
v_all_wares 39778 39778 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 871 6 0 0
v_cancel_client_order 3364 3364 0 0.00 0 3512 5 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 1959 7 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 402 3 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 2304 7 0 0
v_cancel_supplier_invoice 774 758 16 2.06 0 396 10 0 16
v_cancel_supplier_order 738 738 0 0.00 0 2922 11 0 0
v_cancel_write_off 1928 1928 0 0.00 0 1503 14 0 0
v_max_id_clo_ord 5273 5273 0 0.00 0 233 1 0 0
v_max_id_clo_res 965 965 0 0.00 0 2386 17 0 0
v_max_id_ord_sup 5420 5420 0 0.00 0 4867 5 0 0
v_max_non_paid_invoice 1694 1694 0 0.00 0 189 2 0 0
v_max_non_paid_realizn 3729 3729 0 0.00 0 874 3 0 0
v_min_id_clo_ord 5273 5273 0 0.00 0 3846 6 0 0
v_min_id_clo_res 965 965 0 0.00 0 5011 23 0 0
v_min_id_ord_sup 5420 5420 0 0.00 0 5430 9 0 0
v_min_non_paid_invoice 5752 5752 0 0.00 0 6958 17 0 0
v_min_non_paid_realizn 6733 6733 0 0.00 0 7431 25 0 0
v_our_firm 9876 9876 0 0.00 0 22 1 0 0
v_reserve_write_off 19576 19576 0 0.00 0 5337 6 0 0
x_lock_depdocs_on_canc_invoice 10299 10299 0 0.00 0 15928 124 0 0
x_lock_depdocs_on_canc_sup_ord 369 369 0 0.00 0 8270 331 0 0
x_make_qstorno_1000_1200 5275 5275 0 0.00 1 36004 1376 0 0
x_make_qstorno_1200_2000 5427 5427 0 0.00 4 92932 3723 0 0
x_make_qstorno_2100_3300 441757 441757 0 0.00 0 147688 184 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-11-2021 04:39:39 to 22-11-2021 04:39:43.

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:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:12 18705 8192000000 9722636896 10018136064 804700560 8.944 100 80 160 0 830861040 7327792 18832688
02:09:34 26933 8192000000 9762443408 10067623936 834471360 9.244 100 87 174 0 867021088 11950128 20599600
02:11:06 16829 8192000000 9812049168 10128433152 870872608 9.609 100 89 178 0 910643760 19091392 20679760
02:12:27 12978 8192000000 9858214064 10189279232 904099104 9.939 100 72 144 1 963339952 43402880 15837968
02:13:44 11237 8192000000 9897311664 10233745408 937985744 10.273 100 79 159 0 1020048704 64998096 17064864
02:15:00 4430 8192000000 9945105344 10296242176 982631552 10.710 100 75 148 2 1053747216 55821744 15293920
02:16:08 2739 8192000000 10006979904 10365087744 1021653392 11.088 100 86 172 0 1119261920 80252784 17355744
02:17:13 6060 8192000000 10019173664 10390999040 1039826592 11.263 100 73 144 2 1132945472 79557072 13561808
02:18:21 2250 8192000000 10026715056 10398564352 1061819408 11.474 100 91 182 2 1141990112 61460992 18709712
02:19:24 32468 8192000000 10019771632 10405879808 1066287312 11.517 100 53 104 1 1173282320 96841776 10153232
02:20:57 5039 8192000000 10006268208 10403504128 1072789376 11.579 100 40 80 1 1131543488 50610848 8143264
02:22:04 5538 8192000000 9995281248 10395598848 1080162304 11.649 100 37 74 2 1108689184 20949840 7577040
02:23:10 39346 8192000000 10003660064 10398138368 1084796144 11.693 100 60 118 2 1110798352 11640112 14362096
02:25:00 10835 8192000000 10043538768 10452832256 1090347536 11.746 100 37 74 0 1158983392 60810096 7825760
02:26:14 10027 8192000000 10004885296 10401992704 1096146624 11.801 100 29 56 3 1128239168 25750992 6341552
02:27:25 5365 8192000000 9994990992 10392068096 1076765760 11.617 99 28 53 4 1110299552 27918272 5615520
02:28:36 38287 8192000000 9981348048 10391142400 1031570816 11.184 95 41 81 1 1064298848 23999472 8728560
02:30:17 5021 8192000000 9933787648 10314108928 1006172704 10.938 97 36 72 1 1030278336 16398832 7706800
02:31:22 7488 8192000000 9922563728 10307334144 994169440 10.822 98 22 44 0 1015484368 16512272 4802656
02:32:32 8250 8192000000 9944502128 10329296896 1016985760 11.043 99 34 68 1 1045285712 20534000 7765952
02:33:41 33231 8192000000 9938046272 10319208448 1012777584 11.002 98 28 55 0 1032679232 13879872 6021776
02:35:17 10869 8192000000 9961613648 10355032064 1043306144 11.296 100 35 68 4 1077688640 25991936 8390560
02:36:30 9078 8192000000 9973253248 10369814528 1059895232 11.455 100 37 75 0 1089602464 21516160 8191072
02:37:48 3031 8192000000 10017264896 10402852864 1081779216 11.664 100 56 112 1 1120769600 27259792 11730592
02:38:54 106518 8192000000 10037433792 10445438976 1099349184 11.831 100 31 61 1 1147351920 41658608 6344128
02:42:17 13296 8192000000 10111175904 10532995072 1129480256 12.116 100 52 104 0 1234379024 93880320 11018448
02:43:33 28548 8192000000 10148707856 10557526016 1146292416 12.275 100 47 95 2 1257657536 101170016 10195104
02:45:12 15046 8192000000 10175651568 10582048768 1153515952 12.342 100 57 114 1 1281887376 115715232 12656192
02:46:31 5831 8192000000 10194184048 10599596032 1152973344 12.337 99 64 126 1 1304007696 137062992 13971360
02:47:41 5045 8192000000 10238917408 10647474176 1175834032 12.551 100 66 132 2 1348192224 157377248 14980944
02:48:50 8465 8192000000 10263480944 10674429952 1170183616 12.499 100 62 122 0 1364855568 180502352 14169600
02:50:06 6726 8192000000 10282770224 10709045248 1173151584 12.526 99 73 145 2 1393564320 204224560 16188176
02:51:24 9416 8192000000 10318707600 10749046784 1189534592 12.679 100 65 130 0 1412664640 208940656 14189392
02:52:38 3675 8192000000 10342357792 10753028096 1189981904 12.683 100 81 161 0 1449654880 241549376 18123600
02:53:48 9967 8192000000 10380169856 10788249600 1177142480 12.564 100 73 144 1 1486095728 291423184 17530064
02:55:03 3219 8192000000 10412177920 10812731392 1158980544 12.394 99 75 150 1 1502078512 325620768 17477200
02:56:13 6930 8192000000 10409394192 10816512000 1149537456 12.305 99 60 120 1 1505458960 341349760 14571744
02:57:28 4305 8192000000 10398229392 10806657024 1158813296 12.392 100 81 162 0 1493445408 315776240 18855872
02:58:36 6855 8192000000 10318093536 10751348736 1126235024 12.086 99 35 69 1 1403888240 269148896 8504320
02:59:59 31312 8192000000 10372061120 10802388992 1132498720 12.145 100 25 50 0 1461020736 321758128 6763888
03:01:46 10535 8192000000 10314977120 10771292160 1096520672 11.805 99 30 59 2 1414650624 309902096 8227856
03:03:01 44151 8192000000 10095482032 10564472832 1081800048 11.665 97 16 32 1 1342638352 256874704 3963600
03:04:47 22612 8192000000 10083858688 10500796416 1100461936 11.842 99 37 74 1 1173265840 64692704 8111200
03:06:14 93098 8192000000 10096682944 10521554944 1117687056 12.005 100 39 78 0 1182074304 56448160 7939088
03:09:32 185578 8192000000 10072610000 10501861376 1102094176 11.858 98 21 42 1 1174556192 68053520 4408496
03:13:40 14067 8192000000 10094126672 10527121408 1122612112 12.052 100 33 66 0 1179515104 49623536 7279456
03:15:31 27266 8192000000 10192353520 10618847232 1134701776 12.166 100 50 101 0 1270542016 124949056 10891184
03:17:14 67113 8192000000 10172272880 10613305344 1103632336 11.872 98 26 53 0 1260894368 150893632 6368400
03:19:24 79429 8192000000 10056895536 10512691200 1114743152 11.977 99 11 22 1 1172303168 55544000 2016016
03:21:45 59020 8192000000 10100797744 10566987776 1120900944 12.036 99 40 80 1 1205105568 76129264 8075360
03:23:44 91934 8192000000 10082625440 10528571392 1121044448 12.037 99 19 38 0 1170531728 45563104 3924176
03:26:23 61964 8192000000 10105226032 10550538240 1138456272 12.201 100 33 65 0 1203163408 58361680 6345456
03:28:29 31177 8192000000 10093312144 10541412352 1117305904 12.002 99 47 94 0 1184891696 58057456 9528336
03:30:25 62772 8192000000 10102988384 10570096640 1093456272 11.776 96 20 40 0 1154446864 57187280 3803312
03:32:30 107375 8192000000 10080458160 10516066304 1098976624 11.828 97 33 66 0 1165411840 58365616 8069600
03:35:48 33884 8192000000 10097324704 10550968320 1116616192 11.995 98 35 70 1 1180054400 55412960 8025248
03:37:33 38003 8192000000 10094915072 10555027456 1125555520 12.079 99 8 16 0 1189483824 62266976 1661328
03:39:12 55299 8192000000 10124288064 10599354368 1137571808 12.193 100 19 38 1 1229641984 87446480 4623696
03:41:09 51603 8192000000 10156762896 10612568064 1107397184 11.908 96 26 51 0 1234262816 120651456 6214176
03:43:01 62326 8192000000 10135441328 10590961664 1111397152 11.946 98 13 26 0 1198287728 84348352 2542224
03:45:07 60781 8192000000 10095217424 10550996992 1094638752 11.787 96 15 30 0 1170220656 72402016 3179888
03:47:10 41561 8192000000 10064783824 10492174336 1100430960 11.842 97 27 54 1 1142195712 36719536 5045216
03:48:54 31971 8192000000 10102707040 10547884032 1097309008 11.812 97 41 82 0 1177266272 71670960 8286304
03:50:26 65566 8192000000 10062092432 10512400384 1087499872 11.719 97 14 28 0 1119286272 28806608 2979792
03:52:33 48373 8192000000 10079295120 10508337152 1100223744 11.840 98 35 70 0 1156529376 48781408 7524224
03:55:22 44150 8192000000 10090145120 10563072000 1116665472 11.995 99 20 40 1 1191305280 69680448 4959360
03:57:06 63144 8192000000 10054046848 10538364928 1111780480 11.949 99 9 18 1 1147567536 33638352 2148704
03:59:13 49846 8192000000 10086720208 10525962240 1119036032 12.018 99 18 36 0 1186127712 62853184 4238496
04:01:08 74242 8192000000 10109862128 10559594496 1102159504 11.858 98 12 24 1 1190352624 85050304 3142816
04:03:23 29330 8192000000 10116874832 10556788736 1121610096 12.042 100 33 63 2 1214061632 85323376 7128160
04:05:01 56580 8192000000 10090837440 10544717824 1095549360 11.795 97 17 34 1 1165293680 66251152 3493168
04:07:25 46217 8192000000 10099431936 10558300160 1094635984 11.787 98 28 56 0 1156803152 56754960 5412208
04:09:16 50265 8192000000 10111811888 10567536640 1112451456 11.956 99 31 62 0 1180434928 61710432 6273040
04:11:11 86075 8192000000 10075121568 10578518016 1116961872 11.998 99 22 44 1 1251779360 130147792 4669696
04:13:38 30108 8192000000 10053085408 10482225152 1102413232 11.861 98 41 82 0 1138691312 28179424 8098656
04:15:13 40684 8192000000 10083663248 10531323904 1130008448 12.121 100 37 74 0 1185902992 48465968 7428576
04:16:55 81859 8192000000 10113452112 10562883584 1144857168 12.261 100 15 30 0 1204454944 56342320 3255456
04:20:43 45044 8192000000 10108719152 10559246336 1136849280 12.186 99 17 34 2 1228123536 87015216 4259040
04:22:37 76332 8192000000 10066172704 10546249728 1119763168 12.025 98 19 38 0 1196454480 72393312 4298000
04:24:54 28106 8192000000 10135238144 10564382720 1143353200 12.247 100 35 70 0 1215100912 64526528 7221184
04:26:31 152192 8192000000 10089527328 10613452800 1105871392 11.893 96 19 38 0 1209154688 98702752 4580544
04:30:05 46658 8192000000 10075379264 10545057792 1124288960 12.067 99 44 88 1 1178340688 44657680 9394048
04:31:51 66103 8192000000 10077563264 10521010176 1092092608 11.763 96 31 62 0 1121295520 23138208 6064704
04:34:56 46407 8192000000 10076491488 10540879872 1121434208 12.041 99 6 12 0 1168238240 45770832 1033200
04:36:43 35126 8192000000 9278450784 9603018752 428647856 4.972 38 4 8 0 459175600 29754848 772896

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-11-2021 04:39:45 to 22-11-2021 04:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 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 22-11-2021 04:40:01 to 22-11-2021 04:40:03.

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          228669
MON$OLDEST_ACTIVE               228670
MON$OLDEST_SNAPSHOT             228670
MON$NEXT_TRANSACTION            228670
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                       2022400
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2668 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2668 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2668 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 ---
:165: 
:166: mscs-test-161.budzdorov.ru    Mon Nov 22 04:37:38 2021
:167:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:168: 
--- end of diff output ---

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

22.11.21 04:40:04. 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-22 04:40:06 No severe PSQL-related problems occured
22.11.21 04:40:07. End of report.