Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.02.2021 04:29
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 16495088 kB
MemAvailable 31144776 kB
Buffers 20 kB
Cached 15160084 kB
SwapCached 4072 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-02-18 04:26:57
DTS_END                         2021-02-18 04:26:57.4800
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/50216
TRN_ID                          227028
ATT_ID                          656
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: 8775.02 27428 1053002
customer order: creation 163.56 408 19627
customer order: refuse 14.96 319 1795
order to supplier: creation 43.91 1361 5269
order to supplier: removal 3.02 139866 362
invoice (draft): creation 44.23 3583 5307
invoice (draft): removal 48.33 1109 5799
invoice accept: apply 40.56 19744 4867
invoice accept: cancel 44.05 921 5286
customer reserve: creation 3736.96 193 448435
customer reserve: removal 4351.64 75 522197
realization accept: apply 79.01 183 9481
realization accept: cancel 80.59 124 9671
payment from customer: creation 56.50 121 6780
payment from customer: removal 5.81 100 697
payment to supplier: creation 48.11 119 5773
payment to supplier: removal 3.36 57 403
service: total inventory turnovers 3.82 3644 458
service: total monetary turnovers 5.85 670 702
service: aggregate perf. data 0.74 18640 89
service: refresh index statistics 0.03 357316 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-02-2021 04:29:58 to 18-02-2021 04:30:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1011
TEST_TIME 2 2286
TEST_TIME 3 3150
TEST_TIME 4 4024
TEST_TIME 5 4357
TEST_TIME 6 4423
TEST_TIME 7 4519
TEST_TIME 8 4457
TEST_TIME 9 4846
TEST_TIME 10 5182
TEST_TIME 11 5278
TEST_TIME 12 5839
TEST_TIME 13 5956
TEST_TIME 14 6163
TEST_TIME 15 6213
TEST_TIME 16 6718
TEST_TIME 17 7055
TEST_TIME 18 7261
TEST_TIME 19 7379
TEST_TIME 20 7793
TEST_TIME 21 7750
TEST_TIME 22 8065
TEST_TIME 23 8454
TEST_TIME 24 8634
TEST_TIME 25 8671
TEST_TIME 26 8760
TEST_TIME 27 9061
TEST_TIME 28 10147
TEST_TIME 29 10900
TEST_TIME 30 11370
TEST_TIME 31 11171
TEST_TIME 32 11053
TEST_TIME 33 10980
TEST_TIME 34 10836
TEST_TIME 35 10645
TEST_TIME 36 10476
TEST_TIME 37 10219
TEST_TIME 38 10379
TEST_TIME 39 10215
TEST_TIME 40 10115
TEST_TIME 41 9970
TEST_TIME 42 9962
TEST_TIME 43 10088
TEST_TIME 44 9956
TEST_TIME 45 9921
TEST_TIME 46 9922
TEST_TIME 47 9906
TEST_TIME 48 9905
TEST_TIME 49 9845
TEST_TIME 50 9884
TEST_TIME 51 9795
TEST_TIME 52 9772
TEST_TIME 53 9740
TEST_TIME 54 9764
TEST_TIME 55 9728
TEST_TIME 56 9658
TEST_TIME 57 9809
TEST_TIME 58 9872
TEST_TIME 59 9830
TEST_TIME 60 9749
TEST_TIME 61 9618
TEST_TIME 62 9568
TEST_TIME 63 9514
TEST_TIME 64 9474
TEST_TIME 65 9402
TEST_TIME 66 9331
TEST_TIME 67 9294
TEST_TIME 68 9267
TEST_TIME 69 9344
TEST_TIME 70 9412
TEST_TIME 71 9380
TEST_TIME 72 9335
TEST_TIME 73 9388
TEST_TIME 74 9348
TEST_TIME 75 9321
TEST_TIME 76 9319
TEST_TIME 77 9328
TEST_TIME 78 9376
TEST_TIME 79 9409
TEST_TIME 80 9315
TEST_TIME 81 9271
TEST_TIME 82 9211
TEST_TIME 83 9134
TEST_TIME 84 9059
TEST_TIME 85 8983
TEST_TIME 86 8923
TEST_TIME 87 8868
TEST_TIME 88 8943
TEST_TIME 89 8922
TEST_TIME 90 8913
TEST_TIME 91 8898
TEST_TIME 92 8914
TEST_TIME 93 8908
TEST_TIME 94 8948
TEST_TIME 95 8973
TEST_TIME 96 8950
TEST_TIME 97 8967
TEST_TIME 98 8972
TEST_TIME 99 9006
TEST_TIME 100 9013
TEST_TIME 101 9009
TEST_TIME 102 8927
TEST_TIME 103 8875
TEST_TIME 104 8832
TEST_TIME 105 8781
TEST_TIME 106 8776
TEST_TIME 107 8758
TEST_TIME 108 8789
TEST_TIME 109 8766
TEST_TIME 110 8765
TEST_TIME 111 8743
TEST_TIME 112 8795
TEST_TIME 113 8782
TEST_TIME 114 8777
TEST_TIME 115 8852
TEST_TIME 116 8816
TEST_TIME 117 8889
TEST_TIME 118 8873
TEST_TIME 119 8827
TEST_TIME 120 8776
TEST_TIME 121 8703

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-02-2021 04:30:02 to 18-02-2021 04:30:05.

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 1466575 1466575 0 0.00 0 25543 8 0 0
doc_list_biud 1466575 1466575 0 0.00 0 102903 34 0 0
sp_add_invoice_to_stock 5002 4867 135 2.69 51 200447 19745 0 135
sp_cancel_adding_invoice 5286 5286 0 0.00 5 80254 922 0 0
sp_cancel_client_order 1795 1795 0 0.00 4 6972 319 0 0
sp_cancel_customer_reserve 522197 522197 0 0.00 1 25735 75 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 2 6499 101 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 2185 58 0 0
sp_cancel_supplier_invoice 5814 5799 15 0.25 2 102911 1110 0 15
sp_cancel_supplier_order 362 362 0 0.00 8 1204917 139867 0 0
sp_cancel_write_off 9671 9671 0 0.00 1 12972 124 0 0
sp_client_order 19627 19627 0 0.00 4 32792 409 0 0
sp_customer_reserve 448435 448435 0 0.00 0 75759 193 0 0
sp_fill_shopping_cart 30181 30181 0 0.00 1 26020 51 0 0
sp_fill_shopping_cart_clo_res 448424 448424 0 0.00 0 13445 17 0 0
sp_get_clo_for_invoice 4856 4856 0 0.00 0 37890 410 0 0
sp_kill_cost_storno 16057 16057 0 0.00 0 12964 61 0 0
sp_kill_qstorno_ret_qs2qd 1060226 1060226 0 0.00 0 102902 41 0 0
sp_kill_qty_storno 559447 559447 0 0.00 0 102903 88 0 0
sp_lock_selected_doc 20041 20041 0 0.00 0 9041 6 0 0
sp_make_cost_storno 26890 26890 0 0.00 0 23533 61 0 0
sp_multiply_rows_for_pdistr 26890 26890 0 0.00 0 10099 32 0 0
sp_multiply_rows_for_qdistr 19627 19627 0 0.00 0 16882 218 0 0
sp_pay_from_customer 6780 6780 0 0.00 4 18522 122 0 0
sp_pay_to_supplier 5773 5773 0 0.00 5 25548 120 0 0
sp_payment_common 12553 12553 0 0.00 1 8140 19 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 0 6962 285 0 0
sp_qd_handle_on_invoice_upd_sts 10142 10142 0 0.00 0 30522 445 0 0
sp_qd_handle_on_reserve_upd_sts 9481 9481 0 0.00 0 16129 54 0 0
sp_reserve_write_off 9481 9481 0 0.00 7 24077 184 0 0
sp_supplier_invoice 5307 5307 0 0.00 7 112329 3583 0 0
sp_supplier_order 5269 5269 0 0.00 5 34206 1361 0 0
srv_aggregate_perf_data 90 89 1 1.11 2789 320034 18640 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1123 165934 46218 0 0
srv_find_qd_qs_mism 1006192 1006192 0 0.00 0 16099 7 0 0
srv_make_invnt_saldo 458 458 0 0.00 64 128727 3645 0 0
srv_make_money_saldo 702 702 0 0.00 1 15823 671 0 0
srv_recalc_idx_stat 4 4 0 0.00 190026 605623 357317 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4867 4867 0 0.00 0 9544 164 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 3012 80 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 1010 10 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 4034 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 935 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 184 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 2653 23 0 0
t$perf-norm:sp_cancel_supplier_order 362 362 0 0.00 0 26014 1773 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 5947 19 0 0
t$perf-norm:sp_client_order 19627 19627 0 0.00 0 6396 18 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 1913 5 0 0
t$perf-norm:sp_pay_from_customer 6780 6780 0 0.00 0 12802 10 0 0
t$perf-norm:sp_pay_to_supplier 5773 5773 0 0.00 0 6149 10 0 0
t$perf-norm:sp_reserve_write_off 9481 9481 0 0.00 0 10960 10 0 0
t$perf-norm:sp_supplier_invoice 5307 5307 0 0.00 0 8215 22 0 0
t$perf-norm:sp_supplier_order 5269 5269 0 0.00 0 7317 18 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 164 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 3006 52 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 2024 14 0 0
t$perf-norm:srv_make_money_saldo 702 702 0 0.00 0 4011 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9982 9712 270 2.70 0 4529 17 0 270
v_all_customers 35658 35658 0 0.00 0 19 1 0 0
v_all_suppliers 29512 29512 0 0.00 0 25 1 0 0
v_all_wares 39254 39254 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 2278 10 0 0
v_cancel_client_order 3590 3590 0 0.00 0 6391 6 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 3024 11 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 2425 6 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 232 4 0 0
v_cancel_supplier_invoice 794 764 30 3.77 0 1915 17 0 30
v_cancel_supplier_order 724 724 0 0.00 0 2622 14 0 0
v_cancel_write_off 1974 1974 0 0.00 0 8221 26 0 0
v_max_id_clo_ord 5265 5265 0 0.00 0 756 1 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 1482 8 0 0
v_max_id_ord_sup 5289 5289 0 0.00 0 8701 8 0 0
v_max_non_paid_invoice 1571 1571 0 0.00 0 244 2 0 0
v_max_non_paid_realizn 3636 3636 0 0.00 0 5923 4 0 0
v_min_id_clo_ord 5265 5265 0 0.00 0 2295 6 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 8427 16 0 0
v_min_id_ord_sup 5289 5289 0 0.00 0 11241 15 0 0
v_min_non_paid_invoice 5773 5773 0 0.00 0 8136 11 0 0
v_min_non_paid_realizn 6780 6780 0 0.00 0 6918 15 0 0
v_our_firm 9884 9884 0 0.00 0 18 1 0 0
v_reserve_write_off 18962 18962 0 0.00 0 4864 5 0 0
x_lock_depdocs_on_canc_invoice 10703 10703 0 0.00 0 20497 127 0 0
x_lock_depdocs_on_canc_sup_ord 362 362 0 0.00 0 11021 439 0 0
x_make_qstorno_1000_1200 5269 5269 0 0.00 2 34190 1282 0 0
x_make_qstorno_1200_2000 5307 5307 0 0.00 3 108565 3368 0 0
x_make_qstorno_2100_3300 448435 448435 0 0.00 0 75758 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-02-2021 04:30:06 to 18-02-2021 04:30:10.

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:01 4 8192000000 8799338064 8810778624 5349520 0.065 2 1 2 0 5447136 61840 35776
01:58:02 22100 8192000000 9722766976 10017832960 801560464 8.912 100 91 182 0 831857728 8247216 22050048
01:59:28 32660 8192000000 9771660960 10076295168 839856192 9.298 100 91 183 1 874117024 12505344 21755488
02:01:05 22275 8192000000 9817326240 10138112000 869833264 9.598 100 72 144 0 915298704 29136688 16328752
02:02:30 20400 8192000000 9891505344 10223857664 923015440 10.126 100 85 170 3 986024912 44526352 18483120
02:04:03 6365 8192000000 9944737232 10290909184 970635600 10.593 100 81 162 1 1058609776 71234880 16739296
02:05:12 3823 8192000000 9958890672 10314842112 1009843840 10.974 100 77 154 2 1071024192 44903776 16276576
02:06:18 3462 8192000000 10007624336 10369732608 1034687600 11.214 100 90 178 0 1121254064 69433280 17133184
02:07:23 1885 8192000000 10033952448 10401812480 1050375712 11.364 100 87 172 1 1148640880 81219568 17045600
02:08:26 1429 8192000000 10026422048 10400071680 1066570736 11.519 100 89 178 1 1138298544 52670944 19056864
02:09:31 2339 8192000000 10048879296 10431827968 1074197424 11.592 100 73 145 1 1159036416 70184640 14654352
02:10:35 6176 8192000000 9991721456 10389651456 1082634272 11.673 100 42 81 1 1110886176 18110768 10141136
02:11:42 26112 8192000000 10002489008 10386444288 1082888928 11.675 100 52 104 1 1104026656 9127040 12010688
02:13:08 7156 8192000000 9991542240 10397552640 1087191440 11.716 100 24 48 1 1111107248 18928800 4987008
02:14:17 7651 8192000000 9996348656 10405167104 1092430784 11.766 100 27 52 1 1106844576 8689296 5724496
02:15:31 5684 8192000000 10009764128 10412724224 1093724368 11.778 100 41 83 1 1130220416 27624384 8871664
02:16:37 45208 8192000000 10010621888 10423799808 1060808640 11.464 97 30 57 1 1089661808 22425264 6427904
02:18:25 4408 8192000000 9998409408 10391707648 1069097584 11.543 99 52 103 0 1095970512 15244496 11628432
02:19:30 3906 8192000000 9952841008 10336411648 1040140448 11.266 99 44 88 3 1069564880 18103120 11321312
02:20:35 6686 8192000000 9941454240 10323537920 1024483088 11.115 100 42 83 1 1045806768 12496464 8827216
02:21:42 71968 8192000000 9921763536 10307784704 922295088 10.119 89 19 38 1 940983696 14532240 4156368
02:23:55 11153 8192000000 9981059760 10384445440 1035705328 11.223 99 48 94 2 1074143168 28052208 10385632
02:25:08 11577 8192000000 9979355776 10382524416 1065161040 11.506 100 33 65 0 1101929008 29699888 7068080
02:26:22 14221 8192000000 9976446656 10378055680 1070861648 11.560 100 32 61 2 1099345168 22110368 6373152
02:28:11 27830 8192000000 10049057280 10440736768 1093012688 11.771 100 56 109 1 1143657408 39899072 10745648
02:29:44 9908 8192000000 10072865744 10472943616 1113621888 11.967 100 52 104 0 1186417472 62147744 10647840
02:31:10 15680 8192000000 10128613280 10529275904 1131078016 12.132 100 59 116 0 1222178208 79369440 11730752
02:32:30 14792 8192000000 10137211312 10549739520 1143355696 12.247 100 50 100 0 1251273280 97487776 10429808
02:33:47 10773 8192000000 10165959952 10575183872 1139838608 12.214 99 54 107 0 1270947072 119439552 11668912
02:35:12 14026 8192000000 10207084592 10615857152 1167819616 12.476 100 48 94 1 1316113872 137429072 10865184
02:36:29 2917 8192000000 10297937216 10704715776 1185326640 12.640 100 77 155 0 1397774528 195144784 17303104
02:37:35 17518 8192000000 10319017776 10739429376 1183766560 12.625 100 65 130 0 1426302944 227723152 14813232
02:38:57 2910 8192000000 10323083264 10734473216 1189943456 12.683 100 71 139 1 1424764384 219011376 15809552
02:40:06 1493 8192000000 10392930640 10800902144 1200221456 12.778 100 77 154 1 1490746048 273540208 16984384
02:41:12 2433 8192000000 10434251664 10844893184 1181997536 12.609 99 83 167 1 1526250272 325208800 19043936
02:42:21 2488 8192000000 10437938800 10866171904 1183847216 12.626 100 82 159 0 1541114560 338336384 18930960
02:43:30 1805 8192000000 10469586224 10878144512 1187411808 12.659 100 90 177 3 1570043200 361903968 20727424
02:44:36 1119 8192000000 10480826912 10890387456 1180200368 12.592 100 87 172 1 1577651424 376574288 20876768
02:45:44 4683 8192000000 10496595824 10908925952 1171106480 12.507 100 72 143 0 1597567392 409178080 17282832
02:46:57 7046 8192000000 10476511712 10903130112 1152394224 12.332 100 45 90 1 1583394304 418784064 12216016
02:48:07 2142 8192000000 10479098816 10898448384 1153464144 12.342 100 75 149 0 1584043584 411781696 18797744
02:49:13 14213 8192000000 10514332752 10963763200 1171781056 12.513 100 42 84 0 1628692352 446000704 10910592
02:50:34 8893 8192000000 10501838240 10974760960 1165582304 12.456 100 38 76 1 1610090544 434177808 10330432
02:51:48 11455 8192000000 10515239024 10976608256 1154019360 12.347 100 29 58 1 1609408960 446878912 8510688
02:53:42 15482 8192000000 10501833728 11011657728 1147694944 12.288 100 24 48 3 1619776080 465176384 6904752
02:55:06 17490 8192000000 10294111360 10882449408 1144348800 12.256 100 20 40 0 1455474512 305747200 5378512
02:56:24 33578 8192000000 10053623984 10488287232 1101555360 11.852 97 16 32 0 1126937440 22079792 3302288
02:57:58 60145 8192000000 10069404128 10551648256 1137313392 12.190 100 26 52 0 1207190784 64690096 5187296
03:00:02 51682 8192000000 10099281536 10541469696 1137628272 12.193 100 17 34 0 1176027072 35135024 3263776
03:01:54 109955 8192000000 10128060512 10576113664 1112809504 11.959 97 36 72 1 1198356464 77583824 7963136
03:04:56 62465 8192000000 10081911888 10525450240 1118659856 12.014 98 16 31 0 1184622352 62750624 3211872
03:07:00 30973 8192000000 10143819856 10576334848 1110281936 11.935 97 44 86 1 1202710736 83382832 9045968
03:08:33 49585 8192000000 10123349024 10612977664 1122649840 12.052 99 19 39 1 1265778416 138555440 4573136
03:10:52 33349 8192000000 10110688208 10570661888 1134412464 12.163 100 18 35 0 1229532480 91419136 3700880
03:12:26 43955 8192000000 10072533648 10589728768 1119658832 12.024 99 8 16 0 1176025808 54573440 1793536
03:14:10 89452 8192000000 10055217568 10516729856 1107859088 11.912 98 7 14 0 1165279376 55907632 1512656
03:16:40 61605 8192000000 10073263568 10512257024 1141014288 12.225 100 18 36 1 1162095040 17467312 3613440
03:18:43 47934 8192000000 10098210816 10532605952 1134882576 12.167 100 44 88 0 1198786832 55063472 8840784
03:20:32 30244 8192000000 10086586448 10537521152 1142560432 12.240 100 34 68 1 1211881888 62437568 6883888
03:22:34 58211 8192000000 10153017328 10599870464 1127667280 12.099 98 29 58 3 1235920112 102129824 6123008
03:24:39 80716 8192000000 10071470160 10526167040 1086812368 11.712 95 21 42 1 1121816784 30793600 4210816
03:27:10 99549 8192000000 10069449648 10536275968 1124673920 12.071 100 21 43 0 1178598096 49391760 4532416
03:29:54 36608 8192000000 10065577504 10542718976 1138999488 12.206 100 10 20 0 1183358192 42498208 1860496
03:31:40 42816 8192000000 10080745312 10544750592 1122363984 12.049 98 12 23 2 1164859376 40213968 2281424
03:33:42 66172 8192000000 10162966800 10619650048 1138604928 12.202 99 18 36 0 1223327600 80854736 3867936
03:36:38 73668 8192000000 10068297840 10503561216 1100565760 11.843 97 23 46 0 1135401440 29954112 4881568
03:38:54 61263 8192000000 10068020816 10544492544 1109425520 11.927 98 16 32 0 1179733616 66976336 3331760
03:40:56 41674 8192000000 10065706336 10500046848 1104903024 11.884 99 44 88 0 1151953872 37854080 9196768
03:42:39 25053 8192000000 10078793456 10501906432 1079127392 11.639 97 41 81 0 1133232976 46382400 7723184
03:44:05 136026 8192000000 10069969840 10518388736 1077690128 11.625 96 14 28 1 1129967872 49265856 3011888
03:48:46 40904 8192000000 10038481488 10500976640 1117719104 12.005 100 9 18 0 1151004400 31423504 1861792
03:50:27 46875 8192000000 10050295168 10526408704 1123502416 12.060 100 4 8 0 1154004176 29679152 822608
03:52:14 96262 8192000000 10105967536 10553470976 1097746128 11.816 97 19 38 1 1170853872 69335456 3772288
03:54:51 100616 8192000000 10061342656 10499694592 1111284816 11.945 99 16 32 1 1169659456 55147152 3227488
03:57:37 68263 8192000000 10066714032 10538717184 1119815376 12.025 99 25 50 0 1184137168 59265792 5056000
03:59:47 135206 8192000000 10051683760 10520821760 1054270608 11.402 93 15 30 1 1093182208 36135792 2775808
04:03:03 66705 8192000000 10097038096 10554114048 1121120864 12.038 99 24 48 0 1175355456 49517248 4717344
04:05:15 165934 8192000000 10074596704 10603266048 1073097552 11.582 95 14 28 1 1138082272 62307696 2677024
04:09:49 51441 8192000000 10063716256 10540392448 1111265072 11.944 99 5 10 0 1143270576 31122144 883360
04:13:03 66299 8192000000 10082013760 10536574976 1094764128 11.788 97 23 45 2 1150024992 50011552 5249312
04:15:10 69293 8192000000 10106461984 10583924736 1123186992 12.057 99 21 42 0 1192414656 64095648 5132016
04:17:43 62769 8192000000 10131454416 10606211072 1111693648 11.948 98 25 50 1 1212845168 95712912 5438608
04:19:52 63257 8192000000 10131480352 10582376448 1112879520 11.960 97 19 38 2 1205329872 87968288 4482064
04:21:56 64867 8192000000 10102148880 10533130240 1094925088 11.789 96 20 40 0 1135389456 36500096 3964272
04:24:01 108384 8192000000 10077003488 10518138880 1050408576 11.365 93 18 36 0 1090805088 36822304 3574208
04:26:50 24584 8192000000 8983012720 9215107072 145923408 1.750 14 2 4 0 163233872 17003088 307376

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-02-2021 04:30:12 to 18-02-2021 04:30:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 135 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-02-2021 04:30:28 to 18-02-2021 04:30:30.

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          227310
MON$OLDEST_ACTIVE               227311
MON$OLDEST_SNAPSHOT             227311
MON$NEXT_TRANSACTION            227311
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                       2094592
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2369 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2369 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2369 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.2369 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 ---
:25: 
:26: mscs-test-161.budzdorov.ru    Thu Feb 18 04:27:53 2021
:27:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:28: 
:29: 
:30: mscs-test-161.budzdorov.ru    Thu Feb 18 04:27:55 2021
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:32: 
--- end of diff output ---

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

18.02.21 04:30:31. 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-02-18 04:30:34 No severe PSQL-related problems occured
18.02.21 04:30:35. End of report.