Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.06.2022 13:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16128400 kB
MemAvailable 31158464 kB
Buffers 0 kB
Cached 15554004 kB
SwapCached 2500 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-06-30 13:37:13
DTS_END                         2022-06-30 13:37:13.7360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56744
TRN_ID                          217375
ATT_ID                          628
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: 8892.45 25012 1067094
customer order: creation 164.25 512 19710
customer order: refuse 14.06 379 1687
order to supplier: creation 43.97 1366 5276
order to supplier: removal 3.50 158598 420
invoice (draft): creation 44.21 3097 5305
invoice (draft): removal 50.12 995 6014
invoice accept: apply 40.30 25958 4836
invoice accept: cancel 45.15 1135 5418
customer reserve: creation 3722.73 249 446727
customer reserve: removal 4482.11 97 537853
realization accept: apply 79.38 256 9525
realization accept: cancel 78.25 179 9390
payment from customer: creation 56.90 155 6828
payment from customer: removal 5.51 110 661
payment to supplier: creation 46.41 181 5569
payment to supplier: removal 3.08 142 370
service: total inventory turnovers 4.05 3439 486
service: total monetary turnovers 7.55 346 906
service: aggregate perf. data 0.91 16486 109
service: refresh index statistics 0.03 286551 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-06-2022 13:38:18 to 30-06-2022 13:38:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7862
TEST_TIME 2 7073
TEST_TIME 3 6426
TEST_TIME 4 5954
TEST_TIME 5 5641
TEST_TIME 6 5714
TEST_TIME 7 5707
TEST_TIME 8 6538
TEST_TIME 9 6847
TEST_TIME 10 7164
TEST_TIME 11 7377
TEST_TIME 12 7489
TEST_TIME 13 7642
TEST_TIME 14 7863
TEST_TIME 15 7752
TEST_TIME 16 7859
TEST_TIME 17 7811
TEST_TIME 18 7842
TEST_TIME 19 7963
TEST_TIME 20 8113
TEST_TIME 21 8034
TEST_TIME 22 8519
TEST_TIME 23 8833
TEST_TIME 24 8880
TEST_TIME 25 9096
TEST_TIME 26 9503
TEST_TIME 27 10137
TEST_TIME 28 11006
TEST_TIME 29 11383
TEST_TIME 30 11348
TEST_TIME 31 11107
TEST_TIME 32 10804
TEST_TIME 33 10701
TEST_TIME 34 10442
TEST_TIME 35 10622
TEST_TIME 36 10493
TEST_TIME 37 10406
TEST_TIME 38 10254
TEST_TIME 39 10366
TEST_TIME 40 10168
TEST_TIME 41 10010
TEST_TIME 42 9998
TEST_TIME 43 9950
TEST_TIME 44 9747
TEST_TIME 45 9760
TEST_TIME 46 9643
TEST_TIME 47 9728
TEST_TIME 48 9896
TEST_TIME 49 9829
TEST_TIME 50 9853
TEST_TIME 51 9863
TEST_TIME 52 9777
TEST_TIME 53 9700
TEST_TIME 54 9819
TEST_TIME 55 9767
TEST_TIME 56 9762
TEST_TIME 57 9783
TEST_TIME 58 9627
TEST_TIME 59 9609
TEST_TIME 60 9574
TEST_TIME 61 9529
TEST_TIME 62 9548
TEST_TIME 63 9434
TEST_TIME 64 9369
TEST_TIME 65 9338
TEST_TIME 66 9319
TEST_TIME 67 9343
TEST_TIME 68 9320
TEST_TIME 69 9350
TEST_TIME 70 9332
TEST_TIME 71 9296
TEST_TIME 72 9272
TEST_TIME 73 9254
TEST_TIME 74 9242
TEST_TIME 75 9246
TEST_TIME 76 9317
TEST_TIME 77 9446
TEST_TIME 78 9513
TEST_TIME 79 9446
TEST_TIME 80 9371
TEST_TIME 81 9338
TEST_TIME 82 9319
TEST_TIME 83 9346
TEST_TIME 84 9261
TEST_TIME 85 9193
TEST_TIME 86 9186
TEST_TIME 87 9097
TEST_TIME 88 9068
TEST_TIME 89 9139
TEST_TIME 90 9104
TEST_TIME 91 9099
TEST_TIME 92 9149
TEST_TIME 93 9112
TEST_TIME 94 9073
TEST_TIME 95 9083
TEST_TIME 96 9062
TEST_TIME 97 9065
TEST_TIME 98 9014
TEST_TIME 99 9146
TEST_TIME 100 9275
TEST_TIME 101 9260
TEST_TIME 102 9266
TEST_TIME 103 9273
TEST_TIME 104 9212
TEST_TIME 105 9149
TEST_TIME 106 9101
TEST_TIME 107 9105
TEST_TIME 108 9052
TEST_TIME 109 9014
TEST_TIME 110 8980
TEST_TIME 111 8958
TEST_TIME 112 8962
TEST_TIME 113 8916
TEST_TIME 114 8943
TEST_TIME 115 8927
TEST_TIME 116 8906
TEST_TIME 117 8944
TEST_TIME 118 8943
TEST_TIME 119 8986
TEST_TIME 120 8915

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-06-2022 13:38:21 to 30-06-2022 13:38:25.

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 1477310 1477310 0 0.00 0 25241 13 0 0
doc_list_biud 1477306 1477306 0 0.00 0 45937 41 0 0
sp_add_invoice_to_stock 4974 4836 138 2.77 66 249695 25959 0 138
sp_cancel_adding_invoice 5418 5418 0 0.00 7 74112 1136 0 0
sp_cancel_client_order 1687 1687 0 0.00 6 10751 380 0 0
sp_cancel_customer_reserve 537853 537853 0 0.00 1 45940 98 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 5929 111 0 0
sp_cancel_pay_to_supplier 370 370 0 0.00 4 6228 142 0 0
sp_cancel_supplier_invoice 6028 6014 14 0.23 3 30216 995 0 14
sp_cancel_supplier_order 420 420 0 0.00 10 1518419 158598 0 0
sp_cancel_write_off 9390 9390 0 0.00 2 23859 179 0 0
sp_client_order 19710 19710 0 0.00 5 38205 513 0 0
sp_customer_reserve 446727 446727 0 0.00 0 49352 250 0 0
sp_fill_shopping_cart 30282 30282 0 0.00 2 13513 81 0 0
sp_fill_shopping_cart_clo_res 446699 446699 0 0.00 0 16612 21 0 0
sp_get_clo_for_invoice 4788 4788 0 0.00 0 20935 299 0 0
sp_kill_cost_storno 15839 15839 0 0.00 0 20271 68 0 0
sp_kill_qstorno_ret_qs2qd 1091530 1091530 0 0.00 0 45908 43 0 0
sp_kill_qty_storno 575092 575092 0 0.00 0 45936 99 0 0
sp_lock_selected_doc 19864 19864 0 0.00 0 8093 7 0 0
sp_make_cost_storno 26707 26707 0 0.00 0 25236 71 0 0
sp_multiply_rows_for_pdistr 26707 26707 0 0.00 0 6756 33 0 0
sp_multiply_rows_for_qdistr 19710 19710 0 0.00 0 23210 215 0 0
sp_pay_from_customer 6828 6828 0 0.00 5 13996 155 0 0
sp_pay_to_supplier 5569 5569 0 0.00 6 15999 182 0 0
sp_payment_common 12397 12397 0 0.00 2 10863 44 0 0
sp_qd_handle_on_cancel_clo 1687 1687 0 0.00 0 10719 313 0 0
sp_qd_handle_on_invoice_upd_sts 10203 10203 0 0.00 0 33355 496 0 0
sp_qd_handle_on_reserve_upd_sts 9525 9525 0 0.00 0 11408 57 0 0
sp_reserve_write_off 9525 9525 0 0.00 8 25261 257 0 0
sp_supplier_invoice 5305 5305 0 0.00 8 87620 3097 0 0
sp_supplier_order 5276 5276 0 0.00 7 43533 1366 0 0
srv_aggregate_perf_data 109 109 0 0.00 2034 167087 16486 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 939 105955 39044 0 0
srv_find_qd_qs_mism 1019499 1019499 0 0.00 0 24167 10 0 0
srv_make_invnt_saldo 486 486 0 0.00 44 66198 3440 0 0
srv_make_money_saldo 906 906 0 0.00 1 25432 346 0 0
srv_recalc_idx_stat 4 4 0 0.00 104560 395986 286551 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4836 4836 0 0.00 0 11211 908 0 0
t$perf-norm:sp_cancel_adding_invoice 363 363 0 0.00 1 7424 394 0 0
t$perf-norm:sp_cancel_client_order 1687 1687 0 0.00 0 3413 31 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 1242 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 5123 28 0 0
t$perf-norm:sp_cancel_pay_to_supplier 370 370 0 0.00 0 853 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 558 31 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 112363 5453 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 1671 18 0 0
t$perf-norm:sp_client_order 19710 19710 0 0.00 0 6016 37 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 2792 19 0 0
t$perf-norm:sp_pay_from_customer 6828 6828 0 0.00 0 7876 20 0 0
t$perf-norm:sp_pay_to_supplier 5569 5569 0 0.00 0 2453 17 0 0
t$perf-norm:sp_reserve_write_off 9525 9525 0 0.00 0 7904 23 0 0
t$perf-norm:sp_supplier_invoice 5305 5305 0 0.00 0 13251 32 0 0
t$perf-norm:sp_supplier_order 5276 5276 0 0.00 0 4313 30 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 809 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 34 3 0 0
t$perf-norm:srv_make_invnt_saldo 486 486 0 0.00 0 287 8 0 0
t$perf-norm:srv_make_money_saldo 906 906 0 0.00 0 4304 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 100 33 0 0
v_add_invoice_to_stock 9844 9568 276 2.80 0 3258 24 0 276
v_all_customers 36156 36156 0 0.00 0 273 4 0 0
v_all_suppliers 29476 29476 0 0.00 0 275 4 0 0
v_all_wares 39418 39418 0 0.00 0 271 4 0 0
v_cancel_adding_invoice 726 726 0 0.00 0 4807 26 0 0
v_cancel_client_order 3374 3374 0 0.00 0 2310 9 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 2759 12 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 366 10 0 0
v_cancel_payment_to_supplier 740 740 0 0.00 0 1212 12 0 0
v_cancel_supplier_invoice 820 792 28 3.41 0 1891 27 0 28
v_cancel_supplier_order 840 840 0 0.00 0 2099 13 0 0
v_cancel_write_off 1984 1984 0 0.00 0 9801 27 0 0
v_max_id_clo_ord 5275 5275 0 0.00 0 185 5 0 0
v_max_id_clo_res 963 963 0 0.00 0 3939 21 0 0
v_max_id_ord_sup 5298 5298 0 0.00 0 4583 7 0 0
v_max_non_paid_invoice 1404 1404 0 0.00 0 1277 8 0 0
v_max_non_paid_realizn 3482 3482 0 0.00 0 5542 9 0 0
v_min_id_clo_ord 5275 5275 0 0.00 0 2308 8 0 0
v_min_id_clo_res 963 963 0 0.00 0 1253 10 0 0
v_min_id_ord_sup 5298 5298 0 0.00 0 6662 11 0 0
v_min_non_paid_invoice 5569 5569 0 0.00 0 10856 19 0 0
v_min_non_paid_realizn 6828 6828 0 0.00 0 6087 14 0 0
v_our_firm 9954 9954 0 0.00 0 57 5 0 0
v_reserve_write_off 19050 19050 0 0.00 0 6865 12 0 0
x_lock_depdocs_on_canc_invoice 11036 11036 0 0.00 0 17185 127 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 14205 348 0 0
x_make_qstorno_1000_1200 5276 5276 0 0.00 2 43523 1211 0 0
x_make_qstorno_1200_2000 5305 5305 0 0.00 4 87590 2869 0 0
x_make_qstorno_2100_3300 446726 446726 0 0.00 0 49335 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-06-2022 13:38:26 to 30-06-2022 13:38:30.

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
11:07:17 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:18 25352 8192000000 9721553440 10020339712 794480816 8.840 100 90 181 0 824639248 7660384 22498048
11:09:51 16917 8192000000 9754177936 10061615104 829226720 9.191 100 87 170 0 861596048 11891040 20478288
11:11:19 11409 8192000000 9793220224 10114592768 865751264 9.558 100 84 168 0 905223728 20059824 19412640
11:12:45 5274 8192000000 9857011792 10189475840 923056896 10.126 100 88 174 0 975317456 33312816 18947744
11:13:57 2149 8192000000 9921790144 10268139520 959946976 10.488 100 91 175 1 1033856352 56199680 17709696
11:15:04 2377 8192000000 9969743376 10325221376 996450192 10.844 100 89 177 0 1083909504 69398752 18060560
11:16:10 1451 8192000000 10014205344 10382712832 1029321520 11.162 100 88 169 1 1128947456 82723024 16902912
11:17:14 875 8192000000 10019984752 10387066880 1044127872 11.304 100 97 182 1 1137892544 76087152 17677520
11:18:16 1094 8192000000 10035983616 10409541632 1058915904 11.446 100 96 184 1 1150269840 72673408 18680528
11:19:18 840 8192000000 10052889424 10429775872 1074533984 11.595 100 95 190 1 1173745808 79787616 19424208
11:20:21 1825 8192000000 10057945152 10435530752 1074577472 11.596 100 89 170 0 1175306672 83624976 17104224
11:21:25 1022 8192000000 10037625152 10421284864 1089254432 11.736 100 94 179 1 1162250064 54511904 18483728
11:22:27 4345 8192000000 10034055888 10421534720 1088419904 11.728 100 72 139 2 1163943232 62149952 13373376
11:23:32 2088 8192000000 10054856704 10443784192 1094567936 11.786 100 87 159 0 1179882496 68673520 16641040
11:24:35 1587 8192000000 10054242576 10444570624 1095376336 11.794 100 80 146 2 1175974304 66039856 14558112
11:25:38 1024 8192000000 10080158704 10468757504 1100891264 11.846 100 96 179 1 1202391840 82600880 18899696
11:26:39 807 8192000000 10045804816 10436534272 1088033488 11.724 99 91 176 0 1168801504 61933264 18834752
11:27:41 1026 8192000000 10058004224 10452566016 1100662656 11.844 100 85 167 1 1182019664 64260496 17096512
11:28:43 3097 8192000000 10075057232 10471079936 1099023552 11.828 100 74 146 0 1194234688 80978016 14233120
11:29:49 1000 8192000000 10044863392 10431684608 1090419328 11.747 100 88 169 1 1166473264 57012560 19041376
11:30:51 891 8192000000 10020363488 10407579648 1061777072 11.473 100 87 161 2 1143577920 64161600 17639248
11:31:53 1105 8192000000 10021444496 10399084544 1055755936 11.416 100 93 180 0 1144633344 70646992 18230416
11:32:55 960 8192000000 9978240864 10350776320 1039223888 11.257 100 94 172 2 1099659808 42457600 17978320
11:33:56 1116 8192000000 9998641152 10378686464 1044324752 11.306 100 83 157 0 1120584800 59487984 16772064
11:34:58 1975 8192000000 10010238032 10385022976 1032650272 11.194 99 79 152 2 1124062976 75236944 16175760
11:36:01 1083 8192000000 9983128464 10356957184 1032344544 11.191 100 95 175 0 1108068688 57039376 18684768
11:37:03 1750 8192000000 10010587504 10388869120 1054728096 11.406 100 87 158 4 1126106928 55798704 15580128
11:38:14 3204 8192000000 10010096768 10386796544 1062240624 11.478 99 77 142 0 1125622352 49387568 13994160
11:39:38 1334 8192000000 10069293072 10453897216 1098901888 11.827 100 94 176 1 1186478784 70787600 16789296
11:40:43 23648 8192000000 10089121984 10483572736 1104436752 11.880 100 70 127 1 1204026800 86289888 13300160
11:42:14 5895 8192000000 10119328608 10510585856 1116553200 11.994 100 79 147 2 1241515248 109573072 15388976
11:43:27 7342 8192000000 10184005728 10582593536 1124342032 12.068 100 65 127 1 1302701216 164693936 13665248
11:44:42 2367 8192000000 10241209952 10644078592 1135303616 12.171 100 82 157 0 1356787840 203563168 17921056
11:45:49 2633 8192000000 10250799328 10652381184 1149810896 12.308 100 85 154 2 1365979776 200597984 15570896
11:46:54 1225 8192000000 10274724720 10677485568 1154446432 12.351 100 95 184 1 1389266832 214377296 20443104
11:47:57 1052 8192000000 10292830352 10707574784 1160306304 12.406 100 93 171 0 1404551584 224542528 19702752
11:49:02 1119 8192000000 10307442896 10716114944 1171753488 12.513 100 98 193 1 1415511504 223322288 20435728
11:50:08 1703 8192000000 10318754112 10731823104 1180414224 12.594 100 93 185 1 1425966560 225111136 20441200
11:51:13 1507 8192000000 10371255984 10786193408 1186601312 12.652 100 89 169 1 1484161264 277473376 20086576
11:52:16 1063 8192000000 10395391520 10798018560 1196509744 12.744 100 94 179 0 1504490496 285691184 22289568
11:53:20 1393 8192000000 10439096832 10839838720 1175597920 12.549 99 90 175 0 1553905936 357942480 20365536
11:54:23 1242 8192000000 10482092688 10890063872 1189374928 12.678 100 91 179 1 1592367296 381184752 21807616
11:55:30 927 8192000000 10505023952 10922803200 1184352704 12.631 100 96 182 1 1610389760 403691360 22345696
11:56:33 1143 8192000000 10531876192 10963759104 1184282896 12.630 100 95 186 1 1636596144 429440624 22872624
11:57:37 1487 8192000000 10502551584 10932748288 1175537808 12.549 100 86 160 2 1604472112 409449296 19485008
11:58:45 1321 8192000000 10561922768 10993811456 1173178880 12.527 100 98 190 1 1666111776 470042688 22890208
11:59:48 5899 8192000000 10546191824 11020550144 1168731648 12.485 100 52 104 0 1651507504 469213104 13562752
12:01:02 16763 8192000000 10483704144 10925027328 1164552736 12.446 100 32 64 0 1589656128 416206144 8897248
12:02:26 20437 8192000000 10467665968 10903855104 1137441104 12.191 99 37 74 0 1563739856 416365904 9932848
12:03:55 18486 8192000000 10375017328 10871115776 1137167008 12.189 100 37 72 0 1497114480 350840032 9107440
12:05:14 31010 8192000000 10187173584 10700697600 1137816016 12.195 100 23 46 0 1350177104 206342400 6018688
12:06:55 91973 8192000000 10112619648 10540326912 1131544448 12.136 98 36 63 0 1190359696 52153136 6662112
12:10:03 104554 8192000000 10164190656 10618552320 1137762320 12.194 99 25 48 1 1249856896 106959936 5134640
12:13:12 50232 8192000000 10116795456 10570178560 1137598048 12.193 98 16 30 0 1192950272 51738064 3614160
12:15:10 83955 8192000000 10086952720 10611539968 1116464736 11.994 97 14 26 0 1177941584 58440176 3036672
12:17:38 58123 8192000000 10114899232 10572894208 1144551216 12.258 100 14 26 0 1214192256 66745952 2895088
12:19:37 51609 8192000000 10093625312 10585804800 1111596288 11.948 97 18 36 0 1202968336 87090832 4281216
12:21:29 38492 8192000000 10123557792 10573971456 1123140144 12.057 99 22 42 0 1209714432 81933488 4640800
12:23:07 46759 8192000000 10132877232 10595971072 1112796256 11.959 97 24 44 2 1210884432 92939856 5148320
12:24:57 55805 8192000000 10090517264 10603331584 1116552240 11.994 98 16 32 0 1228581168 108078000 3950928
12:26:54 78867 8192000000 10093052208 10539585536 1106860896 11.903 98 35 69 0 1146035376 32303648 6870832
12:29:13 37787 8192000000 10096123824 10532397056 1117701440 12.005 99 42 84 0 1182128512 56112224 8314848
12:30:52 13262 8192000000 10094601456 10548285440 1125764768 12.081 100 47 94 0 1211094640 75843792 9486080
12:32:12 13887 8192000000 10085459472 10526310400 1134049472 12.160 100 59 114 1 1184841472 38661040 12130960
12:33:27 43452 8192000000 10111284528 10562224128 1121636000 12.042 99 53 107 0 1206321424 74226816 10458608
12:35:20 86891 8192000000 10114412992 10554040320 1090538208 11.748 96 44 82 1 1181601696 82102576 8960912
12:37:50 36933 8192000000 10095477392 10537598976 1115421536 11.984 99 37 74 1 1175468208 52847232 7199440
12:39:30 45217 8192000000 10054211600 10538545152 1132578464 12.146 100 12 22 1 1169980320 34657792 2744064
12:41:15 105955 8192000000 10092347920 10560073728 1089732272 11.740 96 17 31 0 1154244640 61302400 3209968
12:44:02 21749 8192000000 10098195824 10522435584 1106206048 11.896 98 40 78 1 1184018512 69019776 8792688
12:45:29 52030 8192000000 10094724320 10535956480 1080491664 11.652 95 21 42 0 1162280432 76820000 4968768
12:47:36 31626 8192000000 10118276960 10543620096 1109053344 11.923 97 45 90 1 1193488864 73493248 10942272
12:49:11 40101 8192000000 10152028432 10598801408 1145925632 12.271 100 36 72 0 1259479120 105283840 8269648
12:50:55 30038 8192000000 10204131008 10644647936 1149252608 12.302 99 44 87 0 1298344544 139888896 9203040
12:52:35 19798 8192000000 10224611312 10660024320 1144992096 12.262 99 41 82 0 1300199104 146131152 9075856
12:54:06 77468 8192000000 10059964352 10545430528 1089242576 11.735 96 13 26 0 1192951280 100594656 3114048
12:56:25 94803 8192000000 10109855312 10557534208 1093511888 11.776 97 44 88 0 1157831712 55323984 8995840
12:59:18 16201 8192000000 10061459072 10486362112 1123662128 12.062 100 67 123 0 1160813248 25094960 12056160
13:00:35 64349 8192000000 10066902272 10557104128 1129396784 12.116 99 11 22 0 1182137424 50681264 2059376
13:02:40 84124 8192000000 10096099152 10588041216 1141033744 12.225 100 15 30 0 1192140384 47786640 3320000
13:05:05 87677 8192000000 10094216320 10583769088 1115683488 11.986 98 21 42 0 1207525904 87197360 4645056
13:07:36 69619 8192000000 10103323664 10580037632 1124741216 12.072 98 23 46 0 1189605344 59935376 4928752
13:09:54 42244 8192000000 10107335680 10546339840 1133135984 12.151 99 32 60 1 1191951824 52665456 6150384
13:11:41 15874 8192000000 10158161024 10594127872 1144148800 12.255 99 44 88 0 1242189440 88203296 9837344
13:12:57 50276 8192000000 10182880960 10632916992 1117770336 12.006 97 32 63 0 1260531056 136267920 6492800
13:14:51 86907 8192000000 10227387664 10701402112 1138976512 12.206 99 25 50 1 1306916352 162463248 5476592
13:17:36 94299 8192000000 10118750976 10575044608 1110684992 11.939 97 24 47 0 1176978496 61984400 4309104
13:20:13 52384 8192000000 10092559296 10567561216 1137057856 12.188 99 30 58 0 1203072704 60374368 5640480
13:22:12 87797 8192000000 10069568432 10512818176 1117456928 12.003 97 32 63 0 1163676672 39424400 6795344
13:24:40 61678 8192000000 10074353248 10558296064 1139695376 12.213 100 6 12 0 1173709696 32647200 1367120
13:27:36 69447 8192000000 10137242176 10622353408 1154208608 12.349 100 23 46 0 1235551968 76187200 5156160
13:30:32 39602 8192000000 10144365776 10602532864 1118917936 12.017 97 32 62 0 1213065392 86366752 7780704
13:32:33 76501 8192000000 10138237024 10606465024 1116215776 11.991 98 27 54 0 1208975584 86828288 5931520
13:34:59 35571 8192000000 10146232272 10587783168 1131599840 12.136 99 37 73 0 1225429216 85470464 8358912
13:36:36 54586 8192000000 8917163312 9134104576 78419536 0.948 7 2 4 0 96819248 18088832 310880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-06-2022 13:38:32 to 30-06-2022 13:38:48.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 30-06-2022 13:38:49 to 30-06-2022 13:38:50.

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          217669
MON$OLDEST_ACTIVE               217670
MON$OLDEST_SNAPSHOT             217670
MON$NEXT_TRANSACTION            217670
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                       2038624
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2794 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2794 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2794 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2794 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Thu Jun 30 13:37:39 2022
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
--- end of diff output ---

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

30.06.22 13:38: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-06-30 13:38:54 No severe PSQL-related problems occured
30.06.22 13:38:55. End of report.