Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.11.2020 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 16820412 kB
MemAvailable 31479064 kB
Buffers 20 kB
Cached 14831140 kB
SwapCached 3108 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-11-24 04:26:56
DTS_END                         2020-11-24 04:26:56.1590
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35150
TRN_ID                          216320
ATT_ID                          627
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: 8541.64 27769 1024997
customer order: creation 150.88 421 18105
customer order: refuse 13.20 410 1584
order to supplier: creation 40.11 1378 4813
order to supplier: removal 3.12 145739 374
invoice (draft): creation 42.16 4259 5059
invoice (draft): removal 51.23 1065 6148
invoice accept: apply 36.48 21788 4378
invoice accept: cancel 46.01 983 5521
customer reserve: creation 3375.20 212 405024
customer reserve: removal 4517.34 78 542081
realization accept: apply 73.14 204 8777
realization accept: cancel 78.74 133 9449
payment from customer: creation 52.96 125 6355
payment from customer: removal 5.27 118 632
payment to supplier: creation 42.88 139 5145
payment to supplier: removal 3.17 88 380
service: total inventory turnovers 3.28 4713 393
service: total monetary turnovers 5.59 790 671
service: aggregate perf. data 0.87 14499 104
service: refresh index statistics 0.03 358232 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-11-2020 04:29:43 to 24-11-2020 04:29:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3301
TEST_TIME 2 3313
TEST_TIME 3 4191
TEST_TIME 4 4642
TEST_TIME 5 5543
TEST_TIME 6 5750
TEST_TIME 7 5842
TEST_TIME 8 5865
TEST_TIME 9 5697
TEST_TIME 10 5817
TEST_TIME 11 5939
TEST_TIME 12 5987
TEST_TIME 13 6324
TEST_TIME 14 6411
TEST_TIME 15 6831
TEST_TIME 16 7054
TEST_TIME 17 7135
TEST_TIME 18 7354
TEST_TIME 19 7538
TEST_TIME 20 7979
TEST_TIME 21 7942
TEST_TIME 22 8066
TEST_TIME 23 8265
TEST_TIME 24 8534
TEST_TIME 25 8477
TEST_TIME 26 8699
TEST_TIME 27 8509
TEST_TIME 28 8602
TEST_TIME 29 8751
TEST_TIME 30 8825
TEST_TIME 31 9151
TEST_TIME 32 10635
TEST_TIME 33 10918
TEST_TIME 34 10645
TEST_TIME 35 10505
TEST_TIME 36 10335
TEST_TIME 37 10251
TEST_TIME 38 10130
TEST_TIME 39 9991
TEST_TIME 40 9836
TEST_TIME 41 9842
TEST_TIME 42 9725
TEST_TIME 43 9632
TEST_TIME 44 9584
TEST_TIME 45 9373
TEST_TIME 46 9339
TEST_TIME 47 9245
TEST_TIME 48 9157
TEST_TIME 49 9185
TEST_TIME 50 9149
TEST_TIME 51 9068
TEST_TIME 52 8984
TEST_TIME 53 8930
TEST_TIME 54 8906
TEST_TIME 55 8976
TEST_TIME 56 8897
TEST_TIME 57 8909
TEST_TIME 58 9087
TEST_TIME 59 9061
TEST_TIME 60 9078
TEST_TIME 61 9065
TEST_TIME 62 9017
TEST_TIME 63 8882
TEST_TIME 64 8849
TEST_TIME 65 8757
TEST_TIME 66 8686
TEST_TIME 67 8671
TEST_TIME 68 8651
TEST_TIME 69 8606
TEST_TIME 70 8644
TEST_TIME 71 8696
TEST_TIME 72 8655
TEST_TIME 73 8749
TEST_TIME 74 8710
TEST_TIME 75 8750
TEST_TIME 76 8716
TEST_TIME 77 8703
TEST_TIME 78 8769
TEST_TIME 79 8784
TEST_TIME 80 8805
TEST_TIME 81 8772
TEST_TIME 82 8779
TEST_TIME 83 8787
TEST_TIME 84 8685
TEST_TIME 85 8621
TEST_TIME 86 8589
TEST_TIME 87 8538
TEST_TIME 88 8533
TEST_TIME 89 8507
TEST_TIME 90 8467
TEST_TIME 91 8501
TEST_TIME 92 8483
TEST_TIME 93 8502
TEST_TIME 94 8616
TEST_TIME 95 8635
TEST_TIME 96 8642
TEST_TIME 97 8652
TEST_TIME 98 8686
TEST_TIME 99 8773
TEST_TIME 100 8721
TEST_TIME 101 8702
TEST_TIME 102 8626
TEST_TIME 103 8632
TEST_TIME 104 8574
TEST_TIME 105 8518
TEST_TIME 106 8507
TEST_TIME 107 8539
TEST_TIME 108 8558
TEST_TIME 109 8524
TEST_TIME 110 8531
TEST_TIME 111 8580
TEST_TIME 112 8633
TEST_TIME 113 8589
TEST_TIME 114 8606
TEST_TIME 115 8587
TEST_TIME 116 8694
TEST_TIME 117 8659
TEST_TIME 118 8641
TEST_TIME 119 8573
TEST_TIME 120 8547

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-11-2020 04:29:47 to 24-11-2020 04:29:50.

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 1401428 1401428 0 0.00 0 22085 8 0 0
doc_list_biud 1401428 1401428 0 0.00 0 49954 38 0 0
sp_add_invoice_to_stock 4466 4378 88 1.97 21 224282 21788 0 88
sp_cancel_adding_invoice 5521 5521 0 0.00 6 59621 984 0 0
sp_cancel_client_order 1584 1584 0 0.00 4 7892 410 0 0
sp_cancel_customer_reserve 542081 542081 0 0.00 1 47819 79 0 0
sp_cancel_pay_from_customer 632 632 0 0.00 3 4330 119 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 4017 89 0 0
sp_cancel_supplier_invoice 6156 6148 8 0.12 2 50017 1065 0 8
sp_cancel_supplier_order 374 374 0 0.00 11 1236986 145739 0 0
sp_cancel_write_off 9449 9449 0 0.00 1 10809 133 0 0
sp_client_order 18105 18105 0 0.00 4 36348 421 0 0
sp_customer_reserve 405024 405024 0 0.00 0 56115 213 0 0
sp_fill_shopping_cart 27961 27961 0 0.00 1 15357 53 0 0
sp_fill_shopping_cart_clo_res 405018 405018 0 0.00 0 20767 18 0 0
sp_get_clo_for_invoice 4370 4370 0 0.00 0 52734 446 0 0
sp_kill_cost_storno 15982 15982 0 0.00 0 6106 73 0 0
sp_kill_qstorno_ret_qs2qd 1100133 1100133 0 0.00 0 49952 42 0 0
sp_kill_qty_storno 578304 578304 0 0.00 0 49953 91 0 0
sp_lock_selected_doc 18312 18312 0 0.00 0 9049 7 0 0
sp_make_cost_storno 24647 24647 0 0.00 0 22081 62 0 0
sp_multiply_rows_for_pdistr 24647 24647 0 0.00 0 9722 37 0 0
sp_multiply_rows_for_qdistr 18105 18105 0 0.00 0 35797 230 0 0
sp_pay_from_customer 6355 6355 0 0.00 4 23950 125 0 0
sp_pay_to_supplier 5145 5145 0 0.00 5 14988 139 0 0
sp_payment_common 11500 11500 0 0.00 1 7987 21 0 0
sp_qd_handle_on_cancel_clo 1584 1584 0 0.00 0 7882 377 0 0
sp_qd_handle_on_invoice_upd_sts 9891 9891 0 0.00 0 32136 458 0 0
sp_qd_handle_on_reserve_upd_sts 8777 8777 0 0.00 0 25813 66 0 0
sp_reserve_write_off 8777 8777 0 0.00 7 26077 204 0 0
sp_supplier_invoice 5059 5059 0 0.00 7 107883 4260 0 0
sp_supplier_order 4813 4813 0 0.00 5 57722 1378 0 0
srv_aggregate_perf_data 104 104 0 0.00 1907 202377 14500 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1304 118160 44505 0 0
srv_find_qd_qs_mism 983619 983619 0 0.00 0 19953 6 0 0
srv_make_invnt_saldo 393 393 0 0.00 70 107809 4714 0 0
srv_make_money_saldo 671 671 0 0.00 1 13854 790 0 0
srv_recalc_idx_stat 4 4 0 0.00 170053 460871 358232 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4378 4378 0 0.00 0 7303 197 0 0
t$perf-norm:sp_cancel_adding_invoice 365 365 0 0.00 0 2452 114 0 0
t$perf-norm:sp_cancel_client_order 1584 1584 0 0.00 0 2823 14 0 0
t$perf-norm:sp_cancel_customer_reserve 611 611 0 0.00 0 2403 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 632 632 0 0.00 0 25576 46 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 739 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 959 26 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 41935 2023 0 0
t$perf-norm:sp_cancel_write_off 832 832 0 0.00 0 3308 11 0 0
t$perf-norm:sp_client_order 18105 18105 0 0.00 0 5538 18 0 0
t$perf-norm:sp_customer_reserve 870 870 0 0.00 0 3942 10 0 0
t$perf-norm:sp_pay_from_customer 6355 6355 0 0.00 0 1735 6 0 0
t$perf-norm:sp_pay_to_supplier 5145 5145 0 0.00 0 2539 7 0 0
t$perf-norm:sp_reserve_write_off 8777 8777 0 0.00 0 4377 9 0 0
t$perf-norm:sp_supplier_invoice 5059 5059 0 0.00 0 4932 21 0 0
t$perf-norm:sp_supplier_order 4813 4813 0 0.00 0 4340 20 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 1118 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 81 2 0 0
t$perf-norm:srv_make_invnt_saldo 393 393 0 0.00 0 3761 20 0 0
t$perf-norm:srv_make_money_saldo 671 671 0 0.00 0 2805 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 13 5 0 0
v_add_invoice_to_stock 8916 8740 176 1.97 0 6828 21 0 176
v_all_customers 33378 33378 0 0.00 0 15 1 0 0
v_all_suppliers 27202 27202 0 0.00 0 93 1 0 0
v_all_wares 36210 36210 0 0.00 0 68 1 0 0
v_cancel_adding_invoice 730 730 0 0.00 0 2151 13 0 0
v_cancel_client_order 3168 3168 0 0.00 0 3534 6 0 0
v_cancel_customer_prepayment 1264 1264 0 0.00 0 1991 10 0 0
v_cancel_customer_reserve 1222 1222 0 0.00 0 1629 5 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 4003 13 0 0
v_cancel_supplier_invoice 790 774 16 2.02 0 507 13 0 16
v_cancel_supplier_order 748 748 0 0.00 0 2229 11 0 0
v_cancel_write_off 1664 1664 0 0.00 0 2365 19 0 0
v_max_id_clo_ord 4813 4813 0 0.00 0 3223 2 0 0
v_max_id_clo_res 870 870 0 0.00 0 8546 28 0 0
v_max_id_ord_sup 5043 5043 0 0.00 0 5044 4 0 0
v_max_non_paid_invoice 1400 1400 0 0.00 0 297 2 0 0
v_max_non_paid_realizn 3227 3227 0 0.00 0 1438 3 0 0
v_min_id_clo_ord 4813 4813 0 0.00 0 4878 9 0 0
v_min_id_clo_res 870 870 0 0.00 0 7153 19 0 0
v_min_id_ord_sup 5043 5043 0 0.00 0 6440 8 0 0
v_min_non_paid_invoice 5145 5145 0 0.00 0 3808 14 0 0
v_min_non_paid_realizn 6355 6355 0 0.00 0 7981 14 0 0
v_our_firm 9088 9088 0 0.00 0 16 1 0 0
v_reserve_write_off 17554 17554 0 0.00 0 3539 6 0 0
x_lock_depdocs_on_canc_invoice 11282 11282 0 0.00 0 18717 136 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 13351 481 0 0
x_make_qstorno_1000_1200 4813 4813 0 0.00 2 57314 1282 0 0
x_make_qstorno_1200_2000 5059 5059 0 0.00 2 107845 4054 0 0
x_make_qstorno_2100_3300 405024 405024 0 0.00 0 56114 191 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-11-2020 04:29:51 to 24-11-2020 04:29:55.

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:59 4 8192000000 8799906080 8811700224 5583344 0.068 2 2 2 0 5709632 90432 35856
01:58:01 26184 8192000000 9726637776 10023944192 795183664 8.847 100 88 176 0 823578256 7347392 21047200
01:59:31 22562 8192000000 9757990288 10061115392 830051392 9.200 100 88 176 0 863075072 12293024 20730656
02:00:56 20652 8192000000 9795662144 10111578112 861032944 9.510 100 88 176 0 902976928 22049664 19894320
02:02:21 16390 8192000000 9851268528 10181132288 898977488 9.888 100 80 160 0 963232880 46224496 18030896
02:03:45 9397 8192000000 9897669488 10236571648 937120528 10.265 100 77 155 0 1012797088 59423744 16252816
02:04:58 5149 8192000000 9950182208 10299838464 981401072 10.698 100 77 154 2 1067979248 71094288 15483888
02:06:07 4081 8192000000 9996704448 10353954816 1017365488 11.047 100 87 172 2 1109515456 75011840 17138128
02:07:13 1760 8192000000 10003800288 10367610880 1039766576 11.262 100 85 170 4 1126468320 69242240 17459504
02:08:16 1722 8192000000 10033599440 10406162432 1052555296 11.385 100 78 155 0 1149782144 82042528 15184320
02:09:19 4780 8192000000 10001361920 10385223680 1061780272 11.474 100 54 108 0 1123457760 50746704 10930784
02:10:26 4830 8192000000 9997832208 10394464256 1071107616 11.563 100 43 86 1 1116499104 36580128 8811360
02:11:34 65693 8192000000 9989152928 10385530880 1081751712 11.664 100 32 65 2 1097563712 9247552 6564448
02:13:40 6221 8192000000 10012772784 10419290112 1091868352 11.760 100 44 89 1 1125464096 23649472 9946272
02:14:48 7757 8192000000 10000258976 10407276544 1083067552 11.677 99 28 56 3 1107243984 18306272 5870160
02:15:59 8785 8192000000 10003713632 10400534528 1083550544 11.681 99 26 53 0 1113829040 24160480 6118016
02:17:08 28254 8192000000 10008631264 10392428544 1069819904 11.550 98 57 111 4 1089788624 7583536 12385184
02:18:37 3466 8192000000 10013276976 10407526400 1078748208 11.636 99 42 82 2 1124470848 37046800 8675840
02:19:42 7655 8192000000 9979107248 10374991872 1027491040 11.144 97 44 84 1 1059005504 21586112 9928352
02:20:53 10181 8192000000 9922635696 10304094208 977873568 10.663 96 34 68 2 1005038672 19657184 7507920
02:22:05 45135 8192000000 9914915696 10301947904 938984400 10.283 92 29 56 2 963599392 18474592 6140400
02:23:54 4664 8192000000 9933443728 10328281088 1020536080 11.077 99 29 58 3 1043345552 16136768 6672704
02:25:00 7834 8192000000 9952383104 10349330432 1042664048 11.290 100 50 97 2 1076214608 22021648 11528912
02:26:09 22587 8192000000 10003551568 10409525248 1052888192 11.388 100 43 85 2 1086667456 24717488 9061776
02:28:04 12942 8192000000 10015183248 10413096960 1076426176 11.613 100 52 104 0 1120421760 33504896 10490688
02:29:24 11848 8192000000 10021432720 10429018112 1085351696 11.698 100 41 80 1 1152291696 58075088 8864912
02:30:40 10807 8192000000 10089725264 10496811008 1098334928 11.822 100 63 126 1 1187443472 76010384 13098160
02:32:00 11069 8192000000 10107569856 10508001280 1114626464 11.976 100 46 92 0 1209678848 84663760 10388624
02:33:17 9450 8192000000 10108690768 10517377024 1125858800 12.082 100 51 102 0 1217891008 81027808 11004400
02:34:31 11127 8192000000 10148135648 10569216000 1135560352 12.174 100 45 89 1 1251259888 105525904 10173632
02:35:46 4471 8192000000 10179398576 10591813632 1147613024 12.287 100 51 102 1 1287673008 128695568 11364416
02:36:57 16992 8192000000 10224509120 10643169280 1167703232 12.475 100 45 86 2 1326891120 149708368 9479520
02:38:17 2096 8192000000 10274527120 10676396032 1178007408 12.572 100 90 176 0 1370497552 173997888 18492256
02:39:24 2912 8192000000 10267586448 10670448640 1165854160 12.458 100 85 169 3 1370001328 186115472 18031696
02:40:30 1994 8192000000 10334340544 10735349760 1181097184 12.600 100 85 171 0 1426345952 226274416 18974352
02:41:37 3478 8192000000 10309694880 10720067584 1148582096 12.296 99 82 165 1 1401596896 235512640 17502160
02:42:48 2853 8192000000 10315690592 10713378816 1150873296 12.318 100 89 175 2 1416750416 246622784 19254336
02:43:55 4709 8192000000 10331621712 10753552384 1142056224 12.235 100 69 135 1 1439554128 282162624 15335280
02:45:05 3773 8192000000 10370011872 10773008384 1141527408 12.230 100 70 139 1 1465173984 307839120 15807456
02:46:15 1304 8192000000 10368182592 10769444864 1149259200 12.303 100 84 168 0 1462537824 293723120 19555504
02:47:22 2598 8192000000 10372653056 10770673664 1162092656 12.423 100 78 157 0 1459229952 279284000 17853296
02:48:29 5078 8192000000 10441176544 10859364352 1165171216 12.452 100 66 132 0 1544422576 363163392 16087968
02:49:42 4161 8192000000 10447866368 10885062656 1181077168 12.600 100 55 110 1 1549390928 354301248 14012512
02:50:59 5985 8192000000 10440998560 10878255104 1178938864 12.580 100 47 92 0 1562391104 371851008 11601232
02:52:25 9976 8192000000 10524408912 10957553664 1175695568 12.550 100 46 92 0 1615165600 427501600 11968432
02:53:44 10858 8192000000 10545819856 10979524608 1172994272 12.525 100 44 88 2 1639685952 455418144 11273536
02:55:23 27298 8192000000 10555703376 11020152832 1144343792 12.256 99 34 69 1 1621773664 468238400 9191472
02:56:53 21539 8192000000 10569429328 11019636736 1134860528 12.167 100 29 58 0 1657818608 514705088 8252992
02:59:02 14963 8192000000 10117376288 10615586816 1129493216 12.117 100 16 32 1 1235332944 102082544 3757184
03:00:19 88268 8192000000 10068635744 10525675520 1124961552 12.074 99 45 89 1 1183200944 47679328 10560064
03:02:59 66874 8192000000 10051349952 10548875264 1110672368 11.939 99 16 33 0 1177388912 63256656 3459888
03:05:11 49460 8192000000 10075293760 10522963968 1121715584 12.043 99 9 18 1 1164709136 41178128 1815424
03:07:08 58323 8192000000 10094297696 10567241728 1129884048 12.120 98 21 42 1 1209972672 75314736 4773888
03:09:47 80959 8192000000 10084000000 10537676800 1119718336 12.024 98 16 31 1 1180342432 57441808 3182288
03:12:57 40770 8192000000 10061077840 10532483072 1110230032 11.935 98 14 28 1 1141597632 28475200 2892400
03:14:40 68695 8192000000 10083231584 10610761728 1125197408 12.076 99 9 19 0 1183805760 56729552 1878800
03:17:39 90506 8192000000 10099628176 10553651200 1140171024 12.217 100 19 38 0 1200236736 55502080 4563632
03:20:10 68202 8192000000 10067421088 10570588160 1128056112 12.103 99 17 34 0 1212416016 80591824 3768080
03:22:23 39540 8192000000 10165785184 10605404160 1142243728 12.237 99 37 74 3 1233861584 83713744 7904112
03:24:03 69557 8192000000 10086341808 10518130688 1104683712 11.882 96 30 60 0 1151088688 40315952 6089024
03:26:16 58501 8192000000 10096816016 10533666816 1146323904 12.275 100 23 46 2 1181142592 30404784 4413904
03:28:17 118160 8192000000 10115075088 10585288704 1139454928 12.210 99 33 66 2 1191807936 46052544 6300464
03:31:33 57726 8192000000 10091167744 10563796992 1150997056 12.319 100 9 17 0 1206058704 53120800 1940848
03:33:33 70369 8192000000 10077065920 10580168704 1129367824 12.115 98 7 14 0 1168731616 37909216 1454576
03:36:05 71847 8192000000 10103451008 10581880832 1072081632 11.572 94 14 28 0 1189473472 114217200 3174640
03:38:17 28975 8192000000 10102326416 10539700224 1132233712 12.142 99 30 56 6 1189874624 52289984 5350928
03:39:47 48739 8192000000 10079902752 10513518592 1111481504 11.946 98 27 55 0 1145808160 29131552 5195104
03:41:40 58669 8192000000 10049555648 10485219328 1069960624 11.552 95 17 34 1 1103517648 30062768 3494256
03:43:52 43397 8192000000 10099633152 10601275392 1118029984 12.008 99 25 50 1 1201267632 78251360 4986288
03:45:36 51400 8192000000 10089054464 10514083840 1093603808 11.777 97 26 52 2 1143133200 44168608 5360784
03:48:01 88102 8192000000 10038496816 10532982784 1060839552 11.465 94 11 22 2 1118917136 55785248 2292336
03:51:37 104638 8192000000 10077228720 10546872320 1102003232 11.857 98 11 22 1 1175692272 71128544 2560496
03:54:49 47041 8192000000 10093199744 10526347264 1110252832 11.935 99 20 40 0 1167646576 52915776 4477968
03:56:37 78326 8192000000 10165900704 10652975104 1129936016 12.121 99 23 46 1 1228685328 93852560 4896752
03:59:13 100662 8192000000 10108522400 10628255744 1097543952 11.814 97 17 34 0 1226150416 124272512 4333952
04:02:21 42034 8192000000 10077574432 10501808128 1125386304 12.078 100 33 66 0 1157842320 25286480 7169536
04:04:04 73770 8192000000 10148736016 10617319424 1131633008 12.137 100 20 40 0 1216649728 80953888 4062832
04:06:18 49686 8192000000 10085289904 10537869312 1102794320 11.864 98 29 58 0 1137026624 28570896 5661408
04:08:15 107140 8192000000 10098103120 10576769024 1126059856 12.084 99 24 48 0 1223039360 91987984 4991520
04:11:20 88565 8192000000 10131587888 10601291776 1114433872 11.974 98 9 18 0 1215235232 99120864 1680496
04:13:49 70683 8192000000 10100397760 10579554304 1108096224 11.914 97 20 40 1 1176867664 64288528 4482912
04:16:07 58189 8192000000 10131736144 10606649344 1146143520 12.273 100 25 50 3 1258951136 106890336 5917280
04:18:12 92209 8192000000 10087714528 10515042304 1129170928 12.114 99 17 34 0 1171201488 38461344 3569216
04:20:49 65059 8192000000 10123267792 10557767680 1137866512 12.195 99 21 42 1 1191275776 48229744 5179520
04:22:57 103898 8192000000 10077985312 10529513472 1103412592 11.870 96 8 16 0 1141221920 36377648 1431680
04:26:27 45952 8192000000 9492934288 9888362496 607363520 6.902 54 2 4 0 647040160 39363072 313568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-11-2020 04:29:57 to 24-11-2020 04:30:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-11-2020 04:30:13 to 24-11-2020 04:30:15.

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          217411
MON$OLDEST_ACTIVE               217412
MON$OLDEST_SNAPSHOT             217412
MON$NEXT_TRANSACTION            217412
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                       2101184
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             767
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2272 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.2272 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 ---
:125: 
:126: mscs-test-161.budzdorov.ru    Tue Nov 24 04:28:02 2020
:127:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:128: 
--- end of diff output ---

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

24.11.20 04:30:16. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-11-24 04:30:18 No severe PSQL-related problems occured
24.11.20 04:30:19. End of report.