Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.07.2022 07: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 15964692 kB
MemAvailable 31110620 kB
Buffers 0 kB
Cached 15771480 kB
SwapCached 2496 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.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-18 07:37:15
DTS_END                         2022-07-18 07:37:15.7420
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/42332
TRN_ID                          217322
ATT_ID                          670
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: 8782.83 23326 1053940
customer order: creation 169.04 513 20285
customer order: refuse 14.08 485 1690
order to supplier: creation 45.18 1416 5422
order to supplier: removal 3.13 190984 376
invoice (draft): creation 44.63 3227 5356
invoice (draft): removal 47.83 1086 5739
invoice accept: apply 41.39 29031 4967
invoice accept: cancel 43.08 1419 5169
customer reserve: creation 3832.92 275 459950
customer reserve: removal 4261.32 110 511358
realization accept: apply 81.32 321 9758
realization accept: cancel 72.40 185 8688
payment from customer: creation 57.41 196 6889
payment from customer: removal 5.38 148 646
payment to supplier: creation 47.62 215 5714
payment to supplier: removal 3.20 139 384
service: total inventory turnovers 3.93 4324 472
service: total monetary turnovers 7.91 459 949
service: aggregate perf. data 1.03 14231 124
service: refresh index statistics 0.03 217748 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-07-2022 07:38:51 to 18-07-2022 07:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8712
TEST_TIME 2 6133
TEST_TIME 3 6541
TEST_TIME 4 6799
TEST_TIME 5 7187
TEST_TIME 6 6854
TEST_TIME 7 7015
TEST_TIME 8 6910
TEST_TIME 9 6736
TEST_TIME 10 7013
TEST_TIME 11 7067
TEST_TIME 12 7187
TEST_TIME 13 7216
TEST_TIME 14 7096
TEST_TIME 15 7375
TEST_TIME 16 7471
TEST_TIME 17 7556
TEST_TIME 18 7536
TEST_TIME 19 7477
TEST_TIME 20 7647
TEST_TIME 21 7629
TEST_TIME 22 7579
TEST_TIME 23 7601
TEST_TIME 24 7641
TEST_TIME 25 7635
TEST_TIME 26 7898
TEST_TIME 27 8086
TEST_TIME 28 8244
TEST_TIME 29 8472
TEST_TIME 30 8913
TEST_TIME 31 8943
TEST_TIME 32 9376
TEST_TIME 33 9868
TEST_TIME 34 10569
TEST_TIME 35 10711
TEST_TIME 36 10505
TEST_TIME 37 10335
TEST_TIME 38 10383
TEST_TIME 39 10410
TEST_TIME 40 10187
TEST_TIME 41 10038
TEST_TIME 42 10251
TEST_TIME 43 10057
TEST_TIME 44 9934
TEST_TIME 45 9817
TEST_TIME 46 9810
TEST_TIME 47 9749
TEST_TIME 48 9749
TEST_TIME 49 9644
TEST_TIME 50 9651
TEST_TIME 51 9524
TEST_TIME 52 9402
TEST_TIME 53 9381
TEST_TIME 54 9370
TEST_TIME 55 9660
TEST_TIME 56 9698
TEST_TIME 57 9652
TEST_TIME 58 9656
TEST_TIME 59 9612
TEST_TIME 60 9670
TEST_TIME 61 9677
TEST_TIME 62 9605
TEST_TIME 63 9610
TEST_TIME 64 9655
TEST_TIME 65 9673
TEST_TIME 66 9651
TEST_TIME 67 9654
TEST_TIME 68 9606
TEST_TIME 69 9521
TEST_TIME 70 9531
TEST_TIME 71 9498
TEST_TIME 72 9409
TEST_TIME 73 9385
TEST_TIME 74 9338
TEST_TIME 75 9258
TEST_TIME 76 9232
TEST_TIME 77 9153
TEST_TIME 78 9130
TEST_TIME 79 9198
TEST_TIME 80 9147
TEST_TIME 81 9221
TEST_TIME 82 9191
TEST_TIME 83 9174
TEST_TIME 84 9229
TEST_TIME 85 9251
TEST_TIME 86 9263
TEST_TIME 87 9249
TEST_TIME 88 9417
TEST_TIME 89 9356
TEST_TIME 90 9370
TEST_TIME 91 9347
TEST_TIME 92 9279
TEST_TIME 93 9256
TEST_TIME 94 9215
TEST_TIME 95 9225
TEST_TIME 96 9176
TEST_TIME 97 9140
TEST_TIME 98 9091
TEST_TIME 99 9019
TEST_TIME 100 8975
TEST_TIME 101 8942
TEST_TIME 102 8944
TEST_TIME 103 9023
TEST_TIME 104 9013
TEST_TIME 105 9001
TEST_TIME 106 8992
TEST_TIME 107 9025
TEST_TIME 108 9007
TEST_TIME 109 9017
TEST_TIME 110 9101
TEST_TIME 111 9058
TEST_TIME 112 9068
TEST_TIME 113 9068
TEST_TIME 114 9007
TEST_TIME 115 8966
TEST_TIME 116 8954
TEST_TIME 117 8900
TEST_TIME 118 8866
TEST_TIME 119 8866
TEST_TIME 120 8806

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-07-2022 07:38:54 to 18-07-2022 07:38:58.

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 1476524 1476524 0 0.00 0 35685 16 0 0
doc_list_biud 1476519 1476519 0 0.00 0 38913 45 0 0
sp_add_invoice_to_stock 5134 4967 167 3.25 87 224728 29031 0 167
sp_cancel_adding_invoice 5169 5169 0 0.00 7 81470 1420 0 0
sp_cancel_client_order 1690 1690 0 0.00 5 14804 485 0 0
sp_cancel_customer_reserve 511358 511358 0 0.00 1 38921 111 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 3 7888 148 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 4 2240 139 0 0
sp_cancel_supplier_invoice 5753 5739 14 0.24 2 23505 1087 0 14
sp_cancel_supplier_order 376 376 0 0.00 15 1692369 190984 0 0
sp_cancel_write_off 8688 8688 0 0.00 2 9819 185 0 0
sp_client_order 20285 20285 0 0.00 6 37544 514 0 0
sp_customer_reserve 459950 459950 0 0.00 0 46180 276 0 0
sp_fill_shopping_cart 31062 31062 0 0.00 1 8738 87 0 0
sp_fill_shopping_cart_clo_res 459917 459917 0 0.00 0 15568 20 0 0
sp_get_clo_for_invoice 4920 4920 0 0.00 0 20610 297 0 0
sp_kill_cost_storno 14887 14887 0 0.00 0 7577 84 0 0
sp_kill_qstorno_ret_qs2qd 1037519 1037519 0 0.00 0 38903 48 0 0
sp_kill_qty_storno 547698 547698 0 0.00 0 38912 111 0 0
sp_lock_selected_doc 20184 20184 0 0.00 0 8173 9 0 0
sp_make_cost_storno 27281 27281 0 0.00 0 35675 92 0 0
sp_multiply_rows_for_pdistr 27281 27281 0 0.00 0 8454 44 0 0
sp_multiply_rows_for_qdistr 20285 20285 0 0.00 0 12268 188 0 0
sp_pay_from_customer 6889 6889 0 0.00 5 26990 196 0 0
sp_pay_to_supplier 5714 5714 0 0.00 6 10778 216 0 0
sp_payment_common 12603 12603 0 0.00 2 9237 53 0 0
sp_qd_handle_on_cancel_clo 1690 1690 0 0.00 0 14771 408 0 0
sp_qd_handle_on_invoice_upd_sts 10089 10089 0 0.00 0 19977 569 0 0
sp_qd_handle_on_reserve_upd_sts 9758 9758 0 0.00 0 14386 77 0 0
sp_reserve_write_off 9758 9758 0 0.00 8 14816 321 0 0
sp_supplier_invoice 5356 5356 0 0.00 10 87804 3227 0 0
sp_supplier_order 5422 5422 0 0.00 6 42420 1417 0 0
srv_aggregate_perf_data 124 124 0 0.00 2409 96943 14232 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 713 146806 34183 0 0
srv_find_qd_qs_mism 1005298 1005298 0 0.00 0 31124 12 0 0
srv_make_invnt_saldo 472 472 0 0.00 73 110568 4324 0 0
srv_make_money_saldo 949 949 0 0.00 1 19529 460 0 0
srv_recalc_idx_stat 4 4 0 0.00 52033 357542 217749 0 0
t$perf-abend:sp_add_invoice_to_stock 167 167 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4968 4968 0 0.00 1 11809 1025 0 0
t$perf-norm:sp_cancel_adding_invoice 375 375 0 0.00 0 7205 564 0 0
t$perf-norm:sp_cancel_client_order 1690 1690 0 0.00 0 17743 38 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 3384 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 3238 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 246 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 468 31 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 91974 7645 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 3056 28 0 0
t$perf-norm:sp_client_order 20285 20285 0 0.00 0 5994 42 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 2227 18 0 0
t$perf-norm:sp_pay_from_customer 6889 6889 0 0.00 0 2810 19 0 0
t$perf-norm:sp_pay_to_supplier 5714 5714 0 0.00 0 6971 24 0 0
t$perf-norm:sp_reserve_write_off 9758 9758 0 0.00 0 8113 27 0 0
t$perf-norm:sp_supplier_invoice 5356 5356 0 0.00 0 17106 40 0 0
t$perf-norm:sp_supplier_order 5422 5422 0 0.00 0 7933 43 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 625 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 650 12 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 980 19 0 0
t$perf-norm:srv_make_money_saldo 949 949 0 0.00 0 461 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 18 173 61 0 0
v_add_invoice_to_stock 10174 9840 334 3.28 0 5455 28 0 334
v_all_customers 37126 37126 0 0.00 0 82 4 0 0
v_all_suppliers 29744 29744 0 0.00 0 78 5 0 0
v_all_wares 40570 40570 0 0.00 0 73 4 0 0
v_cancel_adding_invoice 750 750 0 0.00 0 1330 18 0 0
v_cancel_client_order 3380 3380 0 0.00 0 2045 11 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 7875 19 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 1537 11 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 2158 15 0 0
v_cancel_supplier_invoice 794 766 28 3.52 0 413 25 0 28
v_cancel_supplier_order 752 752 0 0.00 0 405 12 0 0
v_cancel_write_off 1880 1880 0 0.00 0 1306 26 0 0
v_max_id_clo_ord 5422 5422 0 0.00 0 688 6 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 1729 13 0 0
v_max_id_ord_sup 5355 5355 0 0.00 0 5085 8 0 0
v_max_non_paid_invoice 1619 1619 0 0.00 0 232 8 0 0
v_max_non_paid_realizn 3459 3459 0 0.00 0 8446 12 0 0
v_min_id_clo_ord 5422 5422 0 0.00 0 3221 10 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 3475 21 0 0
v_min_id_ord_sup 5355 5355 0 0.00 0 5771 11 0 0
v_min_non_paid_invoice 5714 5714 0 0.00 0 8620 22 0 0
v_min_non_paid_realizn 6889 6889 0 0.00 0 6236 22 0 0
v_our_firm 10304 10304 0 0.00 0 82 5 0 0
v_reserve_write_off 19516 19516 0 0.00 0 7249 11 0 0
x_lock_depdocs_on_canc_invoice 10525 10525 0 0.00 0 22334 156 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 19927 473 0 0
x_make_qstorno_1000_1200 5422 5422 0 0.00 2 42405 1230 0 0
x_make_qstorno_1200_2000 5356 5356 0 0.00 5 86775 2993 0 0
x_make_qstorno_2100_3300 459949 459949 0 0.00 0 41757 242 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-07-2022 07:38:59 to 18-07-2022 07:39:03.

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:19 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:20 25915 8192000000 9725398304 10023272448 802506832 8.922 100 91 181 0 832418336 7623104 22288400
05:10:03 16253 8192000000 9769657744 10081669120 842061792 9.320 100 84 166 1 874894800 12724288 20108720
05:11:24 15849 8192000000 9814818864 10135199744 882514400 9.725 100 87 174 0 921687872 19098368 20075104
05:12:53 10375 8192000000 9887331216 10219487232 930436448 10.199 100 90 181 0 996941136 46924784 19579904
05:14:05 4392 8192000000 9932414544 10275196928 963315488 10.521 100 91 180 0 1046891760 64235216 19341056
05:15:14 2285 8192000000 9956777392 10309984256 986620528 10.749 100 93 178 1 1080240864 74447440 19172896
05:16:19 1400 8192000000 9995311920 10360066048 1015884768 11.032 100 94 183 0 1112097600 78259904 17952928
05:17:23 1342 8192000000 10017711824 10388840448 1042449184 11.288 100 96 191 0 1136766848 74746528 19571136
05:18:27 972 8192000000 10011766896 10382581760 1054712752 11.406 100 96 178 1 1129344288 56841808 17789728
05:19:29 1169 8192000000 10050778096 10428383232 1068550992 11.538 100 97 191 1 1168106752 80207104 19348656
05:20:44 1656 8192000000 10049016608 10429923328 1068492720 11.538 100 78 148 3 1169149904 84891872 15765312
05:21:47 1691 8192000000 10040336288 10417381376 1070115744 11.553 100 96 177 3 1152403408 64935488 17352176
05:22:50 1159 8192000000 10039794752 10424287232 1075657776 11.606 100 96 185 1 1162347360 67647904 19041680
05:23:54 882 8192000000 10037314480 10428694528 1080466448 11.652 100 83 154 0 1163920528 66871088 16582992
05:24:56 1388 8192000000 10064390208 10448285696 1088544416 11.729 100 94 186 0 1185425376 76944640 19936320
05:26:00 1064 8192000000 10040191328 10420953088 1081558240 11.662 100 93 180 1 1160979072 60547200 18873632
05:27:03 1118 8192000000 10073826832 10462572544 1093441024 11.775 100 95 184 0 1195978016 83819200 18717792
05:28:05 1047 8192000000 10044507552 10432618496 1082536048 11.672 100 95 178 1 1168312576 66094624 19681904
05:29:08 2163 8192000000 10030767808 10413015040 1080058736 11.648 100 75 143 1 1153484112 59296464 14128912
05:30:11 821 8192000000 10008490752 10394259456 1052046672 11.380 100 80 143 1 1131967312 64535616 15385024
05:31:13 922 8192000000 10033259200 10418503680 1074579520 11.596 100 91 164 1 1154438608 61317168 18541920
05:32:15 1376 8192000000 9984961632 10367688704 1056107936 11.419 99 78 141 3 1107467232 35866080 15493216
05:33:18 2869 8192000000 10013202720 10402283520 1054973136 11.408 100 61 117 2 1128964208 62237968 11753104
05:34:22 1175 8192000000 9953073872 10325176320 1021472416 11.086 99 81 149 2 1075029904 37530000 16027488
05:35:27 6685 8192000000 9991240256 10373443584 1048509776 11.346 100 66 131 0 1109905616 48562592 12833248
05:36:35 1025 8192000000 9979231824 10351435776 1031082464 11.179 100 89 154 5 1098519504 50756784 16680256
05:37:40 8700 8192000000 9988682176 10363969536 1040833968 11.273 100 76 138 4 1116796016 61570560 14391488
05:38:55 8773 8192000000 10020552240 10410749952 1061408816 11.470 100 67 130 2 1138147856 63298464 13440576
05:40:13 3042 8192000000 10047942320 10435325952 1075386976 11.603 100 78 150 1 1176167984 83844896 16936112
05:41:21 11909 8192000000 10065142768 10455576576 1092078656 11.762 100 64 126 0 1182148240 77599184 12470400
05:42:36 4660 8192000000 10079051872 10468028416 1094149248 11.782 100 66 129 0 1191652784 84526608 12976928
05:43:49 3789 8192000000 10132889136 10529169408 1116634800 11.995 100 77 146 1 1243237648 111454000 15148848
05:44:58 1012 8192000000 10166765248 10564702208 1129359680 12.115 100 93 178 1 1279605616 130918976 19326960
05:46:03 974 8192000000 10203360208 10595446784 1136744208 12.185 100 99 187 1 1322805072 165786672 20274192
05:47:09 1801 8192000000 10249722480 10649210880 1139249984 12.208 99 90 174 2 1368866640 210907152 18709504
05:48:16 1973 8192000000 10269904000 10665385984 1158523024 12.389 100 90 168 3 1382639920 205660144 18456752
05:49:20 1178 8192000000 10301640880 10699304960 1167389648 12.472 100 94 176 1 1408674640 221969296 19315696
05:50:25 2443 8192000000 10328313712 10737397760 1178978112 12.581 100 86 166 2 1439936944 243368288 17590544
05:51:33 903 8192000000 10351406016 10769575936 1179304176 12.584 99 89 174 1 1461180304 261982320 19893808
05:52:37 876 8192000000 10366390336 10777829376 1186906640 12.655 100 97 194 0 1474004624 264087504 23010480
05:53:41 1740 8192000000 10386154096 10799210496 1175944832 12.552 99 91 173 1 1498388144 302423488 20019824
05:54:50 1024 8192000000 10427887120 10844012544 1191168912 12.694 100 97 192 1 1538005856 324416160 22420784
05:55:57 2397 8192000000 10449968928 10859937792 1197143952 12.750 100 86 156 1 1558588176 342967200 18477024
05:57:04 1596 8192000000 10474055264 10897768448 1193611456 12.717 99 96 188 3 1583243136 368034768 21596912
05:58:07 1496 8192000000 10505373264 10923147264 1198347312 12.761 100 93 179 1 1609276240 388732032 22196896
05:59:12 1129 8192000000 10505764544 10919419904 1180886160 12.598 100 100 193 1 1613812000 410749856 22175984
06:00:16 746 8192000000 10556076432 10978504704 1179689088 12.587 100 99 191 1 1661293824 458465600 23139136
06:01:20 971 8192000000 10598129392 11007111168 1195111488 12.731 100 94 182 2 1704691536 486742816 22837232
06:02:26 707 8192000000 10615409712 11050311680 1181563520 12.605 100 99 200 1 1718411056 511197568 25649968
06:03:30 984 8192000000 10580465504 11035492352 1182236368 12.611 100 96 191 0 1689550624 483766016 23548240
06:04:37 3316 8192000000 10606491344 11051859968 1177042752 12.563 100 64 128 0 1713233184 520188368 16002064
06:05:45 8127 8192000000 10570803968 11054379008 1180032688 12.591 100 56 112 0 1672411072 477542464 14835920
06:06:57 6896 8192000000 10505639616 11010183168 1167367648 12.472 100 45 86 2 1616767248 438017104 11382496
06:08:18 11772 8192000000 10480416080 10927157248 1169762880 12.495 100 45 88 1 1582998928 402512496 10723552
06:09:50 18958 8192000000 10361337536 10868850688 1126794416 12.091 98 30 60 0 1489336048 355058720 7482912
06:11:16 42420 8192000000 10149358976 10649735168 1136607744 12.184 99 17 32 0 1274460096 134110384 3741968
06:13:00 40431 8192000000 10124621520 10556973056 1148197648 12.293 100 28 54 0 1212378080 57501504 6678928
06:14:47 11352 8192000000 10127855168 10570919936 1147328368 12.284 100 63 123 2 1220543616 60235504 12979744
06:15:59 116463 8192000000 10115418368 10585513984 1126517776 12.089 98 23 46 1 1232428240 101270080 4640384
06:18:57 146805 8192000000 10095113040 10558865408 1122235776 12.048 98 24 48 0 1172959232 45305904 5417552
06:22:39 8049 8192000000 10140110160 10571513856 1152219808 12.330 100 57 112 0 1230307520 66033152 12054560
06:23:56 28003 8192000000 10162604304 10604056576 1135268992 12.171 98 44 79 0 1243727008 99418896 9039120
06:25:26 30103 8192000000 10205589776 10667245568 1160054384 12.404 100 30 60 1 1302474896 135850096 6570416
06:27:04 48182 8192000000 10167939664 10667114496 1160769008 12.410 100 25 50 1 1296434992 129993696 5672288
06:29:04 34079 8192000000 10219218864 10682376192 1157422768 12.379 100 21 43 1 1324943312 162590096 4930448
06:30:43 40453 8192000000 10209064960 10709229568 1159602304 12.400 100 12 24 0 1345433392 182817456 3013632
06:32:26 62095 8192000000 10058911632 10500743168 1103475600 11.871 96 9 18 0 1145186720 39840032 1871088
06:34:45 16746 8192000000 10113445520 10549911552 1143375552 12.247 99 52 95 1 1201664960 48824816 9464592
06:36:03 44992 8192000000 10111342464 10564427776 1135813264 12.176 99 27 52 0 1210307984 68923856 5570864
06:37:50 77960 8192000000 10076892416 10516115456 1105073616 11.886 96 23 45 0 1161532400 52077808 4380976
06:40:08 21517 8192000000 10126441728 10580860928 1136885392 12.186 99 47 94 0 1228645008 82359584 9400032
06:41:40 89767 8192000000 10070809536 10526556160 1106063360 11.895 97 17 31 0 1147886160 38692736 3130064
06:44:10 11846 8192000000 10108156208 10539692032 1118060240 12.009 98 47 94 1 1181532288 53695712 9776336
06:45:24 97063 8192000000 10103652688 10547679232 1124293504 12.068 99 41 82 0 1215452224 82092848 9065872
06:48:24 64863 8192000000 10053733360 10509238272 1108190544 11.915 98 28 56 0 1141008832 27225040 5593248
06:50:30 33523 8192000000 10115874608 10561335296 1124939952 12.074 99 35 70 0 1214650992 82777168 6933872
06:52:04 62517 8192000000 10132469296 10566033408 1092388560 11.765 96 19 36 0 1191747216 95404736 3953920
06:54:08 42673 8192000000 10170499648 10620059648 1128010480 12.103 100 23 46 0 1265592368 132047936 5533952
06:55:51 72637 8192000000 10131248304 10635841536 1111982928 11.951 98 18 36 0 1230663872 114461648 4219296
06:58:08 29027 8192000000 10073193808 10514493440 1120338304 12.030 99 21 42 0 1203031232 78037216 4655712
06:59:44 42667 8192000000 10151543776 10591588352 1140806432 12.223 100 25 50 1 1246700672 100869824 5024416
07:01:29 48509 8192000000 10147675792 10581536768 1118963200 12.017 99 36 70 1 1227791648 101284112 7544336
07:03:19 44031 8192000000 10167373344 10628558848 1109659376 11.929 98 31 62 1 1219778672 103387056 6732240
07:05:07 88600 8192000000 10098336912 10581422080 1128479536 12.107 100 23 46 0 1233446512 100245104 4721872
07:07:37 49682 8192000000 10098208608 10525151232 1103770672 11.873 97 37 75 0 1154712160 43931408 7010080
07:09:28 101346 8192000000 10071862544 10532782080 1116054512 11.990 98 28 51 0 1196625120 75716192 4854416
07:12:10 76979 8192000000 10062416736 10524753920 1103191056 11.868 97 18 34 1 1156121952 49444768 3486128
07:14:27 40397 8192000000 10056489984 10525487104 1118527312 12.013 98 12 23 0 1154963424 34073136 2362976
07:16:20 102444 8192000000 10139464816 10603405312 1147970496 12.290 100 22 44 0 1238431504 85913200 4547808
07:19:02 92696 8192000000 10140485872 10618261504 1124777312 12.072 99 16 28 0 1225802352 97347216 3677824
07:21:37 22341 8192000000 10086383520 10530390016 1144240816 12.255 100 34 66 1 1189114480 37480192 7393472
07:23:00 31652 8192000000 10105978128 10572328960 1109812688 11.931 97 34 68 0 1186337680 69637616 6887376
07:24:32 33239 8192000000 10132377952 10584629248 1130399136 12.125 99 40 80 0 1204088240 65232048 8457056
07:26:08 37944 8192000000 10140840496 10595811328 1131969856 12.140 99 35 70 0 1218558976 79705776 6883344
07:27:50 46177 8192000000 10073391488 10509279232 1078399120 11.632 95 30 60 0 1130237104 46212224 5625760
07:29:39 30408 8192000000 10107246624 10562514944 1107789312 11.911 99 35 70 0 1184215376 69535536 6890528
07:31:11 81370 8192000000 10051196224 10509979648 1095052304 11.791 98 20 40 0 1155405920 55906896 4446720
07:33:33 62959 8192000000 10054518624 10539573248 1112191760 11.953 100 6 12 0 1161401248 48406192 803296
07:35:37 74169 8192000000 10095821248 10564104192 1117745888 12.006 99 18 36 0 1175266832 53653776 3867168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 18-07-2022 07:39:05 to 18-07-2022 07:39:21.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-07-2022 07:39:22 to 18-07-2022 07:39:24.

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          215386
MON$OLDEST_ACTIVE               217671
MON$OLDEST_SNAPSHOT             217671
MON$NEXT_TRANSACTION            217671
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                       2054304
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2799 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2799 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2799 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2799 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 ---
:86: 
:87: mscs-test-161.budzdorov.ru    Mon Jul 18 07:37:27 2022
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:89: 
--- end of diff output ---

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

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