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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16020924 kB
MemAvailable 30877228 kB
Buffers 0 kB
Cached 15697464 kB
SwapCached 4792 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-10-27 22:37:57
DTS_END                         2021-10-27 22:37:57.1300
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49984
TRN_ID                          244507
ATT_ID                          686
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: 9404.78 19371 1128573
customer order: creation 178.15 336 21378
customer order: refuse 15.11 372 1813
order to supplier: creation 46.28 1319 5554
order to supplier: removal 3.31 104576 397
invoice (draft): creation 47.45 3361 5694
invoice (draft): removal 53.02 845 6362
invoice accept: apply 43.30 16485 5196
invoice accept: cancel 47.16 738 5659
customer reserve: creation 3972.92 164 476750
customer reserve: removal 4691.19 57 562943
realization accept: apply 85.75 193 10290
realization accept: cancel 87.53 100 10504
payment from customer: creation 61.92 112 7430
payment from customer: removal 5.79 71 695
payment to supplier: creation 50.62 114 6074
payment to supplier: removal 3.38 124 406
service: total inventory turnovers 4.78 1146 574
service: total monetary turnovers 6.23 31 748
service: aggregate perf. data 0.85 22981 102
service: refresh index statistics 0.03 234296 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-10-2021 22:40:17 to 27-10-2021 22:40:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3945
TEST_TIME 2 4633
TEST_TIME 3 3441
TEST_TIME 4 3818
TEST_TIME 5 4141
TEST_TIME 6 4169
TEST_TIME 7 4210
TEST_TIME 8 4258
TEST_TIME 9 5386
TEST_TIME 10 5442
TEST_TIME 11 5640
TEST_TIME 12 5827
TEST_TIME 13 5960
TEST_TIME 14 5929
TEST_TIME 15 5973
TEST_TIME 16 6258
TEST_TIME 17 6577
TEST_TIME 18 6915
TEST_TIME 19 7324
TEST_TIME 20 7410
TEST_TIME 21 7367
TEST_TIME 22 7746
TEST_TIME 23 8371
TEST_TIME 24 9688
TEST_TIME 25 10056
TEST_TIME 26 10290
TEST_TIME 27 10359
TEST_TIME 28 10698
TEST_TIME 29 10472
TEST_TIME 30 10595
TEST_TIME 31 10435
TEST_TIME 32 10544
TEST_TIME 33 10394
TEST_TIME 34 10268
TEST_TIME 35 10342
TEST_TIME 36 10471
TEST_TIME 37 10429
TEST_TIME 38 10472
TEST_TIME 39 10457
TEST_TIME 40 10290
TEST_TIME 41 10079
TEST_TIME 42 10050
TEST_TIME 43 9895
TEST_TIME 44 9796
TEST_TIME 45 9808
TEST_TIME 46 9890
TEST_TIME 47 9860
TEST_TIME 48 9908
TEST_TIME 49 9802
TEST_TIME 50 9789
TEST_TIME 51 9665
TEST_TIME 52 9604
TEST_TIME 53 9543
TEST_TIME 54 9528
TEST_TIME 55 9585
TEST_TIME 56 9557
TEST_TIME 57 9645
TEST_TIME 58 9671
TEST_TIME 59 9758
TEST_TIME 60 9700
TEST_TIME 61 9788
TEST_TIME 62 9776
TEST_TIME 63 9753
TEST_TIME 64 9818
TEST_TIME 65 9765
TEST_TIME 66 9858
TEST_TIME 67 9937
TEST_TIME 68 9877
TEST_TIME 69 9769
TEST_TIME 70 9721
TEST_TIME 71 9638
TEST_TIME 72 9610
TEST_TIME 73 9546
TEST_TIME 74 9555
TEST_TIME 75 9508
TEST_TIME 76 9459
TEST_TIME 77 9530
TEST_TIME 78 9534
TEST_TIME 79 9533
TEST_TIME 80 9520
TEST_TIME 81 9490
TEST_TIME 82 9586
TEST_TIME 83 9599
TEST_TIME 84 9569
TEST_TIME 85 9648
TEST_TIME 86 9635
TEST_TIME 87 9667
TEST_TIME 88 9671
TEST_TIME 89 9834
TEST_TIME 90 9823
TEST_TIME 91 9732
TEST_TIME 92 9683
TEST_TIME 93 9631
TEST_TIME 94 9632
TEST_TIME 95 9580
TEST_TIME 96 9631
TEST_TIME 97 9609
TEST_TIME 98 9606
TEST_TIME 99 9642
TEST_TIME 100 9612
TEST_TIME 101 9594
TEST_TIME 102 9658
TEST_TIME 103 9625
TEST_TIME 104 9618
TEST_TIME 105 9623
TEST_TIME 106 9604
TEST_TIME 107 9659
TEST_TIME 108 9664
TEST_TIME 109 9680
TEST_TIME 110 9652
TEST_TIME 111 9578
TEST_TIME 112 9557
TEST_TIME 113 9552
TEST_TIME 114 9529
TEST_TIME 115 9526
TEST_TIME 116 9479
TEST_TIME 117 9485
TEST_TIME 118 9489
TEST_TIME 119 9449
TEST_TIME 120 9411

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-10-2021 22:40:20 to 27-10-2021 22:40:24.

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 1571241 1571241 0 0.00 0 12911 6 0 0
doc_list_biud 1571241 1571241 0 0.00 0 54058 27 0 0
sp_add_invoice_to_stock 5295 5196 99 1.86 35 204072 16485 0 99
sp_cancel_adding_invoice 5659 5659 0 0.00 5 71331 738 0 0
sp_cancel_client_order 1813 1813 0 0.00 5 17587 373 0 0
sp_cancel_customer_reserve 562943 562943 0 0.00 1 54059 58 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 2868 71 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 6067 125 0 0
sp_cancel_supplier_invoice 6369 6362 7 0.10 2 25211 846 0 7
sp_cancel_supplier_order 397 397 0 0.00 8 1083877 104577 0 0
sp_cancel_write_off 10504 10504 0 0.00 1 11108 101 0 0
sp_client_order 21378 21378 0 0.00 3 25275 336 0 0
sp_customer_reserve 476750 476750 0 0.00 0 75528 164 0 0
sp_fill_shopping_cart 32623 32623 0 0.00 1 16130 39 0 0
sp_fill_shopping_cart_clo_res 476740 476740 0 0.00 0 19431 13 0 0
sp_get_clo_for_invoice 5186 5186 0 0.00 0 32024 257 0 0
sp_kill_cost_storno 17264 17264 0 0.00 0 8242 55 0 0
sp_kill_qstorno_ret_qs2qd 1143149 1143149 0 0.00 0 54056 32 0 0
sp_kill_qty_storno 603150 603150 0 0.00 0 54058 69 0 0
sp_lock_selected_doc 21360 21360 0 0.00 0 7472 4 0 0
sp_make_cost_storno 28976 28976 0 0.00 0 12475 55 0 0
sp_multiply_rows_for_pdistr 28976 28976 0 0.00 0 12897 29 0 0
sp_multiply_rows_for_qdistr 21378 21378 0 0.00 0 16803 154 0 0
sp_pay_from_customer 7430 7430 0 0.00 4 9901 112 0 0
sp_pay_to_supplier 6074 6074 0 0.00 5 12915 115 0 0
sp_payment_common 13504 13504 0 0.00 1 7688 28 0 0
sp_qd_handle_on_cancel_clo 1813 1813 0 0.00 0 17581 334 0 0
sp_qd_handle_on_invoice_upd_sts 10841 10841 0 0.00 0 41892 321 0 0
sp_qd_handle_on_reserve_upd_sts 10290 10290 0 0.00 0 26084 74 0 0
sp_reserve_write_off 10290 10290 0 0.00 6 26102 193 0 0
sp_supplier_invoice 5694 5694 0 0.00 6 97240 3362 0 0
sp_supplier_order 5554 5554 0 0.00 5 42033 1320 0 0
srv_aggregate_perf_data 102 102 0 0.00 3133 338157 22981 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2066 164379 48379 0 0
srv_find_qd_qs_mism 1079898 1079898 0 0.00 0 12056 5 0 0
srv_make_invnt_saldo 574 574 0 0.00 29 84396 1146 0 0
srv_make_money_saldo 748 748 0 0.00 1 2555 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 156484 372118 234296 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 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 5196 5196 0 0.00 0 14436 130 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 0 2919 93 0 0
t$perf-norm:sp_cancel_client_order 1813 1813 0 0.00 0 2536 12 0 0
t$perf-norm:sp_cancel_customer_reserve 739 739 0 0.00 0 3788 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 1174 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 771 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 456 456 0 0.00 0 711 10 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 18142 1505 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 334 2 0 0
t$perf-norm:sp_client_order 21378 21378 0 0.00 0 11134 14 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 2019 9 0 0
t$perf-norm:sp_pay_from_customer 7430 7430 0 0.00 0 6622 9 0 0
t$perf-norm:sp_pay_to_supplier 6074 6074 0 0.00 0 7927 7 0 0
t$perf-norm:sp_reserve_write_off 10290 10290 0 0.00 0 7376 8 0 0
t$perf-norm:sp_supplier_invoice 5694 5694 0 0.00 0 10019 18 0 0
t$perf-norm:sp_supplier_order 5554 5554 0 0.00 0 4424 15 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 1011 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 550 11 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 870 20 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 1811 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10562 10364 198 1.87 0 8856 21 0 198
v_all_customers 39320 39320 0 0.00 0 54 0 0 0
v_all_suppliers 31264 31264 0 0.00 0 21 1 0 0
v_all_wares 42754 42754 0 0.00 0 152 1 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 549 6 0 0
v_cancel_client_order 3626 3626 0 0.00 0 3827 9 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 1830 6 0 0
v_cancel_customer_reserve 1478 1478 0 0.00 0 502 4 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 3266 7 0 0
v_cancel_supplier_invoice 926 912 14 1.51 0 1111 16 0 14
v_cancel_supplier_order 794 794 0 0.00 0 974 5 0 0
v_cancel_write_off 2012 2012 0 0.00 0 2835 16 0 0
v_max_id_clo_ord 5554 5554 0 0.00 0 7613 4 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 2187 14 0 0
v_max_id_ord_sup 5692 5692 0 0.00 0 6028 5 0 0
v_max_non_paid_invoice 1688 1688 0 0.00 0 1895 3 0 0
v_max_non_paid_realizn 3788 3788 0 0.00 0 5704 6 0 0
v_min_id_clo_ord 5554 5554 0 0.00 0 10119 20 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 7999 27 0 0
v_min_id_ord_sup 5692 5692 0 0.00 0 9351 13 0 0
v_min_non_paid_invoice 6074 6074 0 0.00 0 4689 16 0 0
v_min_non_paid_realizn 7430 7430 0 0.00 0 7684 24 0 0
v_our_firm 10718 10718 0 0.00 0 52 1 0 0
v_reserve_write_off 20580 20580 0 0.00 0 4243 6 0 0
x_lock_depdocs_on_canc_invoice 11565 11565 0 0.00 0 11875 106 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 9850 346 0 0
x_make_qstorno_1000_1200 5554 5554 0 0.00 1 42027 1227 0 0
x_make_qstorno_1200_2000 5694 5694 0 0.00 3 97187 3233 0 0
x_make_qstorno_2100_3300 476750 476750 0 0.00 0 75527 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-10-2021 22:40:25 to 27-10-2021 22:40:29.

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:00 13 8192000000 8806972080 8820789248 11741184 0.143 3 3 5 0 11937104 78800 117120
20:09:01 25571 8192000000 9919641472 10233114624 996442272 10.844 100 87 174 0 1024932864 6431568 22059024
20:10:30 27267 8192000000 9956853456 10281713664 1036740512 11.233 100 90 180 0 1069360944 9682160 22938272
20:12:02 16218 8192000000 9984844752 10322440192 1070133056 11.553 100 82 164 0 1101068768 10985792 19949920
20:13:24 18126 8192000000 10045727744 10396499968 1123944960 12.064 100 78 155 0 1154055200 11742192 18368048
20:14:45 11927 8192000000 10089982736 10451714048 1170270464 12.499 100 72 144 0 1199200912 12414176 16516272
20:15:59 5735 8192000000 10126766704 10494349312 1207867632 12.849 100 79 158 0 1239547760 14075280 17604848
20:17:07 6566 8192000000 10162942464 10538848256 1244006736 13.183 100 82 164 3 1275693936 13408784 18278416
20:18:15 2972 8192000000 10192914960 10577506304 1278058304 13.495 100 83 166 1 1310025312 13887184 18079824
20:19:20 1629 8192000000 10213594960 10600677376 1296937664 13.667 100 93 186 2 1330542144 13368496 20235984
20:20:23 1280 8192000000 10219263088 10606743552 1304849264 13.739 100 89 180 0 1336441744 11350368 20242112
20:21:27 9294 8192000000 10188594176 10605166592 1306367024 13.753 100 25 50 1 1319808480 7264352 6177104
20:22:39 11784 8192000000 10205479952 10620022784 1317870976 13.857 100 22 44 0 1328760960 6543440 4346544
20:23:51 7006 8192000000 10206022880 10625327104 1320460928 13.881 100 27 53 1 1333323712 6994400 5868384
20:24:59 33864 8192000000 10242729024 10644377600 1327988128 13.949 100 64 130 1 1348815024 5305680 15521216
20:26:34 7119 8192000000 10228773568 10650566656 1333374720 13.998 100 31 62 3 1347207808 6875728 6957360
20:27:41 5920 8192000000 10216716384 10640666624 1320164976 13.878 99 21 42 0 1330302032 5228464 4908592
20:28:47 13645 8192000000 10167657632 10576887808 1209188848 12.862 93 13 26 1 1217642432 5396352 3057232
20:30:04 37588 8192000000 10153256576 10560032768 1225124736 13.009 96 18 36 0 1235228192 6350960 3752496
20:31:44 7522 8192000000 10124670192 10529779712 1223234784 12.992 98 14 28 0 1232513424 5368352 3910288
20:32:52 9872 8192000000 10143979216 10548514816 1230020128 13.054 98 26 52 0 1241777072 5585120 6171824
20:34:02 6544 8192000000 10172099520 10580922368 1278337424 13.498 100 21 42 2 1292674944 9452560 4884960
20:35:09 34227 8192000000 10170491344 10584772608 1274036032 13.459 99 11 22 1 1281876720 5195024 2645664
20:36:44 7353 8192000000 10197752528 10607132672 1298381376 13.681 100 28 56 1 1310621664 5236208 7004080
20:37:53 12402 8192000000 10201756608 10615320576 1306116000 13.751 100 21 42 1 1316544656 5682944 4745712
20:39:17 11965 8192000000 10261939888 10665889792 1340031680 14.058 100 60 120 1 1369088400 15399632 13657088
20:40:34 53035 8192000000 10260055632 10682568704 1357105776 14.211 100 32 64 0 1374788096 9741536 7940784
20:42:43 38991 8192000000 10267528512 10695823360 1367501088 14.305 100 28 56 0 1386806080 12553296 6751696
20:44:39 8110 8192000000 10298052912 10718687232 1381047024 14.426 100 45 90 1 1410206528 18120656 11038848
20:45:51 18208 8192000000 10333661168 10753507328 1398369200 14.580 100 63 124 0 1440585984 26930800 15285984
20:47:17 15772 8192000000 10333011904 10757099520 1402524592 14.617 100 56 112 0 1448378720 32321712 13532416
20:48:44 7357 8192000000 10348708384 10769670144 1412789600 14.709 100 59 117 1 1457982480 30850544 14342336
20:50:07 7205 8192000000 10367262448 10788831232 1423062864 14.800 100 72 144 1 1473545536 32809024 17673648
20:51:19 3938 8192000000 10360157232 10781605888 1410543504 14.689 99 60 118 2 1460460688 35096144 14821040
20:52:29 3729 8192000000 10376031824 10789801984 1420527536 14.777 100 70 140 0 1482758288 44784832 17445920
20:53:40 3818 8192000000 10376710896 10793308160 1421280496 14.784 100 70 140 1 1482745040 43477328 17987216
20:54:51 7129 8192000000 10347873760 10761629696 1370513712 14.332 97 70 139 1 1430971328 42783904 17673712
20:56:04 2064 8192000000 10349836544 10755436544 1388528320 14.493 100 74 148 0 1450624832 43665008 18431504
20:57:14 6388 8192000000 10339414864 10764697600 1396982208 14.568 100 52 103 1 1454982176 43063072 14936896
20:58:28 3540 8192000000 10337569616 10757767168 1382152368 14.436 100 53 106 1 1448655680 51370128 15133184
20:59:41 8875 8192000000 10336792464 10758590464 1373605968 14.359 100 47 92 1 1438827888 52371440 12850480
21:00:58 14468 8192000000 10303187776 10729123840 1336461344 14.025 99 40 80 0 1406203072 57457680 12284048
21:02:20 46133 8192000000 10289307056 10731515904 1354831360 14.191 99 28 56 2 1387581520 25362016 7388144
21:04:10 29358 8192000000 10283368128 10729684992 1352998192 14.174 99 28 56 0 1379981040 20006416 6976432
21:05:44 69770 8192000000 10273170672 10716143616 1351037600 14.157 99 12 24 3 1372341072 18551824 2751648
21:08:02 58453 8192000000 10264594352 10700652544 1297302608 13.671 95 17 33 0 1311609648 11010576 3296464
21:10:01 96032 8192000000 10264295488 10704306176 1300521536 13.700 95 17 34 1 1313467456 8619504 4326416
21:12:47 117853 8192000000 10284091920 10740334592 1343729664 14.091 98 5 10 0 1362708464 18185824 792976
21:15:45 164379 8192000000 10290918384 10737426432 1355069824 14.193 98 13 26 0 1369202912 10952784 3180304
21:19:40 106398 8192000000 10296739344 10744954880 1364688224 14.279 98 26 52 0 1381536640 10167760 6680656
21:22:27 38826 8192000000 10321138192 10765754368 1391076128 14.515 99 45 90 1 1413918768 12322416 10520224
21:24:11 74002 8192000000 10309457584 10773131264 1404835936 14.638 100 14 28 0 1421622272 13488640 3297696
21:26:35 47543 8192000000 10303775920 10762817536 1378563088 14.404 98 10 21 0 1388638720 7695680 2379952
21:28:25 34879 8192000000 10306843056 10763640832 1401910080 14.612 100 8 15 1 1411500992 8104096 1486816
21:30:18 74913 8192000000 10295581296 10771369984 1385377760 14.465 99 7 14 1 1398657184 11739968 1539456
21:32:35 51439 8192000000 10298570144 10755878912 1358866896 14.227 97 14 28 2 1370928944 8655312 3406736
21:34:29 57083 8192000000 10316441904 10780061696 1375721824 14.378 98 6 12 0 1392891360 15732704 1436832
21:36:35 45307 8192000000 10312154368 10768580608 1375537520 14.377 98 14 28 0 1387574592 8842896 3194176
21:38:23 51504 8192000000 10291630320 10752520192 1328449792 13.953 95 23 46 0 1350684592 16997872 5236928
21:40:15 33214 8192000000 10292096576 10725920768 1318652992 13.865 95 29 58 2 1331296128 6515648 6127488
21:41:50 19480 8192000000 10273309984 10707013632 1316117904 13.842 96 41 78 3 1330935888 6461104 8356880
21:43:16 42770 8192000000 10268845840 10721173504 1341717696 14.073 99 19 38 0 1358043248 12144032 4181520
21:44:59 135061 8192000000 10263739120 10708500480 1296426880 13.663 95 27 54 0 1314434448 12183504 5824064
21:48:32 30884 8192000000 10286818640 10733809664 1370231600 14.329 100 41 82 0 1390937728 11016224 9689904
21:50:04 56312 8192000000 10257219296 10712715264 1355007456 14.193 100 10 21 1 1369983952 12825056 2151440
21:52:07 58722 8192000000 10257775408 10705338368 1328563376 13.954 97 18 34 0 1343899648 11057168 4279104
21:55:21 66014 8192000000 10243390368 10689363968 1280114224 13.514 94 18 36 0 1293703584 9112928 4476432
21:57:30 33765 8192000000 10276532944 10721095680 1358510736 14.224 100 27 54 2 1379052352 14418560 6123056
21:59:10 39317 8192000000 10272842768 10727243776 1339734608 14.055 99 24 48 0 1364910800 19212832 5963360
22:00:56 58005 8192000000 10275336864 10707230720 1320511376 13.881 97 19 38 2 1332015456 7636896 3867184
22:02:56 39445 8192000000 10286976496 10723782656 1339172512 14.050 98 34 69 1 1359505248 12778752 7553984
22:05:43 73336 8192000000 10292444336 10764972032 1367435088 14.304 100 13 26 0 1395132656 24428928 3268640
22:07:57 100559 8192000000 10293857888 10748416000 1380988192 14.425 100 30 58 2 1397230976 8608736 7634048
22:10:40 52068 8192000000 10297060288 10755358720 1381607728 14.431 99 18 36 0 1399677120 14072464 3996928
22:12:39 60832 8192000000 10284232480 10755375104 1373218832 14.356 99 11 22 0 1389061136 13623824 2218480
22:14:46 53109 8192000000 10313220848 10771939328 1390657632 14.512 100 22 45 1 1405260944 9307104 5296208
22:16:42 52542 8192000000 10307712976 10763481088 1380239696 14.419 99 25 51 0 1394734352 8697472 5797184
22:18:36 85241 8192000000 10308317216 10778583040 1389016816 14.497 99 24 48 0 1405320704 10705184 5598704
22:21:04 58349 8192000000 10300549712 10761527296 1373749312 14.361 98 22 44 1 1387884800 9080544 5054944
22:23:04 45064 8192000000 10295934224 10743226368 1330022912 13.967 96 27 54 0 1349086928 13234912 5829104
22:24:51 91439 8192000000 10243367696 10691284992 1238592784 13.133 91 23 45 1 1255206944 11172832 5441328
22:27:23 73684 8192000000 10260258880 10702548992 1329985792 13.967 99 17 34 2 1343115888 9082864 4047232
22:30:05 81248 8192000000 10256104128 10714980352 1328386608 13.953 98 18 36 0 1346914288 14552000 3975680
22:32:28 51913 8192000000 10245559248 10696437760 1317666496 13.856 97 11 22 1 1328975840 8777168 2532176
22:34:20 48597 8192000000 10250441680 10712637440 1346980384 14.120 99 17 34 1 1361984960 10720944 4283632
22:36:12 67567 8192000000 10262471296 10721697792 1348516912 14.134 99 15 30 0 1358924160 7533264 2873984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-10-2021 22:40:31 to 27-10-2021 22:40:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 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 27-10-2021 22:40:46 to 27-10-2021 22:40:48.

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          243503
MON$OLDEST_ACTIVE               244613
MON$OLDEST_SNAPSHOT             244613
MON$NEXT_TRANSACTION            244613
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                       2040352
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.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 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 ---
:356: 
:357: mscs-test-161.budzdorov.ru    Wed Oct 27 22:38:00 2021
:358:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:359: 
:360: 
:361: mscs-test-161.budzdorov.ru    Wed Oct 27 22:38:17 2021
:362:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:363: 
:364: 
:365: mscs-test-161.budzdorov.ru    Wed Oct 27 22:38:20 2021
:366:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:367: 
:368: 
:369: mscs-test-161.budzdorov.ru    Wed Oct 27 22:38:20 2021
:370:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:371: 
--- end of diff output ---

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

27.10.21 22:40:49. 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-10-27 22:40:52 No severe PSQL-related problems occured
27.10.21 22:40:53. End of report.