Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.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 15982664 kB
MemAvailable 30750796 kB
Buffers 0 kB
Cached 15731748 kB
SwapCached 4696 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-03 22:37:48
DTS_END                         2021-10-03 22:37:48.8490
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/41762
TRN_ID                          248252
ATT_ID                          682
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: 9308.32 18179 1116998
customer order: creation 184.03 335 22083
customer order: refuse 15.86 330 1903
order to supplier: creation 47.24 1373 5669
order to supplier: removal 3.50 91866 420
invoice (draft): creation 48.16 3495 5779
invoice (draft): removal 52.24 750 6269
invoice accept: apply 43.08 18107 5169
invoice accept: cancel 46.62 801 5594
customer reserve: creation 3937.54 181 472505
customer reserve: removal 4619.73 54 554367
realization accept: apply 87.90 177 10548
realization accept: cancel 85.46 109 10255
payment from customer: creation 62.31 118 7477
payment from customer: removal 6.03 86 724
payment to supplier: creation 52.13 151 6255
payment to supplier: removal 3.67 80 440
service: total inventory turnovers 5.28 1025 633
service: total monetary turnovers 6.63 44 796
service: aggregate perf. data 0.90 21263 108
service: refresh index statistics 0.03 223240 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-10-2021 22:39:40 to 03-10-2021 22:39:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3713
TEST_TIME 2 3438
TEST_TIME 3 3435
TEST_TIME 4 3854
TEST_TIME 5 4916
TEST_TIME 6 4690
TEST_TIME 7 5047
TEST_TIME 8 5551
TEST_TIME 9 5456
TEST_TIME 10 5444
TEST_TIME 11 5615
TEST_TIME 12 5889
TEST_TIME 13 6317
TEST_TIME 14 6564
TEST_TIME 15 7643
TEST_TIME 16 7713
TEST_TIME 17 7433
TEST_TIME 18 7260
TEST_TIME 19 7556
TEST_TIME 20 7595
TEST_TIME 21 7760
TEST_TIME 22 7996
TEST_TIME 23 8260
TEST_TIME 24 8412
TEST_TIME 25 8466
TEST_TIME 26 8352
TEST_TIME 27 8302
TEST_TIME 28 8259
TEST_TIME 29 8270
TEST_TIME 30 8696
TEST_TIME 31 9043
TEST_TIME 32 9893
TEST_TIME 33 9762
TEST_TIME 34 9613
TEST_TIME 35 9629
TEST_TIME 36 9778
TEST_TIME 37 9832
TEST_TIME 38 9912
TEST_TIME 39 9935
TEST_TIME 40 9777
TEST_TIME 41 9598
TEST_TIME 42 9602
TEST_TIME 43 9668
TEST_TIME 44 9503
TEST_TIME 45 9381
TEST_TIME 46 9396
TEST_TIME 47 9396
TEST_TIME 48 9565
TEST_TIME 49 9568
TEST_TIME 50 9486
TEST_TIME 51 9424
TEST_TIME 52 9377
TEST_TIME 53 9359
TEST_TIME 54 9258
TEST_TIME 55 9263
TEST_TIME 56 9227
TEST_TIME 57 9154
TEST_TIME 58 9207
TEST_TIME 59 9174
TEST_TIME 60 9131
TEST_TIME 61 9035
TEST_TIME 62 9046
TEST_TIME 63 9039
TEST_TIME 64 9008
TEST_TIME 65 9008
TEST_TIME 66 9118
TEST_TIME 67 9165
TEST_TIME 68 9168
TEST_TIME 69 9193
TEST_TIME 70 9228
TEST_TIME 71 9245
TEST_TIME 72 9277
TEST_TIME 73 9355
TEST_TIME 74 9331
TEST_TIME 75 9408
TEST_TIME 76 9360
TEST_TIME 77 9269
TEST_TIME 78 9242
TEST_TIME 79 9182
TEST_TIME 80 9237
TEST_TIME 81 9174
TEST_TIME 82 9129
TEST_TIME 83 9085
TEST_TIME 84 9028
TEST_TIME 85 9042
TEST_TIME 86 9085
TEST_TIME 87 9092
TEST_TIME 88 9090
TEST_TIME 89 9072
TEST_TIME 90 9088
TEST_TIME 91 9103
TEST_TIME 92 9179
TEST_TIME 93 9198
TEST_TIME 94 9225
TEST_TIME 95 9236
TEST_TIME 96 9308
TEST_TIME 97 9261
TEST_TIME 98 9283
TEST_TIME 99 9267
TEST_TIME 100 9204
TEST_TIME 101 9196
TEST_TIME 102 9156
TEST_TIME 103 9151
TEST_TIME 104 9106
TEST_TIME 105 9051
TEST_TIME 106 9037
TEST_TIME 107 9073
TEST_TIME 108 9059
TEST_TIME 109 9186
TEST_TIME 110 9179
TEST_TIME 111 9170
TEST_TIME 112 9183
TEST_TIME 113 9246
TEST_TIME 114 9330
TEST_TIME 115 9322
TEST_TIME 116 9281
TEST_TIME 117 9298
TEST_TIME 118 9283
TEST_TIME 119 9311
TEST_TIME 120 9309

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-10-2021 22:39:44 to 03-10-2021 22:39:47.

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 1554843 1554843 0 0.00 0 28027 7 0 0
doc_list_biud 1554843 1554843 0 0.00 0 37885 25 0 0
sp_add_invoice_to_stock 5309 5169 140 2.63 39 224688 18107 0 140
sp_cancel_adding_invoice 5594 5594 0 0.00 5 63374 801 0 0
sp_cancel_client_order 1903 1903 0 0.00 4 7268 330 0 0
sp_cancel_customer_reserve 554367 554367 0 0.00 1 27772 54 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 3 9748 86 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 2 4535 80 0 0
sp_cancel_supplier_invoice 6287 6269 18 0.28 2 20726 751 0 18
sp_cancel_supplier_order 420 420 0 0.00 7 841532 91867 0 0
sp_cancel_write_off 10255 10255 0 0.00 1 15036 110 0 0
sp_client_order 22083 22083 0 0.00 3 36875 335 0 0
sp_customer_reserve 472505 472505 0 0.00 0 45528 182 0 0
sp_fill_shopping_cart 33531 33531 0 0.00 1 20153 39 0 0
sp_fill_shopping_cart_clo_res 472501 472501 0 0.00 0 21764 13 0 0
sp_get_clo_for_invoice 5165 5165 0 0.00 0 21183 267 0 0
sp_kill_cost_storno 17013 17013 0 0.00 0 6890 51 0 0
sp_kill_qstorno_ret_qs2qd 1125678 1125678 0 0.00 0 27765 29 0 0
sp_kill_qty_storno 594521 594521 0 0.00 0 37881 64 0 0
sp_lock_selected_doc 21858 21858 0 0.00 0 9741 6 0 0
sp_make_cost_storno 29445 29445 0 0.00 0 14471 54 0 0
sp_multiply_rows_for_pdistr 29445 29445 0 0.00 0 10369 28 0 0
sp_multiply_rows_for_qdistr 22083 22083 0 0.00 0 12788 144 0 0
sp_pay_from_customer 7477 7477 0 0.00 4 14716 118 0 0
sp_pay_to_supplier 6255 6255 0 0.00 4 14030 151 0 0
sp_payment_common 13732 13732 0 0.00 2 14022 34 0 0
sp_qd_handle_on_cancel_clo 1903 1903 0 0.00 0 6186 289 0 0
sp_qd_handle_on_invoice_upd_sts 10759 10759 0 0.00 0 37877 332 0 0
sp_qd_handle_on_reserve_upd_sts 10548 10548 0 0.00 0 14399 55 0 0
sp_reserve_write_off 10548 10548 0 0.00 6 14416 177 0 0
sp_supplier_invoice 5779 5779 0 0.00 6 111802 3496 0 0
sp_supplier_order 5669 5669 0 0.00 5 35899 1374 0 0
srv_aggregate_perf_data 108 108 0 0.00 2800 255183 21264 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 3067 110259 45276 0 0
srv_find_qd_qs_mism 1067236 1067236 0 0.00 0 28026 6 0 0
srv_make_invnt_saldo 633 633 0 0.00 36 44910 1026 0 0
srv_make_money_saldo 796 796 0 0.00 0 11359 44 0 0
srv_recalc_idx_stat 4 4 0 0.00 101191 313230 223240 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5169 5169 0 0.00 0 9451 157 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 1859 77 0 0
t$perf-norm:sp_cancel_client_order 1903 1903 0 0.00 0 8745 14 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 6831 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 1261 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 1161 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 751 22 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 23051 1595 0 0
t$perf-norm:sp_cancel_write_off 1084 1084 0 0.00 0 1679 7 0 0
t$perf-norm:sp_client_order 22083 22083 0 0.00 0 7277 18 0 0
t$perf-norm:sp_customer_reserve 1100 1100 0 0.00 0 965 5 0 0
t$perf-norm:sp_pay_from_customer 7477 7477 0 0.00 0 5330 8 0 0
t$perf-norm:sp_pay_to_supplier 6255 6255 0 0.00 0 9353 12 0 0
t$perf-norm:sp_reserve_write_off 10548 10548 0 0.00 0 7950 12 0 0
t$perf-norm:sp_supplier_invoice 5779 5779 0 0.00 0 13183 20 0 0
t$perf-norm:sp_supplier_order 5669 5669 0 0.00 0 4816 18 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 94 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1811 31 0 0
t$perf-norm:srv_make_invnt_saldo 633 633 0 0.00 0 631 19 0 0
t$perf-norm:srv_make_money_saldo 796 796 0 0.00 0 3365 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 58 16 0 0
v_add_invoice_to_stock 10610 10330 280 2.63 0 7165 20 0 280
v_all_customers 40128 40128 0 0.00 0 22 1 0 0
v_all_suppliers 31646 31646 0 0.00 0 22 1 0 0
v_all_wares 44166 44166 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 2616 11 0 0
v_cancel_client_order 3806 3806 0 0.00 0 5253 8 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 2145 8 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 1726 7 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 1292 7 0 0
v_cancel_supplier_invoice 914 878 36 3.93 0 3005 18 0 36
v_cancel_supplier_order 840 840 0 0.00 0 1956 7 0 0
v_cancel_write_off 2168 2168 0 0.00 0 5416 18 0 0
v_max_id_clo_ord 5669 5669 0 0.00 0 384 1 0 0
v_max_id_clo_res 1100 1100 0 0.00 0 2590 13 0 0
v_max_id_ord_sup 5779 5779 0 0.00 0 2456 2 0 0
v_max_non_paid_invoice 1880 1880 0 0.00 0 4176 7 0 0
v_max_non_paid_realizn 4036 4036 0 0.00 0 2680 4 0 0
v_min_id_clo_ord 5669 5669 0 0.00 0 6389 6 0 0
v_min_id_clo_res 1100 1100 0 0.00 0 2336 4 0 0
v_min_id_ord_sup 5779 5779 0 0.00 0 7253 8 0 0
v_min_non_paid_invoice 6255 6255 0 0.00 0 14020 25 0 0
v_min_non_paid_realizn 7477 7477 0 0.00 0 12464 20 0 0
v_our_firm 10920 10920 0 0.00 0 20 1 0 0
v_reserve_write_off 21096 21096 0 0.00 0 4948 6 0 0
x_lock_depdocs_on_canc_invoice 11424 11424 0 0.00 0 21742 135 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 18122 443 0 0
x_make_qstorno_1000_1200 5669 5669 0 0.00 2 35487 1296 0 0
x_make_qstorno_1200_2000 5779 5779 0 0.00 3 102517 3346 0 0
x_make_qstorno_2100_3300 472505 472505 0 0.00 0 45526 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-10-2021 22:39:48 to 03-10-2021 22:39:52.

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:07:52 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:53 13259 8192000000 10349998048 10663661568 1405971792 14.648 100 91 182 0 1441999232 5653040 30374400
20:10:10 26130 8192000000 10431512832 10757763072 1479250416 15.295 100 93 186 0 1517326192 7873424 30202352
20:11:38 20988 8192000000 10481435840 10816385024 1533711200 15.769 100 88 176 1 1571177104 9859040 27606864
20:13:05 17309 8192000000 10537199456 10893930496 1600358208 16.342 100 77 154 1 1634801920 10887184 23556528
20:14:24 9596 8192000000 10620275088 10988576768 1679238272 17.011 100 71 142 1 1711738624 11744016 20756336
20:15:39 5923 8192000000 10693492848 11066761216 1750656704 17.607 100 83 162 0 1786723616 12409760 23657152
20:16:48 10719 8192000000 10732426416 11119521792 1799672624 18.011 100 72 144 4 1831940640 12206480 20061536
20:18:00 2709 8192000000 10786735984 11172048896 1845230624 18.383 100 87 175 1 1883041488 13091280 24719584
20:19:05 7512 8192000000 10810794048 11204349952 1874642416 18.622 100 87 174 1 1911298752 12401088 24255248
20:20:13 3202 8192000000 10797156192 11210330112 1878534352 18.653 100 56 114 0 1903529136 8965632 16029152
20:21:19 5533 8192000000 10805849616 11221204992 1878999600 18.657 100 24 48 0 1890578640 5482048 6096992
20:22:26 6899 8192000000 10809284848 11236859904 1896306768 18.797 100 31 62 2 1911999584 7242240 8450576
20:23:36 73572 8192000000 10813722448 11247652864 1902480400 18.846 100 34 69 0 1919255984 5970160 10805424
20:25:51 3851 8192000000 10841127536 11267690496 1915368640 18.950 100 24 48 0 1928784976 6378256 7038080
20:26:56 11306 8192000000 10817117280 11245395968 1854609872 18.460 97 19 37 1 1864947152 5066592 5270688
20:28:07 18278 8192000000 10772688208 11199623168 1802923312 18.038 96 19 37 1 1813300704 5297568 5079824
20:29:27 9430 8192000000 10773735520 11198279680 1839355296 18.336 99 23 46 1 1856438912 10295520 6788096
20:30:37 8137 8192000000 10722067360 11131015168 1747980784 17.585 95 36 73 0 1764792736 6673792 10138160
20:31:47 8880 8192000000 10708089472 11125985280 1753970224 17.634 97 20 40 1 1766210640 5546432 6693984
20:32:56 54815 8192000000 10760236496 11171569664 1820049952 18.178 99 36 72 0 1835568608 4801248 10717408
20:35:04 5001 8192000000 10767592832 11197259776 1853023136 18.447 100 29 57 0 1872302208 10703712 8575360
20:36:09 8188 8192000000 10783331072 11216437248 1869231008 18.578 100 22 42 1 1880906864 5710688 5965168
20:37:17 15199 8192000000 10819984768 11252797440 1900905456 18.834 100 8 16 1 1907486512 4667104 1913952
20:38:38 9446 8192000000 10870037568 11282432000 1933628224 19.096 100 71 145 1 1963637872 8083072 21926576
20:39:49 78056 8192000000 10875605760 11303383040 1942334256 19.165 100 40 80 2 1962130368 7952960 11843152
20:42:23 15538 8192000000 10904581392 11338031104 1966508512 19.358 100 39 78 0 1989963600 12157856 11297232
20:43:44 3067 8192000000 10926000624 11356778496 1988279008 19.530 100 61 119 2 2018484384 12097008 18108368
20:44:52 13095 8192000000 10927057936 11365203968 1992951936 19.567 100 54 108 3 2021563280 13257392 15353952
20:46:10 6058 8192000000 10943348864 11378618368 2000716336 19.628 100 56 112 1 2028668080 11379792 16571952
20:47:22 15832 8192000000 10928672896 11371270144 1978779664 19.455 99 34 68 1 2002668656 13913200 9975792
20:48:40 7320 8192000000 10923457120 11352006656 1961867120 19.321 98 49 96 0 1991419584 16015120 13537344
20:49:54 7757 8192000000 10965349872 11399606272 2010480896 19.705 100 61 120 0 2051328768 23100112 17747760
20:51:07 3189 8192000000 10933515680 11346993152 1955698128 19.272 99 88 174 2 2004372736 22841008 25833600
20:52:15 3703 8192000000 10954290512 11379286016 1987318864 19.523 100 80 159 3 2039134016 28682656 23132496
20:53:22 27141 8192000000 10876226224 11320025088 1943582384 19.175 100 41 83 0 1974867296 18180560 13104352
20:55:31 3487 8192000000 10853413856 11275214848 1891991296 18.762 98 51 100 0 1931139584 24015616 15132672
20:56:41 4791 8192000000 10892477600 11323899904 1907707152 18.888 98 59 120 2 1956694800 30983488 18004160
20:57:56 6032 8192000000 10876832736 11293540352 1891658288 18.759 99 72 141 1 1952413168 38337376 22417504
20:59:08 3118 8192000000 10899110368 11311968256 1917440048 18.966 100 60 120 1 1977850928 42081968 18328912
21:00:22 15656 8192000000 10868084624 11318620160 1918060976 18.971 100 43 86 1 1971606224 39843936 13701312
21:01:41 20816 8192000000 10904297808 11323781120 1929380096 19.062 100 34 70 3 1979561312 38580288 11600928
21:03:06 3864 8192000000 10919767840 11339059200 1940584288 19.151 100 53 105 0 1992378496 35137792 16656416
21:04:20 16734 8192000000 10897188832 11336966144 1922403408 19.006 99 30 60 0 1975686128 43307696 9975024
21:05:40 10885 8192000000 10908330592 11350315008 1942114720 19.164 100 31 60 0 1996990816 44958432 9917664
21:06:53 20345 8192000000 10882429104 11326660608 1898724160 18.816 99 26 52 1 1959951280 51386720 9840400
21:08:46 39767 8192000000 10879519376 11338629120 1941552592 19.159 100 17 34 0 1980966800 33490240 5923968
21:10:27 12287 8192000000 10913828608 11344556032 1970396208 19.389 100 57 115 5 1993804896 6996592 16412096
21:11:40 50278 8192000000 10932226704 11373203456 1982282080 19.483 100 40 81 0 2011101040 15464848 13354112
21:13:32 101589 8192000000 10922831952 11385991168 1984555856 19.501 100 21 42 0 2013423968 22933888 5934224
21:16:15 80648 8192000000 10928227152 11386167296 1974956016 19.425 99 15 30 0 1988781824 9502368 4323440
21:18:42 110259 8192000000 10939831040 11424555008 2011187376 19.711 100 34 68 0 2034647664 12818784 10641504
21:21:45 41226 8192000000 10970146768 11426861056 2023721904 19.809 100 22 43 1 2042269760 11719024 6828832
21:23:29 59819 8192000000 10949651408 11423432704 1998106832 19.608 99 20 40 2 2021613136 17331488 6174816
21:25:31 46022 8192000000 10919748640 11406618624 1967533632 19.366 98 12 24 0 1985340496 14454832 3352032
21:27:31 32784 8192000000 10919383312 11400200192 1998347024 19.610 100 6 11 0 2006791408 7043616 1400768
21:29:06 72582 8192000000 10907296512 11384299520 1952822096 19.249 98 21 41 0 1968565936 9208816 6535024
21:32:15 66316 8192000000 10913017504 11401211904 1981408000 19.476 99 18 36 0 1997348528 10838080 5102448
21:34:22 58790 8192000000 10885489744 11362308096 1929085136 19.060 97 19 36 0 1945176752 11209824 4881792
21:36:45 51754 8192000000 10891046720 11372257280 1973296704 19.412 100 11 22 0 1984253056 8299008 2657344
21:38:37 81177 8192000000 10872126144 11361107968 1902989792 18.850 97 8 16 0 1916003792 11167344 1846656
21:40:58 26832 8192000000 10858097280 11320057856 1844292144 18.376 94 34 68 3 1860410112 6489008 9628960
21:42:28 53788 8192000000 10892922816 11366739968 1909136864 18.900 97 20 40 1 1933850656 18833920 5879872
21:44:23 27674 8192000000 10903024576 11346980864 1907117648 18.884 97 41 81 1 1929629344 9930832 12580864
21:45:53 59922 8192000000 10872907584 11337924608 1901689600 18.840 98 36 71 1 1925064640 13463360 9911680
21:47:58 51688 8192000000 10850083856 11312435200 1833098816 18.285 95 33 66 1 1854146128 11586560 9460752
21:49:53 78541 8192000000 10840051824 11311890432 1837147776 18.318 96 26 52 1 1862650112 18352800 7149536
21:52:17 85159 8192000000 10855261136 11338158080 1887862544 18.729 98 10 20 0 1914116144 22906176 3347424
21:54:43 98180 8192000000 10881901392 11348246528 1929243456 19.061 99 25 49 1 1946354672 10638880 6472336
21:57:32 50209 8192000000 10879697392 11350925312 1932328560 19.085 99 19 38 0 1948865808 11124048 5413200
21:59:23 74767 8192000000 10896720304 11373494272 1971061968 19.394 100 26 50 2 1985777680 6982544 7733168
22:01:56 73429 8192000000 10903587648 11370811392 1946228048 19.196 99 12 24 0 1958641008 8999792 3413168
22:04:14 90362 8192000000 10874222160 11358568448 1846787216 18.396 93 24 48 0 1862732368 8685152 7260000
22:06:50 54057 8192000000 10919691040 11396083712 1986992752 19.520 100 8 16 1 2001708496 12553056 2162688
22:08:46 72728 8192000000 10933974368 11421069312 1985202224 19.506 99 13 26 0 2006917568 18247808 3467536
22:11:06 95849 8192000000 10921426624 11401199616 1923573120 19.015 96 5 10 0 1938679616 14049168 1057328
22:13:44 68118 8192000000 10950925408 11400630272 1966143056 19.355 98 29 56 0 1983518192 9971456 7403680
22:15:52 103661 8192000000 10919447856 11386404864 1941490112 19.159 97 26 52 0 1959828848 10700608 7638128
22:18:39 18231 8192000000 10883385328 11350532096 1901735984 18.840 96 21 42 0 1913456080 6300384 5419712
22:20:04 49002 8192000000 10884070176 11358932992 1909638192 18.904 97 16 32 1 1925255216 11099440 4517584
22:21:57 44502 8192000000 10907586768 11385610240 1979141264 19.458 100 16 32 1 1996537408 12683280 4712864
22:23:42 60824 8192000000 10881274304 11366932480 1920788128 18.993 97 17 34 1 1942254144 15930304 5535712
22:26:03 80962 8192000000 10896310384 11385708544 1903430656 18.854 97 12 24 0 1921143968 14158272 3555040
22:28:25 73441 8192000000 10939327488 11392520192 1980193328 19.466 100 29 58 0 2005216064 16641888 8380848
22:30:39 45243 8192000000 10896805792 11385274368 1942908768 19.170 99 12 24 1 1966190336 19696608 3584960
22:32:31 67103 8192000000 10892480288 11356168192 1888220624 18.731 96 11 22 0 1899102784 8034432 2847728
22:34:42 33640 8192000000 10872512512 11329503232 1809375728 18.091 92 38 76 0 1832743472 12740064 10627680
22:36:20 58461 8192000000 10825803536 11279716352 1758061536 17.668 91 25 50 0 1777418016 12631456 6725024

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-10-2021 22:39:54 to 03-10-2021 22:40:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-10-2021 22:40:10 to 03-10-2021 22:40:12.

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          244859
MON$OLDEST_ACTIVE               248365
MON$OLDEST_SNAPSHOT             248365
MON$NEXT_TRANSACTION            248365
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                       2033824
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.33506 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33506 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33506 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33506 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 ---
:156: 
:157: mscs-test-161.budzdorov.ru    Sun Oct  3 22:38:06 2021
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:159: 
--- end of diff output ---

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

03.10.21 22:40:13. 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-03 22:40:15 No severe PSQL-related problems occured
03.10.21 22:40:16. End of report.