Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.12.2020 04: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 16803048 kB
MemAvailable 31382544 kB
Buffers 20 kB
Cached 14762520 kB
SwapCached 3700 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-12-24 04:26:53
DTS_END                         2020-12-24 04:26:53.6900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33236
TRN_ID                          218795
ATT_ID                          584
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: 8619.52 27395 1034342
customer order: creation 147.56 480 17707
customer order: refuse 12.27 355 1472
order to supplier: creation 39.45 1409 4734
order to supplier: removal 3.08 134466 369
invoice (draft): creation 40.85 4059 4902
invoice (draft): removal 53.51 909 6421
invoice accept: apply 35.79 18876 4295
invoice accept: cancel 47.33 844 5680
customer reserve: creation 3347.63 180 401716
customer reserve: removal 4622.25 71 554670
realization accept: apply 72.69 166 8723
realization accept: cancel 85.30 105 10236
payment from customer: creation 51.03 115 6124
payment from customer: removal 5.30 73 636
payment to supplier: creation 42.41 141 5089
payment to supplier: removal 3.46 80 415
service: total inventory turnovers 3.53 3211 424
service: total monetary turnovers 5.26 567 631
service: aggregate perf. data 0.78 17229 94
service: refresh index statistics 0.03 364564 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-12-2020 04:29:15 to 24-12-2020 04:29:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2521
TEST_TIME 2 4409
TEST_TIME 3 5655
TEST_TIME 4 5692
TEST_TIME 5 5239
TEST_TIME 6 5265
TEST_TIME 7 5638
TEST_TIME 8 5467
TEST_TIME 9 5736
TEST_TIME 10 5949
TEST_TIME 11 6462
TEST_TIME 12 6846
TEST_TIME 13 6997
TEST_TIME 14 6938
TEST_TIME 15 7203
TEST_TIME 16 7671
TEST_TIME 17 7886
TEST_TIME 18 8021
TEST_TIME 19 8381
TEST_TIME 20 8491
TEST_TIME 21 8499
TEST_TIME 22 8503
TEST_TIME 23 8975
TEST_TIME 24 9280
TEST_TIME 25 9330
TEST_TIME 26 10197
TEST_TIME 27 11482
TEST_TIME 28 11347
TEST_TIME 29 11030
TEST_TIME 30 10794
TEST_TIME 31 10645
TEST_TIME 32 10562
TEST_TIME 33 10313
TEST_TIME 34 10101
TEST_TIME 35 10105
TEST_TIME 36 9926
TEST_TIME 37 10039
TEST_TIME 38 9949
TEST_TIME 39 9772
TEST_TIME 40 9732
TEST_TIME 41 9612
TEST_TIME 42 9573
TEST_TIME 43 9465
TEST_TIME 44 9335
TEST_TIME 45 9330
TEST_TIME 46 9252
TEST_TIME 47 9101
TEST_TIME 48 9004
TEST_TIME 49 8938
TEST_TIME 50 8929
TEST_TIME 51 8892
TEST_TIME 52 8853
TEST_TIME 53 8824
TEST_TIME 54 8747
TEST_TIME 55 8731
TEST_TIME 56 8756
TEST_TIME 57 8877
TEST_TIME 58 8835
TEST_TIME 59 8920
TEST_TIME 60 8895
TEST_TIME 61 8949
TEST_TIME 62 8969
TEST_TIME 63 9049
TEST_TIME 64 9079
TEST_TIME 65 9043
TEST_TIME 66 8932
TEST_TIME 67 8864
TEST_TIME 68 8815
TEST_TIME 69 8793
TEST_TIME 70 8802
TEST_TIME 71 8808
TEST_TIME 72 8767
TEST_TIME 73 8874
TEST_TIME 74 8840
TEST_TIME 75 8789
TEST_TIME 76 8845
TEST_TIME 77 8874
TEST_TIME 78 8833
TEST_TIME 79 8976
TEST_TIME 80 8961
TEST_TIME 81 8891
TEST_TIME 82 8811
TEST_TIME 83 8708
TEST_TIME 84 8665
TEST_TIME 85 8632
TEST_TIME 86 8560
TEST_TIME 87 8614
TEST_TIME 88 8586
TEST_TIME 89 8574
TEST_TIME 90 8567
TEST_TIME 91 8527
TEST_TIME 92 8507
TEST_TIME 93 8580
TEST_TIME 94 8707
TEST_TIME 95 8708
TEST_TIME 96 8745
TEST_TIME 97 8766
TEST_TIME 98 8816
TEST_TIME 99 8869
TEST_TIME 100 8847
TEST_TIME 101 8766
TEST_TIME 102 8729
TEST_TIME 103 8686
TEST_TIME 104 8642
TEST_TIME 105 8627
TEST_TIME 106 8627
TEST_TIME 107 8582
TEST_TIME 108 8600
TEST_TIME 109 8565
TEST_TIME 110 8583
TEST_TIME 111 8577
TEST_TIME 112 8579
TEST_TIME 113 8681
TEST_TIME 114 8685
TEST_TIME 115 8697
TEST_TIME 116 8676
TEST_TIME 117 8717
TEST_TIME 118 8685
TEST_TIME 119 8651
TEST_TIME 120 8621

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-12-2020 04:29:18 to 24-12-2020 04:29:22.

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 1407069 1407069 0 0.00 0 12860 6 0 0
doc_list_biud 1407069 1407069 0 0.00 0 85857 35 0 0
sp_add_invoice_to_stock 4368 4295 73 1.67 31 182985 18876 0 73
sp_cancel_adding_invoice 5680 5680 0 0.00 5 79488 845 0 0
sp_cancel_client_order 1472 1472 0 0.00 4 10406 356 0 0
sp_cancel_customer_reserve 554670 554670 0 0.00 1 85859 72 0 0
sp_cancel_pay_from_customer 636 636 0 0.00 3 4494 73 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 4800 81 0 0
sp_cancel_supplier_invoice 6423 6421 2 0.03 2 21789 910 0 2
sp_cancel_supplier_order 369 369 0 0.00 6 1056777 134466 0 0
sp_cancel_write_off 10236 10236 0 0.00 1 8883 106 0 0
sp_client_order 17707 17707 0 0.00 4 81103 481 0 0
sp_customer_reserve 401716 401716 0 0.00 0 74375 181 0 0
sp_fill_shopping_cart 27321 27321 0 0.00 1 25045 53 0 0
sp_fill_shopping_cart_clo_res 401712 401712 0 0.00 0 29465 24 0 0
sp_get_clo_for_invoice 4290 4290 0 0.00 0 45015 654 0 0
sp_kill_cost_storno 16967 16967 0 0.00 0 8869 56 0 0
sp_kill_qstorno_ret_qs2qd 1126366 1126366 0 0.00 0 85853 38 0 0
sp_kill_qty_storno 591860 591860 0 0.00 0 85857 82 0 0
sp_lock_selected_doc 18085 18085 0 0.00 0 6930 5 0 0
sp_make_cost_storno 24225 24225 0 0.00 0 12855 55 0 0
sp_multiply_rows_for_pdistr 24225 24225 0 0.00 0 8139 31 0 0
sp_multiply_rows_for_qdistr 17707 17707 0 0.00 0 81086 284 0 0
sp_pay_from_customer 6124 6124 0 0.00 4 14821 115 0 0
sp_pay_to_supplier 5089 5089 0 0.00 5 12879 142 0 0
sp_payment_common 11213 11213 0 0.00 1 8764 23 0 0
sp_qd_handle_on_cancel_clo 1472 1472 0 0.00 0 10398 318 0 0
sp_qd_handle_on_invoice_upd_sts 9969 9969 0 0.00 0 22448 392 0 0
sp_qd_handle_on_reserve_upd_sts 8723 8723 0 0.00 0 12945 51 0 0
sp_reserve_write_off 8723 8723 0 0.00 7 15369 167 0 0
sp_supplier_invoice 4902 4902 0 0.00 7 109533 4059 0 0
sp_supplier_order 4734 4734 0 0.00 5 53756 1410 0 0
srv_aggregate_perf_data 95 94 1 1.05 2830 215339 17230 0 1
srv_fill_mon_cache_memory 59 59 0 0.00 1684 167060 52434 0 0
srv_find_qd_qs_mism 993344 993344 0 0.00 0 12774 4 0 0
srv_make_invnt_saldo 424 424 0 0.00 71 97420 3212 0 0
srv_make_money_saldo 631 631 0 0.00 1 24185 568 0 0
srv_recalc_idx_stat 4 4 0 0.00 56660 574496 364564 0 0
t$perf-abend:sp_add_invoice_to_stock 73 73 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 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 4295 4295 0 0.00 0 13401 179 0 0
t$perf-norm:sp_cancel_adding_invoice 325 325 0 0.00 0 11151 139 0 0
t$perf-norm:sp_cancel_client_order 1472 1472 0 0.00 0 9827 22 0 0
t$perf-norm:sp_cancel_customer_reserve 608 608 0 0.00 0 4337 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 636 636 0 0.00 0 1591 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 1062 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 574 11 0 0
t$perf-norm:sp_cancel_supplier_order 369 369 0 0.00 0 18556 1606 0 0
t$perf-norm:sp_cancel_write_off 861 861 0 0.00 0 1416 4 0 0
t$perf-norm:sp_client_order 17707 17707 0 0.00 0 9606 17 0 0
t$perf-norm:sp_customer_reserve 892 892 0 0.00 0 2732 8 0 0
t$perf-norm:sp_pay_from_customer 6124 6124 0 0.00 0 4479 7 0 0
t$perf-norm:sp_pay_to_supplier 5089 5089 0 0.00 0 6282 10 0 0
t$perf-norm:sp_reserve_write_off 8723 8723 0 0.00 0 6795 11 0 0
t$perf-norm:sp_supplier_invoice 4902 4902 0 0.00 0 6465 21 0 0
t$perf-norm:sp_supplier_order 4734 4734 0 0.00 0 4504 15 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 153 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 13 1 0 0
t$perf-norm:srv_make_invnt_saldo 424 424 0 0.00 0 335 6 0 0
t$perf-norm:srv_make_money_saldo 631 631 0 0.00 0 497 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 1326 334 0 0
v_add_invoice_to_stock 8724 8578 146 1.67 0 8979 18 0 146
v_all_customers 32010 32010 0 0.00 0 18 1 0 0
v_all_suppliers 26680 26680 0 0.00 0 60 1 0 0
v_all_wares 35414 35414 0 0.00 0 69 1 0 0
v_cancel_adding_invoice 650 650 0 0.00 0 3626 19 0 0
v_cancel_client_order 2944 2944 0 0.00 0 3195 7 0 0
v_cancel_customer_prepayment 1272 1272 0 0.00 0 1310 5 0 0
v_cancel_customer_reserve 1216 1216 0 0.00 0 5760 12 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 4794 14 0 0
v_cancel_supplier_invoice 778 774 4 0.51 0 3179 17 0 4
v_cancel_supplier_order 738 738 0 0.00 0 1304 7 0 0
v_cancel_write_off 1722 1722 0 0.00 0 4390 21 0 0
v_max_id_clo_ord 4732 4732 0 0.00 0 3291 2 0 0
v_max_id_clo_res 892 892 0 0.00 0 3994 16 0 0
v_max_id_ord_sup 4882 4882 0 0.00 0 5377 4 0 0
v_max_non_paid_invoice 1362 1362 0 0.00 0 6168 8 0 0
v_max_non_paid_realizn 3370 3370 0 0.00 0 3169 3 0 0
v_min_id_clo_ord 4732 4732 0 0.00 0 9141 14 0 0
v_min_id_clo_res 892 892 0 0.00 0 7796 31 0 0
v_min_id_ord_sup 4882 4882 0 0.00 0 25023 11 0 0
v_min_non_paid_invoice 5089 5089 0 0.00 0 8153 18 0 0
v_min_non_paid_realizn 6124 6124 0 0.00 0 7725 11 0 0
v_our_firm 8912 8912 0 0.00 0 22 1 0 0
v_reserve_write_off 17446 17446 0 0.00 0 6580 8 0 0
x_lock_depdocs_on_canc_invoice 11714 11714 0 0.00 0 46004 112 0 0
x_lock_depdocs_on_canc_sup_ord 369 369 0 0.00 0 2408 337 0 0
x_make_qstorno_1000_1200 4734 4734 0 0.00 2 53739 1320 0 0
x_make_qstorno_1200_2000 4902 4902 0 0.00 3 109481 3863 0 0
x_make_qstorno_2100_3300 401716 401716 0 0.00 0 58880 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-12-2020 04:29:23 to 24-12-2020 04:29:27.

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:56:57 1 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
01:57:58 22038 8192000000 9722083552 10017808384 802250368 8.919 100 88 178 0 830805392 7669568 20885456
01:59:23 28181 8192000000 9764351904 10067419136 835692160 9.256 100 91 182 1 871428768 13710288 22026320
02:01:00 20743 8192000000 9805866560 10122436608 861099328 9.511 100 85 170 0 912851264 31978016 19773920
02:02:27 10658 8192000000 9879793424 10210705408 916053568 10.057 100 81 162 1 984151280 50520512 17577200
02:03:44 7449 8192000000 9919970400 10264887296 964421440 10.532 100 76 148 2 1032767776 52801072 15545264
02:04:56 3726 8192000000 9963474864 10316050432 998311136 10.862 100 81 161 0 1075599808 61208608 16080064
02:06:01 3939 8192000000 10025113440 10385281024 1027471984 11.144 100 88 176 2 1136457216 91385472 17599760
02:07:07 2090 8192000000 10040318576 10405052416 1056153920 11.420 100 91 182 0 1153466368 78872240 18440208
02:08:11 1908 8192000000 10046302256 10416709632 1070427472 11.556 100 85 170 1 1156908208 69252208 17228528
02:09:14 14761 8192000000 10002081120 10407948288 1073408384 11.585 100 64 129 0 1118458880 30904912 14145584
02:10:35 4156 8192000000 9989170368 10378919936 1078452928 11.633 100 48 94 1 1104448016 15043904 10951184
02:11:41 7041 8192000000 9988537328 10390859776 1088141152 11.725 100 33 67 1 1127150384 32427392 6581840
02:12:49 11499 8192000000 9994807072 10405695488 1093954400 11.780 100 23 47 1 1123414208 24927792 4532016
02:14:02 4161 8192000000 10018802656 10412519424 1104436096 11.880 100 45 90 3 1148071072 34840048 8794928
02:15:08 3378 8192000000 10032731872 10422042624 1101930624 11.856 100 67 133 1 1135107008 19702752 13473632
02:16:12 8725 8192000000 10009458832 10403606528 1074858928 11.598 98 39 76 3 1111801792 28448928 8493936
02:17:21 6299 8192000000 9979695648 10364452864 1069359232 11.546 99 34 65 3 1098615472 22121472 7134768
02:18:28 5603 8192000000 9954967376 10346508288 1037958848 11.245 98 33 67 2 1069018256 24184528 6874880
02:19:35 22714 8192000000 9918004560 10312282112 981733584 10.701 94 15 30 1 1012018080 26845456 3439040
02:20:58 3707 8192000000 9948937712 10323247104 1016777392 11.041 99 57 115 1 1047817632 18992688 12047552
02:22:02 7731 8192000000 9923864352 10308386816 996356672 10.843 98 37 74 3 1020651280 16042816 8251792
02:23:12 44907 8192000000 9965418800 10340950016 1044925424 11.312 100 49 97 1 1065322896 10273536 10123936
02:24:57 10071 8192000000 9957423104 10359820288 1050632096 11.367 100 32 64 2 1082333328 24732560 6968672
02:26:11 8291 8192000000 9993355328 10407063552 1071876208 11.570 100 31 61 2 1119588256 41443264 6268784
02:27:27 11155 8192000000 10001827888 10396389376 1096659232 11.806 100 43 88 2 1114210944 7354016 10197696
02:28:42 73851 8192000000 10091205136 10512089088 1108839408 11.921 100 59 118 1 1201448992 79787472 12822112
02:30:59 18840 8192000000 10070564144 10479063040 1124273136 12.067 100 37 72 0 1172569056 40972544 7323376
02:32:29 5173 8192000000 10114898176 10515722240 1136547120 12.183 100 62 124 2 1223614560 73444736 13622704
02:33:37 5827 8192000000 10154701120 10564435968 1146822464 12.280 100 55 110 1 1267497216 108588656 12086096
02:34:53 19827 8192000000 10214278224 10628861952 1160973024 12.412 100 54 108 0 1319196960 146622592 11601344
02:36:16 5705 8192000000 10221909040 10628263936 1163617680 12.437 100 66 132 1 1328944176 150049568 15276928
02:37:28 6948 8192000000 10238849520 10662326272 1158529648 12.389 100 65 129 0 1355803184 182349984 14923552
02:38:43 2598 8192000000 10265635552 10666373120 1170981168 12.506 100 79 158 2 1361932448 173677072 17274208
02:39:49 4060 8192000000 10282485488 10703536128 1166537232 12.464 99 78 155 2 1395305248 210414848 18353168
02:40:59 1683 8192000000 10327852480 10724540416 1159479744 12.398 99 82 160 2 1428389216 249430544 19478928
02:42:04 4312 8192000000 10378349296 10798714880 1189803472 12.682 100 76 150 0 1475106288 267543472 17759344
02:43:15 6150 8192000000 10377779328 10791825408 1183709392 12.625 100 67 135 2 1476600368 276505712 16385264
02:44:26 3818 8192000000 10350328928 10778312704 1167367216 12.472 100 58 113 1 1449175872 267268032 14540624
02:45:36 8448 8192000000 10426681440 10845360128 1170472976 12.501 100 59 119 0 1534141424 349001632 14666816
02:46:52 3429 8192000000 10424594832 10862473216 1171092448 12.507 100 47 93 0 1519456832 336859904 11504480
02:48:02 12843 8192000000 10470473664 10898317312 1153245488 12.340 99 45 90 0 1550374672 385410048 11719136
02:49:39 12859 8192000000 10508827520 10943954944 1159057680 12.394 100 33 66 2 1594204432 426280608 8866144
02:51:03 16765 8192000000 10485850288 10940145664 1120358864 12.030 99 29 58 2 1575603728 447187312 8057552
02:52:27 13429 8192000000 10325110720 10838351872 1087614736 11.720 99 19 38 0 1470716016 377323648 5777632
02:53:43 18357 8192000000 10086319984 10524278784 1115684640 11.986 100 22 44 1 1178928560 58526384 4717536
02:55:19 97220 8192000000 10081380672 10535653376 1101011376 11.847 99 30 59 2 1163363072 55949488 6402208
03:00:30 92734 8192000000 10154995056 10623123456 1125548032 12.079 99 31 61 4 1241406352 109155440 6702880
03:04:22 99412 8192000000 10076265072 10528735232 1089704992 11.740 97 13 25 2 1164756224 72010240 3040992
03:07:03 19821 8192000000 10063820896 10490105856 1119651072 12.024 99 48 96 2 1156844832 27017952 10175808
03:08:25 59260 8192000000 10078509712 10548387840 1114400176 11.974 99 24 48 1 1182996352 63173440 5422736
03:10:25 90029 8192000000 10079486112 10578100224 1118549312 12.013 99 14 28 1 1195663952 74300512 2814128
03:12:57 134941 8192000000 10068935888 10557554688 1131757088 12.138 100 15 30 0 1180423072 45177488 3488496
03:16:20 45069 8192000000 10079321296 10562797568 1109695760 11.930 98 22 44 0 1180091136 65829056 4566320
03:18:06 39570 8192000000 10048922416 10492719104 1127870864 12.101 100 18 36 2 1150293520 19292624 3130032
03:19:50 57416 8192000000 10110054784 10545508352 1129859248 12.120 99 30 60 2 1197903104 61608432 6435424
03:21:49 66417 8192000000 10119707328 10580717568 1142003344 12.234 100 26 52 0 1220730784 73141152 5586288
03:24:09 41757 8192000000 10111506992 10562682880 1149396208 12.304 100 22 43 4 1204127168 49576912 5154048
03:25:55 86227 8192000000 10106360256 10543869952 1133172672 12.151 98 13 25 0 1187053712 51338864 2542176
03:28:22 167057 8192000000 10103550272 10643537920 1129273392 12.115 97 14 28 1 1210699360 78789584 2636384
03:32:13 118881 8192000000 10075917632 10531610624 1102665696 11.863 96 6 12 0 1132795648 29024256 1105696
03:35:13 80584 8192000000 10070155312 10550874112 1088164144 11.725 95 13 26 2 1153781264 62892720 2724400
03:37:35 45325 8192000000 10104331872 10544701440 1113954480 11.970 98 17 34 0 1172906672 55449824 3502368
03:39:23 86422 8192000000 10112442464 10635202560 1111610352 11.948 98 20 40 1 1171475248 55939824 3925072
03:41:50 95339 8192000000 10048861312 10538102784 1061956144 11.475 93 19 38 0 1141621120 75327904 4337072
03:44:34 15884 8192000000 10096485952 10515537920 1107453200 11.908 99 47 91 2 1169992912 52918880 9620832
03:46:13 94668 8192000000 10030359488 10462257152 1016661856 11.040 91 7 14 0 1057274800 39274672 1338272
03:48:56 145189 8192000000 10087139728 10539065344 1107850720 11.912 100 34 67 0 1193620800 78741680 7028400
03:52:21 38935 8192000000 10084394896 10551820288 1086550400 11.710 98 6 12 0 1163786608 75940528 1295680
03:54:01 52972 8192000000 10031627024 10458705920 1042728928 11.291 95 17 34 1 1090942336 44731120 3482288
03:55:58 52940 8192000000 10049958944 10480594944 1036389056 11.230 94 25 50 0 1091649536 50060224 5200256
03:57:51 48698 8192000000 10145706768 10572726272 1124335040 12.068 100 40 80 3 1220659584 87780080 8544464
03:59:51 63655 8192000000 10077816752 10574868480 1119820944 12.025 100 20 39 1 1207107824 83447632 3839248
04:01:55 66743 8192000000 10085218960 10557677568 1098200448 11.821 97 12 23 0 1159225136 59075360 1949328
04:04:06 73440 8192000000 10072858912 10576220160 1086128032 11.706 96 9 17 1 1143789136 56124320 1536784
04:06:28 124474 8192000000 10053712896 10499502080 1090756800 11.750 97 25 50 0 1116057296 19550272 5750224
04:09:33 46871 8192000000 10081465680 10542538752 1131803840 12.138 100 11 22 0 1186240608 51792960 2643808
04:11:28 70910 8192000000 10078470592 10565349376 1138363472 12.200 100 14 28 0 1211409696 70114704 2931520
04:13:39 77578 8192000000 10105042688 10592907264 1133308064 12.153 99 13 25 1 1194058992 58337536 2413392
04:15:58 51714 8192000000 10127383520 10588565504 1152853488 12.336 100 35 70 3 1235123552 74600160 7669904
04:17:55 66522 8192000000 10132694208 10572812288 1134629552 12.165 98 20 40 1 1188488352 49559840 4298960
04:20:09 66338 8192000000 10090889728 10593714176 1154036704 12.347 100 11 21 1 1209346816 53470480 1839632
04:22:24 47096 8192000000 10106236240 10575384576 1142357328 12.238 99 21 42 0 1206135872 59441312 4337232
04:24:16 109296 8192000000 10119593072 10601406464 1154207344 12.349 100 32 64 1 1217097264 56515680 6374240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 24-12-2020 04:29:29 to 24-12-2020 04:29:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 73 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-12-2020 04:29:44 to 24-12-2020 04:29:46.

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          218185
MON$OLDEST_ACTIVE               218952
MON$OLDEST_SNAPSHOT             218952
MON$NEXT_TRANSACTION            218952
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                       2088864
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             764
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2302 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2302 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2302 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.2302 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 ---
--- end of diff output ---

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

24.12.20 04:29:47. 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-12-24 04:29:50 No severe PSQL-related problems occured
24.12.20 04:29:51. End of report.