Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.10.2020 13:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
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 16677844 kB
MemAvailable 31492540 kB
Buffers 0 kB
Cached 14966452 kB
SwapCached 3064 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2020-10-28 13:26:55
DTS_END                         2020-10-28 13:26:55.9920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/41158
TRN_ID                          224486
ATT_ID                          601
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: 9123.15 19780 1094778
customer order: creation 163.69 435 19643
customer order: refuse 13.41 389 1609
order to supplier: creation 43.00 1368 5160
order to supplier: removal 3.50 132967 420
invoice (draft): creation 43.22 3378 5186
invoice (draft): removal 54.97 1019 6596
invoice accept: apply 38.60 17915 4632
invoice accept: cancel 50.23 928 6028
customer reserve: creation 3602.05 172 432246
customer reserve: removal 4818.03 75 578164
realization accept: apply 78.84 193 9461
realization accept: cancel 94.33 127 11320
payment from customer: creation 54.95 125 6594
payment from customer: removal 5.19 106 623
payment to supplier: creation 46.68 146 5601
payment to supplier: removal 3.06 85 367
service: total inventory turnovers 3.33 4082 399
service: total monetary turnovers 5.17 770 620
service: aggregate perf. data 0.88 16797 105
service: refresh index statistics 0.03 214528 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-10-2020 13:28:02 to 28-10-2020 13:28:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 775
TEST_TIME 2 2188
TEST_TIME 3 2485
TEST_TIME 4 3143
TEST_TIME 5 3351
TEST_TIME 6 3734
TEST_TIME 7 3969
TEST_TIME 8 4381
TEST_TIME 9 4555
TEST_TIME 10 4651
TEST_TIME 11 4891
TEST_TIME 12 4993
TEST_TIME 13 5205
TEST_TIME 14 5331
TEST_TIME 15 5703
TEST_TIME 16 6032
TEST_TIME 17 6126
TEST_TIME 18 6545
TEST_TIME 19 6961
TEST_TIME 20 7335
TEST_TIME 21 7457
TEST_TIME 22 7367
TEST_TIME 23 7466
TEST_TIME 24 7628
TEST_TIME 25 7629
TEST_TIME 26 7794
TEST_TIME 27 7944
TEST_TIME 28 7936
TEST_TIME 29 8071
TEST_TIME 30 9506
TEST_TIME 31 10193
TEST_TIME 32 10345
TEST_TIME 33 10739
TEST_TIME 34 10783
TEST_TIME 35 10773
TEST_TIME 36 10608
TEST_TIME 37 10485
TEST_TIME 38 10349
TEST_TIME 39 10326
TEST_TIME 40 10505
TEST_TIME 41 10365
TEST_TIME 42 10380
TEST_TIME 43 10394
TEST_TIME 44 10419
TEST_TIME 45 10334
TEST_TIME 46 10184
TEST_TIME 47 10027
TEST_TIME 48 9921
TEST_TIME 49 9850
TEST_TIME 50 9871
TEST_TIME 51 9763
TEST_TIME 52 9734
TEST_TIME 53 9760
TEST_TIME 54 9773
TEST_TIME 55 9683
TEST_TIME 56 9723
TEST_TIME 57 9638
TEST_TIME 58 9653
TEST_TIME 59 9711
TEST_TIME 60 9685
TEST_TIME 61 9691
TEST_TIME 62 9736
TEST_TIME 63 9765
TEST_TIME 64 9794
TEST_TIME 65 9746
TEST_TIME 66 9848
TEST_TIME 67 9789
TEST_TIME 68 9793
TEST_TIME 69 9884
TEST_TIME 70 9863
TEST_TIME 71 9840
TEST_TIME 72 9836
TEST_TIME 73 9715
TEST_TIME 74 9624
TEST_TIME 75 9609
TEST_TIME 76 9556
TEST_TIME 77 9511
TEST_TIME 78 9517
TEST_TIME 79 9450
TEST_TIME 80 9364
TEST_TIME 81 9376
TEST_TIME 82 9412
TEST_TIME 83 9377
TEST_TIME 84 9350
TEST_TIME 85 9389
TEST_TIME 86 9330
TEST_TIME 87 9441
TEST_TIME 88 9492
TEST_TIME 89 9458
TEST_TIME 90 9528
TEST_TIME 91 9518
TEST_TIME 92 9549
TEST_TIME 93 9522
TEST_TIME 94 9437
TEST_TIME 95 9392
TEST_TIME 96 9320
TEST_TIME 97 9262
TEST_TIME 98 9248
TEST_TIME 99 9318
TEST_TIME 100 9314
TEST_TIME 101 9297
TEST_TIME 102 9275
TEST_TIME 103 9289
TEST_TIME 104 9308
TEST_TIME 105 9307
TEST_TIME 106 9379
TEST_TIME 107 9399
TEST_TIME 108 9431
TEST_TIME 109 9408
TEST_TIME 110 9383
TEST_TIME 111 9336
TEST_TIME 112 9330
TEST_TIME 113 9273
TEST_TIME 114 9217
TEST_TIME 115 9185
TEST_TIME 116 9165
TEST_TIME 117 9143
TEST_TIME 118 9156
TEST_TIME 119 9135
TEST_TIME 120 9126

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-10-2020 13:28:05 to 28-10-2020 13:28:09.

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 1495888 1495888 0 0.00 0 21042 7 0 0
doc_list_biud 1495888 1495888 0 0.00 0 86392 37 0 0
sp_add_invoice_to_stock 4797 4632 165 3.43 40 164677 17916 0 165
sp_cancel_adding_invoice 6028 6028 0 0.00 4 71559 929 0 0
sp_cancel_client_order 1609 1609 0 0.00 5 11129 389 0 0
sp_cancel_customer_reserve 578164 578164 0 0.00 1 24754 76 0 0
sp_cancel_pay_from_customer 623 623 0 0.00 3 5823 107 0 0
sp_cancel_pay_to_supplier 367 367 0 0.00 3 5251 85 0 0
sp_cancel_supplier_invoice 6613 6596 17 0.25 2 77381 1020 0 17
sp_cancel_supplier_order 420 420 0 0.00 7 1288242 132967 0 0
sp_cancel_write_off 11320 11320 0 0.00 1 9803 127 0 0
sp_client_order 19643 19643 0 0.00 3 91570 436 0 0
sp_customer_reserve 432246 432246 0 0.00 0 45731 172 0 0
sp_fill_shopping_cart 29977 29977 0 0.00 1 13322 37 0 0
sp_fill_shopping_cart_clo_res 432245 432245 0 0.00 0 42716 19 0 0
sp_get_clo_for_invoice 4623 4623 0 0.00 0 55683 431 0 0
sp_kill_cost_storno 18338 18338 0 0.00 0 9779 68 0 0
sp_kill_qstorno_ret_qs2qd 1174664 1174664 0 0.00 0 77372 41 0 0
sp_kill_qty_storno 618213 618213 0 0.00 0 86392 88 0 0
sp_lock_selected_doc 19445 19445 0 0.00 0 9076 6 0 0
sp_make_cost_storno 26271 26271 0 0.00 0 17491 64 0 0
sp_multiply_rows_for_pdistr 26271 26271 0 0.00 0 9967 35 0 0
sp_multiply_rows_for_qdistr 19643 19643 0 0.00 0 85881 225 0 0
sp_pay_from_customer 6594 6594 0 0.00 4 12781 126 0 0
sp_pay_to_supplier 5601 5601 0 0.00 5 12021 146 0 0
sp_payment_common 12195 12195 0 0.00 1 8685 23 0 0
sp_qd_handle_on_cancel_clo 1609 1609 0 0.00 0 11122 353 0 0
sp_qd_handle_on_invoice_upd_sts 10643 10643 0 0.00 0 86391 438 0 0
sp_qd_handle_on_reserve_upd_sts 9461 9461 0 0.00 0 9052 55 0 0
sp_reserve_write_off 9461 9461 0 0.00 7 16792 194 0 0
sp_supplier_invoice 5186 5186 0 0.00 7 79963 3378 0 0
sp_supplier_order 5160 5160 0 0.00 6 19698 1369 0 0
srv_aggregate_perf_data 105 105 0 0.00 2490 331888 16798 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2077 131865 46367 0 0
srv_find_qd_qs_mism 1050462 1050462 0 0.00 0 21041 5 0 0
srv_make_invnt_saldo 399 399 0 0.00 22 117248 4083 0 0
srv_make_money_saldo 620 620 0 0.00 1 20292 771 0 0
srv_recalc_idx_stat 4 4 0 0.00 144136 381055 214528 0 0
t$perf-abend:sp_add_invoice_to_stock 165 165 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4632 4632 0 0.00 0 15293 162 0 0
t$perf-norm:sp_cancel_adding_invoice 372 372 0 0.00 0 2250 81 0 0
t$perf-norm:sp_cancel_client_order 1609 1609 0 0.00 0 8124 14 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 4630 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 623 623 0 0.00 0 1352 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 367 367 0 0.00 0 3902 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 1691 26 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 22281 1756 0 0
t$perf-norm:sp_cancel_write_off 920 920 0 0.00 0 4015 11 0 0
t$perf-norm:sp_client_order 19643 19643 0 0.00 0 7635 19 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 812 5 0 0
t$perf-norm:sp_pay_from_customer 6594 6594 0 0.00 0 6437 8 0 0
t$perf-norm:sp_pay_to_supplier 5601 5601 0 0.00 0 7855 12 0 0
t$perf-norm:sp_reserve_write_off 9461 9461 0 0.00 0 4713 10 0 0
t$perf-norm:sp_supplier_invoice 5186 5186 0 0.00 0 9808 20 0 0
t$perf-norm:sp_supplier_order 5160 5160 0 0.00 0 6561 17 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 436 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 64 4 0 0
t$perf-norm:srv_make_invnt_saldo 399 399 0 0.00 0 4976 22 0 0
t$perf-norm:srv_make_money_saldo 620 620 0 0.00 0 1631 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 9560 9230 330 3.45 0 8284 19 0 330
v_all_customers 35780 35780 0 0.00 0 16 1 0 0
v_all_suppliers 29228 29228 0 0.00 0 19 1 0 0
v_all_wares 39278 39278 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 744 744 0 0.00 0 1184 8 0 0
v_cancel_client_order 3218 3218 0 0.00 0 1936 4 0 0
v_cancel_customer_prepayment 1246 1246 0 0.00 0 4331 16 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 4137 6 0 0
v_cancel_payment_to_supplier 734 734 0 0.00 0 1008 6 0 0
v_cancel_supplier_invoice 774 740 34 4.39 0 1732 18 0 34
v_cancel_supplier_order 840 840 0 0.00 0 2915 9 0 0
v_cancel_write_off 1840 1840 0 0.00 0 2292 16 0 0
v_max_id_clo_ord 5160 5160 0 0.00 0 371 1 0 0
v_max_id_clo_res 956 956 0 0.00 0 1692 14 0 0
v_max_id_ord_sup 5176 5176 0 0.00 0 4441 4 0 0
v_max_non_paid_invoice 1323 1323 0 0.00 0 257 2 0 0
v_max_non_paid_realizn 3441 3441 0 0.00 0 4617 4 0 0
v_min_id_clo_ord 5160 5160 0 0.00 0 1602 4 0 0
v_min_id_clo_res 956 956 0 0.00 0 4552 7 0 0
v_min_id_ord_sup 5176 5176 0 0.00 0 10314 7 0 0
v_min_non_paid_invoice 5601 5601 0 0.00 0 5916 14 0 0
v_min_non_paid_realizn 6594 6594 0 0.00 0 7443 15 0 0
v_our_firm 9804 9804 0 0.00 0 15 1 0 0
v_reserve_write_off 18922 18922 0 0.00 0 3904 6 0 0
x_lock_depdocs_on_canc_invoice 12254 12254 0 0.00 0 15086 113 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 4084 362 0 0
x_make_qstorno_1000_1200 5160 5160 0 0.00 2 19690 1293 0 0
x_make_qstorno_1200_2000 5186 5186 0 0.00 4 79953 3240 0 0
x_make_qstorno_2100_3300 432246 432246 0 0.00 0 45719 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-10-2020 13:28:10 to 28-10-2020 13:28: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
10:56:59 3 8192000000 8796633136 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
10:58:00 21299 8192000000 9718621680 10017284096 800176848 8.898 100 88 176 1 829279728 7579056 21523824
10:59:38 32549 8192000000 9771294624 10079965184 840715872 9.307 100 89 178 0 876532576 14626192 21190512
11:01:18 19754 8192000000 9826860320 10148569088 884066912 9.740 100 84 170 1 930528416 27524896 18936608
11:02:49 12512 8192000000 9880014288 10211635200 924566528 10.141 100 87 175 2 985273808 42198656 18508624
11:04:04 12657 8192000000 9918284688 10264850432 953151744 10.422 100 87 172 2 1038358736 66474368 18732624
11:05:19 6008 8192000000 9966829104 10320781312 987028640 10.753 100 81 162 1 1071344432 67947952 16367840
11:06:28 2879 8192000000 9981265552 10342952960 1013872592 11.013 100 81 163 1 1087119664 56598912 16648160
11:07:32 2480 8192000000 10019447456 10384949248 1032084496 11.189 100 86 169 1 1129440768 80020336 17335936
11:08:37 2433 8192000000 10036163872 10405568512 1054173200 11.401 100 93 186 0 1162727888 89983424 18571264
11:09:41 9670 8192000000 10029935376 10406322176 1060118048 11.458 100 81 162 0 1138748304 61554720 17075536
11:10:51 9455 8192000000 10000262592 10397593600 1067646512 11.530 100 34 69 4 1114042960 39348688 7047760
11:12:03 3943 8192000000 10019941264 10407985152 1084637792 11.692 100 62 123 5 1126273440 29516736 12118912
11:13:07 30301 8192000000 9998336672 10406313984 1084968400 11.695 100 32 64 3 1112703408 19905040 7829968
11:14:48 6445 8192000000 10008972384 10408669184 1095030848 11.790 100 50 100 1 1126575408 20692608 10851952
11:15:59 8942 8192000000 10009934896 10414538752 1088511520 11.729 99 34 68 1 1120477024 24565232 7400272
11:17:09 57986 8192000000 9992331200 10404937728 1096215776 11.802 100 23 46 1 1121337872 20050224 5071872
11:19:12 7610 8192000000 9977738080 10376458240 1051988768 11.380 98 29 58 2 1072129584 13883328 6257488
11:20:22 4479 8192000000 9954728768 10337320960 1029955968 11.168 99 47 95 1 1060889728 20137312 10796448
11:21:30 5979 8192000000 9946951312 10327719936 1019729120 11.069 99 34 68 2 1052913440 24960848 8223472
11:22:36 38783 8192000000 9931749952 10321911808 976403488 10.649 95 38 76 4 1002916688 18114832 8398368
11:24:16 7512 8192000000 9953887424 10347065344 1040149760 11.266 100 47 92 1 1068058720 17025840 10883120
11:25:26 7739 8192000000 9992444720 10384470016 1061211072 11.468 100 38 76 0 1095988080 26150272 8626736
11:26:41 20388 8192000000 9998471600 10400329728 1084890896 11.694 100 22 44 1 1105612368 16122960 4598512
11:28:35 38652 8192000000 10024490624 10432774144 1098343760 11.822 100 39 75 2 1147313808 41724752 7245296
11:30:20 35873 8192000000 10094143568 10505596928 1122757840 12.053 100 50 100 2 1205232352 72063056 10411456
11:31:59 12809 8192000000 10141400000 10539769856 1140006256 12.216 100 65 126 1 1245514496 91718384 13789856
11:33:14 8727 8192000000 10147688400 10553270272 1151294608 12.322 100 56 112 2 1266002560 102120640 12587312
11:34:26 10352 8192000000 10196138624 10601910272 1160456272 12.408 100 59 117 0 1310452688 137449248 12547168
11:35:47 9095 8192000000 10217667456 10623205376 1168893168 12.486 100 58 116 0 1322017712 140196416 12928128
11:36:58 7964 8192000000 10277625728 10687922176 1177585616 12.568 100 55 110 2 1384240592 194205952 12449024
11:38:09 7993 8192000000 10301655344 10707877888 1185524512 12.642 100 60 119 2 1395043312 195559552 13959248
11:39:32 2957 8192000000 10348128496 10766856192 1193092432 12.712 100 79 155 1 1455180256 244651376 17436448
11:40:38 2071 8192000000 10411684016 10818084864 1198513856 12.763 100 84 167 2 1511164480 294287264 18363360
11:41:43 5281 8192000000 10425390720 10845409280 1200463760 12.781 100 77 154 0 1526007568 306868880 18674928
11:42:55 3812 8192000000 10412124480 10844000256 1180666832 12.596 99 64 127 1 1513021024 317322112 15032080
11:44:02 4303 8192000000 10436411408 10843742208 1174493360 12.539 100 79 158 1 1534609376 341178720 18937296
11:45:15 2265 8192000000 10402094352 10808614912 1174364144 12.538 100 81 162 3 1499493328 305285984 19843200
11:46:21 2567 8192000000 10424499120 10855989248 1184493088 12.632 100 88 174 2 1523563360 318755056 20315216
11:47:27 9570 8192000000 10370004112 10787090432 1167411024 12.473 99 45 89 3 1466405952 287630416 11364512
11:48:42 6029 8192000000 10484383760 10897416192 1195038144 12.730 100 65 130 0 1575581168 365275008 15268016
11:49:57 7009 8192000000 10512235264 10961600512 1174652240 12.540 98 41 81 0 1588094880 403005072 10437568
11:51:20 16786 8192000000 10511332336 10936111104 1171184896 12.508 99 45 90 1 1602380240 418941088 12254256
11:52:50 12443 8192000000 10557884768 10986991616 1168934368 12.487 100 34 68 1 1647721696 469823168 8964160
11:54:05 14888 8192000000 10573686240 11011862528 1151277600 12.321 100 32 64 0 1679531264 518710304 9543360
11:55:22 10797 8192000000 10613242736 11087011840 1160693568 12.410 100 43 84 3 1704133808 531686768 11753472
11:56:38 34081 8192000000 10320437840 10900242432 1128853936 12.111 98 20 40 1 1474265472 339985440 5426096
11:58:35 72763 8192000000 10075278736 10548883456 1073125440 11.582 94 14 28 4 1174079776 97901376 3052960
12:00:48 76180 8192000000 10036982864 10505674752 1053493920 11.394 94 6 12 0 1093192240 38857920 840400
12:03:05 58338 8192000000 10073767360 10488983552 1105265408 11.888 98 28 55 1 1142796624 31171088 6360128
12:05:05 89254 8192000000 10087415824 10608992256 1127207216 12.095 99 15 29 0 1203497456 73207104 3083136
12:07:37 35251 8192000000 10077824608 10493964288 1085099776 11.696 96 29 58 1 1123778304 33021936 5656592
12:09:17 128753 8192000000 10055700176 10500460544 1091749728 11.759 97 11 21 0 1148670928 54726496 2194704
12:12:26 131865 8192000000 10051776736 10496061440 1108000896 11.913 98 20 40 1 1143762368 31591488 4169984
12:15:55 67009 8192000000 10092054368 10553110528 1137296448 12.190 100 33 66 0 1224020640 79207488 7516704
12:18:10 48069 8192000000 10131854320 10584072192 1140829216 12.223 99 17 34 2 1235068240 90360656 3878368
12:20:01 69791 8192000000 10129180272 10605113344 1149875488 12.308 100 14 28 1 1241964832 88923120 3166224
12:22:11 44376 8192000000 10114394128 10586824704 1139804240 12.214 99 19 38 0 1221086512 76690672 4591600
12:24:24 57727 8192000000 10099086352 10581295104 1145786896 12.270 99 21 42 3 1207027248 56625648 4614704
12:26:22 39779 8192000000 10096985392 10552729600 1141408192 12.229 99 8 16 0 1178366944 35503232 1455520
12:28:03 55716 8192000000 10077489680 10526527488 1085773696 11.702 94 9 18 1 1117332512 29870416 1688400
12:30:02 64911 8192000000 10095238224 10533494784 1116737568 11.996 97 17 34 0 1162081872 41887024 3457280
12:32:09 47375 8192000000 10078925936 10534125568 1123268928 12.058 98 13 26 0 1189277104 63355680 2652496
12:33:59 15994 8192000000 10106027920 10524127232 1106923728 11.903 97 51 102 4 1167289136 49554016 10811392
12:35:34 83819 8192000000 10048632816 10486419456 1057215424 11.430 93 16 32 0 1093668256 33344224 3108608
12:38:07 122885 8192000000 10068068576 10494615552 1108059936 11.914 98 16 32 2 1140578912 29368992 3149984
12:44:16 54035 8192000000 10059744416 10538782720 1125713296 12.081 100 21 42 1 1196937360 66958576 4265488
12:46:15 69712 8192000000 10054817744 10521329664 1056143712 11.420 94 11 22 0 1129617920 71020880 2453328
12:48:25 45935 8192000000 10026410704 10455388160 1077636448 11.625 98 13 26 1 1099082960 18832640 2613872
12:50:14 49867 8192000000 10098026256 10528088064 1071569840 11.567 97 27 54 0 1152855472 75710128 5575504
12:52:27 77989 8192000000 10077316880 10535354368 1089235504 11.735 98 22 44 0 1186227440 92010464 4981472
12:55:12 33538 8192000000 10076134784 10496036864 1103626288 11.872 99 37 74 1 1164276752 53019312 7631152
12:56:51 54691 8192000000 10045604208 10478903296 1090791552 11.750 97 23 46 0 1136728000 41486624 4449824
12:58:50 102319 8192000000 10059389360 10531860480 1107907552 11.913 98 15 30 0 1167525376 56566960 3050864
13:01:34 66796 8192000000 10076463104 10532483072 1133621888 12.155 100 13 24 1 1182982496 47040416 2320192
13:03:42 76896 8192000000 10141128848 10613891072 1118394480 12.012 98 15 30 1 1220472400 98527040 3550880
13:06:18 62118 8192000000 10088296752 10541404160 1136795920 12.185 99 21 42 0 1177798912 36796848 4206144
13:08:23 39539 8192000000 10120942912 10571026432 1140036016 12.216 99 27 54 1 1215811376 69845664 5929696
13:10:10 44252 8192000000 10127809968 10566356992 1152891680 12.337 100 26 52 1 1219972304 61478992 5601632
13:11:55 127739 8192000000 10121638512 10628849664 1115770960 11.987 97 15 30 0 1219908944 100376368 3761616
13:15:03 46575 8192000000 10088226864 10521923584 1096448096 11.804 96 9 18 1 1132002336 33722592 1831648
13:16:55 121150 8192000000 10062976080 10539368448 1137169904 12.189 100 24 48 0 1202312240 60449696 4692640
13:20:19 61739 8192000000 10063541888 10526461952 1107495856 11.909 97 9 18 0 1151727008 42407936 1823216
13:22:20 79914 8192000000 10104853008 10578378752 1133552928 12.155 99 18 36 2 1192731920 55187664 3991328
13:24:41 63330 8192000000 10099365040 10538717184 1149478624 12.305 100 14 28 0 1205826656 52958496 3389536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-10-2020 13:28:15 to 28-10-2020 13:28:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 165 v_add_invoice_to_stock 335544517
user_exc 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-10-2020 13:28:31 to 28-10-2020 13:28:33.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          221909
MON$OLDEST_ACTIVE               224676
MON$OLDEST_SNAPSHOT             224676
MON$NEXT_TRANSACTION            224676
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-30 07:30:08.6030 GMT
MON$PAGES                       2117760
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             756
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2240 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2240 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2240 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2240 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:64: 
:65: mscs-test-161.budzdorov.ru    Wed Oct 28 13:27:03 2020
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:67: 
:68: 
:69: mscs-test-161.budzdorov.ru    Wed Oct 28 13:27:03 2020
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:71: 
:72: 
:73: mscs-test-161.budzdorov.ru    Wed Oct 28 13:27:03 2020
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:75: 
--- end of diff output ---

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

28.10.20 13:28:34. There are 3 logs in /var/tmp/logs-oltp40 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
2020-10-28 13:28:36 No severe PSQL-related problems occured
28.10.20 13:28:37. End of report.