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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15252964 kB
MemAvailable 30386040 kB
Buffers 0 kB
Cached 16416068 kB
SwapCached 3052 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-03-03 10:37:46
DTS_END                         2023-03-03 10:37:28.0680
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/56630
TRN_ID                          212503
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: 8621.23 21908 1034547
customer order: creation 164.25 528 19710
customer order: refuse 13.36 381 1603
order to supplier: creation 42.27 1332 5072
order to supplier: removal 3.20 170050 384
invoice (draft): creation 42.66 3176 5119
invoice (draft): removal 47.98 1080 5757
invoice accept: apply 39.19 27900 4703
invoice accept: cancel 43.30 1188 5196
customer reserve: creation 3706.23 264 444747
customer reserve: removal 4244.19 100 509303
realization accept: apply 78.75 265 9450
realization accept: cancel 74.54 172 8945
payment from customer: creation 56.53 179 6784
payment from customer: removal 5.04 119 605
payment to supplier: creation 44.80 216 5376
payment to supplier: removal 3.18 125 382
service: total inventory turnovers 3.95 3394 474
service: total monetary turnovers 6.90 394 828
service: aggregate perf. data 0.87 15667 104
service: refresh index statistics 0.04 211625 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-03-2023 10:38:23 to 03-03-2023 10:38:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3854
TEST_TIME 2 4277
TEST_TIME 3 4546
TEST_TIME 4 4922
TEST_TIME 5 5187
TEST_TIME 6 5087
TEST_TIME 7 5924
TEST_TIME 8 6192
TEST_TIME 9 6264
TEST_TIME 10 6233
TEST_TIME 11 6510
TEST_TIME 12 6557
TEST_TIME 13 6819
TEST_TIME 14 6824
TEST_TIME 15 6677
TEST_TIME 16 6845
TEST_TIME 17 6963
TEST_TIME 18 7164
TEST_TIME 19 7125
TEST_TIME 20 7567
TEST_TIME 21 7710
TEST_TIME 22 7906
TEST_TIME 23 7856
TEST_TIME 24 7864
TEST_TIME 25 8186
TEST_TIME 26 8472
TEST_TIME 27 8760
TEST_TIME 28 9114
TEST_TIME 29 10286
TEST_TIME 30 10746
TEST_TIME 31 11402
TEST_TIME 32 11146
TEST_TIME 33 10888
TEST_TIME 34 10692
TEST_TIME 35 10483
TEST_TIME 36 10681
TEST_TIME 37 10468
TEST_TIME 38 10293
TEST_TIME 39 10202
TEST_TIME 40 10097
TEST_TIME 41 10025
TEST_TIME 42 9967
TEST_TIME 43 9860
TEST_TIME 44 9750
TEST_TIME 45 9560
TEST_TIME 46 9612
TEST_TIME 47 9445
TEST_TIME 48 9368
TEST_TIME 49 9416
TEST_TIME 50 9386
TEST_TIME 51 9344
TEST_TIME 52 9248
TEST_TIME 53 9111
TEST_TIME 54 9273
TEST_TIME 55 9263
TEST_TIME 56 9269
TEST_TIME 57 9279
TEST_TIME 58 9223
TEST_TIME 59 9235
TEST_TIME 60 9273
TEST_TIME 61 9234
TEST_TIME 62 9305
TEST_TIME 63 9264
TEST_TIME 64 9286
TEST_TIME 65 9269
TEST_TIME 66 9278
TEST_TIME 67 9219
TEST_TIME 68 9133
TEST_TIME 69 9138
TEST_TIME 70 9080
TEST_TIME 71 8988
TEST_TIME 72 8949
TEST_TIME 73 8875
TEST_TIME 74 8857
TEST_TIME 75 8897
TEST_TIME 76 8895
TEST_TIME 77 8885
TEST_TIME 78 8854
TEST_TIME 79 8930
TEST_TIME 80 8927
TEST_TIME 81 8916
TEST_TIME 82 8981
TEST_TIME 83 8952
TEST_TIME 84 8943
TEST_TIME 85 8942
TEST_TIME 86 8928
TEST_TIME 87 8851
TEST_TIME 88 8788
TEST_TIME 89 8813
TEST_TIME 90 8815
TEST_TIME 91 8772
TEST_TIME 92 8727
TEST_TIME 93 8709
TEST_TIME 94 8735
TEST_TIME 95 8725
TEST_TIME 96 8692
TEST_TIME 97 8687
TEST_TIME 98 8733
TEST_TIME 99 8688
TEST_TIME 100 8659
TEST_TIME 101 8676
TEST_TIME 102 8787
TEST_TIME 103 8775
TEST_TIME 104 8774
TEST_TIME 105 8800
TEST_TIME 106 8804
TEST_TIME 107 8776
TEST_TIME 108 8743
TEST_TIME 109 8753
TEST_TIME 110 8731
TEST_TIME 111 8689
TEST_TIME 112 8667
TEST_TIME 113 8619
TEST_TIME 114 8616
TEST_TIME 115 8601
TEST_TIME 116 8644
TEST_TIME 117 8669
TEST_TIME 118 8655
TEST_TIME 119 8692
TEST_TIME 120 8638

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-03-2023 10:38:26 to 03-03-2023 10:38:30.

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 1443233 1443233 0 0.00 0 37144 13 0 0
doc_list_biud 1443233 1443233 0 0.00 0 59817 41 0 0
sp_add_invoice_to_stock 4816 4703 113 2.34 79 214303 27900 0 113
sp_cancel_adding_invoice 5196 5196 0 0.00 7 128904 1189 0 0
sp_cancel_client_order 1603 1603 0 0.00 6 14956 382 0 0
sp_cancel_customer_reserve 509303 509303 0 0.00 1 39337 100 0 0
sp_cancel_pay_from_customer 605 605 0 0.00 3 3855 119 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 4 4510 126 0 0
sp_cancel_supplier_invoice 5765 5757 8 0.13 3 59824 1081 0 8
sp_cancel_supplier_order 384 384 0 0.00 7 1605799 170050 0 0
sp_cancel_write_off 8945 8945 0 0.00 2 26439 173 0 0
sp_client_order 19710 19710 0 0.00 6 31562 529 0 0
sp_customer_reserve 444747 444747 0 0.00 1 63672 264 0 0
sp_fill_shopping_cart 29897 29897 0 0.00 1 31156 77 0 0
sp_fill_shopping_cart_clo_res 444724 444724 0 0.00 0 21636 19 0 0
sp_get_clo_for_invoice 4667 4667 0 0.00 0 30049 343 0 0
sp_kill_cost_storno 15128 15128 0 0.00 0 26431 77 0 0
sp_kill_qstorno_ret_qs2qd 1033692 1033692 0 0.00 0 59814 44 0 0
sp_kill_qty_storno 545303 545303 0 0.00 0 59816 100 0 0
sp_lock_selected_doc 19341 19341 0 0.00 0 5168 5 0 0
sp_make_cost_storno 26275 26275 0 0.00 0 14642 76 0 0
sp_multiply_rows_for_pdistr 26275 26275 0 0.00 0 12482 37 0 0
sp_multiply_rows_for_qdistr 19710 19710 0 0.00 0 20303 200 0 0
sp_pay_from_customer 6784 6784 0 0.00 5 18122 179 0 0
sp_pay_to_supplier 5376 5376 0 0.00 7 25152 216 0 0
sp_payment_common 12160 12160 0 0.00 2 11392 47 0 0
sp_qd_handle_on_cancel_clo 1603 1603 0 0.00 1 9308 305 0 0
sp_qd_handle_on_invoice_upd_sts 9861 9861 0 0.00 0 29322 503 0 0
sp_qd_handle_on_reserve_upd_sts 9450 9450 0 0.00 0 13707 69 0 0
sp_reserve_write_off 9450 9450 0 0.00 9 22613 265 0 0
sp_supplier_invoice 5119 5119 0 0.00 9 88431 3176 0 0
sp_supplier_order 5072 5072 0 0.00 7 37379 1332 0 0
srv_aggregate_perf_data 104 104 0 0.00 2975 210777 15668 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 963 130730 40978 0 0
srv_find_qd_qs_mism 987708 987708 0 0.00 0 37142 11 0 0
srv_make_invnt_saldo 474 474 0 0.00 60 65181 3394 0 0
srv_make_money_saldo 828 828 0 0.00 1 17395 395 0 0
srv_recalc_idx_stat 5 5 0 0.00 87884 346856 211625 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4703 4703 0 0.00 1 29009 847 0 0
t$perf-norm:sp_cancel_adding_invoice 372 372 0 0.00 2 8918 488 0 0
t$perf-norm:sp_cancel_client_order 1603 1603 0 0.00 0 4520 29 0 0
t$perf-norm:sp_cancel_customer_reserve 618 618 0 0.00 0 1027 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 605 605 0 0.00 0 1703 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 2210 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 1886 34 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 85766 6223 0 0
t$perf-norm:sp_cancel_write_off 886 886 0 0.00 0 29309 53 0 0
t$perf-norm:sp_client_order 19710 19710 0 0.00 0 6813 30 0 0
t$perf-norm:sp_customer_reserve 945 945 0 0.00 0 2546 19 0 0
t$perf-norm:sp_pay_from_customer 6784 6784 0 0.00 0 9725 20 0 0
t$perf-norm:sp_pay_to_supplier 5376 5376 0 0.00 0 12087 21 0 0
t$perf-norm:sp_reserve_write_off 9450 9450 0 0.00 0 9318 21 0 0
t$perf-norm:sp_supplier_invoice 5119 5119 0 0.00 0 9116 28 0 0
t$perf-norm:sp_supplier_order 5072 5072 0 0.00 0 4492 26 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 224 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 306 9 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 864 15 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 20094 29 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 194 64 0 0
v_add_invoice_to_stock 9556 9330 226 2.36 0 8465 25 0 226
v_all_customers 36362 36362 0 0.00 0 119 3 0 0
v_all_suppliers 28366 28366 0 0.00 0 99 4 0 0
v_all_wares 39420 39420 0 0.00 0 94 4 0 0
v_cancel_adding_invoice 744 744 0 0.00 0 234 12 0 0
v_cancel_client_order 3206 3206 0 0.00 0 14883 15 0 0
v_cancel_customer_prepayment 1210 1210 0 0.00 0 2121 14 0 0
v_cancel_customer_reserve 1236 1236 0 0.00 0 2114 16 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 1312 11 0 0
v_cancel_supplier_invoice 768 752 16 2.08 0 900 23 0 16
v_cancel_supplier_order 768 768 0 0.00 0 2003 11 0 0
v_cancel_write_off 1772 1772 0 0.00 0 8523 37 0 0
v_max_id_clo_ord 5072 5072 0 0.00 0 410 4 0 0
v_max_id_clo_res 945 945 0 0.00 0 5947 19 0 0
v_max_id_ord_sup 5115 5115 0 0.00 0 5404 7 0 0
v_max_non_paid_invoice 1380 1380 0 0.00 0 731 8 0 0
v_max_non_paid_realizn 3325 3325 0 0.00 0 2657 8 0 0
v_min_id_clo_ord 5072 5072 0 0.00 0 894 7 0 0
v_min_id_clo_res 945 945 0 0.00 0 3375 21 0 0
v_min_id_ord_sup 5115 5115 0 0.00 0 3091 7 0 0
v_min_non_paid_invoice 5376 5376 0 0.00 0 5999 18 0 0
v_min_non_paid_realizn 6784 6784 0 0.00 0 11387 21 0 0
v_our_firm 9976 9976 0 0.00 0 65 4 0 0
v_reserve_write_off 18900 18900 0 0.00 0 22466 11 0 0
x_lock_depdocs_on_canc_invoice 10577 10577 0 0.00 0 19538 121 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 15325 425 0 0
x_make_qstorno_1000_1200 5072 5072 0 0.00 2 37326 1191 0 0
x_make_qstorno_1200_2000 5119 5119 0 0.00 4 87731 2951 0 0
x_make_qstorno_2100_3300 444747 444747 0 0.00 0 63668 234 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-03-2023 10:38:31 to 03-03-2023 10:38:35.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:31 925 8192000000 8921278464 8976883712 11520784 0.140 3 2 4 0 11896336 124400 251152
08:08:34 29304 8192000000 9723992432 10023608320 800488416 8.901 100 80 159 0 826984384 7610304 18885664
08:10:09 13878 8192000000 9769261888 10077212672 844169040 9.342 100 88 176 0 876827344 12161200 20497104
08:11:28 21962 8192000000 9819343744 10140667904 881030560 9.710 100 80 155 0 918339472 20051840 17257072
08:12:56 5628 8192000000 9865277744 10200231936 921060480 10.107 100 78 156 0 983527232 45359760 17106992
08:14:06 3120 8192000000 9923238672 10269249536 963508176 10.523 100 74 147 1 1036771936 58086240 15177520
08:15:14 1974 8192000000 9972313808 10323922944 993160752 10.812 100 93 177 1 1087565776 76141280 18263744
08:16:19 1757 8192000000 9974216272 10337673216 1017265056 11.046 100 86 171 0 1087546928 53458304 16823568
08:17:23 1493 8192000000 10017095952 10385944576 1039897360 11.264 100 92 184 1 1136434272 77229872 19307040
08:18:27 953 8192000000 10029148352 10403233792 1055430416 11.413 100 97 190 1 1151078576 77018560 18629600
08:19:29 1002 8192000000 10036356992 10415030272 1058452640 11.442 100 99 194 1 1160684768 83327888 18904240
08:20:33 1059 8192000000 10038334912 10416160768 1062906928 11.484 100 87 168 1 1158631472 78262736 17461808
08:21:36 1004 8192000000 10042981136 10423627776 1070104656 11.553 100 98 188 0 1165001584 76102064 18794864
08:22:42 1494 8192000000 10042696032 10428952576 1080151120 11.649 100 87 163 0 1169251712 71598416 17502176
08:23:44 1144 8192000000 10055360096 10439245824 1083596384 11.682 100 97 188 2 1182504016 79512320 19395312
08:24:46 1190 8192000000 10044380960 10432901120 1089453408 11.737 100 99 186 4 1169275776 60001360 19821008
08:25:49 1075 8192000000 10050957072 10441166848 1086003488 11.705 100 92 172 4 1169166832 66692096 16471248
08:26:51 1038 8192000000 10044875280 10434985984 1086730816 11.712 100 85 161 2 1167373424 64165408 16477200
08:27:53 1021 8192000000 10046421456 10435362816 1081245024 11.659 100 82 160 1 1169075952 71566640 16264288
08:28:55 1207 8192000000 10018056288 10398666752 1072719024 11.578 99 94 175 2 1140074896 49579728 17776144
08:29:57 1147 8192000000 10029700480 10414776320 1075333312 11.603 100 94 178 0 1151523424 59026848 17163264
08:31:00 1139 8192000000 10030773568 10408927232 1068387008 11.537 100 91 163 1 1150716176 67313712 15015456
08:32:01 965 8192000000 10002458768 10382471168 1051676464 11.377 99 85 161 1 1127194336 59022896 16494976
08:33:03 1124 8192000000 9989860720 10363445248 1040893264 11.273 99 92 174 2 1113885696 55327840 17664592
08:34:05 953 8192000000 9977240800 10348421120 1030169312 11.170 99 90 162 4 1098874224 51498128 17206784
08:35:07 1422 8192000000 9977546784 10348265472 1023051968 11.101 99 84 146 5 1095225264 56492688 15680608
08:36:10 4088 8192000000 9972381056 10355290112 1030950832 11.178 99 58 114 3 1096389504 54009488 11429184
08:37:17 925 8192000000 9973637024 10347061248 1038167760 11.247 100 86 161 0 1095794864 40447424 17179680
08:38:25 3952 8192000000 10011632464 10390749184 1056447856 11.422 100 67 122 1 1132369088 63457248 12463984
08:39:37 28376 8192000000 10030161584 10411753472 1061115088 11.467 99 63 120 1 1138749296 65129536 12504672
08:41:09 1693 8192000000 10074973920 10462171136 1090389296 11.746 100 79 142 0 1195625520 90500608 14735616
08:42:18 8895 8192000000 10135790848 10525999104 1113475536 11.965 100 77 150 1 1259198880 130102192 15621152
08:43:32 5867 8192000000 10171390992 10570067968 1118997152 12.018 100 68 135 0 1283854240 149976064 14881024
08:44:44 3717 8192000000 10188220480 10591686656 1131253536 12.133 100 70 129 0 1301778352 156708496 13816320
08:45:51 4083 8192000000 10201636448 10604666880 1145044736 12.263 100 69 132 2 1313641808 154630048 13967024
08:47:01 1433 8192000000 10245611936 10643247104 1156831024 12.374 100 88 166 0 1355913488 181359360 17723104
08:48:05 1758 8192000000 10286419392 10690252800 1171981696 12.515 100 86 157 3 1397909120 207988064 17939360
08:49:12 3277 8192000000 10341429344 10740846592 1175166640 12.545 100 88 176 0 1455722512 260890000 19665872
08:50:20 1958 8192000000 10387301552 10811949056 1193501264 12.716 100 84 168 0 1496419856 283302400 19616192
08:51:25 994 8192000000 10419477968 10821959680 1191275904 12.695 100 95 188 1 1529475648 316623376 21576368
08:52:30 1934 8192000000 10456886096 10865242112 1199706112 12.774 100 83 166 0 1566197552 346178528 20312912
08:53:36 2201 8192000000 10493337392 10902933504 1203985568 12.813 100 86 170 2 1603462304 379136976 20339760
08:54:41 1444 8192000000 10529339104 10965938176 1212171712 12.889 100 93 183 0 1637084224 403302192 21610320
08:55:45 1683 8192000000 10549626256 10992218112 1201623808 12.791 100 90 180 1 1658706304 434874192 22208304
08:56:49 958 8192000000 10576385760 11018375168 1194872736 12.729 100 93 175 0 1683282112 467001552 21407824
08:57:53 3471 8192000000 10555371072 10999689216 1180733200 12.597 99 59 116 1 1654602448 457722960 16146288
08:59:01 7073 8192000000 10569549056 11038953472 1195009248 12.730 100 60 119 0 1670129888 459634048 15486592
09:00:14 5684 8192000000 10582145824 11028606976 1192068816 12.703 99 71 140 0 1692718544 483075888 17573840
09:01:26 4674 8192000000 10650591296 11084275712 1206587792 12.837 100 62 122 0 1756566400 533731872 16246736
09:02:38 14752 8192000000 10652196800 11134652416 1193533504 12.716 100 54 108 0 1747813280 539988816 14290960
09:03:53 10571 8192000000 10667577520 11109421056 1189019376 12.674 100 46 90 0 1770732336 569286224 12426736
09:05:05 21454 8192000000 10507097008 11044093952 1154580368 12.352 99 36 70 0 1663671648 499210144 9881136
09:06:27 22665 8192000000 10338488128 10856349696 1151320176 12.322 99 29 58 0 1496435424 337449152 7666096
09:07:50 36455 8192000000 10163225120 10780049408 1132813616 12.148 99 16 32 0 1347054640 210534576 3706448
09:10:02 97816 8192000000 10131814320 10569449472 1130504080 12.126 98 51 97 0 1204245952 63771872 9970000
09:14:12 13940 8192000000 10079431184 10632933376 1139430896 12.210 100 11 20 0 1188329680 47060528 1838256
09:15:26 59081 8192000000 10103748992 10570428416 1144384320 12.257 100 28 52 0 1201045968 50026640 6635008
09:17:31 122431 8192000000 10070561920 10533822464 1080795568 11.655 94 17 34 0 1131233488 47097376 3340544
09:20:53 59732 8192000000 10116637280 10605387776 1146351920 12.275 100 10 20 0 1211483168 62620080 2511168
09:23:53 47578 8192000000 10103656784 10572460032 1145344880 12.266 100 41 78 0 1210893008 56484912 9063216
09:25:44 71468 8192000000 10152329072 10651598848 1159644736 12.400 100 33 65 0 1265586464 99152528 6789200
09:27:56 78078 8192000000 10100182720 10558230528 1117344960 12.002 97 8 16 0 1179847280 60958352 1543968
09:30:16 57830 8192000000 10141178352 10629943296 1140206800 12.217 100 30 61 1 1220956928 74045344 6704784
09:32:25 105831 8192000000 10077768848 10572845056 1096611408 11.805 96 14 28 0 1141465952 41519232 3335312
09:35:42 26875 8192000000 10079311904 10527477760 1095708976 11.797 97 34 67 0 1167176496 64688464 6779056
09:37:10 36142 8192000000 10106587376 10555310080 1116927584 11.998 99 37 72 1 1192061312 67581696 7552032
09:38:47 47902 8192000000 10103782688 10583252992 1106413280 11.898 98 37 74 0 1206226640 92885440 6927920
09:40:37 12409 8192000000 10125694480 10555842560 1132116096 12.141 100 54 102 0 1208665840 66324704 10225040
09:41:51 34581 8192000000 10114596512 10536800256 1131243328 12.133 99 51 99 0 1201700800 60630288 9827184
09:43:28 58590 8192000000 10125187472 10552832000 1123707584 12.062 98 54 103 0 1221596672 87831264 10057824
09:45:31 61888 8192000000 10099863792 10559242240 1134873904 12.167 100 48 94 0 1220145312 75056128 10215280
09:49:03 80460 8192000000 10054287072 10533986304 1124095824 12.066 99 7 14 0 1171773232 46185008 1492400
09:51:42 114874 8192000000 10097019456 10567733248 1120878800 12.035 98 12 24 0 1170084192 46652112 2553280
09:54:39 72417 8192000000 10122478928 10570592256 1132846128 12.148 99 28 55 0 1199764832 61225488 5693216
09:56:52 59493 8192000000 10080855504 10540449792 1122338000 12.049 99 21 42 0 1178525584 50974208 5213376
09:58:52 121480 8192000000 10074235648 10518511616 1072645792 11.577 94 12 24 1 1123989600 48669440 2674368
10:01:56 54710 8192000000 10106076480 10550792192 1127139312 12.094 99 41 79 0 1180184864 45690016 7355536
10:03:51 130730 8192000000 10135463840 10601558016 1112025120 11.952 98 37 74 0 1197057120 77755280 7276720
10:07:08 66318 8192000000 10068506704 10522202112 1126160960 12.085 100 21 42 1 1195328016 64831456 4335600
10:10:42 126263 8192000000 10065564064 10548617216 1096789184 11.807 96 10 20 0 1150192512 51188320 2215008
10:13:48 32995 8192000000 10088643760 10542329856 1113505952 11.966 98 34 68 0 1179779712 58910640 7363120
10:15:28 56958 8192000000 10106248192 10547593216 1114493424 11.975 98 22 45 0 1193843808 74341664 5008720
10:17:28 43941 8192000000 10165465824 10606272512 1130784768 12.129 100 30 60 0 1269716752 132250896 6681088
10:19:12 38646 8192000000 10106173584 10556776448 1130204256 12.123 100 43 83 0 1217806464 79298080 8304128
10:20:56 8399 8192000000 10083972576 10505891840 1118026768 12.008 100 74 137 1 1185509392 52399600 15083024
10:22:06 20085 8192000000 10111470512 10541588480 1119325584 12.021 100 64 125 0 1202611888 70565104 12721200
10:23:31 2303 8192000000 10102037840 10513141760 1125870304 12.082 100 76 146 1 1191500592 51688272 13942016
10:24:34 125480 8192000000 10086968272 10545147904 1090729408 11.750 97 32 64 0 1171024608 73412464 6882736
10:27:55 54018 8192000000 10047711936 10540429312 1097092688 11.810 99 3 6 0 1150918512 53173392 652432
10:29:50 83256 8192000000 10081898528 10525827072 1090232144 11.745 98 13 28 1 1142883648 49776032 2875472
10:32:14 70377 8192000000 10064003712 10523291648 1103748816 11.873 99 20 40 0 1196963536 88577168 4637552
10:34:29 69904 8192000000 10078292752 10531323904 1101375328 11.851 98 16 32 0 1167695264 62840368 3479568
10:36:39 37069 8192000000 10090792352 10522845184 1094461408 11.785 98 34 67 0 1157476800 55697760 7317632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-03-2023 10:38:37 to 03-03-2023 10:38:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-03-2023 10:38:54 to 03-03-2023 10:38:56.

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          218681
MON$OLDEST_ACTIVE               218682
MON$OLDEST_SNAPSHOT             218682
MON$NEXT_TRANSACTION            218682
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                       2038752
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             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

03.03.23 10:38:58. 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-03-03 10:39:00 No severe PSQL-related problems occured
03.03.23 10:39:02. End of report.