Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.11.2020 04: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 16875028 kB
MemAvailable 31425244 kB
Buffers 20 kB
Cached 14704616 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-11-01 04:27:02
DTS_END                         2020-11-01 04:27:02.6340
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39996
TRN_ID                          237102
ATT_ID                          684
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: 8987.83 25148 1078539
customer order: creation 168.89 371 20267
customer order: refuse 14.51 323 1741
order to supplier: creation 44.73 1338 5367
order to supplier: removal 3.26 125403 391
invoice (draft): creation 45.54 3615 5465
invoice (draft): removal 50.58 1002 6070
invoice accept: apply 41.10 16971 4932
invoice accept: cancel 46.22 902 5546
customer reserve: creation 3767.86 167 452143
customer reserve: removal 4509.82 70 541178
realization accept: apply 82.86 178 9943
realization accept: cancel 85.17 126 10220
payment from customer: creation 58.46 109 7015
payment from customer: removal 5.88 102 705
payment to supplier: creation 48.87 121 5864
payment to supplier: removal 3.44 72 413
service: total inventory turnovers 3.61 3453 433
service: total monetary turnovers 6.00 546 720
service: aggregate perf. data 1.01 14307 121
service: refresh index statistics 0.04 333779 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-11-2020 04:28:06 to 01-11-2020 04:28:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5595
TEST_TIME 2 4503
TEST_TIME 3 5344
TEST_TIME 4 5572
TEST_TIME 5 5903
TEST_TIME 6 5496
TEST_TIME 7 5371
TEST_TIME 8 5529
TEST_TIME 9 5780
TEST_TIME 10 5823
TEST_TIME 11 5771
TEST_TIME 12 5738
TEST_TIME 13 5759
TEST_TIME 14 6537
TEST_TIME 15 6491
TEST_TIME 16 6967
TEST_TIME 17 7412
TEST_TIME 18 7308
TEST_TIME 19 7503
TEST_TIME 20 7873
TEST_TIME 21 8648
TEST_TIME 22 8898
TEST_TIME 23 9127
TEST_TIME 24 9155
TEST_TIME 25 9555
TEST_TIME 26 10574
TEST_TIME 27 11419
TEST_TIME 28 11430
TEST_TIME 29 11240
TEST_TIME 30 11013
TEST_TIME 31 10723
TEST_TIME 32 10542
TEST_TIME 33 10524
TEST_TIME 34 10505
TEST_TIME 35 10443
TEST_TIME 36 10511
TEST_TIME 37 10472
TEST_TIME 38 10286
TEST_TIME 39 10190
TEST_TIME 40 10212
TEST_TIME 41 10231
TEST_TIME 42 10162
TEST_TIME 43 10122
TEST_TIME 44 10181
TEST_TIME 45 10182
TEST_TIME 46 10196
TEST_TIME 47 10072
TEST_TIME 48 10013
TEST_TIME 49 10293
TEST_TIME 50 10230
TEST_TIME 51 10195
TEST_TIME 52 10210
TEST_TIME 53 10297
TEST_TIME 54 10208
TEST_TIME 55 10046
TEST_TIME 56 9892
TEST_TIME 57 9814
TEST_TIME 58 9690
TEST_TIME 59 9782
TEST_TIME 60 9717
TEST_TIME 61 9732
TEST_TIME 62 9729
TEST_TIME 63 9675
TEST_TIME 64 9705
TEST_TIME 65 9656
TEST_TIME 66 9673
TEST_TIME 67 9669
TEST_TIME 68 9636
TEST_TIME 69 9781
TEST_TIME 70 9756
TEST_TIME 71 9790
TEST_TIME 72 9778
TEST_TIME 73 9762
TEST_TIME 74 9697
TEST_TIME 75 9573
TEST_TIME 76 9498
TEST_TIME 77 9448
TEST_TIME 78 9493
TEST_TIME 79 9441
TEST_TIME 80 9549
TEST_TIME 81 9498
TEST_TIME 82 9510
TEST_TIME 83 9529
TEST_TIME 84 9518
TEST_TIME 85 9546
TEST_TIME 86 9491
TEST_TIME 87 9569
TEST_TIME 88 9545
TEST_TIME 89 9535
TEST_TIME 90 9464
TEST_TIME 91 9388
TEST_TIME 92 9360
TEST_TIME 93 9348
TEST_TIME 94 9274
TEST_TIME 95 9283
TEST_TIME 96 9240
TEST_TIME 97 9208
TEST_TIME 98 9210
TEST_TIME 99 9221
TEST_TIME 100 9225
TEST_TIME 101 9226
TEST_TIME 102 9211
TEST_TIME 103 9221
TEST_TIME 104 9210
TEST_TIME 105 9232
TEST_TIME 106 9229
TEST_TIME 107 9282
TEST_TIME 108 9292
TEST_TIME 109 9323
TEST_TIME 110 9327
TEST_TIME 111 9327
TEST_TIME 112 9257
TEST_TIME 113 9196
TEST_TIME 114 9161
TEST_TIME 115 9097
TEST_TIME 116 9090
TEST_TIME 117 9067
TEST_TIME 118 9027
TEST_TIME 119 9062
TEST_TIME 120 8988

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-11-2020 04:28:10 to 01-11-2020 04:28:13.

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 1497824 1497824 0 0.00 0 15596 7 0 0
doc_list_biud 1497824 1497824 0 0.00 0 43257 32 0 0
sp_add_invoice_to_stock 5020 4932 88 1.75 35 165405 16972 0 88
sp_cancel_adding_invoice 5546 5546 0 0.00 6 99568 903 0 0
sp_cancel_client_order 1741 1741 0 0.00 4 10080 323 0 0
sp_cancel_customer_reserve 541178 541178 0 0.00 1 35173 71 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 3 6938 103 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 2 2716 72 0 0
sp_cancel_supplier_invoice 6079 6070 9 0.14 2 16895 1003 0 9
sp_cancel_supplier_order 391 391 0 0.00 7 1204815 125403 0 0
sp_cancel_write_off 10220 10220 0 0.00 1 10607 127 0 0
sp_client_order 20267 20267 0 0.00 4 38292 371 0 0
sp_customer_reserve 452143 452143 0 0.00 0 65923 167 0 0
sp_fill_shopping_cart 31073 31073 0 0.00 1 17751 46 0 0
sp_fill_shopping_cart_clo_res 452140 452140 0 0.00 0 24989 16 0 0
sp_get_clo_for_invoice 4929 4929 0 0.00 0 32110 357 0 0
sp_kill_cost_storno 16884 16884 0 0.00 0 10543 63 0 0
sp_kill_qstorno_ret_qs2qd 1099037 1099037 0 0.00 0 33498 38 0 0
sp_kill_qty_storno 580018 580018 0 0.00 0 43257 83 0 0
sp_lock_selected_doc 20711 20711 0 0.00 0 7212 5 0 0
sp_make_cost_storno 27751 27751 0 0.00 0 9419 53 0 0
sp_multiply_rows_for_pdistr 27751 27751 0 0.00 0 7083 31 0 0
sp_multiply_rows_for_qdistr 20267 20267 0 0.00 0 11398 181 0 0
sp_pay_from_customer 7015 7015 0 0.00 4 11640 109 0 0
sp_pay_to_supplier 5864 5864 0 0.00 4 13692 122 0 0
sp_payment_common 12879 12879 0 0.00 1 7711 17 0 0
sp_qd_handle_on_cancel_clo 1741 1741 0 0.00 0 10074 284 0 0
sp_qd_handle_on_invoice_upd_sts 10475 10475 0 0.00 0 43256 388 0 0
sp_qd_handle_on_reserve_upd_sts 9943 9943 0 0.00 0 25736 66 0 0
sp_reserve_write_off 9943 9943 0 0.00 7 33592 179 0 0
sp_supplier_invoice 5465 5465 0 0.00 6 108779 3615 0 0
sp_supplier_order 5367 5367 0 0.00 5 46741 1339 0 0
srv_aggregate_perf_data 121 121 0 0.00 2516 245544 14308 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1380 179286 45683 0 0
srv_find_qd_qs_mism 1031933 1031933 0 0.00 0 15584 6 0 0
srv_make_invnt_saldo 433 433 0 0.00 49 94687 3454 0 0
srv_make_money_saldo 720 720 0 0.00 1 13388 547 0 0
srv_recalc_idx_stat 5 5 0 0.00 145994 497870 333780 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4932 4932 0 0.00 0 11753 147 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 5850 77 0 0
t$perf-norm:sp_cancel_client_order 1741 1741 0 0.00 0 2168 11 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 517 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 4011 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 687 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1663 16 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 17271 1428 0 0
t$perf-norm:sp_cancel_write_off 1092 1092 0 0.00 0 1340 3 0 0
t$perf-norm:sp_client_order 20268 20268 0 0.00 0 7609 20 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 797 5 0 0
t$perf-norm:sp_pay_from_customer 7015 7015 0 0.00 0 6351 9 0 0
t$perf-norm:sp_pay_to_supplier 5864 5864 0 0.00 0 3892 9 0 0
t$perf-norm:sp_reserve_write_off 9943 9943 0 0.00 0 9593 12 0 0
t$perf-norm:sp_supplier_invoice 5465 5465 0 0.00 0 6467 22 0 0
t$perf-norm:sp_supplier_order 5367 5367 0 0.00 0 13871 20 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 556 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 645 15 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 4557 26 0 0
t$perf-norm:srv_make_money_saldo 720 720 0 0.00 0 310 3 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10026 9850 176 1.75 0 10958 19 0 176
v_all_customers 37094 37094 0 0.00 0 81 1 0 0
v_all_suppliers 30458 30458 0 0.00 0 47 1 0 0
v_all_wares 40532 40532 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 2964 11 0 0
v_cancel_client_order 3482 3482 0 0.00 0 7805 8 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 5484 13 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 2310 5 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 2247 7 0 0
v_cancel_supplier_invoice 798 780 18 2.25 0 2430 17 0 18
v_cancel_supplier_order 782 782 0 0.00 0 1654 6 0 0
v_cancel_write_off 2184 2184 0 0.00 0 4104 18 0 0
v_max_id_clo_ord 5366 5366 0 0.00 0 2026 2 0 0
v_max_id_clo_res 971 971 0 0.00 0 4051 23 0 0
v_max_id_ord_sup 5441 5441 0 0.00 0 13696 9 0 0
v_max_non_paid_invoice 1442 1442 0 0.00 0 53 2 0 0
v_max_non_paid_realizn 3630 3630 0 0.00 0 3231 4 0 0
v_min_id_clo_ord 5366 5366 0 0.00 0 5166 6 0 0
v_min_id_clo_res 971 971 0 0.00 0 4231 8 0 0
v_min_id_ord_sup 5441 5441 0 0.00 0 6262 7 0 0
v_min_non_paid_invoice 5864 5864 0 0.00 0 5028 10 0 0
v_min_non_paid_realizn 7015 7015 0 0.00 0 6166 10 0 0
v_our_firm 10208 10208 0 0.00 0 78 1 0 0
v_reserve_write_off 19874 19874 0 0.00 0 7826 6 0 0
x_lock_depdocs_on_canc_invoice 11226 11226 0 0.00 0 18580 109 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 17141 408 0 0
x_make_qstorno_1000_1200 5367 5367 0 0.00 2 46717 1264 0 0
x_make_qstorno_1200_2000 5465 5465 0 0.00 3 108773 3425 0 0
x_make_qstorno_2100_3300 452143 452143 0 0.00 0 65923 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-11-2020 04:28:14 to 01-11-2020 04:28:18.

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
01:57:06 1 8192000000 8796633136 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
01:58:07 24530 8192000000 9705041792 10000334848 783628464 8.730 100 87 174 0 813324208 7608800 22086944
01:59:34 12764 8192000000 9743038928 10044596224 819469744 9.093 100 88 176 0 853191168 12499472 21221952
02:00:53 15979 8192000000 9781045232 10092466176 847153104 9.372 100 82 163 1 886844576 20533840 19157632
02:02:15 19656 8192000000 9852078128 10180857856 905757168 9.955 100 82 162 2 953687424 29510624 18419632
02:03:38 9397 8192000000 9894416896 10236850176 943258480 10.325 100 72 144 0 1022786896 63813168 15715248
02:04:50 4611 8192000000 9952288112 10306027520 991395472 10.795 100 79 156 1 1061460800 54210144 15855184
02:05:56 3446 8192000000 9989504368 10351620096 1018985552 11.062 100 88 177 1 1103106544 66065680 18055312
02:07:03 2864 8192000000 10009651888 10378448896 1039211168 11.257 100 73 146 1 1121142576 67506496 14424912
02:08:07 1588 8192000000 10060730768 10432262144 1065381488 11.508 100 87 174 2 1171906096 88993120 17531488
02:09:10 16217 8192000000 10027802768 10429026304 1072495808 11.576 100 45 90 2 1146085552 64724512 8865232
02:10:28 4994 8192000000 10017360208 10407976960 1084295392 11.688 100 52 104 1 1130119536 35092576 10731568
02:11:35 5353 8192000000 10003662560 10404085760 1088167056 11.725 100 43 83 3 1116796768 19198528 9431184
02:12:43 73965 8192000000 10029607952 10451243008 1093721344 11.778 100 25 50 1 1131798720 32677408 5399968
02:15:00 8197 8192000000 10019116416 10421284864 1101725088 11.854 100 44 88 1 1141705408 30175456 9804864
02:16:09 6033 8192000000 10025843632 10421547008 1089910592 11.742 99 23 46 3 1118330288 22610672 5809024
02:17:16 57410 8192000000 10006229408 10394099712 1071900256 11.570 98 30 58 3 1102191136 23661008 6629872
02:19:14 8184 8192000000 9958938656 10350247936 1021766016 11.089 97 38 75 4 1049102800 18408352 8928432
02:20:22 7029 8192000000 9933835872 10321833984 1012805920 11.003 97 48 93 2 1049296816 26076112 10414784
02:21:29 7796 8192000000 9944073904 10335293440 1018422864 11.057 99 39 80 0 1059233408 32425600 8384944
02:22:38 45703 8192000000 9930612752 10327277568 985454080 10.737 96 24 48 2 1013877040 23792832 4630128
02:24:27 12061 8192000000 9957341072 10352267264 1045691808 11.319 100 46 92 0 1073183664 16821344 10670512
02:25:39 6304 8192000000 9968873184 10354585600 1059522912 11.452 100 43 87 3 1081704672 12249648 9932112
02:26:47 70793 8192000000 9999889984 10391474176 1079606688 11.644 100 38 73 1 1100333152 12983520 7742944
02:29:07 28317 8192000000 10060601920 10455465984 1106552160 11.900 100 51 101 1 1165506448 48295728 10658560
02:30:45 8233 8192000000 10105482272 10508562432 1125472768 12.079 100 49 99 2 1217499088 81852112 10174208
02:31:58 15787 8192000000 10128892864 10549583872 1143768896 12.251 100 44 88 2 1238097456 84066480 10262080
02:33:21 6792 8192000000 10164608192 10578051072 1149999808 12.309 100 41 82 2 1283915088 125105952 8809328
02:34:33 8053 8192000000 10202273312 10609864704 1155851440 12.364 100 53 106 5 1308234944 139525600 12857904
02:35:47 10851 8192000000 10250393776 10660061184 1149235664 12.302 99 45 90 0 1349672656 189429936 11007056
02:37:04 7890 8192000000 10272791760 10675765248 1163498160 12.436 100 62 122 1 1374308144 196574736 14235248
02:38:15 5189 8192000000 10312246320 10718711808 1169567856 12.493 100 69 136 0 1415495520 229765104 16162560
02:39:26 10441 8192000000 10363927552 10772160512 1186853456 12.654 100 53 106 0 1461998176 261943520 13201200
02:40:46 4403 8192000000 10439294480 10838933504 1171601200 12.512 100 79 156 1 1536665328 345895360 19168768
02:41:53 2212 8192000000 10448072048 10855813120 1166728896 12.466 100 85 168 0 1540316496 354612048 18975552
02:43:03 2777 8192000000 10492208992 10908295168 1172805952 12.523 100 84 168 0 1585712512 393089472 19817088
02:44:12 1422 8192000000 10506262240 10902818816 1174933104 12.543 100 91 182 0 1604891184 408597824 21360256
02:45:16 5695 8192000000 10512660880 10930757632 1168622272 12.484 100 59 118 1 1616980336 433689632 14668432
02:46:31 1379 8192000000 10551988496 10950574080 1158074320 12.385 100 93 187 1 1641372752 461657472 21640960
02:47:37 6472 8192000000 10508556880 10948046848 1121134384 12.038 98 44 87 0 1595879232 463035280 11709568
02:48:48 10860 8192000000 10456785440 10922471424 1149217968 12.302 100 40 80 0 1607960784 447969808 10773008
02:50:03 15888 8192000000 10449684544 10878418944 1155906016 12.365 100 36 72 0 1543932080 378725024 9301040
02:51:20 18950 8192000000 10474221392 10906365952 1123412320 12.059 100 29 58 1 1573912064 442191648 8308096
02:52:50 32071 8192000000 10386953104 10872627200 1110782176 11.940 99 24 48 1 1520911552 403806320 6323056
02:54:39 73694 8192000000 10069818880 10537218048 1103897616 11.875 98 7 14 2 1159232048 53848832 1485600
02:56:53 111414 8192000000 10101714464 10529492992 1098429760 11.823 98 26 50 2 1175904608 71948256 5526592
02:59:46 53045 8192000000 10112114608 10549510144 1111194272 11.944 99 25 50 0 1204356096 87788784 5373040
03:01:41 26777 8192000000 10128114576 10551918592 1130033344 12.122 99 36 72 1 1212833872 75488896 7311632
03:03:11 47493 8192000000 10092164352 10583232512 1113797344 11.968 98 12 24 1 1190044928 73785248 2462336
03:04:58 8892 8192000000 10071889888 10530078720 1136961504 12.187 100 15 30 0 1176212784 36589888 2661392
03:06:13 65386 8192000000 10066359776 10534178816 1113277312 11.963 99 10 20 1 1154273472 38723456 2272704
03:08:20 48971 8192000000 10064536992 10498891776 1107081056 11.905 98 13 26 2 1139947360 30359872 2506432
03:10:10 32655 8192000000 10111282704 10546921472 1141522624 12.230 100 46 92 1 1208962416 57736656 9703136
03:11:56 31506 8192000000 10110305344 10550657024 1140526192 12.220 99 34 66 0 1182166800 34892816 6747792
03:13:30 39230 8192000000 10083892608 10518896640 1141048512 12.225 99 15 30 1 1172714768 28282208 3384048
03:15:20 54479 8192000000 10188961776 10643558400 1160291440 12.406 100 15 30 1 1286171120 122479952 3399728
03:17:49 63584 8192000000 10112851376 10565853184 1110350672 11.936 96 18 35 1 1159287296 45656720 3279904
03:20:00 119568 8192000000 10080394464 10545586176 1119786976 12.025 97 12 24 0 1169649824 47187328 2675520
03:23:25 49360 8192000000 10123485152 10557829120 1155532304 12.361 100 34 68 1 1195283840 32147760 7603776
03:25:27 60597 8192000000 10099401712 10589605888 1158648752 12.391 100 10 20 0 1245573104 84189312 2735040
03:27:28 40079 8192000000 10105631120 10566205440 1141990912 12.234 99 12 24 0 1205034672 60091936 2951824
03:29:15 49691 8192000000 10049131856 10491113472 1082381360 11.670 94 14 28 0 1120214880 34366208 3467312
03:31:14 46043 8192000000 10075348592 10531966976 1096237648 11.802 97 17 34 1 1134292496 34748864 3305984
03:33:09 48497 8192000000 10076634608 10506412032 1121362080 12.040 99 22 44 0 1166171680 40574672 4234928
03:34:59 34766 8192000000 10136995760 10559115264 1141872208 12.233 100 36 72 0 1218530048 69379280 7278560
03:36:53 76713 8192000000 10056779168 10510049280 1028941664 11.158 91 10 20 0 1091404368 60631184 1831520
03:39:13 179286 8192000000 10036327264 10513473536 1050838016 11.369 94 12 24 0 1087186688 34248800 2099872
03:43:24 53817 8192000000 10045401024 10501775360 1108085584 11.914 99 9 17 0 1144475872 34823104 1567184
03:45:20 104616 8192000000 10136989536 10653196288 1112739696 11.958 99 18 36 1 1256191456 139357632 4094128
03:48:13 135084 8192000000 10064503792 10560471040 1063666224 11.492 94 8 15 0 1138390512 73118432 1605856
03:51:58 49081 8192000000 10076681920 10515714048 1114733216 11.977 100 30 60 2 1153746144 33083552 5929376
03:54:00 14767 8192000000 10102885568 10534633472 1113668928 11.967 99 51 102 0 1165944912 42055120 10220864
03:55:18 54312 8192000000 10064096048 10502971392 1126348992 12.087 100 14 26 0 1168203696 38584656 3270048
03:57:14 110169 8192000000 10110148928 10539970560 1112343536 11.955 98 50 100 1 1167251520 44697344 10210640
04:00:19 64093 8192000000 10079582032 10552492032 1118486016 12.013 99 12 24 2 1172558432 51442128 2630288
04:02:58 43060 8192000000 10062158240 10524692480 1113608352 11.967 99 11 22 1 1148623728 32902416 2112960
04:05:13 59891 8192000000 10073820896 10524069888 1108095728 11.914 98 17 34 0 1181268640 69711936 3460976
04:07:14 57756 8192000000 10062896880 10514296832 1105172128 11.887 98 21 41 2 1143498592 33642896 4683568
04:09:23 8520 8192000000 10084288128 10512191488 1135910928 12.177 100 56 113 1 1169382784 22025984 11445872
04:10:34 46028 8192000000 10080609760 10521206784 1132864000 12.148 100 23 44 2 1168147216 30865104 4418112
04:12:30 38159 8192000000 10070224736 10497572864 1105651600 11.891 97 25 50 0 1140199248 29817744 4729904
04:14:12 57221 8192000000 10075313904 10515251200 1102218080 11.859 97 24 48 2 1134130160 26638192 5273888
04:16:10 120676 8192000000 10078907264 10566557696 1095493840 11.795 96 25 50 1 1148989280 47929072 5566368
04:19:11 103301 8192000000 10032098560 10474672128 1099349088 11.831 97 16 32 1 1123074832 20296896 3428848
04:21:55 73595 8192000000 10044931312 10485932032 1068878896 11.541 95 7 14 0 1117042656 46652544 1511216
04:24:23 83692 8192000000 10058621632 10513129472 1100570448 11.843 98 17 33 1 1142031888 37861424 3600016
04:26:59 9620 8192000000 8973169440 9189572608 139419424 1.673 13 2 4 0 150408640 10678896 310320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-11-2020 04:28:20 to 01-11-2020 04:28:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 88 v_add_invoice_to_stock 335544517
user_exc 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-11-2020 04:28:36 to 01-11-2020 04:28:38.

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          237595
MON$OLDEST_ACTIVE               237596
MON$OLDEST_SNAPSHOT             237596
MON$NEXT_TRANSACTION            237596
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                       2084704
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             795
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2242 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.2242 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 ---
:97: 
:98: mscs-test-161.budzdorov.ru    Sun Nov  1 04:27:09 2020
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:100: 
:101: 
:102: mscs-test-161.budzdorov.ru    Sun Nov  1 04:27:10 2020
:103:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:104: 
:105: 
:106: mscs-test-161.budzdorov.ru    Sun Nov  1 04:27:10 2020
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:108: 
:109: 
:110: mscs-test-161.budzdorov.ru    Sun Nov  1 04:27:10 2020
:111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:112: 
:113: 
:114: mscs-test-161.budzdorov.ru    Sun Nov  1 04:27:10 2020
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:116: 
--- end of diff output ---

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

01.11.20 04:28:39. 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-01 04:28:41 No severe PSQL-related problems occured
01.11.20 04:28:43. End of report.