Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.06.2022 16: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 16214828 kB
MemAvailable 31205212 kB
Buffers 0 kB
Cached 15473088 kB
SwapCached 2480 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.10
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-06-22 16:38:24
DTS_END                         2022-06-22 16:38:24.0920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33974
TRN_ID                          233423
ATT_ID                          652
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: 9169.98 25592 1100398
customer order: creation 160.68 374 19282
customer order: refuse 13.44 398 1613
order to supplier: creation 42.59 1320 5111
order to supplier: removal 3.62 115199 434
invoice (draft): creation 43.00 3610 5160
invoice (draft): removal 54.39 818 6527
invoice accept: apply 38.92 19067 4671
invoice accept: cancel 48.93 912 5871
customer reserve: creation 3659.83 184 439180
customer reserve: removal 4812.71 69 577525
realization accept: apply 79.03 195 9483
realization accept: cancel 91.43 126 10971
payment from customer: creation 54.05 104 6486
payment from customer: removal 5.68 111 682
payment to supplier: creation 47.00 132 5640
payment to supplier: removal 2.98 50 357
service: total inventory turnovers 4.53 1192 543
service: total monetary turnovers 6.35 34 762
service: aggregate perf. data 0.80 22639 96
service: refresh index statistics 0.03 345298 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-06-2022 16:39:31 to 22-06-2022 16:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2671
TEST_TIME 2 2756
TEST_TIME 3 3253
TEST_TIME 4 3870
TEST_TIME 5 4714
TEST_TIME 6 5540
TEST_TIME 7 5590
TEST_TIME 8 5553
TEST_TIME 9 6004
TEST_TIME 10 6240
TEST_TIME 11 6736
TEST_TIME 12 6651
TEST_TIME 13 6790
TEST_TIME 14 7159
TEST_TIME 15 7372
TEST_TIME 16 7305
TEST_TIME 17 7297
TEST_TIME 18 7234
TEST_TIME 19 7484
TEST_TIME 20 7855
TEST_TIME 21 7923
TEST_TIME 22 8189
TEST_TIME 23 8106
TEST_TIME 24 8734
TEST_TIME 25 8581
TEST_TIME 26 8766
TEST_TIME 27 9222
TEST_TIME 28 9368
TEST_TIME 29 9507
TEST_TIME 30 9811
TEST_TIME 31 10845
TEST_TIME 32 10675
TEST_TIME 33 10552
TEST_TIME 34 10279
TEST_TIME 35 10235
TEST_TIME 36 10248
TEST_TIME 37 10237
TEST_TIME 38 10096
TEST_TIME 39 9975
TEST_TIME 40 9943
TEST_TIME 41 9851
TEST_TIME 42 9759
TEST_TIME 43 9846
TEST_TIME 44 9730
TEST_TIME 45 9703
TEST_TIME 46 9633
TEST_TIME 47 9511
TEST_TIME 48 9545
TEST_TIME 49 9511
TEST_TIME 50 9470
TEST_TIME 51 9372
TEST_TIME 52 9310
TEST_TIME 53 9247
TEST_TIME 54 9294
TEST_TIME 55 9338
TEST_TIME 56 9388
TEST_TIME 57 9304
TEST_TIME 58 9177
TEST_TIME 59 9165
TEST_TIME 60 9170
TEST_TIME 61 9159
TEST_TIME 62 9248
TEST_TIME 63 9227
TEST_TIME 64 9310
TEST_TIME 65 9269
TEST_TIME 66 9305
TEST_TIME 67 9282
TEST_TIME 68 9322
TEST_TIME 69 9309
TEST_TIME 70 9250
TEST_TIME 71 9372
TEST_TIME 72 9337
TEST_TIME 73 9345
TEST_TIME 74 9395
TEST_TIME 75 9363
TEST_TIME 76 9286
TEST_TIME 77 9281
TEST_TIME 78 9224
TEST_TIME 79 9202
TEST_TIME 80 9180
TEST_TIME 81 9174
TEST_TIME 82 9170
TEST_TIME 83 9179
TEST_TIME 84 9106
TEST_TIME 85 9160
TEST_TIME 86 9172
TEST_TIME 87 9141
TEST_TIME 88 9123
TEST_TIME 89 9138
TEST_TIME 90 9199
TEST_TIME 91 9194
TEST_TIME 92 9230
TEST_TIME 93 9249
TEST_TIME 94 9246
TEST_TIME 95 9243
TEST_TIME 96 9327
TEST_TIME 97 9306
TEST_TIME 98 9315
TEST_TIME 99 9354
TEST_TIME 100 9336
TEST_TIME 101 9251
TEST_TIME 102 9240
TEST_TIME 103 9255
TEST_TIME 104 9233
TEST_TIME 105 9206
TEST_TIME 106 9174
TEST_TIME 107 9159
TEST_TIME 108 9172
TEST_TIME 109 9172
TEST_TIME 110 9148
TEST_TIME 111 9090
TEST_TIME 112 9065
TEST_TIME 113 9071
TEST_TIME 114 9083
TEST_TIME 115 9068
TEST_TIME 116 9088
TEST_TIME 117 9087
TEST_TIME 118 9076
TEST_TIME 119 9210
TEST_TIME 120 9171

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-06-2022 16:39:34 to 22-06-2022 16:39:38.

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 1506427 1506427 0 0.00 0 13378 6 0 0
doc_list_biud 1506420 1506420 0 0.00 0 28437 33 0 0
sp_add_invoice_to_stock 4759 4671 88 1.84 31 170784 19068 0 88
sp_cancel_adding_invoice 5871 5871 0 0.00 3 68000 913 0 0
sp_cancel_client_order 1613 1613 0 0.00 4 21908 399 0 0
sp_cancel_customer_reserve 577525 577525 0 0.00 1 26072 69 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 2 8199 112 0 0
sp_cancel_pay_to_supplier 357 357 0 0.00 3 943 51 0 0
sp_cancel_supplier_invoice 6529 6527 2 0.03 2 24476 819 0 2
sp_cancel_supplier_order 434 434 0 0.00 6 1174588 115199 0 0
sp_cancel_write_off 10971 10971 0 0.00 1 8723 126 0 0
sp_client_order 19282 19282 0 0.00 3 31894 374 0 0
sp_customer_reserve 439180 439180 0 0.00 0 41315 185 0 0
sp_fill_shopping_cart 29553 29553 0 0.00 1 10945 42 0 0
sp_fill_shopping_cart_clo_res 439173 439173 0 0.00 0 13316 15 0 0
sp_get_clo_for_invoice 4664 4664 0 0.00 0 31872 314 0 0
sp_kill_cost_storno 17881 17881 0 0.00 0 9239 68 0 0
sp_kill_qstorno_ret_qs2qd 1172982 1172982 0 0.00 0 26061 37 0 0
sp_kill_qty_storno 617083 617083 0 0.00 0 28435 79 0 0
sp_lock_selected_doc 19615 19615 0 0.00 0 8997 5 0 0
sp_make_cost_storno 26273 26273 0 0.00 0 12572 57 0 0
sp_multiply_rows_for_pdistr 26266 26266 0 0.00 0 7510 30 0 0
sp_multiply_rows_for_qdistr 19282 19282 0 0.00 0 9686 159 0 0
sp_pay_from_customer 6486 6486 0 0.00 4 11602 105 0 0
sp_pay_to_supplier 5640 5640 0 0.00 4 12384 133 0 0
sp_payment_common 12124 12124 0 0.00 1 6826 24 0 0
sp_qd_handle_on_cancel_clo 1613 1613 0 0.00 0 21890 357 0 0
sp_qd_handle_on_invoice_upd_sts 10530 10530 0 0.00 0 28431 378 0 0
sp_qd_handle_on_reserve_upd_sts 9483 9483 0 0.00 0 16330 67 0 0
sp_reserve_write_off 9483 9483 0 0.00 6 20565 195 0 0
sp_supplier_invoice 5160 5160 0 0.00 8 79532 3611 0 0
sp_supplier_order 5111 5111 0 0.00 5 38724 1321 0 0
srv_aggregate_perf_data 96 96 0 0.00 2621 319667 22640 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2068 103087 46185 0 0
srv_find_qd_qs_mism 1055171 1055171 0 0.00 0 10879 5 0 0
srv_make_invnt_saldo 543 543 0 0.00 38 76681 1193 0 0
srv_make_money_saldo 762 762 0 0.00 0 4860 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 179036 521628 345298 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4671 4671 0 0.00 0 9858 196 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 0 4409 108 0 0
t$perf-norm:sp_cancel_client_order 1613 1613 0 0.00 0 3555 13 0 0
t$perf-norm:sp_cancel_customer_reserve 651 651 0 0.00 0 804 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 826 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 357 357 0 0.00 0 6500 32 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 1743 24 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 48685 1914 0 0
t$perf-norm:sp_cancel_write_off 933 933 0 0.00 0 4323 17 0 0
t$perf-norm:sp_client_order 19282 19282 0 0.00 0 10201 21 0 0
t$perf-norm:sp_customer_reserve 909 909 0 0.00 0 1778 6 0 0
t$perf-norm:sp_pay_from_customer 6486 6486 0 0.00 0 4899 9 0 0
t$perf-norm:sp_pay_to_supplier 5640 5640 0 0.00 0 3652 8 0 0
t$perf-norm:sp_reserve_write_off 9483 9483 0 0.00 0 4708 12 0 0
t$perf-norm:sp_supplier_invoice 5160 5160 0 0.00 0 3837 17 0 0
t$perf-norm:sp_supplier_order 5111 5111 0 0.00 0 4714 17 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 810 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1533 25 0 0
t$perf-norm:srv_make_invnt_saldo 543 543 0 0.00 0 1345 25 0 0
t$perf-norm:srv_make_money_saldo 762 762 0 0.00 0 825 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9494 9318 176 1.85 0 5809 20 0 176
v_all_customers 34976 34976 0 0.00 0 38 1 0 0
v_all_suppliers 28964 28964 0 0.00 0 18 1 0 0
v_all_wares 38564 38564 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 116 6 0 0
v_cancel_client_order 3226 3226 0 0.00 0 5482 8 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 4110 8 0 0
v_cancel_customer_reserve 1302 1302 0 0.00 0 4710 12 0 0
v_cancel_payment_to_supplier 714 714 0 0.00 0 763 3 0 0
v_cancel_supplier_invoice 844 840 4 0.47 0 1224 14 0 4
v_cancel_supplier_order 868 868 0 0.00 0 1217 5 0 0
v_cancel_write_off 1866 1866 0 0.00 0 6239 16 0 0
v_max_id_clo_ord 5111 5111 0 0.00 0 3414 3 0 0
v_max_id_clo_res 909 909 0 0.00 0 2480 24 0 0
v_max_id_ord_sup 5160 5160 0 0.00 0 4341 6 0 0
v_max_non_paid_invoice 1427 1427 0 0.00 0 760 3 0 0
v_max_non_paid_realizn 3550 3550 0 0.00 0 3984 4 0 0
v_min_id_clo_ord 5111 5111 0 0.00 0 1833 6 0 0
v_min_id_clo_res 909 909 0 0.00 0 1402 8 0 0
v_min_id_ord_sup 5160 5160 0 0.00 0 6303 7 0 0
v_min_non_paid_invoice 5638 5638 0 0.00 0 6733 16 0 0
v_min_non_paid_realizn 6486 6486 0 0.00 0 6823 17 0 0
v_our_firm 9460 9460 0 0.00 0 14 1 0 0
v_reserve_write_off 18966 18966 0 0.00 0 5970 9 0 0
x_lock_depdocs_on_canc_invoice 11978 11978 0 0.00 0 24528 133 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 24341 406 0 0
x_make_qstorno_1000_1200 5111 5111 0 0.00 1 38696 1240 0 0
x_make_qstorno_1200_2000 5160 5160 0 0.00 2 79519 3458 0 0
x_make_qstorno_2100_3300 439180 439180 0 0.00 0 41313 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-06-2022 16:39:39 to 22-06-2022 16:39:43.

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
14:08:27 3 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:27 25626 8192000000 9907346576 10218442752 983196752 10.715 100 89 178 0 1011764928 5670288 22897888
14:11:09 26529 8192000000 9955587280 10278232064 1037448320 11.240 100 93 186 0 1070420032 8903072 24068640
14:12:40 15917 8192000000 9999783648 10332123136 1076890272 11.618 100 87 174 1 1109117312 10535232 21691808
14:14:02 10857 8192000000 10041877840 10390589440 1125995792 12.084 100 78 155 1 1155668992 11535472 18137728
14:15:16 10730 8192000000 10086864912 10450632704 1178904752 12.580 100 81 161 1 1210115344 12165024 19045568
14:16:30 5653 8192000000 10145286416 10513354752 1224378704 13.002 100 85 170 1 1255964688 13434976 18151008
14:17:38 4055 8192000000 10168839200 10542125056 1251137024 13.249 100 84 167 0 1283508416 14360640 18010752
14:18:43 1540 8192000000 10204468768 10585153536 1290587376 13.610 100 89 174 2 1323413200 13255920 19569904
14:19:46 11450 8192000000 10222084048 10607665152 1305610480 13.746 100 85 171 0 1338043568 13555984 18877104
14:20:57 8269 8192000000 10191094320 10601152512 1305316400 13.744 100 39 77 0 1320502704 6182768 9003536
14:22:06 4614 8192000000 10208681552 10619482112 1322183712 13.896 100 25 50 1 1334200464 6663616 5353136
14:23:12 4860 8192000000 10224488640 10637934592 1330645920 13.973 100 26 54 2 1342779296 6236384 5896992
14:24:19 45629 8192000000 10233891008 10645778432 1335248992 14.015 100 26 51 1 1346795008 5287920 6258096
14:26:29 7040 8192000000 10229288592 10650722304 1341826688 14.074 100 24 48 0 1353138144 6048912 5262544
14:27:42 5636 8192000000 10221137904 10639269888 1332814096 13.993 100 36 70 1 1346699984 5247152 8638736
14:28:48 22363 8192000000 10173909904 10589466624 1236569968 13.115 94 23 46 1 1246995808 4679040 5746800
14:30:32 7357 8192000000 10159323248 10566533120 1246179632 13.203 97 26 52 4 1258165280 6170320 5815328
14:31:41 5291 8192000000 10144200720 10540195840 1229734624 13.052 98 29 58 2 1243086016 6295248 7056144
14:32:49 6826 8192000000 10142136144 10540986368 1234698848 13.097 98 30 60 1 1248249680 6671408 6879424
14:33:58 57744 8192000000 10151613504 10559217664 1256223056 13.295 99 16 32 1 1265108096 5095568 3789472
14:36:00 8168 8192000000 10188356416 10595311616 1289636064 13.601 100 39 78 0 1309716160 10821232 9258864
14:37:13 6688 8192000000 10190802944 10609025024 1303668800 13.729 100 22 43 1 1314602112 5467376 5465936
14:38:20 26516 8192000000 10227182336 10638622720 1330212576 13.969 100 32 64 1 1342322400 5267520 6842304
14:39:58 57316 8192000000 10256683072 10674348032 1353117088 14.176 100 42 81 0 1373656688 10915600 9624000
14:42:25 21021 8192000000 10284315280 10700652544 1368097712 14.310 100 55 109 3 1394684496 14131680 12455104
14:43:51 26346 8192000000 10291088496 10716246016 1380488192 14.421 100 42 84 2 1405666432 15202768 9975472
14:45:25 14364 8192000000 10308559248 10731569152 1390897648 14.514 100 59 114 1 1419281296 15003152 13380496
14:46:47 19656 8192000000 10326903136 10753032192 1402769568 14.620 100 44 87 1 1439785376 26671312 10344496
14:48:10 2269 8192000000 10344434320 10761605120 1406777872 14.655 100 68 136 1 1451189040 28345568 16065600
14:49:16 9478 8192000000 10342142112 10758197248 1412793616 14.709 100 76 146 0 1449912832 20607264 16511952
14:50:30 3196 8192000000 10323272368 10740465664 1394642384 14.547 100 65 130 1 1433662528 23432176 15587968
14:51:40 2356 8192000000 10327534160 10737369088 1386982512 14.479 99 70 139 0 1432144112 28100768 17060832
14:52:47 12000 8192000000 10327157152 10748096512 1389927328 14.505 100 58 115 0 1437879488 33485856 14466304
14:54:08 8379 8192000000 10338255088 10758586368 1398326336 14.580 100 63 126 0 1445920928 31702480 15892112
14:55:23 3679 8192000000 10352144992 10767065088 1399608944 14.592 100 73 146 0 1456062384 38302512 18150928
14:56:34 2764 8192000000 10380317440 10786254848 1409975184 14.684 100 88 176 0 1477627440 45396032 22256224
14:57:44 2066 8192000000 10384155312 10792566784 1402111232 14.614 99 87 172 0 1479354864 55192288 22051344
14:58:50 8676 8192000000 10385438480 10812813312 1421841440 14.789 100 58 116 0 1500109728 62283408 15984880
15:00:06 4456 8192000000 10394630032 10817622016 1418464768 14.759 100 65 129 1 1500358848 65553072 16341008
15:01:17 3884 8192000000 10395145920 10808856576 1407846912 14.665 100 82 164 1 1494514848 65794240 20873696
15:02:25 14445 8192000000 10356934992 10787831808 1386047776 14.471 99 34 68 1 1462852864 67269792 9535296
15:04:10 15623 8192000000 10360537360 10787504128 1382980544 14.443 100 38 76 0 1465455408 71849168 10625696
15:05:28 20834 8192000000 10340414464 10791333888 1374039664 14.363 99 22 44 1 1439246048 59155728 6050656
15:07:24 23424 8192000000 10329398080 10768175104 1362515568 14.260 100 31 63 0 1423964560 52591632 8857360
15:08:49 30382 8192000000 10343623568 10778927104 1358784336 14.226 98 48 96 0 1439735808 69276064 11675408
15:10:21 50410 8192000000 10279993792 10722492416 1347766768 14.127 97 26 52 2 1369288528 15438016 6083744
15:12:23 94645 8192000000 10279399920 10728894464 1319866672 13.876 97 11 22 0 1336503520 14226016 2410832
15:15:07 31359 8192000000 10274670032 10718859264 1348951200 14.138 99 13 26 1 1371525792 19612176 2962416
15:16:41 30480 8192000000 10294571600 10730029056 1377244240 14.392 100 13 26 0 1390917696 10858192 2815264
15:18:17 50252 8192000000 10308844592 10747088896 1389657856 14.503 100 26 50 1 1409474704 13613648 6203200
15:20:12 82033 8192000000 10285719472 10735165440 1368401600 14.313 99 17 34 0 1387436224 14714112 4320512
15:22:47 45788 8192000000 10297539680 10746724352 1367937472 14.309 99 24 47 0 1386830624 13656672 5236480
15:24:43 69996 8192000000 10300633680 10747162624 1385744864 14.468 100 24 48 3 1405940064 14805616 5389584
15:28:15 83357 8192000000 10303820512 10762756096 1377200288 14.392 99 19 38 2 1393101312 11562848 4338176
15:30:49 22060 8192000000 10314990256 10772615168 1402496448 14.617 100 22 44 0 1426808688 18594224 5718016
15:32:12 73619 8192000000 10300422768 10769207296 1370973696 14.336 98 26 53 0 1396651264 19603456 6074112
15:34:30 70006 8192000000 10294739232 10766434304 1392451728 14.528 100 13 26 0 1413572880 18534752 2586400
15:36:46 56548 8192000000 10284753264 10749157376 1386944592 14.479 100 13 26 0 1397969440 8185472 2839376
15:38:43 89709 8192000000 10295886720 10762895360 1362719296 14.262 98 17 34 0 1378535888 11820960 3995632
15:41:15 93793 8192000000 10287658016 10745081856 1345270224 14.105 96 9 18 1 1358812000 11730864 1810912
15:43:51 45910 8192000000 10269903504 10719289344 1320423776 13.881 95 22 44 1 1334454512 9218128 4812608
15:45:39 46483 8192000000 10301387072 10744242176 1351220512 14.158 97 45 89 0 1375342992 14447760 9674720
15:47:27 56338 8192000000 10296255968 10741964800 1368548704 14.314 99 22 44 0 1383473808 10304160 4620944
15:49:24 83649 8192000000 10278479968 10720317440 1328340944 13.952 96 26 52 1 1347545568 13137712 6066912
15:51:48 45747 8192000000 10296107856 10741039104 1367808560 14.307 99 34 68 0 1387011584 11640560 7562464
15:53:42 101851 8192000000 10266555888 10717917184 1326247680 13.933 96 18 33 1 1337745312 7948368 3549264
15:56:24 41665 8192000000 10278761104 10718953472 1343704576 14.091 98 42 83 2 1365281920 11933936 9643408
15:58:11 81702 8192000000 10278999008 10731515904 1359043456 14.229 99 28 57 1 1377631808 12084192 6504160
16:00:33 84651 8192000000 10255867472 10710564864 1319303472 13.870 97 22 44 0 1343354992 18822336 5229184
16:03:01 43606 8192000000 10246742512 10713128960 1333682784 14.000 99 11 22 2 1357360768 20422912 3255072
16:04:52 54520 8192000000 10259743312 10701996032 1323040560 13.904 97 28 56 0 1337983008 8641264 6301184
16:06:47 34398 8192000000 10255480304 10697351168 1331823792 13.984 98 35 70 1 1350192416 10423232 7945392
16:08:23 99998 8192000000 10273329632 10721988608 1339227872 14.050 98 13 26 0 1355231904 13010064 2993968
16:11:12 81021 8192000000 10289709792 10739875840 1333992576 14.003 96 17 34 2 1350987680 13182864 3812240
16:13:35 82376 8192000000 10272307728 10732511232 1337484592 14.035 97 9 18 0 1356191216 16673776 2032848
16:15:58 29247 8192000000 10308173216 10743664640 1380762272 14.423 100 39 78 0 1402565536 12643440 9159824
16:17:37 103086 8192000000 10278544080 10727976960 1353199520 14.176 98 13 26 0 1368487952 12482624 2805808
16:20:49 59676 8192000000 10290763616 10739396608 1373883216 14.362 99 22 44 0 1392356096 13527488 4945392
16:22:56 28338 8192000000 10293620384 10760527872 1381077712 14.426 100 14 28 1 1406322752 22260368 2984672
16:24:35 68417 8192000000 10281114512 10737553408 1356797248 14.209 98 14 28 0 1371163792 11218608 3147936
16:27:59 56893 8192000000 10282794848 10748706816 1387922592 14.487 100 7 14 0 1398411552 8559456 1929504
16:30:07 86335 8192000000 10298929856 10765393920 1381794960 14.433 99 26 52 0 1403577312 15751552 6030800
16:32:36 59362 8192000000 10288518192 10745729024 1340872064 14.065 96 17 34 0 1354326704 10116032 3338608
16:34:36 84087 8192000000 10321651472 10772471808 1387729424 14.486 99 20 40 1 1410914448 18817152 4367872
16:37:05 72999 8192000000 10296409152 10766770176 1376012800 14.381 99 14 29 2 1400976528 21429136 3534592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 22-06-2022 16:40:01 to 22-06-2022 16:40:02.

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          233190
MON$OLDEST_ACTIVE               233680
MON$OLDEST_SNAPSHOT             233680
MON$NEXT_TRANSACTION            233680
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                       2032800
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:266: 
:267: mscs-test-161.budzdorov.ru    Wed Jun 22 16:38:48 2022
:268:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:269: 
--- end of diff output ---

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

22.06.22 16:40:03. 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-06-22 16:40:06 No severe PSQL-related problems occured
22.06.22 16:40:07. End of report.