Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.01.2022 13:37
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 16397324 kB
MemAvailable 31306444 kB
Buffers 0 kB
Cached 15251556 kB
SwapCached 1204 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.1
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-01-02 13:37:12
DTS_END                         2022-01-02 13:37:12.0960
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/48228
TRN_ID                          234784
ATT_ID                          650
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: 9233.35 23256 1108002
customer order: creation 169.44 407 20333
customer order: refuse 15.33 297 1839
order to supplier: creation 44.88 1503 5385
order to supplier: removal 3.45 103748 414
invoice (draft): creation 46.34 3535 5561
invoice (draft): removal 53.34 893 6401
invoice accept: apply 41.88 19195 5025
invoice accept: cancel 47.58 800 5709
customer reserve: creation 3848.57 192 461828
customer reserve: removal 4671.76 60 560611
realization accept: apply 81.58 156 9789
realization accept: cancel 82.71 117 9925
payment from customer: creation 57.59 104 6911
payment from customer: removal 5.49 91 659
payment to supplier: creation 49.30 107 5916
payment to supplier: removal 3.33 81 399
service: total inventory turnovers 3.71 3432 445
service: total monetary turnovers 6.19 501 743
service: aggregate perf. data 0.88 17793 105
service: refresh index statistics 0.03 312098 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-01-2022 13:37:48 to 02-01-2022 13:37:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5221
TEST_TIME 2 5900
TEST_TIME 3 6585
TEST_TIME 4 7046
TEST_TIME 5 7081
TEST_TIME 6 6529
TEST_TIME 7 6404
TEST_TIME 8 6905
TEST_TIME 9 7009
TEST_TIME 10 7561
TEST_TIME 11 7451
TEST_TIME 12 7374
TEST_TIME 13 7352
TEST_TIME 14 7651
TEST_TIME 15 7703
TEST_TIME 16 8402
TEST_TIME 17 8479
TEST_TIME 18 8709
TEST_TIME 19 8797
TEST_TIME 20 9673
TEST_TIME 21 9582
TEST_TIME 22 9608
TEST_TIME 23 9566
TEST_TIME 24 9596
TEST_TIME 25 9804
TEST_TIME 26 10536
TEST_TIME 27 10894
TEST_TIME 28 10828
TEST_TIME 29 10764
TEST_TIME 30 10633
TEST_TIME 31 10364
TEST_TIME 32 10279
TEST_TIME 33 10191
TEST_TIME 34 9970
TEST_TIME 35 9955
TEST_TIME 36 9868
TEST_TIME 37 9808
TEST_TIME 38 9759
TEST_TIME 39 9610
TEST_TIME 40 9556
TEST_TIME 41 9788
TEST_TIME 42 9745
TEST_TIME 43 9904
TEST_TIME 44 9902
TEST_TIME 45 9996
TEST_TIME 46 10066
TEST_TIME 47 10057
TEST_TIME 48 10086
TEST_TIME 49 9962
TEST_TIME 50 9926
TEST_TIME 51 10232
TEST_TIME 52 10202
TEST_TIME 53 10148
TEST_TIME 54 9992
TEST_TIME 55 9907
TEST_TIME 56 9834
TEST_TIME 57 9829
TEST_TIME 58 9790
TEST_TIME 59 9709
TEST_TIME 60 9623
TEST_TIME 61 9580
TEST_TIME 62 9544
TEST_TIME 63 9559
TEST_TIME 64 9512
TEST_TIME 65 9496
TEST_TIME 66 9551
TEST_TIME 67 9477
TEST_TIME 68 9646
TEST_TIME 69 9710
TEST_TIME 70 9683
TEST_TIME 71 9708
TEST_TIME 72 9706
TEST_TIME 73 9709
TEST_TIME 74 9714
TEST_TIME 75 9728
TEST_TIME 76 9780
TEST_TIME 77 9664
TEST_TIME 78 9627
TEST_TIME 79 9562
TEST_TIME 80 9561
TEST_TIME 81 9476
TEST_TIME 82 9469
TEST_TIME 83 9406
TEST_TIME 84 9345
TEST_TIME 85 9323
TEST_TIME 86 9424
TEST_TIME 87 9372
TEST_TIME 88 9358
TEST_TIME 89 9340
TEST_TIME 90 9327
TEST_TIME 91 9336
TEST_TIME 92 9426
TEST_TIME 93 9398
TEST_TIME 94 9366
TEST_TIME 95 9362
TEST_TIME 96 9529
TEST_TIME 97 9493
TEST_TIME 98 9460
TEST_TIME 99 9471
TEST_TIME 100 9481
TEST_TIME 101 9395
TEST_TIME 102 9363
TEST_TIME 103 9327
TEST_TIME 104 9312
TEST_TIME 105 9271
TEST_TIME 106 9261
TEST_TIME 107 9243
TEST_TIME 108 9186
TEST_TIME 109 9163
TEST_TIME 110 9195
TEST_TIME 111 9191
TEST_TIME 112 9180
TEST_TIME 113 9218
TEST_TIME 114 9237
TEST_TIME 115 9227
TEST_TIME 116 9222
TEST_TIME 117 9219
TEST_TIME 118 9278
TEST_TIME 119 9309
TEST_TIME 120 9234

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-01-2022 13:37:51 to 02-01-2022 13:37:55.

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 1535437 1535437 0 0.00 0 24972 7 0 0
doc_list_biud 1535437 1535437 0 0.00 0 30569 28 0 0
sp_add_invoice_to_stock 5154 5025 129 2.50 14 197505 19196 0 129
sp_cancel_adding_invoice 5709 5709 0 0.00 4 66527 801 0 0
sp_cancel_client_order 1839 1839 0 0.00 4 8300 298 0 0
sp_cancel_customer_reserve 560611 560611 0 0.00 1 19084 61 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 5765 92 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 4513 81 0 0
sp_cancel_supplier_invoice 6413 6401 12 0.18 1 25997 894 0 12
sp_cancel_supplier_order 414 414 0 0.00 6 885762 103748 0 0
sp_cancel_write_off 9925 9925 0 0.00 1 12615 118 0 0
sp_client_order 20333 20333 0 0.00 4 34750 407 0 0
sp_customer_reserve 461828 461828 0 0.00 0 113776 193 0 0
sp_fill_shopping_cart 31266 31266 0 0.00 1 19252 42 0 0
sp_fill_shopping_cart_clo_res 461826 461826 0 0.00 0 16693 15 0 0
sp_get_clo_for_invoice 5023 5023 0 0.00 0 18896 307 0 0
sp_kill_cost_storno 16692 16692 0 0.00 0 8079 51 0 0
sp_kill_qstorno_ret_qs2qd 1137962 1137962 0 0.00 0 25992 33 0 0
sp_kill_qty_storno 599710 599710 0 0.00 0 30568 71 0 0
sp_lock_selected_doc 20631 20631 0 0.00 0 7916 5 0 0
sp_make_cost_storno 27638 27638 0 0.00 0 14614 50 0 0
sp_multiply_rows_for_pdistr 27638 27638 0 0.00 0 6053 24 0 0
sp_multiply_rows_for_qdistr 20333 20333 0 0.00 0 11293 212 0 0
sp_pay_from_customer 6911 6911 0 0.00 4 16224 105 0 0
sp_pay_to_supplier 5916 5916 0 0.00 5 12936 108 0 0
sp_payment_common 12827 12827 0 0.00 1 13309 20 0 0
sp_qd_handle_on_cancel_clo 1839 1839 0 0.00 0 8284 274 0 0
sp_qd_handle_on_invoice_upd_sts 10731 10731 0 0.00 0 30568 315 0 0
sp_qd_handle_on_reserve_upd_sts 9789 9789 0 0.00 0 13697 52 0 0
sp_reserve_write_off 9789 9789 0 0.00 6 14785 157 0 0
sp_supplier_invoice 5561 5561 0 0.00 6 85686 3535 0 0
sp_supplier_order 5385 5385 0 0.00 6 34657 1503 0 0
srv_aggregate_perf_data 105 105 0 0.00 2678 179008 17793 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1796 124983 48551 0 0
srv_find_qd_qs_mism 1060638 1060638 0 0.00 0 24971 6 0 0
srv_make_invnt_saldo 445 445 0 0.00 53 77104 3432 0 0
srv_make_money_saldo 743 743 0 0.00 1 17304 501 0 0
srv_recalc_idx_stat 4 4 0 0.00 179158 410400 312099 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5025 5025 0 0.00 0 11341 139 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 0 4578 99 0 0
t$perf-norm:sp_cancel_client_order 1839 1839 0 0.00 0 6865 16 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 4247 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 3459 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 471 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 1747 13 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 18088 1351 0 0
t$perf-norm:sp_cancel_write_off 1038 1038 0 0.00 0 2116 7 0 0
t$perf-norm:sp_client_order 20333 20333 0 0.00 0 7948 15 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 4258 12 0 0
t$perf-norm:sp_pay_from_customer 6911 6911 0 0.00 0 9715 9 0 0
t$perf-norm:sp_pay_to_supplier 5916 5916 0 0.00 0 2195 6 0 0
t$perf-norm:sp_reserve_write_off 9789 9789 0 0.00 0 7164 10 0 0
t$perf-norm:sp_supplier_invoice 5561 5561 0 0.00 0 7172 14 0 0
t$perf-norm:sp_supplier_order 5385 5385 0 0.00 0 8924 16 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 74 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 405 8 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 2556 15 0 0
t$perf-norm:srv_make_money_saldo 743 743 0 0.00 0 472 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10302 10044 258 2.50 0 7738 16 0 258
v_all_customers 37516 37516 0 0.00 0 19 1 0 0
v_all_suppliers 30460 30460 0 0.00 0 20 1 0 0
v_all_wares 40664 40664 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 3953 22 0 0
v_cancel_client_order 3678 3678 0 0.00 0 2728 4 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 3945 10 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 3349 7 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 2190 8 0 0
v_cancel_supplier_invoice 858 834 24 2.79 0 525 12 0 24
v_cancel_supplier_order 828 828 0 0.00 0 339 3 0 0
v_cancel_write_off 2076 2076 0 0.00 0 2524 17 0 0
v_max_id_clo_ord 5385 5385 0 0.00 0 4001 2 0 0
v_max_id_clo_res 967 967 0 0.00 0 1373 9 0 0
v_max_id_ord_sup 5549 5549 0 0.00 0 6707 8 0 0
v_max_non_paid_invoice 1620 1620 0 0.00 0 2422 4 0 0
v_max_non_paid_realizn 3556 3556 0 0.00 0 3453 5 0 0
v_min_id_clo_ord 5385 5385 0 0.00 0 3454 7 0 0
v_min_id_clo_res 967 967 0 0.00 0 10772 20 0 0
v_min_id_ord_sup 5549 5549 0 0.00 0 9936 13 0 0
v_min_non_paid_invoice 5916 5916 0 0.00 0 5285 12 0 0
v_min_non_paid_realizn 6911 6911 0 0.00 0 10715 14 0 0
v_our_firm 9858 9858 0 0.00 0 14 1 0 0
v_reserve_write_off 19578 19578 0 0.00 0 3948 6 0 0
x_lock_depdocs_on_canc_invoice 11693 11693 0 0.00 0 11809 108 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 6194 299 0 0
x_make_qstorno_1000_1200 5385 5385 0 0.00 2 34649 1426 0 0
x_make_qstorno_1200_2000 5561 5561 0 0.00 3 68996 3375 0 0
x_make_qstorno_2100_3300 461828 461828 0 0.00 0 113775 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-01-2022 13:37:56 to 02-01-2022 13:38:00.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:15 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:16 25640 8192000000 9720490320 10017054720 792079872 8.816 100 82 164 0 819868384 7533824 20254688
11:09:48 11466 8192000000 9763673664 10070614016 845590208 9.356 100 86 172 0 877882528 12054336 20237984
11:11:07 21982 8192000000 9811025984 10127081472 869526656 9.595 100 85 168 1 908059776 19329504 19203616
11:12:32 14817 8192000000 9857107696 10188214272 913825856 10.035 100 83 166 0 967344688 35649376 17869456
11:13:52 8992 8192000000 9930647504 10273652736 957034848 10.460 100 85 170 1 1032997376 57891280 18071248
11:15:03 5598 8192000000 9964959792 10320142336 997170560 10.851 100 80 159 1 1075251152 62084656 15995936
11:16:14 2435 8192000000 10011076800 10378162176 1023949952 11.110 100 81 158 0 1129389408 89813216 15626240
11:17:18 2213 8192000000 10012015328 10380713984 1045475808 11.317 100 84 167 2 1124412256 60392560 18543888
11:18:22 1839 8192000000 10019951584 10389635072 1064740912 11.502 100 91 182 2 1131879024 48092144 19045968
11:19:26 10053 8192000000 10020807776 10402676736 1070173120 11.554 100 76 150 1 1133006352 46313264 16519968
11:20:37 6307 8192000000 9994172672 10392178688 1079611808 11.644 100 46 94 0 1112471088 22966784 9892496
11:21:47 5540 8192000000 9992402816 10395320320 1088387168 11.727 100 38 76 2 1119639440 23177584 8074688
11:22:58 31798 8192000000 10019939536 10421092352 1092556304 11.767 100 52 105 1 1123358352 19957216 10844832
11:24:30 4829 8192000000 10016279840 10415108096 1097044176 11.810 100 48 96 1 1133816480 26688944 10083360
11:25:36 10217 8192000000 10006574144 10414288896 1091981360 11.761 99 21 42 1 1116699504 20629056 4089088
11:26:48 7265 8192000000 10010411600 10420666368 1091978016 11.761 99 16 29 1 1124571984 29309712 3284256
11:27:58 26063 8192000000 9994594000 10398838784 1040280096 11.267 95 44 87 1 1058059216 7411632 10367488
11:29:26 3681 8192000000 9999445856 10386673664 1047700064 11.339 98 56 110 2 1091894512 31508656 12685792
11:30:30 6153 8192000000 9958332976 10336862208 1033634608 11.203 98 36 69 2 1065081600 23520080 7926912
11:31:38 6943 8192000000 9951091296 10339250176 1019252768 11.065 100 38 75 0 1052256704 24635824 8368112
11:32:45 31123 8192000000 9923933120 10320740352 995434800 10.834 96 28 57 0 1018791760 17371664 5985296
11:34:20 7869 8192000000 9959377424 10344165376 1033095728 11.198 99 56 114 4 1057381952 11712960 12573264
11:35:30 7264 8192000000 9983852576 10382229504 1062213120 11.478 100 29 58 0 1090967360 22404016 6350224
11:36:38 6394 8192000000 9982209120 10387877888 1069382640 11.546 100 26 50 1 1087370640 12664672 5323328
11:37:48 46591 8192000000 10025249360 10421030912 1093529008 11.776 100 46 92 1 1128983360 26246880 9207472
11:39:38 2602 8192000000 10102226720 10494234624 1112015328 11.951 100 64 128 0 1199034320 74343840 12675152
11:40:47 20861 8192000000 10121413056 10518269952 1124415552 12.069 100 67 134 0 1222676432 84824224 13436656
11:42:10 11283 8192000000 10111458880 10513346560 1135536336 12.174 100 42 84 0 1215676640 70700928 9439376
11:43:24 17503 8192000000 10163606544 10565873664 1152086096 12.329 100 62 124 2 1271576928 105890336 13600496
11:44:46 4161 8192000000 10209132256 10613456896 1155707312 12.363 100 62 122 1 1317222480 147883360 13631808
11:46:04 13531 8192000000 10220461872 10637881344 1162226000 12.424 100 51 102 0 1326929312 153612288 11091024
11:47:32 7258 8192000000 10219494160 10646388736 1172511888 12.520 100 55 110 0 1321298432 136416224 12370320
11:48:44 4839 8192000000 10246796144 10661924864 1180989104 12.599 100 61 122 0 1355176720 160543328 13644288
11:49:52 5754 8192000000 10260308368 10669060096 1164676928 12.447 99 71 138 1 1358089728 177515552 15897248
11:51:00 4883 8192000000 10298180912 10722852864 1163822320 12.439 100 67 135 1 1411526576 232423632 15280624
11:52:15 3117 8192000000 10308629552 10707394560 1147751200 12.288 99 80 158 2 1404778096 239559840 17467056
11:53:26 2556 8192000000 10338101264 10744680448 1147237360 12.284 100 68 137 0 1433749424 270294448 16217616
11:54:36 3722 8192000000 10312587152 10725126144 1156797504 12.373 100 61 123 1 1421584224 250921984 13864736
11:55:48 6525 8192000000 10328914544 10756870144 1144596608 12.259 100 67 133 1 1427680240 267657344 15426288
11:56:57 1792 8192000000 10314297552 10731167744 1137336288 12.190 99 87 172 1 1398195248 241059728 19799232
11:58:02 33077 8192000000 10248751152 10666631168 1112943904 11.960 97 37 74 1 1316277216 194386560 8946752
11:59:38 18089 8192000000 10240630848 10676060160 1121806368 12.044 100 24 49 0 1349312928 221607824 5898736
12:00:58 22039 8192000000 10270797520 10704584704 1099208912 11.830 98 22 44 0 1354321664 249445696 5667056
12:02:42 41113 8192000000 10216605888 10669576192 1116473744 11.994 100 22 44 1 1376266656 254060096 5732816
12:04:29 76709 8192000000 10061703488 10492862464 1095494752 11.795 98 21 42 1 1136566688 36984992 4086944
12:06:47 76687 8192000000 10062523824 10487291904 1072922208 11.580 95 24 48 1 1112564512 34409504 5232800
12:09:05 42522 8192000000 10058196560 10482335744 1107963392 11.913 99 20 40 1 1147590768 35120416 4506960
12:10:49 105023 8192000000 10111723264 10548326400 1083665632 11.682 95 39 78 0 1152119056 59710016 8743408
12:14:00 95722 8192000000 10176663600 10643517440 1143689248 12.250 100 30 60 1 1280622160 130056656 6876256
12:17:22 77569 8192000000 10132120640 10669084672 1140257488 12.218 100 19 38 0 1286689104 141714976 4716640
12:19:41 65591 8192000000 10113669760 10549825536 1112850784 11.959 97 26 52 0 1184379248 65276256 6252208
12:22:01 56826 8192000000 10108195488 10582216704 1135659280 12.175 99 26 52 2 1227758016 86175056 5923680
12:23:58 49419 8192000000 10109368288 10551668736 1148874112 12.299 100 42 83 1 1204890480 47889888 8126480
12:25:49 53918 8192000000 10095530576 10523693056 1129659936 12.118 98 23 47 0 1183610048 48849696 5100416
12:27:43 55565 8192000000 10144451728 10650509312 1155914096 12.365 100 11 22 0 1231078704 72675664 2488944
12:29:56 69190 8192000000 10096698880 10553311232 1153364032 12.341 100 19 38 0 1183083104 25866128 3852944
12:32:54 53440 8192000000 10145798224 10617151488 1163387184 12.435 100 29 57 0 1234054432 65071088 5596160
12:35:29 59342 8192000000 10088504176 10546458624 1129421264 12.116 98 12 25 0 1175668528 43927744 2319520
12:37:29 61720 8192000000 10105382832 10564247552 1124711952 12.071 99 13 26 0 1207910976 80671472 2527552
12:40:09 71965 8192000000 10085296512 10535772160 1127770288 12.100 99 12 24 0 1177757488 47598432 2388768
12:42:41 52150 8192000000 10118512320 10577993728 1112029776 11.952 98 21 42 1 1220752512 104169232 4553504
12:44:33 90664 8192000000 10112040800 10583212032 1104533328 11.881 97 18 36 0 1263597312 154695040 4368944
12:47:08 64231 8192000000 10076938256 10503467008 1108953440 11.923 98 26 52 0 1171503264 57471984 5077840
12:49:13 25332 8192000000 10089145552 10516410368 1130923264 12.130 100 46 92 0 1187898688 47802000 9173424
12:50:40 36142 8192000000 10059794704 10497781760 1100316656 11.841 99 33 66 1 1147144240 40471472 6356112
12:52:17 107179 8192000000 10067814064 10573828096 1111051792 11.942 99 21 42 1 1155350848 39818288 4480768
12:55:05 68473 8192000000 10031000192 10476879872 1098705168 11.825 98 24 48 1 1141647088 38218704 4723216
12:57:18 59549 8192000000 10040473360 10499448832 1097421680 11.813 98 15 29 1 1166349232 65844880 3082672
12:59:18 37441 8192000000 10072285680 10509414400 1092119744 11.763 98 16 32 1 1147085648 51672240 3293664
13:01:00 54433 8192000000 10093175824 10539778048 1098134256 11.820 99 18 36 0 1181281488 79445744 3701488
13:02:55 124983 8192000000 10034159120 10509172736 1055712080 11.415 95 10 20 0 1141323424 83585696 2025648
13:06:00 49191 8192000000 10050362368 10493206528 1092429856 11.766 98 25 50 0 1129546224 31569376 5546992
13:07:54 49826 8192000000 10116312912 10574835712 1139623856 12.212 100 29 58 1 1201133248 55768016 5741376
13:09:49 90957 8192000000 10073513104 10530459648 1084769072 11.693 95 16 32 1 1130264640 42595264 2900304
13:12:20 65720 8192000000 10187135472 10654154752 1150025072 12.310 100 13 26 1 1250674864 97796864 2852928
13:14:27 61533 8192000000 10098393696 10528415744 1146564768 12.277 100 29 57 0 1173331600 20968080 5798752
13:16:36 91134 8192000000 10100681616 10588655616 1143236192 12.246 99 18 36 1 1204043296 57463168 3343936
13:20:00 36757 8192000000 10094920224 10533982208 1146190160 12.274 100 29 58 1 1194699712 42902160 5607392
13:21:38 70157 8192000000 10111185280 10588311552 1162665456 12.428 100 12 24 1 1232173696 66677440 2830800
13:24:00 62816 8192000000 10091811616 10555826176 1126541696 12.089 97 12 24 1 1164923232 35750832 2630704
13:26:04 59888 8192000000 10099270032 10586079232 1114575552 11.976 97 9 18 0 1198208848 81502352 2130944
13:28:53 59183 8192000000 10117076720 10567999488 1101578944 11.853 96 19 38 0 1183121232 77368288 4174000
13:30:54 66021 8192000000 10081082528 10579431424 1098312624 11.822 97 16 32 0 1162921936 61540512 3068800
13:33:24 50384 8192000000 10106718288 10534227968 1110895504 11.941 98 40 80 0 1165933152 47017568 8020080
13:35:17 84932 8192000000 10074842000 10572328960 1110236512 11.935 98 20 40 0 1182588528 68338928 4013088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 02-01-2022 13:38:02 to 02-01-2022 13:38:16.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-01-2022 13:38:17 to 02-01-2022 13:38:19.

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          234304
MON$OLDEST_ACTIVE               235147
MON$OLDEST_SNAPSHOT             235147
MON$NEXT_TRANSACTION            235147
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                       2043808
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             797
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2700 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2700 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2700 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2700 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'

02.01.22 13:38:20. 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-01-02 13:38:23 No severe PSQL-related problems occured
02.01.22 13:38:24. End of report.