Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.01.2021 13:27
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16428320 kB
MemAvailable 31179172 kB
Buffers 0 kB
Cached 15121768 kB
SwapCached 4868 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-01-21 13:26:56
DTS_END                         2021-01-21 13:26:56.4790
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/48562
TRN_ID                          235299
ATT_ID                          633
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: 8921.48 29012 1070578
customer order: creation 163.19 428 19583
customer order: refuse 14.11 300 1693
order to supplier: creation 45.23 1340 5428
order to supplier: removal 3.43 117841 412
invoice (draft): creation 45.05 3391 5406
invoice (draft): removal 51.38 887 6166
invoice accept: apply 40.87 16175 4904
invoice accept: cancel 46.78 888 5613
customer reserve: creation 3735.91 159 448309
customer reserve: removal 4491.82 73 539018
realization accept: apply 79.82 182 9578
realization accept: cancel 80.08 114 9609
payment from customer: creation 57.40 121 6888
payment from customer: removal 5.76 61 691
payment to supplier: creation 47.00 134 5640
payment to supplier: removal 3.23 93 388
service: total inventory turnovers 3.73 3369 447
service: total monetary turnovers 5.79 463 695
service: aggregate perf. data 0.88 15255 105
service: refresh index statistics 0.04 418956 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-01-2021 13:27:53 to 21-01-2021 13:27:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3701
TEST_TIME 2 3274
TEST_TIME 3 3339
TEST_TIME 4 4177
TEST_TIME 5 4249
TEST_TIME 6 4541
TEST_TIME 7 4508
TEST_TIME 8 4517
TEST_TIME 9 5133
TEST_TIME 10 5293
TEST_TIME 11 5611
TEST_TIME 12 5864
TEST_TIME 13 6350
TEST_TIME 14 6583
TEST_TIME 15 6895
TEST_TIME 16 7402
TEST_TIME 17 8040
TEST_TIME 18 8341
TEST_TIME 19 8590
TEST_TIME 20 8557
TEST_TIME 21 8723
TEST_TIME 22 8709
TEST_TIME 23 8620
TEST_TIME 24 9100
TEST_TIME 25 9526
TEST_TIME 26 10636
TEST_TIME 27 11178
TEST_TIME 28 10841
TEST_TIME 29 10719
TEST_TIME 30 10978
TEST_TIME 31 10833
TEST_TIME 32 10677
TEST_TIME 33 10471
TEST_TIME 34 10316
TEST_TIME 35 10361
TEST_TIME 36 10232
TEST_TIME 37 10167
TEST_TIME 38 10162
TEST_TIME 39 10220
TEST_TIME 40 10201
TEST_TIME 41 10177
TEST_TIME 42 10136
TEST_TIME 43 10264
TEST_TIME 44 10079
TEST_TIME 45 9874
TEST_TIME 46 9736
TEST_TIME 47 9698
TEST_TIME 48 9710
TEST_TIME 49 9578
TEST_TIME 50 9521
TEST_TIME 51 9455
TEST_TIME 52 9346
TEST_TIME 53 9415
TEST_TIME 54 9338
TEST_TIME 55 9471
TEST_TIME 56 9406
TEST_TIME 57 9552
TEST_TIME 58 9595
TEST_TIME 59 9620
TEST_TIME 60 9558
TEST_TIME 61 9600
TEST_TIME 62 9575
TEST_TIME 63 9511
TEST_TIME 64 9600
TEST_TIME 65 9615
TEST_TIME 66 9603
TEST_TIME 67 9467
TEST_TIME 68 9369
TEST_TIME 69 9315
TEST_TIME 70 9232
TEST_TIME 71 9148
TEST_TIME 72 9150
TEST_TIME 73 9061
TEST_TIME 74 8985
TEST_TIME 75 8975
TEST_TIME 76 9003
TEST_TIME 77 8962
TEST_TIME 78 9008
TEST_TIME 79 9103
TEST_TIME 80 9088
TEST_TIME 81 9208
TEST_TIME 82 9316
TEST_TIME 83 9365
TEST_TIME 84 9327
TEST_TIME 85 9277
TEST_TIME 86 9345
TEST_TIME 87 9340
TEST_TIME 88 9263
TEST_TIME 89 9201
TEST_TIME 90 9171
TEST_TIME 91 9120
TEST_TIME 92 9057
TEST_TIME 93 8998
TEST_TIME 94 8953
TEST_TIME 95 8962
TEST_TIME 96 8935
TEST_TIME 97 8910
TEST_TIME 98 8939
TEST_TIME 99 8995
TEST_TIME 100 8974
TEST_TIME 101 8994
TEST_TIME 102 8980
TEST_TIME 103 8988
TEST_TIME 104 9012
TEST_TIME 105 9037
TEST_TIME 106 9090
TEST_TIME 107 9032
TEST_TIME 108 8984
TEST_TIME 109 8942
TEST_TIME 110 8932
TEST_TIME 111 8940
TEST_TIME 112 8925
TEST_TIME 113 8904
TEST_TIME 114 8922
TEST_TIME 115 8895
TEST_TIME 116 8880
TEST_TIME 117 8887
TEST_TIME 118 8870
TEST_TIME 119 8903
TEST_TIME 120 8923

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-01-2021 13:27:56 to 21-01-2021 13:28:00.

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 1484040 1484040 0 0.00 0 36374 6 0 0
doc_list_biud 1484040 1484040 0 0.00 0 77334 33 0 0
sp_add_invoice_to_stock 5018 4904 114 2.27 25 213848 16175 0 114
sp_cancel_adding_invoice 5613 5613 0 0.00 6 118330 888 0 0
sp_cancel_client_order 1693 1693 0 0.00 5 8718 301 0 0
sp_cancel_customer_reserve 539018 539018 0 0.00 1 77337 74 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 1501 61 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 7388 93 0 0
sp_cancel_supplier_invoice 6176 6166 10 0.16 3 19907 888 0 10
sp_cancel_supplier_order 412 412 0 0.00 8 1145210 117842 0 0
sp_cancel_write_off 9609 9609 0 0.00 1 9826 114 0 0
sp_client_order 19583 19583 0 0.00 4 75523 429 0 0
sp_customer_reserve 448309 448309 0 0.00 0 115198 160 0 0
sp_fill_shopping_cart 30412 30412 0 0.00 1 23896 51 0 0
sp_fill_shopping_cart_clo_res 448305 448305 0 0.00 0 23957 19 0 0
sp_get_clo_for_invoice 4900 4900 0 0.00 0 56482 432 0 0
sp_kill_cost_storno 16301 16301 0 0.00 0 7110 51 0 0
sp_kill_qstorno_ret_qs2qd 1094223 1094223 0 0.00 0 77324 40 0 0
sp_kill_qty_storno 576989 576989 0 0.00 0 77333 84 0 0
sp_lock_selected_doc 20053 20053 0 0.00 0 8963 3 0 0
sp_make_cost_storno 27006 27006 0 0.00 0 36318 69 0 0
sp_multiply_rows_for_pdistr 27006 27006 0 0.00 0 8645 32 0 0
sp_multiply_rows_for_qdistr 19583 19583 0 0.00 0 75498 247 0 0
sp_pay_from_customer 6888 6888 0 0.00 4 35438 122 0 0
sp_pay_to_supplier 5640 5640 0 0.00 5 32310 135 0 0
sp_payment_common 12528 12528 0 0.00 1 35430 25 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 0 8601 276 0 0
sp_qd_handle_on_invoice_upd_sts 10513 10513 0 0.00 0 24334 377 0 0
sp_qd_handle_on_reserve_upd_sts 9578 9578 0 0.00 0 8352 46 0 0
sp_reserve_write_off 9578 9578 0 0.00 7 36806 182 0 0
sp_supplier_invoice 5406 5406 0 0.00 7 88224 3391 0 0
sp_supplier_order 5428 5428 0 0.00 6 45983 1340 0 0
srv_aggregate_perf_data 105 105 0 0.00 2998 251064 15256 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1324 153410 51114 0 0
srv_find_qd_qs_mism 1023715 1023715 0 0.00 0 11501 5 0 0
srv_make_invnt_saldo 447 447 0 0.00 60 97980 3369 0 0
srv_make_money_saldo 695 695 0 0.00 1 13164 464 0 0
srv_recalc_idx_stat 5 5 0 0.00 361865 478418 418956 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4904 4904 0 0.00 0 29405 156 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 4199 117 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 1605 9 0 0
t$perf-norm:sp_cancel_customer_reserve 623 623 0 0.00 0 2158 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 5140 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 2371 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 1332 21 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 20135 1444 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 267 3 0 0
t$perf-norm:sp_client_order 19584 19584 0 0.00 0 5693 15 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 1594 8 0 0
t$perf-norm:sp_pay_from_customer 6888 6888 0 0.00 0 3768 7 0 0
t$perf-norm:sp_pay_to_supplier 5640 5640 0 0.00 0 5796 9 0 0
t$perf-norm:sp_reserve_write_off 9578 9578 0 0.00 0 9429 16 0 0
t$perf-norm:sp_supplier_invoice 5406 5406 0 0.00 0 7385 21 0 0
t$perf-norm:sp_supplier_order 5429 5429 0 0.00 0 3974 15 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 435 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 154 4 0 0
t$perf-norm:srv_make_invnt_saldo 447 447 0 0.00 0 1636 8 0 0
t$perf-norm:srv_make_money_saldo 695 695 0 0.00 0 632 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 9 3 0 0
v_add_invoice_to_stock 10026 9798 228 2.27 0 4005 15 0 228
v_all_customers 35806 35806 0 0.00 0 19 1 0 0
v_all_suppliers 29612 29612 0 0.00 0 23 1 0 0
v_all_wares 39164 39164 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 553 7 0 0
v_cancel_client_order 3386 3386 0 0.00 0 1946 4 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 1485 5 0 0
v_cancel_customer_reserve 1246 1246 0 0.00 0 448 3 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 6042 19 0 0
v_cancel_supplier_invoice 794 774 20 2.51 0 1433 16 0 20
v_cancel_supplier_order 824 824 0 0.00 0 444 4 0 0
v_cancel_write_off 1954 1954 0 0.00 0 1223 12 0 0
v_max_id_clo_ord 5426 5426 0 0.00 0 2599 1 0 0
v_max_id_clo_res 979 979 0 0.00 0 8769 24 0 0
v_max_id_ord_sup 5404 5404 0 0.00 0 5283 6 0 0
v_max_non_paid_invoice 1664 1664 0 0.00 0 155 2 0 0
v_max_non_paid_realizn 3709 3709 0 0.00 0 2460 4 0 0
v_min_id_clo_ord 5426 5426 0 0.00 0 6521 8 0 0
v_min_id_clo_res 979 979 0 0.00 0 4376 15 0 0
v_min_id_ord_sup 5404 5404 0 0.00 0 6144 10 0 0
v_min_non_paid_invoice 5640 5640 0 0.00 0 8467 10 0 0
v_min_non_paid_realizn 6888 6888 0 0.00 0 30143 18 0 0
v_our_firm 9716 9716 0 0.00 0 13 1 0 0
v_reserve_write_off 19156 19156 0 0.00 0 3041 4 0 0
x_lock_depdocs_on_canc_invoice 11392 11392 0 0.00 0 9939 100 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 5103 344 0 0
x_make_qstorno_1000_1200 5428 5428 0 0.00 2 45964 1260 0 0
x_make_qstorno_1200_2000 5406 5406 0 0.00 4 82143 3194 0 0
x_make_qstorno_2100_3300 448309 448309 0 0.00 0 115197 139 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-01-2021 13:28:01 to 21-01-2021 13:28:05.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:57:00 7 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
10:58:01 20832 8192000000 9715894384 10009395200 798358240 8.880 100 88 175 2 826524672 7294608 20871824
10:59:28 18825 8192000000 9772249952 10077585408 843654192 9.336 100 90 180 0 875292464 11111200 20527072
11:00:57 12772 8192000000 9811696688 10128433152 879678352 9.696 100 86 172 0 918872432 19628368 19565712
11:02:13 13292 8192000000 9881715200 10211573760 923805296 10.134 100 84 168 0 988111456 46159488 18146672
11:03:28 8883 8192000000 9923446192 10269351936 964068560 10.529 100 83 166 0 1039786256 58701504 17016192
11:04:40 5945 8192000000 9955368240 10310467584 999127392 10.870 100 79 158 0 1066253776 50937472 16188912
11:05:48 3710 8192000000 9998638688 10359128064 1017973888 11.052 100 75 149 0 1120113088 87351408 14787792
11:06:54 2791 8192000000 10011152768 10374336512 1044364032 11.307 100 91 182 1 1121976336 58513920 19098384
11:07:58 16590 8192000000 10018258688 10391568384 1058836640 11.445 100 76 153 1 1143168032 68701952 15629440
11:09:18 5200 8192000000 10020714560 10410274816 1071259072 11.564 100 56 113 0 1149486640 66798688 11428880
11:10:25 9311 8192000000 9997981840 10392797184 1076796080 11.617 100 29 56 5 1113539600 30584080 6159440
11:11:36 5149 8192000000 9998689280 10386325504 1086660048 11.711 100 43 84 3 1118223648 22189504 9374096
11:12:43 56370 8192000000 10014876720 10426036224 1091879280 11.761 100 45 90 1 1125934928 24127824 9927824
11:14:42 3010 8192000000 10016142592 10405552128 1095254912 11.793 100 51 103 2 1126099552 19952080 10892560
11:15:47 9130 8192000000 10013783856 10413039616 1093471920 11.776 100 31 63 1 1118923120 19119584 6331616
11:16:56 8000 8192000000 9973791456 10368987136 1040657792 11.271 96 9 17 2 1065502768 23067600 1777376
11:18:05 39313 8192000000 9964067296 10360934400 990250688 10.784 92 18 36 0 1015436752 21207760 3978304
11:19:44 3011 8192000000 9969618608 10340356096 1037025232 11.236 99 51 104 2 1079560624 31189904 11345488
11:20:48 10152 8192000000 9930750352 10318364672 1012863552 11.003 99 26 52 0 1039561984 20465328 6233104
11:22:00 71883 8192000000 9933729040 10335899648 1000051104 10.879 97 29 57 2 1022920288 16101488 6767696
11:24:15 7242 8192000000 9973490304 10367082496 1043503104 11.298 100 41 82 1 1084764496 32243504 9017888
11:25:23 6923 8192000000 9974306080 10360451072 1054739584 11.406 100 28 52 1 1076963840 16500848 5723408
11:26:30 9416 8192000000 9990228304 10385817600 1059664032 11.453 100 30 58 2 1084125600 18115648 6345920
11:27:50 95724 8192000000 10025832544 10425868288 1087137952 11.715 100 38 76 0 1150900640 55273600 8489088
11:30:31 17730 8192000000 10067161824 10477056000 1099027536 11.828 100 39 77 2 1183806192 76474736 8303920
11:31:56 25574 8192000000 10097594560 10516193280 1114579120 11.976 100 34 68 1 1219228512 96711776 7937616
11:33:28 30891 8192000000 10177310400 10581131264 1137401024 12.191 100 43 86 1 1276021568 128486336 10134208
11:35:05 13177 8192000000 10238857440 10647093248 1153363040 12.341 100 57 115 0 1348640832 181480352 13797440
11:36:22 10245 8192000000 10257869712 10675146752 1165914512 12.459 100 66 133 1 1357695712 175757760 16023440
11:37:35 1322 8192000000 10296117312 10708934656 1170454288 12.501 100 73 141 1 1405834304 218393920 16986096
11:38:39 12694 8192000000 10314004160 10722009088 1169575232 12.493 100 72 145 0 1413526096 228059472 15891392
11:39:55 3554 8192000000 10343583376 10743128064 1174496432 12.539 100 89 173 0 1438837536 245277808 19063296
11:41:04 2095 8192000000 10376599488 10774695936 1171751648 12.513 100 90 179 3 1480221648 287760224 20709776
11:42:10 2713 8192000000 10363485664 10761613312 1140254592 12.218 99 83 165 0 1454682368 294893232 19534544
11:43:15 6000 8192000000 10331509680 10768953344 1142867424 12.242 100 54 108 0 1433757088 278207568 12682096
11:44:23 5186 8192000000 10364884928 10778849280 1157641248 12.381 100 62 122 1 1459671360 287847152 14182960
11:45:31 10330 8192000000 10329634624 10745430016 1150307600 12.312 99 51 102 0 1417243568 254733936 12202032
11:46:46 12628 8192000000 10340950160 10762072064 1115342800 11.983 98 32 64 1 1423167776 299209600 8615376
11:48:10 14476 8192000000 10403068992 10825072640 1138166000 12.198 99 44 88 0 1496021056 346901616 10953440
11:49:28 9364 8192000000 10437778688 10855485440 1140439360 12.220 99 48 96 0 1526578272 374331728 11807184
11:50:43 32660 8192000000 10404936160 10910564352 1147409840 12.285 100 30 60 1 1540422736 384671168 8341728
11:52:21 21014 8192000000 10219611680 10666860544 1092188032 11.763 99 28 56 0 1365493424 265788384 7517008
11:53:44 88139 8192000000 10120427072 10556747776 1122584272 12.051 100 39 79 2 1195288640 64233600 8470768
11:56:21 32408 8192000000 10046545648 10573955072 1100383856 11.841 99 5 10 0 1205563696 103766944 1412896
11:57:56 66118 8192000000 10036975184 10458873856 1117638400 12.005 100 36 70 3 1145783360 20248976 7895984
12:00:03 57102 8192000000 10094169824 10524049408 1113073264 11.962 99 36 72 0 1181565344 60868480 7623600
12:02:01 27361 8192000000 10073525408 10493734912 1118063088 12.009 100 51 101 1 1153614128 24213584 11337456
12:03:59 58290 8192000000 10071203536 10510385152 1106455824 11.899 98 9 18 0 1135577360 26950048 2171488
12:05:59 86767 8192000000 10092496464 10550652928 1124312800 12.068 99 13 26 0 1176347664 49428496 2606368
12:08:30 119068 8192000000 10084187888 10578235392 1116384240 11.993 98 7 14 0 1181279840 63505968 1389632
12:11:35 153410 8192000000 10097494000 10526732288 1129605008 12.118 99 30 60 0 1159769536 24198288 5966240
12:15:09 44151 8192000000 10094004528 10541342720 1132343600 12.143 99 11 22 2 1180483424 45736256 2403568
12:16:54 67585 8192000000 10109484480 10568699904 1134346112 12.162 99 18 35 1 1203707136 65261856 4099168
12:19:02 44173 8192000000 10141500240 10631151616 1151304592 12.322 100 23 44 2 1275281232 118904160 5072480
12:20:46 54305 8192000000 10188819424 10658795520 1150320464 12.313 100 23 46 1 1287112960 131357392 5435104
12:22:47 47122 8192000000 10166330848 10631438336 1157135392 12.376 100 39 77 1 1254750160 89504416 8110352
12:24:57 29104 8192000000 10115970688 10548785152 1133145520 12.151 98 39 78 1 1168365744 26963856 8256368
12:26:53 53260 8192000000 10080643568 10538774528 1093805152 11.779 97 34 66 4 1172581008 71847616 6928240
12:29:05 114549 8192000000 10087088512 10596687872 1096715792 11.806 97 15 30 3 1180061360 79980976 3364592
12:32:04 122373 8192000000 10066753376 10568232960 1122116576 12.047 100 5 10 0 1164751552 41764848 870128
12:35:17 46649 8192000000 10039875952 10487316480 1110237520 11.935 99 11 22 0 1141343920 29070208 2036192
12:37:05 66817 8192000000 10102144144 10531938304 1098872112 11.827 98 6 12 1 1151277168 50706960 1698096
12:39:22 66905 8192000000 10121540496 10548187136 1075559040 11.605 96 12 24 1 1165466832 87180080 2727712
12:41:35 82318 8192000000 10132652352 10600841216 1103581984 11.872 98 19 38 1 1220811472 112529104 4700384
12:44:02 60419 8192000000 10137567744 10609164288 1098577872 11.824 97 22 43 1 1236711616 133492928 4640816
12:46:10 61281 8192000000 10182846160 10613723136 1104442400 11.880 97 30 60 0 1235767760 124451024 6874336
12:48:11 90004 8192000000 10065824448 10574868480 1086905888 11.713 97 9 18 0 1192227856 103492320 1829648
12:50:41 23301 8192000000 10080393040 10496819200 1124947760 12.074 100 49 97 6 1169128816 34636768 9544288
12:52:11 62073 8192000000 10073165296 10497228800 1110587520 11.938 98 33 66 1 1170040400 53139808 6313072
12:54:16 88409 8192000000 10093296608 10532577280 1119955840 12.027 99 27 54 0 1190943360 65476432 5511088
12:56:50 59180 8192000000 10043549600 10487631872 1115614880 11.986 99 8 16 1 1133225216 15831024 1779312
12:58:49 55380 8192000000 10055455600 10506338304 1128046432 12.103 100 18 37 0 1171502032 39766080 3689520
13:01:34 87436 8192000000 10096792256 10540486656 1122486768 12.050 99 14 28 0 1177392448 52028176 2877504
13:04:02 60614 8192000000 10129947584 10571960320 1133283104 12.152 99 33 65 1 1219867744 79549696 7034944
13:06:06 71133 8192000000 10119137808 10552545280 1133247904 12.152 100 23 46 1 1198315712 60307872 4759936
13:08:18 31508 8192000000 10092207536 10525413376 1139011296 12.206 100 35 67 2 1188654048 42435568 7207184
13:09:55 91860 8192000000 10141445744 10585161728 1132700848 12.147 98 23 45 1 1202978768 64825280 5452640
13:12:38 124695 8192000000 10071148720 10541232128 1120615504 12.033 98 16 32 0 1163069824 38845728 3608592
13:15:52 63125 8192000000 10102787152 10612580352 1146460176 12.276 100 6 12 0 1188342080 40722736 1159168
13:18:01 52053 8192000000 10087345136 10536833024 1122100336 12.047 97 12 23 0 1160301968 36223360 1978272
13:19:57 40519 8192000000 10088947424 10549350400 1102404848 11.860 96 19 38 0 1153758016 47431136 3922032
13:21:54 59421 8192000000 10065103424 10507206656 1094194032 11.783 97 23 46 1 1141659456 42291168 5174256
13:23:55 32803 8192000000 10054765344 10488528896 1082911248 11.675 96 26 52 2 1134741888 46972576 4858064
13:25:31 47334 8192000000 10066415584 10496360448 1089949792 11.742 97 16 32 1 1134773392 41708208 3115392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-01-2021 13:28:07 to 21-01-2021 13:28:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-01-2021 13:28:22 to 21-01-2021 13:28:24.

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          234753
MON$OLDEST_ACTIVE               235661
MON$OLDEST_SNAPSHOT             235661
MON$NEXT_TRANSACTION            235661
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                       2114688
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             799
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2335 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2335 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2335 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.2335 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 ---
:48: 
:49: mscs-test-161.budzdorov.ru    Thu Jan 21 13:26:59 2021
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Thu Jan 21 13:27:06 2021
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:55: 
:56: 
:57: mscs-test-161.budzdorov.ru    Thu Jan 21 13:27:12 2021
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:59: 
--- end of diff output ---

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

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