Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.03.2023 04: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 14868936 kB
MemAvailable 30402580 kB
Buffers 20 kB
Cached 16789764 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-05 04:37:27
DTS_END                         2023-03-05 04:37:27.0380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56834
TRN_ID                          218624
ATT_ID                          604
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: 9012.53 23699 1081504
customer order: creation 170.33 486 20439
customer order: refuse 14.77 490 1772
order to supplier: creation 45.70 1479 5484
order to supplier: removal 3.51 185524 421
invoice (draft): creation 45.76 3033 5491
invoice (draft): removal 49.34 1054 5921
invoice accept: apply 41.18 27449 4942
invoice accept: cancel 44.99 1255 5399
customer reserve: creation 3864.08 259 463690
customer reserve: removal 4437.24 115 532469
realization accept: apply 83.02 317 9962
realization accept: cancel 83.71 187 10045
payment from customer: creation 58.13 215 6975
payment from customer: removal 5.67 143 680
payment to supplier: creation 49.41 229 5929
payment to supplier: removal 3.36 184 403
service: total inventory turnovers 4.08 4310 489
service: total monetary turnovers 7.36 406 883
service: aggregate perf. data 0.88 16662 106
service: refresh index statistics 0.03 230179 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-03-2023 04:39:00 to 05-03-2023 04:39:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6579
TEST_TIME 2 6779
TEST_TIME 3 5665
TEST_TIME 4 6055
TEST_TIME 5 5983
TEST_TIME 6 6227
TEST_TIME 7 6455
TEST_TIME 8 6373
TEST_TIME 9 6397
TEST_TIME 10 6492
TEST_TIME 11 6544
TEST_TIME 12 6717
TEST_TIME 13 6638
TEST_TIME 14 6795
TEST_TIME 15 6689
TEST_TIME 16 6696
TEST_TIME 17 7012
TEST_TIME 18 7209
TEST_TIME 19 7339
TEST_TIME 20 7269
TEST_TIME 21 7376
TEST_TIME 22 7419
TEST_TIME 23 7414
TEST_TIME 24 7329
TEST_TIME 25 7673
TEST_TIME 26 7776
TEST_TIME 27 8058
TEST_TIME 28 7996
TEST_TIME 29 8207
TEST_TIME 30 8180
TEST_TIME 31 8516
TEST_TIME 32 8932
TEST_TIME 33 9240
TEST_TIME 34 9547
TEST_TIME 35 9772
TEST_TIME 36 9748
TEST_TIME 37 9983
TEST_TIME 38 10640
TEST_TIME 39 10466
TEST_TIME 40 10430
TEST_TIME 41 10305
TEST_TIME 42 10232
TEST_TIME 43 10133
TEST_TIME 44 10072
TEST_TIME 45 10164
TEST_TIME 46 10103
TEST_TIME 47 10188
TEST_TIME 48 10127
TEST_TIME 49 9949
TEST_TIME 50 9786
TEST_TIME 51 9784
TEST_TIME 52 9705
TEST_TIME 53 9618
TEST_TIME 54 9562
TEST_TIME 55 9498
TEST_TIME 56 9433
TEST_TIME 57 9455
TEST_TIME 58 9474
TEST_TIME 59 9404
TEST_TIME 60 9419
TEST_TIME 61 9435
TEST_TIME 62 9325
TEST_TIME 63 9285
TEST_TIME 64 9443
TEST_TIME 65 9397
TEST_TIME 66 9424
TEST_TIME 67 9384
TEST_TIME 68 9441
TEST_TIME 69 9535
TEST_TIME 70 9534
TEST_TIME 71 9542
TEST_TIME 72 9523
TEST_TIME 73 9524
TEST_TIME 74 9624
TEST_TIME 75 9541
TEST_TIME 76 9524
TEST_TIME 77 9643
TEST_TIME 78 9538
TEST_TIME 79 9500
TEST_TIME 80 9455
TEST_TIME 81 9387
TEST_TIME 82 9373
TEST_TIME 83 9368
TEST_TIME 84 9355
TEST_TIME 85 9334
TEST_TIME 86 9317
TEST_TIME 87 9273
TEST_TIME 88 9219
TEST_TIME 89 9138
TEST_TIME 90 9113
TEST_TIME 91 9159
TEST_TIME 92 9146
TEST_TIME 93 9119
TEST_TIME 94 9116
TEST_TIME 95 9103
TEST_TIME 96 9130
TEST_TIME 97 9124
TEST_TIME 98 9095
TEST_TIME 99 9120
TEST_TIME 100 9124
TEST_TIME 101 9097
TEST_TIME 102 9107
TEST_TIME 103 9137
TEST_TIME 104 9281
TEST_TIME 105 9339
TEST_TIME 106 9303
TEST_TIME 107 9289
TEST_TIME 108 9291
TEST_TIME 109 9253
TEST_TIME 110 9213
TEST_TIME 111 9214
TEST_TIME 112 9235
TEST_TIME 113 9214
TEST_TIME 114 9192
TEST_TIME 115 9152
TEST_TIME 116 9105
TEST_TIME 117 9067
TEST_TIME 118 9085
TEST_TIME 119 9075
TEST_TIME 120 9038

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-03-2023 04:39:04 to 05-03-2023 04:39:07.

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 1508545 1508545 0 0.00 0 25140 15 0 0
doc_list_biud 1508530 1508530 0 0.00 0 31735 46 0 0
sp_add_invoice_to_stock 5084 4942 142 2.79 63 221886 27450 0 142
sp_cancel_adding_invoice 5399 5399 0 0.00 4 63175 1255 0 0
sp_cancel_client_order 1772 1772 0 0.00 5 16822 490 0 0
sp_cancel_customer_reserve 532469 532469 0 0.00 1 18120 116 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 4 3287 143 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 4 10070 185 0 0
sp_cancel_supplier_invoice 5928 5921 7 0.11 3 23664 1055 0 7
sp_cancel_supplier_order 421 421 0 0.00 20 1522904 185524 0 0
sp_cancel_write_off 10045 10045 0 0.00 2 18539 187 0 0
sp_client_order 20439 20439 0 0.00 6 27934 486 0 0
sp_customer_reserve 463690 463690 0 0.00 0 152940 259 0 0
sp_fill_shopping_cart 31410 31410 0 0.00 1 20088 85 0 0
sp_fill_shopping_cart_clo_res 463644 463644 0 0.00 0 13961 18 0 0
sp_get_clo_for_invoice 4895 4895 0 0.00 0 26915 253 0 0
sp_kill_cost_storno 16527 16527 0 0.00 0 11170 83 0 0
sp_kill_qstorno_ret_qs2qd 1081325 1081325 0 0.00 0 23623 50 0 0
sp_kill_qty_storno 570882 570882 0 0.00 0 31734 114 0 0
sp_lock_selected_doc 20620 20620 0 0.00 0 8503 9 0 0
sp_make_cost_storno 27761 27761 0 0.00 0 14801 87 0 0
sp_multiply_rows_for_pdistr 27761 27761 0 0.00 0 10309 43 0 0
sp_multiply_rows_for_qdistr 20439 20439 0 0.00 0 17224 169 0 0
sp_pay_from_customer 6975 6975 0 0.00 6 25344 216 0 0
sp_pay_to_supplier 5929 5929 0 0.00 6 13726 229 0 0
sp_payment_common 12904 12904 0 0.00 2 25257 59 0 0
sp_qd_handle_on_cancel_clo 1772 1772 0 0.00 0 16808 405 0 0
sp_qd_handle_on_invoice_upd_sts 10292 10292 0 0.00 0 31732 543 0 0
sp_qd_handle_on_reserve_upd_sts 9962 9962 0 0.00 0 20291 74 0 0
sp_reserve_write_off 9962 9962 0 0.00 9 25231 318 0 0
sp_supplier_invoice 5491 5491 0 0.00 11 88176 3033 0 0
sp_supplier_order 5484 5484 0 0.00 8 28783 1479 0 0
srv_aggregate_perf_data 106 106 0 0.00 2868 212281 16663 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 761 151279 31475 0 0
srv_find_qd_qs_mism 1032725 1032725 0 0.00 0 23190 11 0 0
srv_make_invnt_saldo 489 489 0 0.00 54 87852 4310 0 0
srv_make_money_saldo 883 883 0 0.00 1 18680 407 0 0
srv_recalc_idx_stat 4 4 0 0.00 49571 365292 230179 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4944 4944 0 0.00 1 10166 937 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 1 7832 448 0 0
t$perf-norm:sp_cancel_client_order 1772 1772 0 0.00 0 6249 31 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 616 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 4213 31 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 1457 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 1487 35 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 1 89025 7072 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 3321 24 0 0
t$perf-norm:sp_client_order 20440 20440 0 0.00 0 6790 41 0 0
t$perf-norm:sp_customer_reserve 1007 1007 0 0.00 0 5444 21 0 0
t$perf-norm:sp_pay_from_customer 6975 6975 0 0.00 0 7409 24 0 0
t$perf-norm:sp_pay_to_supplier 5929 5929 0 0.00 0 5096 21 0 0
t$perf-norm:sp_reserve_write_off 9962 9962 0 0.00 0 8522 27 0 0
t$perf-norm:sp_supplier_invoice 5491 5491 0 0.00 0 9248 39 0 0
t$perf-norm:sp_supplier_order 5484 5484 0 0.00 0 3508 33 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 1156 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 726 17 0 0
t$perf-norm:srv_make_invnt_saldo 489 489 0 0.00 0 1219 27 0 0
t$perf-norm:srv_make_money_saldo 883 883 0 0.00 0 1411 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 8 177 69 0 0
v_add_invoice_to_stock 10066 9782 284 2.82 0 5337 31 0 284
v_all_customers 37498 37498 0 0.00 0 73 4 0 0
v_all_suppliers 31122 31122 0 0.00 0 121 4 0 0
v_all_wares 40878 40878 0 0.00 0 114 4 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 4394 25 0 0
v_cancel_client_order 3544 3544 0 0.00 0 2742 12 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 3187 16 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 5035 17 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 1133 11 0 0
v_cancel_supplier_invoice 800 786 14 1.75 0 1892 30 0 14
v_cancel_supplier_order 842 842 0 0.00 0 2264 20 0 0
v_cancel_write_off 2002 2002 0 0.00 0 5363 30 0 0
v_max_id_clo_ord 5484 5484 0 0.00 0 1665 6 0 0
v_max_id_clo_res 1007 1007 0 0.00 0 3648 18 0 0
v_max_id_ord_sup 5487 5487 0 0.00 0 6998 8 0 0
v_max_non_paid_invoice 1339 1339 0 0.00 0 421 8 0 0
v_max_non_paid_realizn 3561 3561 0 0.00 0 6406 11 0 0
v_min_id_clo_ord 5484 5484 0 0.00 0 2214 9 0 0
v_min_id_clo_res 1007 1007 0 0.00 0 1329 12 0 0
v_min_id_ord_sup 5487 5487 0 0.00 0 9698 11 0 0
v_min_non_paid_invoice 5929 5929 0 0.00 0 9070 29 0 0
v_min_non_paid_realizn 6975 6975 0 0.00 0 25231 29 0 0
v_our_firm 10208 10208 0 0.00 0 69 5 0 0
v_reserve_write_off 19924 19924 0 0.00 0 5329 14 0 0
x_lock_depdocs_on_canc_invoice 10927 10927 0 0.00 0 16970 135 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 16473 441 0 0
x_make_qstorno_1000_1200 5484 5484 0 0.00 2 28775 1304 0 0
x_make_qstorno_1200_2000 5491 5491 0 0.00 4 85778 2805 0 0
x_make_qstorno_2100_3300 463690 463690 0 0.00 0 152924 229 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-03-2023 04:39:09 to 05-03-2023 04:39:13.

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:31 10 8192000000 8812196560 8828555264 14461424 0.176 7 3 4 0 14668512 120128 86960
02:08:32 22330 8192000000 9715523008 10012897280 798683888 8.883 100 88 176 0 827237536 7435616 21118032
02:10:01 19945 8192000000 9764876208 10072236032 838612320 9.286 100 89 178 1 872648096 12748048 21287728
02:11:25 18291 8192000000 9803964912 10119700480 863629120 9.536 100 85 170 1 902542608 19351776 19561712
02:12:54 9716 8192000000 9876031248 10205536256 915280368 10.049 100 83 166 0 978953024 44800528 18872128
02:14:07 5118 8192000000 9915207952 10258391040 953743264 10.428 100 81 156 1 1026970368 57200256 16026848
02:15:15 3635 8192000000 9955238352 10306023424 984905152 10.732 100 89 174 3 1069665472 66937760 17822560
02:16:23 2169 8192000000 9995810640 10355191808 1013373888 11.008 100 98 196 0 1112303824 78427472 20502464
02:17:26 1343 8192000000 10009951792 10378887168 1033247456 11.200 100 96 180 1 1127572864 76702512 17622896
02:18:30 900 8192000000 10025514288 10397093888 1055059008 11.409 100 95 183 0 1151813344 78721840 18032496
02:19:32 950 8192000000 10028468688 10405629952 1067556000 11.529 100 92 175 2 1153853696 67529840 18767856
02:20:35 1272 8192000000 10045788016 10427465728 1070283520 11.555 100 92 173 0 1160818096 73192816 17341760
02:21:38 1225 8192000000 10052148544 10436214784 1075336784 11.603 100 86 166 2 1174104048 81690848 17076416
02:22:40 4077 8192000000 10041401872 10438787072 1080702688 11.654 100 64 124 3 1159348960 66651936 11994336
02:23:45 953 8192000000 10046673776 10437079040 1090218576 11.745 100 76 144 0 1169102672 65070464 13813632
02:24:47 1208 8192000000 10048619440 10438803456 1091386912 11.756 100 81 150 0 1166691184 58716256 16588016
02:25:50 1700 8192000000 10082842160 10471890944 1092773488 11.769 100 99 187 1 1202457776 90544944 19139344
02:26:52 1133 8192000000 10074021632 10463281152 1100054784 11.838 100 100 196 1 1204267008 83522064 20690160
02:27:54 1129 8192000000 10041894912 10428235776 1088066864 11.724 100 92 170 2 1167753488 62067488 17619136
02:28:57 1181 8192000000 10057439616 10445975552 1095741856 11.797 100 98 189 1 1182434368 67599840 19092672
02:29:59 1251 8192000000 10032104672 10411819008 1079843824 11.646 100 94 182 0 1154078896 56287824 17947248
02:31:01 1048 8192000000 10037432768 10419982336 1079603040 11.644 100 93 173 1 1157432864 58602880 19226944
02:32:04 1142 8192000000 10041763360 10422284288 1069592768 11.548 99 89 167 3 1162068080 73868528 18606784
02:33:06 1163 8192000000 9965284880 10331299840 1013066112 11.005 99 90 176 0 1090563760 59570144 17927504
02:34:10 983 8192000000 10023819824 10400878592 1053050368 11.390 100 93 173 2 1146179952 76254176 16875408
02:35:12 1156 8192000000 9995788048 10369126400 1048372400 11.345 100 95 180 2 1119966208 52359344 19234464
02:36:15 855 8192000000 9993699744 10368741376 1041320432 11.277 99 87 170 1 1114114720 54908688 17885600
02:37:17 985 8192000000 9965750944 10332082176 1019886464 11.071 99 93 166 3 1086128000 48757776 17483760
02:38:25 1157 8192000000 10008187344 10385399808 1055985968 11.418 99 94 185 0 1135464336 58741280 20737088
02:39:29 2287 8192000000 10047006752 10434269184 1071524064 11.567 100 82 154 1 1159756048 72861872 15370112
02:40:38 8307 8192000000 10064459296 10449027072 1082413168 11.670 100 75 145 1 1185451152 88361744 14676240
02:41:55 4902 8192000000 10070292240 10456879104 1083663200 11.682 99 72 137 1 1182262720 84366432 14233088
02:43:04 6625 8192000000 10090949200 10492125184 1108125616 11.915 100 72 125 3 1209282496 87208368 13948512
02:44:14 2702 8192000000 10136191600 10523979776 1117254448 12.001 100 83 159 2 1242352896 108033840 17064608
02:45:21 4851 8192000000 10155793392 10562523136 1131087984 12.132 100 73 142 3 1269420464 122637440 15695040
02:46:29 2134 8192000000 10191413776 10593947648 1144001360 12.253 100 88 160 1 1308408160 147741072 16665728
02:47:34 1744 8192000000 10224122304 10623782912 1153305952 12.341 100 89 173 0 1342474192 170028016 19140224
02:48:41 1535 8192000000 10256069392 10655531008 1162178400 12.424 100 87 166 0 1370862384 189578304 19105680
02:49:46 812 8192000000 10280249472 10684739584 1164763840 12.448 100 96 189 2 1389281456 202329376 22188240
02:50:52 886 8192000000 10322611136 10720833536 1171403216 12.510 100 93 183 0 1434978320 243269600 20305504
02:51:57 1654 8192000000 10326985520 10741563392 1177040176 12.563 100 89 171 0 1434390880 236117760 21232944
02:53:02 1986 8192000000 10376801312 10779729920 1187046016 12.656 100 90 177 1 1484843264 277624720 20172528
02:54:11 1014 8192000000 10407078784 10831671296 1184327760 12.631 100 95 188 4 1513746160 307905424 21512976
02:55:14 755 8192000000 10403770416 10826416128 1180548096 12.595 100 96 190 1 1513271616 309832992 22890528
02:56:20 5160 8192000000 10392838864 10818355200 1182294544 12.612 100 62 120 1 1508672288 311480864 14896880
02:57:40 828 8192000000 10434770368 10864730112 1198272160 12.760 100 75 147 1 1544173728 327249968 18651600
02:58:44 1145 8192000000 10450889664 10875518976 1190840560 12.691 100 86 159 2 1560096160 350270800 18984800
02:59:49 825 8192000000 10491340352 10897465344 1195627920 12.736 100 99 197 0 1596175296 376835808 23711568
03:00:53 3311 8192000000 10502550144 10945310720 1191727680 12.699 100 93 183 1 1598444928 383513008 23204240
03:02:01 1407 8192000000 10567528240 11021488128 1199347488 12.770 100 97 194 0 1667540368 444560352 23632528
03:03:06 961 8192000000 10559127952 10998243328 1200411024 12.780 100 85 165 1 1664294912 443031584 20852304
03:04:12 2117 8192000000 10562738848 11009892352 1192221376 12.704 100 76 148 0 1664177488 453402240 18553872
03:05:25 1172 8192000000 10575733952 10995683328 1190094304 12.684 100 81 161 0 1680039984 470747536 19198144
03:06:29 7787 8192000000 10558188688 11001597952 1176711376 12.560 100 47 93 0 1662337616 473775232 11851008
03:07:40 8609 8192000000 10554233664 11042086912 1181824064 12.607 100 44 88 1 1704994768 511126352 12044352
03:08:51 23344 8192000000 10479647248 11003822080 1166795008 12.467 99 40 80 0 1636326992 459060336 10471648
03:10:16 11787 8192000000 10423381616 10916597760 1164854992 12.449 100 39 78 1 1533324928 358017328 10452608
03:11:33 25778 8192000000 10372531872 10832965632 1163011920 12.431 100 38 76 0 1486382192 314027792 9342480
03:12:59 19042 8192000000 10339103984 10774913024 1155439312 12.361 100 33 66 0 1427274656 263983824 7851520
03:14:35 18608 8192000000 10255145648 10817445888 1144837360 12.261 100 18 34 0 1392864944 243100800 4926784
03:16:55 18098 8192000000 10076204544 10500829184 1134044896 12.159 100 55 107 0 1160321488 14885808 11390784
03:18:13 130982 8192000000 10093322064 10554908672 1115206640 11.982 98 25 50 3 1151129472 31037088 4885744
03:21:28 81335 8192000000 10071326384 10562629632 1122858512 12.054 99 12 23 0 1206904128 81450528 2595088
03:24:02 105893 8192000000 10080156496 10572988416 1102101328 11.858 97 10 20 0 1169281792 64362000 2818464
03:26:48 105524 8192000000 10085288464 10528444416 1097484288 11.814 97 36 70 1 1147285504 42221344 7579872
03:29:37 151279 8192000000 10092921936 10535325696 1118853008 12.016 98 34 58 1 1159156160 33975232 6327920
03:33:13 16882 8192000000 10141397312 10582663168 1136744080 12.185 100 32 64 1 1222622128 78849824 7028224
03:34:35 10178 8192000000 10127372576 10574426112 1127645936 12.099 99 64 124 2 1213428688 71255344 14527408
03:35:53 26744 8192000000 10092742560 10550804480 1106758272 11.902 99 12 24 0 1190785296 80471680 3555344
03:37:48 51477 8192000000 10126064800 10580914176 1122053488 12.046 99 30 58 1 1200538864 71981360 6504016
03:39:40 56494 8192000000 10136596560 10598129664 1117327024 12.002 99 29 57 1 1235931312 112585568 6018720
03:41:37 42256 8192000000 10052817056 10483052544 1107626368 11.910 98 19 37 0 1154407216 43196672 3584176
03:43:20 27058 8192000000 10083244800 10531827712 1117192496 12.000 98 30 51 0 1170773264 48114368 5466400
03:44:47 48514 8192000000 10157783088 10651123712 1150581184 12.315 100 34 68 0 1264108480 106395360 7131936
03:46:38 66782 8192000000 10101635632 10577747968 1132033856 12.141 99 27 54 0 1191026896 52845776 6147264
03:48:46 11320 8192000000 10119082288 10557390848 1143585504 12.249 100 44 88 0 1210816960 58293824 8937632
03:50:03 44637 8192000000 10162295712 10627100672 1138262496 12.199 99 36 72 1 1260190800 114154016 7774288
03:51:49 40647 8192000000 10086031712 10514251776 1101302096 11.850 97 17 32 1 1126356384 21845872 3208416
03:53:30 57085 8192000000 10132459280 10584211456 1100001568 11.838 97 23 46 0 1210253616 105122256 5129792
03:55:29 89212 8192000000 10050921440 10505461760 1113818400 11.969 100 25 51 0 1157043344 38532720 4692224
03:58:04 66051 8192000000 10093824384 10528305152 1126083472 12.084 99 46 88 0 1192962336 58353120 8525744
04:00:18 47281 8192000000 10071332560 10531684352 1125185200 12.076 99 15 30 0 1178491552 49901200 3405152
04:02:12 41291 8192000000 10070487632 10532179968 1135930800 12.177 100 22 42 0 1185943808 45512528 4500480
04:03:54 40755 8192000000 10055915920 10513944576 1095662432 11.796 98 11 21 0 1141555952 44066144 1827376
04:05:52 89465 8192000000 10103019568 10556096512 1107465248 11.908 99 11 22 0 1177463872 67506464 2492160
04:08:37 42665 8192000000 10058280048 10511945728 1118650480 12.014 99 13 26 0 1156397488 35191504 2555504
04:10:21 23090 8192000000 10060786016 10500595712 1109714816 11.930 98 36 70 1 1139656080 23143232 6798032
04:11:48 13921 8192000000 10101143408 10533531648 1132269696 12.143 100 51 102 0 1193014560 49508080 11236784
04:13:05 27174 8192000000 10158132304 10592964608 1114228464 11.972 98 46 92 0 1244392656 120672720 9491472
04:14:38 10364 8192000000 10197174048 10632642560 1143240416 12.246 100 49 94 2 1273476800 119878432 10357952
04:15:49 11753 8192000000 10195512720 10640007168 1130811200 12.129 99 59 117 0 1286106816 142518912 12776704
04:17:01 23883 8192000000 10203225328 10648121344 1138770928 12.204 99 35 70 0 1294972928 148604768 7597232
04:18:26 24341 8192000000 10252952768 10693394432 1143841984 12.252 99 39 77 0 1320304080 167811232 8650864
04:19:53 54037 8192000000 10249761952 10715901952 1135626592 12.174 99 26 52 0 1348912624 206563968 6722064
04:22:02 35790 8192000000 10095575664 10533134336 1112920656 11.960 98 34 66 0 1180984960 61314256 6750048
04:23:40 90295 8192000000 10125420720 10610270208 1128252160 12.105 99 24 48 1 1225606800 92421168 4933472
04:26:28 33923 8192000000 10089402896 10536820736 1140684256 12.222 100 40 73 0 1186468576 37937040 7847280
04:28:03 83665 8192000000 10091740848 10588811264 1121692240 12.043 98 16 32 0 1197968944 72886640 3390064
04:30:31 70688 8192000000 10090333696 10546159616 1145039728 12.263 100 16 32 0 1182188720 33370112 3778880
04:34:14 76735 8192000000 10100677856 10598023168 1138786352 12.204 99 10 20 0 1193371968 52239840 2345776
04:36:31 32523 8192000000 10125827648 10591346688 1136392128 12.182 99 38 74 1 1230117872 85349584 8376160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 05-03-2023 04:39:15 to 05-03-2023 04:39:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-03-2023 04:39:32 to 05-03-2023 04:39:34.

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          215209
MON$OLDEST_ACTIVE               218740
MON$OLDEST_SNAPSHOT             218740
MON$NEXT_TRANSACTION            218740
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                       2074464
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2905 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2905 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2905 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2905 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Sun Mar  5 04:37:36 2023
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sun Mar  5 04:37:37 2023
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Sun Mar  5 04:37:37 2023
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
--- end of diff output ---

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

05.03.23 04:39:36. 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-05 04:39:38 No severe PSQL-related problems occured
05.03.23 04:39:40. End of report.