Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.01.2023 07: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 15186872 kB
MemAvailable 30459712 kB
Buffers 0 kB
Cached 16542572 kB
SwapCached 4244 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.3
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 2023-01-30 07:37:23
DTS_END                         2023-01-30 07:37:23.8990
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43878
TRN_ID                          206954
ATT_ID                          589
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: 8644.87 26403 1037384
customer order: creation 155.51 456 18661
customer order: refuse 13.24 393 1589
order to supplier: creation 40.48 1371 4858
order to supplier: removal 3.17 173309 380
invoice (draft): creation 40.48 3321 4857
invoice (draft): removal 50.30 864 6036
invoice accept: apply 37.70 26841 4524
invoice accept: cancel 45.47 1119 5456
customer reserve: creation 3524.14 256 422897
customer reserve: removal 4462.53 100 535504
realization accept: apply 74.40 270 8928
realization accept: cancel 80.98 150 9718
payment from customer: creation 53.44 166 6413
payment from customer: removal 5.37 158 644
payment to supplier: creation 43.19 177 5183
payment to supplier: removal 3.15 134 378
service: total inventory turnovers 3.85 3764 462
service: total monetary turnovers 6.63 344 796
service: aggregate perf. data 0.80 17731 96
service: refresh index statistics 0.03 297140 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-01-2023 07:40:13 to 30-01-2023 07:40:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3494
TEST_TIME 2 4945
TEST_TIME 3 4811
TEST_TIME 4 4504
TEST_TIME 5 4519
TEST_TIME 6 4792
TEST_TIME 7 4814
TEST_TIME 8 5141
TEST_TIME 9 5682
TEST_TIME 10 5716
TEST_TIME 11 6130
TEST_TIME 12 6309
TEST_TIME 13 6377
TEST_TIME 14 6430
TEST_TIME 15 7293
TEST_TIME 16 7405
TEST_TIME 17 7407
TEST_TIME 18 7481
TEST_TIME 19 7672
TEST_TIME 20 8250
TEST_TIME 21 8824
TEST_TIME 22 8767
TEST_TIME 23 8841
TEST_TIME 24 9335
TEST_TIME 25 9862
TEST_TIME 26 10145
TEST_TIME 27 10490
TEST_TIME 28 10614
TEST_TIME 29 10727
TEST_TIME 30 10418
TEST_TIME 31 10342
TEST_TIME 32 10322
TEST_TIME 33 10203
TEST_TIME 34 10090
TEST_TIME 35 10011
TEST_TIME 36 9901
TEST_TIME 37 9885
TEST_TIME 38 9784
TEST_TIME 39 9564
TEST_TIME 40 9646
TEST_TIME 41 9489
TEST_TIME 42 9401
TEST_TIME 43 9252
TEST_TIME 44 9166
TEST_TIME 45 9222
TEST_TIME 46 9169
TEST_TIME 47 9176
TEST_TIME 48 9115
TEST_TIME 49 9027
TEST_TIME 50 9000
TEST_TIME 51 8979
TEST_TIME 52 8947
TEST_TIME 53 8911
TEST_TIME 54 8801
TEST_TIME 55 8805
TEST_TIME 56 8810
TEST_TIME 57 8776
TEST_TIME 58 8954
TEST_TIME 59 8962
TEST_TIME 60 8900
TEST_TIME 61 8928
TEST_TIME 62 8903
TEST_TIME 63 8991
TEST_TIME 64 8979
TEST_TIME 65 9054
TEST_TIME 66 9127
TEST_TIME 67 8996
TEST_TIME 68 8967
TEST_TIME 69 8894
TEST_TIME 70 8878
TEST_TIME 71 8824
TEST_TIME 72 8768
TEST_TIME 73 8703
TEST_TIME 74 8612
TEST_TIME 75 8584
TEST_TIME 76 8589
TEST_TIME 77 8625
TEST_TIME 78 8670
TEST_TIME 79 8655
TEST_TIME 80 8661
TEST_TIME 81 8625
TEST_TIME 82 8687
TEST_TIME 83 8725
TEST_TIME 84 8695
TEST_TIME 85 8676
TEST_TIME 86 8699
TEST_TIME 87 8700
TEST_TIME 88 8701
TEST_TIME 89 8715
TEST_TIME 90 8634
TEST_TIME 91 8571
TEST_TIME 92 8625
TEST_TIME 93 8586
TEST_TIME 94 8586
TEST_TIME 95 8578
TEST_TIME 96 8539
TEST_TIME 97 8523
TEST_TIME 98 8502
TEST_TIME 99 8464
TEST_TIME 100 8482
TEST_TIME 101 8451
TEST_TIME 102 8471
TEST_TIME 103 8450
TEST_TIME 104 8479
TEST_TIME 105 8522
TEST_TIME 106 8613
TEST_TIME 107 8737
TEST_TIME 108 8923
TEST_TIME 109 8959
TEST_TIME 110 8907
TEST_TIME 111 8841
TEST_TIME 112 8863
TEST_TIME 113 8863
TEST_TIME 114 8806
TEST_TIME 115 8811
TEST_TIME 116 8816
TEST_TIME 117 8774
TEST_TIME 118 8759
TEST_TIME 119 8704
TEST_TIME 120 8664

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-01-2023 07:40:17 to 30-01-2023 07:40:20.

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 1426354 1426354 0 0.00 0 15614 13 0 0
doc_list_biud 1426354 1426354 0 0.00 0 59679 43 0 0
sp_add_invoice_to_stock 4604 4524 80 1.73 40 247187 26842 0 80
sp_cancel_adding_invoice 5456 5456 0 0.00 5 95529 1120 0 0
sp_cancel_client_order 1589 1589 0 0.00 6 13805 394 0 0
sp_cancel_customer_reserve 535504 535504 0 0.00 1 59689 100 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 3 6149 158 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 4 3452 135 0 0
sp_cancel_supplier_invoice 6045 6036 9 0.14 3 18622 865 0 9
sp_cancel_supplier_order 380 380 0 0.00 11 1188849 173310 0 0
sp_cancel_write_off 9718 9718 0 0.00 2 11027 151 0 0
sp_client_order 18661 18661 0 0.00 6 30514 456 0 0
sp_customer_reserve 422897 422897 0 0.00 0 59990 256 0 0
sp_fill_shopping_cart 28371 28371 0 0.00 2 23357 83 0 0
sp_fill_shopping_cart_clo_res 422853 422853 0 0.00 0 14501 21 0 0
sp_get_clo_for_invoice 4478 4478 0 0.00 0 23078 397 0 0
sp_kill_cost_storno 16196 16196 0 0.00 0 10279 68 0 0
sp_kill_qstorno_ret_qs2qd 1087142 1087142 0 0.00 0 59656 44 0 0
sp_kill_qty_storno 572085 572085 0 0.00 0 59677 100 0 0
sp_lock_selected_doc 18648 18648 0 0.00 0 8073 8 0 0
sp_make_cost_storno 24998 24998 0 0.00 0 15603 80 0 0
sp_multiply_rows_for_pdistr 24998 24998 0 0.00 0 7175 35 0 0
sp_multiply_rows_for_qdistr 18661 18661 0 0.00 0 11269 168 0 0
sp_pay_from_customer 6413 6413 0 0.00 5 8553 167 0 0
sp_pay_to_supplier 5183 5183 0 0.00 6 15487 178 0 0
sp_payment_common 11596 11596 0 0.00 2 15422 44 0 0
sp_qd_handle_on_cancel_clo 1589 1589 0 0.00 1 13764 322 0 0
sp_qd_handle_on_invoice_upd_sts 9930 9930 0 0.00 0 31184 485 0 0
sp_qd_handle_on_reserve_upd_sts 8928 8928 0 0.00 0 15423 69 0 0
sp_reserve_write_off 8928 8928 0 0.00 8 15573 271 0 0
sp_supplier_invoice 4857 4857 0 0.00 11 83218 3322 0 0
sp_supplier_order 4858 4858 0 0.00 7 43516 1372 0 0
srv_aggregate_perf_data 96 96 0 0.00 2629 178568 17732 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 811 183097 39156 0 0
srv_find_qd_qs_mism 992915 992915 0 0.00 0 11528 10 0 0
srv_make_invnt_saldo 462 462 0 0.00 34 81516 3764 0 0
srv_make_money_saldo 796 796 0 0.00 1 21385 345 0 0
srv_recalc_idx_stat 4 4 0 0.00 98881 491296 297141 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4524 4524 0 0.00 1 12092 778 0 0
t$perf-norm:sp_cancel_adding_invoice 368 368 0 0.00 1 6147 476 0 0
t$perf-norm:sp_cancel_client_order 1589 1589 0 0.00 0 14357 42 0 0
t$perf-norm:sp_cancel_customer_reserve 585 585 0 0.00 0 945 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 823 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 6897 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 361 361 0 0.00 0 6254 46 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 86556 6295 0 0
t$perf-norm:sp_cancel_write_off 941 941 0 0.00 0 9978 34 0 0
t$perf-norm:sp_client_order 18661 18661 0 0.00 0 6903 32 0 0
t$perf-norm:sp_customer_reserve 873 873 0 0.00 0 4990 17 0 0
t$perf-norm:sp_pay_from_customer 6413 6413 0 0.00 0 5006 20 0 0
t$perf-norm:sp_pay_to_supplier 5183 5183 0 0.00 0 5890 20 0 0
t$perf-norm:sp_reserve_write_off 8928 8928 0 0.00 0 6469 21 0 0
t$perf-norm:sp_supplier_invoice 4857 4857 0 0.00 0 9339 27 0 0
t$perf-norm:sp_supplier_order 4858 4858 0 0.00 0 5445 26 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 174 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 205 7 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 1414 21 0 0
t$perf-norm:srv_make_money_saldo 796 796 0 0.00 0 2857 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 180 50 0 0
v_add_invoice_to_stock 9108 8948 160 1.75 0 7815 26 0 160
v_all_customers 33968 33968 0 0.00 0 93 3 0 0
v_all_suppliers 27124 27124 0 0.00 0 58 4 0 0
v_all_wares 37318 37318 0 0.00 0 67 3 0 0
v_cancel_adding_invoice 736 736 0 0.00 0 528 14 0 0
v_cancel_client_order 3178 3178 0 0.00 0 4444 14 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 2000 14 0 0
v_cancel_customer_reserve 1170 1170 0 0.00 0 754 10 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 1391 14 0 0
v_cancel_supplier_invoice 740 722 18 2.43 0 8200 32 0 18
v_cancel_supplier_order 760 760 0 0.00 0 2050 14 0 0
v_cancel_write_off 1882 1882 0 0.00 0 7878 25 0 0
v_max_id_clo_ord 4858 4858 0 0.00 0 841 4 0 0
v_max_id_clo_res 873 873 0 0.00 0 8243 16 0 0
v_max_id_ord_sup 4854 4854 0 0.00 0 5208 8 0 0
v_max_non_paid_invoice 1333 1333 0 0.00 0 714 7 0 0
v_max_non_paid_realizn 3410 3410 0 0.00 0 4588 9 0 0
v_min_id_clo_ord 4858 4858 0 0.00 0 4519 12 0 0
v_min_id_clo_res 873 873 0 0.00 0 3789 12 0 0
v_min_id_ord_sup 4854 4854 0 0.00 0 5968 10 0 0
v_min_non_paid_invoice 5183 5183 0 0.00 0 15413 20 0 0
v_min_non_paid_realizn 6413 6413 0 0.00 0 6514 20 0 0
v_our_firm 9356 9356 0 0.00 0 95 4 0 0
v_reserve_write_off 17856 17856 0 0.00 0 7919 11 0 0
x_lock_depdocs_on_canc_invoice 11131 11131 0 0.00 0 13397 115 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 8426 343 0 0
x_make_qstorno_1000_1200 4858 4858 0 0.00 2 43431 1224 0 0
x_make_qstorno_1200_2000 4857 4857 0 0.00 5 83176 3071 0 0
x_make_qstorno_2100_3300 422897 422897 0 0.00 0 59987 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-01-2023 07:40:21 to 30-01-2023 07:40:25.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:27 3 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:28 24005 8192000000 9716412112 10011426816 795361136 8.849 100 93 186 0 825982720 7973536 22648048
05:09:55 15178 8192000000 9747935088 10052771840 821090544 9.109 100 88 176 0 854546656 11947824 21508288
05:11:17 12169 8192000000 9785838144 10101473280 857120272 9.471 100 82 164 0 896126912 20025600 18981040
05:12:33 9137 8192000000 9856154192 10187698176 913532256 10.032 100 86 169 1 968156352 36246208 18377888
05:13:44 4706 8192000000 9914039600 10253185024 949584512 10.387 100 86 170 0 1030157376 62192064 18380800
05:14:52 2538 8192000000 9959497936 10309283840 983419616 10.717 100 93 178 0 1073249968 71408768 18421584
05:15:56 1266 8192000000 9982503808 10340413440 1007535024 10.952 100 92 182 0 1096760928 70226720 18999184
05:17:00 2696 8192000000 10005059408 10374258688 1030890784 11.177 100 92 176 1 1121274192 73012688 17370720
05:18:04 887 8192000000 10016265056 10390695936 1054103264 11.400 100 92 178 1 1142117776 69350704 18663808
05:19:06 1425 8192000000 10046450656 10423083008 1070854624 11.560 100 96 185 3 1168956288 79191344 18910320
05:20:10 847 8192000000 10025201648 10407243776 1069143200 11.544 100 85 165 0 1149194816 62455024 17596592
05:21:12 931 8192000000 10037272752 10425683968 1080990192 11.657 100 81 158 3 1162013520 64682176 16341152
05:22:14 9952 8192000000 10044416336 10427142144 1084751360 11.693 100 91 165 4 1160042656 58833488 16457808
05:23:25 990 8192000000 10054235920 10444951552 1090419088 11.747 100 90 171 1 1180660912 72064976 18176848
05:24:26 1176 8192000000 10054365232 10440933376 1088906704 11.732 100 82 159 4 1175267088 69669504 16690880
05:25:28 1627 8192000000 10070697568 10459148288 1094226864 11.783 100 96 181 0 1193957392 81072896 18657632
05:26:31 2365 8192000000 10039574464 10437218304 1086136096 11.706 100 76 139 3 1169250800 68698640 14416064
05:27:34 1124 8192000000 10052371952 10439782400 1084573792 11.691 100 95 176 1 1176116352 73124224 18418336
05:28:36 1065 8192000000 10049889952 10440638464 1091808816 11.760 100 87 164 5 1171325504 62288816 17227872
05:29:38 982 8192000000 10051206640 10440146944 1095573152 11.796 100 89 171 1 1173045040 59748032 17723856
05:30:41 1335 8192000000 10021575328 10407313408 1070950816 11.561 100 79 151 2 1147018624 60013840 16053968
05:31:43 5248 8192000000 9999433840 10388267008 1052202832 11.382 99 52 99 1 1124857136 62725648 9928656
05:32:49 1520 8192000000 9994277728 10375974912 1052966592 11.389 100 79 144 3 1121982400 54025712 14990096
05:33:51 1578 8192000000 10007310768 10383331328 1027493808 11.144 98 81 156 1 1127919376 84062144 16363424
05:34:54 994 8192000000 10003884560 10376335360 1025919744 11.129 100 93 176 1 1128137216 83785120 18432352
05:35:57 1077 8192000000 9987275568 10366406656 1038098368 11.246 100 82 156 2 1110771456 57383968 15289120
05:36:59 955 8192000000 10006370736 10386432000 1046113728 11.323 100 87 163 2 1133042496 69686864 17241904
05:38:06 31646 8192000000 10020911504 10406141952 1065101440 11.505 100 64 120 2 1131345648 54830944 11413264
05:39:41 6457 8192000000 10037657136 10421706752 1077051504 11.619 99 72 143 0 1152488528 61230576 14206448
05:40:52 9420 8192000000 10086828000 10469113856 1098851376 11.827 100 69 138 1 1205538000 92373280 14313344
05:42:05 6347 8192000000 10104537104 10495639552 1104934912 11.884 100 67 127 1 1227500144 109038160 13527072
05:43:18 5919 8192000000 10172056832 10569379840 1116943072 11.998 100 63 118 1 1294910352 164585248 13382032
05:44:44 8900 8192000000 10225212064 10624204800 1129114880 12.113 100 64 125 0 1338452560 195128368 14209312
05:45:57 1334 8192000000 10257005552 10659282944 1144873360 12.261 100 90 178 0 1373495680 209510272 19112048
05:47:04 1330 8192000000 10259562880 10659590144 1159428512 12.398 100 90 170 0 1377125024 199191488 18505024
05:48:08 849 8192000000 10306582144 10713100288 1155601280 12.362 99 96 186 0 1424143552 245709680 22832592
05:49:13 1453 8192000000 10340856128 10744561664 1176642800 12.559 100 92 177 1 1454669392 258037312 19989280
05:50:17 1465 8192000000 10364110736 10769948672 1178617040 12.577 100 92 178 1 1470710832 271329456 20764336
05:51:26 1736 8192000000 10392206656 10828517376 1181123072 12.601 100 80 155 0 1503181392 303879632 18178688
05:52:30 850 8192000000 10417394944 10851041280 1182490240 12.613 100 80 160 0 1526592992 325010048 19092704
05:53:37 2731 8192000000 10403210112 10818195456 1178996320 12.581 100 77 150 1 1511293680 314023008 18274352
05:54:46 798 8192000000 10462384144 10868568064 1181376336 12.603 100 99 191 1 1568927936 366208608 21342992
05:55:50 838 8192000000 10464343712 10910027776 1191293328 12.695 100 99 195 0 1569988160 356365408 22329424
05:56:54 1430 8192000000 10469496592 10935357440 1187235520 12.658 100 93 178 1 1576403296 367674864 21492912
05:57:57 6402 8192000000 10436064256 10904526848 1192920832 12.711 100 49 98 0 1548329536 342501728 12906976
05:59:13 11219 8192000000 10408378048 10847391744 1174960608 12.543 99 41 82 0 1512664944 326740880 10963456
06:00:34 13860 8192000000 10388684192 10852712448 1176440672 12.557 100 30 58 0 1511613952 327287312 7885968
06:02:17 27510 8192000000 10265362784 10740981760 1154446752 12.351 99 34 64 0 1375518336 212645024 8426560
06:03:50 61554 8192000000 10176852224 10631933952 1135128704 12.170 98 21 42 1 1274209648 133974256 5106688
06:05:54 2741 8192000000 10102257632 10588475392 1127901840 12.102 100 54 107 2 1208413088 69080944 11430304
06:06:58 51129 8192000000 10134891072 10552528896 1133203664 12.152 100 72 124 4 1230136768 85008512 11924592
06:08:50 36920 8192000000 10104280256 10597171200 1128565680 12.108 99 19 38 0 1228950192 96194688 4189824
06:10:27 104573 8192000000 10065511072 10556059648 1083462752 11.680 95 20 38 0 1158645808 71446064 3736992
06:13:13 90398 8192000000 10088364656 10534699008 1117274288 12.001 99 17 34 1 1172697648 52009936 3413424
06:16:34 183097 8192000000 10149372160 10642444288 1111692256 11.948 98 22 41 0 1210966448 94250336 5023856
06:20:38 46954 8192000000 10115754784 10559430656 1115058288 11.980 98 28 55 0 1213044544 92356096 5630160
06:22:31 45348 8192000000 10086686448 10549506048 1127654752 12.099 100 10 20 0 1195224464 65440736 2128976
06:24:34 84008 8192000000 10080974800 10570780672 1122753248 12.053 99 12 24 0 1216729344 90861600 3114496
06:26:58 55318 8192000000 10055780368 10558640128 1099220528 11.830 97 12 24 0 1179516400 77797264 2498608
06:28:54 98461 8192000000 10057951552 10532241408 1070563712 11.557 95 10 20 0 1099401408 26919888 1917808
06:31:34 6836 8192000000 10069140912 10495651840 1121261216 12.039 100 70 133 0 1167481136 31404064 14815856
06:32:45 48353 8192000000 10124893856 10565505024 1121304576 12.039 99 19 38 0 1213382256 87935296 4142384
06:35:12 24369 8192000000 10101952176 10575060992 1105548528 11.890 98 32 62 0 1235307376 122431840 7327008
06:36:43 37895 8192000000 10049951648 10485940224 1108795936 11.921 99 34 68 0 1151993392 36415248 6782208
06:38:26 10734 8192000000 10078936272 10498322432 1104881712 11.884 98 53 102 0 1171602864 56848176 9872976
06:39:36 30904 8192000000 10131285008 10548400128 1112481792 11.956 99 49 98 0 1213703840 91246512 9975536
06:41:09 10197 8192000000 10112703760 10535481344 1135838608 12.176 100 61 122 0 1215120368 67201040 12080720
06:42:23 32652 8192000000 10137941168 10623045632 1141528112 12.230 100 22 44 0 1217536928 71321856 4686960
06:44:00 100077 8192000000 10075155184 10529501184 1105341408 11.888 97 28 56 0 1142758976 31230112 6187456
06:47:08 20169 8192000000 10075218992 10553421824 1130088528 12.122 100 26 48 0 1173476640 38543488 4844624
06:48:30 60845 8192000000 10098988608 10559586304 1149931664 12.309 100 17 33 0 1206977616 53564048 3481904
06:50:42 105651 8192000000 10143220848 10594148352 1146016176 12.272 100 17 34 0 1242206896 92252576 3938144
06:53:33 71855 8192000000 10094158768 10600505344 1131358432 12.134 98 12 24 0 1211140352 77252448 2529472
06:55:47 67819 8192000000 10099523920 10553286656 1102585872 11.862 97 19 38 0 1157486000 51017488 3882640
06:57:55 27578 8192000000 10107507920 10540982272 1116739056 11.996 98 60 115 1 1193574784 64459360 12376368
06:59:23 33267 8192000000 10088460112 10531852288 1131976960 12.140 100 18 34 0 1175932928 39946144 4009824
07:01:01 37231 8192000000 10056372384 10498031616 1119907904 12.026 99 42 76 3 1161718304 33847152 7963248
07:02:39 34373 8192000000 10107811728 10527772672 1074476816 11.595 95 53 106 0 1153879840 68955792 10447232
07:04:15 11626 8192000000 10101497600 10527854592 1131262736 12.133 100 53 102 0 1206457664 64251984 10942944
07:05:29 27872 8192000000 10120315776 10560491520 1117410304 12.003 99 40 80 0 1196584048 71401280 7772464
07:07:55 58341 8192000000 10110290288 10537586688 1115890128 11.988 99 49 89 1 1198913712 74323280 8700304
07:12:13 72102 8192000000 10077297200 10519617536 1112480656 11.956 97 20 40 0 1160905344 43809968 4614720
07:14:29 24742 8192000000 10132777888 10573033472 1135468080 12.173 99 30 58 0 1228082064 86022528 6591456
07:15:54 49695 8192000000 10206359872 10643980288 1117047008 11.999 97 32 62 0 1259460144 134872224 7540912
07:17:52 44236 8192000000 10252982672 10684682240 1152619920 12.334 99 27 54 0 1341959888 182769536 6570432
07:19:41 46459 8192000000 10320435088 10758631424 1155851152 12.364 99 33 66 0 1394067600 229537312 8679136
07:21:37 28429 8192000000 10355993776 10832494592 1166655168 12.466 100 29 58 0 1458379472 284298544 7425760
07:23:06 78735 8192000000 10271825312 10801872896 1140237808 12.218 99 20 40 0 1412180080 266730160 5212112
07:25:27 133992 8192000000 10092477664 10550366208 1106297376 11.897 97 12 24 0 1172482336 63797232 2387728
07:28:44 56258 8192000000 10127247568 10580664320 1120738800 12.034 99 28 57 0 1224953088 98179200 6035088
07:30:46 58081 8192000000 10125178064 10574626816 1135192064 12.170 100 16 32 1 1200031728 61252800 3586864
07:33:18 77109 8192000000 10057597952 10507210752 1109688912 11.929 97 17 31 1 1145079776 31310672 4080192
07:35:36 52611 8192000000 10064146000 10535174144 1097724464 11.816 98 8 16 0 1161467648 62089024 1654160

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-01-2023 07:40:27 to 30-01-2023 07:40:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-01-2023 07:40:44 to 30-01-2023 07:40:46.

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          207227
MON$OLDEST_ACTIVE               207228
MON$OLDEST_SNAPSHOT             207228
MON$NEXT_TRANSACTION            207228
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                       2052704
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             758
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2894 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2894 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2894 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 ---
--- end of diff output ---

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

30.01.23 07:40:48. 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
2023-01-30 07:40:50 No severe PSQL-related problems occured
30.01.23 07:40:52. End of report.