Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.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 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 15822044 kB
MemAvailable 30792652 kB
Buffers 0 kB
Cached 15865836 kB
SwapCached 2440 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-07 07:37:08
DTS_END                         2021-11-07 07:37:08.7780
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/47050
TRN_ID                          235922
ATT_ID                          672
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: 9212.19 21964 1105463
customer order: creation 173.66 423 20839
customer order: refuse 14.81 337 1777
order to supplier: creation 46.03 1595 5523
order to supplier: removal 3.62 106698 434
invoice (draft): creation 45.90 3873 5508
invoice (draft): removal 54.09 921 6491
invoice accept: apply 40.29 19270 4835
invoice accept: cancel 48.56 859 5827
customer reserve: creation 3745.32 190 449438
customer reserve: removal 4742.18 63 569061
realization accept: apply 82.46 193 9895
realization accept: cancel 87.03 124 10444
payment from customer: creation 58.42 139 7010
payment from customer: removal 5.88 90 705
payment to supplier: creation 49.05 118 5886
payment to supplier: removal 3.54 95 425
service: total inventory turnovers 4.05 3030 486
service: total monetary turnovers 6.26 450 751
service: aggregate perf. data 1.03 12015 124
service: refresh index statistics 0.03 288800 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3705
TEST_TIME 2 2371
TEST_TIME 3 3269
TEST_TIME 4 3797
TEST_TIME 5 3761
TEST_TIME 6 4033
TEST_TIME 7 4153
TEST_TIME 8 4448
TEST_TIME 9 4650
TEST_TIME 10 5088
TEST_TIME 11 5357
TEST_TIME 12 5511
TEST_TIME 13 6015
TEST_TIME 14 6351
TEST_TIME 15 6505
TEST_TIME 16 6643
TEST_TIME 17 6959
TEST_TIME 18 7578
TEST_TIME 19 8053
TEST_TIME 20 8651
TEST_TIME 21 8900
TEST_TIME 22 9287
TEST_TIME 23 9549
TEST_TIME 24 9694
TEST_TIME 25 10040
TEST_TIME 26 10994
TEST_TIME 27 11171
TEST_TIME 28 11089
TEST_TIME 29 11189
TEST_TIME 30 10951
TEST_TIME 31 10746
TEST_TIME 32 10597
TEST_TIME 33 10377
TEST_TIME 34 10314
TEST_TIME 35 10270
TEST_TIME 36 10281
TEST_TIME 37 10415
TEST_TIME 38 10350
TEST_TIME 39 10280
TEST_TIME 40 10185
TEST_TIME 41 10088
TEST_TIME 42 9955
TEST_TIME 43 10038
TEST_TIME 44 9909
TEST_TIME 45 9782
TEST_TIME 46 9746
TEST_TIME 47 9780
TEST_TIME 48 9720
TEST_TIME 49 9632
TEST_TIME 50 9735
TEST_TIME 51 9831
TEST_TIME 52 9860
TEST_TIME 53 9858
TEST_TIME 54 9918
TEST_TIME 55 9913
TEST_TIME 56 9912
TEST_TIME 57 10034
TEST_TIME 58 10000
TEST_TIME 59 10018
TEST_TIME 60 10072
TEST_TIME 61 9916
TEST_TIME 62 9870
TEST_TIME 63 9858
TEST_TIME 64 9791
TEST_TIME 65 9745
TEST_TIME 66 9640
TEST_TIME 67 9544
TEST_TIME 68 9535
TEST_TIME 69 9652
TEST_TIME 70 9597
TEST_TIME 71 9527
TEST_TIME 72 9547
TEST_TIME 73 9578
TEST_TIME 74 9557
TEST_TIME 75 9574
TEST_TIME 76 9511
TEST_TIME 77 9508
TEST_TIME 78 9616
TEST_TIME 79 9674
TEST_TIME 80 9621
TEST_TIME 81 9546
TEST_TIME 82 9528
TEST_TIME 83 9485
TEST_TIME 84 9493
TEST_TIME 85 9436
TEST_TIME 86 9385
TEST_TIME 87 9346
TEST_TIME 88 9336
TEST_TIME 89 9294
TEST_TIME 90 9292
TEST_TIME 91 9276
TEST_TIME 92 9323
TEST_TIME 93 9307
TEST_TIME 94 9302
TEST_TIME 95 9295
TEST_TIME 96 9346
TEST_TIME 97 9325
TEST_TIME 98 9332
TEST_TIME 99 9343
TEST_TIME 100 9357
TEST_TIME 101 9373
TEST_TIME 102 9315
TEST_TIME 103 9275
TEST_TIME 104 9253
TEST_TIME 105 9211
TEST_TIME 106 9181
TEST_TIME 107 9158
TEST_TIME 108 9128
TEST_TIME 109 9147
TEST_TIME 110 9168
TEST_TIME 111 9131
TEST_TIME 112 9107
TEST_TIME 113 9080
TEST_TIME 114 9051
TEST_TIME 115 9151
TEST_TIME 116 9188
TEST_TIME 117 9306
TEST_TIME 118 9300
TEST_TIME 119 9276
TEST_TIME 120 9217

Performance per minute, chart


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

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 1523773 1523773 0 0.00 0 27651 8 0 0
doc_list_biud 1523765 1523765 0 0.00 0 95261 30 0 0
sp_add_invoice_to_stock 4904 4835 69 1.40 44 234727 19271 0 69
sp_cancel_adding_invoice 5827 5827 0 0.00 5 64191 860 0 0
sp_cancel_client_order 1777 1777 0 0.00 4 10008 337 0 0
sp_cancel_customer_reserve 569061 569061 0 0.00 1 28548 64 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 3 6645 91 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 5953 95 0 0
sp_cancel_supplier_invoice 6493 6491 2 0.03 2 95269 921 0 2
sp_cancel_supplier_order 434 434 0 0.00 6 1077069 106698 0 0
sp_cancel_write_off 10444 10444 0 0.00 1 26042 125 0 0
sp_client_order 20839 20839 0 0.00 4 25223 424 0 0
sp_customer_reserve 449438 449438 0 0.00 0 77889 190 0 0
sp_fill_shopping_cart 31860 31860 0 0.00 1 11754 43 0 0
sp_fill_shopping_cart_clo_res 449430 449430 0 0.00 0 18576 17 0 0
sp_get_clo_for_invoice 4827 4827 0 0.00 0 26169 326 0 0
sp_kill_cost_storno 17401 17401 0 0.00 0 12780 59 0 0
sp_kill_qstorno_ret_qs2qd 1155491 1155491 0 0.00 0 95260 35 0 0
sp_kill_qty_storno 608756 608756 0 0.00 0 95261 75 0 0
sp_lock_selected_doc 20550 20550 0 0.00 0 7489 4 0 0
sp_make_cost_storno 27618 27618 0 0.00 0 26523 63 0 0
sp_multiply_rows_for_pdistr 27618 27618 0 0.00 0 12914 38 0 0
sp_multiply_rows_for_qdistr 20839 20839 0 0.00 0 12349 223 0 0
sp_pay_from_customer 7010 7010 0 0.00 4 26033 140 0 0
sp_pay_to_supplier 5886 5886 0 0.00 5 17318 119 0 0
sp_payment_common 12890 12890 0 0.00 1 17399 30 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 10001 301 0 0
sp_qd_handle_on_invoice_upd_sts 10654 10654 0 0.00 0 61583 377 0 0
sp_qd_handle_on_reserve_upd_sts 9895 9895 0 0.00 0 8655 50 0 0
sp_reserve_write_off 9895 9895 0 0.00 6 26541 194 0 0
sp_supplier_invoice 5508 5508 0 0.00 6 96786 3874 0 0
sp_supplier_order 5523 5523 0 0.00 6 66324 1596 0 0
srv_aggregate_perf_data 124 124 0 0.00 2922 240027 12015 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1077 146904 46123 0 0
srv_find_qd_qs_mism 1059255 1059255 0 0.00 0 14876 7 0 0
srv_make_invnt_saldo 486 486 0 0.00 59 131958 3031 0 0
srv_make_money_saldo 751 751 0 0.00 1 13000 450 0 0
srv_recalc_idx_stat 4 4 0 0.00 203434 327891 288800 0 0
t$perf-abend:sp_add_invoice_to_stock 69 69 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4836 4836 0 0.00 0 48210 171 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 4071 103 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 12509 21 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 4406 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 46689 74 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 806 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 919 16 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 19040 1352 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 1731 6 0 0
t$perf-norm:sp_client_order 20839 20839 0 0.00 0 46730 26 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 3015 11 0 0
t$perf-norm:sp_pay_from_customer 7010 7010 0 0.00 0 39120 15 0 0
t$perf-norm:sp_pay_to_supplier 5886 5886 0 0.00 0 4077 7 0 0
t$perf-norm:sp_reserve_write_off 9896 9896 0 0.00 0 46184 15 0 0
t$perf-norm:sp_supplier_invoice 5508 5508 0 0.00 0 47644 33 0 0
t$perf-norm:sp_supplier_order 5523 5523 0 0.00 0 46723 32 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 259 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 180 7 0 0
t$perf-norm:srv_make_invnt_saldo 486 486 0 0.00 0 10037 27 0 0
t$perf-norm:srv_make_money_saldo 751 751 0 0.00 0 11641 32 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 176 50 0 0
v_add_invoice_to_stock 9792 9654 138 1.40 0 3716 16 0 138
v_all_customers 37720 37720 0 0.00 0 233 1 0 0
v_all_suppliers 30934 30934 0 0.00 0 233 1 0 0
v_all_wares 41678 41678 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 3091 11 0 0
v_cancel_client_order 3554 3554 0 0.00 0 6165 7 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 3728 11 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 2458 8 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 1175 7 0 0
v_cancel_supplier_invoice 810 806 4 0.49 0 1303 13 0 4
v_cancel_supplier_order 868 868 0 0.00 0 2401 7 0 0
v_cancel_write_off 2010 2010 0 0.00 0 1742 14 0 0
v_max_id_clo_ord 5522 5522 0 0.00 0 673 2 0 0
v_max_id_clo_res 993 993 0 0.00 0 2354 19 0 0
v_max_id_ord_sup 5499 5499 0 0.00 0 3388 5 0 0
v_max_non_paid_invoice 1436 1436 0 0.00 0 827 2 0 0
v_max_non_paid_realizn 3690 3690 0 0.00 0 17017 9 0 0
v_min_id_clo_ord 5522 5522 0 0.00 0 4211 10 0 0
v_min_id_clo_res 993 993 0 0.00 0 1360 5 0 0
v_min_id_ord_sup 5499 5499 0 0.00 0 4256 8 0 0
v_min_non_paid_invoice 5882 5882 0 0.00 0 8665 15 0 0
v_min_non_paid_realizn 7008 7008 0 0.00 0 17383 28 0 0
v_our_firm 10594 10594 0 0.00 0 19 1 0 0
v_reserve_write_off 19790 19790 0 0.00 0 9320 6 0 0
x_lock_depdocs_on_canc_invoice 11915 11915 0 0.00 0 14040 108 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 6933 310 0 0
x_make_qstorno_1000_1200 5523 5523 0 0.00 2 66314 1512 0 0
x_make_qstorno_1200_2000 5508 5508 0 0.00 2 96770 3718 0 0
x_make_qstorno_2100_3300 449438 449438 0 0.00 0 77676 171 0 0

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

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:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:13 26120 8192000000 9727154464 10027266048 801574624 8.912 100 92 185 0 831722912 7870352 22277936
05:09:43 27400 8192000000 9769219408 10077650944 841484928 9.315 100 91 182 1 875792752 12791712 21516112
05:11:14 19088 8192000000 9807795728 10127691776 877393040 9.674 100 88 176 0 916378720 18936992 20048688
05:12:41 14180 8192000000 9879466256 10210930688 917510208 10.072 100 85 170 0 982017408 45446848 19060352
05:13:57 14323 8192000000 9924192864 10266001408 953133952 10.422 100 83 166 2 1032125856 61147504 17844400
05:15:14 6296 8192000000 9974380480 10328092672 992707120 10.808 100 86 172 0 1088471840 78065360 17699360
05:16:22 2893 8192000000 9988314656 10349645824 1016353952 11.037 100 80 160 2 1098618352 65712000 16552400
05:17:27 2479 8192000000 10019479376 10388873216 1037656000 11.242 100 83 166 0 1127129120 72241680 17231440
05:18:31 1689 8192000000 10036349968 10408628224 1063788784 11.493 100 88 175 1 1149148000 67263824 18095392
05:19:33 2877 8192000000 10034650944 10419531776 1068764512 11.540 100 73 146 2 1150296400 67476944 14054944
05:20:37 11287 8192000000 9986510112 10386173952 1078407360 11.632 100 30 60 0 1127183760 42392880 6383520
05:21:51 7215 8192000000 9995687472 10395107328 1086146832 11.706 100 38 76 0 1114307472 19693968 8466672
05:22:58 54239 8192000000 10003168128 10409082880 1090818928 11.750 100 24 48 1 1111866256 15911248 5136080
05:24:53 4467 8192000000 10050087024 10446577664 1098058784 11.819 100 63 127 1 1147595120 36154144 13382192
05:26:03 5973 8192000000 10015710816 10433069056 1096123872 11.801 100 24 48 0 1137171648 35739488 5308288
05:27:11 11787 8192000000 10022081296 10431770624 1080028320 11.648 99 26 53 0 1108810832 23738512 5044000
05:28:28 29618 8192000000 10015886784 10421407744 1047595488 11.338 97 33 66 1 1082838176 27857808 7384880
05:30:00 6066 8192000000 9961219408 10354274304 1031558208 11.183 98 38 76 0 1060216720 20741120 7917392
05:31:06 6700 8192000000 9938241456 10311561216 984505888 10.728 95 35 70 0 1010745376 18740800 7498688
05:32:15 7335 8192000000 9950988368 10329632768 1012870272 11.003 98 42 84 2 1042694272 20057744 9766256
05:33:23 19600 8192000000 9937403616 10339504128 1012971872 11.004 98 20 38 1 1039637504 22277536 4388096
05:34:49 6223 8192000000 9985174496 10378227712 1059702240 11.454 100 52 105 1 1094645968 23874016 11069712
05:35:57 8612 8192000000 9990395808 10391658496 1071048080 11.562 100 39 79 1 1098260272 18687712 8524480
05:37:05 26871 8192000000 10013437872 10402443264 1088367216 11.727 100 35 68 0 1118836512 21476320 8992976
05:38:37 103723 8192000000 10064641696 10481016832 1119693312 12.024 100 39 77 2 1178727616 51029536 8004768
05:41:24 23777 8192000000 10102835456 10514079744 1135288432 12.171 100 46 92 0 1216226688 70813296 10124960
05:43:15 12271 8192000000 10176982016 10591330304 1153267680 12.340 100 50 97 2 1283517104 119241376 11008048
05:44:30 11363 8192000000 10216332496 10628673536 1171649072 12.512 100 50 100 0 1323847760 140402560 11796128
05:45:50 18314 8192000000 10256838192 10673651712 1180397728 12.594 100 56 110 2 1367086272 173695808 12992736
05:47:20 9683 8192000000 10274220240 10704695296 1187832976 12.663 100 46 92 1 1386679264 187736688 11109600
05:48:33 3780 8192000000 10320548784 10731704320 1188649392 12.671 100 72 142 0 1424075072 219954128 15471552
05:49:41 1074 8192000000 10378379456 10778017792 1185109536 12.638 100 93 183 3 1474725680 269976416 19639728
05:50:45 2446 8192000000 10406946352 10815684608 1191264048 12.695 100 77 153 0 1504036448 294726608 18045792
05:51:53 2273 8192000000 10391971632 10794004480 1180523872 12.595 100 90 180 3 1485581776 284859968 20197936
05:53:03 2626 8192000000 10426242208 10829234176 1172814912 12.523 100 71 142 2 1522725888 333466304 16444672
05:54:09 2099 8192000000 10415726112 10835558400 1139602800 12.212 99 91 180 0 1513148096 351349936 22195360
05:55:18 11971 8192000000 10434413792 10889822208 1150566096 12.315 100 50 100 0 1553734208 390113408 13054704
05:56:42 3317 8192000000 10383714832 10808725504 1137714000 12.194 99 44 86 2 1481984064 333923056 10347008
05:57:51 11205 8192000000 10356614592 10799026176 1146094400 12.273 100 41 82 3 1455311776 299115232 10102144
05:59:08 13469 8192000000 10310062992 10768588800 1119883728 12.026 100 28 56 0 1439615488 312033872 7697888
06:00:24 24463 8192000000 10278187904 10764787712 1128300640 12.105 100 26 52 0 1423316368 288369248 6646480
06:01:51 29984 8192000000 10215540688 10695479296 1089029024 11.733 98 21 42 0 1354447328 260159472 5258832
06:03:34 34458 8192000000 10066282224 10513539072 1095331696 11.793 99 20 41 0 1155405968 55705008 4369264
06:05:09 65542 8192000000 10095569040 10557779968 1105277904 11.888 99 8 16 1 1172675392 65819152 1578336
06:07:25 116045 8192000000 10101850048 10579668992 1120289088 12.030 100 18 36 0 1165106336 40867440 3949808
06:10:23 85828 8192000000 10140555056 10559586304 1128745728 12.110 99 37 74 1 1216345024 79802224 7797072
06:12:49 43885 8192000000 10157966032 10626887680 1129192320 12.114 98 34 66 2 1260862704 123795888 7874496
06:14:53 21500 8192000000 10093410672 10542419968 1134293520 12.162 100 27 51 1 1200945520 61143200 5508800
06:16:35 36527 8192000000 10090353568 10531594240 1133717216 12.156 100 16 32 0 1185569248 48164208 3687824
06:19:01 68303 8192000000 10115748608 10576691200 1139039344 12.206 100 17 33 1 1197706288 54684096 3982848
06:21:12 89676 8192000000 10076298000 10538909696 1129764160 12.119 99 18 36 0 1168329328 34523344 4041824
06:23:44 64131 8192000000 10076518720 10600013824 1133964192 12.159 99 10 20 1 1224602688 88414752 2223744
06:25:50 21534 8192000000 10110303648 10550366208 1141239520 12.227 99 50 100 1 1178578496 26468464 10870512
06:27:14 46750 8192000000 10131066800 10607685632 1155274512 12.359 100 23 46 0 1246443888 85910576 5258800
06:29:01 51770 8192000000 10122930832 10577989632 1150993600 12.319 100 21 42 2 1221848736 66215344 4639792
06:31:19 78187 8192000000 10094981120 10574557184 1093064416 11.772 95 17 34 0 1184239696 87473328 3701952
06:33:40 38467 8192000000 10111515072 10568376320 1131617904 12.137 99 19 38 3 1212398416 76725200 4055312
06:35:51 36557 8192000000 10140551200 10591928320 1136247696 12.180 99 26 52 1 1212337136 70757104 5332336
06:37:35 122581 8192000000 10074835696 10526392320 1119273840 12.020 98 25 50 1 1172625296 47764432 5587024
06:40:49 38267 8192000000 10073510432 10547638272 1113233984 11.963 98 9 18 0 1164758160 49809408 1714768
06:42:27 6097 8192000000 10058433168 10560516096 1129992896 12.121 100 5 10 0 1167222880 36127200 1102784
06:43:34 67932 8192000000 10116282032 10573852672 1121569792 12.042 98 11 22 0 1187720000 63877504 2272704
06:45:44 55181 8192000000 10099355952 10549293056 1104039056 11.876 97 15 30 1 1165234320 57423280 3771984
06:47:39 28561 8192000000 10057206368 10491953152 1094931584 11.790 98 28 56 0 1135403904 34825696 5646624
06:49:11 48776 8192000000 10071795728 10522288128 1094687232 11.787 98 33 66 0 1162384656 60917936 6779488
06:51:01 34863 8192000000 10082775760 10516373504 1093493312 11.776 98 36 72 1 1138365312 37592656 7279344
06:52:43 50274 8192000000 10091063744 10528665600 1120473056 12.031 100 25 49 2 1167776160 42434976 4868128
06:54:34 48764 8192000000 10175581600 10629984256 1139109856 12.207 100 24 48 2 1252156432 108049904 4996672
06:56:24 68730 8192000000 10068123088 10515378176 1101705872 11.854 98 23 46 0 1139518512 33052736 4759904
06:58:36 126881 8192000000 10121009776 10579087360 1123516384 12.060 100 30 60 2 1220110032 89851472 6742176
07:01:44 71912 8192000000 10058575200 10552213504 1099704768 11.835 98 14 28 0 1140921968 38275728 2941472
07:04:05 47715 8192000000 10066795696 10542166016 1129950176 12.121 100 14 28 1 1182671280 48911712 3809392
07:05:53 33084 8192000000 10033446624 10478522368 1099855008 11.836 97 18 36 1 1127769568 24156208 3758352
07:07:32 55239 8192000000 10091427264 10537910272 1124438016 12.069 98 36 72 0 1178591440 46611632 7541792
07:09:30 85810 8192000000 10089562160 10528014336 1135145712 12.170 99 25 51 0 1166784672 26832352 4806608
07:11:57 46400 8192000000 10128536976 10565378048 1149557776 12.305 100 40 80 1 1217302160 59725376 8019008
07:13:44 32533 8192000000 10087062592 10511695872 1123763216 12.063 98 35 69 2 1158694432 28271664 6659552
07:15:24 61438 8192000000 10078224240 10523586560 1112214256 11.953 97 38 76 0 1172400192 52733248 7452688
07:17:27 29514 8192000000 10082781888 10524278784 1139470080 12.211 99 18 35 1 1167478576 24103872 3904624
07:18:57 146903 8192000000 10061321840 10506043392 1105665216 11.891 97 31 62 0 1164056688 51939888 6451584
07:22:26 40570 8192000000 10056901072 10515603456 1126340208 12.087 99 12 24 0 1161068464 32032864 2695392
07:24:12 49983 8192000000 10068476224 10524446720 1114106640 11.971 98 12 24 0 1158028720 41138448 2783632
07:26:04 70824 8192000000 10109968880 10574082048 1117686032 12.005 99 18 36 2 1208224960 86520880 4018048
07:28:25 59728 8192000000 10119640608 10559188992 1090081040 11.743 96 28 56 0 1185257360 88974464 6201856
07:30:35 77035 8192000000 10193788784 10631155712 1080256128 11.650 95 31 61 1 1241426640 154333504 6837008
07:33:12 40425 8192000000 10154044864 10653835264 1125530880 12.079 100 16 32 0 1240106496 110913232 3662384
07:34:53 91879 8192000000 10096940624 10535796736 1099838672 11.836 98 19 38 2 1183233904 79086704 4308528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 69 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

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

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          233970
MON$OLDEST_ACTIVE               236409
MON$OLDEST_SNAPSHOT             236409
MON$NEXT_TRANSACTION            236409
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                       2057056
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             804
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2653 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2653 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2653 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2653 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 ---
:94: 
:95: mscs-test-161.budzdorov.ru    Sun Nov  7 07:37:14 2021
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:97: 
--- end of diff output ---

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

07.11.21 07:38:37. 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-07 07:38:39 No severe PSQL-related problems occured
07.11.21 07:38:41. End of report.