Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.11.2020 10: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 16646992 kB
MemAvailable 31407248 kB
Buffers 0 kB
Cached 14980140 kB
SwapCached 2812 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-09 10:26:54
DTS_END                         2020-11-09 10:26:54.4440
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56218
TRN_ID                          226343
ATT_ID                          613
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: 8719.34 30341 1046321
customer order: creation 154.63 439 18556
customer order: refuse 13.37 366 1604
order to supplier: creation 41.78 1426 5013
order to supplier: removal 3.23 135675 387
invoice (draft): creation 42.71 3770 5125
invoice (draft): removal 51.88 1040 6225
invoice accept: apply 37.87 18250 4544
invoice accept: cancel 46.63 876 5596
customer reserve: creation 3484.73 177 418167
customer reserve: removal 4566.52 74 547983
realization accept: apply 75.85 200 9102
realization accept: cancel 85.23 135 10228
payment from customer: creation 52.85 149 6342
payment from customer: removal 5.33 102 639
payment to supplier: creation 43.86 146 5263
payment to supplier: removal 3.09 163 371
service: total inventory turnovers 3.16 4594 379
service: total monetary turnovers 5.78 738 693
service: aggregate perf. data 0.83 15171 100
service: refresh index statistics 0.03 423327 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-11-2020 10:27:45 to 09-11-2020 10:27:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2644
TEST_TIME 2 2077
TEST_TIME 3 3017
TEST_TIME 4 3641
TEST_TIME 5 3834
TEST_TIME 6 4392
TEST_TIME 7 4362
TEST_TIME 8 4533
TEST_TIME 9 4620
TEST_TIME 10 5051
TEST_TIME 11 5414
TEST_TIME 12 5590
TEST_TIME 13 5778
TEST_TIME 14 6166
TEST_TIME 15 6206
TEST_TIME 16 6794
TEST_TIME 17 7004
TEST_TIME 18 7117
TEST_TIME 19 7279
TEST_TIME 20 7791
TEST_TIME 21 7740
TEST_TIME 22 7931
TEST_TIME 23 7958
TEST_TIME 24 7918
TEST_TIME 25 8200
TEST_TIME 26 8281
TEST_TIME 27 8388
TEST_TIME 28 8908
TEST_TIME 29 9031
TEST_TIME 30 8909
TEST_TIME 31 8953
TEST_TIME 32 9954
TEST_TIME 33 10814
TEST_TIME 34 10582
TEST_TIME 35 10456
TEST_TIME 36 10451
TEST_TIME 37 10451
TEST_TIME 38 10442
TEST_TIME 39 10211
TEST_TIME 40 10034
TEST_TIME 41 10052
TEST_TIME 42 9944
TEST_TIME 43 9927
TEST_TIME 44 9794
TEST_TIME 45 9888
TEST_TIME 46 9792
TEST_TIME 47 9675
TEST_TIME 48 9515
TEST_TIME 49 9364
TEST_TIME 50 9230
TEST_TIME 51 9217
TEST_TIME 52 9297
TEST_TIME 53 9204
TEST_TIME 54 9166
TEST_TIME 55 9125
TEST_TIME 56 9094
TEST_TIME 57 9063
TEST_TIME 58 9150
TEST_TIME 59 9163
TEST_TIME 60 9091
TEST_TIME 61 9181
TEST_TIME 62 9109
TEST_TIME 63 9283
TEST_TIME 64 9289
TEST_TIME 65 9334
TEST_TIME 66 9241
TEST_TIME 67 9125
TEST_TIME 68 9052
TEST_TIME 69 8996
TEST_TIME 70 8994
TEST_TIME 71 8949
TEST_TIME 72 8929
TEST_TIME 73 8875
TEST_TIME 74 8951
TEST_TIME 75 8900
TEST_TIME 76 8924
TEST_TIME 77 8956
TEST_TIME 78 8989
TEST_TIME 79 8919
TEST_TIME 80 8960
TEST_TIME 81 9025
TEST_TIME 82 8968
TEST_TIME 83 9091
TEST_TIME 84 9050
TEST_TIME 85 8954
TEST_TIME 86 8877
TEST_TIME 87 8914
TEST_TIME 88 8863
TEST_TIME 89 8804
TEST_TIME 90 8805
TEST_TIME 91 8784
TEST_TIME 92 8748
TEST_TIME 93 8721
TEST_TIME 94 8767
TEST_TIME 95 8742
TEST_TIME 96 8734
TEST_TIME 97 8839
TEST_TIME 98 8821
TEST_TIME 99 8873
TEST_TIME 100 8851
TEST_TIME 101 8848
TEST_TIME 102 8869
TEST_TIME 103 8860
TEST_TIME 104 8876
TEST_TIME 105 8833
TEST_TIME 106 8755
TEST_TIME 107 8710
TEST_TIME 108 8679
TEST_TIME 109 8636
TEST_TIME 110 8619
TEST_TIME 111 8628
TEST_TIME 112 8619
TEST_TIME 113 8606
TEST_TIME 114 8581
TEST_TIME 115 8593
TEST_TIME 116 8569
TEST_TIME 117 8625
TEST_TIME 118 8618
TEST_TIME 119 8646
TEST_TIME 120 8726

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-11-2020 10:27:48 to 09-11-2020 10:27:52.

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 1434006 1434006 0 0.00 0 26455 7 0 0
doc_list_biud 1433989 1433989 0 0.00 0 76255 36 0 0
sp_add_invoice_to_stock 4666 4544 122 2.61 32 187513 18250 0 122
sp_cancel_adding_invoice 5596 5596 0 0.00 5 53130 876 0 0
sp_cancel_client_order 1604 1604 0 0.00 4 6690 367 0 0
sp_cancel_customer_reserve 547983 547983 0 0.00 1 30846 75 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 3 4121 102 0 0
sp_cancel_pay_to_supplier 371 371 0 0.00 3 14987 163 0 0
sp_cancel_supplier_invoice 6232 6225 7 0.11 2 76259 1041 0 7
sp_cancel_supplier_order 387 387 0 0.00 6 1381922 135675 0 0
sp_cancel_write_off 10228 10228 0 0.00 1 25310 135 0 0
sp_client_order 18556 18556 0 0.00 4 27299 439 0 0
sp_customer_reserve 418167 418167 0 0.00 0 75544 177 0 0
sp_fill_shopping_cart 28687 28687 0 0.00 1 14669 54 0 0
sp_fill_shopping_cart_clo_res 418153 418153 0 0.00 0 24499 19 0 0
sp_get_clo_for_invoice 4530 4530 0 0.00 0 40965 514 0 0
sp_kill_cost_storno 16834 16834 0 0.00 0 5997 71 0 0
sp_kill_qstorno_ret_qs2qd 1112806 1112806 0 0.00 0 76254 40 0 0
sp_kill_qty_storno 585646 585646 0 0.00 0 76255 87 0 0
sp_lock_selected_doc 18860 18860 0 0.00 0 9682 5 0 0
sp_make_cost_storno 25237 25237 0 0.00 0 25678 74 0 0
sp_multiply_rows_for_pdistr 25226 25226 0 0.00 0 21855 39 0 0
sp_multiply_rows_for_qdistr 18556 18556 0 0.00 0 25686 258 0 0
sp_pay_from_customer 6342 6342 0 0.00 4 21944 150 0 0
sp_pay_to_supplier 5263 5263 0 0.00 5 21733 147 0 0
sp_payment_common 11597 11597 0 0.00 1 21754 25 0 0
sp_qd_handle_on_cancel_clo 1604 1604 0 0.00 0 5847 336 0 0
sp_qd_handle_on_invoice_upd_sts 10123 10123 0 0.00 0 45967 427 0 0
sp_qd_handle_on_reserve_upd_sts 9096 9096 0 0.00 0 11281 54 0 0
sp_reserve_write_off 9102 9102 0 0.00 7 22069 201 0 0
sp_supplier_invoice 5125 5125 0 0.00 7 90466 3770 0 0
sp_supplier_order 5013 5013 0 0.00 6 28695 1427 0 0
srv_aggregate_perf_data 100 100 0 0.00 2866 150955 15172 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1488 163174 45083 0 0
srv_find_qd_qs_mism 1003524 1003524 0 0.00 0 26453 5 0 0
srv_make_invnt_saldo 379 379 0 0.00 47 111370 4595 0 0
srv_make_money_saldo 693 693 0 0.00 1 18080 738 0 0
srv_recalc_idx_stat 4 4 0 0.00 164261 694384 423327 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4544 4544 0 0.00 0 12090 175 0 0
t$perf-norm:sp_cancel_adding_invoice 363 363 0 0.00 0 1617 69 0 0
t$perf-norm:sp_cancel_client_order 1604 1604 0 0.00 0 2378 15 0 0
t$perf-norm:sp_cancel_customer_reserve 598 598 0 0.00 0 648 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 3422 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 371 371 0 0.00 0 1053 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 682 17 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 27436 1932 0 0
t$perf-norm:sp_cancel_write_off 884 884 0 0.00 0 1264 5 0 0
t$perf-norm:sp_client_order 18556 18556 0 0.00 0 8706 19 0 0
t$perf-norm:sp_customer_reserve 914 914 0 0.00 0 675 4 0 0
t$perf-norm:sp_pay_from_customer 6342 6342 0 0.00 0 2981 8 0 0
t$perf-norm:sp_pay_to_supplier 5263 5263 0 0.00 0 4802 8 0 0
t$perf-norm:sp_reserve_write_off 9102 9102 0 0.00 0 5884 10 0 0
t$perf-norm:sp_supplier_invoice 5125 5125 0 0.00 0 7940 20 0 0
t$perf-norm:sp_supplier_order 5013 5013 0 0.00 0 4389 17 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 264 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 8859 149 0 0
t$perf-norm:srv_make_invnt_saldo 379 379 0 0.00 0 2388 11 0 0
t$perf-norm:srv_make_money_saldo 693 693 0 0.00 0 519 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 52 15 0 0
v_add_invoice_to_stock 9298 9054 244 2.62 0 7362 18 0 244
v_all_customers 33790 33790 0 0.00 0 35 1 0 0
v_all_suppliers 27878 27878 0 0.00 0 17 1 0 0
v_all_wares 37110 37110 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 726 726 0 0.00 0 3717 20 0 0
v_cancel_client_order 3208 3208 0 0.00 0 1528 4 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 3422 13 0 0
v_cancel_customer_reserve 1196 1196 0 0.00 0 3075 10 0 0
v_cancel_payment_to_supplier 742 742 0 0.00 0 3990 18 0 0
v_cancel_supplier_invoice 798 784 14 1.75 0 3521 22 0 14
v_cancel_supplier_order 774 774 0 0.00 0 1153 6 0 0
v_cancel_write_off 1768 1768 0 0.00 0 5921 19 0 0
v_max_id_clo_ord 5013 5013 0 0.00 0 6574 3 0 0
v_max_id_clo_res 914 914 0 0.00 0 2381 15 0 0
v_max_id_ord_sup 5119 5119 0 0.00 0 3560 4 0 0
v_max_non_paid_invoice 1451 1451 0 0.00 0 264 2 0 0
v_max_non_paid_realizn 3413 3413 0 0.00 0 6198 6 0 0
v_min_id_clo_ord 5013 5013 0 0.00 0 10961 12 0 0
v_min_id_clo_res 914 914 0 0.00 0 11352 28 0 0
v_min_id_ord_sup 5119 5119 0 0.00 0 8393 11 0 0
v_min_non_paid_invoice 5258 5258 0 0.00 0 9829 13 0 0
v_min_non_paid_realizn 6338 6338 0 0.00 0 21622 15 0 0
v_our_firm 9172 9172 0 0.00 0 17 1 0 0
v_reserve_write_off 18190 18190 0 0.00 0 2668 5 0 0
x_lock_depdocs_on_canc_invoice 11429 11429 0 0.00 0 16801 112 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 11755 396 0 0
x_make_qstorno_1000_1200 5013 5013 0 0.00 2 28364 1325 0 0
x_make_qstorno_1200_2000 5125 5125 0 0.00 3 84056 3571 0 0
x_make_qstorno_2100_3300 418167 418167 0 0.00 0 75541 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-11-2020 10:27:53 to 09-11-2020 10:27:57.

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
07:56:58 5 8192000000 8798220224 8809336832 4308112 0.052 2 1 2 0 4407584 63616 35856
07:57:59 37440 8192000000 9728804960 10027053056 807176032 8.969 100 89 178 0 836677536 7858384 21643120
07:59:44 27774 8192000000 9769401392 10075299840 841289424 9.313 100 92 184 1 876668208 13943120 21435664
08:01:21 11707 8192000000 9797651376 10117152768 867520528 9.575 100 85 170 1 912447584 25474432 19452624
08:02:38 16349 8192000000 9885853648 10218815488 922081392 10.117 100 82 163 0 993116816 53897184 17138240
08:03:56 10332 8192000000 9913422640 10259394560 960091840 10.490 100 80 160 0 1027235280 50477168 16666272
08:05:09 5990 8192000000 9965905792 10320678912 995897920 10.839 100 80 158 1 1077870480 66376896 15595664
08:06:17 2654 8192000000 9998288544 10360934400 1026031728 11.130 100 90 177 3 1100234768 56277456 17925584
08:07:22 2013 8192000000 10030531360 10398437376 1054108336 11.400 100 90 180 1 1141643456 69073312 18461808
08:08:26 1057 8192000000 10046729920 10418946048 1067146224 11.525 100 90 181 0 1158252736 72619152 18487360
08:09:28 6319 8192000000 10003287136 10393817088 1072157984 11.573 100 52 105 3 1116243664 33748272 10337408
08:10:36 3731 8192000000 9985061936 10375528448 1074133840 11.592 100 58 114 4 1101766832 15389088 12243904
08:11:41 8959 8192000000 10007692448 10416615424 1082761888 11.674 100 28 56 1 1132262400 43244880 6255632
08:12:57 28216 8192000000 10041412080 10425540608 1089536992 11.738 100 81 165 0 1128792016 21553664 17701360
08:14:25 5208 8192000000 10006813728 10407432192 1095896992 11.799 100 35 70 2 1118886336 15445248 7544096
08:15:31 6844 8192000000 10004986976 10412863488 1089047264 11.734 99 20 38 1 1122558000 29227392 4283344
08:16:39 5326 8192000000 10014030304 10429157376 1096198720 11.802 100 24 48 1 1129233856 28111280 4923856
08:17:45 43460 8192000000 10005056880 10414141440 1043710848 11.300 95 23 46 0 1074650432 25954144 4985440
08:19:31 6651 8192000000 9952708224 10337787904 1016271568 11.036 97 31 62 1 1045874080 21457872 8144640
08:20:41 4968 8192000000 9938211344 10309857280 997508784 10.854 98 43 85 1 1034379344 28308528 8562032
08:21:48 8773 8192000000 9927682960 10317049856 1022473584 11.096 100 39 76 3 1050205888 19066400 8665904
08:22:57 29603 8192000000 9949231808 10334707712 1025542848 11.125 99 23 47 0 1042666208 11891104 5232256
08:24:27 10703 8192000000 9962831376 10356027392 1043822896 11.301 100 32 62 1 1071088112 19775648 7489568
08:25:41 8877 8192000000 9996387056 10394365952 1066098704 11.515 100 39 76 4 1096149072 21468544 8581824
08:26:54 88702 8192000000 10042906208 10437701632 1096315664 11.803 100 37 74 1 1138284384 34743712 7225008
08:29:26 26799 8192000000 10076400320 10476023808 1112466720 11.956 100 42 84 0 1169592656 48689024 8436912
08:31:00 16093 8192000000 10111876592 10512326656 1131588592 12.136 100 46 93 2 1224703824 83214496 9900736
08:32:22 12996 8192000000 10138246784 10548375552 1132817920 12.148 99 53 104 2 1245712800 100604528 12290352
08:33:48 16164 8192000000 10165117552 10572619776 1153720160 12.344 100 56 111 0 1268143744 102559184 11864400
08:35:07 16781 8192000000 10216794336 10619473920 1160739440 12.410 100 57 114 0 1322455536 149093440 12622656
08:36:29 1848 8192000000 10238281280 10647552000 1162586128 12.427 100 79 158 1 1345328800 164932800 17809872
08:37:34 3348 8192000000 10271212640 10685263872 1168534768 12.483 100 59 118 1 1370045680 188284944 13225968
08:38:46 2317 8192000000 10291713840 10686304256 1160764976 12.410 100 83 163 1 1391523200 213152912 17605312
08:39:53 4726 8192000000 10281849696 10684080128 1159565728 12.399 100 71 139 1 1380275312 204939840 15769744
08:41:03 2872 8192000000 10320229264 10731257856 1169953792 12.496 100 75 150 0 1419158416 232234592 16970032
08:42:12 8712 8192000000 10349445408 10757132288 1180824832 12.598 100 82 162 1 1437253904 238051616 18377456
08:43:25 1487 8192000000 10334946544 10740850688 1172955328 12.524 100 72 144 0 1431639024 242182608 16501088
08:44:32 7611 8192000000 10311994928 10718019584 1145128528 12.264 99 66 129 2 1405387904 245601072 14658304
08:45:46 3418 8192000000 10349396192 10746695680 1140545776 12.221 99 79 157 1 1443421856 284603200 18272880
08:46:54 11884 8192000000 10337367216 10764525568 1146661664 12.278 100 39 78 2 1435038192 278397312 9979216
08:48:37 4777 8192000000 10404298800 10809856000 1172421664 12.519 100 78 157 0 1500516496 309787104 18307728
08:49:47 10782 8192000000 10391743376 10819072000 1149187968 12.302 99 40 80 1 1490376752 330630560 10558224
08:51:11 11462 8192000000 10453247168 10880204800 1148655232 12.297 100 36 71 1 1550111168 391792928 9663008
08:52:25 10856 8192000000 10435329520 10861699072 1148315760 12.294 100 40 79 0 1538531664 379978032 10237872
08:53:44 6554 8192000000 10506364128 10934095872 1154283184 12.350 100 57 114 1 1604087312 435618416 14185712
08:54:53 12584 8192000000 10410917472 10832265216 1137097200 12.188 99 38 76 0 1514286272 367105872 10083200
08:56:24 15650 8192000000 10482279632 10935246848 1144946896 12.262 100 27 54 2 1568118160 416290720 6880544
08:58:07 20577 8192000000 10400017856 10860359680 1130389872 12.125 100 24 48 2 1494355920 357572528 6393520
08:59:29 40546 8192000000 10130451744 10606682112 1140401360 12.219 100 16 32 0 1219100256 75632000 3066896
09:01:26 32211 8192000000 10154817472 10578763776 1149286752 12.303 100 31 62 0 1249543552 93882768 6374032
09:03:05 80393 8192000000 10080582912 10602467328 1084811456 11.693 95 16 32 0 1199340448 111121536 3407456
09:06:13 4161 8192000000 10082038976 10499149824 1129800736 12.119 100 53 105 4 1172498144 30367904 12329504
09:07:18 64186 8192000000 10107833456 10572128256 1119190496 12.019 98 27 55 0 1211458320 86079584 6188240
09:09:32 96469 8192000000 10108881584 10604675072 1134769888 12.166 99 17 34 2 1223922064 85576896 3575280
09:12:16 111930 8192000000 10075879296 10555412480 1133940992 12.158 99 9 18 0 1171042368 35241680 1859696
09:16:14 139033 8192000000 10131367536 10634424320 1120937696 12.036 97 12 24 0 1206759104 83212144 2609264
09:19:36 23174 8192000000 10087670704 10522509312 1149462576 12.304 100 32 62 3 1180592592 24517536 6612480
09:21:06 33298 8192000000 10113405408 10566144000 1159298912 12.397 100 28 56 1 1206916864 41495168 6122784
09:22:46 62314 8192000000 10141746688 10590855168 1135766160 12.176 97 21 43 1 1206626560 65988048 4872352
09:25:24 54465 8192000000 10119258352 10571264000 1165407984 12.454 100 26 51 2 1196606288 25794912 5403392
09:27:22 163174 8192000000 10113939232 10675486720 1125571712 12.080 96 11 22 0 1201410352 73587936 2250704
09:31:08 99917 8192000000 10099077552 10592759808 1124768432 12.072 97 11 22 1 1174868272 48064464 2035376
09:34:33 68137 8192000000 10067482064 10527096832 1100836864 11.846 95 14 28 1 1140019616 36038400 3144352
09:37:28 41733 8192000000 10080095856 10516029440 1121772416 12.044 99 21 39 3 1144448624 18042912 4633296
09:39:24 48832 8192000000 10119719888 10548539392 1128063328 12.103 100 36 72 1 1212622832 76452560 8106944
09:41:23 60582 8192000000 10078594448 10533982208 1097343104 11.812 96 17 34 1 1170064352 69124736 3596512
09:43:27 89998 8192000000 10034362992 10498015232 1000640208 10.885 89 10 20 0 1023075936 20611040 1824688
09:46:57 82991 8192000000 10051179776 10479046656 1066545824 11.519 97 11 22 2 1138920928 70107040 2268064
09:49:20 77627 8192000000 10083308448 10535280640 1085844944 11.703 98 13 26 1 1159516368 70841776 2829648
09:52:19 75865 8192000000 10071283680 10535448576 1104034800 11.876 99 24 47 1 1177705312 68504912 5165600
09:54:35 26627 8192000000 10055862480 10516467712 1115412256 11.984 100 10 20 0 1167499696 50103616 1983824
09:56:30 63767 8192000000 10066128576 10550546432 1132318880 12.143 100 7 14 0 1184008592 50060080 1629632
09:58:46 60894 8192000000 10076101104 10513338368 1085093344 11.696 96 22 42 1 1140950448 50896736 4960368
10:00:59 37633 8192000000 10071264432 10492760064 1111487968 11.947 99 32 62 2 1147482272 29430928 6563376
10:02:37 55510 8192000000 10101892544 10584956928 1087646944 11.720 97 22 44 0 1182291088 89673456 4970688
10:05:19 62042 8192000000 10083013792 10526228480 1096403952 11.804 98 25 50 2 1133799744 32070176 5325616
10:07:21 24634 8192000000 10098806560 10529476608 1124528224 12.070 100 45 90 0 1186689360 53066016 9095120
10:08:52 43717 8192000000 10083930608 10511605760 1118524656 12.013 99 24 48 0 1164371120 41246704 4599760
10:10:42 133429 8192000000 10066170768 10511708160 1105575936 11.891 97 22 44 0 1141374976 31081360 4717680
10:13:58 55820 8192000000 10067034176 10525933568 1128563952 12.108 99 13 26 3 1184842496 53214608 3063936
10:15:54 54688 8192000000 10076270576 10551914496 1137394960 12.191 100 7 14 1 1174038320 35284128 1359232
10:17:49 63353 8192000000 10088025120 10574352384 1132434688 12.144 99 11 23 1 1197125888 62084000 2607200
10:19:55 53595 8192000000 10135294720 10575110144 1139961936 12.215 99 19 38 0 1207203792 63011040 4230816
10:21:50 47171 8192000000 10117986800 10549088256 1110773376 11.940 97 18 36 1 1182405360 67589888 4042096
10:23:43 65776 8192000000 10105537328 10537963520 1127948048 12.102 99 14 28 2 1193180528 62202944 3029536
10:25:49 76238 8192000000 9170757648 9459658752 310770464 3.654 27 7 14 0 365308960 53174832 1363664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-11-2020 10:27:59 to 09-11-2020 10:28:14.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-11-2020 10:28:15 to 09-11-2020 10:28:17.

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          226500
MON$OLDEST_ACTIVE               226757
MON$OLDEST_SNAPSHOT             226757
MON$NEXT_TRANSACTION            226757
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                       2118656
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             779
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2249 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2249 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2249 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.2249 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Mon Nov  9 10:27:10 2020
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Mon Nov  9 10:27:10 2020
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

09.11.20 10:28:18. 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-09 10:28:20 No severe PSQL-related problems occured
09.11.20 10:28:22. End of report.