Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.11.2020 10:27
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 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 16805404 kB
MemAvailable 31372204 kB
Buffers 0 kB
Cached 14830496 kB
SwapCached 3856 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-11-17 10:26:55
DTS_END                         2020-11-17 10:26:55.4440
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40878
TRN_ID                          234322
ATT_ID                          673
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: 9273.59 25463 1112831
customer order: creation 165.56 384 19867
customer order: refuse 14.68 325 1761
order to supplier: creation 45.78 1293 5493
order to supplier: removal 3.40 120691 408
invoice (draft): creation 45.68 3183 5482
invoice (draft): removal 51.34 1049 6161
invoice accept: apply 42.73 18370 5128
invoice accept: cancel 46.60 899 5592
customer reserve: creation 4004.58 178 480550
customer reserve: removal 4569.58 69 548349
realization accept: apply 81.33 182 9759
realization accept: cancel 76.18 118 9141
payment from customer: creation 56.54 107 6785
payment from customer: removal 5.92 66 710
payment to supplier: creation 49.35 116 5922
payment to supplier: removal 3.33 55 399
service: total inventory turnovers 3.80 3399 456
service: total monetary turnovers 6.21 504 745
service: aggregate perf. data 0.99 13128 119
service: refresh index statistics 0.03 345142 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-11-2020 10:28:07 to 17-11-2020 10:28:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1881
TEST_TIME 2 3684
TEST_TIME 3 4972
TEST_TIME 4 5251
TEST_TIME 5 5174
TEST_TIME 6 5681
TEST_TIME 7 5578
TEST_TIME 8 5544
TEST_TIME 9 5740
TEST_TIME 10 6021
TEST_TIME 11 6221
TEST_TIME 12 6602
TEST_TIME 13 6794
TEST_TIME 14 6956
TEST_TIME 15 6917
TEST_TIME 16 7281
TEST_TIME 17 7541
TEST_TIME 18 7706
TEST_TIME 19 8025
TEST_TIME 20 8166
TEST_TIME 21 8713
TEST_TIME 22 8864
TEST_TIME 23 8594
TEST_TIME 24 8446
TEST_TIME 25 9015
TEST_TIME 26 11190
TEST_TIME 27 11777
TEST_TIME 28 11670
TEST_TIME 29 11392
TEST_TIME 30 11306
TEST_TIME 31 10982
TEST_TIME 32 11032
TEST_TIME 33 10855
TEST_TIME 34 10782
TEST_TIME 35 10691
TEST_TIME 36 10686
TEST_TIME 37 10662
TEST_TIME 38 10560
TEST_TIME 39 10429
TEST_TIME 40 10334
TEST_TIME 41 10181
TEST_TIME 42 10051
TEST_TIME 43 10241
TEST_TIME 44 10251
TEST_TIME 45 10236
TEST_TIME 46 10304
TEST_TIME 47 10232
TEST_TIME 48 10304
TEST_TIME 49 10232
TEST_TIME 50 10278
TEST_TIME 51 10302
TEST_TIME 52 10347
TEST_TIME 53 10428
TEST_TIME 54 10379
TEST_TIME 55 10350
TEST_TIME 56 10479
TEST_TIME 57 10320
TEST_TIME 58 10171
TEST_TIME 59 10125
TEST_TIME 60 10039
TEST_TIME 61 9945
TEST_TIME 62 9895
TEST_TIME 63 9830
TEST_TIME 64 9830
TEST_TIME 65 9794
TEST_TIME 66 9740
TEST_TIME 67 9743
TEST_TIME 68 9710
TEST_TIME 69 9708
TEST_TIME 70 9865
TEST_TIME 71 9849
TEST_TIME 72 9874
TEST_TIME 73 9887
TEST_TIME 74 9867
TEST_TIME 75 9907
TEST_TIME 76 9984
TEST_TIME 77 9870
TEST_TIME 78 9809
TEST_TIME 79 9740
TEST_TIME 80 9679
TEST_TIME 81 9627
TEST_TIME 82 9572
TEST_TIME 83 9577
TEST_TIME 84 9599
TEST_TIME 85 9557
TEST_TIME 86 9550
TEST_TIME 87 9545
TEST_TIME 88 9587
TEST_TIME 89 9563
TEST_TIME 90 9647
TEST_TIME 91 9646
TEST_TIME 92 9610
TEST_TIME 93 9706
TEST_TIME 94 9619
TEST_TIME 95 9591
TEST_TIME 96 9516
TEST_TIME 97 9470
TEST_TIME 98 9511
TEST_TIME 99 9462
TEST_TIME 100 9452
TEST_TIME 101 9420
TEST_TIME 102 9449
TEST_TIME 103 9479
TEST_TIME 104 9455
TEST_TIME 105 9433
TEST_TIME 106 9447
TEST_TIME 107 9475
TEST_TIME 108 9476
TEST_TIME 109 9499
TEST_TIME 110 9530
TEST_TIME 111 9531
TEST_TIME 112 9512
TEST_TIME 113 9444
TEST_TIME 114 9416
TEST_TIME 115 9358
TEST_TIME 116 9331
TEST_TIME 117 9288
TEST_TIME 118 9262
TEST_TIME 119 9246
TEST_TIME 120 9279

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-11-2020 10:28:10 to 17-11-2020 10:28:14.

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 1555028 1555028 0 0.00 0 18712 7 0 0
doc_list_biud 1555027 1555027 0 0.00 0 27364 31 0 0
sp_add_invoice_to_stock 5260 5128 132 2.50 28 172742 18371 0 132
sp_cancel_adding_invoice 5592 5592 0 0.00 5 88458 900 0 0
sp_cancel_client_order 1761 1761 0 0.00 5 20677 325 0 0
sp_cancel_customer_reserve 548349 548349 0 0.00 1 27367 70 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 3 2903 67 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 1271 56 0 0
sp_cancel_supplier_invoice 6177 6161 16 0.25 2 20536 1049 0 16
sp_cancel_supplier_order 408 408 0 0.00 5 1278941 120691 0 0
sp_cancel_write_off 9141 9141 0 0.00 1 11674 118 0 0
sp_client_order 19867 19867 0 0.00 4 19313 384 0 0
sp_customer_reserve 480550 480550 0 0.00 0 47198 178 0 0
sp_fill_shopping_cart 30836 30836 0 0.00 1 11470 40 0 0
sp_fill_shopping_cart_clo_res 480544 480544 0 0.00 0 28856 17 0 0
sp_get_clo_for_invoice 5121 5121 0 0.00 0 35501 354 0 0
sp_kill_cost_storno 15842 15842 0 0.00 0 5820 56 0 0
sp_kill_qstorno_ret_qs2qd 1112408 1112408 0 0.00 0 27357 38 0 0
sp_kill_qty_storno 586292 586292 0 0.00 0 27363 82 0 0
sp_lock_selected_doc 20694 20694 0 0.00 0 9413 6 0 0
sp_make_cost_storno 27587 27587 0 0.00 0 17549 48 0 0
sp_multiply_rows_for_pdistr 27587 27587 0 0.00 0 6966 30 0 0
sp_multiply_rows_for_qdistr 19867 19867 0 0.00 0 17257 216 0 0
sp_pay_from_customer 6785 6785 0 0.00 4 19126 108 0 0
sp_pay_to_supplier 5922 5922 0 0.00 5 9806 116 0 0
sp_payment_common 12707 12707 0 0.00 1 7402 21 0 0
sp_qd_handle_on_cancel_clo 1761 1761 0 0.00 0 20669 286 0 0
sp_qd_handle_on_invoice_upd_sts 10713 10713 0 0.00 0 16361 379 0 0
sp_qd_handle_on_reserve_upd_sts 9759 9759 0 0.00 0 21717 53 0 0
sp_reserve_write_off 9759 9759 0 0.00 7 21759 182 0 0
sp_supplier_invoice 5482 5482 0 0.00 7 74201 3184 0 0
sp_supplier_order 5493 5493 0 0.00 6 70286 1294 0 0
srv_aggregate_perf_data 119 119 0 0.00 2569 173026 13128 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1714 145720 43760 0 0
srv_find_qd_qs_mism 1063755 1063755 0 0.00 0 18712 6 0 0
srv_make_invnt_saldo 456 456 0 0.00 52 96313 3399 0 0
srv_make_money_saldo 745 745 0 0.00 1 13056 505 0 0
srv_recalc_idx_stat 4 4 0 0.00 147602 593436 345142 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5129 5129 0 0.00 0 13051 168 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 5692 84 0 0
t$perf-norm:sp_cancel_client_order 1761 1761 0 0.00 0 2629 13 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 1712 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 3288 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 2360 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 510 15 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 24231 1618 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 621 5 0 0
t$perf-norm:sp_client_order 19867 19867 0 0.00 0 11234 15 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 794 6 0 0
t$perf-norm:sp_pay_from_customer 6785 6785 0 0.00 0 4861 8 0 0
t$perf-norm:sp_pay_to_supplier 5922 5922 0 0.00 0 4912 8 0 0
t$perf-norm:sp_reserve_write_off 9759 9759 0 0.00 0 4888 9 0 0
t$perf-norm:sp_supplier_invoice 5482 5482 0 0.00 0 15771 21 0 0
t$perf-norm:sp_supplier_order 5493 5493 0 0.00 0 4192 16 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 772 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 3223 61 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 1806 10 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 2471 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 24 8 0 0
v_add_invoice_to_stock 10500 10236 264 2.51 0 7752 18 0 264
v_all_customers 35992 35992 0 0.00 0 121 1 0 0
v_all_suppliers 30548 30548 0 0.00 0 19 1 0 0
v_all_wares 39732 39732 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 699 7 0 0
v_cancel_client_order 3522 3522 0 0.00 0 18871 9 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 2895 7 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 3794 9 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 715 4 0 0
v_cancel_supplier_invoice 896 864 32 3.57 0 1343 16 0 32
v_cancel_supplier_order 816 816 0 0.00 0 324 4 0 0
v_cancel_write_off 2008 2008 0 0.00 0 4064 20 0 0
v_max_id_clo_ord 5491 5491 0 0.00 0 607 1 0 0
v_max_id_clo_res 974 974 0 0.00 0 6140 17 0 0
v_max_id_ord_sup 5479 5479 0 0.00 0 4329 6 0 0
v_max_non_paid_invoice 1618 1618 0 0.00 0 569 3 0 0
v_max_non_paid_realizn 3673 3673 0 0.00 0 3101 4 0 0
v_min_id_clo_ord 5491 5491 0 0.00 0 7292 9 0 0
v_min_id_clo_res 974 974 0 0.00 0 11443 28 0 0
v_min_id_ord_sup 5479 5479 0 0.00 0 5041 6 0 0
v_min_non_paid_invoice 5922 5922 0 0.00 0 4330 9 0 0
v_min_non_paid_realizn 6785 6785 0 0.00 0 4982 14 0 0
v_our_firm 9964 9964 0 0.00 0 121 1 0 0
v_reserve_write_off 19518 19518 0 0.00 0 17441 7 0 0
x_lock_depdocs_on_canc_invoice 11321 11321 0 0.00 0 13075 115 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 18537 386 0 0
x_make_qstorno_1000_1200 5493 5493 0 0.00 2 70240 1212 0 0
x_make_qstorno_1200_2000 5482 5482 0 0.00 3 71363 3041 0 0
x_make_qstorno_2100_3300 480550 480550 0 0.00 0 42256 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-11-2020 10:28:15 to 17-11-2020 10:28:19.

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
07:56:59 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
07:58:01 38275 8192000000 9737984576 10037035008 808913520 8.987 100 86 172 1 837867200 8015424 20938256
07:59:48 16567 8192000000 9771967456 10080079872 846163424 9.362 100 88 176 0 880922592 13664576 21094592
08:01:11 24436 8192000000 9822500064 10147487744 884666944 9.746 100 83 166 0 927248224 23129968 19451312
08:02:41 16102 8192000000 9880869600 10216402944 923215504 10.128 100 83 165 2 984593312 43086784 18291024
08:04:02 7563 8192000000 9924529456 10273189888 967878272 10.566 100 75 149 1 1034939040 51240784 15819984
08:05:12 4023 8192000000 9978392128 10338365440 1011385392 10.989 100 83 165 1 1100382320 72344256 16652672
08:06:19 3441 8192000000 10012729136 10377560064 1031422944 11.182 100 87 173 1 1124491856 74922496 18146416
08:07:24 2502 8192000000 10025079024 10396323840 1047419232 11.336 100 85 170 2 1141019280 76792016 16808032
08:08:28 1575 8192000000 10058398752 10426683392 1061324736 11.469 100 93 187 0 1168708688 89292464 18091488
08:09:33 3258 8192000000 10028486144 10418200576 1064777200 11.502 100 47 94 2 1135550928 61706608 9067120
08:10:38 6925 8192000000 9995875920 10387714048 1068456608 11.537 100 39 78 0 1119434176 42475040 8502528
08:11:46 4662 8192000000 10014889008 10415833088 1073758096 11.588 100 38 76 1 1128250048 46812912 7679040
08:12:51 9686 8192000000 9996605008 10402881536 1083475424 11.681 100 15 30 2 1110264336 23741216 3047696
08:14:01 5606 8192000000 9998655504 10404720640 1090753792 11.750 100 37 73 0 1114935792 16105824 8076176
08:15:06 12563 8192000000 10002694608 10404880384 1099381200 11.832 100 39 75 2 1130774112 22899680 8493232
08:16:19 13482 8192000000 10016342672 10401980416 1084332240 11.689 99 78 158 1 1112385584 8737184 19316160
08:17:33 6183 8192000000 10016796176 10405875712 1088790064 11.731 100 40 77 4 1125280304 28705328 7784912
08:18:40 7948 8192000000 9980378192 10384977920 1055059488 11.409 98 30 60 2 1088396144 26794992 6541664
08:19:48 5279 8192000000 9923920192 10307571712 985034608 10.733 96 34 68 1 1003819952 11480928 7304416
08:20:55 14128 8192000000 9932828048 10305880064 996865776 10.848 98 74 149 1 1028678688 15194192 16618720
08:22:12 9369 8192000000 9924443376 10318585856 994668832 10.827 98 38 73 3 1022702048 19740640 8292576
08:23:22 8377 8192000000 9959815520 10358157312 1031286592 11.181 99 35 71 1 1061108800 21663392 8158816
08:24:33 4862 8192000000 9966709440 10361131008 1055768384 11.416 100 37 75 2 1094307888 30704928 7834576
08:25:41 58475 8192000000 9985631072 10399985664 1063030656 11.485 100 32 64 1 1096571808 26464736 7076416
08:27:48 14635 8192000000 10025690560 10420449280 1077130448 11.620 100 54 107 2 1109159200 20156896 11871856
08:29:09 2845 8192000000 10076372064 10475376640 1100840304 11.846 100 69 139 0 1184425744 69285200 14300240
08:30:14 18935 8192000000 10116424640 10520186880 1113737344 11.968 100 54 108 0 1222680304 97377296 11565664
08:31:44 10943 8192000000 10123123024 10530283520 1126279120 12.086 100 47 95 1 1221924832 85681872 9963840
08:32:57 23523 8192000000 10153557488 10559328256 1138522704 12.202 100 45 86 1 1250508800 102701328 9284768
08:34:26 24873 8192000000 10173933808 10588934144 1142641376 12.240 100 46 92 1 1282166144 129110512 10414256
08:36:02 3721 8192000000 10221519936 10623586304 1154779872 12.354 100 72 142 1 1325089904 155518416 14791616
08:37:09 4854 8192000000 10281772208 10685104128 1166122112 12.461 100 83 166 2 1379953056 196278608 17552336
08:38:18 10672 8192000000 10290851856 10716741632 1169542480 12.493 99 65 128 2 1408860912 225076544 14241888
08:39:35 2035 8192000000 10371370592 10774704128 1188740352 12.672 100 90 177 0 1468897136 259252800 20903984
08:40:43 1937 8192000000 10396817024 10806308864 1194100032 12.722 100 90 178 1 1493687632 279442736 20144864
08:41:48 2297 8192000000 10431165072 10836787200 1196330816 12.742 100 94 182 1 1534096032 317178720 20586496
08:42:56 2714 8192000000 10444814880 10873425920 1182273248 12.611 100 80 159 2 1537431472 335991968 19166256
08:44:05 1713 8192000000 10490975184 10891952128 1193453904 12.715 100 92 183 1 1585738640 369278320 23006416
08:45:13 1846 8192000000 10452677648 10874855424 1170372032 12.500 99 82 158 1 1552273712 362557776 19343904
08:46:21 4166 8192000000 10509341632 10947973120 1191961904 12.702 100 66 132 1 1620712304 411174784 17575616
08:47:28 6862 8192000000 10497706304 10965655552 1196452144 12.743 100 43 86 0 1591324256 383735392 11136720
08:48:39 15417 8192000000 10504366816 10952200192 1186325472 12.649 100 35 70 0 1608693984 413184848 9183664
08:50:01 31198 8192000000 10598473024 11057627136 1161969616 12.422 100 30 60 1 1694601408 523534208 9097584
08:51:35 20806 8192000000 10501172992 10971742208 1124093072 12.066 99 30 60 1 1603953872 471150688 8710112
08:52:59 43521 8192000000 10107486736 10566930432 1112509088 11.956 98 10 19 0 1213938464 99800752 1628624
08:54:43 40496 8192000000 10077202160 10517639168 1112869360 11.960 99 23 48 0 1147491568 29422352 5199856
08:56:35 145720 8192000000 10079840576 10556567552 1113766880 11.968 98 30 58 1 1199962512 79846240 6349392
09:00:09 5986 8192000000 10087387920 10521681920 1133903200 12.158 100 41 82 0 1180258880 38197248 8158432
09:01:15 34273 8192000000 10088600128 10526556160 1136124944 12.179 100 19 38 1 1183587824 43344128 4118752
09:02:51 32339 8192000000 10071777392 10526961664 1113978640 11.970 99 23 46 0 1173539872 54525936 5035296
09:04:35 13056 8192000000 10077465408 10531770368 1128021184 12.103 100 23 46 0 1173970800 41219136 4730480
09:05:53 55367 8192000000 10095382160 10543685632 1110585280 11.938 98 17 34 0 1168420208 53725728 4109200
09:08:49 84227 8192000000 10069301648 10566307840 1135544400 12.174 100 16 32 0 1271670096 132353408 3772288
09:11:17 37412 8192000000 10070095392 10515988480 1133310496 12.153 99 19 36 1 1170066256 33057504 3698256
09:12:55 18696 8192000000 10086112352 10536177664 1142542384 12.239 100 35 68 1 1174544752 24509360 7493008
09:14:17 46887 8192000000 10091935984 10534129664 1135581872 12.174 100 20 40 2 1191250352 51570576 4097904
09:16:04 30976 8192000000 10138752448 10572099584 1146751760 12.279 100 44 88 1 1217659872 61723200 9184912
09:17:40 70222 8192000000 10104230128 10586288128 1114861584 11.978 98 16 31 3 1212317824 94204320 3251920
09:19:50 80978 8192000000 10106903744 10539802624 1110053792 11.933 97 16 33 0 1157868784 44459152 3355840
09:22:15 64101 8192000000 10055280704 10511753216 1093470256 11.776 96 20 40 0 1145094816 47297904 4326656
09:24:24 68429 8192000000 10079065552 10506833920 1128247632 12.105 100 28 57 0 1156574128 22520176 5806320
09:26:37 43583 8192000000 10070127312 10540974080 1136389376 12.182 100 10 20 0 1170102576 31852048 1861152
09:28:21 39763 8192000000 10043240576 10507345920 1091745088 11.759 96 9 19 0 1125494080 32035552 1713440
09:30:01 62410 8192000000 10073635184 10553737216 1113290176 11.964 98 16 33 2 1172529520 54943904 4295440
09:32:05 40834 8192000000 10109246240 10558717952 1144192096 12.255 100 19 37 2 1211754480 63482928 4079456
09:33:50 43033 8192000000 10125457760 10570129408 1104872368 11.884 97 26 52 3 1218613680 108034352 5706960
09:35:46 60937 8192000000 10051971472 10575585280 1035655280 11.223 92 15 30 0 1142997040 104020512 3321248
09:37:55 61292 8192000000 10078811856 10529280000 1096771568 11.807 98 21 42 0 1153285648 52282880 4231200
09:39:56 32805 8192000000 10089978848 10543788032 1122311072 12.049 100 21 42 0 1184148688 57003136 4834480
09:41:32 47421 8192000000 10070973216 10604969984 1122106608 12.047 99 8 16 0 1229413056 105333360 1973088
09:43:24 78512 8192000000 10047350400 10494623744 1089152224 11.735 96 18 36 0 1113123904 20300704 3670976
09:46:03 60909 8192000000 10023675152 10487099392 1092648112 11.768 97 7 14 0 1114956688 20954096 1354480
09:48:18 46364 8192000000 10080056544 10532302848 1109730640 11.930 99 14 28 2 1158127296 45218544 3178112
09:50:14 77268 8192000000 10064536880 10499772416 1071220208 11.564 95 21 42 0 1100724608 24793952 4710448
09:52:45 63482 8192000000 10116397712 10571309056 1120144288 12.028 99 24 47 1 1205395344 80128944 5122112
09:54:58 112811 8192000000 10123843760 10618900480 1102497424 11.861 97 23 46 1 1203695744 96115392 5082928
09:57:52 17308 8192000000 10080390704 10501464064 1110296352 11.935 98 52 104 0 1155448704 34961488 10190864
09:59:10 139108 8192000000 10085791264 10580385792 1112298656 11.954 98 39 78 0 1197336224 77369776 7667792
10:02:43 69949 8192000000 10085481600 10550468608 1123969440 12.064 99 11 22 0 1191211424 64849808 2392176
10:05:11 71281 8192000000 10081796592 10537598976 1113262704 11.963 98 15 30 1 1139717648 22973536 3481408
10:07:22 40450 8192000000 10119813360 10567593984 1161811440 12.420 100 31 61 1 1200321088 32133792 6375856
10:09:03 86538 8192000000 10074172416 10562138112 1100893568 11.846 95 9 18 1 1180889424 78055344 1940512
10:11:39 26568 8192000000 10106362080 10534871040 1116785056 11.997 97 47 94 2 1163289680 37392256 9112368
10:13:07 40176 8192000000 10081089392 10581880832 1132536640 12.145 100 18 35 2 1225625584 89318944 3770000
10:14:52 56362 8192000000 10136941520 10584047616 1109354048 11.926 98 31 62 0 1156310512 41092464 5864000
10:16:48 109176 8192000000 10092940272 10548408320 1129170592 12.114 99 11 22 0 1185271600 54444512 1656496
10:19:40 122366 8192000000 10084694064 10538967040 1132549952 12.145 99 22 44 2 1178275248 40709488 5015808
10:23:46 41045 8192000000 10065114368 10516877312 1105995952 11.894 97 6 12 0 1140326000 33258320 1071728
10:25:37 73901 8192000000 10080587232 10578161664 1093769056 11.778 96 15 30 0 1162588688 66022784 2796848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-11-2020 10:28:21 to 17-11-2020 10:28:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-11-2020 10:28:37 to 17-11-2020 10:28:39.

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          233927
MON$OLDEST_ACTIVE               234452
MON$OLDEST_SNAPSHOT             234452
MON$NEXT_TRANSACTION            234452
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                       2089248
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             798
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2267 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2267 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2267 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.2267 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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Tue Nov 17 10:27:02 2020
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Tue Nov 17 10:27:08 2020
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
--- end of diff output ---

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

17.11.20 10:28:40. 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-11-17 10:28:42 No severe PSQL-related problems occured
17.11.20 10:28:44. End of report.