Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.11.2021 22: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 16824048 kB
MemAvailable 31645736 kB
Buffers 0 kB
Cached 14873560 kB
SwapCached 0 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.8
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 2021-11-12 22:37:52
DTS_END                         2021-11-12 22:37:52.0670
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/59230
TRN_ID                          238055
ATT_ID                          617
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: 9334.66 20104 1120159
customer order: creation 175.65 392 21078
customer order: refuse 14.73 394 1768
order to supplier: creation 44.18 1490 5302
order to supplier: removal 3.42 104349 410
invoice (draft): creation 46.12 3377 5534
invoice (draft): removal 52.28 900 6273
invoice accept: apply 41.47 18795 4976
invoice accept: cancel 47.53 858 5703
customer reserve: creation 3895.48 183 467457
customer reserve: removal 4713.11 59 565573
realization accept: apply 82.83 186 9940
realization accept: cancel 87.51 114 10501
payment from customer: creation 60.57 135 7268
payment from customer: removal 5.97 96 716
payment to supplier: creation 48.57 119 5828
payment to supplier: removal 3.48 78 418
service: total inventory turnovers 4.65 1193 558
service: total monetary turnovers 6.43 37 772
service: aggregate perf. data 0.67 24963 80
service: refresh index statistics 0.03 244357 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-11-2021 22:39:13 to 12-11-2021 22:39:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3241
TEST_TIME 2 4179
TEST_TIME 3 4178
TEST_TIME 4 3434
TEST_TIME 5 4656
TEST_TIME 6 4972
TEST_TIME 7 5498
TEST_TIME 8 5852
TEST_TIME 9 5902
TEST_TIME 10 5849
TEST_TIME 11 5744
TEST_TIME 12 5747
TEST_TIME 13 5772
TEST_TIME 14 5807
TEST_TIME 15 5881
TEST_TIME 16 6119
TEST_TIME 17 6104
TEST_TIME 18 6772
TEST_TIME 19 7081
TEST_TIME 20 7526
TEST_TIME 21 7708
TEST_TIME 22 7734
TEST_TIME 23 8286
TEST_TIME 24 8764
TEST_TIME 25 9246
TEST_TIME 26 9117
TEST_TIME 27 9663
TEST_TIME 28 10226
TEST_TIME 29 10840
TEST_TIME 30 10932
TEST_TIME 31 10884
TEST_TIME 32 10660
TEST_TIME 33 10785
TEST_TIME 34 10702
TEST_TIME 35 10421
TEST_TIME 36 10277
TEST_TIME 37 10180
TEST_TIME 38 10243
TEST_TIME 39 10005
TEST_TIME 40 10005
TEST_TIME 41 9878
TEST_TIME 42 9805
TEST_TIME 43 9886
TEST_TIME 44 9793
TEST_TIME 45 9811
TEST_TIME 46 9780
TEST_TIME 47 9649
TEST_TIME 48 9665
TEST_TIME 49 9502
TEST_TIME 50 9492
TEST_TIME 51 9570
TEST_TIME 52 9525
TEST_TIME 53 9609
TEST_TIME 54 9602
TEST_TIME 55 9560
TEST_TIME 56 9553
TEST_TIME 57 9653
TEST_TIME 58 9599
TEST_TIME 59 9712
TEST_TIME 60 9702
TEST_TIME 61 9707
TEST_TIME 62 9792
TEST_TIME 63 9805
TEST_TIME 64 9818
TEST_TIME 65 9786
TEST_TIME 66 9677
TEST_TIME 67 9680
TEST_TIME 68 9580
TEST_TIME 69 9585
TEST_TIME 70 9506
TEST_TIME 71 9511
TEST_TIME 72 9443
TEST_TIME 73 9378
TEST_TIME 74 9366
TEST_TIME 75 9425
TEST_TIME 76 9437
TEST_TIME 77 9454
TEST_TIME 78 9500
TEST_TIME 79 9448
TEST_TIME 80 9540
TEST_TIME 81 9570
TEST_TIME 82 9547
TEST_TIME 83 9609
TEST_TIME 84 9633
TEST_TIME 85 9678
TEST_TIME 86 9697
TEST_TIME 87 9741
TEST_TIME 88 9695
TEST_TIME 89 9625
TEST_TIME 90 9563
TEST_TIME 91 9602
TEST_TIME 92 9550
TEST_TIME 93 9476
TEST_TIME 94 9522
TEST_TIME 95 9507
TEST_TIME 96 9463
TEST_TIME 97 9508
TEST_TIME 98 9467
TEST_TIME 99 9491
TEST_TIME 100 9459
TEST_TIME 101 9606
TEST_TIME 102 9599
TEST_TIME 103 9624
TEST_TIME 104 9605
TEST_TIME 105 9658
TEST_TIME 106 9607
TEST_TIME 107 9620
TEST_TIME 108 9615
TEST_TIME 109 9578
TEST_TIME 110 9537
TEST_TIME 111 9518
TEST_TIME 112 9500
TEST_TIME 113 9445
TEST_TIME 114 9418
TEST_TIME 115 9420
TEST_TIME 116 9380
TEST_TIME 117 9412
TEST_TIME 118 9369
TEST_TIME 119 9353
TEST_TIME 120 9337

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-11-2021 22:39:17 to 12-11-2021 22:39:20.

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 1552215 1552215 0 0.00 0 29308 6 0 0
doc_list_biud 1552215 1552215 0 0.00 0 30544 28 0 0
sp_add_invoice_to_stock 5081 4976 105 2.06 32 270455 18796 0 105
sp_cancel_adding_invoice 5703 5703 0 0.00 5 68065 859 0 0
sp_cancel_client_order 1768 1768 0 0.00 4 29482 394 0 0
sp_cancel_customer_reserve 565573 565573 0 0.00 1 28317 59 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 2 14473 97 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 4414 78 0 0
sp_cancel_supplier_invoice 6282 6273 9 0.14 1 30565 901 0 9
sp_cancel_supplier_order 410 410 0 0.00 11 1148239 104350 0 0
sp_cancel_write_off 10501 10501 0 0.00 1 29297 115 0 0
sp_client_order 21078 21078 0 0.00 3 51187 392 0 0
sp_customer_reserve 467457 467457 0 0.00 0 53966 184 0 0
sp_fill_shopping_cart 31909 31909 0 0.00 1 17841 37 0 0
sp_fill_shopping_cart_clo_res 467453 467453 0 0.00 0 22046 14 0 0
sp_get_clo_for_invoice 4971 4971 0 0.00 0 17787 273 0 0
sp_kill_cost_storno 17338 17338 0 0.00 0 7971 51 0 0
sp_kill_qstorno_ret_qs2qd 1148330 1148330 0 0.00 0 30533 33 0 0
sp_kill_qty_storno 605139 605139 0 0.00 0 30539 71 0 0
sp_lock_selected_doc 20748 20748 0 0.00 0 7363 5 0 0
sp_make_cost_storno 28007 28007 0 0.00 0 15665 51 0 0
sp_multiply_rows_for_pdistr 28007 28007 0 0.00 0 11231 29 0 0
sp_multiply_rows_for_qdistr 21078 21078 0 0.00 0 51166 175 0 0
sp_pay_from_customer 7268 7268 0 0.00 4 14536 135 0 0
sp_pay_to_supplier 5828 5828 0 0.00 5 13581 120 0 0
sp_payment_common 13096 13096 0 0.00 1 11756 26 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 29476 356 0 0
sp_qd_handle_on_invoice_upd_sts 10674 10674 0 0.00 0 29199 348 0 0
sp_qd_handle_on_reserve_upd_sts 9940 9940 0 0.00 0 23737 66 0 0
sp_reserve_write_off 9940 9940 0 0.00 6 23764 186 0 0
sp_supplier_invoice 5534 5534 0 0.00 6 92073 3378 0 0
sp_supplier_order 5302 5302 0 0.00 5 40364 1491 0 0
srv_aggregate_perf_data 80 80 0 0.00 2695 521463 24963 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2035 123741 42526 0 0
srv_find_qd_qs_mism 1071565 1071565 0 0.00 0 29307 5 0 0
srv_make_invnt_saldo 558 558 0 0.00 49 57002 1193 0 0
srv_make_money_saldo 772 772 0 0.00 1 8920 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 54562 448398 244357 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4976 4976 0 0.00 0 45230 216 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 3752 104 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 15586 32 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 658 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 606 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 1309 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 3440 31 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 27646 1652 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 5112 11 0 0
t$perf-norm:sp_client_order 21078 21078 0 0.00 0 20222 25 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 6712 20 0 0
t$perf-norm:sp_pay_from_customer 7268 7268 0 0.00 0 4304 10 0 0
t$perf-norm:sp_pay_to_supplier 5828 5828 0 0.00 0 8295 16 0 0
t$perf-norm:sp_reserve_write_off 9940 9940 0 0.00 0 8430 17 0 0
t$perf-norm:sp_supplier_invoice 5534 5534 0 0.00 0 17141 26 0 0
t$perf-norm:sp_supplier_order 5302 5302 0 0.00 0 17770 24 0 0
t$perf-norm:srv_aggregate_perf_data 80 80 0 0.00 0 208 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 48 2 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 2739 42 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 3965 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10150 9940 210 2.06 0 14193 26 0 210
v_all_customers 38432 38432 0 0.00 0 47 1 0 0
v_all_suppliers 30288 30288 0 0.00 0 28 1 0 0
v_all_wares 42156 42156 0 0.00 0 157 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 3451 12 0 0
v_cancel_client_order 3536 3536 0 0.00 0 8272 12 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 14449 18 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 6863 14 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 4373 15 0 0
v_cancel_supplier_invoice 826 808 18 2.17 0 3281 16 0 18
v_cancel_supplier_order 820 820 0 0.00 0 373 4 0 0
v_cancel_write_off 2038 2038 0 0.00 0 8303 23 0 0
v_max_id_clo_ord 5301 5301 0 0.00 0 2795 2 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 6618 26 0 0
v_max_id_ord_sup 5530 5530 0 0.00 0 2387 2 0 0
v_max_non_paid_invoice 1514 1514 0 0.00 0 179 2 0 0
v_max_non_paid_realizn 3875 3875 0 0.00 0 3636 6 0 0
v_min_id_clo_ord 5301 5301 0 0.00 0 14269 13 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 7942 15 0 0
v_min_id_ord_sup 5530 5530 0 0.00 0 5129 6 0 0
v_min_non_paid_invoice 5828 5828 0 0.00 0 10893 17 0 0
v_min_non_paid_realizn 7268 7268 0 0.00 0 11748 16 0 0
v_our_firm 10508 10508 0 0.00 0 15 1 0 0
v_reserve_write_off 19880 19880 0 0.00 0 14038 11 0 0
x_lock_depdocs_on_canc_invoice 11572 11572 0 0.00 0 16486 120 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 8800 346 0 0
x_make_qstorno_1000_1200 5302 5302 0 0.00 1 40353 1408 0 0
x_make_qstorno_1200_2000 5534 5534 0 0.00 3 91896 3246 0 0
x_make_qstorno_2100_3300 467457 467457 0 0.00 0 45796 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-11-2021 22:39:21 to 12-11-2021 22:39:25.

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:07:55 5 8192000000 8804696112 8817971200 9513584 0.115 3 1 2 0 9650880 62768 74528
20:08:56 24163 8192000000 9883971392 10193248256 968548128 10.573 100 89 178 0 997637392 5773792 23315472
20:10:24 13058 8192000000 9924716512 10241134592 1007905632 10.955 100 87 174 0 1038667648 7576448 23185568
20:11:45 18101 8192000000 9963656672 10292932608 1043722368 11.300 100 79 158 0 1076585952 13246800 19616784
20:13:08 13227 8192000000 10027108512 10374451200 1113689840 11.967 100 74 148 1 1141870400 10527872 17652688
20:14:32 11490 8192000000 10086604832 10444890112 1163460704 12.436 100 79 159 1 1193641200 12224496 17956000
20:15:45 5974 8192000000 10123570208 10489462784 1211314336 12.881 100 90 179 2 1244759680 13675200 19770144
20:16:53 2804 8192000000 10164198688 10537848832 1247441216 13.215 100 86 168 0 1278365792 12830480 18094096
20:17:57 1751 8192000000 10189910480 10571513856 1274171424 13.460 100 88 172 0 1306919344 13014720 19733200
20:19:01 3531 8192000000 10206285488 10593185792 1296656224 13.665 100 79 157 2 1327350240 13873104 16820912
20:20:05 6643 8192000000 10201718560 10603970560 1303784608 13.730 100 44 90 2 1320457056 7119520 9552928
20:21:19 10417 8192000000 10200295584 10612895744 1306773616 13.757 100 27 54 1 1319827712 6183632 6870464
20:22:31 6914 8192000000 10205220224 10619908096 1314121744 13.823 100 22 44 2 1325557584 6714048 4721792
20:23:40 11952 8192000000 10212633664 10632704000 1326945776 13.940 100 11 22 0 1334784608 5238672 2600160
20:24:53 67195 8192000000 10222847024 10641100800 1327904064 13.948 100 38 74 3 1342833088 4950464 9978560
20:27:00 9920 8192000000 10209640272 10633928704 1305303616 13.743 98 28 56 2 1317869904 6079504 6486784
20:28:12 8686 8192000000 10214699168 10636316672 1323479808 13.908 100 16 32 1 1332598384 5388544 3730032
20:29:22 50685 8192000000 10159860960 10556813312 1194697216 12.727 91 29 59 3 1205709792 4459216 6553360
20:31:14 3941 8192000000 10169749840 10572328960 1252000160 13.257 98 43 86 1 1272619712 11045584 9573968
20:32:23 10676 8192000000 10130031712 10526883840 1204105824 12.814 96 21 42 1 1214342256 5228912 5007520
20:33:35 11894 8192000000 10146911712 10555539456 1258296656 13.314 100 13 26 0 1267132336 6175392 2660288
20:34:52 60883 8192000000 10169785360 10585432064 1283437488 13.544 100 24 48 0 1294818576 4878928 6502160
20:36:55 4791 8192000000 10194685952 10604883968 1297715600 13.674 100 36 72 0 1313058448 7489888 7852960
20:38:07 21093 8192000000 10229944960 10638286848 1323775728 13.911 100 55 111 0 1343482896 7158960 12548208
20:39:33 8093 8192000000 10261118240 10666844160 1347531472 14.125 100 72 145 1 1374235968 10501392 16203104
20:40:47 96775 8192000000 10273112528 10692931584 1361462288 14.250 100 47 94 1 1387599744 15174112 10963344
20:43:31 25650 8192000000 10286359472 10709766144 1372971376 14.354 100 57 112 3 1400946800 15133408 12842016
20:45:01 16846 8192000000 10309412832 10734186496 1385223904 14.463 100 27 54 1 1413611584 21237888 7149792
20:46:21 18121 8192000000 10323532048 10745573376 1399347616 14.589 100 61 122 2 1435436000 21871456 14216928
20:47:46 8297 8192000000 10335058976 10760568832 1412522032 14.706 100 54 108 1 1451128080 25556080 13049968
20:48:59 4772 8192000000 10343306752 10766049280 1411585440 14.698 100 59 116 1 1454721536 29558208 13577888
20:50:10 8954 8192000000 10361950928 10783535104 1421821168 14.789 100 59 118 1 1469748112 33686480 14240464
20:51:31 3424 8192000000 10361120688 10783997952 1420491776 14.777 100 54 108 1 1468816224 34684128 13640320
20:52:42 2113 8192000000 10350002192 10761527296 1400319360 14.598 99 73 146 0 1457872448 39111376 18441712
20:53:50 6292 8192000000 10345596928 10762567680 1399631600 14.592 100 62 124 1 1456832608 41036624 16164384
20:55:06 2032 8192000000 10386252736 10795458560 1412556704 14.707 100 82 164 0 1487080816 53869728 20654384
20:56:11 2846 8192000000 10385279520 10795642880 1410380448 14.687 100 91 183 2 1487348864 54432768 22535648
20:57:17 2402 8192000000 10380328432 10788728832 1398881552 14.585 99 89 178 1 1481468224 59995840 22590832
20:58:21 8039 8192000000 10381651296 10797006848 1400967952 14.604 100 67 133 3 1481588784 63029744 17591088
20:59:32 9850 8192000000 10340184944 10759819264 1364889968 14.281 98 45 88 2 1436194496 59326704 11977824
21:00:54 10356 8192000000 10323322160 10749444096 1337509136 14.035 97 34 68 4 1406742848 59818080 9415632
21:02:09 13139 8192000000 10314369456 10745217024 1348717648 14.136 99 24 48 0 1410700560 55061504 6921408
21:03:23 2846 8192000000 10335020416 10751315968 1354913776 14.192 99 59 118 1 1422401904 52470128 15018000
21:04:28 27368 8192000000 10301923952 10754572288 1343543856 14.089 99 19 38 1 1406836976 57130960 6162160
21:06:13 29173 8192000000 10300828704 10758881280 1358667520 14.225 100 11 22 1 1411157600 49245248 3244832
21:07:43 32150 8192000000 10284265712 10714402816 1351270864 14.159 99 45 86 0 1367380656 5943744 10166048
21:09:18 36444 8192000000 10315868992 10738798592 1377090368 14.391 100 41 74 1 1396141808 10171856 8879584
21:11:02 79884 8192000000 10266141312 10716737536 1327083936 13.941 97 6 11 0 1347821600 19677984 1059680
21:13:43 30380 8192000000 10293853792 10727075840 1365359840 14.285 99 48 94 1 1385523408 9149616 11013952
21:15:16 89896 8192000000 10275693728 10726817792 1338107776 14.040 98 15 29 1 1355556640 14389024 3059840
21:17:50 33546 8192000000 10282950128 10721185792 1345111232 14.103 98 30 62 3 1361022592 8276176 7635184
21:19:33 28307 8192000000 10299918448 10734526464 1364949664 14.282 99 47 97 1 1390072304 14391408 10731232
21:21:02 31511 8192000000 10274534256 10734583808 1367824000 14.308 99 10 20 0 1384418496 13761744 2832752
21:22:35 21694 8192000000 10277721952 10737725440 1380138816 14.418 100 6 12 0 1391172912 9573088 1461008
21:24:00 71019 8192000000 10290541024 10748239872 1388121072 14.489 100 11 22 0 1400529888 9707088 2701728
21:26:45 51039 8192000000 10285388560 10746683392 1388679072 14.494 100 28 56 1 1407945312 13280816 5985424
21:28:45 54968 8192000000 10277413232 10745982976 1362558336 14.260 98 4 8 0 1377925424 14466848 900240
21:30:41 43601 8192000000 10284914880 10742550528 1370733440 14.334 99 17 34 0 1384194528 9622656 3838432
21:32:28 53469 8192000000 10299807984 10751840256 1376428960 14.385 99 21 41 1 1391061024 9963232 4668832
21:34:28 42291 8192000000 10317059968 10760138752 1382159920 14.436 99 34 68 0 1398214880 7195312 8859648
21:36:12 49154 8192000000 10296223824 10765672448 1384379536 14.456 99 20 40 0 1406665520 17808112 4477872
21:38:01 58969 8192000000 10296178240 10755702784 1377247184 14.392 99 13 26 1 1394327024 14121664 2958176
21:40:01 56357 8192000000 10275022128 10722856960 1328229744 13.951 97 18 36 0 1340276336 7718048 4328544
21:42:18 91323 8192000000 10275926208 10716065792 1331973744 13.985 97 25 50 1 1353358272 15342240 6042288
21:45:32 18580 8192000000 10262756448 10704744448 1336926128 14.030 98 31 62 0 1350664096 7160320 6577648
21:46:52 57587 8192000000 10253853856 10705846272 1311870288 13.803 96 9 18 0 1326995168 13161296 1963584
21:48:50 83739 8192000000 10251239200 10701713408 1270087856 13.422 93 10 20 1 1285391696 12801840 2502000
21:51:16 54269 8192000000 10256436448 10715914240 1349438160 14.142 100 11 22 1 1368669760 16455440 2776160
21:53:41 72049 8192000000 10272448576 10715160576 1297649104 13.674 95 22 44 0 1315890048 13774128 4466816
21:55:53 41542 8192000000 10264604032 10705780736 1322122928 13.896 97 29 58 2 1337919248 9634944 6161376
21:57:36 67744 8192000000 10263963056 10705846272 1294049520 13.641 95 15 30 0 1308099504 10910064 3139920
22:00:16 56562 8192000000 10271961824 10730614784 1347744896 14.127 99 11 22 0 1364226080 13890320 2590864
22:02:17 85986 8192000000 10279590848 10742087680 1356980336 14.210 99 15 30 0 1371603568 11210816 3412416
22:05:14 108062 8192000000 10282838064 10729730048 1350356496 14.151 98 22 44 0 1365323216 9941120 5025600
22:08:05 55714 8192000000 10262673120 10723180544 1338180880 14.041 97 19 38 0 1353532080 10957456 4393744
22:10:52 73415 8192000000 10272971200 10750873600 1379471584 14.412 100 14 28 1 1397414816 14332272 3610960
22:13:06 36228 8192000000 10274278976 10723733504 1331996768 13.985 96 22 44 0 1346868720 9758064 5113888
22:14:44 57156 8192000000 10296422992 10743644160 1359757008 14.235 98 23 46 0 1377170960 11572624 5841328
22:16:44 34422 8192000000 10310180704 10759954432 1386339392 14.473 100 25 50 0 1407466160 15167136 5959632
22:18:33 123734 8192000000 10313171504 10777182208 1393485664 14.537 100 9 18 0 1417829456 22200352 2143440
22:21:40 30914 8192000000 10289752800 10742616064 1369620064 14.324 99 27 54 3 1392489472 16653536 6215872
22:24:29 56153 8192000000 10317135232 10758119424 1378888624 14.407 99 37 74 0 1399655248 13007824 7758800
22:26:32 89594 8192000000 10284906320 10745380864 1344693248 14.100 97 26 52 1 1367362032 16199920 6468864
22:29:05 52948 8192000000 10261509760 10730205184 1361306544 14.249 99 7 14 0 1373195984 10159792 1729648
22:30:58 57436 8192000000 10277480144 10741051392 1367136432 14.301 99 17 34 1 1379358416 8461152 3760832
22:33:03 93734 8192000000 10272932928 10734407680 1350901472 14.156 98 17 34 0 1368070464 12844784 4324208
22:35:42 47889 8192000000 10261070192 10716082176 1339941088 14.057 98 18 35 2 1354113824 9792192 4380544
22:37:33 24162 8192000000 8927653072 9199960064 76955584 0.930 6 2 4 0 80666288 3370448 340256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-11-2021 22:39:27 to 12-11-2021 22:39:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-11-2021 22:39:43 to 12-11-2021 22:39:45.

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          238371
MON$OLDEST_ACTIVE               238372
MON$OLDEST_SNAPSHOT             238372
MON$NEXT_TRANSACTION            238372
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                       2036256
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:192: 
:193: mscs-test-161.budzdorov.ru    Fri Nov 12 22:38:07 2021
:194:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:195: 
--- end of diff output ---

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

12.11.21 22:39:46. 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
2021-11-12 22:39:48 No severe PSQL-related problems occured
12.11.21 22:39:50. End of report.