Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.01.2021 13:28
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 16505660 kB
MemAvailable 31228748 kB
Buffers 0 kB
Cached 15102468 kB
SwapCached 4868 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 2021-01-23 13:26:55
DTS_END                         2021-01-23 13:26:55.5700
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/51536
TRN_ID                          231900
ATT_ID                          664
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: 9013.14 24534 1081577
customer order: creation 165.88 409 19905
customer order: refuse 14.37 306 1724
order to supplier: creation 44.70 1430 5364
order to supplier: removal 3.52 112434 422
invoice (draft): creation 44.06 3448 5287
invoice (draft): removal 52.73 955 6328
invoice accept: apply 39.97 17669 4796
invoice accept: cancel 46.74 911 5609
customer reserve: creation 3723.29 173 446795
customer reserve: removal 4591.94 68 551033
realization accept: apply 80.32 166 9638
realization accept: cancel 82.36 132 9883
payment from customer: creation 56.78 112 6814
payment from customer: removal 5.43 82 652
payment to supplier: creation 46.78 115 5614
payment to supplier: removal 3.17 62 380
service: total inventory turnovers 3.91 2936 469
service: total monetary turnovers 6.36 518 763
service: aggregate perf. data 0.82 20890 98
service: refresh index statistics 0.03 327865 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-01-2021 13:29:09 to 23-01-2021 13:29:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1775
TEST_TIME 2 2354
TEST_TIME 3 3859
TEST_TIME 4 4006
TEST_TIME 5 3760
TEST_TIME 6 4699
TEST_TIME 7 4667
TEST_TIME 8 5223
TEST_TIME 9 5532
TEST_TIME 10 5800
TEST_TIME 11 5902
TEST_TIME 12 5954
TEST_TIME 13 6035
TEST_TIME 14 6130
TEST_TIME 15 6606
TEST_TIME 16 6713
TEST_TIME 17 6795
TEST_TIME 18 7317
TEST_TIME 19 7739
TEST_TIME 20 7752
TEST_TIME 21 7718
TEST_TIME 22 8287
TEST_TIME 23 8333
TEST_TIME 24 8770
TEST_TIME 25 8972
TEST_TIME 26 9790
TEST_TIME 27 10770
TEST_TIME 28 10660
TEST_TIME 29 10518
TEST_TIME 30 10708
TEST_TIME 31 10568
TEST_TIME 32 10599
TEST_TIME 33 10501
TEST_TIME 34 10629
TEST_TIME 35 10627
TEST_TIME 36 10603
TEST_TIME 37 10537
TEST_TIME 38 10508
TEST_TIME 39 10245
TEST_TIME 40 10037
TEST_TIME 41 10014
TEST_TIME 42 9937
TEST_TIME 43 9853
TEST_TIME 44 9715
TEST_TIME 45 9695
TEST_TIME 46 9736
TEST_TIME 47 9726
TEST_TIME 48 9660
TEST_TIME 49 9498
TEST_TIME 50 9351
TEST_TIME 51 9390
TEST_TIME 52 9367
TEST_TIME 53 9422
TEST_TIME 54 9405
TEST_TIME 55 9391
TEST_TIME 56 9445
TEST_TIME 57 9453
TEST_TIME 58 9443
TEST_TIME 59 9429
TEST_TIME 60 9578
TEST_TIME 61 9587
TEST_TIME 62 9726
TEST_TIME 63 9688
TEST_TIME 64 9682
TEST_TIME 65 9599
TEST_TIME 66 9540
TEST_TIME 67 9502
TEST_TIME 68 9399
TEST_TIME 69 9321
TEST_TIME 70 9252
TEST_TIME 71 9193
TEST_TIME 72 9156
TEST_TIME 73 9190
TEST_TIME 74 9306
TEST_TIME 75 9423
TEST_TIME 76 9495
TEST_TIME 77 9451
TEST_TIME 78 9405
TEST_TIME 79 9483
TEST_TIME 80 9470
TEST_TIME 81 9471
TEST_TIME 82 9384
TEST_TIME 83 9359
TEST_TIME 84 9278
TEST_TIME 85 9211
TEST_TIME 86 9174
TEST_TIME 87 9167
TEST_TIME 88 9193
TEST_TIME 89 9188
TEST_TIME 90 9143
TEST_TIME 91 9129
TEST_TIME 92 9126
TEST_TIME 93 9164
TEST_TIME 94 9123
TEST_TIME 95 9241
TEST_TIME 96 9272
TEST_TIME 97 9273
TEST_TIME 98 9269
TEST_TIME 99 9332
TEST_TIME 100 9346
TEST_TIME 101 9289
TEST_TIME 102 9229
TEST_TIME 103 9191
TEST_TIME 104 9147
TEST_TIME 105 9118
TEST_TIME 106 9085
TEST_TIME 107 9080
TEST_TIME 108 9045
TEST_TIME 109 9046
TEST_TIME 110 9031
TEST_TIME 111 9025
TEST_TIME 112 9032
TEST_TIME 113 9181
TEST_TIME 114 9187
TEST_TIME 115 9171
TEST_TIME 116 9131
TEST_TIME 117 9146
TEST_TIME 118 9076
TEST_TIME 119 9054
TEST_TIME 120 9015

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-01-2021 13:29:13 to 23-01-2021 13:29:16.

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 1495924 1495924 0 0.00 0 21181 6 0 0
doc_list_biud 1495924 1495924 0 0.00 0 43791 32 0 0
sp_add_invoice_to_stock 4880 4796 84 1.72 32 212417 17670 0 84
sp_cancel_adding_invoice 5609 5609 0 0.00 5 71571 912 0 0
sp_cancel_client_order 1724 1724 0 0.00 4 14668 306 0 0
sp_cancel_customer_reserve 551033 551033 0 0.00 1 25404 68 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 3 4020 82 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 3977 62 0 0
sp_cancel_supplier_invoice 6335 6328 7 0.11 2 20507 955 0 7
sp_cancel_supplier_order 422 422 0 0.00 8 1068183 112435 0 0
sp_cancel_write_off 9883 9883 0 0.00 1 26093 133 0 0
sp_client_order 19905 19905 0 0.00 4 24384 409 0 0
sp_customer_reserve 446795 446795 0 0.00 0 139993 173 0 0
sp_fill_shopping_cart 30538 30538 0 0.00 1 13527 41 0 0
sp_fill_shopping_cart_clo_res 446791 446791 0 0.00 0 17163 18 0 0
sp_get_clo_for_invoice 4791 4791 0 0.00 0 52178 360 0 0
sp_kill_cost_storno 16524 16524 0 0.00 0 6798 60 0 0
sp_kill_qstorno_ret_qs2qd 1118699 1118699 0 0.00 0 26090 37 0 0
sp_kill_qty_storno 589428 589428 0 0.00 0 43791 80 0 0
sp_lock_selected_doc 19966 19966 0 0.00 0 11207 5 0 0
sp_make_cost_storno 26857 26857 0 0.00 0 20314 48 0 0
sp_multiply_rows_for_pdistr 26857 26857 0 0.00 0 12530 32 0 0
sp_multiply_rows_for_qdistr 19905 19905 0 0.00 0 24374 249 0 0
sp_pay_from_customer 6814 6814 0 0.00 4 20456 113 0 0
sp_pay_to_supplier 5614 5614 0 0.00 5 15252 115 0 0
sp_payment_common 12428 12428 0 0.00 1 11442 27 0 0
sp_qd_handle_on_cancel_clo 1724 1724 0 0.00 0 14631 284 0 0
sp_qd_handle_on_invoice_upd_sts 10400 10400 0 0.00 0 43785 363 0 0
sp_qd_handle_on_reserve_upd_sts 9638 9638 0 0.00 0 15833 48 0 0
sp_reserve_write_off 9638 9638 0 0.00 7 19810 166 0 0
sp_supplier_invoice 5287 5287 0 0.00 6 116060 3448 0 0
sp_supplier_order 5364 5364 0 0.00 6 32639 1430 0 0
srv_aggregate_perf_data 98 98 0 0.00 2435 720199 20891 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 781 159618 46936 0 0
srv_find_qd_qs_mism 1035955 1035955 0 0.00 0 21179 5 0 0
srv_make_invnt_saldo 469 469 0 0.00 41 108887 2936 0 0
srv_make_money_saldo 763 763 0 0.00 1 13082 519 0 0
srv_recalc_idx_stat 3 3 0 0.00 188560 430875 327865 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4796 4796 0 0.00 0 13093 159 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 0 5734 132 0 0
t$perf-norm:sp_cancel_client_order 1724 1724 0 0.00 0 18264 23 0 0
t$perf-norm:sp_cancel_customer_reserve 617 617 0 0.00 0 1796 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 1190 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 3809 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 1035 14 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 18797 1450 0 0
t$perf-norm:sp_cancel_write_off 914 914 0 0.00 0 585 4 0 0
t$perf-norm:sp_client_order 19905 19905 0 0.00 0 10292 16 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 1161 5 0 0
t$perf-norm:sp_pay_from_customer 6814 6814 0 0.00 0 8299 9 0 0
t$perf-norm:sp_pay_to_supplier 5614 5614 0 0.00 0 7329 10 0 0
t$perf-norm:sp_reserve_write_off 9638 9638 0 0.00 0 3449 8 0 0
t$perf-norm:sp_supplier_invoice 5287 5287 0 0.00 0 10503 19 0 0
t$perf-norm:sp_supplier_order 5364 5364 0 0.00 0 7807 18 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 44 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 230 11 0 0
t$perf-norm:srv_make_invnt_saldo 469 469 0 0.00 0 223 4 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 9642 18 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 3 94 34 0 0
v_add_invoice_to_stock 9748 9580 168 1.72 0 6277 19 0 168
v_all_customers 36164 36164 0 0.00 0 21 1 0 0
v_all_suppliers 29586 29586 0 0.00 0 18 1 0 0
v_all_wares 39810 39810 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 152 5 0 0
v_cancel_client_order 3448 3448 0 0.00 0 1766 4 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 3017 8 0 0
v_cancel_customer_reserve 1234 1234 0 0.00 0 4413 11 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 541 4 0 0
v_cancel_supplier_invoice 878 864 14 1.59 0 919 11 0 14
v_cancel_supplier_order 844 844 0 0.00 0 5312 12 0 0
v_cancel_write_off 1828 1828 0 0.00 0 3768 16 0 0
v_max_id_clo_ord 5353 5353 0 0.00 0 1336 2 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 5738 18 0 0
v_max_id_ord_sup 5280 5280 0 0.00 0 4976 4 0 0
v_max_non_paid_invoice 1454 1454 0 0.00 0 3767 5 0 0
v_max_non_paid_realizn 3628 3628 0 0.00 0 1267 3 0 0
v_min_id_clo_ord 5353 5353 0 0.00 0 7614 8 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 3891 8 0 0
v_min_id_ord_sup 5280 5280 0 0.00 0 11051 7 0 0
v_min_non_paid_invoice 5614 5614 0 0.00 0 7671 18 0 0
v_min_non_paid_realizn 6814 6814 0 0.00 0 8437 20 0 0
v_our_firm 10018 10018 0 0.00 0 13 1 0 0
v_reserve_write_off 19276 19276 0 0.00 0 5682 7 0 0
x_lock_depdocs_on_canc_invoice 11505 11505 0 0.00 0 24144 109 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 7735 309 0 0
x_make_qstorno_1000_1200 5364 5364 0 0.00 2 32628 1353 0 0
x_make_qstorno_1200_2000 5287 5287 0 0.00 3 116010 3295 0 0
x_make_qstorno_2100_3300 446795 446795 0 0.00 0 139993 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-01-2021 13:29:17 to 23-01-2021 13:29:21.

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 8 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
10:58:00 26189 8192000000 9701969584 9990430720 778291184 8.676 100 90 180 0 808727728 7947376 22489168
10:59:31 14366 8192000000 9739247584 10043711488 820220272 9.101 100 79 158 0 851091392 11572384 19298736
11:01:03 10412 8192000000 9808056960 10127364096 875187024 9.652 100 85 170 1 920887280 25974944 19725312
11:02:21 14228 8192000000 9877958096 10209349632 919759616 10.094 100 83 166 1 986929344 48676112 18493616
11:03:36 10358 8192000000 9925729840 10271928320 966969664 10.557 100 85 170 0 1038777264 54010784 17796816
11:04:49 4421 8192000000 9966461968 10319462400 1002513488 10.903 100 82 164 0 1069988752 50561392 16913872
11:05:56 2177 8192000000 10020374976 10384576512 1029498256 11.164 100 88 174 0 1137838864 91242464 17098144
11:07:00 1416 8192000000 10038567552 10406391808 1048959936 11.351 100 89 174 0 1152326848 85023024 18343888
11:08:03 1825 8192000000 10016590752 10394034176 1067030864 11.524 100 86 173 1 1139090192 54398672 17660656
11:09:07 9315 8192000000 10035169440 10413322240 1073018000 11.581 100 68 135 1 1146007264 59058688 13930576
11:10:17 10347 8192000000 9997884816 10399100928 1082223040 11.669 100 27 53 1 1114949200 27207824 5518336
11:11:28 4618 8192000000 10007085616 10417516544 1092891536 11.770 100 29 56 2 1123672656 24864736 5916384
11:12:33 6196 8192000000 10011759184 10416226304 1096366656 11.803 100 46 92 1 1147397680 41284848 9746176
11:13:40 72307 8192000000 10019264512 10435657728 1099352256 11.831 100 38 76 0 1128455776 20590976 8512544
11:15:53 6420 8192000000 10024855648 10428502016 1100696000 11.844 100 44 89 1 1128903456 18267232 9940224
11:17:01 5667 8192000000 10006880640 10399973376 1089144816 11.735 99 46 92 3 1111523376 12087008 10291552
11:18:07 9279 8192000000 9969768352 10367746048 993331920 10.814 92 26 52 2 1023373664 24237184 5804560
11:19:17 47848 8192000000 9930876832 10327244800 981903040 10.703 95 27 55 1 1020111424 31706192 6502192
11:21:12 3245 8192000000 9932468320 10304167936 1018886816 11.061 100 60 121 1 1040138480 9118640 12133024
11:22:16 7883 8192000000 9951603232 10340790272 1032932112 11.197 100 38 77 0 1050034352 8170752 8931488
11:23:26 10646 8192000000 9965432544 10350120960 1049857328 11.359 100 28 56 1 1074878640 18311616 6709696
11:24:39 66642 8192000000 9961689136 10366550016 1053553920 11.395 100 24 47 1 1075708384 16499968 5654496
11:26:46 24288 8192000000 9994690080 10395987968 1085887808 11.704 100 26 52 1 1116638368 24686480 6064080
11:28:12 12356 8192000000 10034100160 10430033920 1105421520 11.889 100 47 94 1 1140772128 25490640 9859968
11:29:27 25437 8192000000 10092567552 10492563456 1117865776 12.007 100 52 104 0 1206232768 77742384 10624608
11:30:59 12686 8192000000 10121190976 10518016000 1129109360 12.113 100 51 102 0 1225541296 85655968 10775968
11:32:14 42652 8192000000 10122129216 10549510144 1134599936 12.165 100 34 68 1 1236075328 94165584 7309808
11:34:02 22481 8192000000 10190405088 10601775104 1144190240 12.255 100 56 109 0 1283455008 127348592 11916176
11:35:27 12709 8192000000 10167511200 10578198528 1152907744 12.337 100 55 108 1 1262180336 97163120 12109472
11:36:48 3480 8192000000 10235906656 10641022976 1171446512 12.510 100 75 150 1 1338442832 151560144 15436176
11:37:56 5793 8192000000 10214212912 10626519040 1163334272 12.434 99 53 103 2 1331021520 156376528 11310720
11:39:08 1378 8192000000 10285715136 10692431872 1184459136 12.632 100 78 156 2 1391903104 188368576 19075392
11:40:15 5514 8192000000 10301464032 10714324992 1174872432 12.542 100 57 115 0 1407204128 218993040 13338656
11:41:31 2984 8192000000 10311111216 10715389952 1162388896 12.426 99 69 137 0 1409692928 231561408 15742624
11:42:41 1332 8192000000 10376912416 10769063936 1164085616 12.442 100 93 188 0 1472351728 287078560 21187552
11:43:45 2867 8192000000 10421536112 10826883072 1174092192 12.535 100 80 160 0 1516810576 323911216 18807168
11:44:56 780 8192000000 10410058240 10825334784 1158463824 12.389 100 88 174 0 1513154192 333551856 21138512
11:46:03 11070 8192000000 10350432512 10782531584 1130284592 12.124 99 40 80 0 1459440912 318956128 10200192
11:47:19 7723 8192000000 10411287536 10828656640 1134963104 12.168 99 45 88 0 1507900720 361028080 11909536
11:48:33 2831 8192000000 10412258416 10836955136 1143517376 12.249 100 69 138 0 1510545280 350484848 16543056
11:49:43 19538 8192000000 10392584848 10836119552 1102938192 11.866 97 34 67 3 1482039872 369696224 9405456
11:51:13 21971 8192000000 10350092368 10817003520 1124421904 12.069 100 33 66 2 1459938784 326740400 8776480
11:52:41 15123 8192000000 10234560976 10706890752 1098572192 11.824 98 21 43 0 1349577616 245696064 5309360
11:53:58 44944 8192000000 10015823296 10461732864 1038034112 11.246 94 7 14 0 1078191600 38636528 1520960
11:55:58 115819 8192000000 10058243296 10512019456 1097824080 11.817 98 14 28 1 1181215632 80461888 2929664
11:59:11 12203 8192000000 10065931264 10488561664 1132668064 12.147 100 47 98 1 1160731632 17614800 10448768
12:00:23 159618 8192000000 10072945872 10532200448 1048455152 11.346 92 8 16 0 1107354176 57327344 1571680
12:04:03 80899 8192000000 10076033744 10543747072 1112797968 11.959 98 14 28 1 1172206400 56176368 3232064
12:06:43 42503 8192000000 10097935328 10538160128 1117721088 12.005 98 50 101 1 1167288432 38960896 10606448
12:08:27 57191 8192000000 10078218304 10530811904 1131659424 12.137 100 29 58 1 1178869376 40785728 6424224
12:10:35 125994 8192000000 10118553216 10602496000 1145037728 12.263 100 20 40 0 1221310112 72065408 4206976
12:13:41 26826 8192000000 10066651936 10511855616 1135381136 12.172 99 15 30 1 1157112800 18498080 3233584
12:15:09 60546 8192000000 10103107232 10553417728 1151110624 12.320 100 16 30 1 1206873968 52406016 3357328
12:17:10 11056 8192000000 10091944640 10583248896 1155905568 12.365 100 27 54 2 1198705136 37580512 5219056
12:18:23 55301 8192000000 10094075808 10584416256 1139706928 12.213 99 12 24 1 1183164112 40864336 2592848
12:20:29 69337 8192000000 10099718496 10567852032 1091599376 11.758 95 15 30 0 1143577136 49001472 2976288
12:22:38 50122 8192000000 10145873056 10593435648 1156568672 12.371 100 18 36 1 1239059536 78676960 3813904
12:24:29 33531 8192000000 10125079104 10594295808 1140742464 12.223 98 24 48 0 1222332016 76027552 5562000
12:26:09 104389 8192000000 10113812864 10549542912 1077899808 11.627 94 15 29 1 1181580032 100771712 2908512
12:28:54 45405 8192000000 10080906688 10519838720 1140097216 12.216 99 12 24 1 1170284496 27686960 2500320
12:30:44 115002 8192000000 10057048336 10517995520 1116393248 11.993 99 23 46 0 1176831920 55748288 4690384
12:33:50 64565 8192000000 10082653408 10543992832 1120500528 12.032 99 14 28 1 1184921360 61369936 3050896
12:35:54 56650 8192000000 10138429808 10588626944 1110937776 11.941 97 21 42 0 1209743792 93971280 4834736
12:38:01 69815 8192000000 10212526368 10657472512 1096217280 11.802 96 27 54 2 1261911728 159087264 6607184
12:40:41 55203 8192000000 10149838320 10641498112 1107196784 11.906 98 17 35 0 1260353504 148804896 4351824
12:42:46 49325 8192000000 10047375552 10476945408 1064354000 11.498 95 16 31 0 1097608112 30363728 2890384
12:44:40 34953 8192000000 10120152512 10562654208 1117049120 11.999 100 46 92 0 1213089440 86704608 9335712
12:46:22 58813 8192000000 10073950352 10491355136 1107129664 11.905 99 40 80 2 1177114656 61785168 8199824
12:48:23 82968 8192000000 10039678528 10507767808 1105478080 11.890 99 16 32 1 1154948624 45994032 3476512
12:51:03 93203 8192000000 10090677584 10566004736 1125872432 12.082 100 12 24 2 1186705232 57992960 2839840
12:53:38 64040 8192000000 10074828704 10537074688 1115458384 11.984 99 20 40 1 1180248304 60589792 4200128
12:55:49 43131 8192000000 10081295488 10513739776 1100597648 11.843 97 22 44 0 1146696096 41359584 4738864
12:57:34 55408 8192000000 10118020128 10562338816 1093817168 11.779 96 29 55 1 1178091840 78455136 5819536
12:59:31 68370 8192000000 10122060544 10555736064 1120427568 12.031 99 25 50 1 1214012640 88444000 5141072
13:01:49 72055 8192000000 10065463920 10541416448 1116939104 11.998 98 14 27 1 1159664896 40095008 2630784
13:04:01 30418 8192000000 10113584928 10544279552 1127665296 12.099 99 38 75 0 1179359152 43620496 8073360
13:05:36 75602 8192000000 10096317984 10584854528 1146392448 12.276 100 15 30 0 1199647872 50163632 3091792
13:07:52 97304 8192000000 10076454320 10541797376 1139735584 12.213 100 25 50 2 1175778320 30824032 5218704
13:10:47 53472 8192000000 10059030608 10528276480 1124864208 12.073 99 6 12 0 1154530640 28521664 1144768
13:12:40 50145 8192000000 10055476720 10515296256 1126353904 12.087 98 11 22 1 1171112016 42459968 2298144
13:14:31 35739 8192000000 10156272384 10598420480 1137077552 12.188 98 40 80 1 1213301376 67954272 8269552
13:16:29 46818 8192000000 10160942432 10608136192 1114819408 11.978 96 27 54 2 1233836416 112783808 6233200
13:18:17 52129 8192000000 10166251808 10627637248 1115034512 11.980 97 23 46 0 1226622096 106699136 4888448
13:20:10 105651 8192000000 10074734608 10514186240 1062205488 11.478 93 15 30 1 1108760736 43711232 2844016
13:23:02 19230 8192000000 10112161120 10541707264 1146183840 12.274 100 36 72 0 1204009616 50912464 6913312
13:24:22 103155 8192000000 10072533776 10541502464 1138954992 12.206 99 25 50 0 1198287792 54198576 5134224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-01-2021 13:29:23 to 23-01-2021 13:29:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-01-2021 13:29:39 to 23-01-2021 13:29:41.

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          231815
MON$OLDEST_ACTIVE               232632
MON$OLDEST_SNAPSHOT             232632
MON$NEXT_TRANSACTION            232632
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                       2106688
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             789
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2337 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2337 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2337 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.2337 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 ---
:36: 
:37: mscs-test-161.budzdorov.ru    Sat Jan 23 13:26:56 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Sat Jan 23 13:27:26 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
--- end of diff output ---

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

23.01.21 13:29:42. 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
2021-01-23 13:29:44 No severe PSQL-related problems occured
23.01.21 13:29:46. End of report.