Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.01.2023 04: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 14652308 kB
MemAvailable 30400440 kB
Buffers 20 kB
Cached 17008180 kB
SwapCached 4392 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-10 04:37:25
DTS_END                         2023-01-10 04:37:25.4290
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47052
TRN_ID                          217592
ATT_ID                          614
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: 8963.55 22973 1075626
customer order: creation 174.10 493 20892
customer order: refuse 14.95 476 1794
order to supplier: creation 45.16 1481 5419
order to supplier: removal 3.31 177729 397
invoice (draft): creation 45.39 3541 5447
invoice (draft): removal 50.45 1067 6054
invoice accept: apply 41.60 26969 4992
invoice accept: cancel 45.78 1417 5494
customer reserve: creation 3792.78 258 455133
customer reserve: removal 4460.44 103 535253
realization accept: apply 82.48 319 9898
realization accept: cancel 76.88 182 9225
payment from customer: creation 60.07 198 7208
payment from customer: removal 5.59 137 671
payment to supplier: creation 48.91 239 5869
payment to supplier: removal 3.28 136 393
service: total inventory turnovers 3.85 4663 462
service: total monetary turnovers 7.63 353 915
service: aggregate perf. data 0.88 18547 106
service: refresh index statistics 0.03 221155 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-01-2023 04:39:30 to 10-01-2023 04:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7031
TEST_TIME 2 5698
TEST_TIME 3 5491
TEST_TIME 4 6280
TEST_TIME 5 6680
TEST_TIME 6 6541
TEST_TIME 7 6392
TEST_TIME 8 6259
TEST_TIME 9 6793
TEST_TIME 10 6868
TEST_TIME 11 7147
TEST_TIME 12 7128
TEST_TIME 13 7219
TEST_TIME 14 7370
TEST_TIME 15 7431
TEST_TIME 16 7482
TEST_TIME 17 7742
TEST_TIME 18 7783
TEST_TIME 19 7734
TEST_TIME 20 7939
TEST_TIME 21 8010
TEST_TIME 22 8121
TEST_TIME 23 8196
TEST_TIME 24 8345
TEST_TIME 25 8475
TEST_TIME 26 8428
TEST_TIME 27 8304
TEST_TIME 28 8352
TEST_TIME 29 8268
TEST_TIME 30 8332
TEST_TIME 31 8567
TEST_TIME 32 8591
TEST_TIME 33 8910
TEST_TIME 34 9273
TEST_TIME 35 9534
TEST_TIME 36 9731
TEST_TIME 37 9799
TEST_TIME 38 9776
TEST_TIME 39 10411
TEST_TIME 40 10321
TEST_TIME 41 10237
TEST_TIME 42 10139
TEST_TIME 43 10474
TEST_TIME 44 10353
TEST_TIME 45 10276
TEST_TIME 46 10254
TEST_TIME 47 10191
TEST_TIME 48 10214
TEST_TIME 49 10116
TEST_TIME 50 9998
TEST_TIME 51 9938
TEST_TIME 52 10029
TEST_TIME 53 10028
TEST_TIME 54 9956
TEST_TIME 55 9966
TEST_TIME 56 10012
TEST_TIME 57 9915
TEST_TIME 58 9853
TEST_TIME 59 9719
TEST_TIME 60 9733
TEST_TIME 61 9710
TEST_TIME 62 9667
TEST_TIME 63 9561
TEST_TIME 64 9463
TEST_TIME 65 9435
TEST_TIME 66 9382
TEST_TIME 67 9367
TEST_TIME 68 9405
TEST_TIME 69 9351
TEST_TIME 70 9464
TEST_TIME 71 9482
TEST_TIME 72 9437
TEST_TIME 73 9381
TEST_TIME 74 9318
TEST_TIME 75 9315
TEST_TIME 76 9296
TEST_TIME 77 9229
TEST_TIME 78 9201
TEST_TIME 79 9177
TEST_TIME 80 9110
TEST_TIME 81 9078
TEST_TIME 82 9014
TEST_TIME 83 9021
TEST_TIME 84 9081
TEST_TIME 85 9096
TEST_TIME 86 9075
TEST_TIME 87 9199
TEST_TIME 88 9215
TEST_TIME 89 9182
TEST_TIME 90 9251
TEST_TIME 91 9251
TEST_TIME 92 9274
TEST_TIME 93 9255
TEST_TIME 94 9326
TEST_TIME 95 9307
TEST_TIME 96 9230
TEST_TIME 97 9182
TEST_TIME 98 9127
TEST_TIME 99 9164
TEST_TIME 100 9165
TEST_TIME 101 9105
TEST_TIME 102 9076
TEST_TIME 103 9035
TEST_TIME 104 9005
TEST_TIME 105 8993
TEST_TIME 106 8963
TEST_TIME 107 8988
TEST_TIME 108 9001
TEST_TIME 109 8984
TEST_TIME 110 8982
TEST_TIME 111 8985
TEST_TIME 112 8995
TEST_TIME 113 9012
TEST_TIME 114 9076
TEST_TIME 115 9051
TEST_TIME 116 9094
TEST_TIME 117 9117
TEST_TIME 118 9050
TEST_TIME 119 9037
TEST_TIME 120 8992

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-01-2023 04:39:33 to 10-01-2023 04:39:37.

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 1494904 1494904 0 0.00 0 20822 14 0 0
doc_list_biud 1494892 1494892 0 0.00 0 62772 44 0 0
sp_add_invoice_to_stock 5161 4992 169 3.27 45 273907 26970 0 169
sp_cancel_adding_invoice 5494 5494 0 0.00 7 99983 1418 0 0
sp_cancel_client_order 1794 1794 0 0.00 6 7767 476 0 0
sp_cancel_customer_reserve 535253 535253 0 0.00 1 62774 104 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 8022 138 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 4 3289 137 0 0
sp_cancel_supplier_invoice 6070 6054 16 0.26 3 35194 1068 0 16
sp_cancel_supplier_order 397 397 0 0.00 22 1531099 177729 0 0
sp_cancel_write_off 9225 9225 0 0.00 2 24143 183 0 0
sp_client_order 20892 20892 0 0.00 6 30188 494 0 0
sp_customer_reserve 455133 455133 0 0.00 1 70353 259 0 0
sp_fill_shopping_cart 31750 31750 0 0.00 1 22090 94 0 0
sp_fill_shopping_cart_clo_res 455099 455099 0 0.00 0 26151 19 0 0
sp_get_clo_for_invoice 4948 4948 0 0.00 0 39004 276 0 0
sp_kill_cost_storno 15783 15783 0 0.00 0 20519 82 0 0
sp_kill_qstorno_ret_qs2qd 1086182 1086182 0 0.00 0 62765 45 0 0
sp_kill_qty_storno 573063 573063 0 0.00 0 62772 104 0 0
sp_lock_selected_doc 20505 20505 0 0.00 0 10329 8 0 0
sp_make_cost_storno 27923 27923 0 0.00 0 20378 95 0 0
sp_multiply_rows_for_pdistr 27923 27923 0 0.00 0 9330 42 0 0
sp_multiply_rows_for_qdistr 20891 20891 0 0.00 0 12459 169 0 0
sp_pay_from_customer 7208 7208 0 0.00 5 11821 199 0 0
sp_pay_to_supplier 5869 5869 0 0.00 7 32228 240 0 0
sp_payment_common 13074 13074 0 0.00 2 7406 50 0 0
sp_qd_handle_on_cancel_clo 1794 1794 0 0.00 0 7760 392 0 0
sp_qd_handle_on_invoice_upd_sts 10441 10441 0 0.00 0 14569 580 0 0
sp_qd_handle_on_reserve_upd_sts 9898 9898 0 0.00 0 18696 72 0 0
sp_reserve_write_off 9898 9898 0 0.00 8 20967 320 0 0
sp_supplier_invoice 5447 5447 0 0.00 8 100615 3542 0 0
sp_supplier_order 5419 5419 0 0.00 7 43134 1481 0 0
srv_aggregate_perf_data 106 106 0 0.00 2736 242258 18547 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 730 119168 33791 0 0
srv_find_qd_qs_mism 1026926 1026926 0 0.00 0 14003 10 0 0
srv_make_invnt_saldo 462 462 0 0.00 63 69874 4663 0 0
srv_make_money_saldo 915 915 0 0.00 1 11620 353 0 0
srv_recalc_idx_stat 4 4 0 0.00 27687 496721 221155 0 0
t$perf-abend:sp_add_invoice_to_stock 169 169 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5001 5001 0 0.00 1 15546 1066 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 1 7429 483 0 0
t$perf-norm:sp_cancel_client_order 1794 1794 0 0.00 0 4403 33 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 1661 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 785 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 607 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 3197 47 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 122236 7816 0 0
t$perf-norm:sp_cancel_write_off 895 895 0 0.00 0 1978 20 0 0
t$perf-norm:sp_client_order 20892 20892 0 0.00 0 9754 40 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 4025 24 0 0
t$perf-norm:sp_pay_from_customer 7208 7208 0 0.00 0 5206 22 0 0
t$perf-norm:sp_pay_to_supplier 5870 5870 0 0.00 0 6268 22 0 0
t$perf-norm:sp_reserve_write_off 9898 9898 0 0.00 0 6087 27 0 0
t$perf-norm:sp_supplier_invoice 5447 5447 0 0.00 0 7607 36 0 0
t$perf-norm:sp_supplier_order 5419 5419 0 0.00 0 9405 36 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 320 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 706 15 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 601 13 0 0
t$perf-norm:srv_make_money_saldo 915 915 0 0.00 0 5087 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 22 193 93 0 0
v_add_invoice_to_stock 10226 9888 338 3.30 0 31085 47 0 338
v_all_customers 38464 38464 0 0.00 0 71 4 0 0
v_all_suppliers 30482 30482 0 0.00 0 80 5 0 0
v_all_wares 41774 41774 0 0.00 0 292 4 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 5033 26 0 0
v_cancel_client_order 3588 3588 0 0.00 0 7185 13 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 4662 14 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 9082 18 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 3241 15 0 0
v_cancel_supplier_invoice 862 830 32 3.71 0 2380 32 0 32
v_cancel_supplier_order 794 794 0 0.00 0 1451 12 0 0
v_cancel_write_off 1790 1790 0 0.00 0 23951 41 0 0
v_max_id_clo_ord 5416 5416 0 0.00 0 555 6 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 1396 14 0 0
v_max_id_ord_sup 5442 5442 0 0.00 0 5542 13 0 0
v_max_non_paid_invoice 1482 1482 0 0.00 0 58 8 0 0
v_max_non_paid_realizn 3606 3606 0 0.00 0 2079 9 0 0
v_min_id_clo_ord 5416 5416 0 0.00 0 3628 11 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 814 9 0 0
v_min_id_ord_sup 5442 5442 0 0.00 0 5778 11 0 0
v_min_non_paid_invoice 5867 5867 0 0.00 0 4566 15 0 0
v_min_non_paid_realizn 7207 7207 0 0.00 0 6181 21 0 0
v_our_firm 10508 10508 0 0.00 0 72 5 0 0
v_reserve_write_off 19796 19796 0 0.00 0 18444 14 0 0
x_lock_depdocs_on_canc_invoice 11133 11133 0 0.00 0 18765 140 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 2846 358 0 0
x_make_qstorno_1000_1200 5419 5419 0 0.00 2 43108 1291 0 0
x_make_qstorno_1200_2000 5447 5447 0 0.00 4 99469 3277 0 0
x_make_qstorno_2100_3300 455132 455132 0 0.00 0 70341 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-01-2023 04:39:38 to 10-01-2023 04:39:42.

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
02:07:29 9 8192000000 8800609344 8812613632 4473472 0.054 2 1 2 0 4572864 63616 35776
02:08:30 28586 8192000000 9724282496 10021867520 797859232 8.875 100 92 183 0 828576944 8024704 22693008
02:10:08 29229 8192000000 9766138064 10073219072 833128832 9.231 100 82 164 0 865693136 12912400 19651904
02:12:00 15050 8192000000 9815079872 10139873280 881247360 9.712 100 74 148 0 922157072 24358304 16551408
02:13:18 8075 8192000000 9869375632 10201976832 926400704 10.159 100 83 162 0 978838848 34758736 17679408
02:14:29 7321 8192000000 9922838048 10266136576 960627120 10.495 100 85 164 0 1032453040 55124880 16701040
02:15:39 1549 8192000000 9977844240 10328694784 987451264 10.757 100 95 184 0 1095631232 89957376 18222592
02:16:44 1806 8192000000 9979195056 10339057664 1012930832 11.004 100 87 170 1 1100060208 70138544 16990832
02:17:48 2016 8192000000 10026653008 10394537984 1028972000 11.159 100 82 158 0 1135893584 91642112 15279472
02:18:53 829 8192000000 10045654512 10420658176 1050684000 11.367 100 95 182 0 1167261104 97511568 19065536
02:19:55 1809 8192000000 10034988640 10414649344 1059804448 11.455 100 94 182 0 1161475040 82993728 18676864
02:20:58 1555 8192000000 10041391328 10423877632 1066201664 11.516 100 80 153 2 1163269504 81687632 15380208
02:22:00 787 8192000000 10038392656 10418626560 1072405600 11.575 100 96 190 0 1157238704 66895312 17937792
02:23:02 6257 8192000000 10032947456 10421317632 1076301136 11.612 100 71 128 4 1151315696 61978608 13035952
02:24:10 945 8192000000 10047152896 10434052096 1082247024 11.669 100 92 181 0 1168393904 67562288 18584592
02:25:13 5480 8192000000 10042350432 10431647744 1087905824 11.723 100 65 124 2 1163663776 64333664 11424288
02:26:21 1109 8192000000 10062404528 10447626240 1080288656 11.650 99 93 175 1 1181705776 83482128 17934992
02:27:22 1011 8192000000 10062720992 10451894272 1088939760 11.733 100 92 187 1 1187486272 79600464 18946048
02:28:24 4283 8192000000 10039790544 10435268608 1091147520 11.754 100 65 121 3 1156241808 53384800 11709488
02:29:29 1652 8192000000 10042750768 10431811584 1079034320 11.638 100 80 160 0 1164355680 68761360 16560000
02:30:33 1216 8192000000 10060575712 10446712832 1086635168 11.711 100 93 182 2 1181891664 76961904 18294592
02:31:35 1239 8192000000 10008667968 10398543872 1068606128 11.539 99 72 127 1 1134105920 51559680 13940112
02:32:37 1033 8192000000 10026314112 10410700800 1070256720 11.555 100 85 167 0 1149244576 62620208 16367648
02:33:39 1236 8192000000 9999319520 10376630272 1048067824 11.342 100 81 159 2 1119891536 54880464 16943248
02:34:41 1310 8192000000 10007402640 10376724480 1036472720 11.231 100 97 178 1 1130055088 76128672 17453696
02:35:44 1309 8192000000 9982854768 10354753536 1026911072 11.139 100 92 168 1 1107130768 63038560 17181136
02:36:47 1129 8192000000 9992284432 10361851904 1012845568 11.003 98 94 183 2 1115530016 84075552 18608896
02:37:52 2677 8192000000 10007312544 10385457152 1040892672 11.273 99 93 179 2 1124143952 64801648 18449632
02:39:02 8863 8192000000 10025652272 10406400000 1066789344 11.521 100 86 162 2 1147421744 63935824 16696576
02:40:13 9847 8192000000 10046931920 10434953216 1074114128 11.591 100 66 126 0 1167220336 80378240 12727968
02:41:27 1946 8192000000 10077015536 10452590592 1073034672 11.581 99 92 177 0 1193749824 101842656 18872496
02:42:32 6744 8192000000 10091150416 10483093504 1085190688 11.697 99 77 148 2 1208615264 108203520 15221056
02:43:46 3057 8192000000 10108557984 10500038656 1108460768 11.918 100 69 131 0 1227906848 105230512 14215568
02:44:54 1402 8192000000 10136715008 10534113280 1121256384 12.039 100 90 167 1 1259064960 119743984 18064592
02:45:59 2739 8192000000 10183533872 10594889728 1130834784 12.129 100 77 139 2 1300404944 154468736 15101424
02:47:05 835 8192000000 10198265056 10590969856 1140119424 12.217 100 93 175 1 1312299104 153979568 18200112
02:48:10 846 8192000000 10196550384 10607677440 1142238528 12.237 100 90 168 1 1309942976 149686528 18017920
02:49:15 2795 8192000000 10244108000 10649063424 1156565344 12.371 100 81 152 0 1357847888 184337360 16945184
02:50:21 942 8192000000 10247936400 10646765568 1152952704 12.337 100 98 190 2 1361035472 187119904 20962864
02:51:27 5265 8192000000 10307721952 10714017792 1166607936 12.465 100 86 157 2 1413859808 230444160 16807712
02:52:36 1145 8192000000 10316693040 10722230272 1178913952 12.580 100 95 185 1 1424714320 225113696 20686672
02:53:41 1584 8192000000 10352930032 10759294976 1178009600 12.572 100 93 183 0 1460863376 262005376 20848400
02:54:45 902 8192000000 10417317120 10840920064 1200866736 12.784 100 99 196 0 1530464112 306682592 22914784
02:55:50 1386 8192000000 10393593696 10798321664 1182611440 12.615 100 94 182 1 1501246640 298775664 19859536
02:56:54 786 8192000000 10453008944 10865770496 1191081424 12.693 100 97 190 1 1554504256 342043088 21379744
02:57:56 721 8192000000 10414800672 10844991488 1156033088 12.366 100 94 186 0 1517827488 340376048 21418352
02:59:02 1093 8192000000 10459698352 10888060928 1176507744 12.558 100 91 168 1 1563794992 367111088 20176160
03:00:08 3205 8192000000 10452253936 10908639232 1176106480 12.554 100 70 137 2 1562974960 370334464 16534016
03:01:18 8029 8192000000 10453344592 10906726400 1182575280 12.614 100 53 103 0 1582096752 386490912 13030560
03:02:36 5711 8192000000 10469339280 10892136448 1185341232 12.640 100 53 104 0 1573596960 375245984 13009744
03:03:48 2284 8192000000 10500899584 10922586112 1178799408 12.579 99 61 118 2 1608753888 415828272 14126208
03:04:54 3909 8192000000 10538357040 10972958720 1167684928 12.475 99 61 118 1 1652542336 469143808 15713600
03:06:10 977 8192000000 10583967600 10993922048 1173153872 12.526 100 89 165 1 1686561504 491591248 21816384
03:07:15 7516 8192000000 10591624368 11036422144 1165598048 12.456 99 65 130 0 1688844976 506698000 16548928
03:08:24 12062 8192000000 10555865760 11054014464 1176708768 12.559 100 52 104 1 1683678352 493480384 13489200
03:09:51 5574 8192000000 10542644032 11017351168 1177296144 12.565 100 53 101 0 1644411312 454315648 12799520
03:10:58 19968 8192000000 10477044912 10979000320 1174903536 12.543 100 45 90 1 1579242880 393120752 11218592
03:12:22 11214 8192000000 10403934112 10847191040 1169349072 12.491 100 38 76 0 1492851392 314329584 9172736
03:14:12 34690 8192000000 10338364720 10802483200 1132345568 12.143 97 23 46 0 1416476720 278313248 5817904
03:16:01 17716 8192000000 10251842432 10760286208 1147812896 12.289 100 40 77 0 1373481040 216704928 8963216
03:17:22 14045 8192000000 10219840912 10662768640 1159691344 12.400 100 56 98 1 1302303472 131706560 10905568
03:18:38 14065 8192000000 10202987264 10652303360 1153213840 12.340 100 35 70 0 1301913680 141245600 7454240
03:20:10 75224 8192000000 10099082624 10588401664 1137171808 12.189 99 15 28 0 1225352800 85073024 3107968
03:22:26 61058 8192000000 10139831632 10595102720 1132720032 12.147 99 19 35 1 1223302400 86880928 3701440
03:25:09 101920 8192000000 10104434112 10557120512 1140972224 12.225 99 15 32 0 1179708576 35840384 2895968
03:27:53 69418 8192000000 10165579264 10628427776 1155212288 12.358 100 30 59 0 1234197936 72364480 6621168
03:30:02 98873 8192000000 10100471808 10558271488 1117576848 12.004 97 21 40 0 1166021696 43902096 4542752
03:32:45 80495 8192000000 10119105008 10605711360 1139299600 12.209 99 8 16 0 1193905744 53163536 1442608
03:35:08 102710 8192000000 10090355600 10536185856 1124763264 12.072 98 36 69 0 1171174576 38310256 8101056
03:38:03 75948 8192000000 10109104752 10538299392 1114420592 11.974 97 38 70 0 1154535408 33160144 6954672
03:40:21 84378 8192000000 10108331040 10533986304 1116301776 11.992 96 47 91 1 1175166960 49852288 9012896
03:42:48 37669 8192000000 10157980208 10591555584 1142785216 12.242 99 48 93 0 1253712512 100554032 10373264
03:44:27 14687 8192000000 10228226640 10664370176 1157563216 12.380 100 65 127 0 1323666880 152181392 13922272
03:45:47 51055 8192000000 10164736624 10642710528 1125538832 12.079 98 11 22 1 1252327360 124502160 2286368
03:47:45 29135 8192000000 10102418416 10596859904 1125915712 12.083 99 13 24 0 1209113904 80544896 2653296
03:49:23 48210 8192000000 10062017648 10507366400 1100366640 11.841 97 29 58 0 1135791792 29290208 6134944
03:51:18 75266 8192000000 10069327696 10554347520 1124982560 12.074 100 8 16 0 1156267152 29665520 1619072
03:53:44 43405 8192000000 10049109312 10511486976 1127646336 12.099 100 14 28 0 1153441200 22801968 2992896
03:55:34 67687 8192000000 10060093872 10532622336 1117775520 12.006 99 14 28 0 1176109920 55318464 3015936
03:57:50 67864 8192000000 10092601664 10562035712 1136767824 12.185 100 26 53 0 1213105216 70705904 5631488
04:00:09 59932 8192000000 10119910288 10585645056 1125607056 12.080 99 17 34 0 1232017536 102941040 3469440
04:02:11 20683 8192000000 10090370608 10544766976 1110816704 11.940 99 30 60 0 1163242736 46247104 6178928
04:03:32 36279 8192000000 10112814112 10554064896 1091557440 11.757 97 48 96 0 1176069968 74292576 10219952
04:05:33 40080 8192000000 10087223008 10520190976 1125753280 12.081 100 29 51 0 1173167136 42360496 5053360
04:07:13 71171 8192000000 10082043056 10527305728 1097276928 11.812 97 22 44 0 1166543904 65168432 4098544
04:09:31 54723 8192000000 10103275584 10550083584 1113618464 11.967 98 35 67 0 1174507712 54618336 6270912
04:11:29 67665 8192000000 10077586832 10514460672 1125608672 12.080 98 29 56 1 1187979920 56618544 5752704
04:13:39 89078 8192000000 10126195040 10567274496 1148142656 12.292 100 41 80 0 1228547136 72103200 8301280
04:16:20 69950 8192000000 10073205872 10584768512 1135632272 12.174 99 17 34 0 1235623536 95952192 4039072
04:18:32 60819 8192000000 10057773376 10534821888 1129122016 12.113 99 11 21 0 1174976992 43651504 2203472
04:21:18 63505 8192000000 10066690944 10560946176 1122115872 12.047 98 19 36 0 1173352832 47594688 3642272
04:23:22 85766 8192000000 10103172752 10572951552 1122732576 12.053 98 14 28 0 1192592816 66652000 3208240
04:25:47 17006 8192000000 10085581920 10529517568 1135065456 12.169 99 52 86 1 1186723424 41910832 9747136
04:27:10 22361 8192000000 10095965824 10546515968 1144693728 12.260 100 43 80 0 1191713088 38705728 8313632
04:28:52 35564 8192000000 10114103616 10563575808 1111903584 11.950 97 41 82 0 1193770560 73662128 8204848
04:30:39 29080 8192000000 10104244592 10554904576 1124558560 12.070 99 46 92 0 1228255360 94195120 9501680
04:32:10 33460 8192000000 10090371120 10544783360 1078735632 11.635 95 34 67 0 1150683408 65483856 6463920
04:33:44 119167 8192000000 10082951760 10568916992 1100922448 11.846 97 19 38 0 1158222576 53297088 4003040
04:36:43 54724 8192000000 9444746992 9804484608 537182640 6.153 47 11 22 0 578820256 39619680 2017936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 10-01-2023 04:39:44 to 10-01-2023 04:40:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 169 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-01-2023 04:40:02 to 10-01-2023 04:40:04.

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          217539
MON$OLDEST_ACTIVE               218297
MON$OLDEST_SNAPSHOT             218297
MON$NEXT_TRANSACTION            218297
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                       2105664
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

10.01.23 04:40:05. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-01-10 04:40:07 No severe PSQL-related problems occured
10.01.23 04:40:09. End of report.