Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.08.2022 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 15618444 kB
MemAvailable 30909512 kB
Buffers 20 kB
Cached 16062164 kB
SwapCached 2532 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-08-14 04:37:44
DTS_END                         2022-08-14 04:37:20.2520
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42666
TRN_ID                          225976
ATT_ID                          259
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: 9196.27 28299 1103552
customer order: creation 173.56 437 20827
customer order: refuse 15.39 370 1847
order to supplier: creation 46.82 1284 5618
order to supplier: removal 3.35 159357 402
invoice (draft): creation 45.99 2768 5519
invoice (draft): removal 50.32 959 6038
invoice accept: apply 42.67 27581 5120
invoice accept: cancel 45.99 1216 5519
customer reserve: creation 3959.93 265 475191
customer reserve: removal 4518.57 93 542228
realization accept: apply 85.48 271 10257
realization accept: cancel 73.75 171 8850
payment from customer: creation 61.47 168 7376
payment from customer: removal 5.76 135 691
payment to supplier: creation 51.35 181 6162
payment to supplier: removal 3.54 117 425
service: total inventory turnovers 3.83 4133 459
service: total monetary turnovers 7.58 361 909
service: aggregate perf. data 0.91 14676 109
service: refresh index statistics 0.04 351431 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-08-2022 04:40:19 to 14-08-2022 04:40:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8194
TEST_TIME 2 6024
TEST_TIME 3 6344
TEST_TIME 4 6996
TEST_TIME 5 6990
TEST_TIME 6 6837
TEST_TIME 7 7135
TEST_TIME 8 6971
TEST_TIME 9 7132
TEST_TIME 10 7287
TEST_TIME 11 7565
TEST_TIME 12 7593
TEST_TIME 13 7431
TEST_TIME 14 7388
TEST_TIME 15 7763
TEST_TIME 16 7745
TEST_TIME 17 7902
TEST_TIME 18 8126
TEST_TIME 19 8127
TEST_TIME 20 8107
TEST_TIME 21 8162
TEST_TIME 22 8192
TEST_TIME 23 8625
TEST_TIME 24 9015
TEST_TIME 25 9217
TEST_TIME 26 9494
TEST_TIME 27 9373
TEST_TIME 28 9951
TEST_TIME 29 10256
TEST_TIME 30 10362
TEST_TIME 31 10811
TEST_TIME 32 10808
TEST_TIME 33 11265
TEST_TIME 34 11316
TEST_TIME 35 11157
TEST_TIME 36 11021
TEST_TIME 37 11044
TEST_TIME 38 11099
TEST_TIME 39 11020
TEST_TIME 40 10865
TEST_TIME 41 10844
TEST_TIME 42 10881
TEST_TIME 43 10635
TEST_TIME 44 10525
TEST_TIME 45 10335
TEST_TIME 46 10286
TEST_TIME 47 10374
TEST_TIME 48 10261
TEST_TIME 49 10279
TEST_TIME 50 10183
TEST_TIME 51 10115
TEST_TIME 52 9978
TEST_TIME 53 9968
TEST_TIME 54 9908
TEST_TIME 55 9873
TEST_TIME 56 9789
TEST_TIME 57 9834
TEST_TIME 58 9767
TEST_TIME 59 9654
TEST_TIME 60 9588
TEST_TIME 61 9760
TEST_TIME 62 9691
TEST_TIME 63 9672
TEST_TIME 64 9724
TEST_TIME 65 9693
TEST_TIME 66 9677
TEST_TIME 67 9706
TEST_TIME 68 9695
TEST_TIME 69 9675
TEST_TIME 70 9691
TEST_TIME 71 9675
TEST_TIME 72 9758
TEST_TIME 73 9814
TEST_TIME 74 9756
TEST_TIME 75 9688
TEST_TIME 76 9649
TEST_TIME 77 9602
TEST_TIME 78 9546
TEST_TIME 79 9470
TEST_TIME 80 9402
TEST_TIME 81 9400
TEST_TIME 82 9353
TEST_TIME 83 9314
TEST_TIME 84 9298
TEST_TIME 85 9368
TEST_TIME 86 9358
TEST_TIME 87 9341
TEST_TIME 88 9373
TEST_TIME 89 9346
TEST_TIME 90 9366
TEST_TIME 91 9400
TEST_TIME 92 9404
TEST_TIME 93 9462
TEST_TIME 94 9457
TEST_TIME 95 9406
TEST_TIME 96 9343
TEST_TIME 97 9276
TEST_TIME 98 9286
TEST_TIME 99 9264
TEST_TIME 100 9237
TEST_TIME 101 9164
TEST_TIME 102 9163
TEST_TIME 103 9192
TEST_TIME 104 9204
TEST_TIME 105 9200
TEST_TIME 106 9183
TEST_TIME 107 9162
TEST_TIME 108 9190
TEST_TIME 109 9183
TEST_TIME 110 9205
TEST_TIME 111 9158
TEST_TIME 112 9208
TEST_TIME 113 9184
TEST_TIME 114 9226
TEST_TIME 115 9378
TEST_TIME 116 9398
TEST_TIME 117 9336
TEST_TIME 118 9332
TEST_TIME 119 9292
TEST_TIME 120 9226

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-08-2022 04:40:22 to 14-08-2022 04:40:26.

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 1540337 1540337 0 0.00 0 26722 14 0 0
doc_list_biud 1540326 1540326 0 0.00 0 32649 38 0 0
sp_add_invoice_to_stock 5268 5120 148 2.80 43 199974 27582 0 148
sp_cancel_adding_invoice 5519 5519 0 0.00 5 68310 1216 0 0
sp_cancel_client_order 1847 1847 0 0.00 6 9682 371 0 0
sp_cancel_customer_reserve 542228 542228 0 0.00 1 27671 93 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 4 6034 135 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 4 2486 118 0 0
sp_cancel_supplier_invoice 6051 6038 13 0.21 3 32663 960 0 13
sp_cancel_supplier_order 402 402 0 0.00 9 1397789 159358 0 0
sp_cancel_write_off 8850 8850 0 0.00 2 9621 172 0 0
sp_client_order 20827 20827 0 0.00 6 18386 437 0 0
sp_customer_reserve 475191 475191 0 0.00 0 99029 266 0 0
sp_fill_shopping_cart 31953 31953 0 0.00 1 17589 77 0 0
sp_fill_shopping_cart_clo_res 475155 475155 0 0.00 0 17362 19 0 0
sp_get_clo_for_invoice 5070 5070 0 0.00 0 29782 253 0 0
sp_kill_cost_storno 15485 15485 0 0.00 0 7361 71 0 0
sp_kill_qstorno_ret_qs2qd 1099746 1099746 0 0.00 0 32647 41 0 0
sp_kill_qty_storno 580210 580210 0 0.00 0 32649 94 0 0
sp_lock_selected_doc 21296 21296 0 0.00 0 11771 7 0 0
sp_make_cost_storno 28864 28864 0 0.00 0 26716 74 0 0
sp_multiply_rows_for_pdistr 28863 28863 0 0.00 0 9342 36 0 0
sp_multiply_rows_for_qdistr 20827 20827 0 0.00 0 12757 163 0 0
sp_pay_from_customer 7376 7376 0 0.00 5 14160 169 0 0
sp_pay_to_supplier 6162 6162 0 0.00 6 14496 182 0 0
sp_payment_common 13537 13537 0 0.00 2 9332 46 0 0
sp_qd_handle_on_cancel_clo 1847 1847 0 0.00 1 9670 304 0 0
sp_qd_handle_on_invoice_upd_sts 10589 10589 0 0.00 0 26152 494 0 0
sp_qd_handle_on_reserve_upd_sts 10256 10256 0 0.00 0 16826 61 0 0
sp_reserve_write_off 10257 10257 0 0.00 8 26743 272 0 0
sp_supplier_invoice 5519 5519 0 0.00 8 97738 2769 0 0
sp_supplier_order 5618 5618 0 0.00 6 86110 1284 0 0
srv_aggregate_perf_data 109 109 0 0.00 2314 148075 14676 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 727 126041 36533 0 0
srv_find_qd_qs_mism 1052958 1052958 0 0.00 0 11837 11 0 0
srv_make_invnt_saldo 459 459 0 0.00 27 57791 4133 0 0
srv_make_money_saldo 909 909 0 0.00 1 12735 361 0 0
srv_recalc_idx_stat 5 5 0 0.00 198547 536123 351431 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5128 5128 0 0.00 1 12858 870 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 1 5488 452 0 0
t$perf-norm:sp_cancel_client_order 1847 1847 0 0.00 0 1571 24 0 0
t$perf-norm:sp_cancel_customer_reserve 722 722 0 0.00 0 1720 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 539 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 1015 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 1103 26 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 102069 6597 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 496 17 0 0
t$perf-norm:sp_client_order 20828 20828 0 0.00 0 5865 31 0 0
t$perf-norm:sp_customer_reserve 1055 1055 0 0.00 0 3688 19 0 0
t$perf-norm:sp_pay_from_customer 7376 7376 0 0.00 0 3231 15 0 0
t$perf-norm:sp_pay_to_supplier 6162 6162 0 0.00 0 3377 18 0 0
t$perf-norm:sp_reserve_write_off 10257 10257 0 0.00 0 3814 21 0 0
t$perf-norm:sp_supplier_invoice 5519 5519 0 0.00 0 9093 30 0 0
t$perf-norm:sp_supplier_order 5618 5618 0 0.00 0 8417 31 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 539 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 2588 43 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 437 10 0 0
t$perf-norm:srv_make_money_saldo 909 909 0 0.00 0 1077 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 8 2287 557 0 0
v_add_invoice_to_stock 10434 10138 296 2.83 0 7374 28 0 296
v_all_customers 38306 38306 0 0.00 0 234 4 0 0
v_all_suppliers 31524 31524 0 0.00 0 236 4 0 0
v_all_wares 41642 41642 0 0.00 0 154 4 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 820 14 0 0
v_cancel_client_order 3694 3694 0 0.00 0 4356 11 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 6008 19 0 0
v_cancel_customer_reserve 1444 1444 0 0.00 0 7334 16 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 2410 15 0 0
v_cancel_supplier_invoice 872 846 26 2.98 0 1496 27 0 26
v_cancel_supplier_order 804 804 0 0.00 0 613 11 0 0
v_cancel_write_off 2032 2032 0 0.00 0 5273 24 0 0
v_max_id_clo_ord 5615 5615 0 0.00 0 371 5 0 0
v_max_id_clo_res 1055 1055 0 0.00 0 1067 9 0 0
v_max_id_ord_sup 5515 5515 0 0.00 0 9956 10 0 0
v_max_non_paid_invoice 1528 1528 0 0.00 0 69 7 0 0
v_max_non_paid_realizn 3848 3848 0 0.00 0 585 7 0 0
v_min_id_clo_ord 5615 5615 0 0.00 0 2574 9 0 0
v_min_id_clo_res 1054 1054 0 0.00 0 3280 12 0 0
v_min_id_ord_sup 5515 5515 0 0.00 0 6071 9 0 0
v_min_non_paid_invoice 6161 6161 0 0.00 0 5678 16 0 0
v_min_non_paid_realizn 7376 7376 0 0.00 0 9321 20 0 0
v_our_firm 10390 10390 0 0.00 0 111 4 0 0
v_reserve_write_off 20509 20509 0 0.00 0 6231 9 0 0
x_lock_depdocs_on_canc_invoice 11134 11134 0 0.00 0 13341 121 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 24618 494 0 0
x_make_qstorno_1000_1200 5618 5618 0 0.00 2 86095 1117 0 0
x_make_qstorno_1200_2000 5519 5519 0 0.00 3 97710 2569 0 0
x_make_qstorno_2100_3300 475191 475191 0 0.00 0 99028 234 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-08-2022 04:40:27 to 14-08-2022 04:40:31.

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:23 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:24 19069 8192000000 9719137120 10017206272 796297568 8.859 100 86 172 0 825326064 7860864 21167632
02:09:49 26082 8192000000 9765897632 10078265344 840609392 9.306 100 89 178 0 873276304 11702048 20964864
02:11:20 17178 8192000000 9806441536 10130309120 879736800 9.697 100 87 174 0 917268816 17528096 20003920
02:12:44 9347 8192000000 9859074576 10192470016 918405296 10.080 100 76 149 1 974563408 40503568 15654544
02:14:00 4187 8192000000 9939513648 10283962368 963032272 10.519 100 89 178 0 1049089088 67794944 18261872
02:15:07 2433 8192000000 9967557936 10319167488 991689104 10.798 100 93 184 0 1082885328 72728560 18467664
02:16:11 946 8192000000 9996556576 10359390208 1013813216 11.012 100 89 169 0 1113563696 82938096 16812384
02:17:14 984 8192000000 10010206656 10373423104 1038270240 11.248 100 93 178 1 1128643040 72291984 18080816
02:18:17 1382 8192000000 10023498160 10395021312 1054690352 11.406 100 96 192 0 1137025184 63943968 18390864
02:19:21 981 8192000000 10037024304 10410938368 1064057664 11.495 100 87 165 2 1150877680 70098752 16721264
02:20:26 952 8192000000 10060944560 10442432512 1070424912 11.556 100 96 188 1 1181309696 93142800 17741984
02:21:28 1158 8192000000 10068539248 10452295680 1082961664 11.676 100 95 189 1 1187222688 85219024 19042000
02:22:32 1218 8192000000 10058410448 10446639104 1085827680 11.703 100 90 177 0 1178955104 75391680 17735744
02:23:35 1370 8192000000 10066977632 10456580096 1089214704 11.735 100 97 183 1 1184243456 76798464 18230288
02:24:37 1334 8192000000 10050970192 10438664192 1094784304 11.788 100 94 183 3 1170228016 56958576 18485136
02:25:39 1205 8192000000 10079960608 10470813696 1100052240 11.838 100 96 185 0 1199876096 80553856 19270000
02:26:42 1187 8192000000 10098162336 10488111104 1103145344 11.867 100 100 200 1 1220891280 97225136 20520800
02:27:44 1198 8192000000 10061538864 10448121856 1106589136 11.900 100 92 180 1 1182821536 58473552 17758848
02:28:47 1144 8192000000 10057585248 10445848576 1106660512 11.901 100 95 171 1 1179824512 55603136 17560864
02:29:49 2405 8192000000 10033221120 10437885952 1092105376 11.763 100 62 120 0 1158909856 55182128 11622352
02:30:54 1147 8192000000 10020670992 10404204544 1065987024 11.514 100 93 176 2 1144382720 59180720 19214976
02:31:56 3339 8192000000 9991161888 10368233472 1048210480 11.344 100 81 137 2 1114149232 52617760 13320992
02:33:01 6291 8192000000 9974997984 10356248576 1039524912 11.260 100 66 122 0 1086962096 35205536 12231648
02:34:08 978 8192000000 9995740528 10371096576 1039059808 11.256 99 96 189 0 1119648400 62664656 17923936
02:35:09 1392 8192000000 10014631392 10387390464 1055726544 11.416 100 96 187 3 1138964704 63660432 19577728
02:36:11 5930 8192000000 9990711440 10369695744 1011658096 10.991 98 70 131 3 1105045552 79634992 13752464
02:37:18 1138 8192000000 10008386224 10387738624 1044864720 11.311 100 82 160 0 1125380848 64786992 15729136
02:38:26 9938 8192000000 10010045040 10385809408 1057647168 11.434 100 78 144 1 1129403472 58296880 13459424
02:39:43 16692 8192000000 10029335040 10421907456 1070443840 11.556 100 65 124 1 1143334624 60076816 12813968
02:41:03 2775 8192000000 10075066608 10464665600 1077699904 11.626 100 68 132 0 1199680864 107871984 14108976
02:42:08 1593 8192000000 10117229200 10502037504 1099255120 11.831 100 87 161 4 1238228208 121761424 17211664
02:43:14 4476 8192000000 10130981360 10522116096 1110376640 11.936 100 88 175 1 1250499440 121953984 18168816
02:44:30 5127 8192000000 10170502096 10576269312 1118952384 12.017 100 73 140 0 1274187760 140516192 14719184
02:45:39 1229 8192000000 10202685888 10595500032 1132706000 12.147 100 90 166 3 1313607984 162666176 18235808
02:46:44 3183 8192000000 10219886976 10619109376 1153726224 12.344 100 80 158 0 1334146016 163244000 17175792
02:47:51 1005 8192000000 10247854704 10647756800 1164317296 12.444 100 97 187 0 1368141824 182854080 20970448
02:48:53 2633 8192000000 10266798016 10675445760 1172555424 12.521 100 82 155 0 1379474848 189551264 17368160
02:50:01 4926 8192000000 10289319728 10695684096 1186124224 12.647 100 79 153 1 1398159584 195618560 16416800
02:51:10 1725 8192000000 10322839696 10739302400 1179174848 12.582 100 93 182 0 1431804720 232101760 20528112
02:52:16 1887 8192000000 10377176416 10791149568 1197899664 12.757 100 96 176 1 1482654208 264252576 20501968
02:53:20 712 8192000000 10397410912 10822365184 1201188672 12.787 100 96 187 0 1507802880 284928624 21685584
02:54:25 1157 8192000000 10415881312 10852909056 1204703856 12.820 100 94 184 0 1522131136 296619872 20807408
02:55:28 1300 8192000000 10465427040 10877284352 1201635168 12.792 100 94 183 0 1574593600 350500992 22457440
02:56:32 1031 8192000000 10504559152 10911203328 1203091408 12.805 100 95 183 1 1613331088 387841712 22397968
02:57:37 1272 8192000000 10497526736 10906578944 1191419120 12.697 100 94 181 1 1600456608 387893888 21143600
02:58:41 1561 8192000000 10540507488 10952384512 1188853312 12.673 100 91 179 0 1643171280 432371152 21946816
02:59:46 1208 8192000000 10528352720 10962006016 1175901472 12.552 100 96 185 0 1632111312 431459760 24750080
03:00:49 5198 8192000000 10466916576 10964119552 1168581328 12.484 99 78 154 0 1574483056 386112912 19788816
03:01:59 2749 8192000000 10477660336 10927267840 1183722128 12.625 100 65 129 0 1585036928 386250704 15064096
03:03:05 12002 8192000000 10463082256 10912813056 1174978032 12.543 100 36 70 0 1557140480 372619568 9542880
03:04:32 35593 8192000000 10439712208 10903805952 1136457888 12.182 99 35 70 0 1526787280 381423888 8905504
03:06:24 28859 8192000000 10265412688 10740428800 1116831760 11.997 98 28 55 0 1393892368 270232272 6828336
03:07:54 76264 8192000000 10200637472 10652213248 1131871040 12.139 99 18 36 0 1288181584 152217696 4092848
03:10:13 40201 8192000000 10067381920 10514771968 1095202816 11.792 97 28 45 2 1171103328 69076720 6823792
03:11:57 68093 8192000000 10077529168 10510307328 1106011664 11.895 98 23 44 1 1155219248 44703552 4504032
03:14:07 54186 8192000000 10119145328 10603180032 1119801184 12.025 99 11 22 0 1202602448 80562496 2238768
03:16:08 84604 8192000000 10103661408 10528763904 1113519440 11.966 98 43 84 0 1192855488 71054224 8281824
03:18:42 99299 8192000000 10102388944 10600931328 1134442400 12.163 99 20 40 0 1239205280 100753760 4009120
03:22:01 54051 8192000000 10120110160 10557661184 1150229120 12.312 100 46 80 1 1224909776 66637776 8042880
03:23:57 40829 8192000000 10062219584 10518638592 1069570112 11.548 94 26 48 1 1126917952 51352736 5995104
03:25:43 24291 8192000000 10100843488 10556592128 1132669824 12.147 100 40 80 0 1204168624 63801744 7697056
03:27:08 90885 8192000000 10106887664 10560741376 1147659040 12.288 100 20 39 0 1191046752 38738800 4648912
03:29:40 33330 8192000000 10124364848 10591633408 1158086272 12.385 100 27 54 1 1225369888 60910800 6372816
03:31:13 70630 8192000000 10137762144 10621550592 1149854352 12.308 100 26 52 0 1247459024 91089216 6515456
03:33:33 64367 8192000000 10081720816 10571608064 1115157168 11.981 97 24 46 0 1203047344 82881248 5008928
03:35:40 65387 8192000000 10139626160 10609401856 1132620432 12.146 99 21 42 0 1214422416 77361760 4440224
03:38:28 50939 8192000000 10061410160 10513960960 1126814800 12.091 99 8 16 0 1168802928 40544128 1444000
03:40:22 39183 8192000000 10115408224 10564849664 1129181856 12.114 99 37 74 0 1198309744 61318448 7809440
03:42:01 8139 8192000000 10074971760 10501025792 1118944368 12.017 99 46 86 1 1162305808 34442896 8918544
03:43:10 67918 8192000000 10102946000 10564366336 1127527024 12.098 99 26 52 1 1199191872 66364528 5300320
03:45:46 7747 8192000000 10098002096 10532659200 1122920304 12.055 99 40 80 0 1177713312 47089888 7703120
03:46:55 41867 8192000000 10168354960 10607321088 1124742928 12.072 98 43 85 0 1218425232 84988112 8694192
03:48:40 39453 8192000000 10146486336 10584080384 1069528816 11.548 94 25 44 1 1185528640 110520048 5479776
03:50:22 126041 8192000000 10111106192 10578399232 1126103536 12.085 100 37 74 0 1209517632 76611568 6802528
03:53:29 44421 8192000000 10067844720 10527850496 1100473952 11.842 98 40 80 0 1142797136 33951744 8371440
03:55:18 44793 8192000000 10054826864 10528792576 1100209056 11.840 98 7 13 0 1140936496 39643152 1084288
03:57:22 52853 8192000000 10051449088 10522009600 1092086240 11.762 97 26 48 0 1137619184 39863728 5669216
03:59:18 10922 8192000000 10053477328 10482593792 1082190448 11.668 96 44 76 0 1119838512 30050464 7597600
04:00:36 21429 8192000000 10093049904 10529640448 1096470976 11.804 98 32 62 0 1167121184 64036512 6613696
04:01:58 22386 8192000000 10076696032 10540875776 1095611696 11.796 99 33 66 0 1174216256 71646400 6958160
04:03:23 23101 8192000000 10083208640 10530729984 1108219712 11.916 99 46 91 0 1171231056 52999088 10012256
04:04:48 72468 8192000000 10082664048 10505814016 1094516160 11.786 97 38 75 0 1142354064 40337120 7500784
04:07:03 39756 8192000000 10112035536 10584805376 1102933344 11.865 98 39 78 1 1191768368 81069024 7766000
04:08:43 79593 8192000000 10135325808 10576076800 1113516560 11.966 98 37 72 1 1213678496 92736880 7425056
04:11:05 60912 8192000000 10074325456 10516107264 1110398800 11.936 98 15 30 0 1129052784 15607408 3046576
04:13:14 102683 8192000000 10087073936 10544476160 1135067152 12.169 100 29 56 0 1201826304 60903520 5855632
04:15:58 91693 8192000000 10053287568 10529173504 1098910592 11.827 97 11 20 1 1140598928 39211760 2476576
04:18:31 96457 8192000000 10126972784 10613231616 1144320128 12.256 100 22 44 0 1234642032 85408048 4913856
04:21:07 47214 8192000000 10083623760 10558414848 1115003104 11.980 98 23 45 0 1156081056 36887888 4190064
04:22:55 25097 8192000000 10101009184 10542428160 1140449392 12.220 100 28 56 0 1197942128 51981184 5511552
04:24:20 15439 8192000000 10124647136 10568249344 1117141280 12.000 98 31 62 0 1204012624 80002016 6869328
04:25:36 57316 8192000000 10156935664 10595954688 1121920768 12.045 97 26 52 0 1234415536 106852304 5642464
04:27:45 27997 8192000000 10191799328 10612613120 1134970624 12.168 98 51 96 2 1269351440 124000592 10380224
04:29:15 19100 8192000000 10278303760 10726854656 1125640096 12.080 98 53 106 0 1334074736 196638224 11796416
04:30:45 81034 8192000000 10108651456 10713980928 1124320896 12.068 99 20 40 0 1312521104 183346336 4853872
04:33:11 31876 8192000000 10194599328 10636980224 1137797792 12.195 100 55 106 1 1256166960 107719664 10649504
04:34:46 91031 8192000000 10075460048 10551459840 1133816288 12.157 100 22 44 0 1224776160 86107968 4851904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-08-2022 04:40:32 to 14-08-2022 04:40:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-08-2022 04:40:49 to 14-08-2022 04:40:51.

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          226011
MON$OLDEST_ACTIVE               227024
MON$OLDEST_SNAPSHOT             227024
MON$NEXT_TRANSACTION            227024
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                       2059744
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 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 ---
:233: 
:234: mscs-test-161.budzdorov.ru    Sun Aug 14 04:37:54 2022
:235:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:236: 
--- end of diff output ---

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

14.08.22 04:40:52. 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-08-14 04:40:55 No severe PSQL-related problems occured
14.08.22 04:40:56. End of report.