Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.09.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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15886880 kB
MemAvailable 30903296 kB
Buffers 0 kB
Cached 15810640 kB
SwapCached 340 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-09-05 22:37:47
DTS_END                         2021-09-05 22:37:47.5680
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(57:17)
IP                              ::1/56470
TRN_ID                          233011
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: 9235.03 26091 1108203
customer order: creation 165.69 330 19883
customer order: refuse 14.28 354 1714
order to supplier: creation 44.29 1325 5315
order to supplier: removal 3.42 126315 410
invoice (draft): creation 43.87 3769 5264
invoice (draft): removal 53.49 980 6419
invoice accept: apply 41.17 20425 4940
invoice accept: cancel 48.38 1005 5806
customer reserve: creation 3813.99 201 457679
customer reserve: removal 4714.42 71 565730
realization accept: apply 81.08 201 9729
realization accept: cancel 85.80 116 10296
payment from customer: creation 57.02 116 6842
payment from customer: removal 5.43 96 651
payment to supplier: creation 46.96 146 5635
payment to supplier: removal 3.08 99 369
service: total inventory turnovers 4.96 1498 595
service: total monetary turnovers 6.93 28 831
service: aggregate perf. data 0.76 23827 91
service: refresh index statistics 0.03 340915 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-09-2021 22:39:14 to 05-09-2021 22:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7995
TEST_TIME 2 7600
TEST_TIME 3 6657
TEST_TIME 4 7318
TEST_TIME 5 6993
TEST_TIME 6 7106
TEST_TIME 7 7186
TEST_TIME 8 6868
TEST_TIME 9 6554
TEST_TIME 10 6367
TEST_TIME 11 6231
TEST_TIME 12 6542
TEST_TIME 13 6515
TEST_TIME 14 6955
TEST_TIME 15 6868
TEST_TIME 16 6847
TEST_TIME 17 6998
TEST_TIME 18 7320
TEST_TIME 19 7307
TEST_TIME 20 7483
TEST_TIME 21 7543
TEST_TIME 22 7531
TEST_TIME 23 7584
TEST_TIME 24 8273
TEST_TIME 25 8320
TEST_TIME 26 8701
TEST_TIME 27 8660
TEST_TIME 28 8785
TEST_TIME 29 8605
TEST_TIME 30 8776
TEST_TIME 31 9228
TEST_TIME 32 9602
TEST_TIME 33 9933
TEST_TIME 34 11050
TEST_TIME 35 10983
TEST_TIME 36 10940
TEST_TIME 37 11028
TEST_TIME 38 10830
TEST_TIME 39 10735
TEST_TIME 40 10725
TEST_TIME 41 10560
TEST_TIME 42 10384
TEST_TIME 43 10394
TEST_TIME 44 10252
TEST_TIME 45 10124
TEST_TIME 46 10244
TEST_TIME 47 10155
TEST_TIME 48 10112
TEST_TIME 49 10000
TEST_TIME 50 10017
TEST_TIME 51 9944
TEST_TIME 52 9985
TEST_TIME 53 9932
TEST_TIME 54 9973
TEST_TIME 55 9998
TEST_TIME 56 10043
TEST_TIME 57 9936
TEST_TIME 58 9772
TEST_TIME 59 9720
TEST_TIME 60 9694
TEST_TIME 61 9608
TEST_TIME 62 9615
TEST_TIME 63 9538
TEST_TIME 64 9496
TEST_TIME 65 9461
TEST_TIME 66 9374
TEST_TIME 67 9380
TEST_TIME 68 9325
TEST_TIME 69 9496
TEST_TIME 70 9419
TEST_TIME 71 9394
TEST_TIME 72 9358
TEST_TIME 73 9256
TEST_TIME 74 9262
TEST_TIME 75 9242
TEST_TIME 76 9244
TEST_TIME 77 9205
TEST_TIME 78 9246
TEST_TIME 79 9293
TEST_TIME 80 9312
TEST_TIME 81 9342
TEST_TIME 82 9352
TEST_TIME 83 9351
TEST_TIME 84 9409
TEST_TIME 85 9402
TEST_TIME 86 9418
TEST_TIME 87 9459
TEST_TIME 88 9466
TEST_TIME 89 9552
TEST_TIME 90 9463
TEST_TIME 91 9426
TEST_TIME 92 9380
TEST_TIME 93 9386
TEST_TIME 94 9347
TEST_TIME 95 9301
TEST_TIME 96 9260
TEST_TIME 97 9248
TEST_TIME 98 9289
TEST_TIME 99 9307
TEST_TIME 100 9308
TEST_TIME 101 9275
TEST_TIME 102 9336
TEST_TIME 103 9347
TEST_TIME 104 9341
TEST_TIME 105 9392
TEST_TIME 106 9371
TEST_TIME 107 9356
TEST_TIME 108 9403
TEST_TIME 109 9420
TEST_TIME 110 9535
TEST_TIME 111 9485
TEST_TIME 112 9406
TEST_TIME 113 9421
TEST_TIME 114 9389
TEST_TIME 115 9359
TEST_TIME 116 9318
TEST_TIME 117 9291
TEST_TIME 118 9271
TEST_TIME 119 9275
TEST_TIME 120 9243

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-09-2021 22:39:18 to 05-09-2021 22:39:21.

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 1532454 1532454 0 0.00 0 13453 7 0 0
doc_list_biud 1532452 1532452 0 0.00 0 23410 33 0 0
sp_add_invoice_to_stock 5029 4940 89 1.76 35 228709 20426 0 89
sp_cancel_adding_invoice 5806 5806 0 0.00 6 73305 1006 0 0
sp_cancel_client_order 1714 1714 0 0.00 4 9784 354 0 0
sp_cancel_customer_reserve 565730 565730 0 0.00 1 21479 71 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 3 4340 96 0 0
sp_cancel_pay_to_supplier 369 369 0 0.00 3 7484 100 0 0
sp_cancel_supplier_invoice 6423 6419 4 0.06 2 23414 981 0 4
sp_cancel_supplier_order 410 410 0 0.00 7 1212900 126315 0 0
sp_cancel_write_off 10296 10296 0 0.00 1 8159 117 0 0
sp_client_order 19883 19883 0 0.00 4 16571 330 0 0
sp_customer_reserve 457679 457679 0 0.00 0 71242 201 0 0
sp_fill_shopping_cart 30462 30462 0 0.00 1 14861 42 0 0
sp_fill_shopping_cart_clo_res 457663 457663 0 0.00 0 12860 13 0 0
sp_get_clo_for_invoice 4921 4921 0 0.00 0 23300 312 0 0
sp_kill_cost_storno 17122 17122 0 0.00 0 7110 70 0 0
sp_kill_qstorno_ret_qs2qd 1148585 1148585 0 0.00 0 23409 38 0 0
sp_kill_qty_storno 605024 605024 0 0.00 0 23410 83 0 0
sp_lock_selected_doc 20229 20229 0 0.00 0 8042 5 0 0
sp_make_cost_storno 27126 27126 0 0.00 0 10868 61 0 0
sp_multiply_rows_for_pdistr 27126 27126 0 0.00 0 8674 35 0 0
sp_multiply_rows_for_qdistr 19883 19883 0 0.00 0 12896 141 0 0
sp_pay_from_customer 6842 6842 0 0.00 4 9910 117 0 0
sp_pay_to_supplier 5635 5635 0 0.00 4 9213 146 0 0
sp_payment_common 12477 12477 0 0.00 1 9902 29 0 0
sp_qd_handle_on_cancel_clo 1714 1714 0 0.00 0 9777 333 0 0
sp_qd_handle_on_invoice_upd_sts 10726 10726 0 0.00 0 14082 398 0 0
sp_qd_handle_on_reserve_upd_sts 9729 9729 0 0.00 0 12186 64 0 0
sp_reserve_write_off 9729 9729 0 0.00 6 16017 201 0 0
sp_supplier_invoice 5264 5264 0 0.00 6 108132 3769 0 0
sp_supplier_order 5315 5315 0 0.00 6 39179 1325 0 0
srv_aggregate_perf_data 91 91 0 0.00 2675 284704 23827 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2091 154348 42498 0 0
srv_find_qd_qs_mism 1061994 1061994 0 0.00 0 12214 6 0 0
srv_make_invnt_saldo 595 595 0 0.00 32 227531 1499 0 0
srv_make_money_saldo 831 831 0 0.00 1 487 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 105335 668180 340916 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4940 4940 0 0.00 0 8350 197 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 4280 133 0 0
t$perf-norm:sp_cancel_client_order 1714 1714 0 0.00 0 4193 18 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 609 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 4593 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 369 369 0 0.00 0 2573 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 489 14 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 34284 2124 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 1941 11 0 0
t$perf-norm:sp_client_order 19883 19883 0 0.00 0 7400 20 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 4377 8 0 0
t$perf-norm:sp_pay_from_customer 6842 6842 0 0.00 0 3571 9 0 0
t$perf-norm:sp_pay_to_supplier 5635 5635 0 0.00 0 5665 9 0 0
t$perf-norm:sp_reserve_write_off 9729 9729 0 0.00 0 7413 11 0 0
t$perf-norm:sp_supplier_invoice 5264 5264 0 0.00 0 7781 23 0 0
t$perf-norm:sp_supplier_order 5315 5315 0 0.00 0 3767 19 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 281 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 130 4 0 0
t$perf-norm:srv_make_invnt_saldo 595 595 0 0.00 0 1299 22 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 3293 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10018 9840 178 1.77 0 7274 19 0 178
v_all_customers 36332 36332 0 0.00 0 18 1 0 0
v_all_suppliers 29364 29364 0 0.00 0 21 1 0 0
v_all_wares 39766 39766 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 164 6 0 0
v_cancel_client_order 3428 3428 0 0.00 0 1590 4 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 4274 12 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 2751 6 0 0
v_cancel_payment_to_supplier 738 738 0 0.00 0 4166 16 0 0
v_cancel_supplier_invoice 828 820 8 0.96 0 855 18 0 8
v_cancel_supplier_order 820 820 0 0.00 0 987 5 0 0
v_cancel_write_off 1940 1940 0 0.00 0 6333 20 0 0
v_max_id_clo_ord 5315 5315 0 0.00 0 492 1 0 0
v_max_id_clo_res 997 997 0 0.00 0 958 6 0 0
v_max_id_ord_sup 5264 5264 0 0.00 0 4571 5 0 0
v_max_non_paid_invoice 1532 1532 0 0.00 0 888 4 0 0
v_max_non_paid_realizn 3567 3567 0 0.00 0 2920 5 0 0
v_min_id_clo_ord 5315 5315 0 0.00 0 3334 6 0 0
v_min_id_clo_res 997 997 0 0.00 0 2086 6 0 0
v_min_id_ord_sup 5264 5264 0 0.00 0 4710 5 0 0
v_min_non_paid_invoice 5635 5635 0 0.00 0 7752 19 0 0
v_min_non_paid_realizn 6842 6842 0 0.00 0 9898 20 0 0
v_our_firm 9984 9984 0 0.00 0 17 1 0 0
v_reserve_write_off 19458 19458 0 0.00 0 7469 6 0 0
x_lock_depdocs_on_canc_invoice 11815 11815 0 0.00 0 18833 152 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 8187 469 0 0
x_make_qstorno_1000_1200 5315 5315 0 0.00 2 39149 1227 0 0
x_make_qstorno_1200_2000 5264 5264 0 0.00 3 101375 3628 0 0
x_make_qstorno_2100_3300 457679 457679 0 0.00 0 71242 185 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-09-2021 22:39:22 to 05-09-2021 22:39:26.

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:51 47 8192000000 8829846512 8848252928 26754176 0.325 4 4 6 0 27078288 99584 224528
20:08:52 28094 8192000000 10369703216 10689073152 1415300000 14.731 100 86 172 0 1449555104 5548240 28706864
20:10:25 17814 8192000000 10409953088 10738442240 1472757952 15.238 100 86 172 0 1508402880 8143840 27501088
20:11:57 13905 8192000000 10502442336 10845462528 1560330800 15.999 100 85 170 1 1596206672 9710912 26164960
20:13:17 13897 8192000000 10579269296 10939691008 1634634416 16.634 100 77 154 2 1669519600 11354784 23530400
20:14:36 6830 8192000000 10641769120 11012657152 1708348496 17.255 100 80 160 1 1744690240 12320048 24021696
20:15:46 4496 8192000000 10706089968 11082633216 1762409840 17.704 100 79 158 2 1798178944 13564896 22204208
20:16:52 3068 8192000000 10764657872 11144179712 1821354544 18.189 100 84 169 1 1857304864 12341680 23608640
20:17:59 1835 8192000000 10808639120 11191476224 1859343296 18.498 100 93 185 3 1899563776 15159936 25060544
20:19:02 1272 8192000000 10821597728 11214553088 1879592848 18.662 100 80 160 0 1915245024 13542880 22109296
20:20:05 6346 8192000000 10810871392 11226386432 1885493744 18.709 100 44 88 0 1906937632 9236128 12207760
20:21:13 11163 8192000000 10804965696 11231477760 1895737728 18.792 100 21 42 0 1907504368 5992880 5773760
20:22:25 58157 8192000000 10811201968 11249209344 1911016144 18.915 100 33 66 0 1925800528 5298880 9485504
20:24:24 6615 8192000000 10830525696 11263627264 1916142704 18.956 100 24 48 2 1928999344 5795472 7061168
20:25:32 4855 8192000000 10839481760 11274399744 1923007440 19.011 100 22 45 1 1935578128 6579136 5991552
20:26:39 60035 8192000000 10860608064 11277996032 1926464576 19.039 100 24 48 1 1938974368 4698256 7811536
20:28:40 4825 8192000000 10778342352 11189444608 1839248576 18.335 98 39 78 4 1857741264 8053216 10439472
20:29:47 7113 8192000000 10732983488 11153670144 1809535488 18.092 99 23 46 2 1821981952 5287664 7158800
20:30:55 8324 8192000000 10663259600 11068297216 1654987984 16.807 93 23 45 3 1667883904 6202416 6693504
20:32:06 31959 8192000000 10666690880 11089842176 1724981296 17.394 96 43 86 0 1743529264 4957792 13590176
20:33:40 6118 8192000000 10728458224 11154628608 1820144400 18.179 100 30 60 1 1835306304 7108320 8053584
20:34:47 6659 8192000000 10756203360 11182043136 1843364592 18.368 100 22 44 2 1855505280 5625136 6515552
20:35:54 13804 8192000000 10770491856 11204214784 1862364528 18.522 100 18 36 1 1873927520 6094080 5468912
20:37:09 40329 8192000000 10803030496 11233243136 1882647456 18.686 100 38 74 2 1898786784 5753872 10385456
20:38:54 5298 8192000000 10849259744 11273596928 1925358000 19.030 100 65 128 1 1954145952 10215680 18572272
20:40:06 10857 8192000000 10885992688 11303747584 1939782864 19.145 100 69 137 3 1970667072 11699840 19184368
20:41:22 18794 8192000000 10891939984 11315666944 1949306784 19.221 100 56 111 1 1976302640 11022448 15973408
20:42:53 9602 8192000000 10906708912 11331289088 1966028512 19.354 100 57 114 0 1993497104 11809024 15659568
20:44:05 4691 8192000000 10919543648 11342483456 1968325072 19.372 100 55 110 0 1999640608 15134896 16180640
20:45:16 14861 8192000000 10927629888 11361345536 1985822304 19.511 100 43 84 1 2014437584 15814240 12801040
20:46:35 22793 8192000000 10940598048 11372032000 1993864864 19.574 100 56 112 2 2029118176 18723072 16530240
20:48:05 9961 8192000000 10918944720 11361722368 1985740320 19.510 100 47 93 1 2018094896 18545760 13808816
20:49:24 9636 8192000000 10915383232 11338903552 1944491552 19.183 98 63 125 4 1984141072 20845664 18803856
20:50:41 3552 8192000000 10927735472 11350794240 1976252480 19.435 100 65 130 3 2018872176 22980000 19639696
20:51:53 3757 8192000000 10914486080 11331768320 1958180608 19.292 100 75 148 2 2001833376 20686400 22966368
20:53:03 8367 8192000000 10919267360 11349798912 1967366000 19.365 100 63 126 1 2011853056 25079232 19407824
20:54:16 4423 8192000000 10959777520 11370848256 1978381024 19.452 100 82 160 0 2038986704 36282576 24323104
20:55:25 2717 8192000000 10967341632 11375112192 1978569984 19.453 99 92 180 1 2043992880 37692864 27730032
20:56:33 2629 8192000000 10973937168 11389218816 1993425856 19.571 100 78 156 0 2056817200 38621008 24770336
20:57:39 3357 8192000000 10967760976 11371782144 1979121424 19.458 100 82 163 3 2047514704 41704896 26688384
20:58:49 2795 8192000000 11005137392 11421958144 2011387472 19.712 100 82 164 1 2087440528 50069616 25983440
20:59:55 4464 8192000000 11020510560 11440689152 2021081776 19.789 100 80 160 0 2105040352 57081680 26876896
21:01:07 2089 8192000000 11026191104 11439546368 2017852352 19.763 100 84 166 3 2106722416 61566880 27303184
21:02:22 3769 8192000000 10998177696 11423367168 2004212512 19.656 100 66 132 3 2079269344 52801824 22255008
21:03:29 2090 8192000000 10983286048 11415449600 1987737088 19.526 100 65 131 1 2072798608 61264528 23796992
21:04:36 24577 8192000000 10969954048 11415437312 1984983584 19.504 100 40 80 2 2054299488 55238752 14077152
21:06:03 8775 8192000000 10985411536 11426373632 1999966944 19.622 100 45 90 1 2070723952 55170320 15586688
21:07:17 9155 8192000000 10982832480 11438694400 1990734208 19.550 100 40 80 1 2075694880 69629888 15330784
21:09:06 51331 8192000000 10964002960 11433385984 1980121440 19.466 100 27 54 1 2056777584 66594880 10061264
21:11:40 55022 8192000000 10918563552 11400851456 1977716720 19.447 100 10 20 0 2002153744 22133680 2303344
21:13:58 99360 8192000000 10902108768 11364667392 1902446656 18.846 96 7 14 0 1916434272 12204656 1782960
21:16:56 32117 8192000000 10868857856 11330744320 1914972320 18.947 97 18 36 1 1930103584 10211392 4919872
21:18:39 67849 8192000000 10915129040 11370237952 1969523120 19.382 100 13 26 0 1985367728 12115168 3729440
21:21:09 47407 8192000000 10923725440 11389849600 1992806848 19.566 100 22 42 1 2012362656 13768480 5787328
21:23:03 74746 8192000000 10931426720 11394641920 1997082480 19.600 100 24 49 1 2015565296 12131504 6351312
21:25:27 87104 8192000000 10943188560 11413639168 1980544448 19.469 99 17 34 1 1999115712 14240240 4331024
21:28:03 34804 8192000000 10961020624 11399790592 1985133328 19.505 99 35 69 0 2001547296 6687632 9726336
21:29:41 154347 8192000000 10924866096 11414450176 1934559680 19.103 97 4 8 0 1949856224 14152576 1143968
21:33:32 107292 8192000000 10929495728 11398635520 1988916176 19.535 100 8 16 1 2009577392 18500576 2160640
21:36:45 25564 8192000000 10938704928 11390201856 1963337776 19.333 98 61 123 0 1989464224 8876608 17249840
21:38:12 36288 8192000000 10929992848 11377479680 1944258304 19.181 97 51 99 4 1967147632 9065008 13824320
21:39:51 47134 8192000000 10909827344 11370455040 1923899328 19.018 96 25 49 1 1944239568 13493760 6846480
21:41:56 66853 8192000000 10903494736 11378745344 1935248240 19.109 98 18 36 0 1961173328 19805824 6119264
21:44:22 78941 8192000000 10884434688 11357511680 1898582208 18.815 97 16 32 1 1929675824 26391072 4702544
21:46:43 46173 8192000000 10863023648 11335753728 1937631120 19.128 100 11 22 1 1950909712 10063136 3215456
21:48:43 56168 8192000000 10860949760 11343372288 1951051104 19.235 100 33 64 0 1969097520 9148896 8897520
21:51:05 88832 8192000000 10853597744 11348746240 1919082400 18.979 98 7 14 1 1931045872 10306896 1656576
21:54:14 38131 8192000000 10882407312 11352330240 1933389536 19.094 99 16 32 2 1945586400 7711200 4485664
21:56:03 36711 8192000000 10852724912 11325607936 1902076176 18.843 98 15 28 2 1915641024 9655104 3909744
21:57:42 77181 8192000000 10881323200 11355545600 1933778416 19.097 99 18 36 0 1951756368 12822256 5155696
22:00:02 17239 8192000000 10873446176 11326328832 1915983264 18.955 99 32 64 0 1935852864 11138944 8730656
22:01:26 95043 8192000000 10871007616 11346399232 1939084128 19.139 100 16 32 1 1959556800 15014912 5457760
22:04:01 55088 8192000000 10878590832 11354279936 1914874096 18.946 98 32 62 1 1938847008 14736224 9236688
22:05:59 83037 8192000000 10872535584 11356512256 1929257040 19.061 99 12 24 0 1957250528 24304080 3689408
22:08:22 51270 8192000000 10900166752 11357827072 1931941600 19.082 98 30 58 0 1947462800 6705392 8815808
22:10:15 30012 8192000000 10887152432 11359932416 1957281008 19.284 99 37 73 1 1977538240 10216928 10040304
22:11:46 39181 8192000000 10895458192 11377991680 1972821296 19.408 100 12 24 1 1984985536 9248048 2916192
22:13:26 43134 8192000000 10894772080 11364966400 1940804592 19.153 98 21 42 0 1958383856 11697872 5881392
22:15:10 88098 8192000000 10881349600 11354034176 1942807216 19.169 98 16 32 0 1963554528 15919424 4827888
22:17:39 71295 8192000000 10914474672 11387535360 1925339408 19.030 97 24 49 0 1939156000 7561776 6254816
22:19:50 40398 8192000000 10907080256 11373142016 1960254816 19.308 99 32 64 2 1979113984 9968992 8890176
22:21:36 107251 8192000000 10916683216 11389898752 1964564864 19.342 99 16 31 1 1984798240 16130528 4102848
22:24:26 31812 8192000000 10919172016 11369852928 1941101744 19.156 98 45 89 0 1963640320 9898784 12639792
22:26:03 141416 8192000000 10889500288 11379322880 1899970720 18.826 96 10 20 1 1918438464 15655600 2812144
22:29:32 77405 8192000000 10899701840 11375230976 1969081920 19.378 100 28 56 1 1989392544 12456656 7853968
22:33:28 49660 8192000000 10882648512 11366916096 1946650672 19.200 99 6 12 0 1957931168 9514016 1766480
22:36:21 55625 8192000000 10874538848 11344154624 1908183200 18.892 97 9 18 0 1920594064 9728864 2682000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-09-2021 22:39:28 to 05-09-2021 22:39:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-09-2021 22:39:44 to 05-09-2021 22:39:46.

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          232917
MON$OLDEST_ACTIVE               234414
MON$OLDEST_SNAPSHOT             234414
MON$NEXT_TRANSACTION            234414
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                       2064096
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.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 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 ---
:224: 
:225: mscs-test-161.budzdorov.ru    Sun Sep  5 22:37:50 2021
:226:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:227: 
:228: 
:229: mscs-test-161.budzdorov.ru    Sun Sep  5 22:37:50 2021
:230:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:231: 
:232: 
:233: mscs-test-161.budzdorov.ru    Sun Sep  5 22:37:50 2021
:234:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:235: 
:236: 
:237: mscs-test-161.budzdorov.ru    Sun Sep  5 22:37:51 2021
:238:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:239: 
--- end of diff output ---

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

05.09.21 22:39:47. 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-09-05 22:39:49 No severe PSQL-related problems occured
05.09.21 22:39:51. End of report.