Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.11.2021 01: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 15984900 kB
MemAvailable 30817748 kB
Buffers 0 kB
Cached 15741680 kB
SwapCached 2452 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-11-08 01:37:54
DTS_END                         2021-11-08 01:37:54.7550
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/53118
TRN_ID                          238017
ATT_ID                          699
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: 9281.50 21782 1113780
customer order: creation 171.35 418 20562
customer order: refuse 15.07 360 1808
order to supplier: creation 45.03 1453 5404
order to supplier: removal 3.49 109191 419
invoice (draft): creation 45.33 3599 5439
invoice (draft): removal 53.35 951 6402
invoice accept: apply 40.52 18699 4862
invoice accept: cancel 48.19 889 5783
customer reserve: creation 3835.73 179 460287
customer reserve: removal 4730.59 62 567671
realization accept: apply 82.74 207 9929
realization accept: cancel 81.22 116 9746
payment from customer: creation 58.13 145 6976
payment from customer: removal 5.61 75 673
payment to supplier: creation 48.89 157 5867
payment to supplier: removal 3.48 125 417
service: total inventory turnovers 5.02 1146 602
service: total monetary turnovers 6.93 33 832
service: aggregate perf. data 0.82 22616 98
service: refresh index statistics 0.03 275228 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-11-2021 01:40:01 to 08-11-2021 01:40:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3349
TEST_TIME 2 3691
TEST_TIME 3 3542
TEST_TIME 4 4340
TEST_TIME 5 4406
TEST_TIME 6 4305
TEST_TIME 7 4542
TEST_TIME 8 4910
TEST_TIME 9 4938
TEST_TIME 10 5391
TEST_TIME 11 6028
TEST_TIME 12 6422
TEST_TIME 13 6665
TEST_TIME 14 6853
TEST_TIME 15 7451
TEST_TIME 16 7593
TEST_TIME 17 7592
TEST_TIME 18 7734
TEST_TIME 19 7759
TEST_TIME 20 8321
TEST_TIME 21 8212
TEST_TIME 22 8308
TEST_TIME 23 8541
TEST_TIME 24 9045
TEST_TIME 25 9057
TEST_TIME 26 8962
TEST_TIME 27 9363
TEST_TIME 28 10025
TEST_TIME 29 10954
TEST_TIME 30 11244
TEST_TIME 31 11143
TEST_TIME 32 10825
TEST_TIME 33 10634
TEST_TIME 34 10626
TEST_TIME 35 10411
TEST_TIME 36 10470
TEST_TIME 37 10346
TEST_TIME 38 10187
TEST_TIME 39 10097
TEST_TIME 40 9993
TEST_TIME 41 9971
TEST_TIME 42 9832
TEST_TIME 43 9833
TEST_TIME 44 9774
TEST_TIME 45 9702
TEST_TIME 46 9694
TEST_TIME 47 9584
TEST_TIME 48 9559
TEST_TIME 49 9551
TEST_TIME 50 9453
TEST_TIME 51 9402
TEST_TIME 52 9321
TEST_TIME 53 9228
TEST_TIME 54 9133
TEST_TIME 55 9150
TEST_TIME 56 9058
TEST_TIME 57 9015
TEST_TIME 58 9079
TEST_TIME 59 9133
TEST_TIME 60 9226
TEST_TIME 61 9211
TEST_TIME 62 9356
TEST_TIME 63 9366
TEST_TIME 64 9350
TEST_TIME 65 9545
TEST_TIME 66 9487
TEST_TIME 67 9466
TEST_TIME 68 9573
TEST_TIME 69 9551
TEST_TIME 70 9662
TEST_TIME 71 9610
TEST_TIME 72 9486
TEST_TIME 73 9444
TEST_TIME 74 9455
TEST_TIME 75 9399
TEST_TIME 76 9393
TEST_TIME 77 9340
TEST_TIME 78 9346
TEST_TIME 79 9261
TEST_TIME 80 9206
TEST_TIME 81 9139
TEST_TIME 82 9236
TEST_TIME 83 9216
TEST_TIME 84 9200
TEST_TIME 85 9245
TEST_TIME 86 9249
TEST_TIME 87 9249
TEST_TIME 88 9282
TEST_TIME 89 9295
TEST_TIME 90 9364
TEST_TIME 91 9404
TEST_TIME 92 9407
TEST_TIME 93 9467
TEST_TIME 94 9482
TEST_TIME 95 9455
TEST_TIME 96 9372
TEST_TIME 97 9354
TEST_TIME 98 9299
TEST_TIME 99 9350
TEST_TIME 100 9335
TEST_TIME 101 9285
TEST_TIME 102 9260
TEST_TIME 103 9250
TEST_TIME 104 9252
TEST_TIME 105 9229
TEST_TIME 106 9238
TEST_TIME 107 9223
TEST_TIME 108 9242
TEST_TIME 109 9268
TEST_TIME 110 9285
TEST_TIME 111 9260
TEST_TIME 112 9327
TEST_TIME 113 9332
TEST_TIME 114 9380
TEST_TIME 115 9392
TEST_TIME 116 9387
TEST_TIME 117 9368
TEST_TIME 118 9304
TEST_TIME 119 9298
TEST_TIME 120 9285

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-11-2021 01:40:05 to 08-11-2021 01:40:08.

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 1540294 1540294 0 0.00 0 27247 6 0 0
doc_list_biud 1540293 1540293 0 0.00 0 36491 30 0 0
sp_add_invoice_to_stock 4952 4862 90 1.81 29 197729 18699 0 90
sp_cancel_adding_invoice 5783 5783 0 0.00 6 65446 889 0 0
sp_cancel_client_order 1808 1808 0 0.00 4 13332 361 0 0
sp_cancel_customer_reserve 567671 567671 0 0.00 1 36495 63 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 2 5584 75 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 16781 125 0 0
sp_cancel_supplier_invoice 6409 6402 7 0.10 2 20407 951 0 7
sp_cancel_supplier_order 419 419 0 0.00 7 1078920 109192 0 0
sp_cancel_write_off 9746 9746 0 0.00 1 17516 117 0 0
sp_client_order 20562 20562 0 0.00 4 39089 419 0 0
sp_customer_reserve 460287 460287 0 0.00 0 81137 179 0 0
sp_fill_shopping_cart 31400 31400 0 0.00 1 16965 44 0 0
sp_fill_shopping_cart_clo_res 460283 460283 0 0.00 0 19259 15 0 0
sp_get_clo_for_invoice 4854 4854 0 0.00 0 22992 348 0 0
sp_kill_cost_storno 16619 16619 0 0.00 0 17458 62 0 0
sp_kill_qstorno_ret_qs2qd 1151909 1151909 0 0.00 0 36465 35 0 0
sp_kill_qty_storno 606612 606612 0 0.00 0 36491 75 0 0
sp_lock_selected_doc 20600 20600 0 0.00 0 8566 6 0 0
sp_make_cost_storno 27626 27626 0 0.00 0 16757 56 0 0
sp_multiply_rows_for_pdistr 27626 27626 0 0.00 0 26524 36 0 0
sp_multiply_rows_for_qdistr 20562 20562 0 0.00 0 26552 190 0 0
sp_pay_from_customer 6976 6976 0 0.00 4 17228 146 0 0
sp_pay_to_supplier 5867 5867 0 0.00 4 26907 157 0 0
sp_payment_common 12843 12843 0 0.00 1 17217 43 0 0
sp_qd_handle_on_cancel_clo 1808 1808 0 0.00 0 13325 337 0 0
sp_qd_handle_on_invoice_upd_sts 10637 10637 0 0.00 0 17755 359 0 0
sp_qd_handle_on_reserve_upd_sts 9929 9929 0 0.00 0 17301 78 0 0
sp_reserve_write_off 9929 9929 0 0.00 6 17312 208 0 0
sp_supplier_invoice 5439 5439 0 0.00 5 72401 3600 0 0
sp_supplier_order 5404 5404 0 0.00 6 49614 1454 0 0
srv_aggregate_perf_data 98 98 0 0.00 3062 157988 22617 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1986 129131 46611 0 0
srv_find_qd_qs_mism 1066490 1066490 0 0.00 0 27246 5 0 0
srv_make_invnt_saldo 602 602 0 0.00 31 48157 1146 0 0
srv_make_money_saldo 832 832 0 0.00 1 8112 33 0 0
srv_recalc_idx_stat 3 3 0 0.00 220411 348811 275228 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 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 4862 4862 0 0.00 0 16539 188 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 4294 125 0 0
t$perf-norm:sp_cancel_client_order 1808 1808 0 0.00 0 12349 22 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 3765 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 975 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 5797 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 805 14 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 23086 1662 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 3051 7 0 0
t$perf-norm:sp_client_order 20562 20562 0 0.00 0 16611 22 0 0
t$perf-norm:sp_customer_reserve 1049 1049 0 0.00 0 25231 65 0 0
t$perf-norm:sp_pay_from_customer 6976 6976 0 0.00 0 6068 9 0 0
t$perf-norm:sp_pay_to_supplier 5867 5867 0 0.00 0 16806 14 0 0
t$perf-norm:sp_reserve_write_off 9929 9929 0 0.00 0 10766 17 0 0
t$perf-norm:sp_supplier_invoice 5439 5439 0 0.00 0 6148 25 0 0
t$perf-norm:sp_supplier_order 5404 5404 0 0.00 0 5821 17 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 492 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 199 4 0 0
t$perf-norm:srv_make_invnt_saldo 602 602 0 0.00 0 1582 26 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 3892 23 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 3 5 4 0 0
v_add_invoice_to_stock 9888 9708 180 1.82 0 7286 18 0 180
v_all_customers 37484 37484 0 0.00 0 17 1 0 0
v_all_suppliers 30092 30092 0 0.00 0 26 1 0 0
v_all_wares 41124 41124 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 7294 17 0 0
v_cancel_client_order 3616 3616 0 0.00 0 1563 4 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 3701 10 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 3813 7 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 3457 12 0 0
v_cancel_supplier_invoice 836 822 14 1.67 0 7564 30 0 14
v_cancel_supplier_order 838 838 0 0.00 0 1005 5 0 0
v_cancel_write_off 1978 1978 0 0.00 0 3555 16 0 0
v_max_id_clo_ord 5404 5404 0 0.00 0 2508 2 0 0
v_max_id_clo_res 1049 1049 0 0.00 0 2391 11 0 0
v_max_id_ord_sup 5433 5433 0 0.00 0 6139 8 0 0
v_max_non_paid_invoice 1658 1658 0 0.00 0 4747 8 0 0
v_max_non_paid_realizn 3630 3630 0 0.00 0 2286 4 0 0
v_min_id_clo_ord 5404 5404 0 0.00 0 16913 17 0 0
v_min_id_clo_res 1049 1049 0 0.00 0 10885 35 0 0
v_min_id_ord_sup 5433 5433 0 0.00 0 5514 4 0 0
v_min_non_paid_invoice 5867 5867 0 0.00 0 16772 24 0 0
v_min_non_paid_realizn 6976 6976 0 0.00 0 17173 47 0 0
v_our_firm 10332 10332 0 0.00 0 36 1 0 0
v_reserve_write_off 19858 19858 0 0.00 0 7274 6 0 0
x_lock_depdocs_on_canc_invoice 11774 11774 0 0.00 0 17762 126 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 7604 358 0 0
x_make_qstorno_1000_1200 5404 5404 0 0.00 1 49600 1361 0 0
x_make_qstorno_1200_2000 5439 5439 0 0.00 2 72391 3443 0 0
x_make_qstorno_2100_3300 460287 460287 0 0.00 0 81135 161 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 08-11-2021 01:40:09 to 08-11-2021 01:40:14.

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
23:07:58 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:08:59 36975 8192000000 9909978112 10220765184 984492864 10.728 100 89 179 0 1013728016 5904976 23330176
23:10:43 23467 8192000000 9943517616 10264666112 1020726144 11.079 100 85 170 0 1051180032 8680928 21772960
23:12:14 14390 8192000000 9978197536 10312433664 1060020160 11.457 100 69 137 1 1086515616 9507632 16987824
23:13:33 15292 8192000000 10038650608 10386300928 1127409232 12.097 100 84 168 1 1159144464 11563616 20171616
23:14:54 7440 8192000000 10093375216 10455285760 1175510272 12.548 100 75 150 0 1205814544 12975248 17329024
23:16:03 3708 8192000000 10126182592 10495381504 1215968992 12.924 100 85 167 1 1248259056 13753200 18536864
23:17:08 4158 8192000000 10167526288 10546950144 1251557424 13.253 100 82 164 0 1281894048 11644864 18691760
23:18:14 1821 8192000000 10200900592 10584137728 1291185584 13.615 100 90 181 2 1324592928 13019968 20387376
23:19:17 3776 8192000000 10203730400 10597326848 1300224656 13.697 100 56 109 2 1323121392 10724448 12172288
23:20:22 11242 8192000000 10192113104 10610233344 1314582192 13.828 100 20 40 1 1325760240 5779760 5398288
23:21:34 34555 8192000000 10209788192 10626183168 1314861232 13.830 100 40 80 0 1331125744 5832720 10431792
23:23:12 6090 8192000000 10226482208 10642931712 1333982592 14.003 100 18 36 1 1343667312 5392624 4292096
23:24:22 5831 8192000000 10228079936 10648322048 1335309456 14.015 100 45 89 1 1353759472 8406256 10043760
23:25:29 26361 8192000000 10231894096 10649038848 1338212736 14.041 100 29 58 1 1351740208 5666304 7861168
23:26:57 10708 8192000000 10222310496 10647224320 1326382272 13.934 99 19 38 1 1336649424 6207264 4059888
23:28:08 4667 8192000000 10217923152 10627129344 1316614768 13.846 100 37 74 1 1332171216 6919600 8636848
23:29:16 7932 8192000000 10153412688 10563604480 1250303376 13.241 98 24 48 1 1262446624 6561088 5582160
23:30:27 11355 8192000000 10123839168 10526441472 1198206592 12.760 96 24 48 2 1210130688 6197408 5726688
23:31:41 16868 8192000000 10138912432 10538934272 1203621296 12.810 96 20 39 1 1213676096 5641424 4413376
23:32:59 6196 8192000000 10160238512 10562043904 1264891920 13.375 100 31 62 1 1278730144 6772000 7066224
23:34:07 9291 8192000000 10178226128 10587291648 1286616976 13.573 100 23 46 1 1298044544 5524448 5903120
23:35:16 19033 8192000000 10192394784 10603393024 1300320832 13.698 100 16 32 0 1309802528 5473200 4008496
23:36:36 11599 8192000000 10204252912 10612957184 1305776208 13.748 100 74 149 1 1330743488 6652864 18314416
23:37:50 3966 8192000000 10217428640 10639073280 1335573040 14.017 100 37 72 2 1349325504 5370256 8382208
23:38:58 6978 8192000000 10264714288 10673569792 1355005760 14.192 100 57 115 4 1376992240 8865552 13120928
23:40:13 25683 8192000000 10276064368 10694901760 1371386928 14.339 100 41 79 1 1391746848 10976800 9383120
23:41:46 52823 8192000000 10283412000 10714628096 1386239680 14.472 100 34 68 2 1407862464 12611008 9011776
23:43:50 15745 8192000000 10321114976 10743562240 1396062144 14.560 100 58 116 3 1430299056 20021152 14215760
23:45:11 14981 8192000000 10324410064 10746384384 1404948640 14.639 100 53 106 1 1437100272 19746112 12405520
23:46:30 4905 8192000000 10328542240 10748497920 1403374048 14.625 100 50 98 1 1433282800 18127232 11781520
23:47:44 9518 8192000000 10324930016 10751889408 1400122496 14.596 100 57 114 0 1435808544 21477472 14208576
23:49:01 6352 8192000000 10341772752 10768084992 1405212944 14.641 99 63 126 0 1444740896 23708832 15819120
23:50:16 2242 8192000000 10344474768 10766032896 1408655392 14.672 99 64 124 0 1449743536 26359504 14728640
23:51:30 8730 8192000000 10366420880 10791493632 1420325216 14.776 100 66 132 0 1469337264 32871168 16140880
23:52:42 6111 8192000000 10356093008 10778959872 1411276848 14.695 100 62 124 0 1460523296 34206480 15039968
23:53:52 4481 8192000000 10345318768 10757373952 1385189280 14.463 98 77 151 4 1438912496 35634144 18089072
23:55:03 4614 8192000000 10368908112 10775834624 1405217488 14.641 100 84 167 5 1467685072 42071744 20395840
23:56:13 1984 8192000000 10361253600 10767118336 1385365968 14.464 99 89 175 3 1452023008 45186672 21470368
23:57:20 4154 8192000000 10341842672 10742804480 1374676432 14.369 99 81 162 2 1441833024 47326672 19829920
23:58:31 11419 8192000000 10324139312 10738565120 1372960912 14.354 99 44 86 0 1428380496 43607792 11811792
23:59:48 3825 8192000000 10356777152 10767527936 1387114080 14.480 100 82 164 2 1462041184 52665552 22261552
00:00:55 13775 8192000000 10352525296 10780045312 1392916320 14.532 100 46 92 1 1461992048 56061584 13014144
00:02:30 26328 8192000000 10346046368 10774753280 1358470752 14.224 97 34 67 0 1419080768 50745024 9864992
00:04:30 24981 8192000000 10334213104 10784718848 1374527184 14.368 100 28 56 1 1450961328 67526352 8907792
00:06:17 18279 8192000000 10274133728 10719866880 1335769200 14.019 99 26 52 0 1375667920 32881904 7016816
00:07:36 77553 8192000000 10286603664 10719559680 1354782896 14.190 99 14 28 0 1371885088 14054032 3048160
00:09:55 128287 8192000000 10275618528 10731016192 1347475168 14.125 98 26 50 4 1365309616 11995136 5839312
00:13:05 44540 8192000000 10278953392 10730610688 1375679472 14.378 100 37 74 0 1395055616 10857728 8518416
00:15:20 54893 8192000000 10277585536 10725621760 1362878112 14.263 99 23 46 0 1377632800 9320304 5434384
00:17:19 38864 8192000000 10267588144 10718453760 1357165104 14.212 98 38 76 2 1378617664 12564336 8888224
00:19:06 85815 8192000000 10307410816 10754322432 1380615520 14.422 99 19 38 1 1399550992 13839920 5095552
00:21:37 67026 8192000000 10285866496 10748788736 1376913920 14.389 99 15 30 0 1392879328 12265376 3700032
00:23:53 71253 8192000000 10307590128 10760974336 1392350800 14.527 100 22 44 1 1411777200 14741120 4685280
00:26:04 51917 8192000000 10282398112 10753306624 1384799808 14.459 99 9 18 0 1394694064 8059344 1834912
00:27:56 21609 8192000000 10285122800 10740506624 1379888976 14.416 99 33 66 1 1395474256 7693472 7891808
00:29:28 66498 8192000000 10266487488 10734510080 1354004896 14.183 97 13 26 0 1365292992 8264928 3023168
00:31:35 47728 8192000000 10271915024 10743672832 1369394048 14.322 99 22 44 0 1387743648 13533632 4815968
00:33:27 37682 8192000000 10257065056 10715631616 1323793776 13.911 96 15 28 1 1340275328 12961232 3520320
00:35:05 47889 8192000000 10284359680 10747052032 1382563936 14.439 100 24 48 1 1407125184 18692000 5869248
00:36:53 47342 8192000000 10287228128 10738671616 1358614016 14.225 98 20 40 0 1373721872 10623104 4484752
00:38:56 66015 8192000000 10267488784 10738868224 1364052560 14.274 99 12 23 2 1379518640 12621904 2844176
00:41:09 28944 8192000000 10308673888 10749251584 1364489248 14.278 98 50 99 0 1391081840 15400048 11192544
00:43:04 49877 8192000000 10286157856 10722603008 1313224208 13.815 95 32 64 0 1331725216 11349040 7151968
00:44:54 77825 8192000000 10263545344 10714632192 1314596256 13.828 96 14 28 1 1335080000 17481616 3002128
00:47:15 102104 8192000000 10255321968 10712477696 1323045152 13.904 97 8 16 0 1339256784 14574384 1637248
00:50:36 67915 8192000000 10242933296 10695307264 1313117728 13.814 97 16 32 2 1328447296 11254304 4075264
00:52:47 69602 8192000000 10246294864 10699489280 1351819552 14.164 100 12 24 2 1365061296 10311760 2929984
00:55:30 50109 8192000000 10214379952 10664009728 1288447296 13.590 96 11 22 1 1301622336 10959040 2216000
00:57:54 98149 8192000000 10249064976 10708111360 1340860416 14.065 100 9 18 1 1356307408 13377632 2069360
01:00:36 61399 8192000000 10267282560 10698936320 1329880000 13.966 98 17 34 1 1349234976 15013648 4341328
01:02:41 50751 8192000000 10282178960 10733699072 1372513072 14.350 100 25 50 1 1392600864 13748352 6339440
01:04:58 46454 8192000000 10289589712 10735063040 1365453024 14.286 99 12 24 1 1379638672 11461680 2723968
01:06:59 60620 8192000000 10290568112 10753413120 1372098320 14.346 99 20 40 0 1396279120 20147552 4033248
01:09:05 57748 8192000000 10313664592 10761191424 1380108944 14.418 99 13 27 0 1393778304 11040928 2628432
01:11:03 78030 8192000000 10323729312 10764931072 1390235984 14.508 99 24 46 1 1407695584 12478560 4981040
01:13:21 102570 8192000000 10308905808 10761285632 1368575776 14.314 97 37 72 0 1387549696 10417568 8556352
01:16:05 57706 8192000000 10286991392 10750787584 1344425360 14.097 96 18 36 0 1359555120 10965568 4164192
01:18:03 45207 8192000000 10293950640 10757894144 1389917072 14.505 99 10 20 1 1400371648 8353456 2101120
01:19:49 45955 8192000000 10300379648 10763313152 1391497696 14.519 99 24 47 1 1406960432 9669584 5793152
01:21:37 81599 8192000000 10286032304 10749587456 1328298960 13.952 95 11 22 1 1342258272 11605328 2353984
01:24:02 44391 8192000000 10287909568 10737561600 1336063600 14.022 96 21 42 0 1352205440 11284480 4857360
01:25:47 32763 8192000000 10304642784 10756206592 1379060880 14.408 99 36 72 1 1402045600 14957696 8027024
01:27:34 50456 8192000000 10302405888 10744381440 1334527968 14.008 96 28 56 1 1351262032 11029584 5704480
01:29:31 93959 8192000000 10281540096 10719956992 1307660208 13.765 95 10 20 0 1325343632 15248736 2434688
01:32:08 83703 8192000000 10268059776 10709794816 1278830480 13.502 93 22 44 0 1294747312 11175216 4741616
01:34:32 129130 8192000000 10268917536 10729897984 1356730080 14.208 99 26 53 1 1376454416 13272592 6451744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-11-2021 01:40:15 to 08-11-2021 01:40:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 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 08-11-2021 01:40:31 to 08-11-2021 01:40:33.

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          237043
MON$OLDEST_ACTIVE               238147
MON$OLDEST_SNAPSHOT             238147
MON$NEXT_TRANSACTION            238147
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                       2033504
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.33528 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33528 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33528 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33528 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 ---
:220: 
:221: mscs-test-161.budzdorov.ru    Mon Nov  8 01:37:57 2021
:222:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:223: 
:224: 
:225: mscs-test-161.budzdorov.ru    Mon Nov  8 01:38:03 2021
:226:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:227: 
:228: 
:229: mscs-test-161.budzdorov.ru    Mon Nov  8 01:38:09 2021
:230:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:231: 
:232: 
:233: mscs-test-161.budzdorov.ru    Mon Nov  8 01:38:10 2021
:234:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:235: 
--- end of diff output ---

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

08.11.21 01:40:34. 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-11-08 01:40:37 No severe PSQL-related problems occured
08.11.21 01:40:38. End of report.