Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.09.2021 16:44
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 16224564 kB
MemAvailable 30883352 kB
Buffers 0 kB
Cached 15487160 kB
SwapCached 372 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-09 16:42:57
DTS_END                         2021-09-09 16:42:57.1540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59902
TRN_ID                          241687
ATT_ID                          721
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: 9153.76 20909 1098451
customer order: creation 174.96 348 20995
customer order: refuse 14.64 379 1757
order to supplier: creation 46.78 1262 5613
order to supplier: removal 3.25 112078 390
invoice (draft): creation 47.56 3320 5707
invoice (draft): removal 51.00 912 6120
invoice accept: apply 42.94 18816 5153
invoice accept: cancel 46.09 881 5531
customer reserve: creation 3953.18 186 474381
customer reserve: removal 4470.12 62 536414
realization accept: apply 86.26 183 10351
realization accept: cancel 81.41 122 9769
payment from customer: creation 61.88 115 7426
payment from customer: removal 6.15 94 738
payment to supplier: creation 51.33 131 6159
payment to supplier: removal 3.42 109 410
service: total inventory turnovers 5.20 1169 624
service: total monetary turnovers 6.79 35 815
service: aggregate perf. data 0.78 21068 94
service: refresh index statistics 0.03 256907 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-09-2021 16:44:52 to 09-09-2021 16:44:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4271
TEST_TIME 2 3844
TEST_TIME 3 4446
TEST_TIME 4 5269
TEST_TIME 5 5133
TEST_TIME 6 4912
TEST_TIME 7 5258
TEST_TIME 8 5161
TEST_TIME 9 5640
TEST_TIME 10 5540
TEST_TIME 11 6132
TEST_TIME 12 6360
TEST_TIME 13 6665
TEST_TIME 14 6716
TEST_TIME 15 7254
TEST_TIME 16 7594
TEST_TIME 17 7601
TEST_TIME 18 7560
TEST_TIME 19 7789
TEST_TIME 20 7998
TEST_TIME 21 8273
TEST_TIME 22 8547
TEST_TIME 23 8796
TEST_TIME 24 8861
TEST_TIME 25 9000
TEST_TIME 26 9106
TEST_TIME 27 9859
TEST_TIME 28 10097
TEST_TIME 29 10982
TEST_TIME 30 10910
TEST_TIME 31 10801
TEST_TIME 32 10626
TEST_TIME 33 10416
TEST_TIME 34 10371
TEST_TIME 35 10248
TEST_TIME 36 10148
TEST_TIME 37 9987
TEST_TIME 38 9837
TEST_TIME 39 9827
TEST_TIME 40 9715
TEST_TIME 41 9728
TEST_TIME 42 9621
TEST_TIME 43 9597
TEST_TIME 44 9483
TEST_TIME 45 9447
TEST_TIME 46 9342
TEST_TIME 47 9643
TEST_TIME 48 9564
TEST_TIME 49 9403
TEST_TIME 50 9442
TEST_TIME 51 9365
TEST_TIME 52 9345
TEST_TIME 53 9330
TEST_TIME 54 9193
TEST_TIME 55 9262
TEST_TIME 56 9192
TEST_TIME 57 9164
TEST_TIME 58 9128
TEST_TIME 59 9144
TEST_TIME 60 9255
TEST_TIME 61 9258
TEST_TIME 62 9295
TEST_TIME 63 9363
TEST_TIME 64 9364
TEST_TIME 65 9388
TEST_TIME 66 9504
TEST_TIME 67 9462
TEST_TIME 68 9501
TEST_TIME 69 9503
TEST_TIME 70 9529
TEST_TIME 71 9489
TEST_TIME 72 9426
TEST_TIME 73 9412
TEST_TIME 74 9360
TEST_TIME 75 9348
TEST_TIME 76 9389
TEST_TIME 77 9305
TEST_TIME 78 9289
TEST_TIME 79 9228
TEST_TIME 80 9208
TEST_TIME 81 9141
TEST_TIME 82 9162
TEST_TIME 83 9195
TEST_TIME 84 9162
TEST_TIME 85 9146
TEST_TIME 86 9203
TEST_TIME 87 9310
TEST_TIME 88 9299
TEST_TIME 89 9304
TEST_TIME 90 9289
TEST_TIME 91 9262
TEST_TIME 92 9369
TEST_TIME 93 9321
TEST_TIME 94 9381
TEST_TIME 95 9371
TEST_TIME 96 9318
TEST_TIME 97 9245
TEST_TIME 98 9308
TEST_TIME 99 9288
TEST_TIME 100 9240
TEST_TIME 101 9234
TEST_TIME 102 9212
TEST_TIME 103 9151
TEST_TIME 104 9129
TEST_TIME 105 9114
TEST_TIME 106 9091
TEST_TIME 107 9089
TEST_TIME 108 9131
TEST_TIME 109 9113
TEST_TIME 110 9135
TEST_TIME 111 9135
TEST_TIME 112 9147
TEST_TIME 113 9124
TEST_TIME 114 9201
TEST_TIME 115 9200
TEST_TIME 116 9240
TEST_TIME 117 9215
TEST_TIME 118 9271
TEST_TIME 119 9205
TEST_TIME 120 9162

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-09-2021 16:44:56 to 09-09-2021 16:44:59.

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 1536609 1536609 0 0.00 0 13697 7 0 0
doc_list_biud 1536607 1536607 0 0.00 0 85757 28 0 0
sp_add_invoice_to_stock 5264 5153 111 2.10 28 193374 18816 0 111
sp_cancel_adding_invoice 5531 5531 0 0.00 5 66435 881 0 0
sp_cancel_client_order 1757 1757 0 0.00 4 15059 380 0 0
sp_cancel_customer_reserve 536414 536414 0 0.00 1 85760 63 0 0
sp_cancel_pay_from_customer 738 738 0 0.00 2 5146 95 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 4212 110 0 0
sp_cancel_supplier_invoice 6131 6120 11 0.17 2 77338 912 0 11
sp_cancel_supplier_order 390 390 0 0.00 6 1055940 112078 0 0
sp_cancel_write_off 9769 9769 0 0.00 1 10660 122 0 0
sp_client_order 20995 20995 0 0.00 3 45690 349 0 0
sp_customer_reserve 474381 474381 0 0.00 0 185763 187 0 0
sp_fill_shopping_cart 32305 32305 0 0.00 1 24535 44 0 0
sp_fill_shopping_cart_clo_res 474371 474371 0 0.00 0 16379 14 0 0
sp_get_clo_for_invoice 5140 5140 0 0.00 0 49046 357 0 0
sp_kill_cost_storno 16448 16448 0 0.00 0 6846 60 0 0
sp_kill_qstorno_ret_qs2qd 1089107 1089107 0 0.00 0 85754 34 0 0
sp_kill_qty_storno 575471 575471 0 0.00 0 85757 75 0 0
sp_lock_selected_doc 21336 21336 0 0.00 0 8806 7 0 0
sp_make_cost_storno 29075 29075 0 0.00 0 12201 52 0 0
sp_multiply_rows_for_pdistr 29075 29075 0 0.00 0 7539 28 0 0
sp_multiply_rows_for_qdistr 20995 20995 0 0.00 0 45682 171 0 0
sp_pay_from_customer 7426 7426 0 0.00 4 32951 116 0 0
sp_pay_to_supplier 6159 6159 0 0.00 5 17817 131 0 0
sp_payment_common 13584 13584 0 0.00 1 32935 30 0 0
sp_qd_handle_on_cancel_clo 1757 1757 0 0.00 0 15037 350 0 0
sp_qd_handle_on_invoice_upd_sts 10670 10670 0 0.00 0 42899 353 0 0
sp_qd_handle_on_reserve_upd_sts 10351 10351 0 0.00 0 7833 56 0 0
sp_reserve_write_off 10351 10351 0 0.00 6 15118 184 0 0
sp_supplier_invoice 5707 5707 0 0.00 7 72818 3320 0 0
sp_supplier_order 5613 5613 0 0.00 5 62940 1262 0 0
srv_aggregate_perf_data 94 94 0 0.00 3056 201274 21068 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2075 233588 50697 0 0
srv_find_qd_qs_mism 1048681 1048681 0 0.00 0 13608 6 0 0
srv_make_invnt_saldo 624 624 0 0.00 33 47814 1170 0 0
srv_make_money_saldo 815 815 0 0.00 1 3998 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 184556 388635 256907 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5153 5153 0 0.00 0 9202 177 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 3514 105 0 0
t$perf-norm:sp_cancel_client_order 1757 1757 0 0.00 0 3780 14 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 1318 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 738 738 0 0.00 0 2608 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 7220 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 751 15 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 26089 1882 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 2342 9 0 0
t$perf-norm:sp_client_order 20995 20995 0 0.00 0 9493 22 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 1562 6 0 0
t$perf-norm:sp_pay_from_customer 7426 7426 0 0.00 0 7166 10 0 0
t$perf-norm:sp_pay_to_supplier 6159 6159 0 0.00 0 7120 12 0 0
t$perf-norm:sp_reserve_write_off 10351 10351 0 0.00 0 7323 9 0 0
t$perf-norm:sp_supplier_invoice 5707 5707 0 0.00 0 11880 26 0 0
t$perf-norm:sp_supplier_order 5613 5613 0 0.00 0 7349 20 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 195 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 1363 24 0 0
t$perf-norm:srv_make_invnt_saldo 624 624 0 0.00 0 1042 24 0 0
t$perf-norm:srv_make_money_saldo 815 815 0 0.00 0 5957 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10500 10278 222 2.11 0 5322 21 0 222
v_all_customers 38258 38258 0 0.00 0 79 1 0 0
v_all_suppliers 31520 31520 0 0.00 0 134 1 0 0
v_all_wares 41988 41988 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 420 6 0 0
v_cancel_client_order 3514 3514 0 0.00 0 3955 7 0 0
v_cancel_customer_prepayment 1476 1476 0 0.00 0 5112 17 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 2194 5 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 3522 17 0 0
v_cancel_supplier_invoice 850 828 22 2.58 0 1261 20 0 22
v_cancel_supplier_order 780 780 0 0.00 0 2856 14 0 0
v_cancel_write_off 2032 2032 0 0.00 0 4617 17 0 0
v_max_id_clo_ord 5613 5613 0 0.00 0 683 1 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 3032 11 0 0
v_max_id_ord_sup 5698 5698 0 0.00 0 6118 8 0 0
v_max_non_paid_invoice 1710 1710 0 0.00 0 337 2 0 0
v_max_non_paid_realizn 3971 3971 0 0.00 0 3240 5 0 0
v_min_id_clo_ord 5613 5613 0 0.00 0 4760 12 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 2342 7 0 0
v_min_id_ord_sup 5698 5698 0 0.00 0 3839 8 0 0
v_min_non_paid_invoice 6159 6159 0 0.00 0 5191 15 0 0
v_min_non_paid_realizn 7425 7425 0 0.00 0 32930 23 0 0
v_our_firm 10638 10638 0 0.00 0 17 1 0 0
v_reserve_write_off 20700 20700 0 0.00 0 5658 10 0 0
x_lock_depdocs_on_canc_invoice 11237 11237 0 0.00 0 29914 131 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 22941 475 0 0
x_make_qstorno_1000_1200 5613 5613 0 0.00 1 62930 1167 0 0
x_make_qstorno_1200_2000 5707 5707 0 0.00 2 63390 3172 0 0
x_make_qstorno_2100_3300 474381 474381 0 0.00 0 185763 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-09-2021 16:45:00 to 09-09-2021 16:45:04.

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:13:00 21 8192000000 8820598544 8836784128 23370608 0.284 5 3 5 0 23625408 80544 174256
14:14:01 37011 8192000000 10396745840 10718359552 1456315568 15.093 100 95 190 0 1493497264 6386912 30794784
14:15:49 28558 8192000000 10433049024 10763632640 1484000736 15.336 100 90 177 2 1522070048 8920160 29149152
14:17:24 15145 8192000000 10492397168 10839494656 1551528528 15.923 100 77 154 0 1586079792 10410224 24141040
14:18:46 11836 8192000000 10568721600 10931994624 1631295648 16.606 100 76 152 0 1665185056 11279168 22610240
14:20:02 5310 8192000000 10636482192 11004280832 1698393408 17.172 100 76 153 3 1733240064 13085392 21761264
14:21:08 7414 8192000000 10711650912 11088035840 1769093104 17.760 100 76 152 0 1803221456 13082976 21045376
14:22:17 3202 8192000000 10769324416 11151638528 1819009568 18.170 100 84 167 0 1855045504 12513728 23522208
14:23:22 2182 8192000000 10813548704 11196362752 1866147504 18.553 100 91 181 0 1904881136 13359728 25373904
14:24:26 27265 8192000000 10826038864 11225624576 1883769632 18.696 100 65 131 1 1910801856 7469520 19562704
14:25:54 3814 8192000000 10810328464 11230253056 1896999408 18.802 100 46 92 1 1918324656 8729344 12595904
14:26:59 39302 8192000000 10840506304 11252744192 1912558000 18.927 100 63 125 0 1936596720 5094272 18944448
14:28:39 6439 8192000000 10823666320 11254497280 1909722944 18.904 100 30 60 0 1924843712 6259952 8860816
14:29:47 12527 8192000000 10833746624 11266375680 1919704336 18.984 100 13 26 0 1928995280 5409040 3881904
14:31:00 8770 8192000000 10830459296 11265208320 1917815040 18.969 100 15 30 3 1927332464 5880112 3637312
14:32:11 39193 8192000000 10815456080 11250651136 1876487456 18.637 98 19 36 1 1886951856 5107120 5357280
14:33:51 6589 8192000000 10776226384 11197304832 1830618144 18.264 98 22 44 1 1841942944 5272944 6051856
14:34:58 9850 8192000000 10737306880 11158634496 1793798144 17.963 97 20 40 4 1804761120 5400944 5562032
14:36:11 13333 8192000000 10721491968 11136311296 1770133760 17.768 97 34 65 1 1784874288 5119056 9621472
14:37:27 17473 8192000000 10686597712 11104653312 1678819088 17.007 93 23 45 0 1689883904 4374800 6690016
14:38:47 8353 8192000000 10737220800 11156381696 1819876400 18.177 100 22 44 0 1832376848 6260608 6239840
14:39:58 10452 8192000000 10768765776 11191197696 1849353440 18.417 100 22 44 2 1860851664 5108432 6389792
14:41:10 17454 8192000000 10769299152 11201638400 1863603248 18.532 100 21 42 0 1875501312 5572528 6325536
14:42:29 4394 8192000000 10827093680 11221766144 1877640368 18.646 100 80 162 3 1908314880 6595456 24079056
14:43:37 6402 8192000000 10836184128 11254378496 1906479024 18.878 100 44 87 1 1926028096 6832400 12716672
14:44:50 10982 8192000000 10862465200 11285200896 1929596000 19.064 100 50 98 0 1954606976 11615616 13395360
14:46:08 18357 8192000000 10875599776 11307577344 1946629360 19.200 100 47 92 3 1971688432 11810464 13248608
14:47:31 42073 8192000000 10899346592 11332153344 1944315872 19.181 99 32 64 1 1966300688 12350976 9633840
14:49:17 21273 8192000000 10911787488 11355693056 1975577744 19.430 100 43 86 0 2007660496 19125520 12957232
14:50:59 8331 8192000000 10926795568 11357847552 1976705536 19.439 100 54 108 2 2014783616 22091440 15986640
14:52:21 3597 8192000000 10955172160 11380518912 1999191600 19.616 100 65 130 1 2040759696 21640880 19927216
14:53:28 4357 8192000000 10953295424 11387273216 2003846336 19.653 100 66 132 0 2048797920 25500848 19450736
14:54:37 2824 8192000000 10937158784 11356110848 1968786304 19.376 98 85 169 0 2026522272 32089520 25646448
14:55:42 2073 8192000000 10984030624 11397861376 1992755520 19.566 99 90 178 1 2054538496 35721888 26061088
14:56:50 2931 8192000000 10977839088 11403304960 1997530768 19.603 100 82 164 2 2063288480 40281968 25475744
14:58:00 13472 8192000000 10978656960 11421040640 2012160784 19.719 100 48 95 1 2065105392 37651216 15293392
14:59:19 3481 8192000000 10999421152 11421720576 2017510608 19.761 100 63 124 1 2076372640 39624800 19237232
15:00:26 2154 8192000000 10968855920 11383906304 1973920224 19.417 98 87 174 1 2048026336 46117408 27988704
15:01:36 4422 8192000000 10988043440 11412963328 1991849504 19.558 100 74 147 1 2068459920 53665152 22945264
15:02:45 2430 8192000000 10973467584 11380768768 1972664528 19.407 100 92 183 2 2050597664 48860384 29072752
15:03:51 4102 8192000000 10910096272 11336609792 1931823408 19.081 99 50 98 0 1992576384 44541728 16211248
15:05:04 6026 8192000000 10902946480 11330957312 1925112176 19.028 100 50 100 0 1989644912 48070016 16462720
15:06:17 7743 8192000000 10924189712 11356987392 1936612864 19.120 99 41 82 1 1993160912 43639056 12908992
15:07:28 2730 8192000000 10970762688 11391246336 1977916800 19.448 100 60 120 1 2047562432 50547872 19097760
15:08:31 23314 8192000000 10948268704 11395194880 1964118912 19.339 100 30 60 0 2032415296 57000208 11296176
15:10:08 26998 8192000000 10916566112 11365470208 1950895984 19.234 100 22 44 1 2001528032 42390624 8241424
15:11:54 52899 8192000000 10892267952 11332886528 1930835232 19.074 99 35 70 1 1953603120 11445776 11322112
15:13:48 62748 8192000000 10888913024 11344773120 1961726464 19.320 100 19 38 0 1975311520 8546784 5038272
15:16:07 233588 8192000000 10914567616 11357675520 1883524976 18.694 95 35 72 0 1905774624 11434976 10814672
15:21:05 39744 8192000000 10893946736 11373293568 1976102832 19.434 100 20 41 1 1999241936 17201056 5938048
15:23:48 129417 8192000000 10888847536 11358912512 1893780688 18.776 96 21 42 0 1923702784 23211936 6710160
15:27:14 28387 8192000000 10907750592 11358642176 1975371488 19.428 100 43 85 2 2002130272 14001936 12756848
15:28:50 46355 8192000000 10911703136 11379453952 1978596720 19.454 100 37 74 1 2013399216 22972720 11829776
15:30:56 64881 8192000000 10913021808 11382550528 1994471264 19.579 100 19 38 0 2010918032 11056592 5390176
15:33:03 56050 8192000000 10910544304 11361955840 1960893520 19.313 99 28 54 3 1975449312 7035632 7520160
15:35:00 116470 8192000000 10902632496 11380469760 1975100176 19.426 100 5 10 0 1989687216 13476528 1110512
15:38:12 64448 8192000000 10870009712 11335151616 1885942064 18.713 96 7 14 0 1897461696 9822832 1696800
15:40:18 39481 8192000000 10907268416 11368255488 1966155344 19.355 100 27 54 0 1985815184 11807152 7852688
15:42:12 26307 8192000000 10922283184 11379720192 1982317408 19.483 100 31 62 2 2005070768 13607600 9145760
15:43:43 51411 8192000000 10913541728 11385991168 1969092240 19.378 99 23 46 1 1984307040 9013152 6201648
15:45:34 101952 8192000000 10877199920 11341107200 1802068224 18.031 91 15 30 1 1827999376 21367056 4564096
15:48:16 124355 8192000000 10869794480 11333898240 1847524320 18.402 94 14 28 0 1866005136 14687072 3793744
15:51:30 31537 8192000000 10891803392 11335729152 1866636992 18.557 95 25 49 0 1881329488 7883120 6809376
15:53:14 174311 8192000000 10863338160 11311169536 1800161904 18.015 92 21 42 0 1817274544 10981488 6131152
15:57:09 30169 8192000000 10853736624 11306233856 1877331568 18.644 97 28 56 0 1893695136 8913008 7450560
15:58:43 42472 8192000000 10843803472 11316297728 1905472672 18.870 99 6 12 0 1915229280 8163776 1592832
16:00:30 60890 8192000000 10866565184 11334189056 1925978656 19.035 99 22 45 3 1941757120 9104144 6674320
16:02:35 56042 8192000000 10849195024 11310993408 1905422464 18.870 99 13 26 0 1920383040 11160896 3799680
16:04:39 76044 8192000000 10841339024 11321249792 1884650032 18.703 98 6 12 0 1897917344 11756224 1511088
16:06:56 79769 8192000000 10877270176 11352895488 1930234784 19.069 99 16 32 1 1950673872 15858304 4580784
16:09:19 65965 8192000000 10874570720 11345887232 1932907904 19.090 99 11 22 1 1946442800 10503728 3031168
16:11:26 62406 8192000000 10893311472 11351900160 1920637680 18.992 98 24 48 0 1936770704 9262176 6870848
16:14:24 88993 8192000000 10929110688 11397709824 1945352768 19.189 98 10 20 0 1968117952 20299952 2465232
16:17:01 90678 8192000000 10900356272 11363926016 1926161888 19.036 97 31 63 1 1946002064 10827392 9012784
16:19:34 132093 8192000000 10917463632 11403509760 1978641744 19.454 100 25 50 3 2005884432 19668976 7573712
16:23:00 94110 8192000000 10902402608 11383480320 1966819312 19.360 99 11 22 0 1980407296 10178432 3409552
16:25:37 77486 8192000000 10913200544 11401273344 1999441632 19.618 100 20 40 0 2015477344 9667872 6367840
16:27:54 47409 8192000000 10920808480 11388702720 1960137232 19.307 98 14 26 1 1975066160 10860288 4068640
16:29:53 44880 8192000000 10915003888 11397476352 1974534944 19.421 99 20 40 1 1993382576 13060384 5787248
16:31:41 22967 8192000000 10909475888 11367956480 1957043728 19.283 99 48 96 0 1979761216 8883680 13833808
16:33:07 26297 8192000000 10927075360 11399667712 1999312608 19.617 100 37 74 1 2020245424 10371680 10561136
16:34:47 73608 8192000000 10925596336 11386036224 1973070176 19.410 99 26 52 1 1995438144 14564480 7803488
16:37:02 108834 8192000000 10843554544 11322413056 1823940064 18.210 93 24 48 1 1846561888 15799888 6821936
16:39:52 54496 8192000000 10877230208 11346731008 1926387584 19.038 99 23 46 2 1949374000 15561600 7424816
16:41:49 92372 8192000000 9236138848 9500626944 384001440 4.477 20 5 9 0 389096016 3843008 1251568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-09-2021 16:45:06 to 09-09-2021 16:45:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 09-09-2021 16:45:22 to 09-09-2021 16:45:23.

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          241961
MON$OLDEST_ACTIVE               241962
MON$OLDEST_SNAPSHOT             241962
MON$NEXT_TRANSACTION            241962
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                       2019072
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.33494 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33494 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33494 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33494 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Sep  9 16:43:35 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Sep  9 16:43:35 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
--- end of diff output ---

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

09.09.21 16:45:24. 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-09 16:45:27 No severe PSQL-related problems occured
09.09.21 16:45:28. End of report.