Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.07.2022 10:38
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 15856244 kB
MemAvailable 30989956 kB
Buffers 0 kB
Cached 15827168 kB
SwapCached 2512 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.2
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 2022-07-29 10:37:18
DTS_END                         2022-07-29 10:37:18.3610
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44362
TRN_ID                          223121
ATT_ID                          654
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: 8899.44 28755 1067933
customer order: creation 168.72 492 20246
customer order: refuse 14.88 394 1785
order to supplier: creation 45.41 1319 5449
order to supplier: removal 3.22 167454 386
invoice (draft): creation 45.33 3213 5439
invoice (draft): removal 48.33 1014 5799
invoice accept: apply 41.74 25780 5009
invoice accept: cancel 43.73 1205 5247
customer reserve: creation 3866.68 246 464001
customer reserve: removal 4336.35 98 520362
realization accept: apply 81.05 267 9726
realization accept: cancel 72.68 175 8722
payment from customer: creation 59.88 163 7186
payment from customer: removal 5.73 113 688
payment to supplier: creation 48.65 185 5838
payment to supplier: removal 3.76 125 451
service: total inventory turnovers 4.56 3357 547
service: total monetary turnovers 7.88 376 945
service: aggregate perf. data 0.86 17580 103
service: refresh index statistics 0.03 351549 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-07-2022 10:38:27 to 29-07-2022 10:38:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6825
TEST_TIME 2 5571
TEST_TIME 3 5992
TEST_TIME 4 5899
TEST_TIME 5 6171
TEST_TIME 6 5963
TEST_TIME 7 5769
TEST_TIME 8 5674
TEST_TIME 9 5953
TEST_TIME 10 6155
TEST_TIME 11 6646
TEST_TIME 12 6736
TEST_TIME 13 6859
TEST_TIME 14 7299
TEST_TIME 15 7499
TEST_TIME 16 7584
TEST_TIME 17 7628
TEST_TIME 18 7739
TEST_TIME 19 7796
TEST_TIME 20 7733
TEST_TIME 21 7784
TEST_TIME 22 8050
TEST_TIME 23 8040
TEST_TIME 24 8357
TEST_TIME 25 9167
TEST_TIME 26 9779
TEST_TIME 27 9718
TEST_TIME 28 9682
TEST_TIME 29 10397
TEST_TIME 30 10535
TEST_TIME 31 11008
TEST_TIME 32 11016
TEST_TIME 33 11067
TEST_TIME 34 10760
TEST_TIME 35 10567
TEST_TIME 36 10459
TEST_TIME 37 10338
TEST_TIME 38 10173
TEST_TIME 39 10098
TEST_TIME 40 10026
TEST_TIME 41 9955
TEST_TIME 42 9882
TEST_TIME 43 9822
TEST_TIME 44 9766
TEST_TIME 45 9694
TEST_TIME 46 9612
TEST_TIME 47 9559
TEST_TIME 48 9628
TEST_TIME 49 9552
TEST_TIME 50 9488
TEST_TIME 51 9425
TEST_TIME 52 9410
TEST_TIME 53 9434
TEST_TIME 54 9530
TEST_TIME 55 9471
TEST_TIME 56 9589
TEST_TIME 57 9645
TEST_TIME 58 9615
TEST_TIME 59 9571
TEST_TIME 60 9516
TEST_TIME 61 9553
TEST_TIME 62 9435
TEST_TIME 63 9485
TEST_TIME 64 9451
TEST_TIME 65 9412
TEST_TIME 66 9402
TEST_TIME 67 9351
TEST_TIME 68 9296
TEST_TIME 69 9236
TEST_TIME 70 9227
TEST_TIME 71 9153
TEST_TIME 72 9147
TEST_TIME 73 9134
TEST_TIME 74 9164
TEST_TIME 75 9243
TEST_TIME 76 9242
TEST_TIME 77 9229
TEST_TIME 78 9226
TEST_TIME 79 9256
TEST_TIME 80 9286
TEST_TIME 81 9272
TEST_TIME 82 9267
TEST_TIME 83 9276
TEST_TIME 84 9295
TEST_TIME 85 9296
TEST_TIME 86 9218
TEST_TIME 87 9150
TEST_TIME 88 9125
TEST_TIME 89 9072
TEST_TIME 90 9048
TEST_TIME 91 9025
TEST_TIME 92 8976
TEST_TIME 93 8956
TEST_TIME 94 8929
TEST_TIME 95 8954
TEST_TIME 96 8957
TEST_TIME 97 8911
TEST_TIME 98 8881
TEST_TIME 99 8936
TEST_TIME 100 9047
TEST_TIME 101 9081
TEST_TIME 102 9144
TEST_TIME 103 9219
TEST_TIME 104 9203
TEST_TIME 105 9197
TEST_TIME 106 9236
TEST_TIME 107 9155
TEST_TIME 108 9123
TEST_TIME 109 9075
TEST_TIME 110 9108
TEST_TIME 111 9075
TEST_TIME 112 9069
TEST_TIME 113 9046
TEST_TIME 114 8977
TEST_TIME 115 8972
TEST_TIME 116 8957
TEST_TIME 117 8958
TEST_TIME 118 8956
TEST_TIME 119 8987
TEST_TIME 120 8924

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-07-2022 10:38:30 to 29-07-2022 10:38:34.

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 1495170 1495170 0 0.00 0 49835 13 0 0
doc_list_biud 1495161 1495161 0 0.00 0 53687 40 0 0
sp_add_invoice_to_stock 5096 5009 87 1.70 47 190645 25781 0 87
sp_cancel_adding_invoice 5247 5247 0 0.00 7 83453 1205 0 0
sp_cancel_client_order 1785 1785 0 0.00 7 20010 395 0 0
sp_cancel_customer_reserve 520362 520362 0 0.00 1 53690 98 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 4 3608 114 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 3 3678 125 0 0
sp_cancel_supplier_invoice 5810 5799 11 0.18 3 30012 1015 0 11
sp_cancel_supplier_order 386 386 0 0.00 10 1370498 167455 0 0
sp_cancel_write_off 8722 8722 0 0.00 2 6843 176 0 0
sp_client_order 20246 20246 0 0.00 6 42507 493 0 0
sp_customer_reserve 464001 464001 0 0.00 0 51068 247 0 0
sp_fill_shopping_cart 31127 31127 0 0.00 1 13945 83 0 0
sp_fill_shopping_cart_clo_res 463962 463962 0 0.00 0 13701 18 0 0
sp_get_clo_for_invoice 4965 4965 0 0.00 0 33749 349 0 0
sp_kill_cost_storno 15108 15108 0 0.00 0 5926 68 0 0
sp_kill_qstorno_ret_qs2qd 1055631 1055631 0 0.00 0 53683 43 0 0
sp_kill_qty_storno 556991 556991 0 0.00 0 53687 99 0 0
sp_lock_selected_doc 20515 20515 0 0.00 0 7405 7 0 0
sp_make_cost_storno 27714 27714 0 0.00 0 49818 77 0 0
sp_multiply_rows_for_pdistr 27714 27714 0 0.00 0 8934 36 0 0
sp_multiply_rows_for_qdistr 20245 20245 0 0.00 0 42365 204 0 0
sp_pay_from_customer 7186 7186 0 0.00 5 13260 164 0 0
sp_pay_to_supplier 5838 5838 0 0.00 6 8464 185 0 0
sp_payment_common 13019 13019 0 0.00 2 8175 43 0 0
sp_qd_handle_on_cancel_clo 1785 1785 0 0.00 0 20002 329 0 0
sp_qd_handle_on_invoice_upd_sts 10211 10211 0 0.00 0 18961 510 0 0
sp_qd_handle_on_reserve_upd_sts 9726 9726 0 0.00 0 17076 61 0 0
sp_reserve_write_off 9726 9726 0 0.00 8 17099 268 0 0
sp_supplier_invoice 5439 5439 0 0.00 9 88090 3214 0 0
sp_supplier_order 5449 5449 0 0.00 8 46063 1320 0 0
srv_aggregate_perf_data 103 103 0 0.00 2298 146729 17580 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 745 142961 33003 0 0
srv_find_qd_qs_mism 1019004 1019004 0 0.00 0 16864 10 0 0
srv_make_invnt_saldo 547 547 0 0.00 41 81013 3358 0 0
srv_make_money_saldo 945 945 0 0.00 1 14381 377 0 0
srv_recalc_idx_stat 4 4 0 0.00 270806 450341 351550 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5013 5013 0 0.00 0 21798 863 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 2 7556 470 0 0
t$perf-norm:sp_cancel_client_order 1785 1785 0 0.00 0 1028 23 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 3012 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 1936 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 1146 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1441 31 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 91780 6845 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 644 15 0 0
t$perf-norm:sp_client_order 20246 20246 0 0.00 0 6211 32 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 1476 15 0 0
t$perf-norm:sp_pay_from_customer 7186 7186 0 0.00 0 3389 18 0 0
t$perf-norm:sp_pay_to_supplier 5838 5838 0 0.00 0 4345 18 0 0
t$perf-norm:sp_reserve_write_off 9726 9726 0 0.00 0 3537 20 0 0
t$perf-norm:sp_supplier_invoice 5439 5439 0 0.00 0 2749 27 0 0
t$perf-norm:sp_supplier_order 5449 5449 0 0.00 0 1786 27 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 390 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 102 5 0 0
t$perf-norm:srv_make_invnt_saldo 547 547 0 0.00 0 579 12 0 0
t$perf-norm:srv_make_money_saldo 945 945 0 0.00 0 5882 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 183 61 0 0
v_add_invoice_to_stock 10098 9924 174 1.72 0 2804 25 0 174
v_all_customers 37598 37598 0 0.00 0 125 4 0 0
v_all_suppliers 30391 30391 0 0.00 0 77 4 0 0
v_all_wares 40480 40480 0 0.00 0 83 4 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 1197 15 0 0
v_cancel_client_order 3570 3570 0 0.00 0 2384 9 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 1679 13 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 937 11 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 2268 14 0 0
v_cancel_supplier_invoice 844 822 22 2.60 0 2306 29 0 22
v_cancel_supplier_order 772 772 0 0.00 0 2359 13 0 0
v_cancel_write_off 2062 2062 0 0.00 0 5750 28 0 0
v_max_id_clo_ord 5448 5448 0 0.00 0 8237 7 0 0
v_max_id_clo_res 983 983 0 0.00 0 3908 25 0 0
v_max_id_ord_sup 5435 5435 0 0.00 0 4124 7 0 0
v_max_non_paid_invoice 1523 1523 0 0.00 0 245 8 0 0
v_max_non_paid_realizn 3606 3606 0 0.00 0 896 7 0 0
v_min_id_clo_ord 5448 5448 0 0.00 0 3013 9 0 0
v_min_id_clo_res 983 983 0 0.00 0 1154 9 0 0
v_min_id_ord_sup 5434 5434 0 0.00 0 8018 13 0 0
v_min_non_paid_invoice 5837 5837 0 0.00 0 7882 18 0 0
v_min_non_paid_realizn 7180 7180 0 0.00 0 8170 14 0 0
v_our_firm 10018 10018 0 0.00 0 77 4 0 0
v_reserve_write_off 19444 19444 0 0.00 0 2754 10 0 0
x_lock_depdocs_on_canc_invoice 10635 10635 0 0.00 0 10236 131 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 6634 398 0 0
x_make_qstorno_1000_1200 5449 5449 0 0.00 3 46046 1151 0 0
x_make_qstorno_1200_2000 5439 5439 0 0.00 5 81804 2988 0 0
x_make_qstorno_2100_3300 464001 464001 0 0.00 0 51059 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-07-2022 10:38:35 to 29-07-2022 10:38:39.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:22 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:23 19689 8192000000 9716790592 10014990336 797669568 8.873 100 86 171 0 826285504 7480160 21135776
08:09:47 13681 8192000000 9750808240 10057887744 826429760 9.163 100 86 172 0 858054096 11290496 20333840
08:11:02 15360 8192000000 9788846336 10099945472 855207952 9.452 100 93 187 0 895274960 18301920 21765088
08:12:26 8778 8192000000 9849303040 10178818048 899239536 9.891 100 89 174 0 962067504 43945968 18882000
08:13:38 4819 8192000000 9914567872 10261213184 943949344 10.332 100 82 155 1 1028607456 68706848 15951264
08:14:49 1268 8192000000 9961125168 10310598656 982386080 10.707 100 94 183 0 1086224960 84415200 19423680
08:15:51 999 8192000000 9979517888 10340786176 1020482272 11.077 100 91 180 2 1106140368 67658192 17999904
08:16:53 978 8192000000 10027590144 10400739328 1033369792 11.201 100 96 185 3 1148829744 96794080 18665872
08:17:56 1006 8192000000 10025429536 10398683136 1060313680 11.459 100 95 182 1 1150535504 71773920 18447904
08:18:58 2344 8192000000 10030270400 10407424000 1065320832 11.507 100 86 157 2 1145098832 63553152 16224848
08:20:01 933 8192000000 10044049504 10423390208 1072098832 11.572 100 91 180 1 1160134448 71024720 17010896
08:21:03 1162 8192000000 10032206576 10414788608 1080093200 11.648 100 95 178 2 1157472576 58341920 19037456
08:22:05 922 8192000000 10042013280 10433331200 1080518752 11.652 100 82 154 0 1162097648 66222544 15356352
08:23:07 1067 8192000000 10069462016 10457858048 1088336448 11.727 100 90 176 1 1191936912 86433872 17166592
08:24:10 1173 8192000000 10064168080 10454753280 1088439888 11.728 100 94 187 0 1188180240 80589088 19151264
08:25:12 1019 8192000000 10090809520 10479845376 1094416960 11.785 100 98 192 1 1214023952 100414400 19192592
08:26:14 1166 8192000000 10069625504 10458566656 1100037360 11.838 100 96 188 1 1194992112 75062992 19891760
08:27:16 1241 8192000000 10043110336 10432225280 1091424560 11.756 100 96 183 0 1162137824 50430304 20282960
08:28:18 992 8192000000 10036589152 10423390208 1090388144 11.746 100 91 175 0 1156350640 47104512 18857984
08:29:20 1061 8192000000 10060154832 10451750912 1094122336 11.782 100 87 168 2 1181359568 71073456 16163776
08:30:22 3969 8192000000 10043631040 10436272128 1082243728 11.669 100 76 147 3 1166833040 70754768 13834544
08:31:27 848 8192000000 10031129184 10413150208 1068495744 11.538 100 88 165 1 1152572016 66543744 17532528
08:32:29 1192 8192000000 10033767760 10408812544 1061138224 11.467 100 99 189 1 1154863680 73901376 19824080
08:33:31 1235 8192000000 9993991888 10369626112 1041563328 11.280 100 93 176 4 1120976752 60907200 18506224
08:34:34 1529 8192000000 9983399888 10362687488 1032818336 11.196 100 78 151 2 1107456816 59560944 15077536
08:35:37 1080 8192000000 9976941680 10352840704 1039529536 11.260 100 85 166 2 1096535776 40655600 16350640
08:36:39 1060 8192000000 9997279904 10373971968 1040853728 11.273 100 95 183 0 1120359408 58596560 20909120
08:37:42 3639 8192000000 9986284672 10361212928 1032264272 11.190 100 77 139 1 1094471104 48873632 13333200
08:38:55 10922 8192000000 10012380128 10395242496 1047963936 11.341 100 61 117 0 1130592288 71200560 11427792
08:40:13 11690 8192000000 10030264032 10415837184 1065338800 11.508 100 64 124 2 1151138176 72721280 13078096
08:41:28 2094 8192000000 10063125488 10444574720 1082505120 11.671 100 86 153 0 1180384304 83222192 14656992
08:42:33 2485 8192000000 10106007952 10487025664 1094897936 11.789 100 92 168 0 1222211936 111564096 15749904
08:43:38 4199 8192000000 10145052560 10535657472 1109392928 11.927 100 72 140 1 1265112064 140027840 15691296
08:44:49 7091 8192000000 10196695536 10594508800 1119691328 12.024 100 76 147 3 1310441424 175422208 15327888
08:45:59 1963 8192000000 10229633872 10632052736 1128031968 12.103 100 94 182 1 1347262160 199394784 19835408
08:47:04 1627 8192000000 10267565488 10661007360 1142522416 12.239 100 92 174 0 1382287248 219161536 20603296
08:48:09 845 8192000000 10292784800 10700324864 1152569376 12.334 100 97 190 0 1404503776 230632928 21301472
08:49:12 1077 8192000000 10308403792 10720436224 1160708144 12.410 100 92 184 1 1418649936 237587312 20354480
08:50:19 1964 8192000000 10328078160 10742898688 1171769488 12.513 100 84 166 0 1440102528 249478112 18854928
08:51:27 1382 8192000000 10363903216 10781544448 1178431136 12.576 100 91 178 2 1471425760 273133872 19860752
08:52:30 923 8192000000 10391418672 10785873920 1176044480 12.553 100 99 190 2 1494859088 296903920 21910688
08:53:33 1115 8192000000 10415159136 10814504960 1174993808 12.543 100 93 179 1 1520270272 326133424 19143040
08:54:38 1001 8192000000 10453463808 10861486080 1182864928 12.617 100 98 196 2 1563416176 358204128 22347120
08:55:42 1436 8192000000 10475995040 10883633152 1178217168 12.574 100 97 192 1 1584579264 383590096 22772000
08:56:46 1481 8192000000 10546190048 10948714496 1185551136 12.642 100 97 192 1 1650590096 441502240 23536720
08:57:51 1121 8192000000 10545631056 10959089664 1177324608 12.565 100 97 193 0 1648977072 448706608 22945856
08:58:55 781 8192000000 10557771984 10998800384 1180654992 12.596 100 99 194 1 1661508384 456754464 24098928
08:59:59 1224 8192000000 10568455504 10989965312 1172536752 12.521 100 82 160 1 1675599328 481979456 21083120
09:01:05 3984 8192000000 10547279216 11051036672 1185713232 12.643 100 79 146 2 1664587440 459209552 19664656
09:02:10 737 8192000000 10470377440 11003645952 1190713520 12.690 100 90 173 2 1576904480 364797312 21393648
09:03:18 15980 8192000000 10406042800 10871455744 1176380432 12.556 100 35 68 1 1528507616 343154576 8972608
09:04:40 26609 8192000000 10403516656 10887704576 1143561232 12.249 98 24 48 0 1495422432 345272144 6589056
09:07:04 46071 8192000000 10244396128 10699595776 1155287248 12.359 100 18 36 0 1341462736 181899808 4275680
09:08:56 33494 8192000000 10107010176 10549362688 1150151600 12.311 100 26 52 1 1231896896 74914176 6831120
09:10:31 81254 8192000000 10068706240 10499080192 1116585168 11.995 98 37 71 1 1163554208 39378512 7590528
09:12:54 71187 8192000000 10056894224 10550534144 1107141024 11.905 97 19 38 0 1154132464 42210720 4780720
09:15:07 21026 8192000000 10067239072 10518544384 1132687760 12.147 100 24 43 0 1159029584 22530912 3810912
09:16:29 43865 8192000000 10100776464 10554109952 1113979600 11.970 98 26 50 0 1179731392 60871856 4879936
09:18:18 27808 8192000000 10124989920 10559119360 1139369008 12.210 99 58 103 0 1216353264 65244928 11739328
09:19:50 26528 8192000000 10149477200 10609401856 1152139968 12.330 100 45 86 0 1256731536 94981248 9610320
09:21:19 24873 8192000000 10128138192 10608685056 1131570656 12.136 99 16 32 0 1216127472 80639312 3917504
09:23:20 46136 8192000000 10115031280 10586243072 1117798320 12.006 98 8 15 0 1209200112 89272144 2129648
09:25:19 74029 8192000000 10040251328 10499194880 1090649072 11.749 96 16 32 1 1130765744 36731792 3384880
09:27:33 28063 8192000000 10080441904 10510864384 1104888032 11.884 98 45 89 1 1157341488 43485360 8968096
09:29:11 49565 8192000000 10109045808 10558992384 1113835936 11.969 99 19 38 1 1209475696 91432432 4207328
09:31:10 43230 8192000000 10126124288 10632372224 1118578096 12.014 99 20 40 0 1234711488 111028128 5105264
09:33:14 79883 8192000000 10110780160 10576474112 1109170560 11.925 98 14 28 0 1206912336 94834544 2907232
09:35:34 38339 8192000000 10060816224 10498699264 1109758400 11.930 99 20 39 0 1144232512 30615472 3858640
09:37:14 77138 8192000000 10140889376 10631647232 1112339360 11.955 99 33 66 0 1219202816 100452864 6410592
09:40:45 64710 8192000000 10051220160 10509004800 1100965824 11.847 98 36 69 0 1151950288 43924000 7060464
09:42:51 35968 8192000000 10083951296 10526941184 1108097520 11.914 99 32 60 0 1173415296 58775408 6542368
09:44:38 48024 8192000000 10068979440 10524733440 1126523984 12.089 100 15 30 0 1183289360 53707968 3057408
09:46:28 49734 8192000000 10085858720 10540023808 1123976160 12.065 100 13 26 0 1179753456 52932544 2844752
09:48:18 45727 8192000000 10112828256 10585329664 1136689184 12.184 100 22 40 0 1212138128 71103328 4345616
09:50:05 109609 8192000000 10108048096 10596229120 1080031536 11.648 95 19 38 0 1168578976 84406176 4141264
09:52:57 50754 8192000000 10077110176 10514137088 1112044176 11.952 99 14 28 0 1165684608 50951296 2689136
09:54:52 12639 8192000000 10071238640 10491899904 1096868560 11.808 98 48 96 1 1163778976 57070928 9839488
09:56:12 26702 8192000000 10088969472 10532593664 1108584128 11.919 99 36 72 0 1205888496 89692608 7611760
09:57:46 21815 8192000000 10074576960 10500730880 1117052912 11.999 99 41 75 0 1158742816 34652592 7037312
09:59:09 10028 8192000000 10089925984 10512101376 1109765088 11.930 99 55 108 0 1172098016 50264096 12068832
10:00:22 14618 8192000000 10114743312 10561761280 1129819744 12.120 100 36 72 0 1200406656 63436272 7150640
10:01:41 102635 8192000000 10054777456 10502066176 1081491728 11.662 96 31 60 0 1166270064 78987104 5791232
10:04:24 4633 8192000000 10058356688 10492248064 1116774864 11.997 100 43 82 2 1161112352 35203024 9134464
10:05:30 55232 8192000000 10104126096 10559971328 1125532208 12.079 100 20 40 0 1219811728 90089696 4189824
10:07:26 54890 8192000000 10074017664 10602491904 1125993552 12.084 100 7 14 0 1201850784 74255808 1601424
10:09:30 41207 8192000000 10106094992 10552700928 1101026288 11.847 97 20 40 0 1167109408 61812320 4270800
10:11:21 32930 8192000000 10135093936 10576355328 1123488080 12.060 99 31 60 0 1220790976 90216784 7086112
10:13:00 58967 8192000000 10157560464 10627186688 1124188128 12.067 99 40 78 1 1233747680 100639072 8920480
10:14:59 21916 8192000000 10198217456 10636603392 1123171792 12.057 98 40 80 0 1276296080 144282128 8842160
10:16:23 22412 8192000000 10231286784 10700812288 1141598368 12.231 100 40 80 0 1319471680 168915872 8957440
10:17:50 87573 8192000000 10114830944 10604421120 1126181424 12.085 99 14 28 0 1251446064 122619520 2645120
10:20:18 98628 8192000000 10092566272 10561613824 1095313248 11.793 96 16 32 0 1158657712 60438048 2906416
10:22:57 142958 8192000000 10078171088 10503749632 1120468816 12.031 98 22 42 0 1165918752 41351616 4098320
10:26:20 22523 8192000000 10114937632 10546003968 1127299968 12.096 98 55 106 0 1183223424 45713744 10209712
10:27:48 32315 8192000000 10097287936 10578710528 1141381488 12.229 100 24 46 0 1222078528 76284416 4412624
10:29:47 40144 8192000000 10059598768 10538586112 1118590448 12.014 98 11 22 0 1152430768 31643264 2197056
10:31:35 70738 8192000000 10057484544 10525417472 1111523248 11.947 98 12 22 0 1154111088 40337648 2250192
10:33:46 28626 8192000000 10101443360 10558611456 1133942448 12.159 99 27 54 0 1189836608 49600816 6293344
10:35:15 47446 8192000000 10113991616 10573426688 1114339008 11.973 98 32 64 0 1203543216 81771376 7432832

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 29-07-2022 10:38:41 to 29-07-2022 10:38:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-07-2022 10:38:58 to 29-07-2022 10:39:00.

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          218523
MON$OLDEST_ACTIVE               223299
MON$OLDEST_SNAPSHOT             223299
MON$NEXT_TRANSACTION            223299
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                       2048416
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 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 ---
:227: 
:228: mscs-test-161.budzdorov.ru    Fri Jul 29 10:37:20 2022
:229:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:230: 
:231: 
:232: mscs-test-161.budzdorov.ru    Fri Jul 29 10:37:21 2022
:233:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:234: 
:235: 
:236: mscs-test-161.budzdorov.ru    Fri Jul 29 10:37:22 2022
:237:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:238: 
:239: 
:240: mscs-test-161.budzdorov.ru    Fri Jul 29 10:37:22 2022
:241:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:242: 
--- end of diff output ---

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

29.07.22 10:39:01. 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
2022-07-29 10:39:03 No severe PSQL-related problems occured
29.07.22 10:39:04. End of report.