Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.09.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 15274144 kB
MemAvailable 30601984 kB
Buffers 0 kB
Cached 16458132 kB
SwapCached 2040 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-09-30 13:37:20
DTS_END                         2022-09-30 13:37:20.7200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35682
TRN_ID                          217475
ATT_ID                          632
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: 8922.38 26498 1070685
customer order: creation 165.31 484 19837
customer order: refuse 14.13 403 1695
order to supplier: creation 44.22 1344 5306
order to supplier: removal 3.47 168169 416
invoice (draft): creation 44.25 3121 5310
invoice (draft): removal 51.15 929 6138
invoice accept: apply 39.27 25420 4712
invoice accept: cancel 46.13 1110 5536
customer reserve: creation 3688.28 240 442593
customer reserve: removal 4544.45 101 545334
realization accept: apply 79.53 273 9543
realization accept: cancel 77.84 175 9341
payment from customer: creation 56.46 162 6775
payment from customer: removal 5.50 100 660
payment to supplier: creation 46.81 192 5617
payment to supplier: removal 3.45 121 414
service: total inventory turnovers 4.13 3524 496
service: total monetary turnovers 7.11 393 853
service: aggregate perf. data 0.87 16286 104
service: refresh index statistics 0.04 307404 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-09-2022 13:38:49 to 30-09-2022 13:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5175
TEST_TIME 2 5111
TEST_TIME 3 5390
TEST_TIME 4 5605
TEST_TIME 5 5630
TEST_TIME 6 5792
TEST_TIME 7 5817
TEST_TIME 8 5679
TEST_TIME 9 5537
TEST_TIME 10 5393
TEST_TIME 11 5781
TEST_TIME 12 6147
TEST_TIME 13 6225
TEST_TIME 14 6480
TEST_TIME 15 6493
TEST_TIME 16 6711
TEST_TIME 17 6868
TEST_TIME 18 6955
TEST_TIME 19 7371
TEST_TIME 20 7419
TEST_TIME 21 7445
TEST_TIME 22 7562
TEST_TIME 23 7650
TEST_TIME 24 7957
TEST_TIME 25 8429
TEST_TIME 26 8564
TEST_TIME 27 8508
TEST_TIME 28 8739
TEST_TIME 29 9096
TEST_TIME 30 9559
TEST_TIME 31 10250
TEST_TIME 32 10497
TEST_TIME 33 10983
TEST_TIME 34 10786
TEST_TIME 35 10858
TEST_TIME 36 10752
TEST_TIME 37 10736
TEST_TIME 38 10716
TEST_TIME 39 10512
TEST_TIME 40 10388
TEST_TIME 41 10308
TEST_TIME 42 10212
TEST_TIME 43 10197
TEST_TIME 44 10230
TEST_TIME 45 10253
TEST_TIME 46 10188
TEST_TIME 47 10112
TEST_TIME 48 10145
TEST_TIME 49 10341
TEST_TIME 50 10290
TEST_TIME 51 10241
TEST_TIME 52 10110
TEST_TIME 53 9934
TEST_TIME 54 9909
TEST_TIME 55 9883
TEST_TIME 56 9760
TEST_TIME 57 9704
TEST_TIME 58 9673
TEST_TIME 59 9663
TEST_TIME 60 9667
TEST_TIME 61 9677
TEST_TIME 62 9610
TEST_TIME 63 9716
TEST_TIME 64 9659
TEST_TIME 65 9559
TEST_TIME 66 9459
TEST_TIME 67 9411
TEST_TIME 68 9338
TEST_TIME 69 9369
TEST_TIME 70 9271
TEST_TIME 71 9276
TEST_TIME 72 9204
TEST_TIME 73 9184
TEST_TIME 74 9203
TEST_TIME 75 9175
TEST_TIME 76 9181
TEST_TIME 77 9240
TEST_TIME 78 9241
TEST_TIME 79 9298
TEST_TIME 80 9370
TEST_TIME 81 9344
TEST_TIME 82 9423
TEST_TIME 83 9371
TEST_TIME 84 9380
TEST_TIME 85 9287
TEST_TIME 86 9304
TEST_TIME 87 9234
TEST_TIME 88 9202
TEST_TIME 89 9197
TEST_TIME 90 9156
TEST_TIME 91 9158
TEST_TIME 92 9115
TEST_TIME 93 9041
TEST_TIME 94 9068
TEST_TIME 95 9025
TEST_TIME 96 9009
TEST_TIME 97 9003
TEST_TIME 98 8995
TEST_TIME 99 8974
TEST_TIME 100 9003
TEST_TIME 101 9030
TEST_TIME 102 9042
TEST_TIME 103 9170
TEST_TIME 104 9167
TEST_TIME 105 9168
TEST_TIME 106 9134
TEST_TIME 107 9188
TEST_TIME 108 9229
TEST_TIME 109 9202
TEST_TIME 110 9127
TEST_TIME 111 9141
TEST_TIME 112 9095
TEST_TIME 113 9098
TEST_TIME 114 9046
TEST_TIME 115 8998
TEST_TIME 116 8978
TEST_TIME 117 9020
TEST_TIME 118 8999
TEST_TIME 119 8989
TEST_TIME 120 8937

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-09-2022 13:38:52 to 30-09-2022 13:38:56.

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 1481239 1481239 0 0.00 0 27510 13 0 0
doc_list_biud 1481224 1481224 0 0.00 0 44643 42 0 0
sp_add_invoice_to_stock 4836 4712 124 2.56 33 193808 25420 0 124
sp_cancel_adding_invoice 5536 5536 0 0.00 4 115629 1110 0 0
sp_cancel_client_order 1695 1695 0 0.00 6 12683 403 0 0
sp_cancel_customer_reserve 545334 545334 0 0.00 1 34152 101 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 4 5034 101 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 4 3510 122 0 0
sp_cancel_supplier_invoice 6152 6138 14 0.22 2 34942 929 0 14
sp_cancel_supplier_order 416 416 0 0.00 8 1372373 168169 0 0
sp_cancel_write_off 9341 9341 0 0.00 2 44681 175 0 0
sp_client_order 19837 19837 0 0.00 5 39952 485 0 0
sp_customer_reserve 442593 442593 0 0.00 1 77880 240 0 0
sp_fill_shopping_cart 30435 30435 0 0.00 1 12072 79 0 0
sp_fill_shopping_cart_clo_res 442569 442569 0 0.00 0 11275 19 0 0
sp_get_clo_for_invoice 4682 4682 0 0.00 0 34984 308 0 0
sp_kill_cost_storno 15951 15951 0 0.00 0 13247 71 0 0
sp_kill_qstorno_ret_qs2qd 1106563 1106563 0 0.00 0 44637 43 0 0
sp_kill_qty_storno 582682 582682 0 0.00 0 44640 99 0 0
sp_lock_selected_doc 19862 19862 0 0.00 0 6882 7 0 0
sp_make_cost_storno 26617 26617 0 0.00 0 17417 77 0 0
sp_multiply_rows_for_pdistr 26614 26614 0 0.00 0 10868 40 0 0
sp_multiply_rows_for_qdistr 19835 19835 0 0.00 0 13814 180 0 0
sp_pay_from_customer 6775 6775 0 0.00 5 10517 163 0 0
sp_pay_to_supplier 5617 5617 0 0.00 6 13061 193 0 0
sp_payment_common 12391 12391 0 0.00 2 7623 43 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 0 12673 324 0 0
sp_qd_handle_on_invoice_upd_sts 10217 10217 0 0.00 0 36069 504 0 0
sp_qd_handle_on_reserve_upd_sts 9541 9541 0 0.00 0 20534 63 0 0
sp_reserve_write_off 9543 9543 0 0.00 8 25723 273 0 0
sp_supplier_invoice 5310 5310 0 0.00 7 89216 3122 0 0
sp_supplier_order 5306 5306 0 0.00 7 49110 1345 0 0
srv_aggregate_perf_data 104 104 0 0.00 2553 121750 16286 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 780 202562 36526 0 0
srv_find_qd_qs_mism 1025237 1025237 0 0.00 0 27502 9 0 0
srv_make_invnt_saldo 496 496 0 0.00 44 62152 3524 0 0
srv_make_money_saldo 853 853 0 0.00 1 21900 393 0 0
srv_recalc_idx_stat 5 5 0 0.00 97425 435757 307404 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4715 4715 0 0.00 1 10367 856 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 1 6581 401 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 1939 24 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 1013 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 632 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 375 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 984 28 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 88944 6398 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 2799 22 0 0
t$perf-norm:sp_client_order 19839 19839 0 0.00 0 5546 33 0 0
t$perf-norm:sp_customer_reserve 890 890 0 0.00 0 3206 14 0 0
t$perf-norm:sp_pay_from_customer 6775 6775 0 0.00 0 13583 20 0 0
t$perf-norm:sp_pay_to_supplier 5617 5617 0 0.00 0 10146 25 0 0
t$perf-norm:sp_reserve_write_off 9543 9543 0 0.00 0 10466 26 0 0
t$perf-norm:sp_supplier_invoice 5310 5310 0 0.00 0 2346 27 0 0
t$perf-norm:sp_supplier_order 5306 5306 0 0.00 0 7515 29 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 129 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 38 3 0 0
t$perf-norm:srv_make_invnt_saldo 496 496 0 0.00 0 1429 15 0 0
t$perf-norm:srv_make_money_saldo 853 853 0 0.00 0 2730 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 7 43 24 0 0
v_add_invoice_to_stock 9606 9358 248 2.58 0 3864 24 0 248
v_all_customers 36668 36668 0 0.00 0 95 4 0 0
v_all_suppliers 29717 29717 0 0.00 0 71 4 0 0
v_all_wares 39664 39664 0 0.00 0 270 4 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 1425 14 0 0
v_cancel_client_order 3390 3390 0 0.00 0 2870 10 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 658 8 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 1693 12 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 3446 18 0 0
v_cancel_supplier_invoice 844 816 28 3.31 0 965 25 0 28
v_cancel_supplier_order 832 832 0 0.00 0 4939 17 0 0
v_cancel_write_off 1956 1956 0 0.00 0 5071 24 0 0
v_max_id_clo_ord 5301 5301 0 0.00 0 202 5 0 0
v_max_id_clo_res 890 890 0 0.00 0 2909 19 0 0
v_max_id_ord_sup 5300 5300 0 0.00 0 4179 7 0 0
v_max_non_paid_invoice 1358 1358 0 0.00 0 62 8 0 0
v_max_non_paid_realizn 3370 3370 0 0.00 0 822 7 0 0
v_min_id_clo_ord 5301 5301 0 0.00 0 3486 9 0 0
v_min_id_clo_res 890 890 0 0.00 0 2174 13 0 0
v_min_id_ord_sup 5300 5300 0 0.00 0 3841 8 0 0
v_min_non_paid_invoice 5616 5616 0 0.00 0 7597 20 0 0
v_min_non_paid_realizn 6775 6775 0 0.00 0 5713 16 0 0
v_our_firm 9805 9805 0 0.00 0 277 4 0 0
v_reserve_write_off 19080 19080 0 0.00 0 13131 12 0 0
x_lock_depdocs_on_canc_invoice 11266 11266 0 0.00 0 22038 122 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 19739 438 0 0
x_make_qstorno_1000_1200 5306 5306 0 0.00 3 49095 1188 0 0
x_make_qstorno_1200_2000 5310 5310 0 0.00 3 89166 2909 0 0
x_make_qstorno_2100_3300 442593 442593 0 0.00 0 77203 210 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-09-2022 13:38:57 to 30-09-2022 13:39:01.

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:24 4 8192000000 8802019888 8814518272 7249104 0.088 3 2 3 0 7402832 92368 61360
11:08:25 24106 8192000000 9723053088 10021629952 801475296 8.911 100 88 176 0 830564496 7578528 21510672
11:09:56 10112 8192000000 9758288208 10065432576 834210592 9.242 100 89 178 0 868465328 13083792 21170944
11:11:11 15065 8192000000 9788111040 10104389632 858219568 9.482 100 83 167 0 897381168 19804400 19357200
11:12:32 10159 8192000000 9839956144 10170736640 897738080 9.876 100 79 158 0 953667024 38770912 17158032
11:13:48 5353 8192000000 9915750048 10253127680 937908560 10.272 100 90 175 0 1023984512 66746272 19329680
11:14:56 3217 8192000000 9945769072 10296401920 978185840 10.667 100 86 170 0 1063500416 67364704 17949872
11:16:01 1992 8192000000 9993802160 10353033216 1011733536 10.992 100 89 173 0 1105623264 76659696 17230032
11:17:05 1352 8192000000 10001489328 10367242240 1033687984 11.204 100 96 184 0 1112762992 62194384 16880624
11:18:08 859 8192000000 10016774192 10387988480 1056076720 11.419 100 97 189 0 1138706672 63374912 19255040
11:19:09 1082 8192000000 10049456528 10433949696 1062834208 11.484 100 94 180 1 1171321248 91075584 17411456
11:20:12 1041 8192000000 10039566880 10419904512 1063728464 11.492 100 96 181 2 1158395120 76750848 17915808
11:21:14 1038 8192000000 10054275808 10440138752 1076249360 11.612 100 94 181 0 1177437008 82420704 18766944
11:22:17 1104 8192000000 10039602784 10426519552 1080098320 11.648 100 87 166 0 1159511872 61656096 17757456
11:23:20 2355 8192000000 10081565888 10470739968 1087972224 11.723 100 92 173 4 1200807872 96027792 16807856
11:24:23 5129 8192000000 10040379264 10433376256 1087791744 11.722 100 72 141 0 1158343488 57060016 13491728
11:25:34 1101 8192000000 10052971696 10442534912 1097766784 11.816 100 97 182 1 1176931840 60536432 18628624
11:26:36 925 8192000000 10079909632 10472284160 1099869856 11.836 100 94 180 2 1200704288 82050640 18783792
11:27:38 1115 8192000000 10077559488 10468921344 1095019712 11.790 100 95 179 0 1202610144 89825936 17764496
11:28:40 969 8192000000 10050813248 10442424320 1093196112 11.773 100 89 169 1 1171001856 61088752 16716992
11:29:41 1150 8192000000 10037505904 10423226368 1083929920 11.685 100 96 183 0 1165485200 62658256 18897024
11:30:44 2533 8192000000 10026803600 10416488448 1067940224 11.532 99 74 137 2 1149933376 67857584 14135568
11:31:48 1403 8192000000 10015773632 10394157056 1055324384 11.412 100 95 177 2 1141541616 67264960 18952272
11:32:50 1339 8192000000 9986241760 10356912128 1028529584 11.154 100 97 179 1 1109335584 61996576 18809424
11:33:52 837 8192000000 10000825312 10375331840 1041029888 11.275 100 97 186 3 1126449904 64454736 20965280
11:34:54 936 8192000000 9995416544 10368376832 1035215968 11.219 100 92 171 1 1120293136 67435984 17641184
11:35:56 1052 8192000000 9983796336 10360836096 1052203280 11.382 100 84 156 2 1102015696 32283184 17529232
11:36:58 12999 8192000000 9989532448 10380472320 1058055264 11.438 100 46 90 0 1118600688 51453024 9092400
11:38:17 5046 8192000000 10019423392 10398539776 1070142992 11.553 100 77 146 2 1138317600 54068960 14105648
11:39:32 17866 8192000000 10050211040 10441584640 1093545552 11.776 100 69 129 1 1180432368 72821376 14065440
11:40:54 4318 8192000000 10051560560 10436620288 1104809120 11.883 100 73 131 0 1165577024 47569408 13198496
11:42:03 5551 8192000000 10112561168 10501898240 1114699376 11.977 100 75 144 0 1220922528 90817408 15405744
11:43:15 1242 8192000000 10144565712 10535845888 1128595824 12.108 100 94 174 1 1259785536 113176816 18012896
11:44:20 4559 8192000000 10156632192 10549522432 1133709632 12.156 100 88 164 1 1275563776 123482432 18371712
11:45:28 3454 8192000000 10187612176 10587316224 1144658544 12.259 100 83 155 0 1305509024 144872304 15978176
11:46:39 1261 8192000000 10229091696 10625986560 1155342976 12.360 100 89 168 1 1340621568 167729072 17549520
11:47:45 5612 8192000000 10265126528 10662440960 1158252896 12.387 100 86 166 1 1376113360 199197472 18662992
11:48:59 3363 8192000000 10286840256 10698600448 1174961456 12.543 100 84 154 1 1406878800 215766432 16150912
11:50:04 894 8192000000 10328610272 10730057728 1175144688 12.545 100 95 183 1 1439189280 241765648 22278944
11:51:10 1436 8192000000 10378927824 10791690240 1193520112 12.716 100 94 182 1 1489775136 273514496 22740528
11:52:13 1181 8192000000 10403869696 10809827328 1199413728 12.771 100 87 168 0 1514146944 295543248 19189968
11:53:18 1407 8192000000 10449549744 10877505536 1211994704 12.888 100 98 194 2 1560874736 325975520 22904512
11:54:22 4151 8192000000 10484372192 10912124928 1204038960 12.814 100 85 168 0 1586221712 362164832 20017920
11:55:31 1467 8192000000 10471597152 10924032000 1203630368 12.810 100 87 172 0 1574559008 350532256 20396384
11:56:34 2643 8192000000 10510230304 10951385088 1206278000 12.835 100 84 167 1 1615241424 388903520 20059904
11:57:39 769 8192000000 10559479488 10989486080 1210971136 12.878 100 98 189 0 1664078640 429684512 23422992
11:58:44 834 8192000000 10592849168 11020259328 1212401696 12.891 100 96 182 1 1697893984 463593952 21898336
11:59:47 1237 8192000000 10593622688 11021606912 1197533664 12.753 100 69 137 0 1697166240 482076784 17555792
12:00:55 3578 8192000000 10617311776 11079876608 1201277904 12.788 100 80 158 0 1721927728 499864848 20784976
12:02:03 11142 8192000000 10630229008 11089666048 1209694496 12.866 100 57 112 0 1725336096 500509376 15132224
12:03:17 11698 8192000000 10582372160 11052793856 1207797520 12.849 100 49 98 0 1685441664 465421680 12222464
12:04:34 26848 8192000000 10606517760 11091066880 1186253184 12.648 99 39 78 0 1721699696 525536928 9909584
12:06:03 27687 8192000000 10512505552 11010007040 1152870816 12.336 98 40 78 1 1646736128 483493344 10371968
12:07:32 24850 8192000000 10410986848 10907049984 1144704544 12.260 100 29 58 0 1535723744 383583504 7435696
12:08:59 26812 8192000000 10227601264 10725658624 1156618016 12.372 100 32 64 0 1333638992 169740816 7280160
12:10:28 34486 8192000000 10142070128 10597285888 1156772032 12.373 100 35 70 0 1248896672 83926816 8197824
12:12:46 61584 8192000000 10116524544 10599378944 1134738672 12.166 100 14 25 0 1205734912 68456640 2539600
12:14:52 99524 8192000000 10071481792 10512453632 1114008608 11.970 97 19 38 0 1143240816 25793568 3438640
12:17:32 16654 8192000000 10086098960 10514313216 1140981920 12.225 100 55 97 1 1178685472 25979232 11724320
12:19:10 41163 8192000000 10155889936 10616328192 1145973504 12.272 100 24 48 0 1229936992 78914864 5048624
12:20:56 64555 8192000000 10095750112 10584326144 1136539360 12.183 99 10 20 0 1213010240 73979600 2491280
12:23:29 66020 8192000000 10131132496 10583031808 1116669648 11.996 97 33 59 2 1178962624 55999024 6293952
12:25:36 202562 8192000000 10114019248 10590605312 1080868240 11.656 94 12 24 0 1184287776 100462992 2956544
12:31:44 137552 8192000000 10108365456 10550943744 1131651632 12.137 99 28 52 0 1216940368 78841696 6447040
12:35:03 40259 8192000000 10120538864 10576769024 1107108000 11.905 96 19 37 1 1188652368 77790928 3753440
12:36:44 35089 8192000000 10172171664 10605547520 1133842128 12.158 99 47 94 1 1239424928 94579520 11003280
12:38:20 55713 8192000000 10151917664 10622431232 1135300144 12.171 99 17 34 0 1236324192 97401888 3622160
12:40:16 37485 8192000000 10074137504 10597306368 1123119792 12.056 99 5 10 0 1175259648 51031184 1108672
12:41:59 70717 8192000000 10042201392 10507796480 1107827088 11.912 98 19 38 0 1139508528 27549568 4131872
12:44:11 57824 8192000000 10094268736 10563108864 1124331328 12.068 99 26 50 0 1178851104 48963488 5556288
12:46:13 92974 8192000000 10039504752 10513567744 1119379440 12.021 99 7 14 0 1145815840 24845952 1590448
12:48:47 61325 8192000000 10048986192 10505093120 1109420864 11.927 99 12 24 0 1144305024 32763568 2120592
12:50:51 41014 8192000000 10114976032 10560733184 1124303456 12.068 99 40 80 0 1203682048 71045088 8333504
12:52:32 36902 8192000000 10133324304 10613694464 1142286880 12.237 100 24 48 1 1231330784 83596880 5447024
12:54:11 31463 8192000000 10130093536 10591383552 1146251488 12.274 100 32 64 0 1239553472 86416288 6885696
12:55:45 47646 8192000000 10131287056 10612846592 1110300432 11.935 98 27 54 0 1241841056 124933440 6607184
12:57:40 42974 8192000000 10083895488 10538475520 1115179376 11.981 98 15 28 1 1177093360 58346736 3567248
12:59:23 63150 8192000000 10068229824 10523074560 1120386752 12.031 99 13 26 0 1155962336 33158224 2417360
13:01:27 44389 8192000000 10078705680 10520584192 1106382944 11.898 99 40 77 1 1154048336 39423408 8241984
13:03:55 53526 8192000000 10080343808 10548387840 1118868352 12.016 100 35 70 0 1170255552 44493136 6894064
13:05:50 67545 8192000000 10094436640 10591010816 1109577744 11.928 99 9 17 0 1176326576 65162976 1585856
13:08:02 52387 8192000000 10045590048 10527825920 1087645392 11.720 97 21 42 0 1147324144 54756336 4922416
13:09:57 66169 8192000000 10079339328 10522316800 1113850960 11.969 99 18 36 0 1168302304 50685840 3765504
13:12:18 72303 8192000000 10088422480 10541449216 1133693024 12.156 100 15 28 1 1158629824 22817040 2119760
13:15:03 28208 8192000000 10119091616 10566713344 1143812384 12.251 100 36 68 0 1207285120 56367328 7105408
13:16:37 15846 8192000000 10174718800 10609344512 1158866640 12.393 100 36 72 1 1259498752 92964832 7667280
13:17:58 28328 8192000000 10176841552 10616893440 1147805792 12.289 99 34 68 0 1257422496 102519376 7097328
13:19:38 11198 8192000000 10164017936 10605203456 1142814576 12.242 100 48 94 1 1270910272 118719280 9376416
13:21:03 14397 8192000000 10081263808 10516869120 1121963952 12.046 98 37 74 0 1151437680 21726912 7746816
13:22:18 16271 8192000000 10161862208 10598961152 1154205392 12.349 100 49 94 1 1239027520 75255792 9566336
13:23:37 14144 8192000000 10156541344 10595680256 1142095856 12.235 100 68 134 0 1246101312 90458656 13546800
13:24:51 39308 8192000000 10100938288 10561089536 1099743168 11.835 96 39 78 0 1170339264 62943184 7652912
13:26:31 122960 8192000000 10122985472 10584211456 1124292064 12.068 100 41 82 0 1210812224 77622896 8897264
13:29:35 48538 8192000000 10063112800 10549342208 1123134880 12.057 99 29 56 0 1188701120 60323840 5242400
13:31:27 88209 8192000000 10102844384 10570059776 1132511376 12.145 99 15 30 0 1179451040 43259296 3680368
13:34:04 63206 8192000000 10080339920 10567319552 1109493904 11.928 98 14 28 0 1170635616 58191776 2949936
13:36:13 33600 8192000000 10115490304 10563530752 1122046448 12.046 100 30 59 0 1208153664 80196496 5910720

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-09-2022 13:39:03 to 30-09-2022 13:39:19.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-09-2022 13:39:20 to 30-09-2022 13:39:22.

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          211918
MON$OLDEST_ACTIVE               217628
MON$OLDEST_SNAPSHOT             217628
MON$NEXT_TRANSACTION            217628
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                       2068832
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2850 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2850 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2850 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2850 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 ---
:156: 
:157: mscs-test-161.budzdorov.ru    Fri Sep 30 13:37:23 2022
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:159: 
:160: 
:161: mscs-test-161.budzdorov.ru    Fri Sep 30 13:37:28 2022
:162:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:163: 
--- end of diff output ---

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

30.09.22 13:39:23. 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-09-30 13:39:25 No severe PSQL-related problems occured
30.09.22 13:39:27. End of report.