Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.04.2022 22:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16644032 kB
MemAvailable 31514692 kB
Buffers 0 kB
Cached 15039184 kB
SwapCached 2676 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-21 22:38:13
DTS_END                         2022-04-21 22:38:13.4480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50012
TRN_ID                          236989
ATT_ID                          625
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: 9326.13 28530 1119136
customer order: creation 168.70 362 20244
customer order: refuse 14.68 385 1761
order to supplier: creation 46.55 1219 5586
order to supplier: removal 3.32 114697 398
invoice (draft): creation 44.88 3633 5385
invoice (draft): removal 53.41 853 6409
invoice accept: apply 40.81 19140 4897
invoice accept: cancel 48.40 866 5808
customer reserve: creation 3835.44 185 460253
customer reserve: removal 4778.64 63 573437
realization accept: apply 81.37 182 9764
realization accept: cancel 82.26 116 9871
payment from customer: creation 58.26 106 6991
payment from customer: removal 5.50 68 660
payment to supplier: creation 48.24 138 5789
payment to supplier: removal 3.57 63 428
service: total inventory turnovers 4.93 1106 591
service: total monetary turnovers 6.54 27 785
service: aggregate perf. data 0.63 30803 75
service: refresh index statistics 0.03 396584 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-04-2022 22:39:08 to 21-04-2022 22:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1934
TEST_TIME 2 3085
TEST_TIME 3 3779
TEST_TIME 4 3819
TEST_TIME 5 5407
TEST_TIME 6 5357
TEST_TIME 7 5365
TEST_TIME 8 5357
TEST_TIME 9 5310
TEST_TIME 10 6058
TEST_TIME 11 6412
TEST_TIME 12 6801
TEST_TIME 13 6812
TEST_TIME 14 7213
TEST_TIME 15 7714
TEST_TIME 16 7723
TEST_TIME 17 7870
TEST_TIME 18 7994
TEST_TIME 19 8061
TEST_TIME 20 8488
TEST_TIME 21 8502
TEST_TIME 22 8806
TEST_TIME 23 9207
TEST_TIME 24 9392
TEST_TIME 25 9607
TEST_TIME 26 10063
TEST_TIME 27 10336
TEST_TIME 28 10973
TEST_TIME 29 11277
TEST_TIME 30 11065
TEST_TIME 31 10915
TEST_TIME 32 10766
TEST_TIME 33 10797
TEST_TIME 34 10555
TEST_TIME 35 10400
TEST_TIME 36 10354
TEST_TIME 37 10299
TEST_TIME 38 10381
TEST_TIME 39 10314
TEST_TIME 40 10219
TEST_TIME 41 10154
TEST_TIME 42 9987
TEST_TIME 43 9923
TEST_TIME 44 9842
TEST_TIME 45 10084
TEST_TIME 46 10043
TEST_TIME 47 9905
TEST_TIME 48 9829
TEST_TIME 49 9712
TEST_TIME 50 9641
TEST_TIME 51 9482
TEST_TIME 52 9472
TEST_TIME 53 9483
TEST_TIME 54 9453
TEST_TIME 55 9386
TEST_TIME 56 9297
TEST_TIME 57 9248
TEST_TIME 58 9285
TEST_TIME 59 9247
TEST_TIME 60 9397
TEST_TIME 61 9387
TEST_TIME 62 9373
TEST_TIME 63 9372
TEST_TIME 64 9398
TEST_TIME 65 9480
TEST_TIME 66 9517
TEST_TIME 67 9601
TEST_TIME 68 9528
TEST_TIME 69 9522
TEST_TIME 70 9589
TEST_TIME 71 9564
TEST_TIME 72 9602
TEST_TIME 73 9629
TEST_TIME 74 9584
TEST_TIME 75 9507
TEST_TIME 76 9441
TEST_TIME 77 9416
TEST_TIME 78 9402
TEST_TIME 79 9401
TEST_TIME 80 9417
TEST_TIME 81 9390
TEST_TIME 82 9342
TEST_TIME 83 9287
TEST_TIME 84 9349
TEST_TIME 85 9337
TEST_TIME 86 9346
TEST_TIME 87 9328
TEST_TIME 88 9363
TEST_TIME 89 9334
TEST_TIME 90 9331
TEST_TIME 91 9405
TEST_TIME 92 9452
TEST_TIME 93 9474
TEST_TIME 94 9497
TEST_TIME 95 9532
TEST_TIME 96 9503
TEST_TIME 97 9445
TEST_TIME 98 9525
TEST_TIME 99 9475
TEST_TIME 100 9407
TEST_TIME 101 9381
TEST_TIME 102 9354
TEST_TIME 103 9353
TEST_TIME 104 9301
TEST_TIME 105 9288
TEST_TIME 106 9272
TEST_TIME 107 9212
TEST_TIME 108 9231
TEST_TIME 109 9251
TEST_TIME 110 9225
TEST_TIME 111 9226
TEST_TIME 112 9253
TEST_TIME 113 9304
TEST_TIME 114 9263
TEST_TIME 115 9247
TEST_TIME 116 9311
TEST_TIME 117 9332
TEST_TIME 118 9293
TEST_TIME 119 9292
TEST_TIME 120 9327

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-04-2022 22:39:12 to 21-04-2022 22:39:15.

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 1545151 1545151 0 0.00 0 33433 6 0 0
doc_list_biud 1545151 1545151 0 0.00 0 51068 30 0 0
sp_add_invoice_to_stock 5034 4897 137 2.72 29 244119 19141 0 137
sp_cancel_adding_invoice 5808 5808 0 0.00 4 52125 866 0 0
sp_cancel_client_order 1761 1761 0 0.00 4 9710 385 0 0
sp_cancel_customer_reserve 573437 573437 0 0.00 1 51070 64 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 2 1705 68 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 2 1014 63 0 0
sp_cancel_supplier_invoice 6416 6409 7 0.10 1 12410 854 0 7
sp_cancel_supplier_order 398 398 0 0.00 6 1086419 114698 0 0
sp_cancel_write_off 9871 9871 0 0.00 1 17620 117 0 0
sp_client_order 20244 20244 0 0.00 3 41315 362 0 0
sp_customer_reserve 460253 460253 0 0.00 0 132679 185 0 0
sp_fill_shopping_cart 31205 31205 0 0.00 1 36984 42 0 0
sp_fill_shopping_cart_clo_res 460250 460250 0 0.00 0 20373 16 0 0
sp_get_clo_for_invoice 4893 4893 0 0.00 0 47765 410 0 0
sp_kill_cost_storno 16767 16767 0 0.00 0 28468 60 0 0
sp_kill_qstorno_ret_qs2qd 1163552 1163552 0 0.00 0 51040 34 0 0
sp_kill_qty_storno 612337 612337 0 0.00 0 51068 74 0 0
sp_lock_selected_doc 20357 20357 0 0.00 0 9881 7 0 0
sp_make_cost_storno 27433 27433 0 0.00 0 21166 55 0 0
sp_multiply_rows_for_pdistr 27433 27433 0 0.00 0 7961 30 0 0
sp_multiply_rows_for_qdistr 20244 20244 0 0.00 0 30305 179 0 0
sp_pay_from_customer 6991 6991 0 0.00 4 33305 106 0 0
sp_pay_to_supplier 5789 5789 0 0.00 4 40184 138 0 0
sp_payment_common 12780 12780 0 0.00 1 40175 32 0 0
sp_qd_handle_on_cancel_clo 1761 1761 0 0.00 0 9697 350 0 0
sp_qd_handle_on_invoice_upd_sts 10697 10697 0 0.00 0 15532 342 0 0
sp_qd_handle_on_reserve_upd_sts 9764 9764 0 0.00 0 12559 62 0 0
sp_reserve_write_off 9764 9764 0 0.00 6 12579 182 0 0
sp_supplier_invoice 5385 5385 0 0.00 7 87870 3633 0 0
sp_supplier_order 5586 5586 0 0.00 5 23336 1220 0 0
srv_aggregate_perf_data 75 75 0 0.00 2697 573521 30804 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1176 175872 48033 0 0
srv_find_qd_qs_mism 1071816 1071816 0 0.00 0 33432 5 0 0
srv_make_invnt_saldo 591 591 0 0.00 36 30874 1106 0 0
srv_make_money_saldo 785 785 0 0.00 1 1169 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 204475 668768 396584 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4897 4897 0 0.00 0 24646 240 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 3184 127 0 0
t$perf-norm:sp_cancel_client_order 1761 1761 0 0.00 0 3505 13 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 15968 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 2440 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 3653 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 23084 100 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 41243 2023 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 3625 12 0 0
t$perf-norm:sp_client_order 20244 20244 0 0.00 0 19867 26 0 0
t$perf-norm:sp_customer_reserve 1007 1007 0 0.00 0 674 4 0 0
t$perf-norm:sp_pay_from_customer 6991 6991 0 0.00 0 20468 19 0 0
t$perf-norm:sp_pay_to_supplier 5789 5789 0 0.00 0 18469 17 0 0
t$perf-norm:sp_reserve_write_off 9764 9764 0 0.00 0 13063 23 0 0
t$perf-norm:sp_supplier_invoice 5385 5385 0 0.00 0 13118 19 0 0
t$perf-norm:sp_supplier_order 5586 5586 0 0.00 0 18942 29 0 0
t$perf-norm:srv_aggregate_perf_data 75 75 0 0.00 0 520 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 14 1 0 0
t$perf-norm:srv_make_invnt_saldo 591 591 0 0.00 0 1763 26 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 2773 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10052 9778 274 2.72 0 7353 19 0 274
v_all_customers 36816 36816 0 0.00 0 27 1 0 0
v_all_suppliers 30326 30326 0 0.00 0 25 1 0 0
v_all_wares 40486 40486 0 0.00 0 189 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 97 5 0 0
v_cancel_client_order 3522 3522 0 0.00 0 2041 5 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 1527 10 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 831 5 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 788 5 0 0
v_cancel_supplier_invoice 858 844 14 1.63 0 3466 19 0 14
v_cancel_supplier_order 796 796 0 0.00 0 949 6 0 0
v_cancel_write_off 1962 1962 0 0.00 0 2299 13 0 0
v_max_id_clo_ord 5586 5586 0 0.00 0 410 1 0 0
v_max_id_clo_res 1007 1007 0 0.00 0 2211 12 0 0
v_max_id_ord_sup 5376 5376 0 0.00 0 7389 6 0 0
v_max_non_paid_invoice 1588 1588 0 0.00 0 917 3 0 0
v_max_non_paid_realizn 3642 3642 0 0.00 0 3199 6 0 0
v_min_id_clo_ord 5586 5586 0 0.00 0 6376 6 0 0
v_min_id_clo_res 1007 1007 0 0.00 0 927 3 0 0
v_min_id_ord_sup 5376 5376 0 0.00 0 6186 8 0 0
v_min_non_paid_invoice 5789 5789 0 0.00 0 40142 22 0 0
v_min_non_paid_realizn 6991 6991 0 0.00 0 5210 18 0 0
v_our_firm 10368 10368 0 0.00 0 16 1 0 0
v_reserve_write_off 19528 19528 0 0.00 0 3417 6 0 0
x_lock_depdocs_on_canc_invoice 11795 11795 0 0.00 0 14427 123 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 6476 404 0 0
x_make_qstorno_1000_1200 5586 5586 0 0.00 1 23279 1130 0 0
x_make_qstorno_1200_2000 5385 5385 0 0.00 3 83637 3479 0 0
x_make_qstorno_2100_3300 460253 460253 0 0.00 0 132677 167 0 0

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

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:16 6 8192000000 8805086336 8818561024 9311504 0.113 3 1 2 0 9429232 60320 57408
20:09:18 25157 8192000000 9898820336 10208980992 981947728 10.703 100 89 178 2 1011293664 5656240 23689696
20:10:48 13647 8192000000 9947134656 10266796032 1031840832 11.186 100 90 178 1 1062637504 7562464 23234208
20:12:07 21038 8192000000 9984548560 10314551296 1058744560 11.444 100 82 164 1 1088723632 9145824 20833248
20:13:32 1410 8192000000 10029392944 10372124672 1114949328 11.979 100 82 164 1 1145033280 9852720 20231232
20:14:46 11881 8192000000 10071670400 10429722624 1164085824 12.442 100 75 150 1 1193309792 12010048 17213920
20:16:05 6781 8192000000 10132537472 10502049792 1217984704 12.943 100 80 159 1 1248606160 12581968 18039488
20:17:14 3282 8192000000 10171077072 10543644672 1245606976 13.198 100 86 174 1 1282177264 18057808 18512480
20:18:20 2390 8192000000 10188856992 10567577600 1274467856 13.462 100 86 173 1 1308160944 14335840 19357248
20:19:24 1819 8192000000 10204194288 10589061120 1293611392 13.637 100 89 176 2 1326473296 13112128 19749776
20:20:27 6254 8192000000 10202440800 10600636416 1301680576 13.711 100 48 94 0 1321130928 8357648 11092704
20:21:35 2310 8192000000 10198484720 10608820224 1312143408 13.806 100 32 64 2 1326303888 6757504 7402976
20:22:38 11631 8192000000 10202630448 10616995840 1317747152 13.856 100 17 34 2 1327306592 5602128 3957312
20:23:53 50370 8192000000 10228756608 10638352384 1322894832 13.903 100 34 68 0 1337264944 6146336 8223776
20:25:45 7799 8192000000 10217841392 10639286272 1327113056 13.941 100 23 44 0 1338377664 5459904 5804704
20:26:55 7773 8192000000 10225255984 10638721024 1308095792 13.769 98 24 47 1 1318430160 4921536 5412832
20:28:05 13858 8192000000 10214323856 10626932736 1312069648 13.805 99 32 63 1 1326156752 5891616 8195488
20:29:19 22609 8192000000 10204624544 10621050880 1280816048 13.520 97 10 20 0 1287675424 4911184 1948192
20:30:44 7762 8192000000 10136370192 10536701952 1200827824 12.784 94 15 30 2 1209583824 4862704 3893296
20:31:53 11674 8192000000 10128510784 10522058752 1189651328 12.680 95 14 28 0 1198011504 5229328 3130848
20:33:09 68631 8192000000 10175615760 10572541952 1260699840 13.336 99 32 64 2 1273331520 5113872 7517808
20:35:24 7218 8192000000 10187874096 10591764480 1286748320 13.575 100 28 54 0 1298962688 6206704 6007664
20:36:33 7179 8192000000 10186661712 10603212800 1298464176 13.681 100 12 24 0 1306556736 5613568 2478992
20:37:40 9251 8192000000 10202372784 10617454592 1310736688 13.793 100 21 40 3 1320727424 5064400 4926336
20:38:54 30554 8192000000 10232912848 10640162816 1328450592 13.953 100 61 124 3 1350423312 6197904 15774816
20:40:46 14035 8192000000 10266574912 10680463360 1353050464 14.175 100 49 95 1 1378199280 14375552 10773264
20:42:04 33932 8192000000 10282023184 10701934592 1368479312 14.313 100 47 94 1 1396493808 17293776 10720720
20:43:41 16664 8192000000 10291561200 10715414528 1374157600 14.364 100 26 52 0 1398125360 17155696 6812064
20:45:01 4990 8192000000 10314713344 10732711936 1391440960 14.519 100 61 121 1 1425190080 19683936 14065184
20:46:12 19702 8192000000 10305114064 10732417024 1389869776 14.505 100 41 82 1 1424053136 24179120 10004240
20:47:40 1176 8192000000 10335371664 10754719744 1406057568 14.649 100 68 137 0 1448227504 25920496 16249440
20:48:45 2600 8192000000 10368685328 10786672640 1421503152 14.786 100 74 147 0 1469483200 30576192 17403856
20:49:51 3324 8192000000 10356466256 10769362944 1396793568 14.566 99 76 152 0 1451698800 36629072 18276160
20:50:59 9772 8192000000 10368396736 10791276544 1414910144 14.728 100 63 126 0 1472704400 41623056 16171200
20:52:19 3965 8192000000 10380165168 10793398272 1413596224 14.716 100 85 170 1 1480049136 45633520 20819392
20:53:30 2447 8192000000 10357064736 10763694080 1390022288 14.506 99 81 161 0 1448662208 37832992 20806928
20:54:38 1840 8192000000 10370602960 10778619904 1408460640 14.670 100 85 171 1 1469505104 39766304 21278160
20:55:47 4252 8192000000 10354479552 10771673088 1389138592 14.498 99 70 140 1 1449530448 40835600 19556256
20:56:57 2254 8192000000 10343721664 10749132800 1376147776 14.382 100 77 154 0 1443344528 47557072 19639680
20:58:04 2931 8192000000 10373015568 10782965760 1395303936 14.553 100 91 180 0 1472793760 54354624 23135200
20:59:12 2340 8192000000 10369378256 10778828800 1390172336 14.507 100 82 161 1 1466450512 55287168 20991008
21:00:18 21779 8192000000 10344453520 10775326720 1373626432 14.360 98 40 80 0 1444472848 59621008 11225408
21:01:45 12041 8192000000 10338419040 10764562432 1378696976 14.405 100 44 88 2 1439886896 49779856 11410064
21:03:00 21130 8192000000 10322792720 10753060864 1357861696 14.218 98 27 54 3 1417754960 51987424 7905840
21:04:24 23806 8192000000 10317031984 10747015168 1349282736 14.141 98 29 57 1 1403890400 46437216 8170448
21:05:49 18632 8192000000 10304254976 10744320000 1353389952 14.178 100 19 39 0 1407877664 48302528 6185184
21:07:09 13702 8192000000 10295801616 10727972864 1353777200 14.181 100 42 84 2 1391261520 27578096 9906224
21:08:42 26205 8192000000 10277831776 10724864000 1363458160 14.268 100 17 34 0 1387439168 20047664 3933344
21:10:23 41574 8192000000 10283904992 10727161856 1363887184 14.272 99 10 20 0 1384401440 18391056 2123200
21:12:06 61922 8192000000 10288118640 10727403520 1356725712 14.208 98 32 65 0 1375960304 12087808 7146784
21:14:15 143746 8192000000 10304338336 10757144576 1379350368 14.411 100 15 30 0 1399574656 17006016 3218272
21:17:56 175872 8192000000 10288061904 10727587840 1330237984 13.969 96 20 39 0 1343653664 9844048 3571632
21:21:55 39394 8192000000 10332122784 10768654336 1402792912 14.620 100 38 77 0 1424543808 12674960 9075936
21:23:36 40555 8192000000 10304049280 10769547264 1396330336 14.562 100 15 29 0 1417055920 17616608 3108976
21:25:25 37356 8192000000 10302270176 10750877696 1396174016 14.561 100 32 63 1 1410378224 6436528 7767680
21:27:24 71032 8192000000 10307483584 10763907072 1388896752 14.496 99 17 34 0 1405711472 13216512 3598208
21:29:35 33917 8192000000 10301759328 10758348800 1389878544 14.505 99 35 70 2 1408385040 10159664 8346832
21:31:21 69159 8192000000 10285767472 10752368640 1350306784 14.150 96 24 48 1 1364483776 8914128 5262864
21:33:38 36310 8192000000 10292441008 10741747712 1350705568 14.154 96 19 38 1 1374595392 19799808 4090016
21:35:18 68281 8192000000 10296122528 10758828032 1398675856 14.583 100 16 32 0 1419432752 16974784 3782112
21:37:27 46975 8192000000 10300489888 10755092480 1391602400 14.520 100 22 45 1 1412082448 15249792 5230256
21:39:46 51183 8192000000 10297096688 10754297856 1362316752 14.258 97 16 32 1 1376092080 10154768 3620560
21:41:38 37480 8192000000 10311796176 10762108928 1397414416 14.572 100 29 59 1 1421362240 17156416 6791408
21:43:23 66160 8192000000 10292231040 10750509056 1375575472 14.377 99 16 32 0 1398739488 19156368 4007648
21:45:30 61496 8192000000 10280010624 10711924736 1300093792 13.696 95 36 72 1 1321373392 13859280 7420320
21:47:33 47140 8192000000 10262575744 10707808256 1333411600 13.998 98 46 92 2 1361281616 17748752 10121264
21:49:22 41875 8192000000 10265463632 10708176896 1326398160 13.935 97 18 36 0 1348831280 18459184 3973936
21:51:39 148078 8192000000 10262104128 10703560704 1293771040 13.639 95 24 48 0 1308379488 9002704 5605744
21:55:08 110518 8192000000 10257310560 10716590080 1344270128 14.096 99 25 50 1 1368128224 17899680 5958416
21:58:11 33299 8192000000 10258004528 10713968640 1350191328 14.149 99 29 59 2 1367440592 10439712 6809552
21:59:50 69859 8192000000 10262008768 10708504576 1314860544 13.830 96 17 34 0 1331834880 12759152 4215184
22:02:11 97608 8192000000 10268972560 10741018624 1371914640 14.344 100 13 26 0 1390837248 15716848 3205760
22:04:53 68174 8192000000 10252640752 10699649024 1337173040 14.032 98 15 29 0 1348249888 8085232 2991616
22:07:02 91292 8192000000 10270086016 10722881536 1328041648 13.949 97 24 48 1 1346620976 13083440 5495888
22:09:33 65215 8192000000 10269624448 10727186432 1352809600 14.173 99 35 69 1 1374447520 13275984 8361936
22:11:49 92606 8192000000 10289455312 10749366272 1362406944 14.259 99 9 18 0 1378775520 14051568 2317008
22:14:24 40911 8192000000 10302604736 10740154368 1384145760 14.454 100 15 30 0 1396391664 8696800 3549104
22:16:05 15635 8192000000 10305623104 10755366912 1384080288 14.453 100 49 98 0 1407891472 13097776 10713408
22:17:24 126485 8192000000 10282632448 10738302976 1371732656 14.343 99 44 88 0 1395394976 14166576 9495744
22:20:52 76451 8192000000 10279820400 10744893440 1372480896 14.349 99 14 28 1 1386077552 10551824 3044832
22:23:08 51252 8192000000 10275984496 10723868672 1337359984 14.034 97 17 35 0 1350782896 9234112 4188800
22:25:00 87912 8192000000 10286888240 10750259200 1377299040 14.392 99 13 26 2 1396418336 16113424 3005872
22:27:29 92868 8192000000 10274121136 10726350848 1345585792 14.108 97 16 32 0 1359810368 10664672 3559904
22:30:02 82631 8192000000 10276527728 10741112832 1370784384 14.334 99 22 44 2 1386873920 11281264 4808272
22:32:38 30307 8192000000 10272478544 10704252928 1289148880 13.596 94 45 91 1 1310246608 11223216 9874512
22:34:26 128618 8192000000 10274186560 10721705984 1321114416 13.887 97 12 24 0 1340121344 16323568 2683360
22:37:38 50849 8192000000 9180647024 9440661504 330443072 3.877 25 3 6 0 339841040 8951360 446608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-04-2022 22:39:22 to 21-04-2022 22:39:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-04-2022 22:39:38 to 21-04-2022 22:39:40.

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          236896
MON$OLDEST_ACTIVE               237268
MON$OLDEST_SNAPSHOT             237268
MON$NEXT_TRANSACTION            237268
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                       2029024
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:1414: 
:1415: mscs-test-161.budzdorov.ru    Thu Apr 21 22:38:35 2022
:1416:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1417: 
:1418: 
:1419: mscs-test-161.budzdorov.ru    Thu Apr 21 22:38:35 2022
:1420:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1421: 
:1422: 
:1423: mscs-test-161.budzdorov.ru    Thu Apr 21 22:38:35 2022
:1424:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1425: 
--- end of diff output ---

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

21.04.22 22:39:41. 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-21 22:39:43 No severe PSQL-related problems occured
21.04.22 22:39:44. End of report.