Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.04.2022 19:40
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 16772328 kB
MemAvailable 31614216 kB
Buffers 0 kB
Cached 14975708 kB
SwapCached 2228 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-04-03 19:38:15
DTS_END                         2022-04-03 19:38:15.6060
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40924
TRN_ID                          237976
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: 9273.28 17537 1112794
customer order: creation 173.19 318 20783
customer order: refuse 14.45 327 1734
order to supplier: creation 45.00 1222 5400
order to supplier: removal 3.19 103194 383
invoice (draft): creation 45.88 3165 5506
invoice (draft): removal 51.58 849 6190
invoice accept: apply 42.28 17026 5074
invoice accept: cancel 46.64 800 5597
customer reserve: creation 3943.16 166 473179
customer reserve: removal 4612.43 56 553492
realization accept: apply 85.38 178 10246
realization accept: cancel 80.47 105 9657
payment from customer: creation 59.73 115 7168
payment from customer: removal 5.60 138 672
payment to supplier: creation 48.11 128 5773
payment to supplier: removal 3.38 47 406
service: total inventory turnovers 4.90 1165 588
service: total monetary turnovers 7.04 42 845
service: aggregate perf. data 0.81 19074 97
service: refresh index statistics 0.03 202629 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-04-2022 19:40:35 to 03-04-2022 19:40:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4586
TEST_TIME 2 4396
TEST_TIME 3 4954
TEST_TIME 4 4578
TEST_TIME 5 4600
TEST_TIME 6 5214
TEST_TIME 7 5281
TEST_TIME 8 5753
TEST_TIME 9 5603
TEST_TIME 10 5623
TEST_TIME 11 5655
TEST_TIME 12 6211
TEST_TIME 13 6390
TEST_TIME 14 6594
TEST_TIME 15 6922
TEST_TIME 16 7172
TEST_TIME 17 7644
TEST_TIME 18 7981
TEST_TIME 19 8316
TEST_TIME 20 8297
TEST_TIME 21 8762
TEST_TIME 22 9365
TEST_TIME 23 9324
TEST_TIME 24 9290
TEST_TIME 25 9278
TEST_TIME 26 9051
TEST_TIME 27 9290
TEST_TIME 28 9820
TEST_TIME 29 10663
TEST_TIME 30 10528
TEST_TIME 31 10532
TEST_TIME 32 10476
TEST_TIME 33 10367
TEST_TIME 34 10222
TEST_TIME 35 9963
TEST_TIME 36 10047
TEST_TIME 37 9856
TEST_TIME 38 9802
TEST_TIME 39 9690
TEST_TIME 40 9652
TEST_TIME 41 9773
TEST_TIME 42 9775
TEST_TIME 43 9817
TEST_TIME 44 9764
TEST_TIME 45 9636
TEST_TIME 46 9547
TEST_TIME 47 9577
TEST_TIME 48 9506
TEST_TIME 49 9434
TEST_TIME 50 9336
TEST_TIME 51 9364
TEST_TIME 52 9428
TEST_TIME 53 9376
TEST_TIME 54 9398
TEST_TIME 55 9512
TEST_TIME 56 9560
TEST_TIME 57 9556
TEST_TIME 58 9534
TEST_TIME 59 9670
TEST_TIME 60 9599
TEST_TIME 61 9656
TEST_TIME 62 9817
TEST_TIME 63 9802
TEST_TIME 64 9934
TEST_TIME 65 9896
TEST_TIME 66 9747
TEST_TIME 67 9643
TEST_TIME 68 9667
TEST_TIME 69 9603
TEST_TIME 70 9587
TEST_TIME 71 9571
TEST_TIME 72 9507
TEST_TIME 73 9455
TEST_TIME 74 9421
TEST_TIME 75 9379
TEST_TIME 76 9384
TEST_TIME 77 9347
TEST_TIME 78 9324
TEST_TIME 79 9277
TEST_TIME 80 9397
TEST_TIME 81 9411
TEST_TIME 82 9443
TEST_TIME 83 9511
TEST_TIME 84 9493
TEST_TIME 85 9457
TEST_TIME 86 9547
TEST_TIME 87 9575
TEST_TIME 88 9547
TEST_TIME 89 9590
TEST_TIME 90 9594
TEST_TIME 91 9600
TEST_TIME 92 9665
TEST_TIME 93 9588
TEST_TIME 94 9533
TEST_TIME 95 9511
TEST_TIME 96 9525
TEST_TIME 97 9493
TEST_TIME 98 9452
TEST_TIME 99 9412
TEST_TIME 100 9363
TEST_TIME 101 9321
TEST_TIME 102 9364
TEST_TIME 103 9357
TEST_TIME 104 9344
TEST_TIME 105 9385
TEST_TIME 106 9421
TEST_TIME 107 9449
TEST_TIME 108 9428
TEST_TIME 109 9421
TEST_TIME 110 9464
TEST_TIME 111 9467
TEST_TIME 112 9466
TEST_TIME 113 9495
TEST_TIME 114 9569
TEST_TIME 115 9536
TEST_TIME 116 9460
TEST_TIME 117 9425
TEST_TIME 118 9388
TEST_TIME 119 9346
TEST_TIME 120 9274

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-04-2022 19:40:39 to 03-04-2022 19:40:42.

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 1549719 1549719 0 0.00 0 32373 6 0 0
doc_list_biud 1549718 1549718 0 0.00 0 52975 26 0 0
sp_add_invoice_to_stock 5216 5074 142 2.72 34 138890 17026 0 142
sp_cancel_adding_invoice 5597 5597 0 0.00 4 49006 800 0 0
sp_cancel_client_order 1734 1734 0 0.00 4 9377 327 0 0
sp_cancel_customer_reserve 553492 553492 0 0.00 1 52977 56 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 3 20598 139 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 789 47 0 0
sp_cancel_supplier_invoice 6205 6190 15 0.24 1 16037 849 0 15
sp_cancel_supplier_order 383 383 0 0.00 5 946947 103194 0 0
sp_cancel_write_off 9657 9657 0 0.00 1 24353 106 0 0
sp_client_order 20783 20783 0 0.00 4 26771 319 0 0
sp_customer_reserve 473179 473179 0 0.00 0 115291 167 0 0
sp_fill_shopping_cart 31684 31684 0 0.00 1 19329 39 0 0
sp_fill_shopping_cart_clo_res 473178 473178 0 0.00 0 19052 14 0 0
sp_get_clo_for_invoice 5072 5072 0 0.00 0 17182 275 0 0
sp_kill_cost_storno 16332 16332 0 0.00 0 24304 58 0 0
sp_kill_qstorno_ret_qs2qd 1123214 1123214 0 0.00 0 52969 31 0 0
sp_kill_qty_storno 592371 592371 0 0.00 0 52975 67 0 0
sp_lock_selected_doc 20950 20950 0 0.00 0 9026 5 0 0
sp_make_cost_storno 28259 28259 0 0.00 0 32370 58 0 0
sp_multiply_rows_for_pdistr 28259 28259 0 0.00 0 24424 29 0 0
sp_multiply_rows_for_qdistr 20782 20782 0 0.00 0 13282 148 0 0
sp_pay_from_customer 7168 7168 0 0.00 4 20320 116 0 0
sp_pay_to_supplier 5773 5773 0 0.00 5 24476 128 0 0
sp_payment_common 12941 12941 0 0.00 1 20208 35 0 0
sp_qd_handle_on_cancel_clo 1734 1734 0 0.00 0 9372 296 0 0
sp_qd_handle_on_invoice_upd_sts 10669 10669 0 0.00 0 24627 316 0 0
sp_qd_handle_on_reserve_upd_sts 10246 10246 0 0.00 0 16678 62 0 0
sp_reserve_write_off 10246 10246 0 0.00 6 21686 179 0 0
sp_supplier_invoice 5506 5506 0 0.00 7 69524 3166 0 0
sp_supplier_order 5400 5400 0 0.00 5 34375 1223 0 0
srv_aggregate_perf_data 97 97 0 0.00 2497 231068 19074 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2325 151121 48304 0 0
srv_find_qd_qs_mism 1064038 1064038 0 0.00 0 11132 5 0 0
srv_make_invnt_saldo 588 588 0 0.00 35 119087 1166 0 0
srv_make_money_saldo 845 845 0 0.00 0 15424 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 155332 261154 202630 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5074 5074 0 0.00 0 19020 196 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 18869 140 0 0
t$perf-norm:sp_cancel_client_order 1734 1734 0 0.00 0 794 8 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 21620 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 3025 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 690 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 1261 19 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 27724 1872 0 0
t$perf-norm:sp_cancel_write_off 945 945 0 0.00 0 5293 14 0 0
t$perf-norm:sp_client_order 20783 20783 0 0.00 0 10614 20 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 3573 6 0 0
t$perf-norm:sp_pay_from_customer 7168 7168 0 0.00 0 20714 15 0 0
t$perf-norm:sp_pay_to_supplier 5773 5773 0 0.00 0 23389 12 0 0
t$perf-norm:sp_reserve_write_off 10246 10246 0 0.00 0 10606 15 0 0
t$perf-norm:sp_supplier_invoice 5506 5506 0 0.00 0 10293 23 0 0
t$perf-norm:sp_supplier_order 5400 5400 0 0.00 0 7484 21 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 173 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 154 3 0 0
t$perf-norm:srv_make_invnt_saldo 588 588 0 0.00 0 3155 24 0 0
t$perf-norm:srv_make_money_saldo 845 845 0 0.00 0 771 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 28 9 0 0
v_add_invoice_to_stock 10426 10142 284 2.72 0 5710 19 0 284
v_all_customers 37706 37706 0 0.00 0 107 1 0 0
v_all_suppliers 30410 30410 0 0.00 0 107 1 0 0
v_all_wares 41564 41564 0 0.00 0 181 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 985 7 0 0
v_cancel_client_order 3468 3468 0 0.00 0 3462 7 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 2306 9 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 2464 7 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 765 5 0 0
v_cancel_supplier_invoice 866 836 30 3.46 0 2484 20 0 30
v_cancel_supplier_order 766 766 0 0.00 0 4010 8 0 0
v_cancel_write_off 1890 1890 0 0.00 0 4515 19 0 0
v_max_id_clo_ord 5400 5400 0 0.00 0 7896 4 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 41352 53 0 0
v_max_id_ord_sup 5501 5501 0 0.00 0 8406 7 0 0
v_max_non_paid_invoice 1469 1469 0 0.00 0 1181 4 0 0
v_max_non_paid_realizn 3866 3866 0 0.00 0 737 3 0 0
v_min_id_clo_ord 5400 5400 0 0.00 0 2803 7 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 872 2 0 0
v_min_id_ord_sup 5501 5501 0 0.00 0 8765 16 0 0
v_min_non_paid_invoice 5773 5773 0 0.00 0 20181 27 0 0
v_min_non_paid_realizn 7168 7168 0 0.00 0 19780 29 0 0
v_our_firm 10462 10462 0 0.00 0 99 1 0 0
v_reserve_write_off 20492 20492 0 0.00 0 4077 6 0 0
x_lock_depdocs_on_canc_invoice 11369 11369 0 0.00 0 18397 116 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 8507 370 0 0
x_make_qstorno_1000_1200 5400 5400 0 0.00 2 34361 1140 0 0
x_make_qstorno_1200_2000 5506 5506 0 0.00 3 69513 3028 0 0
x_make_qstorno_2100_3300 473179 473179 0 0.00 0 115290 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-04-2022 19:40:43 to 03-04-2022 19:40:47.

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
17:08:19 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:20 22060 8192000000 9883555536 10192408576 967336304 10.561 100 83 166 0 996075648 5890912 22848432
17:10:46 25404 8192000000 9940185168 10263728128 1019832240 11.070 100 86 172 0 1050026448 7966384 22227824
17:12:18 28309 8192000000 9989375056 10324983808 1066496304 11.519 100 83 166 0 1096793728 9942864 20354560
17:13:52 14974 8192000000 10028508128 10375688192 1116906672 11.998 100 75 150 1 1145149248 10698624 17543952
17:15:09 8260 8192000000 10074356448 10431270912 1161165968 12.414 100 81 164 2 1191351696 11556976 18628752
17:16:21 4760 8192000000 10120361696 10486796288 1210548384 12.874 100 85 169 0 1242058464 12680144 18829936
17:17:30 2405 8192000000 10158870528 10532864000 1241760192 13.162 100 87 174 0 1275302592 14408960 19133440
17:18:35 2401 8192000000 10187035232 10567970816 1271870832 13.439 100 91 184 0 1305769264 13723072 20175360
17:19:39 7360 8192000000 10210396176 10591596544 1289607600 13.601 100 80 160 1 1321468400 14312624 17548176
17:20:49 1931 8192000000 10213914368 10601848832 1298960720 13.686 100 79 160 2 1326434528 9293888 18179920
17:21:52 8161 8192000000 10206259248 10609451008 1308316736 13.771 100 33 66 0 1321798112 6497888 6983488
17:23:01 6071 8192000000 10195744768 10614874112 1314293440 13.825 100 13 26 1 1322962128 5251872 3416816
17:24:07 7521 8192000000 10205336416 10621952000 1317895136 13.858 100 20 40 1 1329107408 6703440 4508832
17:25:19 60449 8192000000 10207550304 10631958528 1322463872 13.899 100 26 52 3 1334163168 5519584 6179712
17:27:23 7209 8192000000 10212690016 10629193728 1299828944 13.694 98 27 54 1 1312732096 6903584 5999568
17:28:35 7119 8192000000 10199099536 10611372032 1288792368 13.593 98 22 42 2 1299688320 5987968 4907984
17:29:42 32235 8192000000 10148495488 10554806272 1192754080 12.709 93 27 54 0 1203601680 4642816 6204784
17:31:31 6250 8192000000 10158419040 10564231168 1255951936 13.293 99 34 68 1 1271104592 7321056 7831600
17:32:40 7419 8192000000 10121073008 10502492160 1165664576 12.456 94 37 74 0 1180779536 6194064 8920896
17:33:50 12362 8192000000 10164246192 10568335360 1256560464 13.298 99 27 54 0 1268083392 4872816 6650112
17:35:06 36393 8192000000 10177779792 10592542720 1287600896 13.582 100 28 55 0 1299498592 4702768 7194928
17:36:42 6931 8192000000 10198626464 10610601984 1304486576 13.736 100 25 50 0 1316127456 5737744 5903136
17:37:52 10994 8192000000 10208127728 10623664128 1316005968 13.841 100 10 20 1 1323215008 4972976 2236064
17:39:08 7615 8192000000 10255502464 10664710144 1346166144 14.113 100 47 94 1 1368554544 11734736 10653664
17:40:21 106054 8192000000 10257969600 10687176704 1358233680 14.221 100 28 56 3 1374919808 10261792 6424336
17:43:14 20464 8192000000 10288888656 10711302144 1376399056 14.384 100 36 72 3 1397976336 12264720 9312560
17:44:42 6691 8192000000 10321303872 10737664000 1396868496 14.567 100 65 127 1 1429597664 17249216 15479952
17:45:58 11933 8192000000 10333296992 10754920448 1406489472 14.653 100 57 114 1 1439247872 19803136 12955264
17:47:19 10961 8192000000 10327270736 10748256256 1405093360 14.640 99 65 128 1 1440933648 21043728 14796560
17:48:35 17577 8192000000 10349832256 10777923584 1408961440 14.675 99 49 98 1 1454859296 33411008 12486848
17:50:03 3909 8192000000 10374191584 10793541632 1424646096 14.814 100 73 142 0 1475311680 33220592 17444992
17:51:10 5995 8192000000 10371433104 10792116224 1408174368 14.668 98 81 160 0 1461557120 34265504 19117248
17:52:20 6032 8192000000 10366996848 10788737024 1415722032 14.735 99 68 133 1 1465202128 33496496 15983600
17:53:34 3591 8192000000 10365062656 10784051200 1413013008 14.711 100 69 138 0 1471047808 39906560 18128240
17:54:44 9933 8192000000 10352660656 10785587200 1413451296 14.715 100 46 92 0 1464597680 38708112 12438272
17:55:57 2324 8192000000 10336211312 10756005888 1384630896 14.458 99 74 146 2 1441675936 39069264 17975776
17:57:06 10911 8192000000 10310075728 10726719488 1358569344 14.225 98 51 99 3 1405551376 34796944 12185088
17:58:23 5074 8192000000 10323342720 10737917952 1368041600 14.309 100 59 117 3 1421331104 37685088 15604416
17:59:34 2962 8192000000 10342755424 10752245760 1372703328 14.351 100 82 164 2 1441260704 47147056 21410320
18:00:44 15892 8192000000 10302982608 10734301184 1368868240 14.317 99 34 68 0 1412639888 34312944 9458704
18:02:06 11704 8192000000 10311803472 10738778112 1377048944 14.390 100 40 79 2 1413767328 26193968 10524416
18:03:23 11655 8192000000 10313914064 10744049664 1384258912 14.455 100 34 68 0 1423451664 30537520 8655232
18:04:45 32378 8192000000 10293342592 10716073984 1347556800 14.125 100 24 48 3 1396313264 41351472 7404992
18:06:19 33073 8192000000 10272481744 10707857408 1308020992 13.768 98 22 44 0 1365513760 50762816 6729952
18:07:54 20950 8192000000 10231336144 10663817216 1298775872 13.684 97 9 19 0 1309359456 8898144 1685440
18:09:19 90087 8192000000 10275578288 10720268288 1344407856 14.097 99 15 30 0 1367463520 19830112 3225552
18:12:03 48724 8192000000 10254198192 10692952064 1345245680 14.105 98 33 66 2 1359025776 7002128 6777968
18:14:21 77290 8192000000 10274784560 10732814336 1373111408 14.355 100 10 20 0 1385928112 10688448 2128256
18:16:45 34457 8192000000 10307429824 10752647168 1394363520 14.545 100 23 45 2 1410631488 10656128 5611840
18:18:20 37212 8192000000 10318752320 10769870848 1400421808 14.599 100 41 80 1 1428640224 18721984 9496432
18:21:31 86735 8192000000 10311879280 10766573568 1382775200 14.441 99 9 18 0 1394411824 9158592 2478032
18:23:59 98258 8192000000 10302254384 10768142336 1391778112 14.522 99 25 50 0 1408306768 10587056 5941600
18:26:59 39712 8192000000 10301915680 10765971456 1399031696 14.586 100 14 28 0 1414116720 11628912 3456112
18:29:25 52778 8192000000 10325830896 10795855872 1404450240 14.635 100 10 20 0 1423467168 16356096 2660832
18:31:19 56843 8192000000 10324374336 10788868096 1413528560 14.715 100 26 52 0 1435557760 16152640 5876560
18:33:16 57603 8192000000 10303131296 10771406848 1386865120 14.478 98 16 32 0 1405667264 15023456 3778688
18:35:14 64466 8192000000 10318893248 10772709376 1371366704 14.339 97 23 46 0 1386260704 9607664 5286336
18:37:42 55538 8192000000 10312700448 10764906496 1349057264 14.139 96 15 30 1 1363917808 11691856 3168688
18:39:43 75600 8192000000 10293495360 10755809280 1318844656 13.866 94 9 18 0 1348018640 26622048 2551936
18:42:30 92464 8192000000 10281889024 10756149248 1364531456 14.278 99 8 17 0 1385719920 19308416 1880048
18:45:55 29288 8192000000 10288657184 10751021056 1378542576 14.404 100 17 34 2 1403582880 20996016 4044288
18:47:24 47987 8192000000 10275056176 10753261568 1363328528 14.267 99 6 12 0 1386163952 21358784 1476640
18:49:14 66572 8192000000 10250629440 10710994944 1327365008 13.943 97 15 31 0 1339885008 8983232 3536768
18:51:35 59167 8192000000 10241762864 10695266304 1334797808 14.010 97 19 38 0 1346759152 7617184 4344160
18:54:36 81535 8192000000 10265809184 10719498240 1360364176 14.241 99 19 38 0 1378896176 13637968 4894032
18:56:58 75899 8192000000 10256907472 10704306176 1317284144 13.852 97 18 36 0 1336392016 14658032 4449840
18:59:21 151121 8192000000 10238342416 10703253504 1228872704 13.044 90 9 18 0 1251129968 20525200 1732064
19:02:52 41848 8192000000 10249673184 10677760000 1309293520 13.780 98 30 60 0 1325694576 9790032 6611024
19:04:41 61459 8192000000 10256817040 10704297984 1333851392 14.002 99 14 28 0 1351935120 15244080 2839648
19:06:49 27689 8192000000 10277202640 10715717632 1342964016 14.084 99 48 96 0 1367231360 13918960 10348384
19:08:18 85089 8192000000 10299098784 10740490240 1360352496 14.241 99 36 72 0 1377476448 9364640 7759312
19:10:44 137936 8192000000 10297055472 10745217024 1373281456 14.356 99 14 28 1 1389042880 12859728 2901696
19:14:23 56294 8192000000 10287470512 10751344640 1391354080 14.518 100 17 34 0 1406777296 11637760 3785456
19:16:22 84756 8192000000 10291883888 10754433024 1386797088 14.477 99 8 17 0 1395637008 7255792 1584128
19:18:49 53994 8192000000 10296638256 10764156928 1400974432 14.604 100 8 16 0 1412780608 10104832 1701344
19:20:43 62358 8192000000 10302089088 10762481664 1403809040 14.629 100 10 20 0 1413318640 7482816 2026784
19:22:49 31944 8192000000 10299834784 10749284352 1392407056 14.527 100 23 46 0 1406607168 8908880 5291232
19:24:30 112613 8192000000 10287482048 10740948992 1348588176 14.135 96 23 46 0 1366619200 12302000 5729024
19:27:23 21529 8192000000 10309405120 10751619072 1381776512 14.432 99 34 68 1 1398904208 9835824 7291872
19:28:47 90968 8192000000 10303318032 10750644224 1339115968 14.049 96 23 46 0 1356323696 11796720 5411008
19:31:26 48407 8192000000 10290787168 10747809792 1369447360 14.322 99 20 40 1 1389021632 14790768 4783504
19:33:24 113648 8192000000 10279162240 10717782016 1348334720 14.132 98 27 52 1 1364533456 11080480 5118256
19:36:18 53392 8192000000 10256804064 10717679616 1334866128 14.011 97 6 12 1 1346233712 10117280 1250304
19:38:12 25488 8192000000 9821888272 10212753408 950355472 10.395 70 4 7 0 959457888 8421440 680976

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 03-04-2022 19:40:49 to 03-04-2022 19:41:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-04-2022 19:41:04 to 03-04-2022 19:41:06.

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          234963
MON$OLDEST_ACTIVE               238146
MON$OLDEST_SNAPSHOT             238146
MON$NEXT_TRANSACTION            238146
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                       2024928
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:963: 
:964: mscs-test-161.budzdorov.ru    Sun Apr  3 19:38:50 2022
:965:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:966: 
:967: 
:968: mscs-test-161.budzdorov.ru    Sun Apr  3 19:38:53 2022
:969:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:970: 
--- end of diff output ---

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

03.04.22 19:41:07. 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-04-03 19:41:10 No severe PSQL-related problems occured
03.04.22 19:41:11. End of report.