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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16453116 kB
MemAvailable 31122624 kB
Buffers 0 kB
Cached 15251680 kB
SwapCached 2544 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-06 22:38:22
DTS_END                         2022-07-06 22:38:22.8980
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52974
TRN_ID                          237467
ATT_ID                          713
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: 8932.53 25086 1071904
customer order: creation 172.45 375 20694
customer order: refuse 14.73 323 1768
order to supplier: creation 45.63 1262 5475
order to supplier: removal 3.55 84207 426
invoice (draft): creation 47.33 3450 5680
invoice (draft): removal 49.61 708 5953
invoice accept: apply 41.28 18389 4954
invoice accept: cancel 45.01 722 5401
customer reserve: creation 3847.18 182 461662
customer reserve: removal 4379.93 54 525592
realization accept: apply 83.17 166 9980
realization accept: cancel 74.13 112 8895
payment from customer: creation 59.15 108 7098
payment from customer: removal 5.69 70 683
payment to supplier: creation 48.07 119 5768
payment to supplier: removal 3.60 61 432
service: total inventory turnovers 4.84 1013 581
service: total monetary turnovers 6.28 32 753
service: aggregate perf. data 0.88 15627 105
service: refresh index statistics 0.03 374743 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-07-2022 22:39:32 to 06-07-2022 22:39:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6956
TEST_TIME 2 5674
TEST_TIME 3 5675
TEST_TIME 4 5931
TEST_TIME 5 5679
TEST_TIME 6 6006
TEST_TIME 7 5710
TEST_TIME 8 5476
TEST_TIME 9 5555
TEST_TIME 10 6234
TEST_TIME 11 6365
TEST_TIME 12 6772
TEST_TIME 13 6733
TEST_TIME 14 7368
TEST_TIME 15 7342
TEST_TIME 16 7317
TEST_TIME 17 8309
TEST_TIME 18 8345
TEST_TIME 19 9010
TEST_TIME 20 9333
TEST_TIME 21 9748
TEST_TIME 22 10342
TEST_TIME 23 10644
TEST_TIME 24 10998
TEST_TIME 25 10636
TEST_TIME 26 10656
TEST_TIME 27 10719
TEST_TIME 28 10482
TEST_TIME 29 10401
TEST_TIME 30 10511
TEST_TIME 31 10325
TEST_TIME 32 10231
TEST_TIME 33 10033
TEST_TIME 34 10307
TEST_TIME 35 10060
TEST_TIME 36 10099
TEST_TIME 37 9899
TEST_TIME 38 9800
TEST_TIME 39 9720
TEST_TIME 40 9665
TEST_TIME 41 9888
TEST_TIME 42 9800
TEST_TIME 43 9816
TEST_TIME 44 9731
TEST_TIME 45 9766
TEST_TIME 46 9783
TEST_TIME 47 9809
TEST_TIME 48 9698
TEST_TIME 49 9680
TEST_TIME 50 9738
TEST_TIME 51 9747
TEST_TIME 52 9639
TEST_TIME 53 9736
TEST_TIME 54 9730
TEST_TIME 55 9921
TEST_TIME 56 9881
TEST_TIME 57 9742
TEST_TIME 58 9684
TEST_TIME 59 9641
TEST_TIME 60 9557
TEST_TIME 61 9523
TEST_TIME 62 9438
TEST_TIME 63 9605
TEST_TIME 64 9541
TEST_TIME 65 9512
TEST_TIME 66 9419
TEST_TIME 67 9395
TEST_TIME 68 9351
TEST_TIME 69 9344
TEST_TIME 70 9321
TEST_TIME 71 9439
TEST_TIME 72 9416
TEST_TIME 73 9363
TEST_TIME 74 9381
TEST_TIME 75 9382
TEST_TIME 76 9326
TEST_TIME 77 9363
TEST_TIME 78 9389
TEST_TIME 79 9364
TEST_TIME 80 9373
TEST_TIME 81 9313
TEST_TIME 82 9207
TEST_TIME 83 9295
TEST_TIME 84 9250
TEST_TIME 85 9218
TEST_TIME 86 9161
TEST_TIME 87 9161
TEST_TIME 88 9129
TEST_TIME 89 9086
TEST_TIME 90 9064
TEST_TIME 91 9012
TEST_TIME 92 8979
TEST_TIME 93 9009
TEST_TIME 94 9023
TEST_TIME 95 9030
TEST_TIME 96 9080
TEST_TIME 97 9076
TEST_TIME 98 9134
TEST_TIME 99 9103
TEST_TIME 100 9122
TEST_TIME 101 9141
TEST_TIME 102 9170
TEST_TIME 103 9174
TEST_TIME 104 9169
TEST_TIME 105 9199
TEST_TIME 106 9217
TEST_TIME 107 9164
TEST_TIME 108 9108
TEST_TIME 109 9077
TEST_TIME 110 9061
TEST_TIME 111 9018
TEST_TIME 112 8989
TEST_TIME 113 8977
TEST_TIME 114 8965
TEST_TIME 115 8939
TEST_TIME 116 8949
TEST_TIME 117 8933
TEST_TIME 118 8923
TEST_TIME 119 8915
TEST_TIME 120 8942

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-07-2022 22:39:36 to 06-07-2022 22:39:39.

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 1499817 1499817 0 0.00 0 21399 6 0 0
doc_list_biud 1499817 1499817 0 0.00 0 62035 24 0 0
sp_add_invoice_to_stock 5056 4954 102 2.01 26 220009 18389 0 102
sp_cancel_adding_invoice 5401 5401 0 0.00 4 45643 722 0 0
sp_cancel_client_order 1768 1768 0 0.00 4 16675 323 0 0
sp_cancel_customer_reserve 525592 525592 0 0.00 0 62039 55 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 2 3349 71 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 2 3419 61 0 0
sp_cancel_supplier_invoice 5967 5953 14 0.23 2 56678 709 0 14
sp_cancel_supplier_order 426 426 0 0.00 5 932479 84208 0 0
sp_cancel_write_off 8895 8895 0 0.00 1 18963 113 0 0
sp_client_order 20694 20694 0 0.00 3 38033 375 0 0
sp_customer_reserve 461662 461662 0 0.00 0 40443 183 0 0
sp_fill_shopping_cart 31838 31838 0 0.00 1 20866 41 0 0
sp_fill_shopping_cart_clo_res 461641 461641 0 0.00 0 12954 14 0 0
sp_get_clo_for_invoice 4933 4933 0 0.00 0 20908 360 0 0
sp_kill_cost_storno 15411 15411 0 0.00 0 15332 50 0 0
sp_kill_qstorno_ret_qs2qd 1066458 1066458 0 0.00 0 62020 30 0 0
sp_kill_qty_storno 562948 562948 0 0.00 0 62034 64 0 0
sp_lock_selected_doc 20719 20719 0 0.00 0 7150 5 0 0
sp_make_cost_storno 27779 27779 0 0.00 0 11881 50 0 0
sp_multiply_rows_for_pdistr 27779 27779 0 0.00 0 9462 23 0 0
sp_multiply_rows_for_qdistr 20694 20694 0 0.00 0 16640 177 0 0
sp_pay_from_customer 7098 7098 0 0.00 4 15816 108 0 0
sp_pay_to_supplier 5768 5768 0 0.00 4 15765 120 0 0
sp_payment_common 12866 12866 0 0.00 1 15810 29 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 16670 298 0 0
sp_qd_handle_on_invoice_upd_sts 10334 10334 0 0.00 0 13718 291 0 0
sp_qd_handle_on_reserve_upd_sts 9980 9980 0 0.00 0 14802 56 0 0
sp_reserve_write_off 9980 9980 0 0.00 5 14826 166 0 0
sp_supplier_invoice 5680 5680 0 0.00 5 93270 3450 0 0
sp_supplier_order 5475 5475 0 0.00 5 46567 1263 0 0
srv_aggregate_perf_data 105 105 0 0.00 2622 148154 15627 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1338 192658 44151 0 0
srv_find_qd_qs_mism 1024400 1024400 0 0.00 0 21399 6 0 0
srv_make_invnt_saldo 581 581 0 0.00 27 41668 1013 0 0
srv_make_money_saldo 753 753 0 0.00 1 8925 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 198651 603939 374744 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 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 4954 4954 0 0.00 0 11839 179 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 7005 101 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 4341 14 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 5547 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 5136 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 171 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 4623 25 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 22362 1337 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 3324 11 0 0
t$perf-norm:sp_client_order 20694 20694 0 0.00 0 9649 15 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 3274 10 0 0
t$perf-norm:sp_pay_from_customer 7098 7098 0 0.00 0 10708 11 0 0
t$perf-norm:sp_pay_to_supplier 5768 5768 0 0.00 0 6311 9 0 0
t$perf-norm:sp_reserve_write_off 9980 9980 0 0.00 0 11254 14 0 0
t$perf-norm:sp_supplier_invoice 5680 5680 0 0.00 0 5352 17 0 0
t$perf-norm:sp_supplier_order 5475 5475 0 0.00 0 9140 16 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 413 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 236 4 0 0
t$perf-norm:srv_make_invnt_saldo 581 581 0 0.00 0 1346 18 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 7270 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10066 9862 204 2.02 0 10684 20 0 204
v_all_customers 37580 37580 0 0.00 0 19 0 0 0
v_all_suppliers 30686 30686 0 0.00 0 19 1 0 0
v_all_wares 41386 41386 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 2518 12 0 0
v_cancel_client_order 3536 3536 0 0.00 0 3055 6 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 3327 9 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 2528 9 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 569 3 0 0
v_cancel_supplier_invoice 814 786 28 3.43 0 2837 27 0 28
v_cancel_supplier_order 852 852 0 0.00 0 3239 8 0 0
v_cancel_write_off 2020 2020 0 0.00 0 7080 20 0 0
v_max_id_clo_ord 5473 5473 0 0.00 0 387 1 0 0
v_max_id_clo_res 981 981 0 0.00 0 2721 19 0 0
v_max_id_ord_sup 5672 5672 0 0.00 0 3115 3 0 0
v_max_non_paid_invoice 1570 1570 0 0.00 0 2511 5 0 0
v_max_non_paid_realizn 3871 3871 0 0.00 0 732 3 0 0
v_min_id_clo_ord 5473 5473 0 0.00 0 3467 6 0 0
v_min_id_clo_res 981 981 0 0.00 0 5255 14 0 0
v_min_id_ord_sup 5672 5672 0 0.00 0 5703 10 0 0
v_min_non_paid_invoice 5768 5768 0 0.00 0 15692 21 0 0
v_min_non_paid_realizn 7098 7098 0 0.00 0 15807 23 0 0
v_our_firm 10260 10260 0 0.00 0 14 0 0 0
v_reserve_write_off 19960 19960 0 0.00 0 5758 8 0 0
x_lock_depdocs_on_canc_invoice 10961 10961 0 0.00 0 15160 102 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 7039 327 0 0
x_make_qstorno_1000_1200 5475 5475 0 0.00 1 46557 1192 0 0
x_make_qstorno_1200_2000 5680 5680 0 0.00 2 93263 3291 0 0
x_make_qstorno_2100_3300 461662 461662 0 0.00 0 40443 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-07-2022 22:39:40 to 06-07-2022 22:39:44.

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
20:08:26 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:27 15588 8192000000 9893205904 10201088000 980568032 10.690 100 90 181 0 1009544880 5473168 23503680
20:10:46 12875 8192000000 9950958544 10273853440 1031366304 11.182 100 82 165 0 1059777808 7619744 20791760
20:12:01 19447 8192000000 9978302160 10313547776 1065348768 11.508 100 83 165 0 1095531968 9654384 20528816
20:13:25 11437 8192000000 10016707936 10362900480 1109173552 11.925 100 82 164 0 1139790480 10711280 19905648
20:14:39 8724 8192000000 10065533280 10423635968 1156658880 12.372 100 81 163 0 1187145056 11515216 18970960
20:15:53 4507 8192000000 10109566800 10477543424 1199008400 12.767 100 79 156 0 1230056048 12739456 18308192
20:17:01 1759 8192000000 10149946336 10524323840 1241676240 13.162 100 77 154 0 1271093760 12760880 16656640
20:18:06 2337 8192000000 10189334416 10570723328 1266423024 13.389 100 86 172 0 1298247152 12900752 18923376
20:19:11 1015 8192000000 10202502400 10586456064 1289265248 13.598 100 89 178 0 1323883072 15382928 19234896
20:20:15 1100 8192000000 10221531088 10608463872 1305433488 13.745 100 90 174 2 1338636352 14364752 18838112
20:21:19 1939 8192000000 10212046432 10612793344 1311376640 13.799 100 51 102 0 1332775872 10195536 11203696
20:22:22 48773 8192000000 10225253328 10632843264 1319354112 13.871 100 39 76 0 1333522752 4695792 9472848
20:24:13 12166 8192000000 10211209744 10634448896 1328271776 13.952 100 19 38 0 1337458448 4892240 4294432
20:25:26 6295 8192000000 10222979648 10647003136 1338060288 14.040 100 19 38 0 1351756864 10014544 3682032
20:26:32 14137 8192000000 10231669776 10656231424 1343332256 14.087 100 12 24 0 1351042464 5082624 2627584
20:27:47 24919 8192000000 10226884672 10651725824 1326154336 13.932 98 41 82 1 1341494944 5321600 10019008
20:29:15 5866 8192000000 10201808560 10619973632 1291059712 13.614 97 29 58 0 1303923104 6311424 6551968
20:30:22 8741 8192000000 10170254304 10577784832 1265129968 13.377 99 24 48 0 1276311616 5575248 5606400
20:31:34 71246 8192000000 10121749888 10522005504 1091301984 11.755 86 27 55 0 1102214256 4898304 6013968
20:33:49 10079 8192000000 10135563728 10532986880 1206755648 12.839 96 25 51 0 1217379824 5184304 5439872
20:35:00 8118 8192000000 10153299728 10563407872 1258412832 13.315 99 20 40 0 1268882736 5189856 5280048
20:36:08 7127 8192000000 10186149760 10596257792 1293603568 13.637 100 20 41 0 1304331856 5453872 5274416
20:37:18 29564 8192000000 10180244256 10600194048 1298918304 13.685 100 22 44 2 1310008672 5097488 5992880
20:38:48 15672 8192000000 10234352928 10645090304 1331604048 13.982 100 55 110 0 1353352752 8984032 12764672
20:40:12 43307 8192000000 10254897808 10675310592 1349307792 14.141 100 30 60 0 1365880320 9823568 6748960
20:42:00 18777 8192000000 10276586208 10703482880 1368596448 14.314 100 32 65 0 1394666016 18581008 7488560
20:43:27 20670 8192000000 10301257552 10723643392 1382505488 14.439 100 45 90 0 1407633408 14058912 11069008
20:44:55 11029 8192000000 10301241984 10729103360 1389718880 14.503 100 38 77 1 1414340352 16192752 8428720
20:46:10 12692 8192000000 10323145056 10749784064 1401623440 14.609 100 41 80 1 1433691440 22638592 9429408
20:47:46 6287 8192000000 10342884272 10773565440 1414752704 14.726 100 51 102 0 1458817568 30896464 13168400
20:48:57 6024 8192000000 10350278960 10772557824 1409462768 14.679 99 56 110 0 1454033392 31113008 13457616
20:50:06 5153 8192000000 10379636752 10802917376 1433477920 14.892 100 64 128 0 1486160336 37080208 15602208
20:51:17 4136 8192000000 10367130144 10791067648 1422169360 14.792 100 61 117 2 1475785568 37756976 15859232
20:52:27 2641 8192000000 10366979216 10786918400 1417165808 14.748 99 74 146 0 1467230752 30802656 19262288
20:53:34 14312 8192000000 10364248576 10779484160 1398309776 14.580 99 80 157 0 1457177584 38651248 20216560
20:54:58 1338 8192000000 10360229328 10773385216 1396753392 14.566 100 74 148 0 1459303808 43603584 18946832
20:56:04 25822 8192000000 10322508848 10755936256 1381283136 14.428 99 27 54 0 1423183984 33980896 7919952
20:57:50 11794 8192000000 10297642080 10730205184 1355359296 14.196 99 33 65 1 1400081344 36061200 8660848
20:59:11 18445 8192000000 10268224080 10700455936 1342296064 14.078 100 31 62 1 1376677280 25819760 8561456
21:00:38 8159 8192000000 10251723632 10678116352 1310439536 13.790 97 33 67 1 1348297984 29104544 8753904
21:01:48 39900 8192000000 10246568848 10684022784 1303920480 13.731 97 16 33 0 1331671248 23287584 4463184
21:03:29 52851 8192000000 10260465120 10694516736 1329424640 13.962 99 31 62 1 1357655760 20439472 7791648
21:06:16 14843 8192000000 10259202736 10697031680 1351943776 14.165 100 28 60 1 1368671616 9762816 6965024
21:07:32 43816 8192000000 10263156272 10699849728 1349422576 14.142 100 18 37 0 1367353648 14086592 3844480
21:09:24 58914 8192000000 10274366688 10721554432 1358255792 14.222 99 16 31 0 1375373344 13526192 3591360
21:11:47 83590 8192000000 10278809168 10726674432 1357923744 14.219 99 25 51 0 1385783328 21526880 6332704
21:14:11 35456 8192000000 10280557312 10730053632 1374916496 14.371 100 23 46 1 1389193440 9577328 4699616
21:15:49 6949 8192000000 10284881968 10728972288 1382359792 14.438 100 28 56 0 1401770720 13413072 5997856
21:16:57 52212 8192000000 10298285008 10739486720 1375986544 14.381 99 34 68 1 1400794080 16320144 8487392
21:19:18 67022 8192000000 10280826368 10747146240 1374310128 14.366 99 14 28 1 1394335120 16644960 3380032
21:22:20 132974 8192000000 10302163760 10761220096 1368532416 14.314 98 10 18 0 1388582896 17965552 2084928
21:25:33 76033 8192000000 10309277664 10747920384 1377724432 14.396 99 18 35 0 1401497392 19939776 3833184
21:27:55 51193 8192000000 10299887520 10754867200 1381226352 14.428 99 17 34 0 1397630656 12738016 3666288
21:29:48 38489 8192000000 10319478960 10764623872 1405607168 14.645 100 32 62 0 1421418112 8788208 7022736
21:31:37 25764 8192000000 10316306608 10766843904 1390336016 14.509 99 27 55 0 1410617200 14219664 6061520
21:33:04 192657 8192000000 10322458464 10769870848 1402331248 14.616 99 17 34 0 1424742640 18644848 3766544
21:37:17 40061 8192000000 10317301920 10765541376 1376813328 14.388 97 47 94 0 1396058352 8838608 10406416
21:39:05 12632 8192000000 10294320816 10732675072 1365631600 14.288 97 40 82 0 1382053424 8025072 8396752
21:40:27 58877 8192000000 10323361920 10792484864 1415989024 14.737 100 33 66 1 1440596528 16948912 7658592
21:42:27 47893 8192000000 10286096336 10747174912 1354927232 14.192 96 7 14 0 1367607248 11459552 1220464
21:44:16 48590 8192000000 10274587024 10739073024 1335196992 14.014 96 14 28 0 1346691648 8493840 3000816
21:46:05 40007 8192000000 10270620752 10722435072 1325618624 13.928 96 25 50 0 1340634368 9224624 5791120
21:47:46 54950 8192000000 10255217472 10700210176 1317030944 13.850 97 17 34 0 1334181728 13412880 3737904
21:49:44 71639 8192000000 10250725888 10696630272 1276649680 13.482 94 15 30 0 1291047104 11181744 3215680
21:52:00 61076 8192000000 10244830480 10685489152 1311460272 13.799 98 31 60 0 1329308848 11096528 6752048
21:54:01 17188 8192000000 10260650448 10692689920 1343156320 14.086 100 29 58 2 1358046944 8591376 6299248
21:55:48 51571 8192000000 10264769424 10699808768 1333445792 13.998 99 27 55 1 1352696576 13530656 5720128
21:57:41 7850 8192000000 10263217152 10693586944 1342448832 14.079 100 42 85 0 1361718816 10585296 8684688
21:58:50 40971 8192000000 10268068816 10696896512 1317303584 13.852 97 42 82 0 1342565216 16101008 9160624
22:00:34 54005 8192000000 10249430544 10707329024 1334792128 14.010 99 41 81 3 1363456800 18085056 10579616
22:02:36 74060 8192000000 10240595728 10694467584 1330171344 13.969 99 25 48 0 1347507840 11737648 5598848
22:05:09 49573 8192000000 10250682288 10712190976 1353943440 14.183 100 11 22 0 1371562752 14393120 3226192
22:07:00 46287 8192000000 10261586960 10714230784 1359462752 14.233 100 26 50 0 1375982960 9894480 6625728
22:08:52 38939 8192000000 10271715712 10724265984 1374469184 14.367 100 27 54 0 1386237616 4843936 6924496
22:10:38 78714 8192000000 10291893856 10751655936 1375767712 14.379 99 17 34 0 1393219120 13674160 3777248
22:13:03 71939 8192000000 10305760224 10766028800 1394314576 14.544 100 12 24 0 1412142304 15310544 2517184
22:15:26 69649 8192000000 10303869584 10774515712 1388110016 14.489 99 12 24 0 1402483712 11700192 2673504
22:17:35 44937 8192000000 10319057296 10768302080 1389568800 14.502 99 43 84 1 1411373376 12557520 9247056
22:19:29 26614 8192000000 10308813824 10764435456 1388263360 14.490 99 19 38 0 1404154288 11868416 4022512
22:20:56 88139 8192000000 10300604304 10753028096 1349453344 14.143 96 21 42 0 1363391696 9215056 4723296
22:23:29 76127 8192000000 10305178160 10761879552 1380955504 14.425 99 16 32 0 1394601584 9726352 3919728
22:26:07 71732 8192000000 10299296272 10763935744 1387831632 14.487 99 18 36 1 1400622672 8349728 4441312
22:29:06 53870 8192000000 10263653504 10729799680 1354143664 14.185 98 9 19 0 1365798032 9506736 2147632
22:31:18 69732 8192000000 10269887936 10731626496 1365802208 14.289 99 22 44 0 1380769648 10048448 4918992
22:33:29 45815 8192000000 10262978976 10715648000 1308067776 13.769 95 13 25 0 1321748640 10975424 2705440
22:35:25 28946 8192000000 10268906944 10713427968 1312169568 13.806 96 28 56 0 1333792752 14914672 6708512
22:37:01 84310 8192000000 9871168096 10268405760 960634320 10.495 70 20 41 0 985323856 20014944 4674592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-07-2022 22:39:46 to 06-07-2022 22:40:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 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 06-07-2022 22:40:02 to 06-07-2022 22:40:04.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          235550
MON$OLDEST_ACTIVE               237765
MON$OLDEST_SNAPSHOT             237765
MON$NEXT_TRANSACTION            237765
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-03 16:41:55.3470
MON$PAGES                       1996928
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:54: 
:55: mscs-test-161.budzdorov.ru    Wed Jul  6 22:38:36 2022
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:57: 
--- end of diff output ---

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

06.07.22 22:40:05. There are 3 logs in /var/tmp/logs-oltp30 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-06 22:40:07 No severe PSQL-related problems occured
06.07.22 22:40:08. End of report.