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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15076260 kB
MemAvailable 30430752 kB
Buffers 0 kB
Cached 16647164 kB
SwapCached 3028 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-07 07:37:26
DTS_END                         2023-03-07 07:37:26.8530
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/32936
TRN_ID                          215928
ATT_ID                          608
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: 8978.57 20658 1077428
customer order: creation 161.92 460 19430
customer order: refuse 13.93 413 1672
order to supplier: creation 44.34 1357 5321
order to supplier: removal 3.19 160720 383
invoice (draft): creation 43.83 3297 5260
invoice (draft): removal 51.79 962 6215
invoice accept: apply 40.13 25467 4816
invoice accept: cancel 46.95 1093 5634
customer reserve: creation 3737.09 243 448451
customer reserve: removal 4550.23 88 546027
realization accept: apply 79.81 271 9577
realization accept: cancel 82.88 161 9945
payment from customer: creation 55.68 176 6682
payment from customer: removal 5.34 117 641
payment to supplier: creation 45.50 190 5460
payment to supplier: removal 3.43 121 412
service: total inventory turnovers 4.03 3592 484
service: total monetary turnovers 7.47 376 896
service: aggregate perf. data 0.98 13614 117
service: refresh index statistics 0.04 200436 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-03-2023 07:39:49 to 07-03-2023 07:39:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8427
TEST_TIME 2 5798
TEST_TIME 3 6279
TEST_TIME 4 5911
TEST_TIME 5 5185
TEST_TIME 6 5563
TEST_TIME 7 5334
TEST_TIME 8 5757
TEST_TIME 9 5828
TEST_TIME 10 6066
TEST_TIME 11 6598
TEST_TIME 12 6675
TEST_TIME 13 6904
TEST_TIME 14 7108
TEST_TIME 15 7141
TEST_TIME 16 7619
TEST_TIME 17 8081
TEST_TIME 18 7905
TEST_TIME 19 7868
TEST_TIME 20 8028
TEST_TIME 21 8177
TEST_TIME 22 8236
TEST_TIME 23 8700
TEST_TIME 24 8602
TEST_TIME 25 8762
TEST_TIME 26 8887
TEST_TIME 27 9723
TEST_TIME 28 10257
TEST_TIME 29 10591
TEST_TIME 30 10779
TEST_TIME 31 10867
TEST_TIME 32 10821
TEST_TIME 33 11059
TEST_TIME 34 10950
TEST_TIME 35 10780
TEST_TIME 36 10579
TEST_TIME 37 10532
TEST_TIME 38 10460
TEST_TIME 39 10278
TEST_TIME 40 10276
TEST_TIME 41 10281
TEST_TIME 42 10133
TEST_TIME 43 10092
TEST_TIME 44 10110
TEST_TIME 45 10014
TEST_TIME 46 9868
TEST_TIME 47 9767
TEST_TIME 48 9676
TEST_TIME 49 9580
TEST_TIME 50 9443
TEST_TIME 51 9367
TEST_TIME 52 9281
TEST_TIME 53 9224
TEST_TIME 54 9171
TEST_TIME 55 9206
TEST_TIME 56 9206
TEST_TIME 57 9244
TEST_TIME 58 9184
TEST_TIME 59 9125
TEST_TIME 60 9151
TEST_TIME 61 9197
TEST_TIME 62 9228
TEST_TIME 63 9207
TEST_TIME 64 9289
TEST_TIME 65 9305
TEST_TIME 66 9327
TEST_TIME 67 9307
TEST_TIME 68 9331
TEST_TIME 69 9436
TEST_TIME 70 9431
TEST_TIME 71 9424
TEST_TIME 72 9421
TEST_TIME 73 9389
TEST_TIME 74 9352
TEST_TIME 75 9321
TEST_TIME 76 9242
TEST_TIME 77 9200
TEST_TIME 78 9186
TEST_TIME 79 9144
TEST_TIME 80 9111
TEST_TIME 81 9078
TEST_TIME 82 9081
TEST_TIME 83 8995
TEST_TIME 84 8964
TEST_TIME 85 8991
TEST_TIME 86 9094
TEST_TIME 87 9063
TEST_TIME 88 9042
TEST_TIME 89 8997
TEST_TIME 90 9006
TEST_TIME 91 9024
TEST_TIME 92 9061
TEST_TIME 93 9137
TEST_TIME 94 9249
TEST_TIME 95 9210
TEST_TIME 96 9208
TEST_TIME 97 9248
TEST_TIME 98 9204
TEST_TIME 99 9155
TEST_TIME 100 9138
TEST_TIME 101 9130
TEST_TIME 102 9100
TEST_TIME 103 9036
TEST_TIME 104 9004
TEST_TIME 105 9007
TEST_TIME 106 9007
TEST_TIME 107 8964
TEST_TIME 108 8989
TEST_TIME 109 8960
TEST_TIME 110 8961
TEST_TIME 111 8982
TEST_TIME 112 9011
TEST_TIME 113 8995
TEST_TIME 114 9054
TEST_TIME 115 9052
TEST_TIME 116 9034
TEST_TIME 117 9025
TEST_TIME 118 9097
TEST_TIME 119 9088
TEST_TIME 120 9013

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-03-2023 07:39:53 to 07-03-2023 07:39: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 1490208 1490208 0 0.00 0 31415 12 0 0
doc_list_biud 1490195 1490195 0 0.00 0 42302 39 0 0
sp_add_invoice_to_stock 4936 4816 120 2.43 38 221408 25467 0 120
sp_cancel_adding_invoice 5634 5634 0 0.00 7 82411 1094 0 0
sp_cancel_client_order 1672 1672 0 0.00 7 28064 413 0 0
sp_cancel_customer_reserve 546027 546027 0 0.00 1 42321 89 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 3 6323 117 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 4 3273 121 0 0
sp_cancel_supplier_invoice 6227 6215 12 0.19 3 30414 963 0 12
sp_cancel_supplier_order 383 383 0 0.00 8 1331491 160721 0 0
sp_cancel_write_off 9945 9945 0 0.00 2 14442 161 0 0
sp_client_order 19430 19430 0 0.00 6 35514 461 0 0
sp_customer_reserve 448451 448451 0 0.00 0 102065 244 0 0
sp_fill_shopping_cart 29999 29999 0 0.00 1 16987 85 0 0
sp_fill_shopping_cart_clo_res 448420 448420 0 0.00 0 21746 18 0 0
sp_get_clo_for_invoice 4780 4780 0 0.00 0 36724 305 0 0
sp_kill_cost_storno 16632 16632 0 0.00 0 18520 68 0 0
sp_kill_qstorno_ret_qs2qd 1108597 1108597 0 0.00 0 42247 40 0 0
sp_kill_qty_storno 584233 584233 0 0.00 0 42300 92 0 0
sp_lock_selected_doc 19886 19886 0 0.00 0 11602 10 0 0
sp_make_cost_storno 26499 26499 0 0.00 0 18706 87 0 0
sp_multiply_rows_for_pdistr 26499 26499 0 0.00 0 10401 40 0 0
sp_multiply_rows_for_qdistr 19430 19430 0 0.00 0 14109 180 0 0
sp_pay_from_customer 6682 6682 0 0.00 6 17237 176 0 0
sp_pay_to_supplier 5460 5460 0 0.00 6 17230 190 0 0
sp_payment_common 12142 12142 0 0.00 2 15237 46 0 0
sp_qd_handle_on_cancel_clo 1672 1672 0 0.00 0 28048 340 0 0
sp_qd_handle_on_invoice_upd_sts 10414 10414 0 0.00 0 28468 466 0 0
sp_qd_handle_on_reserve_upd_sts 9577 9577 0 0.00 0 10435 61 0 0
sp_reserve_write_off 9577 9577 0 0.00 9 20858 271 0 0
sp_supplier_invoice 5260 5260 0 0.00 8 97489 3297 0 0
sp_supplier_order 5321 5321 0 0.00 8 29114 1358 0 0
srv_aggregate_perf_data 117 117 0 0.00 2275 213476 13614 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 845 155374 41988 0 0
srv_find_qd_qs_mism 1030636 1030636 0 0.00 0 31413 9 0 0
srv_make_invnt_saldo 484 484 0 0.00 32 87326 3593 0 0
srv_make_money_saldo 896 896 0 0.00 1 26913 377 0 0
srv_recalc_idx_stat 5 5 0 0.00 68897 368455 200437 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4828 4828 0 0.00 1 12220 774 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 1 7701 361 0 0
t$perf-norm:sp_cancel_client_order 1672 1672 0 0.00 0 6588 27 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 7152 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 464 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 3580 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 350 350 0 0.00 0 621 22 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 84029 6865 0 0
t$perf-norm:sp_cancel_write_off 938 938 0 0.00 0 4744 21 0 0
t$perf-norm:sp_client_order 19430 19430 0 0.00 0 12925 32 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 4070 17 0 0
t$perf-norm:sp_pay_from_customer 6682 6682 0 0.00 0 28889 22 0 0
t$perf-norm:sp_pay_to_supplier 5460 5460 0 0.00 0 7407 19 0 0
t$perf-norm:sp_reserve_write_off 9577 9577 0 0.00 0 9425 23 0 0
t$perf-norm:sp_supplier_invoice 5260 5260 0 0.00 0 7378 29 0 0
t$perf-norm:sp_supplier_order 5321 5321 0 0.00 0 8950 26 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 153 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 132 6 0 0
t$perf-norm:srv_make_invnt_saldo 484 484 0 0.00 0 599 12 0 0
t$perf-norm:srv_make_money_saldo 896 896 0 0.00 0 3811 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 248 62 0 0
v_add_invoice_to_stock 9781 9541 240 2.45 0 8502 23 0 240
v_all_customers 35473 35473 0 0.00 0 178 3 0 0
v_all_suppliers 29028 29028 0 0.00 0 140 4 0 0
v_all_wares 38846 38846 0 0.00 0 59 3 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 1705 13 0 0
v_cancel_client_order 3344 3344 0 0.00 0 2611 10 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 766 10 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 1613 10 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 3185 15 0 0
v_cancel_supplier_invoice 724 700 24 3.31 0 1922 23 0 24
v_cancel_supplier_order 766 766 0 0.00 0 2373 13 0 0
v_cancel_write_off 1876 1876 0 0.00 0 680 20 0 0
v_max_id_clo_ord 5317 5317 0 0.00 0 3041 5 0 0
v_max_id_clo_res 941 941 0 0.00 0 3507 14 0 0
v_max_id_ord_sup 5255 5255 0 0.00 0 3978 7 0 0
v_max_non_paid_invoice 1517 1517 0 0.00 0 84 6 0 0
v_max_non_paid_realizn 3549 3549 0 0.00 0 2447 7 0 0
v_min_id_clo_ord 5317 5317 0 0.00 0 3766 12 0 0
v_min_id_clo_res 941 941 0 0.00 0 2862 14 0 0
v_min_id_ord_sup 5255 5255 0 0.00 0 9886 13 0 0
v_min_non_paid_invoice 5460 5460 0 0.00 0 8192 20 0 0
v_min_non_paid_realizn 6681 6681 0 0.00 0 15228 20 0 0
v_our_firm 9632 9632 0 0.00 0 88 4 0 0
v_reserve_write_off 19150 19150 0 0.00 0 3573 10 0 0
x_lock_depdocs_on_canc_invoice 11499 11499 0 0.00 0 16694 112 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 2480 319 0 0
x_make_qstorno_1000_1200 5321 5321 0 0.00 2 29068 1207 0 0
x_make_qstorno_1200_2000 5260 5260 0 0.00 3 82435 3040 0 0
x_make_qstorno_2100_3300 448451 448451 0 0.00 0 102056 215 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-03-2023 07:39:57 to 07-03-2023 07:40: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
05:07:29 918 8192000000 8926966496 8983355392 7223664 0.088 3 2 4 0 7410448 114352 72432
05:08:30 32201 8192000000 9716271712 10016104448 791355072 8.809 100 90 179 0 821348560 7950800 22042688
05:10:08 12718 8192000000 9748404992 10056826880 829252368 9.192 100 89 178 0 863050048 12571680 21226000
05:11:30 7938 8192000000 9780951536 10099560448 857276816 9.473 100 84 168 0 897678624 20974528 19427280
05:12:44 5156 8192000000 9847007168 10175971328 904509568 9.943 100 83 166 0 959743872 36652976 18581328
05:14:04 2805 8192000000 9922652416 10263994368 954752944 10.438 100 84 164 0 1033712240 61795120 17164176
05:15:09 1540 8192000000 9959014320 10309500928 984615648 10.729 100 93 182 0 1072711456 69240304 18855504
05:16:12 1185 8192000000 9983985632 10345078784 1013267856 11.007 100 92 176 1 1102287312 71570080 17449376
05:17:15 800 8192000000 10027849888 10395475968 1037823120 11.244 100 98 188 0 1153702688 96365808 19513760
05:18:17 897 8192000000 10035287072 10406313984 1051811312 11.378 100 98 187 1 1157219072 86921424 18486336
05:19:19 1353 8192000000 10030921136 10407960576 1065265584 11.507 100 89 170 0 1148148528 66251184 16631760
05:20:22 933 8192000000 10050687392 10433675264 1069499104 11.547 100 94 173 1 1167231456 80475824 17256528
05:21:25 5109 8192000000 10038912800 10430087168 1079747088 11.645 100 62 121 0 1156318672 64735152 11836432
05:22:31 1440 8192000000 10039864720 10425626624 1088018496 11.724 100 83 158 2 1167564144 63626608 15919040
05:23:34 4076 8192000000 10036871376 10430697472 1089067616 11.734 100 81 154 1 1156915776 52393664 15454496
05:24:38 1089 8192000000 10045009184 10434875392 1096402864 11.803 100 88 175 0 1166212192 51904944 17904384
05:25:41 2439 8192000000 10063264480 10455040000 1097728960 11.816 100 73 142 1 1187751232 76241536 13780736
05:26:44 1120 8192000000 10077449776 10464718848 1102327952 11.860 100 97 187 0 1196940544 75845488 18767104
05:27:46 1116 8192000000 10078522176 10466365440 1102251504 11.859 100 93 173 1 1201843520 81492528 18099488
05:28:48 5910 8192000000 10035728912 10433097728 1095350656 11.794 100 72 138 1 1164166768 54574400 14241712
05:29:58 1147 8192000000 10015408992 10398011392 1070911376 11.561 100 92 179 0 1136324320 46945968 18466976
05:31:00 1372 8192000000 10030239792 10405023744 1044730944 11.310 99 96 185 2 1149803136 86041472 19030720
05:32:02 1051 8192000000 10030343440 10411749376 1063033520 11.486 100 97 187 3 1152321344 69507680 19780144
05:33:04 1127 8192000000 9999323600 10375643136 1041762064 11.282 100 94 175 1 1119831920 59569648 18500208
05:34:07 1134 8192000000 9982186144 10359754752 1041457552 11.279 100 87 171 0 1104108496 44665824 17985120
05:35:08 1050 8192000000 10025894560 10406547456 1057386032 11.431 100 79 148 2 1144540064 73315168 13838864
05:36:10 1437 8192000000 9980699648 10358210560 1041776656 11.282 99 80 156 1 1099406352 40952848 16676848
05:37:13 2870 8192000000 9996433280 10373128192 1030859424 11.177 98 66 130 1 1113557072 69525632 13172016
05:38:21 3455 8192000000 9992240624 10377392128 1068308784 11.536 100 78 142 1 1115414624 31893584 15212256
05:39:43 10726 8192000000 10068321184 10456391680 1082646928 11.673 100 80 158 0 1192391104 93593248 16150928
05:40:57 1293 8192000000 10072025984 10456858624 1105652432 11.891 100 96 187 2 1188228272 62538320 20037520
05:42:04 9525 8192000000 10106396048 10504396800 1116205264 11.991 100 65 130 0 1224204000 94402208 13596528
05:43:16 3360 8192000000 10166305008 10556760064 1128430048 12.107 100 80 147 1 1274264624 131344000 14490576
05:44:25 8145 8192000000 10218728544 10615414784 1140539536 12.221 100 69 138 0 1328729184 173618400 14571248
05:45:40 882 8192000000 10254431792 10646646784 1152039248 12.329 100 95 186 0 1366475088 194012048 20423792
05:46:46 1376 8192000000 10299467232 10698768384 1159600480 12.400 100 91 179 0 1417170256 237614768 19955008
05:47:51 1333 8192000000 10318545888 10731687936 1171245248 12.508 100 85 165 1 1433333680 243679328 18409104
05:48:56 1106 8192000000 10314921344 10716553216 1180685040 12.597 100 95 177 3 1423628624 222648400 20295184
05:49:59 840 8192000000 10389752512 10805473280 1198566176 12.763 100 98 186 1 1497617360 278411056 20640128
05:51:03 1091 8192000000 10413977424 10828099584 1200248704 12.779 100 93 179 1 1520493024 299531888 20712432
05:52:08 958 8192000000 10457981744 10866311168 1196760080 12.746 100 95 185 0 1567355184 348716912 21878192
05:53:12 1452 8192000000 10436263520 10889953280 1205005424 12.823 100 92 173 1 1540603328 316656800 18941104
05:54:16 13402 8192000000 10410883424 10863800320 1191057888 12.693 99 57 107 2 1524243184 319608000 13577296
05:55:33 5244 8192000000 10457530160 10882523136 1195593968 12.735 100 55 108 1 1561571904 352600656 13377280
05:56:40 12359 8192000000 10473787808 10931617792 1183523024 12.623 100 49 98 0 1610484912 414344048 12617840
05:57:59 2358 8192000000 10500012112 10930155520 1192669584 12.708 100 86 166 2 1598275312 385638608 19967120
05:59:10 3641 8192000000 10496285920 10931458048 1197456656 12.753 100 75 147 0 1600880784 385438176 17985952
06:00:18 9526 8192000000 10488066816 10980548608 1197559696 12.754 100 41 82 0 1616727456 408512048 10655712
06:01:40 27564 8192000000 10506800672 10971389952 1169561696 12.493 99 39 78 0 1616307808 436232208 10513904
06:03:30 21356 8192000000 10413226336 10962997248 1148603536 12.296 99 29 56 1 1547274384 390933248 7737600
06:04:53 14835 8192000000 10303164000 10775056384 1144542544 12.258 99 37 71 1 1402869200 249576400 8750256
06:06:18 16855 8192000000 10219697408 10689052672 1132652672 12.146 98 46 86 3 1323506288 180795440 10058176
06:07:36 8596 8192000000 10194410128 10624757760 1135534304 12.174 99 37 74 0 1288826752 144987824 8304624
06:08:45 31890 8192000000 10212029568 10678571008 1147320080 12.284 100 25 50 0 1314995856 161486976 6188800
06:10:19 31305 8192000000 10141146176 10662825984 1112572560 11.957 99 27 54 0 1291454800 172604912 6277328
06:12:04 84121 8192000000 10106433216 10533257216 1129798928 12.119 100 51 99 2 1214117600 73270048 11048624
06:14:29 56411 8192000000 10116394192 10558889984 1124140560 12.066 99 23 46 0 1217564320 88637776 4785984
06:17:36 62692 8192000000 10082363504 10564304896 1100858176 11.846 97 19 38 0 1202351232 97150496 4342560
06:19:41 87676 8192000000 10075364400 10530344960 1120677824 12.033 99 17 34 0 1184487088 60056048 3753216
06:22:09 59154 8192000000 10060101632 10513612800 1141396384 12.229 100 13 23 0 1166321584 23064336 1860864
06:24:09 59480 8192000000 10084514928 10535759872 1130960000 12.130 99 30 58 0 1177549824 40890944 5698880
06:26:13 96785 8192000000 10083210480 10564608000 1139734432 12.213 100 20 38 0 1175513936 31389168 4390336
06:28:51 50900 8192000000 10064301424 10535620608 1099227680 11.830 96 16 30 1 1144313360 41512960 3572720
06:30:44 23962 8192000000 10100755120 10521104384 1093166288 11.773 97 41 84 0 1167541152 64403120 9971744
06:32:08 83231 8192000000 10080573328 10589335552 1092674176 11.768 97 15 30 0 1227485344 131061424 3749744
06:34:32 61467 8192000000 10020720640 10495156224 1103084288 11.867 99 11 20 1 1137787088 32279728 2423072
06:37:23 52801 8192000000 10100955392 10533486592 1116210144 11.991 99 20 40 1 1187331424 67097008 4024272
06:39:16 55631 8192000000 10079272576 10529296384 1113020224 11.961 98 16 30 0 1149008240 32805312 3182704
06:41:18 54431 8192000000 10108340528 10561122304 1140559408 12.221 100 29 58 0 1240234768 93958672 5716688
06:43:13 77648 8192000000 10107311168 10566062080 1127284736 12.096 98 19 36 0 1173075840 42442544 3348560
06:45:40 87337 8192000000 10144944576 10666594304 1150492464 12.314 99 29 58 0 1258906208 102230848 6182896
06:48:13 59294 8192000000 10112648032 10560126976 1124596032 12.070 97 32 62 0 1181824368 49634496 7593840
06:50:14 133238 8192000000 10064592208 10550607872 1129383872 12.116 99 35 69 0 1233524960 96420736 7720352
06:54:00 35014 8192000000 10107744112 10561490944 1139699584 12.213 99 41 77 1 1188776912 41357648 7719680
06:55:36 33894 8192000000 10075859472 10527850496 1124207936 12.067 98 23 46 1 1172723600 43749536 4766128
06:57:10 43334 8192000000 10070526064 10519597056 1098816192 11.826 97 13 26 0 1162858304 61593744 2448368
06:58:57 54387 8192000000 10068309696 10547949568 1120654880 12.033 99 17 33 0 1185587360 61314704 3617776
07:00:53 89832 8192000000 10130994784 10614386688 1109438080 11.927 97 15 30 0 1212578096 99825664 3314352
07:04:04 23386 8192000000 10068590144 10510819328 1115717600 11.987 98 30 56 1 1159816496 38419856 5679040
07:05:29 55859 8192000000 10136869312 10568163328 1074657632 11.597 95 21 41 0 1159967520 81002896 4306992
07:07:28 30968 8192000000 10172906208 10607857664 1103807712 11.874 98 30 60 0 1240959920 130547264 6604944
07:09:04 87742 8192000000 10143576128 10648858624 1144929728 12.262 100 29 56 0 1276746688 125490176 6326784
07:11:33 79776 8192000000 10087023056 10513600512 1085786960 11.703 96 13 26 0 1123602288 35491392 2323936
07:13:53 60915 8192000000 10076139664 10510225408 1128068784 12.103 100 26 49 0 1194336512 61669024 4598704
07:16:01 155371 8192000000 10099420064 10555113472 1096931104 11.809 97 39 78 0 1183037040 78031568 8074368
07:19:42 59577 8192000000 10072319968 10533199872 1123275840 12.058 99 7 14 0 1174362832 49573456 1513536
07:21:43 79042 8192000000 10073769936 10557894656 1093323648 11.774 97 10 21 0 1186477872 90799072 2355152
07:25:01 63242 8192000000 10082399456 10568175616 1120427600 12.031 99 27 50 0 1186685424 60214624 6043200
07:27:07 32195 8192000000 10145982272 10581741568 1116719184 11.996 98 30 60 2 1221891504 98270176 6902144
07:28:39 44362 8192000000 10138578864 10582851584 1142390224 12.238 100 27 54 0 1215631936 67471328 5770384
07:30:24 52331 8192000000 10169959104 10621640704 1123135040 12.057 99 30 60 0 1222926448 93336256 6455152
07:32:17 52407 8192000000 10085066096 10528239616 1105741904 11.892 98 38 72 0 1161049056 47783056 7524096
07:34:09 35875 8192000000 10131355616 10572853248 1123359440 12.059 99 36 69 2 1204906416 74801184 6745792
07:35:49 110742 8192000000 9611071792 10017890304 719327056 8.072 64 17 34 0 780017472 56743344 3947072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-03-2023 07:40:03 to 07-03-2023 07:40:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-03-2023 07:40:20 to 07-03-2023 07:40: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          215725
MON$OLDEST_ACTIVE               216114
MON$OLDEST_SNAPSHOT             216114
MON$NEXT_TRANSACTION            216114
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                       2058144
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.2906 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2906 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2906 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2906 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 ---
:98: 
:99: mscs-test-161.budzdorov.ru    Tue Mar  7 07:37:57 2023
:100:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:101: 
:102: 
:103: mscs-test-161.budzdorov.ru    Tue Mar  7 07:38:10 2023
:104:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:105: 
:106: 
:107: mscs-test-161.budzdorov.ru    Tue Mar  7 07:38:10 2023
:108:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:109: 
--- end of diff output ---

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

07.03.23 07:40:24. 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-07 07:40:26 No severe PSQL-related problems occured
07.03.23 07:40:28. End of report.