Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.10.2022 13: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 15473596 kB
MemAvailable 30523776 kB
Buffers 0 kB
Cached 16267248 kB
SwapCached 2152 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 2022-10-16 13:37:21
DTS_END                         2022-10-16 13:37:21.6740
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/56312
TRN_ID                          210785
ATT_ID                          618
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: 8643.48 22278 1037217
customer order: creation 159.74 482 19169
customer order: refuse 13.43 389 1612
order to supplier: creation 41.41 1393 4969
order to supplier: removal 3.19 175102 383
invoice (draft): creation 41.43 3193 4972
invoice (draft): removal 50.27 975 6032
invoice accept: apply 38.31 25824 4597
invoice accept: cancel 44.98 1098 5398
customer reserve: creation 3553.36 249 426403
customer reserve: removal 4418.83 99 530260
realization accept: apply 76.78 269 9214
realization accept: cancel 83.73 157 10047
payment from customer: creation 53.85 187 6462
payment from customer: removal 4.90 127 588
payment to supplier: creation 45.33 189 5439
payment to supplier: removal 2.98 137 358
service: total inventory turnovers 3.48 3741 417
service: total monetary turnovers 6.49 307 779
service: aggregate perf. data 0.94 12671 113
service: refresh index statistics 0.04 218973 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-10-2022 13:39:19 to 16-10-2022 13:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4135
TEST_TIME 2 4303
TEST_TIME 3 5738
TEST_TIME 4 6084
TEST_TIME 5 5778
TEST_TIME 6 5772
TEST_TIME 7 5566
TEST_TIME 8 5693
TEST_TIME 9 6066
TEST_TIME 10 6321
TEST_TIME 11 6581
TEST_TIME 12 6502
TEST_TIME 13 6585
TEST_TIME 14 6563
TEST_TIME 15 6588
TEST_TIME 16 6512
TEST_TIME 17 6987
TEST_TIME 18 7068
TEST_TIME 19 7225
TEST_TIME 20 7502
TEST_TIME 21 7974
TEST_TIME 22 7942
TEST_TIME 23 7995
TEST_TIME 24 8395
TEST_TIME 25 9145
TEST_TIME 26 9498
TEST_TIME 27 9657
TEST_TIME 28 9752
TEST_TIME 29 9742
TEST_TIME 30 10296
TEST_TIME 31 10857
TEST_TIME 32 10765
TEST_TIME 33 10499
TEST_TIME 34 10303
TEST_TIME 35 10219
TEST_TIME 36 10560
TEST_TIME 37 10478
TEST_TIME 38 10275
TEST_TIME 39 10214
TEST_TIME 40 10085
TEST_TIME 41 9993
TEST_TIME 42 9983
TEST_TIME 43 9876
TEST_TIME 44 9771
TEST_TIME 45 9671
TEST_TIME 46 9516
TEST_TIME 47 9402
TEST_TIME 48 9387
TEST_TIME 49 9370
TEST_TIME 50 9240
TEST_TIME 51 9251
TEST_TIME 52 9256
TEST_TIME 53 9193
TEST_TIME 54 9095
TEST_TIME 55 8992
TEST_TIME 56 8872
TEST_TIME 57 8876
TEST_TIME 58 8999
TEST_TIME 59 9010
TEST_TIME 60 8969
TEST_TIME 61 8907
TEST_TIME 62 8919
TEST_TIME 63 8987
TEST_TIME 64 9042
TEST_TIME 65 9199
TEST_TIME 66 9164
TEST_TIME 67 9170
TEST_TIME 68 9123
TEST_TIME 69 9125
TEST_TIME 70 9166
TEST_TIME 71 9209
TEST_TIME 72 9190
TEST_TIME 73 9112
TEST_TIME 74 9049
TEST_TIME 75 8961
TEST_TIME 76 8924
TEST_TIME 77 8922
TEST_TIME 78 8896
TEST_TIME 79 8869
TEST_TIME 80 8808
TEST_TIME 81 8738
TEST_TIME 82 8755
TEST_TIME 83 8721
TEST_TIME 84 8760
TEST_TIME 85 8742
TEST_TIME 86 8733
TEST_TIME 87 8749
TEST_TIME 88 8721
TEST_TIME 89 8787
TEST_TIME 90 8784
TEST_TIME 91 8780
TEST_TIME 92 8783
TEST_TIME 93 8784
TEST_TIME 94 8839
TEST_TIME 95 8982
TEST_TIME 96 8939
TEST_TIME 97 8927
TEST_TIME 98 8998
TEST_TIME 99 8949
TEST_TIME 100 8921
TEST_TIME 101 8933
TEST_TIME 102 8928
TEST_TIME 103 8901
TEST_TIME 104 8875
TEST_TIME 105 8884
TEST_TIME 106 8945
TEST_TIME 107 8957
TEST_TIME 108 8963
TEST_TIME 109 8960
TEST_TIME 110 8953
TEST_TIME 111 8902
TEST_TIME 112 8856
TEST_TIME 113 8903
TEST_TIME 114 8856
TEST_TIME 115 8841
TEST_TIME 116 8808
TEST_TIME 117 8792
TEST_TIME 118 8751
TEST_TIME 119 8703
TEST_TIME 120 8657

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-10-2022 13:39:22 to 16-10-2022 13:39:26.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1431590 1431590 0 0.00 0 14209 12 0 0
doc_list_biud 1431577 1431577 0 0.00 0 78291 42 0 0
sp_add_invoice_to_stock 4705 4597 108 2.29 46 181835 25824 0 108
sp_cancel_adding_invoice 5398 5398 0 0.00 6 76480 1099 0 0
sp_cancel_client_order 1612 1612 0 0.00 7 10396 389 0 0
sp_cancel_customer_reserve 530260 530260 0 0.00 1 78304 99 0 0
sp_cancel_pay_from_customer 588 588 0 0.00 4 7371 128 0 0
sp_cancel_pay_to_supplier 358 358 0 0.00 4 6446 138 0 0
sp_cancel_supplier_invoice 6042 6032 10 0.16 3 32544 976 0 10
sp_cancel_supplier_order 383 383 0 0.00 6 1294128 175103 0 0
sp_cancel_write_off 10047 10047 0 0.00 2 12624 158 0 0
sp_client_order 19169 19169 0 0.00 5 29192 483 0 0
sp_customer_reserve 426403 426403 0 0.00 0 32979 249 0 0
sp_fill_shopping_cart 29091 29091 0 0.00 1 15519 76 0 0
sp_fill_shopping_cart_clo_res 426374 426374 0 0.00 0 15178 21 0 0
sp_get_clo_for_invoice 4567 4567 0 0.00 0 37566 341 0 0
sp_kill_cost_storno 16391 16391 0 0.00 0 12590 70 0 0
sp_kill_qstorno_ret_qs2qd 1076982 1076982 0 0.00 0 78057 44 0 0
sp_kill_qty_storno 567509 567509 0 0.00 0 78290 99 0 0
sp_lock_selected_doc 19003 19003 0 0.00 0 11378 8 0 0
sp_make_cost_storno 25682 25682 0 0.00 0 12914 77 0 0
sp_multiply_rows_for_pdistr 25678 25678 0 0.00 0 10692 35 0 0
sp_multiply_rows_for_qdistr 19169 19169 0 0.00 0 13099 170 0 0
sp_pay_from_customer 6462 6462 0 0.00 5 15164 187 0 0
sp_pay_to_supplier 5439 5439 0 0.00 6 10765 190 0 0
sp_payment_common 11901 11901 0 0.00 2 14130 51 0 0
sp_qd_handle_on_cancel_clo 1612 1612 0 0.00 0 10370 325 0 0
sp_qd_handle_on_invoice_upd_sts 9963 9963 0 0.00 0 27816 497 0 0
sp_qd_handle_on_reserve_upd_sts 9212 9212 0 0.00 0 17885 68 0 0
sp_reserve_write_off 9214 9214 0 0.00 8 17937 269 0 0
sp_supplier_invoice 4972 4972 0 0.00 8 82644 3194 0 0
sp_supplier_order 4969 4969 0 0.00 6 32752 1394 0 0
srv_aggregate_perf_data 113 113 0 0.00 2206 87853 12671 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 786 164540 38897 0 0
srv_find_qd_qs_mism 993135 993135 0 0.00 0 14208 9 0 0
srv_make_invnt_saldo 417 417 0 0.00 40 110075 3742 0 0
srv_make_money_saldo 779 779 0 0.00 1 8935 308 0 0
srv_recalc_idx_stat 5 5 0 0.00 81026 441078 218974 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4599 4599 0 0.00 1 8546 787 0 0
t$perf-norm:sp_cancel_adding_invoice 367 367 0 0.00 1 6203 310 0 0
t$perf-norm:sp_cancel_client_order 1612 1612 0 0.00 0 3340 23 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 893 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 588 588 0 0.00 0 367 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 358 358 0 0.00 0 365 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 1323 28 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 1 90977 6780 0 0
t$perf-norm:sp_cancel_write_off 871 871 0 0.00 0 2195 20 0 0
t$perf-norm:sp_client_order 19170 19170 0 0.00 0 6256 32 0 0
t$perf-norm:sp_customer_reserve 935 935 0 0.00 0 5302 19 0 0
t$perf-norm:sp_pay_from_customer 6462 6462 0 0.00 0 9057 20 0 0
t$perf-norm:sp_pay_to_supplier 5439 5439 0 0.00 0 9005 21 0 0
t$perf-norm:sp_reserve_write_off 9215 9215 0 0.00 0 7092 23 0 0
t$perf-norm:sp_supplier_invoice 4972 4972 0 0.00 0 3852 27 0 0
t$perf-norm:sp_supplier_order 4969 4969 0 0.00 0 8188 30 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 304 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 21 3 0 0
t$perf-norm:srv_make_invnt_saldo 417 417 0 0.00 0 839 17 0 0
t$perf-norm:srv_make_money_saldo 779 779 0 0.00 0 9007 16 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 159 54 0 0
v_add_invoice_to_stock 9340 9124 216 2.31 0 6453 26 0 216
v_all_customers 35208 35208 0 0.00 0 168 3 0 0
v_all_suppliers 28314 28314 0 0.00 0 115 3 0 0
v_all_wares 38334 38334 0 0.00 0 64 3 0 0
v_cancel_adding_invoice 734 734 0 0.00 0 7700 34 0 0
v_cancel_client_order 3224 3224 0 0.00 0 3124 11 0 0
v_cancel_customer_prepayment 1176 1176 0 0.00 0 3790 13 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 1 6786 17 0 0
v_cancel_payment_to_supplier 716 716 0 0.00 0 1616 12 0 0
v_cancel_supplier_invoice 798 778 20 2.50 0 3287 27 0 20
v_cancel_supplier_order 766 766 0 0.00 0 957 11 0 0
v_cancel_write_off 1742 1742 0 0.00 0 5232 25 0 0
v_max_id_clo_ord 4963 4963 0 0.00 0 3876 5 0 0
v_max_id_clo_res 935 935 0 0.00 0 7588 18 0 0
v_max_id_ord_sup 4960 4960 0 0.00 0 3431 6 0 0
v_max_non_paid_invoice 1204 1204 0 0.00 0 924 8 0 0
v_max_non_paid_realizn 3248 3248 0 0.00 0 4553 9 0 0
v_min_id_clo_ord 4963 4963 0 0.00 0 11078 12 0 0
v_min_id_clo_res 935 935 0 0.00 0 4843 22 0 0
v_min_id_ord_sup 4960 4960 0 0.00 0 5784 8 0 0
v_min_non_paid_invoice 5439 5439 0 0.00 0 6442 22 0 0
v_min_non_paid_realizn 6462 6462 0 0.00 0 11529 25 0 0
v_our_firm 9548 9548 0 0.00 0 51 4 0 0
v_reserve_write_off 18424 18424 0 0.00 0 6977 11 0 0
x_lock_depdocs_on_canc_invoice 11041 11041 0 0.00 0 14058 130 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 7146 392 0 0
x_make_qstorno_1000_1200 4969 4969 0 0.00 2 32739 1243 0 0
x_make_qstorno_1200_2000 4972 4972 0 0.00 3 82569 2975 0 0
x_make_qstorno_2100_3300 426403 426403 0 0.00 0 32969 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-10-2022 13:39:27 to 16-10-2022 13:39:31.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:25 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:26 15118 8192000000 9712550240 10007973888 793124016 8.827 100 87 175 0 822073984 7579216 21370752
11:09:49 7811 8192000000 9751037808 10058399744 830936480 9.209 100 85 168 0 862517504 11494656 20086368
11:11:01 15357 8192000000 9797932496 10113302528 868329232 9.583 100 90 180 0 905836208 16942880 20564096
11:12:19 9290 8192000000 9847443056 10177814528 910624304 10.003 100 86 170 0 960087440 30951104 18512032
11:13:32 3971 8192000000 9906655552 10248982528 950441552 10.395 100 88 173 1 1021705104 53171792 18091760
11:14:38 2577 8192000000 9968920608 10320257024 989407520 10.776 100 80 159 1 1082465488 76858560 16199408
11:15:42 2117 8192000000 9975245744 10335842304 1015246400 11.026 100 82 159 1 1096299552 64918832 16134320
11:16:51 1436 8192000000 10013396528 10382753792 1038274864 11.248 100 92 183 0 1135983344 79043968 18664512
11:17:54 1204 8192000000 10010096112 10380591104 1051171728 11.372 100 99 193 1 1125077664 55370256 18535680
11:18:58 1106 8192000000 10027071648 10403057664 1058910080 11.446 100 100 188 2 1147170224 68320624 19939520
11:20:01 1164 8192000000 10042619952 10420764672 1072461248 11.576 100 98 191 0 1164638464 72060736 20116480
11:21:05 1140 8192000000 10025737248 10405076992 1076079120 11.610 100 96 181 1 1149774624 55336688 18358816
11:22:08 1479 8192000000 10098951536 10486636544 1082722176 11.673 100 92 182 3 1216017296 114963328 18331792
11:23:13 890 8192000000 10042220128 10422587392 1086113584 11.706 100 96 189 1 1159344736 53079840 20151312
11:24:15 1096 8192000000 10040822592 10430685184 1092871808 11.770 100 84 161 1 1164713712 55141056 16700848
11:25:17 1221 8192000000 10068907184 10457546752 1098351680 11.822 100 99 187 3 1189976752 73074704 18550368
11:26:20 1150 8192000000 10077007488 10457874432 1085890672 11.704 99 96 186 1 1199960832 95874128 18196032
11:27:22 1234 8192000000 10065145760 10450157568 1093217872 11.773 100 97 191 0 1189026976 75383456 20425648
11:28:26 1010 8192000000 10065035648 10455003136 1099224304 11.830 100 91 173 3 1187627072 69430272 18972496
11:29:29 899 8192000000 10030320784 10419441664 1089941264 11.742 100 95 181 0 1151700848 43459248 18300336
11:30:31 1236 8192000000 10053852224 10436341760 1089137952 11.734 100 97 184 0 1174407088 66661616 18607520
11:31:34 1025 8192000000 9990592352 10369589248 1050116176 11.362 99 88 168 6 1118149984 51203856 16829952
11:32:36 1128 8192000000 10035516784 10411356160 1062286992 11.478 100 94 180 0 1157226352 75253568 19685792
11:33:38 2307 8192000000 10008426128 10389991424 1052238896 11.382 100 91 172 1 1137285744 65579744 19467104
11:34:42 1138 8192000000 9971707856 10339160064 1017007488 11.043 100 92 176 0 1092468192 56396752 19063952
11:35:44 1398 8192000000 10012254768 10387177472 1045060288 11.313 100 97 189 0 1133979664 69169456 19749920
11:36:46 1155 8192000000 10008489840 10387562496 1044075184 11.304 100 95 177 2 1130389824 67429872 18884768
11:37:54 18637 8192000000 10002527072 10384625664 1064980224 11.504 99 64 114 2 1111327568 35180864 11166480
11:39:19 27563 8192000000 10063675856 10453237760 1090114192 11.744 100 67 127 0 1188794768 85916576 12764000
11:40:51 1796 8192000000 10096021040 10488324096 1096508000 11.804 100 76 149 0 1216931808 104829824 15593984
11:41:58 8672 8192000000 10119485568 10516152320 1111435584 11.946 100 55 105 2 1234846816 112649920 10761312
11:43:21 5835 8192000000 10146381344 10547630080 1122795776 12.053 100 74 143 3 1260321440 121908112 15617552
11:44:33 3614 8192000000 10187237808 10589794304 1135023232 12.169 100 83 153 2 1312962928 161492368 16447328
11:45:50 2061 8192000000 10223934816 10635247616 1149385664 12.304 100 90 175 3 1338425408 168086864 20952880
11:46:56 979 8192000000 10260880864 10661371904 1160849664 12.411 100 96 184 1 1372771744 192527152 19394928
11:47:59 960 8192000000 10273106256 10688151552 1169051312 12.488 100 93 181 0 1384797504 195815152 19931040
11:49:06 3712 8192000000 10298487968 10715697152 1184795680 12.635 100 73 141 0 1416775296 215820592 16159024
11:50:14 4016 8192000000 10331081696 10749288448 1191934736 12.701 100 79 155 0 1443052016 232860880 18256400
11:51:22 835 8192000000 10372306256 10778447872 1201317904 12.789 100 99 187 1 1481196528 258433296 21445328
11:52:26 1389 8192000000 10455431216 10861092864 1215336256 12.919 100 95 180 1 1561168640 324481440 21350944
11:53:31 1147 8192000000 10481897776 10908168192 1210679296 12.875 100 96 188 1 1591595248 358221616 22694336
11:54:35 1096 8192000000 10485258320 10898485248 1205277904 12.825 100 99 187 3 1589425104 361884400 22262800
11:55:40 1086 8192000000 10523868160 10961506304 1212367424 12.891 100 98 193 0 1629233504 394631984 22234096
11:56:43 1022 8192000000 10569463104 10989912064 1218624560 12.949 100 99 197 0 1672609216 430941984 23042672
11:57:47 780 8192000000 10557095920 11003314176 1203561360 12.809 100 77 149 1 1662998192 441620448 17816384
11:59:02 16044 8192000000 10536157568 10982436864 1191067280 12.693 100 52 101 0 1644132816 440290432 12775104
12:00:20 7265 8192000000 10550281888 10993127424 1177911792 12.571 100 54 108 0 1661539856 469802384 13825680
12:01:35 5212 8192000000 10528457056 11039531008 1179638640 12.587 100 55 110 0 1649243664 454622544 14982480
12:02:47 15257 8192000000 10411030976 10889666560 1172103408 12.516 100 38 76 0 1523257008 341032752 10120848
12:04:04 37587 8192000000 10369909264 10821758976 1165973888 12.459 99 26 52 0 1448257968 275611840 6672240
12:05:45 36833 8192000000 10375767664 10826477568 1121931632 12.045 96 22 44 0 1442442048 314804400 5706016
12:08:01 10166 8192000000 10184245488 10670268416 1139757696 12.213 99 39 77 1 1296991408 148225872 9007840
12:09:12 51497 8192000000 10172823616 10631643136 1150018704 12.310 99 23 44 0 1280046320 124848256 5179360
12:11:37 63091 8192000000 10205930240 10678849536 1136458240 12.182 98 21 40 0 1291500432 150471168 4571024
12:13:40 70672 8192000000 10086462848 10585493504 1126793344 12.091 98 23 46 0 1165572656 34001328 4777984
12:15:52 54632 8192000000 10115520768 10598227968 1146999040 12.281 99 24 48 0 1202999616 50938384 5062192
12:18:02 76414 8192000000 10100232560 10542194688 1097427408 11.813 95 29 58 1 1153779632 49821024 6531200
12:20:19 57699 8192000000 10070840624 10518142976 1121690448 12.043 99 42 81 0 1189483584 59333104 8460032
12:22:19 151410 8192000000 10100296384 10554060800 1096490896 11.804 97 24 48 0 1143731648 41930688 5310064
12:25:54 29079 8192000000 10092963312 10597707776 1135319344 12.171 100 22 42 1 1217283984 77483008 4481632
12:28:01 60285 8192000000 10094905360 10575728640 1114336960 11.973 99 28 56 0 1200698080 80138448 6222672
12:30:22 41249 8192000000 10081599136 10543177728 1117077344 11.999 99 17 32 1 1184981520 64517680 3386496
12:32:06 48953 8192000000 10095566736 10548330496 1124547088 12.070 99 16 32 0 1194168944 66059248 3562608
12:34:20 77342 8192000000 10112932400 10657382400 1139296304 12.209 100 9 16 0 1232714992 91714448 1704240
12:36:49 44826 8192000000 10075316608 10527682560 1097459360 11.814 97 13 26 0 1132096304 31606976 3029968
12:38:40 58854 8192000000 10157214832 10589937664 1113577120 11.966 97 23 45 0 1207686352 89194000 4915232
12:40:39 37603 8192000000 10148258848 10633342976 1141088928 12.226 100 28 56 1 1290932752 143421728 6422096
12:42:18 71440 8192000000 10083648400 10530504704 1132056768 12.141 99 20 38 1 1198869136 62105824 4706544
12:44:29 28524 8192000000 10108315440 10578419712 1141560032 12.230 99 33 65 0 1209955360 61795312 6600016
12:45:58 31674 8192000000 10116623648 10557435904 1119990496 12.027 98 44 87 0 1202066464 73320016 8755952
12:47:31 25131 8192000000 10094044368 10524495872 1111325984 11.945 99 31 62 0 1190968416 73301344 6341088
12:48:59 22580 8192000000 10094053264 10522406912 1134065008 12.160 100 33 66 0 1186944352 46671520 6207824
12:50:23 80765 8192000000 10066881472 10506846208 1123829536 12.063 99 37 66 3 1177018960 46444688 6744736
12:52:46 17251 8192000000 10085135328 10525675520 1142767664 12.242 100 51 98 0 1192499536 39501680 10230192
12:54:04 58128 8192000000 10067802288 10539925504 1108381808 11.917 98 21 42 0 1169266320 56842544 4041968
12:56:09 41723 8192000000 10057261312 10519498752 1108933824 11.922 98 10 20 0 1153130000 42073744 2122432
12:58:16 58008 8192000000 10057638912 10514149376 1080112528 11.649 97 13 23 0 1151849200 69223536 2513136
13:00:38 28348 8192000000 10055898576 10523439104 1091772560 11.760 98 25 50 1 1157589568 60630672 5186336
13:02:08 16811 8192000000 10101744336 10538954752 1127039872 12.093 100 47 88 2 1191866640 54981152 9845616
13:03:39 26934 8192000000 10125404720 10565296128 1127795376 12.101 99 29 58 0 1221169296 87414928 5958992
13:05:07 28999 8192000000 10157252016 10590502912 1121308176 12.039 99 38 76 0 1244470080 114535632 8626272
13:06:36 58786 8192000000 10155575248 10592153600 1132648192 12.146 100 35 68 1 1274243920 134560832 7034896
13:08:39 60274 8192000000 10174372000 10628730880 1115253104 11.982 97 24 48 0 1253199600 132440752 5505744
13:10:43 39136 8192000000 10201558944 10652651520 1116208592 11.991 97 31 62 0 1280857936 158292800 6356544
13:12:52 87558 8192000000 10134027360 10636419072 1125280064 12.077 99 11 22 0 1203742000 75819408 2642528
13:15:33 164539 8192000000 10073466144 10588319744 1043683552 11.300 92 17 34 0 1095324672 47799184 3841936
13:19:25 39483 8192000000 10078520608 10508189696 1124076528 12.065 99 29 54 1 1149366832 19878224 5412080
13:21:06 46945 8192000000 10133999040 10577330176 1108509968 11.918 97 35 68 0 1174347264 59205984 6631312
13:22:55 93570 8192000000 10073137120 10590072832 1104260272 11.878 97 17 34 0 1187360624 79523200 3577152
13:25:32 73855 8192000000 10101212064 10531188736 1110408832 11.936 98 36 72 0 1172440000 54844320 7186848
13:27:47 112910 8192000000 10124271216 10561245184 1110910352 11.941 98 36 70 1 1210427616 91017984 8499280
13:30:43 47687 8192000000 10073442288 10535706624 1120705040 12.034 100 27 54 1 1160444800 34686768 5052992
13:32:31 46110 8192000000 10057948960 10501607424 1105101984 11.886 97 18 36 0 1143233632 34916240 3215408
13:34:17 76459 8192000000 10101321904 10569850880 1127617120 12.099 99 14 28 0 1193106576 62577360 2912096
13:36:42 23885 8192000000 10096702480 10546376704 1099576864 11.834 97 16 31 0 1167113744 64121536 3415344

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-10-2022 13:39:33 to 16-10-2022 13:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-10-2022 13:39:50 to 16-10-2022 13:39:52.

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          206125
MON$OLDEST_ACTIVE               210920
MON$OLDEST_SNAPSHOT             210920
MON$NEXT_TRANSACTION            210920
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                       2035552
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             761
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2859 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2859 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2859 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2859 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 ---
:96: 
:97: mscs-test-161.budzdorov.ru    Sun Oct 16 13:37:22 2022
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:99: 
:100: 
:101: mscs-test-161.budzdorov.ru    Sun Oct 16 13:37:28 2022
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:103: 
:104: 
:105: mscs-test-161.budzdorov.ru    Sun Oct 16 13:37:35 2022
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:107: 
--- end of diff output ---

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

16.10.22 13:39:53. 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-10-16 13:39:55 No severe PSQL-related problems occured
16.10.22 13:39:56. End of report.