builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-068 starttime: 1501811301.5 results: success (0) revision: 32083f24a1bb2c33050b4c972783f066432194eb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-03 18:48:21.499741) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-03 18:48:21.500293) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-03 18:48:21.500588) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-03 18:48:21.541420) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 18:48:21.541801) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-03 18:48:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 53.9M=0s 2017-08-03 18:48:21 (53.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.189280 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 18:48:21.788790) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 18:48:21.789100) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015647 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 18:48:21.840657) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 27 secs) (at 2017-08-03 18:48:21.840992) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 32083f24a1bb2c33050b4c972783f066432194eb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 32083f24a1bb2c33050b4c972783f066432194eb --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-03 18:48:21,965 truncating revision to first 12 chars 2017-08-03 18:48:21,965 Setting DEBUG logging. 2017-08-03 18:48:21,965 attempt 1/10 2017-08-03 18:48:21,965 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/32083f24a1bb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-03 18:48:23,355 attempt 1/10 2017-08-03 18:48:23,870 current task status: no status available at this point. state: PENDING 2017-08-03 18:48:23,871 sleeping for 10.00s (attempt 1/10) 2017-08-03 18:48:33,881 attempt 2/10 2017-08-03 18:48:34,409 current task status: no status available at this point. state: PENDING 2017-08-03 18:48:34,410 sleeping for 14.00s (attempt 2/10) 2017-08-03 18:48:48,424 attempt 3/10 2017-08-03 18:48:48,758 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-03 18:48:49,023 unpacking tar archive at: mozilla-central-32083f24a1bb/testing/mozharness/ program finished with exit code 0 elapsedTime=27.384712 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 27 secs) (at 2017-08-03 18:48:49.236906) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-03 18:48:49.237264) ========= script_repo_revision: 32083f24a1bb2c33050b4c972783f066432194eb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-03 18:48:49.237646) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-03 18:48:49.237925) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-03 18:48:49.245834) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-08-03 18:48:49.246269) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:48:49 INFO - MultiFileLogger online at 20170803 18:48:49 in /builds/slave/test 18:48:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:48:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:48:49 INFO - {'append_to_log': False, 18:48:49 INFO - 'base_work_dir': '/builds/slave/test', 18:48:49 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:48:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:48:49 INFO - 'branch': 'Firefox-Non-PGO', 18:48:49 INFO - 'buildbot_json_path': 'buildprops.json', 18:48:49 INFO - 'code_coverage': False, 18:48:49 INFO - 'config_files': ('talos/linux_config.py',), 18:48:49 INFO - 'default_actions': ('clobber', 18:48:49 INFO - 'read-buildbot-config', 18:48:49 INFO - 'download-and-extract', 18:48:49 INFO - 'populate-webroot', 18:48:49 INFO - 'create-virtualenv', 18:48:49 INFO - 'install', 18:48:49 INFO - 'setup-mitmproxy', 18:48:49 INFO - 'run-tests'), 18:48:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:48:49 INFO - 'disable_ccov_upload': False, 18:48:49 INFO - 'download_minidump_stackwalk': True, 18:48:49 INFO - 'download_symbols': 'ondemand', 18:48:49 INFO - 'enable_stylo': False, 18:48:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:48:49 INFO - 'tooltool.py': '/tools/tooltool.py', 18:48:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:48:49 INFO - '/tools/misc-python/virtualenv.py')}, 18:48:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:48:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:48:49 INFO - 'gecko_profile': False, 18:48:49 INFO - 'gecko_profile_interval': 0, 18:48:49 INFO - 'installer_path': 'installer.exe', 18:48:49 INFO - 'log_level': 'info', 18:48:49 INFO - 'log_name': 'talos', 18:48:49 INFO - 'log_to_console': True, 18:48:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:48:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:48:49 INFO - 'opt_config_files': (), 18:48:49 INFO - 'pip_index': False, 18:48:49 INFO - 'suite': 'dromaeojs-e10s', 18:48:49 INFO - 'system_bits': '32', 18:48:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:48:49 INFO - 'title': 'talos-linux64-ix-068', 18:48:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:48:49 INFO - 'use_talos_json': True, 18:48:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:48:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:48:49 INFO - 'work_dir': 'build'} 18:48:49 INFO - [mozharness: 2017-08-04 01:48:49.619647Z] Running clobber step. 18:48:49 INFO - Running pre-action listener: _resource_record_pre_action 18:48:49 INFO - Running main action method: clobber 18:48:49 INFO - rmtree: /builds/slave/test/build 18:48:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:48:50 INFO - Running post-action listener: _resource_record_post_action 18:48:50 INFO - [mozharness: 2017-08-04 01:48:50.603701Z] Finished clobber step (success) 18:48:50 INFO - [mozharness: 2017-08-04 01:48:50.603803Z] Running read-buildbot-config step. 18:48:50 INFO - Running pre-action listener: _resource_record_pre_action 18:48:50 INFO - Running main action method: read_buildbot_config 18:48:50 INFO - Using buildbot properties: 18:48:50 INFO - { 18:48:50 INFO - "project": "", 18:48:50 INFO - "product": "firefox", 18:48:50 INFO - "who": "kwierso@gmail.com", 18:48:50 INFO - "installer_path": "public/build/target.tar.bz2", 18:48:50 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:48:50 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:48:50 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:48:50 INFO - "stage_platform": "linux64", 18:48:50 INFO - "basedir": "/builds/slave/test", 18:48:50 INFO - "buildnumber": 1057, 18:48:50 INFO - "platform": "ubuntu64_hw", 18:48:50 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:48:50 INFO - "slavebuilddir": "test", 18:48:50 INFO - "taskId": "eZj28URAQrGDr6TbgnG6FA", 18:48:50 INFO - "branch": "mozilla-central", 18:48:50 INFO - "script_repo_revision": "production", 18:48:50 INFO - "revision": "32083f24a1bb2c33050b4c972783f066432194eb", 18:48:50 INFO - "slavename": "talos-linux64-ix-068", 18:48:50 INFO - "repo_path": "mozilla-central" 18:48:50 INFO - } 18:48:50 INFO - Finding installer, test and symbols from parent task. 18:48:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eZj28URAQrGDr6TbgnG6FA'}, attempt #1 18:48:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eZj28URAQrGDr6TbgnG6FA'}, attempt #1 18:48:51 INFO - Task dependencies: H5B5IEnwSEm76TFIhWTsdQ 18:48:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ'}, attempt #1 18:48:51 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2 18:48:51 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.test_packages.json 18:48:51 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.crashreporter-symbols.zip 18:48:51 INFO - Running post-action listener: _resource_record_post_action 18:48:51 INFO - [mozharness: 2017-08-04 01:48:51.945232Z] Finished read-buildbot-config step (success) 18:48:51 INFO - [mozharness: 2017-08-04 01:48:51.945454Z] Running download-and-extract step. 18:48:51 INFO - Running pre-action listener: _resource_record_pre_action 18:48:51 INFO - Running main action method: download_and_extract 18:48:51 INFO - mkdir: /builds/slave/test/build/tests 18:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:51 INFO - trying https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.test_packages.json 18:48:51 INFO - Downloading https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:48:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:48:52 INFO - Downloaded 989 bytes. 18:48:52 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:48:52 INFO - Using the following test package requirements: 18:48:52 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:48:52 INFO - u'common': [u'target.common.tests.zip'], 18:48:52 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:48:52 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:48:52 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:48:52 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:48:52 INFO - u'mozbase': [u'target.common.tests.zip'], 18:48:52 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:48:52 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:48:52 INFO - u'web-platform': [u'target.common.tests.zip', 18:48:52 INFO - u'target.web-platform.tests.tar.gz'], 18:48:52 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:48:52 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:48:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.common.tests.zip 18:48:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:48:52 INFO - Fetch https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.common.tests.zip into memory 18:48:53 INFO - Content-Length response header: 54278817 18:48:53 INFO - Bytes received: 54278817 18:49:04 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:49:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.common.tests.zip 18:49:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:49:04 INFO - Fetch https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.common.tests.zip into memory 18:49:05 INFO - Content-Length response header: 54278817 18:49:05 INFO - Bytes received: 54278817 18:49:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.talos.tests.zip 18:49:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:49:18 INFO - Fetch https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.talos.tests.zip into memory 18:49:19 INFO - Content-Length response header: 14496012 18:49:19 INFO - Bytes received: 14496012 18:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:19 INFO - trying https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2 18:49:19 INFO - Downloading https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:49:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:49:21 INFO - Downloaded 62304218 bytes. 18:49:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2 18:49:21 INFO - mkdir: /builds/slave/test/properties 18:49:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:49:21 INFO - Writing to file /builds/slave/test/properties/build_url 18:49:21 INFO - Contents: 18:49:21 INFO - build_url:https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2 18:49:21 INFO - Running post-action listener: _resource_record_post_action 18:49:21 INFO - Running post-action listener: set_extra_try_arguments 18:49:21 INFO - [mozharness: 2017-08-04 01:49:21.051784Z] Finished download-and-extract step (success) 18:49:21 INFO - [mozharness: 2017-08-04 01:49:21.052012Z] Running populate-webroot step. 18:49:21 INFO - Running pre-action listener: _resource_record_pre_action 18:49:21 INFO - Running main action method: populate_webroot 18:49:21 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:49:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:49:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:49:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:49:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:49:21 INFO - 'tests': ['damp', 'tps']}, 18:49:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:49:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:49:21 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:49:21 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:49:21 INFO - 'other-e10s': {'tests': ['a11yr', 18:49:21 INFO - 'ts_paint', 18:49:21 INFO - 'tpaint', 18:49:21 INFO - 'sessionrestore', 18:49:21 INFO - 'sessionrestore_many_windows', 18:49:21 INFO - 'sessionrestore_no_auto_restore', 18:49:21 INFO - 'tabpaint']}, 18:49:21 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:49:21 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:49:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:49:21 INFO - 'tsvgr_opacity', 18:49:21 INFO - 'tart', 18:49:21 INFO - 'tscrollx', 18:49:21 INFO - 'cart', 18:49:21 INFO - 'tsvg_static']}, 18:49:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:49:21 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:49:21 INFO - 'talos_options': ['--mitmproxy', 18:49:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:49:21 INFO - '--firstNonBlankPaint'], 18:49:21 INFO - 'tests': ['tp6_google', 18:49:21 INFO - 'tp6_youtube', 18:49:21 INFO - 'tp6_amazon', 18:49:21 INFO - 'tp6_facebook']}, 18:49:21 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:49:21 INFO - 'talos_options': ['--stylo', 18:49:21 INFO - '--mitmproxy', 18:49:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:49:21 INFO - '--firstNonBlankPaint'], 18:49:21 INFO - 'tests': ['tp6_google', 18:49:21 INFO - 'tp6_youtube', 18:49:21 INFO - 'tp6_amazon', 18:49:21 INFO - 'tp6_facebook']}, 18:49:21 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:49:21 INFO - 'talos_options': ['--stylo', 18:49:21 INFO - '--stylo-threads=1', 18:49:21 INFO - '--mitmproxy', 18:49:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:49:21 INFO - '--firstNonBlankPaint'], 18:49:21 INFO - 'tests': ['tp6_google', 18:49:21 INFO - 'tp6_youtube', 18:49:21 INFO - 'tp6_amazon', 18:49:21 INFO - 'tp6_facebook']}, 18:49:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:49:21 INFO - 'talos_options': ['--xperf_path', 18:49:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:49:21 INFO - 'tests': ['tp5n']}}} 18:49:21 INFO - Running post-action listener: _resource_record_post_action 18:49:21 INFO - [mozharness: 2017-08-04 01:49:21.062361Z] Finished populate-webroot step (success) 18:49:21 INFO - [mozharness: 2017-08-04 01:49:21.062451Z] Running create-virtualenv step. 18:49:21 INFO - Running pre-action listener: _resource_record_pre_action 18:49:21 INFO - Running main action method: create_virtualenv 18:49:21 INFO - Creating virtualenv /builds/slave/test/build/venv 18:49:21 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:49:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:49:21 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:49:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:49:21 INFO - Using real prefix '/usr' 18:49:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:49:21 INFO - Installing distribute.............................................................................................................................................................................................done. 18:49:23 INFO - Installing pip.................done. 18:49:23 INFO - Return code: 0 18:49:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:49:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:49:24 INFO - Reading from file tmpfile_stdout 18:49:24 INFO - Output received: 18:49:24 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:49:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bfeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:49:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:49:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:49:24 INFO - Using env: {'DISPLAY': ':0', 18:49:24 INFO - 'HOME': '/home/cltbld', 18:49:24 INFO - 'LANG': 'en_US.UTF-8', 18:49:24 INFO - 'LANGUAGE': 'en_US:en', 18:49:24 INFO - 'LOGNAME': 'cltbld', 18:49:24 INFO - 'MAIL': '/var/mail/cltbld', 18:49:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:24 INFO - 'MOZ_NO_REMOTE': '1', 18:49:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:24 INFO - 'NO_EM_RESTART': '1', 18:49:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:24 INFO - 'PWD': '/builds/slave/test', 18:49:24 INFO - 'SHELL': '/bin/bash', 18:49:24 INFO - 'SHLVL': '1', 18:49:24 INFO - 'TERM': 'linux', 18:49:24 INFO - 'TMOUT': '86400', 18:49:24 INFO - 'USER': 'cltbld', 18:49:24 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 18:49:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:24 INFO - '_': '/tools/buildbot/bin/python'} 18:49:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:49:24 INFO - Cleaning up... 18:49:24 INFO - Return code: 0 18:49:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bfeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:49:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:49:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:49:24 INFO - Using env: {'DISPLAY': ':0', 18:49:24 INFO - 'HOME': '/home/cltbld', 18:49:24 INFO - 'LANG': 'en_US.UTF-8', 18:49:24 INFO - 'LANGUAGE': 'en_US:en', 18:49:24 INFO - 'LOGNAME': 'cltbld', 18:49:24 INFO - 'MAIL': '/var/mail/cltbld', 18:49:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:24 INFO - 'MOZ_NO_REMOTE': '1', 18:49:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:24 INFO - 'NO_EM_RESTART': '1', 18:49:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:24 INFO - 'PWD': '/builds/slave/test', 18:49:24 INFO - 'SHELL': '/bin/bash', 18:49:24 INFO - 'SHLVL': '1', 18:49:24 INFO - 'TERM': 'linux', 18:49:24 INFO - 'TMOUT': '86400', 18:49:24 INFO - 'USER': 'cltbld', 18:49:24 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 18:49:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:24 INFO - '_': '/tools/buildbot/bin/python'} 18:49:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:24 INFO - Downloading/unpacking psutil>=3.1.1 18:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:49:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:49:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:49:25 INFO - Installing collected packages: psutil 18:49:25 INFO - Running setup.py install for psutil 18:49:25 INFO - building 'psutil._psutil_linux' extension 18:49:25 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:49:26 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:49:27 INFO - building 'psutil._psutil_posix' extension 18:49:27 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:49:27 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:49:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:49:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:49:27 INFO - Successfully installed psutil 18:49:27 INFO - Cleaning up... 18:49:27 INFO - Return code: 0 18:49:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bfeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:49:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:49:27 INFO - Using env: {'DISPLAY': ':0', 18:49:27 INFO - 'HOME': '/home/cltbld', 18:49:27 INFO - 'LANG': 'en_US.UTF-8', 18:49:27 INFO - 'LANGUAGE': 'en_US:en', 18:49:27 INFO - 'LOGNAME': 'cltbld', 18:49:27 INFO - 'MAIL': '/var/mail/cltbld', 18:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:27 INFO - 'MOZ_NO_REMOTE': '1', 18:49:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:27 INFO - 'NO_EM_RESTART': '1', 18:49:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:27 INFO - 'PWD': '/builds/slave/test', 18:49:27 INFO - 'SHELL': '/bin/bash', 18:49:27 INFO - 'SHLVL': '1', 18:49:27 INFO - 'TERM': 'linux', 18:49:27 INFO - 'TMOUT': '86400', 18:49:27 INFO - 'USER': 'cltbld', 18:49:27 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 18:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:27 INFO - '_': '/tools/buildbot/bin/python'} 18:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:49:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:49:28 INFO - Installing collected packages: mozsystemmonitor 18:49:28 INFO - Running setup.py install for mozsystemmonitor 18:49:28 INFO - Successfully installed mozsystemmonitor 18:49:28 INFO - Cleaning up... 18:49:28 INFO - Return code: 0 18:49:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bfeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:49:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:49:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:49:28 INFO - Using env: {'DISPLAY': ':0', 18:49:28 INFO - 'HOME': '/home/cltbld', 18:49:28 INFO - 'LANG': 'en_US.UTF-8', 18:49:28 INFO - 'LANGUAGE': 'en_US:en', 18:49:28 INFO - 'LOGNAME': 'cltbld', 18:49:28 INFO - 'MAIL': '/var/mail/cltbld', 18:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:28 INFO - 'MOZ_NO_REMOTE': '1', 18:49:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:28 INFO - 'NO_EM_RESTART': '1', 18:49:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:28 INFO - 'PWD': '/builds/slave/test', 18:49:28 INFO - 'SHELL': '/bin/bash', 18:49:28 INFO - 'SHLVL': '1', 18:49:28 INFO - 'TERM': 'linux', 18:49:28 INFO - 'TMOUT': '86400', 18:49:28 INFO - 'USER': 'cltbld', 18:49:28 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 18:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:28 INFO - '_': '/tools/buildbot/bin/python'} 18:49:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:29 INFO - Downloading/unpacking jsonschema==2.5.1 18:49:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:49:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:30 INFO - Downloading functools32-3.2.3-2.tar.gz 18:49:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:49:30 INFO - warning: no files found matching '*.txt' 18:49:30 INFO - no previously-included directories found matching 'build' 18:49:30 INFO - no previously-included directories found matching 'dist' 18:49:30 INFO - no previously-included directories found matching '.git*' 18:49:30 INFO - Installing collected packages: jsonschema, functools32 18:49:30 INFO - Running setup.py install for functools32 18:49:30 INFO - warning: no files found matching '*.txt' 18:49:30 INFO - no previously-included directories found matching 'build' 18:49:30 INFO - no previously-included directories found matching 'dist' 18:49:30 INFO - no previously-included directories found matching '.git*' 18:49:30 INFO - Successfully installed jsonschema functools32 18:49:30 INFO - Cleaning up... 18:49:30 INFO - Return code: 0 18:49:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bfeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:49:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:49:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:49:30 INFO - Using env: {'DISPLAY': ':0', 18:49:30 INFO - 'HOME': '/home/cltbld', 18:49:30 INFO - 'LANG': 'en_US.UTF-8', 18:49:30 INFO - 'LANGUAGE': 'en_US:en', 18:49:30 INFO - 'LOGNAME': 'cltbld', 18:49:30 INFO - 'MAIL': '/var/mail/cltbld', 18:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:30 INFO - 'MOZ_NO_REMOTE': '1', 18:49:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:30 INFO - 'NO_EM_RESTART': '1', 18:49:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:30 INFO - 'PWD': '/builds/slave/test', 18:49:30 INFO - 'SHELL': '/bin/bash', 18:49:30 INFO - 'SHLVL': '1', 18:49:30 INFO - 'TERM': 'linux', 18:49:30 INFO - 'TMOUT': '86400', 18:49:30 INFO - 'USER': 'cltbld', 18:49:30 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 18:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:30 INFO - '_': '/tools/buildbot/bin/python'} 18:49:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:49:30 INFO - Cleaning up... 18:49:30 INFO - Return code: 0 18:49:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bfeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:49:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:49:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:49:30 INFO - Using env: {'DISPLAY': ':0', 18:49:30 INFO - 'HOME': '/home/cltbld', 18:49:30 INFO - 'LANG': 'en_US.UTF-8', 18:49:30 INFO - 'LANGUAGE': 'en_US:en', 18:49:30 INFO - 'LOGNAME': 'cltbld', 18:49:30 INFO - 'MAIL': '/var/mail/cltbld', 18:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:30 INFO - 'MOZ_NO_REMOTE': '1', 18:49:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:30 INFO - 'NO_EM_RESTART': '1', 18:49:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:30 INFO - 'PWD': '/builds/slave/test', 18:49:30 INFO - 'SHELL': '/bin/bash', 18:49:30 INFO - 'SHLVL': '1', 18:49:30 INFO - 'TERM': 'linux', 18:49:30 INFO - 'TMOUT': '86400', 18:49:30 INFO - 'USER': 'cltbld', 18:49:30 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 18:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:30 INFO - '_': '/tools/buildbot/bin/python'} 18:49:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:31 INFO - Downloading/unpacking blobuploader==1.2.4 18:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:32 INFO - Downloading blobuploader-1.2.4.tar.gz 18:49:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:49:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:49:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:32 INFO - Downloading docopt-0.6.1.tar.gz 18:49:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:49:32 INFO - Installing collected packages: blobuploader, requests, docopt 18:49:32 INFO - Running setup.py install for blobuploader 18:49:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:49:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:49:32 INFO - Running setup.py install for requests 18:49:33 INFO - Running setup.py install for docopt 18:49:33 INFO - Successfully installed blobuploader requests docopt 18:49:33 INFO - Cleaning up... 18:49:33 INFO - Return code: 0 18:49:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:49:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bfeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:49:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:49:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:49:33 INFO - Using env: {'DISPLAY': ':0', 18:49:33 INFO - 'HOME': '/home/cltbld', 18:49:33 INFO - 'LANG': 'en_US.UTF-8', 18:49:33 INFO - 'LANGUAGE': 'en_US:en', 18:49:33 INFO - 'LOGNAME': 'cltbld', 18:49:33 INFO - 'MAIL': '/var/mail/cltbld', 18:49:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:33 INFO - 'MOZ_NO_REMOTE': '1', 18:49:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:33 INFO - 'NO_EM_RESTART': '1', 18:49:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:33 INFO - 'PWD': '/builds/slave/test', 18:49:33 INFO - 'SHELL': '/bin/bash', 18:49:33 INFO - 'SHLVL': '1', 18:49:33 INFO - 'TERM': 'linux', 18:49:33 INFO - 'TMOUT': '86400', 18:49:33 INFO - 'USER': 'cltbld', 18:49:33 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 18:49:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:33 INFO - '_': '/tools/buildbot/bin/python'} 18:49:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:49:33 INFO - Running setup.py (path:/tmp/pip-fsvWS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:49:33 INFO - Running setup.py (path:/tmp/pip-3x5fO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:49:33 INFO - Running setup.py (path:/tmp/pip-W84PJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:49:33 INFO - Running setup.py (path:/tmp/pip-fYoObl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:49:33 INFO - Running setup.py (path:/tmp/pip-lUPlAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:49:33 INFO - Running setup.py (path:/tmp/pip-K4K7ia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:49:33 INFO - Running setup.py (path:/tmp/pip-bXXpWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:49:34 INFO - Running setup.py (path:/tmp/pip-E2gUQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:49:34 INFO - Running setup.py (path:/tmp/pip-m9OfjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:49:34 INFO - Running setup.py (path:/tmp/pip-Z9uGgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:49:34 INFO - Running setup.py (path:/tmp/pip-YxBGyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:49:34 INFO - Running setup.py (path:/tmp/pip-RPl1ps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:49:34 INFO - Running setup.py (path:/tmp/pip-BEh81w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:49:34 INFO - Running setup.py (path:/tmp/pip-tFmQRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:49:34 INFO - Running setup.py (path:/tmp/pip-AOaO0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:49:34 INFO - Running setup.py (path:/tmp/pip-fh4ddu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:49:34 INFO - Running setup.py (path:/tmp/pip-WgTv57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:49:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:49:34 INFO - Running setup.py install for manifestparser 18:49:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:49:34 INFO - Running setup.py install for mozcrash 18:49:34 INFO - Running setup.py install for mozdebug 18:49:35 INFO - Running setup.py install for mozdevice 18:49:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:49:35 INFO - Running setup.py install for mozfile 18:49:35 INFO - Running setup.py install for mozhttpd 18:49:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:49:35 INFO - Running setup.py install for mozinfo 18:49:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:49:35 INFO - Running setup.py install for mozInstall 18:49:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:49:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:49:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:49:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:49:35 INFO - Running setup.py install for mozleak 18:49:35 INFO - Running setup.py install for mozlog 18:49:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:49:35 INFO - Running setup.py install for moznetwork 18:49:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:49:35 INFO - Running setup.py install for mozprocess 18:49:35 INFO - Running setup.py install for mozprofile 18:49:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:49:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:49:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:49:36 INFO - Running setup.py install for mozrunner 18:49:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:49:36 INFO - Running setup.py install for mozscreenshot 18:49:36 INFO - Running setup.py install for moztest 18:49:36 INFO - Running setup.py install for mozversion 18:49:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:49:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:49:36 INFO - Cleaning up... 18:49:36 INFO - Return code: 0 18:49:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:49:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bfeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:49:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:49:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:49:36 INFO - Using env: {'DISPLAY': ':0', 18:49:36 INFO - 'HOME': '/home/cltbld', 18:49:36 INFO - 'LANG': 'en_US.UTF-8', 18:49:36 INFO - 'LANGUAGE': 'en_US:en', 18:49:36 INFO - 'LOGNAME': 'cltbld', 18:49:36 INFO - 'MAIL': '/var/mail/cltbld', 18:49:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:36 INFO - 'MOZ_NO_REMOTE': '1', 18:49:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:36 INFO - 'NO_EM_RESTART': '1', 18:49:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:36 INFO - 'PWD': '/builds/slave/test', 18:49:36 INFO - 'SHELL': '/bin/bash', 18:49:36 INFO - 'SHLVL': '1', 18:49:36 INFO - 'TERM': 'linux', 18:49:36 INFO - 'TMOUT': '86400', 18:49:36 INFO - 'USER': 'cltbld', 18:49:36 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 18:49:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:36 INFO - '_': '/tools/buildbot/bin/python'} 18:49:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:49:36 INFO - Running setup.py (path:/tmp/pip-GeoUuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:49:36 INFO - Running setup.py (path:/tmp/pip-8xEw84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:49:36 INFO - Running setup.py (path:/tmp/pip-0MCKTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:49:36 INFO - Running setup.py (path:/tmp/pip-hL293f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:49:37 INFO - Running setup.py (path:/tmp/pip-MWKhNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:49:37 INFO - Running setup.py (path:/tmp/pip-k3x8wd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:49:37 INFO - Running setup.py (path:/tmp/pip-Ohct8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:49:37 INFO - Running setup.py (path:/tmp/pip-UluDW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:49:37 INFO - Running setup.py (path:/tmp/pip-Qur1Z2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:49:37 INFO - Running setup.py (path:/tmp/pip-jSTAmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:49:37 INFO - Running setup.py (path:/tmp/pip-lVv3fA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:49:37 INFO - Running setup.py (path:/tmp/pip-iTRdaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:49:37 INFO - Running setup.py (path:/tmp/pip-2luZSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:49:37 INFO - Running setup.py (path:/tmp/pip-GM3OxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:49:37 INFO - Running setup.py (path:/tmp/pip-q1XONH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:49:37 INFO - Running setup.py (path:/tmp/pip-_fbflA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:49:37 INFO - Running setup.py (path:/tmp/pip-L20_S5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:49:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:39 INFO - Downloading blessings-1.6.tar.gz 18:49:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:49:39 INFO - Installing collected packages: blessings 18:49:39 INFO - Running setup.py install for blessings 18:49:39 INFO - Successfully installed blessings 18:49:39 INFO - Cleaning up... 18:49:39 INFO - Return code: 0 18:49:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:49:39 INFO - Reading from file tmpfile_stdout 18:49:39 INFO - Current package versions: 18:49:39 INFO - argparse == 1.2.1 18:49:39 INFO - blessings == 1.6 18:49:39 INFO - blobuploader == 1.2.4 18:49:39 INFO - docopt == 0.6.1 18:49:39 INFO - functools32 == 3.2.3-2 18:49:39 INFO - jsonschema == 2.5.1 18:49:39 INFO - manifestparser == 1.1 18:49:39 INFO - mozInstall == 1.13 18:49:39 INFO - mozcrash == 1.0 18:49:39 INFO - mozdebug == 0.1 18:49:39 INFO - mozdevice == 0.51 18:49:39 INFO - mozfile == 1.2 18:49:39 INFO - mozhttpd == 0.7 18:49:39 INFO - mozinfo == 0.10 18:49:39 INFO - mozleak == 0.1 18:49:39 INFO - mozlog == 3.5 18:49:39 INFO - moznetwork == 0.27 18:49:39 INFO - mozprocess == 0.25 18:49:39 INFO - mozprofile == 0.28 18:49:39 INFO - mozrunner == 6.13 18:49:39 INFO - mozscreenshot == 0.1 18:49:39 INFO - mozsystemmonitor == 0.3 18:49:39 INFO - moztest == 0.8 18:49:39 INFO - mozversion == 1.4 18:49:39 INFO - psutil == 3.1.1 18:49:39 INFO - requests == 1.2.3 18:49:39 INFO - wsgiref == 0.1.2 18:49:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:49:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bfeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:49:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:49:39 INFO - Using env: {'DISPLAY': ':0', 18:49:39 INFO - 'HOME': '/home/cltbld', 18:49:39 INFO - 'LANG': 'en_US.UTF-8', 18:49:39 INFO - 'LANGUAGE': 'en_US:en', 18:49:39 INFO - 'LOGNAME': 'cltbld', 18:49:39 INFO - 'MAIL': '/var/mail/cltbld', 18:49:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:39 INFO - 'MOZ_NO_REMOTE': '1', 18:49:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:39 INFO - 'NO_EM_RESTART': '1', 18:49:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:39 INFO - 'PWD': '/builds/slave/test', 18:49:39 INFO - 'SHELL': '/bin/bash', 18:49:39 INFO - 'SHLVL': '1', 18:49:39 INFO - 'TERM': 'linux', 18:49:39 INFO - 'TMOUT': '86400', 18:49:39 INFO - 'USER': 'cltbld', 18:49:39 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 18:49:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:39 INFO - '_': '/tools/buildbot/bin/python'} 18:49:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:49:39 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:49:40 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:49:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:49:40 INFO - Installing collected packages: simplejson 18:49:40 INFO - Running setup.py install for simplejson 18:49:41 INFO - building 'simplejson._speedups' extension 18:49:41 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:49:41 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:49:41 INFO - Successfully installed simplejson 18:49:41 INFO - Cleaning up... 18:49:42 INFO - Return code: 0 18:49:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:49:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bfeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:49:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:49:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:49:42 INFO - Using env: {'DISPLAY': ':0', 18:49:42 INFO - 'HOME': '/home/cltbld', 18:49:42 INFO - 'LANG': 'en_US.UTF-8', 18:49:42 INFO - 'LANGUAGE': 'en_US:en', 18:49:42 INFO - 'LOGNAME': 'cltbld', 18:49:42 INFO - 'MAIL': '/var/mail/cltbld', 18:49:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:42 INFO - 'MOZ_NO_REMOTE': '1', 18:49:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:42 INFO - 'NO_EM_RESTART': '1', 18:49:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:42 INFO - 'PWD': '/builds/slave/test', 18:49:42 INFO - 'SHELL': '/bin/bash', 18:49:42 INFO - 'SHLVL': '1', 18:49:42 INFO - 'TERM': 'linux', 18:49:42 INFO - 'TMOUT': '86400', 18:49:42 INFO - 'USER': 'cltbld', 18:49:42 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 18:49:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:42 INFO - '_': '/tools/buildbot/bin/python'} 18:49:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:49:42 INFO - Cleaning up... 18:49:42 INFO - Return code: 0 18:49:42 INFO - Running post-action listener: _resource_record_post_action 18:49:42 INFO - Running post-action listener: _start_resource_monitoring 18:49:42 INFO - Starting resource monitoring. 18:49:42 INFO - [mozharness: 2017-08-04 01:49:42.267561Z] Finished create-virtualenv step (success) 18:49:42 INFO - [mozharness: 2017-08-04 01:49:42.268505Z] Running install step. 18:49:42 INFO - Running pre-action listener: _resource_record_pre_action 18:49:42 INFO - Running main action method: install 18:49:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:49:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:49:42 INFO - Reading from file tmpfile_stdout 18:49:42 INFO - Detecting whether we're running mozinstall >=1.0... 18:49:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:49:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:49:42 INFO - Reading from file tmpfile_stdout 18:49:42 INFO - Output received: 18:49:42 INFO - Usage: mozinstall [options] installer 18:49:42 INFO - Options: 18:49:42 INFO - -h, --help show this help message and exit 18:49:42 INFO - -d DEST, --destination=DEST 18:49:42 INFO - Directory to install application into. [default: 18:49:42 INFO - "/builds/slave/test"] 18:49:42 INFO - --app=APP Application being installed. [default: firefox] 18:49:42 INFO - mkdir: /builds/slave/test/build/application 18:49:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:49:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:50:00 INFO - Reading from file tmpfile_stdout 18:50:00 INFO - Output received: 18:50:00 INFO - /builds/slave/test/build/application/firefox/firefox 18:50:00 INFO - Running post-action listener: _resource_record_post_action 18:50:00 INFO - [mozharness: 2017-08-04 01:50:00.716366Z] Finished install step (success) 18:50:00 INFO - [mozharness: 2017-08-04 01:50:00.716607Z] Running setup-mitmproxy step. 18:50:00 INFO - Running pre-action listener: _resource_record_pre_action 18:50:00 INFO - Running main action method: setup_mitmproxy 18:50:00 INFO - Skipping: mitmproxy is not required 18:50:00 INFO - Running post-action listener: _resource_record_post_action 18:50:00 INFO - [mozharness: 2017-08-04 01:50:00.717145Z] Finished setup-mitmproxy step (success) 18:50:00 INFO - [mozharness: 2017-08-04 01:50:00.717248Z] Running run-tests step. 18:50:00 INFO - Running pre-action listener: _resource_record_pre_action 18:50:00 INFO - Running pre-action listener: _set_gcov_prefix 18:50:00 INFO - Running main action method: run_tests 18:50:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:50:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:50:00 INFO - Python 2.7.3 18:50:00 INFO - Return code: 0 18:50:00 INFO - grabbing minidump binary from tooltool 18:50:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:00 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bfeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:50:00 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:50:00 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:50:00 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:50:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:50:00 INFO - Return code: 0 18:50:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:50:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:50:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:50:00 INFO - ENV: RUST_BACKTRACE is now 1 18:50:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:50:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:50:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:50:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-068 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:50:00 INFO - Using env: {'DISPLAY': ':0', 18:50:00 INFO - 'HOME': '/home/cltbld', 18:50:00 INFO - 'LANG': 'en_US.UTF-8', 18:50:00 INFO - 'LANGUAGE': 'en_US:en', 18:50:00 INFO - 'LOGNAME': 'cltbld', 18:50:00 INFO - 'MAIL': '/var/mail/cltbld', 18:50:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:50:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:50:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:00 INFO - 'MOZ_NO_REMOTE': '1', 18:50:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:50:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:00 INFO - 'NO_EM_RESTART': '1', 18:50:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:00 INFO - 'PWD': '/builds/slave/test', 18:50:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:50:00 INFO - 'RUST_BACKTRACE': '1', 18:50:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:50:00 INFO - 'SHELL': '/bin/bash', 18:50:00 INFO - 'SHLVL': '1', 18:50:00 INFO - 'TERM': 'linux', 18:50:00 INFO - 'TMOUT': '86400', 18:50:00 INFO - 'USER': 'cltbld', 18:50:00 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723', 18:50:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:00 INFO - '_': '/tools/buildbot/bin/python'} 18:50:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:50:01 INFO - mozversion application_buildid: 20170804010714 18:50:01 INFO - mozversion application_changeset: 32083f24a1bb2c33050b4c972783f066432194eb 18:50:01 INFO - mozversion application_display_name: Nightly 18:50:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:50:01 INFO - mozversion application_name: Firefox 18:50:01 INFO - mozversion application_remotingname: firefox 18:50:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:50:01 INFO - mozversion application_vendor: Mozilla 18:50:01 INFO - mozversion application_version: 57.0a1 18:50:01 INFO - mozversion platform_buildid: 20170804010714 18:50:01 INFO - mozversion platform_changeset: 32083f24a1bb2c33050b4c972783f066432194eb 18:50:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:50:01 INFO - mozversion platform_version: 57.0a1 18:50:01 INFO - using testdate: 1501811401 18:50:01 INFO - actual date: 1501811401 18:50:01 INFO - starting webserver on 'localhost:41947' 18:50:01 INFO - SUITE-START | Running 2 tests 18:50:01 INFO - TEST-START | dromaeo_css 18:50:01 INFO - Initialising browser for dromaeo_css test... 18:50:01 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41947/getInfo.html -profile /tmp/tmpaFaY5h/profile 18:50:01 INFO - TEST-INFO | started process 3388 (/builds/slave/test/build/application/firefox/firefox http://localhost:41947/getInfo.html) 18:50:10 INFO - TEST-INFO | 3388: exit 0 18:50:10 INFO - Browser initialized. 18:50:10 INFO - Running cycle 1/1 for dromaeo_css test... 18:50:10 INFO - TEST-INFO | started process 3597 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaFaY5h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:50:11 INFO - PID 3597 | 18:50:11 INFO - PID 3597 | (/builds/slave/test/build/application/firefox/firefox:3645): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:50:11 INFO - PID 3597 | 18:50:21 INFO - PID 3597 | RSS: Main: 171360256 18:50:21 INFO - PID 3597 | 18:53:21 INFO - PID 3597 | Cycle 1(1): loaded http://localhost:41947/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41947/tests/dromaeo/cssquery-ext.html) 18:53:22 INFO - PID 3597 | RSS: Main: 173617152 18:53:22 INFO - PID 3597 | 18:56:24 INFO - PID 3597 | Cycle 1(1): loaded http://localhost:41947/tests/dromaeo/cssquery-ext.html (next: http://localhost:41947/tests/dromaeo/cssquery-jquery.html) 18:56:24 INFO - PID 3597 | RSS: Main: 171495424 18:56:24 INFO - PID 3597 | 18:59:26 INFO - PID 3597 | Cycle 1(1): loaded http://localhost:41947/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41947/tests/dromaeo/cssquery-mootools.html) 18:59:27 INFO - PID 3597 | RSS: Main: 172666880 18:59:27 INFO - PID 3597 | 19:02:29 INFO - PID 3597 | Cycle 1(1): loaded http://localhost:41947/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41947/tests/dromaeo/cssquery-prototype.html) 19:02:29 INFO - PID 3597 | RSS: Main: 171253760 19:02:29 INFO - PID 3597 | 19:05:33 INFO - PID 3597 | Cycle 1(1): loaded http://localhost:41947/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41947/tests/dromaeo/cssquery-yui.html) 19:05:33 INFO - PID 3597 | RSS: Main: 174800896 19:05:33 INFO - PID 3597 | 19:08:35 INFO - PID 3597 | Cycle 1(1): loaded http://localhost:41947/tests/dromaeo/cssquery-yui.html (next: http://localhost:41947/tests/dromaeo/cssquery-dojo.html) 19:08:35 INFO - PID 3597 | RSS: Main: 173617152 19:08:35 INFO - PID 3597 | 19:08:35 INFO - PID 3597 | __start_tp_report 19:08:35 INFO - PID 3597 | _x_x_mozilla_page_load 19:08:35 INFO - PID 3597 | _x_x_mozilla_page_load_details 19:08:35 INFO - PID 3597 | |i|pagename|runs| 19:08:35 INFO - PID 3597 | |0;dojo.html;3114.221444638839;3151.732102771264;3154.5110507871286;3157;3188.4739018062014;6073.3926607339245;6153.6923153842245;6194.783182588599;6210.503159747221;6227.657478838662;5980.162777211194;6091;6094.9085763713465;6106.145139680449;6129.601575897564;5588.3573389060375;5620.381758006626;5642.4357564243455;5658.377578466366;5690.2033715279895;5654.123610840326;5670.404607516213;5671.291088613923;5675.233843431125;5686.829395118132;5661.745221465044;5664.40523745007;5667.121596152582;5674.489295963363;5686.402927692595;5537.390887002426;5578.0517312057045;5624.746886390122;5647.491725744702;5678.432156784289;5492.945070549284;5498.1752737089355;5594.104943209108;5642.633228840113;5700.3159620845345;5732.082866741302;5888.352281249059;5899.174115623816;5970.984932561475;6012.5791194616395;6494.642794646293;6501.02484627305;6560.835979100513;6592.835179120512;6617.669116544153;6547.083408322838;6620.0400941863645;6627.966860165717;6690.56511326767;6717.361850624183;6136.325004249574;6159.476444502223;6173.9073913891325;6174.073888916609;6187.938120618826;6793.864122717617;6811.965940170367;6820.35206655367;6821.863562728718;6825.044493770876;6486.6756662167645;6494.772682956074;6495.350464953467;6503.219613646393;6508.251551071593;6535.3464653534265;6591.703373348211;6609.438197753245;6618.139641846626;6622.403983641495;6461.579997300254;6554.410103090745;6581.341865813476;6585.5719378240265;6642.800715978528;6530.444912182423;6558.311377305409;6567.8358041048405;6645.966770166215;6651.467882569383;5203.739813009334;5233.241180028951;5293.444188360167;5354.518093371616;5359.732013399314;4532.342810292489;4610.331501932201;4650.302454631832;4651.186042442559;4659.044895796354;5726.112452569859;5740.655560666373;5811;5817.854553636193;5825.504832089235;6499.317571655003;6604;6610.603363798091;6611.206655201407;6723.596584204964;5677.517411020027;5718.1708652246025;5751.396103409165;5753;5802.854928626733;5797.768089276466;5832.533397328203;5843.094320380263;5856.355800861902;5856.677882716492;6402.103705481236;6416.711247993758;6482.708278127496;6495.7401703932255;6508.544401891917;6606;6635.104260924713;6642.90035649456;6726.7981960541265;6729.427998620172;6252.531060170414;6373.681315934092;6397.232332120176;6462.870742585216;6476.384743449365;6465.353464653497;6489.318621544859;6543.869122617426;6568.638724870178;6571.835704107436;6618.63597502142;6626.867462650816;6710.698018589273;6720.126383570105;6720.764773232913;6246.843828904313;6350.206224221973;6376;6388.041793730792;6497.057926600711;6368.9044664330995;6378.840528986627;6394.7442102317245;6472.673102013981;6497.18785151856;6255.906161407492;6318.494520438446;6407.967960160169;6417.005364168562;6420.390062944013;6311.337309582611;6356.237251529846;6447.258565264867;6459.903101453576;6460.580062295936;6141.201643786458;6224.972879474836;6233.470155036873;6273.498120150286;6317.431431171217 19:08:35 INFO - PID 3597 | |1;ext.html;819.8155415031615;838.3460900497614;863.5725315968594;893.5175005497032;899.7435730816723;41063.384049239285;42227.57772422275;42487.78756106223;42505;42538.57461425385;37907.43138852919;38179.809100954524;38204.617953820525;38225.61774382256;38271;6936.132983377065;7012.228654847962;7034.507584469089;7063.6115013674225;7089;6955.7131930593005;7063.046488724033;7066.258042905498;7068.681909314093;7070.0101985721785;6964.512484126113;6991.82520436988;7024.859502809941;7036.261192574782;7056.964715176443;6978.057962175118;7005.299470053005;7036.190838053638;7036.40190583801;7042.4366050716335;6321.273053598849;6330.113784070234;6381.042843573501;6381.234251889757;6392.968035159841;33603.6639633603;33787.49318760221;33821.15447113817;33883.830580847185;33885.49171762425;3367.2928684976187;3403.4894765785084;3404.097914052778;3404.9829750851336;3406.3017081498483;27039.86480067587;27122.186334409802;27124.32189195286;27191.45617087667;27240.72759272402;4351.782410879443;4438.267685831834;4496.910061798746;4516.18708632446;4558.658100642432;181230;218172;221133;221482;221933;49289.26066109011;49976.75011624918;49991.75004124956;50038;50052.49947500551;51724;51831;52002.739986300585;55076.449235507935;55870.441295587334;16566.337346506247;16657.750133748003;16662.4168154114;16695.916520417562;16699.83300167007;13773.793393099111;13774.793378099337;13778;13778.242196679279;13791.379387927567;14680.119192848464;14727.263636818116;14783.260836958325;14811.925940370445;14916.62708432298;5875.29976019183;5875.882482350329;5951.702414879239;5999.850003749942;6030.306514750772;990.9108180263811;998.8102260570469;1005.8239808033711;1016.6695823857276;1028.7633844215873;22204.222852200095;23388.18141365044;28683.426331473253;29202.787693353715;29672.851635742114;1109.611635927419;1118.116687816632;1119.160629527854;1122.764219513895;1126.3298337489346;12499.437525311383;12668.619941401772;12687.555935542212;12714.774914389945;12813.231206127663;222286;223345;223519;224035;225406;51607.7419612907;52111.47888521142;52143.478565214624;52271;56682.43317566854;1500.482333594886;1501.5720519652066;1530.6785575029146;1531.6400645848216;1535.7005383950245;27509.724902750717;27542.862285689243;27765.61171941435;28291.71708282891;28563.571546426403;53277.46722532725;53582.196267055246;53765.46234537761;53937.99999999843;53988.19017714816;203891;204765;208523;208677;208749;36683;37755.81122094372;38977.80511097427;39173.608263917;39334.40998385084;47220.7638961803;48769.2684609724;48833;48848.2672759916;49709.75145124251;34522.30955380928;35231.59073637026;35315;35461.11347216237;35466.468002980495;34672.30655386828;34676;34952;35106.29787404186;35613;37760.244795104496;38362.42456363101;38379.424308634836;38430.80784596059;38608;33457.49813752747;33531.32937341288;33703;33817.3236535263;34137.82931085329;24882.50234995326;25171.119010834533;25277.747222528276;25464.872675636503;25800.09699660503 19:08:35 INFO - PID 3597 | |2;jquery.html;565.2651552981124;575.3153747041018;575.3872126185612;578.2656026845904;597.8834127345167;22290.442738931535;22481.3255602332;23255.186068487605;23369.18207862725;23380.532389352225;25800.35499112523;26354.341141471472;26468.867655661743;26475.867620661917;26506.602400964002;6655.334466553354;6791.660416979156;6820.386165245127;6821.590704557718;6822.590644561317;6046.7883624073165;6626.403623673868;6629.73481060757;6637.568558043736;6662.866742665144;6613.768518101868;6618.834529136786;6625.304343043983;6628.43658289046;6629.93370066298;6572.769953051644;6588.604683718968;6592.373724496165;6601.174853143357;6601.70292336846;6142.84642883927;6150.138980542728;6153.261608606996;6172.259328880518;6182.938170618281;28449.28876778097;28739.56890646642;29255.70744292551;29946.251343716365;30247.697523024708;1961.3821646181407;1980.3563841751488;1994.7307113539632;1996.8502362322738;1999.050451035761;19587.608247835105;20073.498162545908;20141.5971680567;20259.493512662153;20283.492912677153;2424.6422003678;2456.4104614892476;2516.9244922652165;2529;2536.036306203631;126946.36526817307;128026.35986820099;135829;138919;139013;14738.55784326472;14763.33564989578;15180.924095379452;15350.232488375537;15407.306671199824;23686.289411317688;23693.644595331083;23709.407264818172;23716.407089822547;23824.285271441884;10329.380237185793;10575.312604680672;10608.469576521144;10640.946795265974;10863.619773307897;8440.535770532557;9872.753181170528;9926.950365248273;9954.153896918842;10049.648262310784;8122;11063.280886742335;11086.667399978014;11400.657980260605;11514.827277590843;3570.535830342091;3636.618155093677;3650.4668039423505;4184.372344148402;4357.564243575681;971.9562619682131;984.3945973226414;986.6349450703286;995.9452230127413;998.5905778630872;19930;20235.190592376435;20400.693989590167;20440.897795510926;20736.170553178006;1061.135174832509;1065.472591067411;1065.5471424644495;1068.1188019883627;1086.5327908999047;11100.056495197998;11284.266522676;11349.659510214708;11386.658400248005;11421.086313094933;470812;471251;533782;537632;538640;121637;123577;125488.37255813663;126401.36799316129;127117;1294.6504443800309;1301.9088663793443;1337.4249072898554;1343.173948021979;1343.9731205376033;21123.26068587592;21291.89354053282;21384.572308554052;21523.892380537996;21719.348419547438;55611.44388556063;55893;57414.42585574258;58219.12671310019;58819.70590147022;71073;71507.64246178736;71520.64239678768;71945.6402717983;71996;12461.314627695201;13074.673133171747;14529.564113076625;14681.119132852064;15006.474773382879;22928.656070159293;23210.071597135924;23286.417839554146;23318.3004509865;23487.882560587088;17302.567435814206;17677.911610441864;17793.822061779218;17818.821811781716;18649.347272845385;17001.319947201973;17081.74377384365;17632.73550896763;17646.82353176452;17811.287548497916;17337.73993390075;18408.723869142243;18455.354062607745;18658.346957856953;18677.90661046686;14752;14778.556643300719;14942.178180200193;14954.925225373803;14971;18464.907675461538;18543.443696689123;18625.720614190526;18628.254869805056;18788.624227515098 19:08:35 INFO - PID 3597 | |3;mootools.html;1298.623399214228;1370.390176371515;1371.3417559571405;1380.4823191303262;1382.3710211853609;13645.590632281022;14829.110253384792;14834.332455039548;14846.628834279134;14849.703005939875;13834.861651383482;14110;14112.15327080376;14116.153030818157;14130.505432309872;2577.819552631307;2695.4878573071055;2732.1257197696746;2741.150243424535;2751.8486483243414;2752.0642981386322;2776.5696317070783;2779.1662501249643;2781.721827817212;2782.1514438096374;2653.3631928337254;2668.933276668089;2695.043259642828;2701.0816322450364;2704.5672692369126;2633.354828067126;2668.0395057779183;2681.3698780786694;2682.6512553368134;2683.919482415521;2583.6899572051284;2610.2038878142157;2628.290361602376;2628.776553992913;2639.129087401153;13737;13941.372638231303;14071.155730656092;14078.436862525487;14081.08472949255;7644;7867.370610351159;7868.370530357558;7877.606119693993;7943.205679432069;4720.740359280238;5104.51507106828;5153.278541004225;5225.399079105876;5246.422893481714;1843.5759775251768;1853.073463268366;1853.2401715296799;1875.263693445517;1907.8092190780949;22569;22782.772172278397;22960.65559016616;22961;22981.08075676971;6494.09082728409;6497.317781632955;6544.8363790905605;6685.298043705438;6703.932960670429;15383.538493845203;15444.150571718663;15450.690986180214;15461.458848940232;15498.457553985556;7214.96392518034;7473.364763995121;7519.774406767697;7520.586367749717;7528.473006889465;5911.704414779244;6127.724252408652;7027.754028608974;7213.206547279795;7243.239459856744;4975.577075948512;5125.359330083739;5151.562117220078;5189.221616757516;5279.023380674587;1401.2923473645744;1403.1090257686442;1404.5084220522735;1407.8380986186514;1420.6874487612708;826.1573195340839;829.0962850492871;853.2363534636465;857.2113655437013;878.578282424427;7233.529820561646;7395.149557800814;7401;7412.666430010662;7422.665980030912;974.0843607009389;994.1400688404478;999.3803841618098;1016.7102375823002;1022.039283073923;4471.150481408522;4513;4543.68194226401;4575.199340115467;4782.832600859023;18891.81108188928;19576.51058723515;19604;19674.40976770699;19733.210671572975;18377.90811045963;19098.904505477385;19546.70679939802;19596.21615135407;19615;10739.624113156002;10910.454477276104;10982.450877455936;10997.450127493594;11000.284981476178;7559.886601700869;7620.8475830481975;7709.267619576131;8042.115367309474;8048.798780030546;16343;16425.178741063137;16426.83573164253;16525.33898644041;17040.659186815945;15308.61728456753;16347;17132;17176.31294748246;17249.482515524553;10677.412742299248;10957.671269861918;11016.339019658846;11575.479103440197;11577.594784182513;12878.291693956922;12991.675208119872;13023.674408139872;13031.674208144872;13104.868951310746;9678.516074196403;9753.707388778348;9771.31600787938;10887.183461240596;11011.559537618405;10362.378257304586;10386.792264154523;10427.895721042692;10484;10913.07238884672;9080.455172689659;9098.40860344102;9100.590473428843;9274.907250927405;9317.11487408695;6643.634600097041;6673.432758215606;6720.495962802712;6755.560888542229;6770.763023294161;6586.37429444202;6672.297719423641;6695.263521012782;6782.593044417351;6855.760048398282 19:08:35 INFO - PID 3597 | |4;prototype.html;485.2508064139125;497.22433004512953;511.6214001638787;518.5929045699127;531.1793279383352;6081.483073938715;6097.786577469789;6139.693015349226;6229.501639868811;6236.126942228092;6059.545534084939;6068.57519973602;6068.939310606893;6080.756769729205;6083.574149809515;4981.5516603505675;5015.67398119122;5033.6728112672745;5053.444121146671;5054.292399064134;4669.719816811003;4963.35476388069;4985.750712464363;5037.370328708911;5038.269450929613;4816.63875209359;4831.637627177958;4865.27020946859;4880.975595122037;4899.436564795041;4817.614590832725;4836.226203807391;4843.699242859649;4861.927071093936;4899.216125419951;4369.4319738434115;4622.745748983804;4700.976495117537;4712.811487540494;4754.952450475485;5890.440408161217;5901.586888917777;5946.543096941234;5999.280086389617;6021.337652858182;5863.325717542494;5909.8227053188475;5944.702764861783;5959.314678811948;5970.850728731816;6596.373344532261;6607.174103237096;6644.202695676549;6649.534532582721;6650.866982660368;5238.004779091961;5244.711540865251;5255.528452033437;5285.233641122016;5321.201819727034;6540.869182616321;6602.240742314647;6671.366220209073;6772.492063095189;6794.660266986631;5321.3348331458565;5330.92003619946;5374.27447294618;5395.757190926418;5399.163129714901;5386.596005299618;5410.78356865721;5433.103537916279;5466.72666366688;5520.420355862657;5865.794697185578;5888.793892213752;5903.76384944606;5915.674637894871;5953.017752070947;6497.447716944018;6503.479721622259;6542.836429089216;6585.275619681831;6595.670216489157;1898.3071179019557;1924.7978962209047;1927.884326940388;1931.3916116423281;1947.2211115553891;1373.6085215713474;1402.754517959353;1434.8162765718323;1444.6925532393202;1456.7669172932492;5662.37713851476;5706.343770466367;5743.195952566644;5747.741351639186;5756.884862302814;6427.646479443675;6477.319881412478;6546.9017964730565;6599.439047680905;6623.801285961429;5683.9821863238785;5685.573581981368;5755.251817263729;5764.596478246566;5786.81370319092;3483.3729928613093;3504.5794504659602;3531.4879342495196;3532.3641744485726;3559.4727743302547;6448.613083215022;6462.806115816533;6475.6438395887735;6498.967505162444;6502.967485162639;6755.7973260802255;6766.695498702472;6772.187337519529;6834.257264397546;6868.828279293058;6418.807435776934;6458.515611329169;6544.280129185786;6544.9672751635935;6548.541602087808;6601.702923368364;6622.635755033327;6655.301193374626;6673.164879658133;6701.32986701326;6523.934760652332;6556.442702370352;6565.671716414256;6569.57297775643;6648.800535983928;6433.464407592543;6438.67806609677;6447.2263328400895;6458.451031662361;6492.480601551959;5379.112446446293;5392;5414;5414.729263536886;5415.241866138823;6303.36966303366;6369.490440764635;6480.254770701241;6709.530332876652;6720.361565651256;6266.216722909637;6405.51958603116;6405.740182778401;6440.259370172303;6440.5491615588335;6296.685165741603;6417.518686098655;6442.871142577029;6464.709088090953;6465.478463399184;6263.185785847811;6287.465565426807;6311.6232815445355;6351.316236582678;6378.840528986627;6162.568620196723;6327.588706734047;6383.265924418565;6431.614103153825;6437;6203.7828675996125;6241.96879015602;6274.309825919054;6308.810735677937;6313.40022697843 19:08:35 INFO - PID 3597 | |5;yui.html;876.0976194519643;918.6968300460849;920.6409500294885;925.9953700231498;938.6667732954801;17946.641067178665;19043.809561904392;19088;19093.713594296096;20318.288859889908;21084;21708.13167473303;21749.238776642782;21768.564628707416;21813.454663633376;1017.6285655735641;1049.2917280835406;1049.7690508088208;1050.2595670052594;1053.0943388685723;1026.0509029148045;1059.332620449116;1060.9045185933264;1062.134360496197;1070.6038765656717;1109.9788194860707;1113;1116.1628778416189;1117.5976648406609;1117.6423544465774;1103.7461383881712;1104.1608377632976;1115.7378016166194;1117.6088369070842;1118.4463690473226;808.2765924497542;808.8584497712877;816.930560902324;817.7628487738549;818.6275244763618;17823.821761782347;17955.281788728436;19131.330403435953;19471;19780.50548736279;20822.895885520476;20954.266600669056;21580.784192158033;21587.784122158733;21607.67588486174;15991.680166396724;16123;16233.188340583041;16342.264598093114;16384.590385240463;3601.1537288737127;3609.169890925076;3615.7468977171607;3619.565652121736;3635.1457407509215;120397.39801301026;122783.38608306991;124325;124970;125784.37107814402;16359;16909.492715218563;16953.576160596083;17063.573410664834;17083.572910677085;31159.68840311613;31206.687933120833;31211.531827022613;31230.53154202689;31354.843225783727;7763.573003484749;7929.286364227247;7938.960305198437;7960.721374751856;7961;4277.144571085795;4355.216061109031;4359.651227901761;4372.322290045048;4383.9342409863875;5267.341582302212;6031.939680603138;6238.968805155945;6290.528210384239;6295.244570651551;1184.0486357385896;1203.6870413692327;1211.666791632308;1227.4967263422134;1227.975440491203;589.5755056359415;647.4367300448546;677.2685499660454;690.2338404371144;694.1184695436868;11146.777064458827;11694.70763230909;12323.366246816295;12516.81224781629;12524.686882828002;668.2815972829228;675.3111825937516;676.9356911093438;677.1366507702719;688.0951548713426;5138.254953031864;5151.330327057459;5285.9735701322015;5320.201969704498;5417.295751552273;105246;106020.46989765002;106341;108236.45881770541;110631.44684276528;93349.5332523333;93496.53251733833;95028.52485737666;98472;100559.49720251352;81974.59012704898;84029.57985210157;84162.5791871049;85469.57265213634;86115.70826437608;9890.137315876269;10044.347117437343;10051.99480051989;10174.949125254327;10186.643467478603;36156.81921590375;36935.63064369322;37204.62795372012;37447.753134811996;39327.60672393354;48085.75957120192;67110.66444667746;67716.322836771;71330;72734.63632681803;19165.61668766589;19219.711704324727;19350.12924418433;19457.61084778325;19851.106700198237;22070.779292207513;22136.114555418248;22230.333090007327;22633.434164146027;22844.885775571016;18214.089295535436;19050.7142392867;19337.323193688153;19528.21887124556;19980.800191997892;17272.827271727623;18905.054747262308;18906.52733681669;19064.809351906857;19100.617987639893;18512.44462666122;19727.802721972595;19932.90033549823;20027;20259.79740202638;13738.175709457464;13795.517156899461;14178.14931104137;14259;14542.4910128145;13143.14569552976;13557.932210338886;13598.048136630341;13695.726085478036;14675.926620367258 19:08:35 INFO - PID 3597 | __end_tp_report 19:08:35 INFO - PID 3597 | __start_cc_report 19:08:35 INFO - PID 3597 | _x_x_mozilla_cycle_collect,380 19:08:35 INFO - PID 3597 | __end_cc_report 19:08:35 INFO - PID 3597 | __startTimestamp1501812515956__endTimestamp 19:08:35 INFO - PID 3597 | 19:08:35 INFO - PID 3597 | ------- Summary: start ------- 19:08:35 INFO - PID 3597 | Number of tests: 6 19:08:35 INFO - PID 3597 | 19:08:35 INFO - PID 3597 | [#0] dojo.html Cycles:165 Average:6070.45 Median:6311.34 stddev:710.70 (11.3%) stddev-sans-first:673.98 19:08:35 INFO - PID 3597 | Values: 3114.2 3151.7 3154.5 3157.0 3188.5 6073.4 6153.7 6194.8 6210.5 6227.7 5980.2 6091.0 6094.9 6106.1 6129.6 5588.4 5620.4 5642.4 5658.4 5690.2 5654.1 5670.4 5671.3 5675.2 5686.8 5661.7 5664.4 5667.1 5674.5 5686.4 5537.4 5578.1 5624.7 5647.5 5678.4 5492.9 5498.2 5594.1 5642.6 5700.3 5732.1 5888.4 5899.2 5971.0 6012.6 6494.6 6501.0 6560.8 6592.8 6617.7 6547.1 6620.0 6628.0 6690.6 6717.4 6136.3 6159.5 6173.9 6174.1 6187.9 6793.9 6812.0 6820.4 6821.9 6825.0 6486.7 6494.8 6495.4 6503.2 6508.3 6535.3 6591.7 6609.4 6618.1 6622.4 6461.6 6554.4 6581.3 6585.6 6642.8 6530.4 6558.3 6567.8 6646.0 6651.5 5203.7 5233.2 5293.4 5354.5 5359.7 4532.3 4610.3 4650.3 4651.2 4659.0 5726.1 5740.7 5811.0 5817.9 5825.5 6499.3 6604.0 6610.6 6611.2 6723.6 5677.5 5718.2 5751.4 5753.0 5802.9 5797.8 5832.5 5843.1 5856.4 5856.7 6402.1 6416.7 6482.7 6495.7 6508.5 6606.0 6635.1 6642.9 6726.8 6729.4 6252.5 6373.7 6397.2 6462.9 6476.4 6465.4 6489.3 6543.9 6568.6 6571.8 6618.6 6626.9 6710.7 6720.1 6720.8 6246.8 6350.2 6376.0 6388.0 6497.1 6368.9 6378.8 6394.7 6472.7 6497.2 6255.9 6318.5 6408.0 6417.0 6420.4 6311.3 6356.2 6447.3 6459.9 6460.6 6141.2 6225.0 6233.5 6273.5 6317.4 19:08:35 INFO - PID 3597 | 19:08:35 INFO - PID 3597 | [#1] ext.html Cycles:180 Average:39289.23 Median:35463.79 stddev:55727.99 (157.1%) stddev-sans-first:55809.46 19:08:35 INFO - PID 3597 | Values: 819.8 838.3 863.6 893.5 899.7 41063.4 42227.6 42487.8 42505.0 42538.6 37907.4 38179.8 38204.6 38225.6 38271.0 6936.1 7012.2 7034.5 7063.6 7089.0 6955.7 7063.0 7066.3 7068.7 7070.0 6964.5 6991.8 7024.9 7036.3 7057.0 6978.1 7005.3 7036.2 7036.4 7042.4 6321.3 6330.1 6381.0 6381.2 6393.0 33603.7 33787.5 33821.2 33883.8 33885.5 3367.3 3403.5 3404.1 3405.0 3406.3 27039.9 27122.2 27124.3 27191.5 27240.7 4351.8 4438.3 4496.9 4516.2 4558.7 181230.0 218172.0 221133.0 221482.0 221933.0 49289.3 49976.8 49991.8 50038.0 50052.5 51724.0 51831.0 52002.7 55076.4 55870.4 16566.3 16657.8 16662.4 16695.9 16699.8 13773.8 13774.8 13778.0 13778.2 13791.4 14680.1 14727.3 14783.3 14811.9 14916.6 5875.3 5875.9 5951.7 5999.9 6030.3 990.9 998.8 1005.8 1016.7 1028.8 22204.2 23388.2 28683.4 29202.8 29672.9 1109.6 1118.1 1119.2 1122.8 1126.3 12499.4 12668.6 12687.6 12714.8 12813.2 222286.0 223345.0 223519.0 224035.0 225406.0 51607.7 52111.5 52143.5 52271.0 56682.4 1500.5 1501.6 1530.7 1531.6 1535.7 27509.7 27542.9 27765.6 28291.7 28563.6 53277.5 53582.2 53765.5 53938.0 53988.2 203891.0 204765.0 208523.0 208677.0 208749.0 36683.0 37755.8 38977.8 39173.6 39334.4 47220.8 48769.3 48833.0 48848.3 49709.8 34522.3 35231.6 35315.0 35461.1 35466.5 34672.3 34676.0 34952.0 35106.3 35613.0 37760.2 38362.4 38379.4 38430.8 38608.0 33457.5 33531.3 33703.0 33817.3 34137.8 24882.5 25171.1 25277.7 25464.9 25800.1 19:08:35 INFO - PID 3597 | 19:08:35 INFO - PID 3597 | [#2] jquery.html Cycles:180 Average:35792.54 Median:22385.88 stddev:85947.91 (383.9%) stddev-sans-first:86148.32 19:08:35 INFO - PID 3597 | Values: 565.3 575.3 575.4 578.3 597.9 22290.4 22481.3 23255.2 23369.2 23380.5 25800.4 26354.3 26468.9 26475.9 26506.6 6655.3 6791.7 6820.4 6821.6 6822.6 6046.8 6626.4 6629.7 6637.6 6662.9 6613.8 6618.8 6625.3 6628.4 6629.9 6572.8 6588.6 6592.4 6601.2 6601.7 6142.8 6150.1 6153.3 6172.3 6182.9 28449.3 28739.6 29255.7 29946.3 30247.7 1961.4 1980.4 1994.7 1996.9 1999.1 19587.6 20073.5 20141.6 20259.5 20283.5 2424.6 2456.4 2516.9 2529.0 2536.0 126946.4 128026.4 135829.0 138919.0 139013.0 14738.6 14763.3 15180.9 15350.2 15407.3 23686.3 23693.6 23709.4 23716.4 23824.3 10329.4 10575.3 10608.5 10640.9 10863.6 8440.5 9872.8 9927.0 9954.2 10049.6 8122.0 11063.3 11086.7 11400.7 11514.8 3570.5 3636.6 3650.5 4184.4 4357.6 972.0 984.4 986.6 995.9 998.6 19930.0 20235.2 20400.7 20440.9 20736.2 1061.1 1065.5 1065.5 1068.1 1086.5 11100.1 11284.3 11349.7 11386.7 11421.1 470812.0 471251.0 533782.0 537632.0 538640.0 121637.0 123577.0 125488.4 126401.4 127117.0 1294.7 1301.9 1337.4 1343.2 1344.0 21123.3 21291.9 21384.6 21523.9 21719.3 55611.4 55893.0 57414.4 58219.1 58819.7 71073.0 71507.6 71520.6 71945.6 71996.0 12461.3 13074.7 14529.6 14681.1 15006.5 22928.7 23210.1 23286.4 23318.3 23487.9 17302.6 17677.9 17793.8 17818.8 18649.3 17001.3 17081.7 17632.7 17646.8 17811.3 17337.7 18408.7 18455.4 18658.3 18677.9 14752.0 14778.6 14942.2 14954.9 14971.0 18464.9 18543.4 18625.7 18628.3 18788.6 19:08:35 INFO - PID 3597 | 19:08:35 INFO - PID 3597 | [#3] mootools.html Cycles:180 Average:8605.75 Median:2646.25 stddev:5900.18 (223.0%) stddev-sans-first:5891.19 19:08:35 INFO - PID 3597 | Values: 1298.6 1370.4 1371.3 1380.5 1382.4 13645.6 14829.1 14834.3 14846.6 14849.7 13834.9 14110.0 14112.2 14116.2 14130.5 2577.8 2695.5 2732.1 2741.2 2751.8 2752.1 2776.6 2779.2 2781.7 2782.2 2653.4 2668.9 2695.0 2701.1 2704.6 2633.4 2668.0 2681.4 2682.7 2683.9 2583.7 2610.2 2628.3 2628.8 2639.1 13737.0 13941.4 14071.2 14078.4 14081.1 7644.0 7867.4 7868.4 7877.6 7943.2 4720.7 5104.5 5153.3 5225.4 5246.4 1843.6 1853.1 1853.2 1875.3 1907.8 22569.0 22782.8 22960.7 22961.0 22981.1 6494.1 6497.3 6544.8 6685.3 6703.9 15383.5 15444.2 15450.7 15461.5 15498.5 7215.0 7473.4 7519.8 7520.6 7528.5 5911.7 6127.7 7027.8 7213.2 7243.2 4975.6 5125.4 5151.6 5189.2 5279.0 1401.3 1403.1 1404.5 1407.8 1420.7 826.2 829.1 853.2 857.2 878.6 7233.5 7395.1 7401.0 7412.7 7422.7 974.1 994.1 999.4 1016.7 1022.0 4471.2 4513.0 4543.7 4575.2 4782.8 18891.8 19576.5 19604.0 19674.4 19733.2 18377.9 19098.9 19546.7 19596.2 19615.0 10739.6 10910.5 10982.5 10997.5 11000.3 7559.9 7620.8 7709.3 8042.1 8048.8 16343.0 16425.2 16426.8 16525.3 17040.7 15308.6 16347.0 17132.0 17176.3 17249.5 10677.4 10957.7 11016.3 11575.5 11577.6 12878.3 12991.7 13023.7 13031.7 13104.9 9678.5 9753.7 9771.3 10887.2 11011.6 10362.4 10386.8 10427.9 10484.0 10913.1 9080.5 9098.4 9100.6 9274.9 9317.1 6643.6 6673.4 6720.5 6755.6 6770.8 6586.4 6672.3 6695.3 6782.6 6855.8 19:08:35 INFO - PID 3597 | 19:08:35 INFO - PID 3597 | [#4] prototype.html Cycles:180 Average:5520.79 Median:6064.06 stddev:1480.59 (24.4%) stddev-sans-first:1435.69 19:08:35 INFO - PID 3597 | Values: 485.3 497.2 511.6 518.6 531.2 6081.5 6097.8 6139.7 6229.5 6236.1 6059.5 6068.6 6068.9 6080.8 6083.6 4981.6 5015.7 5033.7 5053.4 5054.3 4669.7 4963.4 4985.8 5037.4 5038.3 4816.6 4831.6 4865.3 4881.0 4899.4 4817.6 4836.2 4843.7 4861.9 4899.2 4369.4 4622.7 4701.0 4712.8 4755.0 5890.4 5901.6 5946.5 5999.3 6021.3 5863.3 5909.8 5944.7 5959.3 5970.9 6596.4 6607.2 6644.2 6649.5 6650.9 5238.0 5244.7 5255.5 5285.2 5321.2 6540.9 6602.2 6671.4 6772.5 6794.7 5321.3 5330.9 5374.3 5395.8 5399.2 5386.6 5410.8 5433.1 5466.7 5520.4 5865.8 5888.8 5903.8 5915.7 5953.0 6497.4 6503.5 6542.8 6585.3 6595.7 1898.3 1924.8 1927.9 1931.4 1947.2 1373.6 1402.8 1434.8 1444.7 1456.8 5662.4 5706.3 5743.2 5747.7 5756.9 6427.6 6477.3 6546.9 6599.4 6623.8 5684.0 5685.6 5755.3 5764.6 5786.8 3483.4 3504.6 3531.5 3532.4 3559.5 6448.6 6462.8 6475.6 6499.0 6503.0 6755.8 6766.7 6772.2 6834.3 6868.8 6418.8 6458.5 6544.3 6545.0 6548.5 6601.7 6622.6 6655.3 6673.2 6701.3 6523.9 6556.4 6565.7 6569.6 6648.8 6433.5 6438.7 6447.2 6458.5 6492.5 5379.1 5392.0 5414.0 5414.7 5415.2 6303.4 6369.5 6480.3 6709.5 6720.4 6266.2 6405.5 6405.7 6440.3 6440.5 6296.7 6417.5 6442.9 6464.7 6465.5 6263.2 6287.5 6311.6 6351.3 6378.8 6162.6 6327.6 6383.3 6431.6 6437.0 6203.8 6242.0 6274.3 6308.8 6313.4 19:08:35 INFO - PID 3597 | 19:08:35 INFO - PID 3597 | [#5] yui.html Cycles:180 Average:23459.68 Median:19956.85 stddev:31303.76 (156.9%) stddev-sans-first:31345.65 19:08:35 INFO - PID 3597 | Values: 876.1 918.7 920.6 926.0 938.7 17946.6 19043.8 19088.0 19093.7 20318.3 21084.0 21708.1 21749.2 21768.6 21813.5 1017.6 1049.3 1049.8 1050.3 1053.1 1026.1 1059.3 1060.9 1062.1 1070.6 1110.0 1113.0 1116.2 1117.6 1117.6 1103.7 1104.2 1115.7 1117.6 1118.4 808.3 808.9 816.9 817.8 818.6 17823.8 17955.3 19131.3 19471.0 19780.5 20822.9 20954.3 21580.8 21587.8 21607.7 15991.7 16123.0 16233.2 16342.3 16384.6 3601.2 3609.2 3615.7 3619.6 3635.1 120397.4 122783.4 124325.0 124970.0 125784.4 16359.0 16909.5 16953.6 17063.6 17083.6 31159.7 31206.7 31211.5 31230.5 31354.8 7763.6 7929.3 7939.0 7960.7 7961.0 4277.1 4355.2 4359.7 4372.3 4383.9 5267.3 6031.9 6239.0 6290.5 6295.2 1184.0 1203.7 1211.7 1227.5 1228.0 589.6 647.4 677.3 690.2 694.1 11146.8 11694.7 12323.4 12516.8 12524.7 668.3 675.3 676.9 677.1 688.1 5138.3 5151.3 5286.0 5320.2 5417.3 105246.0 106020.5 106341.0 108236.5 110631.4 93349.5 93496.5 95028.5 98472.0 100559.5 81974.6 84029.6 84162.6 85469.6 86115.7 9890.1 10044.3 10052.0 10174.9 10186.6 36156.8 36935.6 37204.6 37447.8 39327.6 48085.8 67110.7 67716.3 71330.0 72734.6 19165.6 19219.7 19350.1 19457.6 19851.1 22070.8 22136.1 22230.3 22633.4 22844.9 18214.1 19050.7 19337.3 19528.2 19980.8 17272.8 18905.1 18906.5 19064.8 19100.6 18512.4 19727.8 19932.9 20027.0 20259.8 13738.2 13795.5 14178.1 14259.0 14542.5 13143.1 13557.9 13598.0 13695.7 14675.9 19:08:35 INFO - PID 3597 | -------- Summary: end -------- 19:08:35 INFO - PID 3597 | 19:08:36 INFO - TEST-INFO | 3597: exit 0 19:08:36 INFO - TEST-OK | dromaeo_css | took 1115499ms 19:08:36 INFO - TEST-START | kraken 19:08:36 INFO - Initialising browser for kraken test... 19:08:36 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41947/getInfo.html -profile /tmp/tmpVtwMQG/profile 19:08:36 INFO - TEST-INFO | started process 3963 (/builds/slave/test/build/application/firefox/firefox http://localhost:41947/getInfo.html) 19:08:42 INFO - TEST-INFO | 3963: exit 0 19:08:42 INFO - Browser initialized. 19:08:42 INFO - Running cycle 1/1 for kraken test... 19:08:42 INFO - TEST-INFO | started process 4172 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVtwMQG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:08:43 INFO - PID 4172 | 19:08:43 INFO - PID 4172 | (/builds/slave/test/build/application/firefox/firefox:4219): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:08:43 INFO - PID 4172 | 19:08:54 INFO - PID 4172 | RSS: Main: 169717760 19:08:54 INFO - PID 4172 | 19:08:56 INFO - PID 4172 | Cycle 1(1): loaded http://localhost:41947/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41947/tests/kraken/driver.html?testName=audio-beat-detection) 19:08:57 INFO - PID 4172 | RSS: Main: 175443968 19:08:57 INFO - PID 4172 | 19:09:00 INFO - PID 4172 | Cycle 1(1): loaded http://localhost:41947/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41947/tests/kraken/driver.html?testName=audio-dft) 19:09:00 INFO - PID 4172 | RSS: Main: 175738880 19:09:00 INFO - PID 4172 | 19:09:03 INFO - PID 4172 | Cycle 1(1): loaded http://localhost:41947/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41947/tests/kraken/driver.html?testName=audio-fft) 19:09:04 INFO - PID 4172 | RSS: Main: 171327488 19:09:04 INFO - PID 4172 | 19:09:06 INFO - PID 4172 | Cycle 1(1): loaded http://localhost:41947/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41947/tests/kraken/driver.html?testName=audio-oscillator) 19:09:07 INFO - PID 4172 | RSS: Main: 171483136 19:09:07 INFO - PID 4172 | 19:09:09 INFO - PID 4172 | Cycle 1(1): loaded http://localhost:41947/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41947/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:09:10 INFO - PID 4172 | RSS: Main: 172122112 19:09:10 INFO - PID 4172 | 19:09:13 INFO - PID 4172 | Cycle 1(1): loaded http://localhost:41947/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41947/tests/kraken/driver.html?testName=imaging-darkroom) 19:09:13 INFO - PID 4172 | RSS: Main: 174575616 19:09:13 INFO - PID 4172 | 19:09:17 INFO - PID 4172 | Cycle 1(1): loaded http://localhost:41947/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41947/tests/kraken/driver.html?testName=imaging-desaturate) 19:09:17 INFO - PID 4172 | RSS: Main: 174391296 19:09:17 INFO - PID 4172 | 19:09:20 INFO - PID 4172 | Cycle 1(1): loaded http://localhost:41947/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41947/tests/kraken/driver.html?testName=json-parse-financial) 19:09:21 INFO - PID 4172 | RSS: Main: 175546368 19:09:21 INFO - PID 4172 | 19:09:23 INFO - PID 4172 | Cycle 1(1): loaded http://localhost:41947/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41947/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:09:23 INFO - PID 4172 | RSS: Main: 174698496 19:09:23 INFO - PID 4172 | 19:09:25 INFO - PID 4172 | Cycle 1(1): loaded http://localhost:41947/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41947/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:09:26 INFO - PID 4172 | RSS: Main: 174870528 19:09:26 INFO - PID 4172 | 19:09:28 INFO - PID 4172 | Cycle 1(1): loaded http://localhost:41947/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41947/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:09:28 INFO - PID 4172 | RSS: Main: 172589056 19:09:28 INFO - PID 4172 | 19:09:31 INFO - PID 4172 | Cycle 1(1): loaded http://localhost:41947/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41947/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:09:32 INFO - PID 4172 | RSS: Main: 172142592 19:09:32 INFO - PID 4172 | 19:09:35 INFO - PID 4172 | Cycle 1(1): loaded http://localhost:41947/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41947/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:09:35 INFO - PID 4172 | RSS: Main: 172310528 19:09:35 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | Cycle 1(1): loaded http://localhost:41947/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41947/tests/kraken/driver.html?testName=ai-astar) 19:09:37 INFO - PID 4172 | RSS: Main: 173862912 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | __start_tp_report 19:09:37 INFO - PID 4172 | _x_x_mozilla_page_load 19:09:37 INFO - PID 4172 | _x_x_mozilla_page_load_details 19:09:37 INFO - PID 4172 | |i|pagename|runs| 19:09:37 INFO - PID 4172 | |0;ai-astar;100;108;102;108;108;109;111;100;109;110 19:09:37 INFO - PID 4172 | |1;audio-beat-detection;142;124;149;176;121;149;142;119;172;119 19:09:37 INFO - PID 4172 | |2;audio-dft;165;165;164;165;164;166;163;164;167;164 19:09:37 INFO - PID 4172 | |3;audio-fft;76;83;75;168;76;74;74;75;154;77 19:09:37 INFO - PID 4172 | |4;audio-oscillator;79;79;126;78;85;80;136;79;78;124 19:09:37 INFO - PID 4172 | |5;imaging-gaussian-blur;114;114;115;114;116;116;116;115;114;115 19:09:37 INFO - PID 4172 | |6;imaging-darkroom;152;152;151;154;152;151;151;152;151;152 19:09:37 INFO - PID 4172 | |7;imaging-desaturate;94;94;95;95;94;94;95;94;94;95 19:09:37 INFO - PID 4172 | |8;json-parse-financial;55;54;54;54;55;56;55;55;54;56 19:09:37 INFO - PID 4172 | |9;json-stringify-tinderbox;54;54;55;53;54;53;53;54;54;54 19:09:37 INFO - PID 4172 | |10;stanford-crypto-aes;80;86;77;77;78;80;79;89;80;77 19:09:37 INFO - PID 4172 | |11;stanford-crypto-ccm;128;129;130;142;126;127;127;135;125;162 19:09:37 INFO - PID 4172 | |12;stanford-crypto-pbkdf2;172;182;171;178;173;174;174;174;175;173 19:09:37 INFO - PID 4172 | |13;stanford-crypto-sha256-iterative;68;67;67;67;66;67;67;66;65;66 19:09:37 INFO - PID 4172 | __end_tp_report 19:09:37 INFO - PID 4172 | __start_cc_report 19:09:37 INFO - PID 4172 | _x_x_mozilla_cycle_collect,1015 19:09:37 INFO - PID 4172 | __end_cc_report 19:09:37 INFO - PID 4172 | __startTimestamp1501812577952__endTimestamp 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | ------- Summary: start ------- 19:09:37 INFO - PID 4172 | Number of tests: 14 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | [#0] ai-astar Cycles:10 Average:106.50 Median:108.50 stddev:4.17 (3.8%) stddev-sans-first:3.70 19:09:37 INFO - PID 4172 | Values: 100.0 108.0 102.0 108.0 108.0 109.0 111.0 100.0 109.0 110.0 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:145.50 stddev:21.01 (14.4%) stddev-sans-first:22.28 19:09:37 INFO - PID 4172 | Values: 142.0 124.0 149.0 176.0 121.0 149.0 142.0 119.0 172.0 119.0 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | [#2] audio-dft Cycles:10 Average:164.70 Median:165.00 stddev:1.16 (0.7%) stddev-sans-first:1.22 19:09:37 INFO - PID 4172 | Values: 165.0 165.0 164.0 165.0 164.0 166.0 163.0 164.0 167.0 164.0 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | [#3] audio-fft Cycles:10 Average:93.20 Median:75.50 stddev:35.98 (47.7%) stddev-sans-first:37.62 19:09:37 INFO - PID 4172 | Values: 76.0 83.0 75.0 168.0 76.0 74.0 74.0 75.0 154.0 77.0 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:79.00 stddev:23.92 (30.3%) stddev-sans-first:24.72 19:09:37 INFO - PID 4172 | Values: 79.0 79.0 126.0 78.0 85.0 80.0 136.0 79.0 78.0 124.0 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | [#5] imaging-gaussian-blur Cycles:10 Average:114.90 Median:115.00 stddev:0.88 (0.8%) stddev-sans-first:0.87 19:09:37 INFO - PID 4172 | Values: 114.0 114.0 115.0 114.0 116.0 116.0 116.0 115.0 114.0 115.0 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | [#6] imaging-darkroom Cycles:10 Average:151.80 Median:152.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 19:09:37 INFO - PID 4172 | Values: 152.0 152.0 151.0 154.0 152.0 151.0 151.0 152.0 151.0 152.0 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 19:09:37 INFO - PID 4172 | Values: 94.0 94.0 95.0 95.0 94.0 94.0 95.0 94.0 94.0 95.0 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:0.79 (1.4%) stddev-sans-first:0.83 19:09:37 INFO - PID 4172 | Values: 55.0 54.0 54.0 54.0 55.0 56.0 55.0 55.0 54.0 56.0 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | [#9] json-stringify-tinderbox Cycles:10 Average:53.80 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 19:09:37 INFO - PID 4172 | Values: 54.0 54.0 55.0 53.0 54.0 53.0 53.0 54.0 54.0 54.0 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:80.00 stddev:4.06 (5.1%) stddev-sans-first:4.30 19:09:37 INFO - PID 4172 | Values: 80.0 86.0 77.0 77.0 78.0 80.0 79.0 89.0 80.0 77.0 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | [#11] stanford-crypto-ccm Cycles:10 Average:133.10 Median:129.50 stddev:11.36 (8.8%) stddev-sans-first:11.90 19:09:37 INFO - PID 4172 | Values: 128.0 129.0 130.0 142.0 126.0 127.0 127.0 135.0 125.0 162.0 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.60 Median:174.00 stddev:3.20 (1.8%) stddev-sans-first:3.26 19:09:37 INFO - PID 4172 | Values: 172.0 182.0 171.0 178.0 173.0 174.0 174.0 174.0 175.0 173.0 19:09:37 INFO - PID 4172 | 19:09:37 INFO - PID 4172 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:0.84 (1.3%) stddev-sans-first:0.73 19:09:37 INFO - PID 4172 | Values: 68.0 67.0 67.0 67.0 66.0 67.0 67.0 66.0 65.0 66.0 19:09:37 INFO - PID 4172 | -------- Summary: end -------- 19:09:37 INFO - PID 4172 | 19:09:38 INFO - PID 4172 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:09:38 INFO - TEST-INFO | 4172: exit 0 19:09:38 INFO - TEST-OK | kraken | took 61956ms 19:09:38 INFO - SUITE-END | took 1177s 19:09:39 INFO - Completed test suite (00:19:37) 19:09:39 INFO - javascript benchmark 19:09:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8535.200364671935, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3114.221444638839, 3151.732102771264, 3154.5110507871286, 3157.0, 3188.4739018062014, 6073.3926607339245, 6153.6923153842245, 6194.783182588599, 6210.503159747221, 6227.657478838662, 5980.162777211194, 6091.0, 6094.9085763713465, 6106.145139680449, 6129.601575897564, 5588.3573389060375, 5620.381758006626, 5642.4357564243455, 5658.377578466366, 5690.2033715279895, 5654.123610840326, 5670.404607516213, 5671.291088613923, 5675.233843431125, 5686.829395118132, 5661.745221465044, 5664.40523745007, 5667.121596152582, 5674.489295963363, 5686.402927692595, 5537.390887002426, 5578.0517312057045, 5624.746886390122, 5647.491725744702, 5678.432156784289, 5492.945070549284, 5498.1752737089355, 5594.104943209108, 5642.633228840113, 5700.3159620845345, 5732.082866741302, 5888.352281249059, 5899.174115623816, 5970.984932561475, 6012.5791194616395, 6494.642794646293, 6501.02484627305, 6560.835979100513, 6592.835179120512, 6617.669116544153, 6547.083408322838, 6620.0400941863645, 6627.966860165717, 6690.56511326767, 6717.361850624183, 6136.325004249574, 6159.476444502223, 6173.9073913891325, 6174.073888916609, 6187.938120618826, 6793.864122717617, 6811.965940170367, 6820.35206655367, 6821.863562728718, 6825.044493770876, 6486.6756662167645, 6494.772682956074, 6495.350464953467, 6503.219613646393, 6508.251551071593, 6535.3464653534265, 6591.703373348211, 6609.438197753245, 6618.139641846626, 6622.403983641495, 6461.579997300254, 6554.410103090745, 6581.341865813476, 6585.5719378240265, 6642.800715978528, 6530.444912182423, 6558.311377305409, 6567.8358041048405, 6645.966770166215, 6651.467882569383, 5203.739813009334, 5233.241180028951, 5293.444188360167, 5354.518093371616, 5359.732013399314, 4532.342810292489, 4610.331501932201, 4650.302454631832, 4651.186042442559, 4659.044895796354, 5726.112452569859, 5740.655560666373, 5811.0, 5817.854553636193, 5825.504832089235, 6499.317571655003, 6604.0, 6610.603363798091, 6611.206655201407, 6723.596584204964, 5677.517411020027, 5718.1708652246025, 5751.396103409165, 5753.0, 5802.854928626733, 5797.768089276466, 5832.533397328203, 5843.094320380263, 5856.355800861902, 5856.677882716492, 6402.103705481236, 6416.711247993758, 6482.708278127496, 6495.7401703932255, 6508.544401891917, 6606.0, 6635.104260924713, 6642.90035649456, 6726.7981960541265, 6729.427998620172, 6252.531060170414, 6373.681315934092, 6397.232332120176, 6462.870742585216, 6476.384743449365, 6465.353464653497, 6489.318621544859, 6543.869122617426, 6568.638724870178, 6571.835704107436, 6618.63597502142, 6626.867462650816, 6710.698018589273, 6720.126383570105, 6720.764773232913, 6246.843828904313, 6350.206224221973, 6376.0, 6388.041793730792, 6497.057926600711, 6368.9044664330995, 6378.840528986627, 6394.7442102317245, 6472.673102013981, 6497.18785151856, 6255.906161407492, 6318.494520438446, 6407.967960160169, 6417.005364168562, 6420.390062944013, 6311.337309582611, 6356.237251529846, 6447.258565264867, 6459.903101453576, 6460.580062295936, 6141.201643786458, 6224.972879474836, 6233.470155036873, 6273.498120150286, 6317.431431171217], "value": 6017.955155864788, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [819.8155415031615, 838.3460900497614, 863.5725315968594, 893.5175005497032, 899.7435730816723, 41063.384049239285, 42227.57772422275, 42487.78756106223, 42505.0, 42538.57461425385, 37907.43138852919, 38179.809100954524, 38204.617953820525, 38225.61774382256, 38271.0, 6936.132983377065, 7012.228654847962, 7034.507584469089, 7063.6115013674225, 7089.0, 6955.7131930593005, 7063.046488724033, 7066.258042905498, 7068.681909314093, 7070.0101985721785, 6964.512484126113, 6991.82520436988, 7024.859502809941, 7036.261192574782, 7056.964715176443, 6978.057962175118, 7005.299470053005, 7036.190838053638, 7036.40190583801, 7042.4366050716335, 6321.273053598849, 6330.113784070234, 6381.042843573501, 6381.234251889757, 6392.968035159841, 33603.6639633603, 33787.49318760221, 33821.15447113817, 33883.830580847185, 33885.49171762425, 3367.2928684976187, 3403.4894765785084, 3404.097914052778, 3404.9829750851336, 3406.3017081498483, 27039.86480067587, 27122.186334409802, 27124.32189195286, 27191.45617087667, 27240.72759272402, 4351.782410879443, 4438.267685831834, 4496.910061798746, 4516.18708632446, 4558.658100642432, 181230.0, 218172.0, 221133.0, 221482.0, 221933.0, 49289.26066109011, 49976.75011624918, 49991.75004124956, 50038.0, 50052.49947500551, 51724.0, 51831.0, 52002.739986300585, 55076.449235507935, 55870.441295587334, 16566.337346506247, 16657.750133748003, 16662.4168154114, 16695.916520417562, 16699.83300167007, 13773.793393099111, 13774.793378099337, 13778.0, 13778.242196679279, 13791.379387927567, 14680.119192848464, 14727.263636818116, 14783.260836958325, 14811.925940370445, 14916.62708432298, 5875.29976019183, 5875.882482350329, 5951.702414879239, 5999.850003749942, 6030.306514750772, 990.9108180263811, 998.8102260570469, 1005.8239808033711, 1016.6695823857276, 1028.7633844215873, 22204.222852200095, 23388.18141365044, 28683.426331473253, 29202.787693353715, 29672.851635742114, 1109.611635927419, 1118.116687816632, 1119.160629527854, 1122.764219513895, 1126.3298337489346, 12499.437525311383, 12668.619941401772, 12687.555935542212, 12714.774914389945, 12813.231206127663, 222286.0, 223345.0, 223519.0, 224035.0, 225406.0, 51607.7419612907, 52111.47888521142, 52143.478565214624, 52271.0, 56682.43317566854, 1500.482333594886, 1501.5720519652066, 1530.6785575029146, 1531.6400645848216, 1535.7005383950245, 27509.724902750717, 27542.862285689243, 27765.61171941435, 28291.71708282891, 28563.571546426403, 53277.46722532725, 53582.196267055246, 53765.46234537761, 53937.99999999843, 53988.19017714816, 203891.0, 204765.0, 208523.0, 208677.0, 208749.0, 36683.0, 37755.81122094372, 38977.80511097427, 39173.608263917, 39334.40998385084, 47220.7638961803, 48769.2684609724, 48833.0, 48848.2672759916, 49709.75145124251, 34522.30955380928, 35231.59073637026, 35315.0, 35461.11347216237, 35466.468002980495, 34672.30655386828, 34676.0, 34952.0, 35106.29787404186, 35613.0, 37760.244795104496, 38362.42456363101, 38379.424308634836, 38430.80784596059, 38608.0, 33457.49813752747, 33531.32937341288, 33703.0, 33817.3236535263, 34137.82931085329, 24882.50234995326, 25171.119010834533, 25277.747222528276, 25464.872675636503, 25800.09699660503], "value": 17491.25127596881, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.2651552981124, 575.3153747041018, 575.3872126185612, 578.2656026845904, 597.8834127345167, 22290.442738931535, 22481.3255602332, 23255.186068487605, 23369.18207862725, 23380.532389352225, 25800.35499112523, 26354.341141471472, 26468.867655661743, 26475.867620661917, 26506.602400964002, 6655.334466553354, 6791.660416979156, 6820.386165245127, 6821.590704557718, 6822.590644561317, 6046.7883624073165, 6626.403623673868, 6629.73481060757, 6637.568558043736, 6662.866742665144, 6613.768518101868, 6618.834529136786, 6625.304343043983, 6628.43658289046, 6629.93370066298, 6572.769953051644, 6588.604683718968, 6592.373724496165, 6601.174853143357, 6601.70292336846, 6142.84642883927, 6150.138980542728, 6153.261608606996, 6172.259328880518, 6182.938170618281, 28449.28876778097, 28739.56890646642, 29255.70744292551, 29946.251343716365, 30247.697523024708, 1961.3821646181407, 1980.3563841751488, 1994.7307113539632, 1996.8502362322738, 1999.050451035761, 19587.608247835105, 20073.498162545908, 20141.5971680567, 20259.493512662153, 20283.492912677153, 2424.6422003678, 2456.4104614892476, 2516.9244922652165, 2529.0, 2536.036306203631, 126946.36526817307, 128026.35986820099, 135829.0, 138919.0, 139013.0, 14738.55784326472, 14763.33564989578, 15180.924095379452, 15350.232488375537, 15407.306671199824, 23686.289411317688, 23693.644595331083, 23709.407264818172, 23716.407089822547, 23824.285271441884, 10329.380237185793, 10575.312604680672, 10608.469576521144, 10640.946795265974, 10863.619773307897, 8440.535770532557, 9872.753181170528, 9926.950365248273, 9954.153896918842, 10049.648262310784, 8122.0, 11063.280886742335, 11086.667399978014, 11400.657980260605, 11514.827277590843, 3570.535830342091, 3636.618155093677, 3650.4668039423505, 4184.372344148402, 4357.564243575681, 971.9562619682131, 984.3945973226414, 986.6349450703286, 995.9452230127413, 998.5905778630872, 19930.0, 20235.190592376435, 20400.693989590167, 20440.897795510926, 20736.170553178006, 1061.135174832509, 1065.472591067411, 1065.5471424644495, 1068.1188019883627, 1086.5327908999047, 11100.056495197998, 11284.266522676, 11349.659510214708, 11386.658400248005, 11421.086313094933, 470812.0, 471251.0, 533782.0, 537632.0, 538640.0, 121637.0, 123577.0, 125488.37255813663, 126401.36799316129, 127117.0, 1294.6504443800309, 1301.9088663793443, 1337.4249072898554, 1343.173948021979, 1343.9731205376033, 21123.26068587592, 21291.89354053282, 21384.572308554052, 21523.892380537996, 21719.348419547438, 55611.44388556063, 55893.0, 57414.42585574258, 58219.12671310019, 58819.70590147022, 71073.0, 71507.64246178736, 71520.64239678768, 71945.6402717983, 71996.0, 12461.314627695201, 13074.673133171747, 14529.564113076625, 14681.119132852064, 15006.474773382879, 22928.656070159293, 23210.071597135924, 23286.417839554146, 23318.3004509865, 23487.882560587088, 17302.567435814206, 17677.911610441864, 17793.822061779218, 17818.821811781716, 18649.347272845385, 17001.319947201973, 17081.74377384365, 17632.73550896763, 17646.82353176452, 17811.287548497916, 17337.73993390075, 18408.723869142243, 18455.354062607745, 18658.346957856953, 18677.90661046686, 14752.0, 14778.556643300719, 14942.178180200193, 14954.925225373803, 14971.0, 18464.907675461538, 18543.443696689123, 18625.720614190526, 18628.254869805056, 18788.624227515098], "value": 12393.297814701838, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1298.623399214228, 1370.390176371515, 1371.3417559571405, 1380.4823191303262, 1382.3710211853609, 13645.590632281022, 14829.110253384792, 14834.332455039548, 14846.628834279134, 14849.703005939875, 13834.861651383482, 14110.0, 14112.15327080376, 14116.153030818157, 14130.505432309872, 2577.819552631307, 2695.4878573071055, 2732.1257197696746, 2741.150243424535, 2751.8486483243414, 2752.0642981386322, 2776.5696317070783, 2779.1662501249643, 2781.721827817212, 2782.1514438096374, 2653.3631928337254, 2668.933276668089, 2695.043259642828, 2701.0816322450364, 2704.5672692369126, 2633.354828067126, 2668.0395057779183, 2681.3698780786694, 2682.6512553368134, 2683.919482415521, 2583.6899572051284, 2610.2038878142157, 2628.290361602376, 2628.776553992913, 2639.129087401153, 13737.0, 13941.372638231303, 14071.155730656092, 14078.436862525487, 14081.08472949255, 7644.0, 7867.370610351159, 7868.370530357558, 7877.606119693993, 7943.205679432069, 4720.740359280238, 5104.51507106828, 5153.278541004225, 5225.399079105876, 5246.422893481714, 1843.5759775251768, 1853.073463268366, 1853.2401715296799, 1875.263693445517, 1907.8092190780949, 22569.0, 22782.772172278397, 22960.65559016616, 22961.0, 22981.08075676971, 6494.09082728409, 6497.317781632955, 6544.8363790905605, 6685.298043705438, 6703.932960670429, 15383.538493845203, 15444.150571718663, 15450.690986180214, 15461.458848940232, 15498.457553985556, 7214.96392518034, 7473.364763995121, 7519.774406767697, 7520.586367749717, 7528.473006889465, 5911.704414779244, 6127.724252408652, 7027.754028608974, 7213.206547279795, 7243.239459856744, 4975.577075948512, 5125.359330083739, 5151.562117220078, 5189.221616757516, 5279.023380674587, 1401.2923473645744, 1403.1090257686442, 1404.5084220522735, 1407.8380986186514, 1420.6874487612708, 826.1573195340839, 829.0962850492871, 853.2363534636465, 857.2113655437013, 878.578282424427, 7233.529820561646, 7395.149557800814, 7401.0, 7412.666430010662, 7422.665980030912, 974.0843607009389, 994.1400688404478, 999.3803841618098, 1016.7102375823002, 1022.039283073923, 4471.150481408522, 4513.0, 4543.68194226401, 4575.199340115467, 4782.832600859023, 18891.81108188928, 19576.51058723515, 19604.0, 19674.40976770699, 19733.210671572975, 18377.90811045963, 19098.904505477385, 19546.70679939802, 19596.21615135407, 19615.0, 10739.624113156002, 10910.454477276104, 10982.450877455936, 10997.450127493594, 11000.284981476178, 7559.886601700869, 7620.8475830481975, 7709.267619576131, 8042.115367309474, 8048.798780030546, 16343.0, 16425.178741063137, 16426.83573164253, 16525.33898644041, 17040.659186815945, 15308.61728456753, 16347.0, 17132.0, 17176.31294748246, 17249.482515524553, 10677.412742299248, 10957.671269861918, 11016.339019658846, 11575.479103440197, 11577.594784182513, 12878.291693956922, 12991.675208119872, 13023.674408139872, 13031.674208144872, 13104.868951310746, 9678.516074196403, 9753.707388778348, 9771.31600787938, 10887.183461240596, 11011.559537618405, 10362.378257304586, 10386.792264154523, 10427.895721042692, 10484.0, 10913.07238884672, 9080.455172689659, 9098.40860344102, 9100.590473428843, 9274.907250927405, 9317.11487408695, 6643.634600097041, 6673.432758215606, 6720.495962802712, 6755.560888542229, 6770.763023294161, 6586.37429444202, 6672.297719423641, 6695.263521012782, 6782.593044417351, 6855.760048398282], "value": 6289.385169711024, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.2508064139125, 497.22433004512953, 511.6214001638787, 518.5929045699127, 531.1793279383352, 6081.483073938715, 6097.786577469789, 6139.693015349226, 6229.501639868811, 6236.126942228092, 6059.545534084939, 6068.57519973602, 6068.939310606893, 6080.756769729205, 6083.574149809515, 4981.5516603505675, 5015.67398119122, 5033.6728112672745, 5053.444121146671, 5054.292399064134, 4669.719816811003, 4963.35476388069, 4985.750712464363, 5037.370328708911, 5038.269450929613, 4816.63875209359, 4831.637627177958, 4865.27020946859, 4880.975595122037, 4899.436564795041, 4817.614590832725, 4836.226203807391, 4843.699242859649, 4861.927071093936, 4899.216125419951, 4369.4319738434115, 4622.745748983804, 4700.976495117537, 4712.811487540494, 4754.952450475485, 5890.440408161217, 5901.586888917777, 5946.543096941234, 5999.280086389617, 6021.337652858182, 5863.325717542494, 5909.8227053188475, 5944.702764861783, 5959.314678811948, 5970.850728731816, 6596.373344532261, 6607.174103237096, 6644.202695676549, 6649.534532582721, 6650.866982660368, 5238.004779091961, 5244.711540865251, 5255.528452033437, 5285.233641122016, 5321.201819727034, 6540.869182616321, 6602.240742314647, 6671.366220209073, 6772.492063095189, 6794.660266986631, 5321.3348331458565, 5330.92003619946, 5374.27447294618, 5395.757190926418, 5399.163129714901, 5386.596005299618, 5410.78356865721, 5433.103537916279, 5466.72666366688, 5520.420355862657, 5865.794697185578, 5888.793892213752, 5903.76384944606, 5915.674637894871, 5953.017752070947, 6497.447716944018, 6503.479721622259, 6542.836429089216, 6585.275619681831, 6595.670216489157, 1898.3071179019557, 1924.7978962209047, 1927.884326940388, 1931.3916116423281, 1947.2211115553891, 1373.6085215713474, 1402.754517959353, 1434.8162765718323, 1444.6925532393202, 1456.7669172932492, 5662.37713851476, 5706.343770466367, 5743.195952566644, 5747.741351639186, 5756.884862302814, 6427.646479443675, 6477.319881412478, 6546.9017964730565, 6599.439047680905, 6623.801285961429, 5683.9821863238785, 5685.573581981368, 5755.251817263729, 5764.596478246566, 5786.81370319092, 3483.3729928613093, 3504.5794504659602, 3531.4879342495196, 3532.3641744485726, 3559.4727743302547, 6448.613083215022, 6462.806115816533, 6475.6438395887735, 6498.967505162444, 6502.967485162639, 6755.7973260802255, 6766.695498702472, 6772.187337519529, 6834.257264397546, 6868.828279293058, 6418.807435776934, 6458.515611329169, 6544.280129185786, 6544.9672751635935, 6548.541602087808, 6601.702923368364, 6622.635755033327, 6655.301193374626, 6673.164879658133, 6701.32986701326, 6523.934760652332, 6556.442702370352, 6565.671716414256, 6569.57297775643, 6648.800535983928, 6433.464407592543, 6438.67806609677, 6447.2263328400895, 6458.451031662361, 6492.480601551959, 5379.112446446293, 5392.0, 5414.0, 5414.729263536886, 5415.241866138823, 6303.36966303366, 6369.490440764635, 6480.254770701241, 6709.530332876652, 6720.361565651256, 6266.216722909637, 6405.51958603116, 6405.740182778401, 6440.259370172303, 6440.5491615588335, 6296.685165741603, 6417.518686098655, 6442.871142577029, 6464.709088090953, 6465.478463399184, 6263.185785847811, 6287.465565426807, 6311.6232815445355, 6351.316236582678, 6378.840528986627, 6162.568620196723, 6327.588706734047, 6383.265924418565, 6431.614103153825, 6437.0, 6203.7828675996125, 6241.96879015602, 6274.309825919054, 6308.810735677937, 6313.40022697843], "value": 5097.462302779394, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [876.0976194519643, 918.6968300460849, 920.6409500294885, 925.9953700231498, 938.6667732954801, 17946.641067178665, 19043.809561904392, 19088.0, 19093.713594296096, 20318.288859889908, 21084.0, 21708.13167473303, 21749.238776642782, 21768.564628707416, 21813.454663633376, 1017.6285655735641, 1049.2917280835406, 1049.7690508088208, 1050.2595670052594, 1053.0943388685723, 1026.0509029148045, 1059.332620449116, 1060.9045185933264, 1062.134360496197, 1070.6038765656717, 1109.9788194860707, 1113.0, 1116.1628778416189, 1117.5976648406609, 1117.6423544465774, 1103.7461383881712, 1104.1608377632976, 1115.7378016166194, 1117.6088369070842, 1118.4463690473226, 808.2765924497542, 808.8584497712877, 816.930560902324, 817.7628487738549, 818.6275244763618, 17823.821761782347, 17955.281788728436, 19131.330403435953, 19471.0, 19780.50548736279, 20822.895885520476, 20954.266600669056, 21580.784192158033, 21587.784122158733, 21607.67588486174, 15991.680166396724, 16123.0, 16233.188340583041, 16342.264598093114, 16384.590385240463, 3601.1537288737127, 3609.169890925076, 3615.7468977171607, 3619.565652121736, 3635.1457407509215, 120397.39801301026, 122783.38608306991, 124325.0, 124970.0, 125784.37107814402, 16359.0, 16909.492715218563, 16953.576160596083, 17063.573410664834, 17083.572910677085, 31159.68840311613, 31206.687933120833, 31211.531827022613, 31230.53154202689, 31354.843225783727, 7763.573003484749, 7929.286364227247, 7938.960305198437, 7960.721374751856, 7961.0, 4277.144571085795, 4355.216061109031, 4359.651227901761, 4372.322290045048, 4383.9342409863875, 5267.341582302212, 6031.939680603138, 6238.968805155945, 6290.528210384239, 6295.244570651551, 1184.0486357385896, 1203.6870413692327, 1211.666791632308, 1227.4967263422134, 1227.975440491203, 589.5755056359415, 647.4367300448546, 677.2685499660454, 690.2338404371144, 694.1184695436868, 11146.777064458827, 11694.70763230909, 12323.366246816295, 12516.81224781629, 12524.686882828002, 668.2815972829228, 675.3111825937516, 676.9356911093438, 677.1366507702719, 688.0951548713426, 5138.254953031864, 5151.330327057459, 5285.9735701322015, 5320.201969704498, 5417.295751552273, 105246.0, 106020.46989765002, 106341.0, 108236.45881770541, 110631.44684276528, 93349.5332523333, 93496.53251733833, 95028.52485737666, 98472.0, 100559.49720251352, 81974.59012704898, 84029.57985210157, 84162.5791871049, 85469.57265213634, 86115.70826437608, 9890.137315876269, 10044.347117437343, 10051.99480051989, 10174.949125254327, 10186.643467478603, 36156.81921590375, 36935.63064369322, 37204.62795372012, 37447.753134811996, 39327.60672393354, 48085.75957120192, 67110.66444667746, 67716.322836771, 71330.0, 72734.63632681803, 19165.61668766589, 19219.711704324727, 19350.12924418433, 19457.61084778325, 19851.106700198237, 22070.779292207513, 22136.114555418248, 22230.333090007327, 22633.434164146027, 22844.885775571016, 18214.089295535436, 19050.7142392867, 19337.323193688153, 19528.21887124556, 19980.800191997892, 17272.827271727623, 18905.054747262308, 18906.52733681669, 19064.809351906857, 19100.617987639893, 18512.44462666122, 19727.802721972595, 19932.90033549823, 20027.0, 20259.79740202638, 13738.175709457464, 13795.517156899461, 14178.14931104137, 14259.0, 14542.4910128145, 13143.14569552976, 13557.932210338886, 13598.048136630341, 13695.726085478036, 14675.926620367258], "value": 9243.478336624907, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1524.3999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 108.0, 102.0, 108.0, 108.0, 109.0, 111.0, 100.0, 109.0, 110.0], "value": 106.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 124.0, 149.0, 176.0, 121.0, 149.0, 142.0, 119.0, 172.0, 119.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 164.0, 165.0, 164.0, 166.0, 163.0, 164.0, 167.0, 164.0], "value": 164.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 83.0, 75.0, 168.0, 76.0, 74.0, 74.0, 75.0, 154.0, 77.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 126.0, 78.0, 85.0, 80.0, 136.0, 79.0, 78.0, 124.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 114.0, 116.0, 116.0, 116.0, 115.0, 114.0, 115.0], "value": 114.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 151.0, 154.0, 152.0, 151.0, 151.0, 152.0, 151.0, 152.0], "value": 151.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 55.0, 56.0, 55.0, 55.0, 54.0, 56.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 53.0, 54.0, 53.0, 53.0, 54.0, 54.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 86.0, 77.0, 77.0, 78.0, 80.0, 79.0, 89.0, 80.0, 77.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 129.0, 130.0, 142.0, 126.0, 127.0, 127.0, 135.0, 125.0, 162.0], "value": 133.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 182.0, 171.0, 178.0, 173.0, 174.0, 174.0, 174.0, 175.0, 173.0], "value": 174.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 67.0, 66.0, 67.0, 67.0, 66.0, 65.0, 66.0], "value": 66.6, "unit": "score"}]}]} 19:09:39 INFO - Return code: 0 19:09:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:09:39 INFO - # TBPL SUCCESS # 19:09:39 INFO - Running post-action listener: _package_coverage_data 19:09:39 INFO - Running post-action listener: _resource_record_post_action 19:09:39 INFO - [mozharness: 2017-08-04 02:09:39.252449Z] Finished run-tests step (success) 19:09:39 INFO - Running post-run listener: _resource_record_post_run 19:09:39 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 7471104; Write bytes: 439169024; Read time: 4896; Write time: 378944 19:09:39 INFO - TinderboxPrint: CPU usage
13.2% 19:09:39 INFO - TinderboxPrint: I/O read bytes / time
7,471,104 / 4,896 19:09:39 INFO - TinderboxPrint: I/O write bytes / time
439,169,024 / 378,944 19:09:39 INFO - TinderboxPrint: CPU idle
8,277.5 (86.8%) 19:09:39 INFO - TinderboxPrint: CPU user
1,202.3 (12.6%) 19:09:39 INFO - TinderboxPrint: Swap in / out
0 / 0 19:09:39 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 118296576; Read time: 0; Write time: 95160 19:09:39 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:09:39 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 5255168; Write bytes: 320872448; Read time: 4676; Write time: 283784 19:09:39 INFO - Running post-run listener: _upload_blobber_files 19:09:39 INFO - Blob upload gear active. 19:09:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:09:39 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:09:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:09:40 INFO - (blobuploader) - INFO - Open directory for files ... 19:09:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:09:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:09:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:09:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:09:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:09:41 INFO - (blobuploader) - INFO - Done attempting. 19:09:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:09:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:09:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:09:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:09:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:09:41 INFO - (blobuploader) - INFO - Done attempting. 19:09:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:09:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:09:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:09:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:09:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:09:48 INFO - (blobuploader) - INFO - Done attempting. 19:09:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:09:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:09:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:09:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:09:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:09:49 INFO - (blobuploader) - INFO - Done attempting. 19:09:49 INFO - (blobuploader) - INFO - Iteration through files over. 19:09:49 INFO - Return code: 0 19:09:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:09:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:09:49 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c869044211bfc87d0dab3c5a470ed13ac2a27b1715ac6723d99db41ed7da21a1d2895367c9483895fcf4d08ecb64e8c9db032b6c48696f5fe0b7ad9f90dd2332", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92fdc3532c2df542eb61530668d60605c0809f83849f6d814249f27dc751ee592037826d3c6b1335f2a2354e9ed44e2ef541ac88ffc33ae94da06c06e55e3f82", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54c3eb4ef3cadccd938caca2629276c48292358d010950e7e0653c97b25895a8cf07de9c9ebb59052a8ef116c5b165430a845e8127031ef99c91d8a15892a3fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/50ba72efd9001a3ad9ed99666f21857f2d16aa82247288bd24657a45617669c121113ac6f1601ed02f882ca117b1b6dccbfcd0f14a7d020451d8eb9089c1e39d"} 19:09:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:09:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:09:49 INFO - Contents: 19:09:49 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c869044211bfc87d0dab3c5a470ed13ac2a27b1715ac6723d99db41ed7da21a1d2895367c9483895fcf4d08ecb64e8c9db032b6c48696f5fe0b7ad9f90dd2332", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92fdc3532c2df542eb61530668d60605c0809f83849f6d814249f27dc751ee592037826d3c6b1335f2a2354e9ed44e2ef541ac88ffc33ae94da06c06e55e3f82", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54c3eb4ef3cadccd938caca2629276c48292358d010950e7e0653c97b25895a8cf07de9c9ebb59052a8ef116c5b165430a845e8127031ef99c91d8a15892a3fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/50ba72efd9001a3ad9ed99666f21857f2d16aa82247288bd24657a45617669c121113ac6f1601ed02f882ca117b1b6dccbfcd0f14a7d020451d8eb9089c1e39d"} 19:09:49 INFO - Running post-run listener: copy_logs_to_upload_dir 19:09:49 INFO - Copying logs to upload dir... 19:09:49 INFO - mkdir: /builds/slave/test/build/upload/logs 19:09:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.174138 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-08-03 19:09:49.407543) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-03 19:09:49.412745) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c869044211bfc87d0dab3c5a470ed13ac2a27b1715ac6723d99db41ed7da21a1d2895367c9483895fcf4d08ecb64e8c9db032b6c48696f5fe0b7ad9f90dd2332", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92fdc3532c2df542eb61530668d60605c0809f83849f6d814249f27dc751ee592037826d3c6b1335f2a2354e9ed44e2ef541ac88ffc33ae94da06c06e55e3f82", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54c3eb4ef3cadccd938caca2629276c48292358d010950e7e0653c97b25895a8cf07de9c9ebb59052a8ef116c5b165430a845e8127031ef99c91d8a15892a3fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/50ba72efd9001a3ad9ed99666f21857f2d16aa82247288bd24657a45617669c121113ac6f1601ed02f882ca117b1b6dccbfcd0f14a7d020451d8eb9089c1e39d"} build_url:https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011081 build_url: 'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c869044211bfc87d0dab3c5a470ed13ac2a27b1715ac6723d99db41ed7da21a1d2895367c9483895fcf4d08ecb64e8c9db032b6c48696f5fe0b7ad9f90dd2332", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92fdc3532c2df542eb61530668d60605c0809f83849f6d814249f27dc751ee592037826d3c6b1335f2a2354e9ed44e2ef541ac88ffc33ae94da06c06e55e3f82", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54c3eb4ef3cadccd938caca2629276c48292358d010950e7e0653c97b25895a8cf07de9c9ebb59052a8ef116c5b165430a845e8127031ef99c91d8a15892a3fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/50ba72efd9001a3ad9ed99666f21857f2d16aa82247288bd24657a45617669c121113ac6f1601ed02f882ca117b1b6dccbfcd0f14a7d020451d8eb9089c1e39d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-03 19:09:49.436880) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 19:09:49.437215) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=97facb8ba2ac28944cc45e6b000001d4-1501807799.202535-980229723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004338 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 19:09:49.520339) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-03 19:09:49.520651) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-03 19:09:49.520961) ========= ========= Total master_lag: 0.18 =========