builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-002 starttime: 1505688625.18 results: success (0) revision: ae39864562c6048fdc2950c5dfedb48e247c3300 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-17 15:50:25.180508) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-17 15:50:25.180959) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-17 15:50:25.181389) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-17 15:50:25.307326) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 15:50:25.307593) ========= 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=a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-17 15:50:25-- 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% 46.3M=0s 2017-09-17 15:50:25 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128023 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 15:50:25.496175) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 15:50:25.496610) ========= 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=a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015780 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 15:50:25.589412) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 15:50:25.589746) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ae39864562c6048fdc2950c5dfedb48e247c3300 --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 ae39864562c6048fdc2950c5dfedb48e247c3300 --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=a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-17 15:50:25,644 truncating revision to first 12 chars 2017-09-17 15:50:25,644 Setting DEBUG logging. 2017-09-17 15:50:25,645 attempt 1/10 2017-09-17 15:50:25,645 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ae39864562c6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-17 15:50:26,015 unpacking tar archive at: mozilla-central-ae39864562c6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.735632 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 15:50:26.383698) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-17 15:50:26.383995) ========= script_repo_revision: ae39864562c6048fdc2950c5dfedb48e247c3300 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-17 15:50:26.384376) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-17 15:50:26.384692) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-17 15:50:26.491288) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-17 15:50:26.491574) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:50:26 INFO - MultiFileLogger online at 20170917 15:50:26 in /builds/slave/test 15:50:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:50:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:50:26 INFO - {'append_to_log': False, 15:50:26 INFO - 'base_work_dir': '/builds/slave/test', 15:50:26 INFO - 'blob_upload_branch': 'Firefox', 15:50:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:50:26 INFO - 'branch': 'Firefox', 15:50:26 INFO - 'buildbot_json_path': 'buildprops.json', 15:50:26 INFO - 'code_coverage': False, 15:50:26 INFO - 'config_files': ('talos/linux_config.py',), 15:50:26 INFO - 'default_actions': ('clobber', 15:50:26 INFO - 'read-buildbot-config', 15:50:26 INFO - 'download-and-extract', 15:50:26 INFO - 'populate-webroot', 15:50:26 INFO - 'create-virtualenv', 15:50:26 INFO - 'install', 15:50:26 INFO - 'setup-mitmproxy', 15:50:26 INFO - 'run-tests'), 15:50:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:50:26 INFO - 'disable_ccov_upload': False, 15:50:26 INFO - 'disable_stylo': False, 15:50:26 INFO - 'download_minidump_stackwalk': True, 15:50:26 INFO - 'download_symbols': 'ondemand', 15:50:26 INFO - 'enable_stylo': False, 15:50:26 INFO - 'enable_webrender': False, 15:50:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:50:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:50:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:50:26 INFO - 'gecko_profile': False, 15:50:26 INFO - 'gecko_profile_interval': 0, 15:50:26 INFO - 'installer_path': 'installer.exe', 15:50:26 INFO - 'log_level': 'info', 15:50:26 INFO - 'log_name': 'talos', 15:50:26 INFO - 'log_to_console': True, 15:50:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:50:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:50:26 INFO - 'opt_config_files': (), 15:50:26 INFO - 'pip_index': False, 15:50:26 INFO - 'suite': 'dromaeojs-e10s', 15:50:26 INFO - 'system_bits': '32', 15:50:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:50:26 INFO - 'title': 'talos-linux64-ix-002', 15:50:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:50:26 INFO - 'use_talos_json': True, 15:50:26 INFO - 'verify': 'False', 15:50:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:50:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:50:26 INFO - 'work_dir': 'build'} 15:50:26 INFO - [mozharness: 2017-09-17 22:50:26.682243Z] Running clobber step. 15:50:26 INFO - Running pre-action listener: _resource_record_pre_action 15:50:26 INFO - Running main action method: clobber 15:50:26 INFO - rmtree: /builds/slave/test/build 15:50:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:50:28 INFO - Running post-action listener: _resource_record_post_action 15:50:28 INFO - [mozharness: 2017-09-17 22:50:28.182727Z] Finished clobber step (success) 15:50:28 INFO - [mozharness: 2017-09-17 22:50:28.182824Z] Running read-buildbot-config step. 15:50:28 INFO - Running pre-action listener: _resource_record_pre_action 15:50:28 INFO - Running main action method: read_buildbot_config 15:50:28 INFO - Using buildbot properties: 15:50:28 INFO - { 15:50:28 INFO - "project": "", 15:50:28 INFO - "product": "firefox", 15:50:28 INFO - "who": "archaeopteryx@coole-files.de", 15:50:28 INFO - "installer_path": "public/build/target.tar.bz2", 15:50:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:50:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:50:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 15:50:28 INFO - "stage_platform": "linux64-pgo", 15:50:28 INFO - "basedir": "/builds/slave/test", 15:50:28 INFO - "buildnumber": 174, 15:50:28 INFO - "platform": "ubuntu64_hw", 15:50:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:50:28 INFO - "slavebuilddir": "test", 15:50:28 INFO - "taskId": "IzpZdE25QOmYCWlfSAPj4A", 15:50:28 INFO - "branch": "mozilla-central", 15:50:28 INFO - "script_repo_revision": "production", 15:50:28 INFO - "revision": "ae39864562c6048fdc2950c5dfedb48e247c3300", 15:50:28 INFO - "slavename": "talos-linux64-ix-002", 15:50:28 INFO - "repo_path": "mozilla-central" 15:50:28 INFO - } 15:50:28 INFO - Finding installer, test and symbols from parent task. 15:50:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IzpZdE25QOmYCWlfSAPj4A'}, attempt #1 15:50:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IzpZdE25QOmYCWlfSAPj4A'}, attempt #1 15:50:29 INFO - Task dependencies: Tm_s3FMBRbu6xkB0TPt4pA 15:50:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA'}, attempt #1 15:50:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.tar.bz2 15:50:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.test_packages.json 15:50:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.crashreporter-symbols.zip 15:50:29 INFO - Running post-action listener: _resource_record_post_action 15:50:29 INFO - [mozharness: 2017-09-17 22:50:29.616488Z] Finished read-buildbot-config step (success) 15:50:29 INFO - [mozharness: 2017-09-17 22:50:29.616704Z] Running download-and-extract step. 15:50:29 INFO - Running pre-action listener: _resource_record_pre_action 15:50:29 INFO - Running main action method: download_and_extract 15:50:29 INFO - mkdir: /builds/slave/test/build/tests 15:50:29 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')]} 15:50:29 INFO - trying https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.test_packages.json 15:50:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:50:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:50:30 INFO - Downloaded 989 bytes. 15:50:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:50:30 INFO - Using the following test package requirements: 15:50:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:50:30 INFO - u'common': [u'target.common.tests.zip'], 15:50:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:50:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:50:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:50:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:50:30 INFO - u'mozbase': [u'target.common.tests.zip'], 15:50:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:50:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:50:30 INFO - u'web-platform': [u'target.common.tests.zip', 15:50:30 INFO - u'target.web-platform.tests.tar.gz'], 15:50:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:50:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:50:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.common.tests.zip 15:50:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:50:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.common.tests.zip into memory 15:50:32 INFO - Content-Length response header: 55324248 15:50:32 INFO - Bytes received: 55324248 15:50:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.talos.tests.zip 15:50:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:50:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.talos.tests.zip into memory 15:50:44 INFO - Content-Length response header: 14508748 15:50:44 INFO - Bytes received: 14508748 15:50:44 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')]} 15:50:44 INFO - trying https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.tar.bz2 15:50:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:50:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:50:47 INFO - Downloaded 62191165 bytes. 15:50:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.tar.bz2 15:50:47 INFO - mkdir: /builds/slave/test/properties 15:50:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:50:47 INFO - Writing to file /builds/slave/test/properties/build_url 15:50:47 INFO - Contents: 15:50:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.tar.bz2 15:50:47 INFO - Running post-action listener: _resource_record_post_action 15:50:47 INFO - Running post-action listener: find_tests_for_verification 15:50:47 INFO - Running post-action listener: set_extra_try_arguments 15:50:47 INFO - [mozharness: 2017-09-17 22:50:47.341204Z] Finished download-and-extract step (success) 15:50:47 INFO - [mozharness: 2017-09-17 22:50:47.341303Z] Running populate-webroot step. 15:50:47 INFO - Running pre-action listener: _resource_record_pre_action 15:50:47 INFO - Running main action method: populate_webroot 15:50:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:50:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:47 INFO - 'tests': ['tresize', 15:50:47 INFO - 'tcanvasmark']}, 15:50:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:50:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:47 INFO - 'tests': ['dromaeo_css', 15:50:47 INFO - 'kraken']}, 15:50:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:50:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:50:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:50:47 INFO - 'talos_options': ['--disable-stylo'], 15:50:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:50:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:50:47 INFO - 'tests': ['damp', 'tps']}, 15:50:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:50:47 INFO - 'talos_options': ['--disable-stylo'], 15:50:47 INFO - 'tests': ['damp', 'tps']}, 15:50:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:50:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:47 INFO - 'tests': ['dromaeo_dom']}, 15:50:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:50:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:47 INFO - 'tests': ['basic_compositor_video', 15:50:47 INFO - 'glvideo']}, 15:50:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:50:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:50:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:50:47 INFO - 'talos_options': ['--disable-stylo'], 15:50:47 INFO - 'tests': ['ts_paint_webext', 15:50:47 INFO - 'tp5o_webext']}, 15:50:47 INFO - 'other-e10s': {'tests': ['a11yr', 15:50:47 INFO - 'ts_paint', 15:50:47 INFO - 'tpaint', 15:50:47 INFO - 'sessionrestore', 15:50:47 INFO - 'sessionrestore_many_windows', 15:50:47 INFO - 'sessionrestore_no_auto_restore', 15:50:47 INFO - 'tabpaint']}, 15:50:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:47 INFO - 'tests': ['a11yr', 15:50:47 INFO - 'ts_paint', 15:50:47 INFO - 'tpaint', 15:50:47 INFO - 'sessionrestore', 15:50:47 INFO - 'sessionrestore_many_windows', 15:50:47 INFO - 'sessionrestore_no_auto_restore', 15:50:47 INFO - 'tabpaint']}, 15:50:47 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:50:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 15:50:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:47 INFO - 'tests': ['perf_reftest_singletons']}, 15:50:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:47 INFO - 'tests': ['bloom_basic']}, 15:50:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:50:47 INFO - 'tsvgr_opacity', 15:50:47 INFO - 'tart', 15:50:47 INFO - 'tscrollx', 15:50:47 INFO - 'tsvg_static']}, 15:50:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:47 INFO - 'tests': ['tsvgx', 15:50:47 INFO - 'tsvgr_opacity', 15:50:47 INFO - 'tart', 15:50:47 INFO - 'tscrollx', 15:50:47 INFO - 'tsvg_static']}, 15:50:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:50:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:50:47 INFO - 'talos_options': ['--disable-stylo'], 15:50:47 INFO - 'tests': ['tp5o']}, 15:50:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:50:47 INFO - 'talos_options': ['--mitmproxy', 15:50:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:50:47 INFO - '--firstNonBlankPaint'], 15:50:47 INFO - 'tests': ['tp6_google', 15:50:47 INFO - 'tp6_youtube', 15:50:47 INFO - 'tp6_amazon', 15:50:47 INFO - 'tp6_facebook']}, 15:50:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:50:47 INFO - 'talos_options': ['--disable-stylo', 15:50:47 INFO - '--mitmproxy', 15:50:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:50:47 INFO - '--firstNonBlankPaint'], 15:50:47 INFO - 'tests': ['tp6_google', 15:50:47 INFO - 'tp6_youtube', 15:50:47 INFO - 'tp6_amazon', 15:50:47 INFO - 'tp6_facebook']}, 15:50:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:50:47 INFO - 'talos_options': ['--stylo-threads=1', 15:50:47 INFO - '--mitmproxy', 15:50:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:50:47 INFO - '--firstNonBlankPaint'], 15:50:47 INFO - 'tests': ['tp6_google', 15:50:47 INFO - 'tp6_youtube', 15:50:47 INFO - 'tp6_amazon', 15:50:47 INFO - 'tp6_facebook']}, 15:50:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:50:47 INFO - 'talos_options': ['--xperf_path', 15:50:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:50:47 INFO - 'tests': ['tp5n']}, 15:50:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:50:47 INFO - 'talos_options': ['--disable-stylo', 15:50:47 INFO - '--xperf_path', 15:50:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:50:47 INFO - 'tests': ['tp5n']}}} 15:50:47 INFO - Running post-action listener: _resource_record_post_action 15:50:47 INFO - [mozharness: 2017-09-17 22:50:47.356239Z] Finished populate-webroot step (success) 15:50:47 INFO - [mozharness: 2017-09-17 22:50:47.356328Z] Running create-virtualenv step. 15:50:47 INFO - Running pre-action listener: _resource_record_pre_action 15:50:47 INFO - Running main action method: create_virtualenv 15:50:47 INFO - Creating virtualenv /builds/slave/test/build/venv 15:50:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:50:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:50:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:50:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:50:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:50:47 INFO - Installing distribute.............................................................................................................................................................................................done. 15:50:48 INFO - Installing pip...............done. 15:50:48 INFO - Return code: 0 15:50:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:50:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:50:48 INFO - Reading from file tmpfile_stdout 15:50:48 INFO - Output received: 15:50:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:50:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:50:48 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')]} 15:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:48 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')]} 15:50:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:48 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 0xd7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea090>, '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 0x10ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', '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 15:50:48 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 15:50:48 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 15:50:48 INFO - Using env: {'DISPLAY': ':0', 15:50:48 INFO - 'HOME': '/home/cltbld', 15:50:48 INFO - 'LANG': 'en_US.UTF-8', 15:50:48 INFO - 'LANGUAGE': 'en_US:en', 15:50:48 INFO - 'LOGNAME': 'cltbld', 15:50:48 INFO - 'MAIL': '/var/mail/cltbld', 15:50:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:48 INFO - 'MOZ_NO_REMOTE': '1', 15:50:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:48 INFO - 'NO_EM_RESTART': '1', 15:50:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:48 INFO - 'PWD': '/builds/slave/test', 15:50:48 INFO - 'SHELL': '/bin/bash', 15:50:48 INFO - 'SHLVL': '1', 15:50:48 INFO - 'TERM': 'linux', 15:50:48 INFO - 'TMOUT': '86400', 15:50:48 INFO - 'USER': 'cltbld', 15:50:48 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', 15:50:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:48 INFO - '_': '/tools/buildbot/bin/python'} 15:50:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:50:49 INFO - Downloading/unpacking pip>=1.5 15:50:49 INFO - Running setup.py egg_info for package pip 15:50:49 INFO - warning: no files found matching 'pip/cacert.pem' 15:50:49 INFO - warning: no files found matching '*.html' under directory 'docs' 15:50:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:50:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:50:49 INFO - Installing collected packages: pip 15:50:49 INFO - Found existing installation: pip 1.1 15:50:49 INFO - Uninstalling pip: 15:50:49 INFO - Successfully uninstalled pip 15:50:49 INFO - Running setup.py install for pip 15:50:49 INFO - warning: no files found matching 'pip/cacert.pem' 15:50:49 INFO - warning: no files found matching '*.html' under directory 'docs' 15:50:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:50:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:50:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:50:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:50:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:50:49 INFO - Successfully installed pip 15:50:49 INFO - Cleaning up... 15:50:49 INFO - Return code: 0 15:50:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:50:49 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')]} 15:50:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:49 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')]} 15:50:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:49 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 0xd7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea090>, '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 0x10ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', '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 15:50:49 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 15:50:49 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 15:50:49 INFO - Using env: {'DISPLAY': ':0', 15:50:49 INFO - 'HOME': '/home/cltbld', 15:50:49 INFO - 'LANG': 'en_US.UTF-8', 15:50:49 INFO - 'LANGUAGE': 'en_US:en', 15:50:49 INFO - 'LOGNAME': 'cltbld', 15:50:49 INFO - 'MAIL': '/var/mail/cltbld', 15:50:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:49 INFO - 'MOZ_NO_REMOTE': '1', 15:50:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:49 INFO - 'NO_EM_RESTART': '1', 15:50:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:49 INFO - 'PWD': '/builds/slave/test', 15:50:49 INFO - 'SHELL': '/bin/bash', 15:50:49 INFO - 'SHLVL': '1', 15:50:49 INFO - 'TERM': 'linux', 15:50:49 INFO - 'TMOUT': '86400', 15:50:49 INFO - 'USER': 'cltbld', 15:50:49 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', 15:50:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:49 INFO - '_': '/tools/buildbot/bin/python'} 15:50:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:49 INFO - Downloading/unpacking psutil>=3.1.1 15:50:49 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 15:50:49 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 15:50:49 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 15:50:49 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 15:50:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:50:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:51 INFO - Installing collected packages: psutil 15:50:51 INFO - Running setup.py install for psutil 15:50:51 INFO - building 'psutil._psutil_linux' extension 15:50:51 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 15:50:51 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 15:50:51 INFO - building 'psutil._psutil_posix' extension 15:50:51 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 15:50:51 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 15:50:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:51 INFO - Successfully installed psutil 15:50:51 INFO - Cleaning up... 15:50:52 INFO - Return code: 0 15:50:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:50:52 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')]} 15:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:52 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')]} 15:50:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:52 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 0xd7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea090>, '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 0x10ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', '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 15:50:52 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 15:50:52 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 15:50:52 INFO - Using env: {'DISPLAY': ':0', 15:50:52 INFO - 'HOME': '/home/cltbld', 15:50:52 INFO - 'LANG': 'en_US.UTF-8', 15:50:52 INFO - 'LANGUAGE': 'en_US:en', 15:50:52 INFO - 'LOGNAME': 'cltbld', 15:50:52 INFO - 'MAIL': '/var/mail/cltbld', 15:50:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:52 INFO - 'MOZ_NO_REMOTE': '1', 15:50:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:52 INFO - 'NO_EM_RESTART': '1', 15:50:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:52 INFO - 'PWD': '/builds/slave/test', 15:50:52 INFO - 'SHELL': '/bin/bash', 15:50:52 INFO - 'SHLVL': '1', 15:50:52 INFO - 'TERM': 'linux', 15:50:52 INFO - 'TMOUT': '86400', 15:50:52 INFO - 'USER': 'cltbld', 15:50:52 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', 15:50:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:52 INFO - '_': '/tools/buildbot/bin/python'} 15:50:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:50:52 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 15:50:52 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 15:50:52 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 15:50:52 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 15:50:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:50:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:50:53 INFO - Installing collected packages: mozsystemmonitor 15:50:53 INFO - Running setup.py install for mozsystemmonitor 15:50:53 INFO - Successfully installed mozsystemmonitor 15:50:53 INFO - Cleaning up... 15:50:53 INFO - Return code: 0 15:50:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:50:53 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')]} 15:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:53 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')]} 15:50:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:53 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 0xd7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea090>, '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 0x10ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', '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 15:50:53 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 15:50:53 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 15:50:53 INFO - Using env: {'DISPLAY': ':0', 15:50:53 INFO - 'HOME': '/home/cltbld', 15:50:53 INFO - 'LANG': 'en_US.UTF-8', 15:50:53 INFO - 'LANGUAGE': 'en_US:en', 15:50:53 INFO - 'LOGNAME': 'cltbld', 15:50:53 INFO - 'MAIL': '/var/mail/cltbld', 15:50:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:53 INFO - 'MOZ_NO_REMOTE': '1', 15:50:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:53 INFO - 'NO_EM_RESTART': '1', 15:50:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:53 INFO - 'PWD': '/builds/slave/test', 15:50:53 INFO - 'SHELL': '/bin/bash', 15:50:53 INFO - 'SHLVL': '1', 15:50:53 INFO - 'TERM': 'linux', 15:50:53 INFO - 'TMOUT': '86400', 15:50:53 INFO - 'USER': 'cltbld', 15:50:53 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', 15:50:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:53 INFO - '_': '/tools/buildbot/bin/python'} 15:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:54 INFO - Downloading/unpacking jsonschema==2.5.1 15:50:54 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 15:50:54 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 15:50:54 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 15:50:54 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 15:50:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:50:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:50:55 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 15:50:55 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 15:50:55 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 15:50:55 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 15:50:55 INFO - Downloading functools32-3.2.3-2.tar.gz 15:50:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:50:55 INFO - warning: no files found matching '*.txt' 15:50:55 INFO - no previously-included directories found matching 'build' 15:50:55 INFO - no previously-included directories found matching 'dist' 15:50:55 INFO - no previously-included directories found matching '.git*' 15:50:55 INFO - Installing collected packages: jsonschema, functools32 15:50:55 INFO - Running setup.py install for functools32 15:50:55 INFO - warning: no files found matching '*.txt' 15:50:55 INFO - no previously-included directories found matching 'build' 15:50:55 INFO - no previously-included directories found matching 'dist' 15:50:55 INFO - no previously-included directories found matching '.git*' 15:50:55 INFO - Successfully installed jsonschema functools32 15:50:55 INFO - Cleaning up... 15:50:55 INFO - Return code: 0 15:50:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:50:55 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')]} 15:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:55 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')]} 15:50:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:55 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 0xd7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea090>, '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 0x10ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', '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 15:50:55 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 15:50:55 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 15:50:55 INFO - Using env: {'DISPLAY': ':0', 15:50:55 INFO - 'HOME': '/home/cltbld', 15:50:55 INFO - 'LANG': 'en_US.UTF-8', 15:50:55 INFO - 'LANGUAGE': 'en_US:en', 15:50:55 INFO - 'LOGNAME': 'cltbld', 15:50:55 INFO - 'MAIL': '/var/mail/cltbld', 15:50:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:55 INFO - 'MOZ_NO_REMOTE': '1', 15:50:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:55 INFO - 'NO_EM_RESTART': '1', 15:50:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:55 INFO - 'PWD': '/builds/slave/test', 15:50:55 INFO - 'SHELL': '/bin/bash', 15:50:55 INFO - 'SHLVL': '1', 15:50:55 INFO - 'TERM': 'linux', 15:50:55 INFO - 'TMOUT': '86400', 15:50:55 INFO - 'USER': 'cltbld', 15:50:55 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', 15:50:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:55 INFO - '_': '/tools/buildbot/bin/python'} 15:50:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:50:55 INFO - Cleaning up... 15:50:55 INFO - Return code: 0 15:50:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:50:55 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')]} 15:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:55 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')]} 15:50:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:55 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 0xd7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea090>, '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 0x10ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', '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 15:50:55 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 15:50:55 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 15:50:55 INFO - Using env: {'DISPLAY': ':0', 15:50:55 INFO - 'HOME': '/home/cltbld', 15:50:55 INFO - 'LANG': 'en_US.UTF-8', 15:50:55 INFO - 'LANGUAGE': 'en_US:en', 15:50:55 INFO - 'LOGNAME': 'cltbld', 15:50:55 INFO - 'MAIL': '/var/mail/cltbld', 15:50:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:55 INFO - 'MOZ_NO_REMOTE': '1', 15:50:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:55 INFO - 'NO_EM_RESTART': '1', 15:50:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:55 INFO - 'PWD': '/builds/slave/test', 15:50:55 INFO - 'SHELL': '/bin/bash', 15:50:55 INFO - 'SHLVL': '1', 15:50:55 INFO - 'TERM': 'linux', 15:50:55 INFO - 'TMOUT': '86400', 15:50:55 INFO - 'USER': 'cltbld', 15:50:55 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', 15:50:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:55 INFO - '_': '/tools/buildbot/bin/python'} 15:50:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:55 INFO - Downloading/unpacking blobuploader==1.2.4 15:50:55 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 15:50:55 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 15:50:55 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 15:50:55 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 15:50:57 INFO - Downloading blobuploader-1.2.4.tar.gz 15:50:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:50:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:50:57 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 15:50:57 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 15:50:57 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 15:50:57 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 15:50:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:50:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:50:57 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 15:50:57 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 15:50:57 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 15:50:57 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 15:50:57 INFO - Downloading docopt-0.6.1.tar.gz 15:50:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:50:57 INFO - Installing collected packages: blobuploader, requests, docopt 15:50:57 INFO - Running setup.py install for blobuploader 15:50:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:50:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:50:57 INFO - Running setup.py install for requests 15:50:58 INFO - Running setup.py install for docopt 15:50:58 INFO - Successfully installed blobuploader requests docopt 15:50:58 INFO - Cleaning up... 15:50:58 INFO - Return code: 0 15:50:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50:58 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')]} 15:50:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:58 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')]} 15:50:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:58 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 0xd7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea090>, '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 0x10ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', '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 15:50:58 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 15:50:58 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 15:50:58 INFO - Using env: {'DISPLAY': ':0', 15:50:58 INFO - 'HOME': '/home/cltbld', 15:50:58 INFO - 'LANG': 'en_US.UTF-8', 15:50:58 INFO - 'LANGUAGE': 'en_US:en', 15:50:58 INFO - 'LOGNAME': 'cltbld', 15:50:58 INFO - 'MAIL': '/var/mail/cltbld', 15:50:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:58 INFO - 'MOZ_NO_REMOTE': '1', 15:50:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:58 INFO - 'NO_EM_RESTART': '1', 15:50:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:58 INFO - 'PWD': '/builds/slave/test', 15:50:58 INFO - 'SHELL': '/bin/bash', 15:50:58 INFO - 'SHLVL': '1', 15:50:58 INFO - 'TERM': 'linux', 15:50:58 INFO - 'TMOUT': '86400', 15:50:58 INFO - 'USER': 'cltbld', 15:50:58 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', 15:50:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:58 INFO - '_': '/tools/buildbot/bin/python'} 15:50:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:50:58 INFO - Running setup.py (path:/tmp/pip-_bBQUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:50:58 INFO - Running setup.py (path:/tmp/pip-bnQXbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:50:58 INFO - Running setup.py (path:/tmp/pip-IxlMCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:50:58 INFO - Running setup.py (path:/tmp/pip-KHqRxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:50:58 INFO - Running setup.py (path:/tmp/pip-QIk0jS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:50:59 INFO - Running setup.py (path:/tmp/pip-m7RnfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:50:59 INFO - Running setup.py (path:/tmp/pip-FEbUbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:50:59 INFO - Running setup.py (path:/tmp/pip-KQ5cH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:50:59 INFO - Running setup.py (path:/tmp/pip-nSgPFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:50:59 INFO - Running setup.py (path:/tmp/pip-QX4ph_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:50:59 INFO - Running setup.py (path:/tmp/pip-ZmvpqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:50:59 INFO - Running setup.py (path:/tmp/pip-4jSB6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:50:59 INFO - Running setup.py (path:/tmp/pip-fozikr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:50:59 INFO - Running setup.py (path:/tmp/pip-DPVLaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:59 INFO - Running setup.py (path:/tmp/pip-6LVpAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:50:59 INFO - Running setup.py (path:/tmp/pip-3pZvqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:50:59 INFO - Running setup.py (path:/tmp/pip-PHFv5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:50:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:50:59 INFO - Running setup.py install for manifestparser 15:50:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:50:59 INFO - Running setup.py install for mozcrash 15:51:00 INFO - Running setup.py install for mozdebug 15:51:00 INFO - Running setup.py install for mozdevice 15:51:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:51:00 INFO - Running setup.py install for mozfile 15:51:00 INFO - Running setup.py install for mozhttpd 15:51:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:51:00 INFO - Running setup.py install for mozinfo 15:51:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:51:00 INFO - Running setup.py install for mozInstall 15:51:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:51:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:51:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:51:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:51:00 INFO - Running setup.py install for mozleak 15:51:00 INFO - Running setup.py install for mozlog 15:51:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:51:00 INFO - Running setup.py install for moznetwork 15:51:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:51:01 INFO - Running setup.py install for mozprocess 15:51:01 INFO - Running setup.py install for mozprofile 15:51:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:51:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:51:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:51:01 INFO - Running setup.py install for mozrunner 15:51:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:51:01 INFO - Running setup.py install for mozscreenshot 15:51:01 INFO - Running setup.py install for moztest 15:51:01 INFO - Running setup.py install for mozversion 15:51:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:51:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:51:01 INFO - Cleaning up... 15:51:01 INFO - Return code: 0 15:51:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:01 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')]} 15:51:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:01 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')]} 15:51:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:01 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 0xd7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea090>, '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 0x10ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', '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 15:51:01 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 15:51:01 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 15:51:01 INFO - Using env: {'DISPLAY': ':0', 15:51:01 INFO - 'HOME': '/home/cltbld', 15:51:01 INFO - 'LANG': 'en_US.UTF-8', 15:51:01 INFO - 'LANGUAGE': 'en_US:en', 15:51:01 INFO - 'LOGNAME': 'cltbld', 15:51:01 INFO - 'MAIL': '/var/mail/cltbld', 15:51:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:01 INFO - 'MOZ_NO_REMOTE': '1', 15:51:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:01 INFO - 'NO_EM_RESTART': '1', 15:51:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:01 INFO - 'PWD': '/builds/slave/test', 15:51:01 INFO - 'SHELL': '/bin/bash', 15:51:01 INFO - 'SHLVL': '1', 15:51:01 INFO - 'TERM': 'linux', 15:51:01 INFO - 'TMOUT': '86400', 15:51:01 INFO - 'USER': 'cltbld', 15:51:01 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', 15:51:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:01 INFO - '_': '/tools/buildbot/bin/python'} 15:51:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:01 INFO - Running setup.py (path:/tmp/pip-mjHyT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:01 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)) 15:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:01 INFO - Running setup.py (path:/tmp/pip-9eQy7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:02 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)) 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:02 INFO - Running setup.py (path:/tmp/pip-VEgI4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:02 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)) 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:02 INFO - Running setup.py (path:/tmp/pip-zGP1y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:02 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)) 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:02 INFO - Running setup.py (path:/tmp/pip-sq507E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:02 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)) 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:02 INFO - Running setup.py (path:/tmp/pip-UaKzcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:02 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)) 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:02 INFO - Running setup.py (path:/tmp/pip-eQ3bGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:02 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)) 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:02 INFO - Running setup.py (path:/tmp/pip-eKSVzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:02 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)) 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:02 INFO - Running setup.py (path:/tmp/pip-qqM9zJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:02 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)) 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:02 INFO - Running setup.py (path:/tmp/pip-b4F7p8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:02 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)) 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:02 INFO - Running setup.py (path:/tmp/pip-XAlgaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:02 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)) 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:02 INFO - Running setup.py (path:/tmp/pip-QIQ_Fd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:02 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)) 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:02 INFO - Running setup.py (path:/tmp/pip-6NDQJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:02 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)) 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:02 INFO - Running setup.py (path:/tmp/pip-NQ5K5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:02 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)) 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:02 INFO - Running setup.py (path:/tmp/pip-PZSIWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:02 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)) 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:02 INFO - Running setup.py (path:/tmp/pip-ItlEeS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:03 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)) 15:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:03 INFO - Running setup.py (path:/tmp/pip-8GIaUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:03 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)) 15:51:03 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)) 15:51:03 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)) 15:51:03 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)) 15:51:03 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)) 15:51:03 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)) 15:51:03 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)) 15:51:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:51:03 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 15:51:03 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 15:51:03 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 15:51:03 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 15:51:04 INFO - Downloading blessings-1.6.tar.gz 15:51:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:51:04 INFO - Installing collected packages: blessings 15:51:04 INFO - Running setup.py install for blessings 15:51:04 INFO - Successfully installed blessings 15:51:04 INFO - Cleaning up... 15:51:04 INFO - Return code: 0 15:51:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:51:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:04 INFO - Reading from file tmpfile_stdout 15:51:04 INFO - Current package versions: 15:51:04 INFO - argparse == 1.2.1 15:51:04 INFO - blessings == 1.6 15:51:04 INFO - blobuploader == 1.2.4 15:51:04 INFO - docopt == 0.6.1 15:51:04 INFO - functools32 == 3.2.3-2 15:51:04 INFO - jsonschema == 2.5.1 15:51:04 INFO - manifestparser == 1.1 15:51:04 INFO - mozInstall == 1.13 15:51:04 INFO - mozcrash == 1.0 15:51:04 INFO - mozdebug == 0.1 15:51:04 INFO - mozdevice == 0.51 15:51:04 INFO - mozfile == 1.2 15:51:04 INFO - mozhttpd == 0.7 15:51:04 INFO - mozinfo == 0.10 15:51:04 INFO - mozleak == 0.1 15:51:04 INFO - mozlog == 3.5 15:51:04 INFO - moznetwork == 0.27 15:51:04 INFO - mozprocess == 0.25 15:51:04 INFO - mozprofile == 0.28 15:51:04 INFO - mozrunner == 6.13 15:51:04 INFO - mozscreenshot == 0.1 15:51:04 INFO - mozsystemmonitor == 0.3 15:51:04 INFO - moztest == 0.8 15:51:04 INFO - mozversion == 1.4 15:51:04 INFO - psutil == 3.1.1 15:51:04 INFO - requests == 1.2.3 15:51:04 INFO - wsgiref == 0.1.2 15:51:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:04 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')]} 15:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:04 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')]} 15:51:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:04 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 0xd7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea090>, '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 0x10ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', '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 15:51:04 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 15:51:04 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 15:51:04 INFO - Using env: {'DISPLAY': ':0', 15:51:04 INFO - 'HOME': '/home/cltbld', 15:51:04 INFO - 'LANG': 'en_US.UTF-8', 15:51:04 INFO - 'LANGUAGE': 'en_US:en', 15:51:04 INFO - 'LOGNAME': 'cltbld', 15:51:04 INFO - 'MAIL': '/var/mail/cltbld', 15:51:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:04 INFO - 'MOZ_NO_REMOTE': '1', 15:51:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:04 INFO - 'NO_EM_RESTART': '1', 15:51:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:04 INFO - 'PWD': '/builds/slave/test', 15:51:04 INFO - 'SHELL': '/bin/bash', 15:51:04 INFO - 'SHLVL': '1', 15:51:04 INFO - 'TERM': 'linux', 15:51:04 INFO - 'TMOUT': '86400', 15:51:04 INFO - 'USER': 'cltbld', 15:51:04 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', 15:51:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:04 INFO - '_': '/tools/buildbot/bin/python'} 15:51:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:04 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)) 15:51:04 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)) 15:51:04 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)) 15:51:04 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)) 15:51:04 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)) 15:51:04 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)) 15:51:04 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)) 15:51:04 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)) 15:51:04 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)) 15:51:04 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:51:04 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 15:51:04 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 15:51:04 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 15:51:04 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 15:51:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:51:06 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)) 15:51:06 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)) 15:51:06 INFO - Installing collected packages: simplejson 15:51:06 INFO - Running setup.py install for simplejson 15:51:06 INFO - building 'simplejson._speedups' extension 15:51:06 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 15:51:06 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 15:51:07 INFO - Successfully installed simplejson 15:51:07 INFO - Cleaning up... 15:51:07 INFO - Return code: 0 15:51:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:51:07 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')]} 15:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:07 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')]} 15:51:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:07 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 0xd7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea090>, '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 0x10ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', '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 15:51:07 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 15:51:07 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 15:51:07 INFO - Using env: {'DISPLAY': ':0', 15:51:07 INFO - 'HOME': '/home/cltbld', 15:51:07 INFO - 'LANG': 'en_US.UTF-8', 15:51:07 INFO - 'LANGUAGE': 'en_US:en', 15:51:07 INFO - 'LOGNAME': 'cltbld', 15:51:07 INFO - 'MAIL': '/var/mail/cltbld', 15:51:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:07 INFO - 'MOZ_NO_REMOTE': '1', 15:51:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:07 INFO - 'NO_EM_RESTART': '1', 15:51:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:07 INFO - 'PWD': '/builds/slave/test', 15:51:07 INFO - 'SHELL': '/bin/bash', 15:51:07 INFO - 'SHLVL': '1', 15:51:07 INFO - 'TERM': 'linux', 15:51:07 INFO - 'TMOUT': '86400', 15:51:07 INFO - 'USER': 'cltbld', 15:51:07 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', 15:51:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:07 INFO - '_': '/tools/buildbot/bin/python'} 15:51:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:51:07 INFO - Cleaning up... 15:51:07 INFO - Return code: 0 15:51:07 INFO - Running post-action listener: _resource_record_post_action 15:51:07 INFO - Running post-action listener: _start_resource_monitoring 15:51:07 INFO - Starting resource monitoring. 15:51:07 INFO - [mozharness: 2017-09-17 22:51:07.394183Z] Finished create-virtualenv step (success) 15:51:07 INFO - [mozharness: 2017-09-17 22:51:07.394703Z] Running install step. 15:51:07 INFO - Running pre-action listener: _resource_record_pre_action 15:51:07 INFO - Running main action method: install 15:51:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:07 INFO - Reading from file tmpfile_stdout 15:51:07 INFO - Detecting whether we're running mozinstall >=1.0... 15:51:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:51:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:51:07 INFO - Reading from file tmpfile_stdout 15:51:07 INFO - Output received: 15:51:07 INFO - Usage: mozinstall [options] installer 15:51:07 INFO - Options: 15:51:07 INFO - -h, --help show this help message and exit 15:51:07 INFO - -d DEST, --destination=DEST 15:51:07 INFO - Directory to install application into. [default: 15:51:07 INFO - "/builds/slave/test"] 15:51:07 INFO - --app=APP Application being installed. [default: firefox] 15:51:07 INFO - mkdir: /builds/slave/test/build/application 15:51:07 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'] 15:51:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:51:25 INFO - Reading from file tmpfile_stdout 15:51:25 INFO - Output received: 15:51:25 INFO - /builds/slave/test/build/application/firefox/firefox 15:51:25 INFO - Running post-action listener: _resource_record_post_action 15:51:25 INFO - [mozharness: 2017-09-17 22:51:25.321880Z] Finished install step (success) 15:51:25 INFO - [mozharness: 2017-09-17 22:51:25.322130Z] Running setup-mitmproxy step. 15:51:25 INFO - Running pre-action listener: _resource_record_pre_action 15:51:25 INFO - Running main action method: setup_mitmproxy 15:51:25 INFO - Skipping: mitmproxy is not required 15:51:25 INFO - Running post-action listener: _resource_record_post_action 15:51:25 INFO - [mozharness: 2017-09-17 22:51:25.323381Z] Finished setup-mitmproxy step (success) 15:51:25 INFO - [mozharness: 2017-09-17 22:51:25.323600Z] Running run-tests step. 15:51:25 INFO - Running pre-action listener: _resource_record_pre_action 15:51:25 INFO - Running pre-action listener: _set_gcov_prefix 15:51:25 INFO - Running main action method: run_tests 15:51:25 WARNING - Try message not found. 15:51:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:51:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:51:25 INFO - Python 2.7.3 15:51:25 INFO - Return code: 0 15:51:25 INFO - grabbing minidump binary from tooltool 15:51:25 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')]} 15:51:25 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 0x10ea090>, '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 0x10ead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:51:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 15:51:25 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:51:25 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 15:51:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:51:25 INFO - Return code: 0 15:51:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:51:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:51:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:51:25 INFO - ENV: RUST_BACKTRACE is now full 15:51:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:51:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:51:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 15:51:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-002 --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 15:51:25 INFO - Using env: {'DISPLAY': ':0', 15:51:25 INFO - 'HOME': '/home/cltbld', 15:51:25 INFO - 'LANG': 'en_US.UTF-8', 15:51:25 INFO - 'LANGUAGE': 'en_US:en', 15:51:25 INFO - 'LOGNAME': 'cltbld', 15:51:25 INFO - 'MAIL': '/var/mail/cltbld', 15:51:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:51:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:51:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:25 INFO - 'MOZ_NO_REMOTE': '1', 15:51:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:51:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:25 INFO - 'NO_EM_RESTART': '1', 15:51:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:25 INFO - 'PWD': '/builds/slave/test', 15:51:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:51:25 INFO - 'RUST_BACKTRACE': 'full', 15:51:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:51:25 INFO - 'SHELL': '/bin/bash', 15:51:25 INFO - 'SHLVL': '1', 15:51:25 INFO - 'TERM': 'linux', 15:51:25 INFO - 'TMOUT': '86400', 15:51:25 INFO - 'USER': 'cltbld', 15:51:25 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', 15:51:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:25 INFO - '_': '/tools/buildbot/bin/python'} 15:51:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 15:51:25 INFO - mozversion application_buildid: 20170917213235 15:51:25 INFO - mozversion application_changeset: ae39864562c6048fdc2950c5dfedb48e247c3300 15:51:25 INFO - mozversion application_display_name: Nightly 15:51:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:51:25 INFO - mozversion application_name: Firefox 15:51:25 INFO - mozversion application_remotingname: firefox 15:51:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:51:25 INFO - mozversion application_vendor: Mozilla 15:51:25 INFO - mozversion application_version: 57.0a1 15:51:25 INFO - mozversion platform_buildid: 20170917213235 15:51:25 INFO - mozversion platform_changeset: ae39864562c6048fdc2950c5dfedb48e247c3300 15:51:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:51:25 INFO - mozversion platform_version: 57.0a1 15:51:25 INFO - using testdate: 1505688685 15:51:25 INFO - actual date: 1505688685 15:51:25 INFO - starting webserver on 'localhost:60641' 15:51:25 INFO - SUITE-START | Running 2 tests 15:51:25 INFO - TEST-START | dromaeo_css 15:51:25 INFO - Initialising browser for dromaeo_css test... 15:51:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60641/getInfo.html -profile /tmp/tmpRnJCAj/profile 15:51:25 INFO - TEST-INFO | started process 12473 (/builds/slave/test/build/application/firefox/firefox http://localhost:60641/getInfo.html) 15:51:31 INFO - TEST-INFO | 12473: exit 0 15:51:31 INFO - Browser initialized. 15:51:31 INFO - Running cycle 1/1 for dromaeo_css test... 15:51:31 INFO - Using env: {'DISPLAY': ':0', 15:51:31 INFO - 'HOME': '/home/cltbld', 15:51:31 INFO - 'JSGC_DISABLE_POISONING': '1', 15:51:31 INFO - 'LANG': 'en_US.UTF-8', 15:51:31 INFO - 'LANGUAGE': 'en_US:en', 15:51:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:51:31 INFO - 'LOGNAME': 'cltbld', 15:51:31 INFO - 'MAIL': '/var/mail/cltbld', 15:51:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:51:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:51:31 INFO - 'MOZ_CRASHREPORTER': '1', 15:51:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:51:31 INFO - 'MOZ_NO_REMOTE': '1', 15:51:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:51:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:31 INFO - 'NO_EM_RESTART': '1', 15:51:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:31 INFO - 'PWD': '/builds/slave/test', 15:51:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:51:31 INFO - 'RUST_BACKTRACE': 'full', 15:51:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:51:31 INFO - 'SHELL': '/bin/bash', 15:51:31 INFO - 'SHLVL': '1', 15:51:31 INFO - 'TERM': 'linux', 15:51:31 INFO - 'TMOUT': '86400', 15:51:31 INFO - 'USER': 'cltbld', 15:51:31 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', 15:51:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:31 INFO - '_': '/tools/buildbot/bin/python'} 15:51:31 INFO - TEST-INFO | started process 12709 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRnJCAj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:51:32 INFO - PID 12709 | 15:51:32 INFO - PID 12709 | (/builds/slave/test/build/application/firefox/firefox:12759): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:51:32 INFO - PID 12709 | 15:51:43 INFO - PID 12709 | RSS: Main: 144130048 15:51:43 INFO - PID 12709 | 15:54:39 INFO - PID 12709 | Cycle 1(1): loaded http://localhost:60641/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60641/tests/dromaeo/cssquery-ext.html) 15:54:41 INFO - PID 12709 | RSS: Main: 148729856 15:54:41 INFO - PID 12709 | 15:57:42 INFO - PID 12709 | Cycle 1(1): loaded http://localhost:60641/tests/dromaeo/cssquery-ext.html (next: http://localhost:60641/tests/dromaeo/cssquery-jquery.html) 15:57:43 INFO - PID 12709 | RSS: Main: 148316160 15:57:43 INFO - PID 12709 | 16:00:45 INFO - PID 12709 | Cycle 1(1): loaded http://localhost:60641/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60641/tests/dromaeo/cssquery-mootools.html) 16:00:45 INFO - PID 12709 | RSS: Main: 150601728 16:00:45 INFO - PID 12709 | 16:03:47 INFO - PID 12709 | Cycle 1(1): loaded http://localhost:60641/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60641/tests/dromaeo/cssquery-prototype.html) 16:03:47 INFO - PID 12709 | RSS: Main: 148742144 16:03:47 INFO - PID 12709 | 16:06:50 INFO - PID 12709 | Cycle 1(1): loaded http://localhost:60641/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60641/tests/dromaeo/cssquery-yui.html) 16:06:51 INFO - PID 12709 | RSS: Main: 150786048 16:06:51 INFO - PID 12709 | 16:09:52 INFO - PID 12709 | Cycle 1(1): loaded http://localhost:60641/tests/dromaeo/cssquery-yui.html (next: http://localhost:60641/tests/dromaeo/cssquery-dojo.html) 16:09:53 INFO - PID 12709 | RSS: Main: 152285184 16:09:53 INFO - PID 12709 | 16:09:53 INFO - PID 12709 | __start_tp_report 16:09:53 INFO - PID 12709 | _x_x_mozilla_page_load 16:09:53 INFO - PID 12709 | _x_x_mozilla_page_load_details 16:09:53 INFO - PID 12709 | |i|pagename|runs| 16:09:53 INFO - PID 12709 | |0;dojo.html;3836.827342769575;3846.615338466154;3846.8268927898243;3868.2650296443658;3875.8255878485465;6167.568270221086;6210.627362358262;6242.687865606718;6247.9062814057825;6339;6230.470410015154;6242.781502647408;6263.279722831876;6267.247930248376;6305.148804911336;5681.630694004897;5724.398938111501;5738.483536481726;5749.655020698771;5772.0476121439915;5722.313322401317;5771.855703607402;5788.5658575606785;5818.738156782955;5822.767089316422;118.5698979168496;5292.232626269189;5601.635893666919;5741.88516229673;5743.023685973395;5622.690752008638;5674.489295963342;5706.115552089433;5727.055035919068;5733.369329373765;5653.406392328788;5665.121906104519;5733.885322293572;5737.655740655574;5752.02215623345;6257.9687101564205;6279.748810047593;6285.371462853724;6309.148264984215;6360.141380913564;6750.852355099645;6825.35159159884;6829.248782633907;6885.758998435031;6926;6798.116244888182;6868.450523958071;6868.519203655746;6877.449804015667;6898.931010689929;6386.744530218739;6401.455876250524;6447.323031081763;6476.74093036278;6478.967605161944;7023.157221133394;7025;7109.111361079865;7111.502194846415;7118.430525557943;6482.286948435668;6592.241892182365;6677.632730199788;6688.933110668831;6774.3225677431865;6876.518643694993;6898.1377327834025;6904.896426553606;6918.411934985583;6929.722811087601;6675.098861653614;6766.25571187169;6776.491763117786;6819.897701534481;6872.347127022925;6766.966165169143;6778.18661760582;6866.690998905061;6879.415249703731;6906.8618627628175;4830.3237546743485;4877.341558889575;4881.487443818419;4882.902341953141;4885.8045678172475;4515.14212299662;4515.2549829277805;4529.048899731093;4589.655775816827;4599.540045995441;6152.538559608047;6230.56386052981;6313.684315784192;6321.146645202839;6321.3994670507145;6729.360710732472;6797.0823938767635;6869.793906182823;6893.138357705287;6894.655267236618;6179.84550386235;6247.687615619201;6253.78111766086;6267.9373206267355;6273.309935907047;5514.944850551524;5523.6409633373705;5523.889522209533;5530.806421775219;5603.5237004854225;7054.788356349318;7070.505064645528;7156.320149585781;7162.033125528092;7175.067241258707;6792.864142717019;6797.422219111332;6807.863842723217;6896.965515172493;6908.067410899464;6936.4797640176175;7030.472714546328;7031.367176954099;7096.822579435349;7104.928950710633;6716.328367163244;6802.795916122524;6818;6822.317768223138;6827.146606674166;6810.931890681228;6815.625140617115;6900.689468973807;6918.273581274095;6920.377166055079;6553.115329430657;6557.180352455943;6599.241087275025;6658.034584985053;6661.333866613494;6511;6632.502562307943;6635.834104147435;6671.766488172891;6686.7993960181275;6499.772507962199;6510.31641677636;6581.308962559054;6585.078089067631;6589.4728421725185;6590.209174899042;6596.109525214035;6613.6693165340575;6626.237982632059;6711.832204194934;6406.455451286693;6411.134496842867;6506.512011599244;6507.511936604868;6508.121403610452 16:09:53 INFO - PID 12709 | |1;ext.html;949.4255975135042;951.457669128597;985.0838719990406;989.3865803202016;999.6101520407038;45031.77484112579;45935;45961.54038459614;46037.53962460374;46069.7696511517;43756;43854;44016.3397549037;44172.55827441725;44272.33591496122;7441.851162976737;7547.584882831469;7551.546907185559;7560.357369623589;7560.848783024337;7414.480986330959;7513.474056816025;7516.849663006737;7519.323260906517;7519.812004699872;7479;7485.139208990954;8084.676612935505;8254.050784159808;8278.379121565877;8178.591070446483;8239.052508961457;8241.752747417588;8248.711295104642;8251.669933202666;7950.880736788953;8032.3172530335;8096.028476582789;8099.635516401776;8132.796680082986;41326.93365331722;41838.790806046076;41877.371839422434;41917.161656767;42010.78994605038;3636.563612366533;3639.927201455956;3643.453481977698;3657.871974480907;3670.3576874046935;32825.50761738576;32827.6717232827;33024.00927972165;33421.16447088818;33700.49449258263;5510.1459273812625;5510.752016159283;5519.834404967857;5554.944450555524;5558.360788509332;220013;220971;221095;221732.8913355439;221976;47950.5204947953;48917.266241006415;53941;54312.45687543153;54396.184057239174;56298.718506407204;56514.71742641343;56533;56549.43450565524;56707.716461417425;16139.919300403422;16255;16257.67484650324;16261.349546018027;16294.42969496062;12985.091043627044;13059.738805223842;13065.150765200422;13072.215667060007;13073.934630326787;14740.557783266519;14746.778798318033;14823.555293341216;14970.101793892401;15129.319180636678;5708.857278568069;5766.192733017381;5839.416058394127;5848.941510584925;5849.151872978395;1108.6563165418745;1110.2894147745449;1114.4929057278919;1119.1047162270152;1127.8984891359817;25939.610905836427;26132.477350452886;26192.34519137037;26342.20973370802;26359.73640263611;1201.6034708546333;1204.747003129335;1223.473906220334;1229.3791635224154;1237.5359240284893;13730.244836534086;13832.290935869067;13911.930440347733;13935.651608709863;14088.788668169986;279106;279944;280808;280968;281373;60148;60167.097493536756;60196;60274.397256027754;60558.69720651457;1515.2953876447284;1536.1627912787558;1559.235974372572;1561.8360072192484;1579.8736101111883;25482.872585636953;25684.357891052874;25919.740802591732;26073.217803465926;26307.736922630527;43365.566344336155;43421.348679769195;43422.56577434185;43493.56506434895;43591.782041089595;234275;234419;234715;236623;236889;33772.831135844164;34332.485012724326;34420.82789586036;36654.63345366512;37533.4369984445;47826.28260576164;48581.27128093152;49220.507794923025;49631.255531167786;49920.75039624924;36202.4569631461;36328;36334.27331453409;36497.817510912275;36549.45175822311;35746;36145.27709445744;36314.63685363113;36688.81655591705;36993.26013479769;41415.171696566504;41592.792036039624;41725.79137104295;41862.79068604638;41990.1601967965;34257.14357141092;34795.82602087074;35063;35151.29697406089;35548.82225588855;26386;26418.73581264163;26512.602310965736;26679.33301667474;27095.86452067727 16:09:53 INFO - PID 12709 | |2;jquery.html;573.7848306884913;577.9653220806752;580.8470186679443;587.3861814403949;601.3565484931124;22954.196603118893;23667;23806.88096559519;23909.521809563797;23966.161184358552;25467.235982920494;25659.486810263785;26157;27337.863310683468;27533.586996195074;6628.834279143024;6770.255271920085;6801.285864984155;6804.761833335834;6805.047293378906;6442.645654489002;6670.833229169285;6710.5302628816;6772.356626120511;6803.047573339736;6630.6021638701595;6632.568883022588;6634.3365663433515;6639.269680335159;6658.866822663544;6604.504662150334;6635.966820165917;6639.3692599203;6914.896276555855;6915.481338899603;6398.264199617057;6405.8398540036405;6407.743690252384;6417.967910160466;6429.967850160766;31772.364552708816;32041.51937720936;32144.196395090075;32185.517217241526;32304.353912921844;2186.6173419651495;2187.9453013674784;2188.321620297713;2189.7810218978134;2208.8674679519117;20285;21743.13027478899;21835.672464913037;21851.672224916638;21854;2500.249925022504;2526.1032333521703;2566.1659960512907;2578.1492107604445;2584.237649893286;135847;141457;150776;154152;155476;15890.205489725467;16003.759943600855;16222.83777162237;16352.75470867938;16720.16399180036;25535.36161595975;25544;25550.488990220092;25582.360440989123;25654;10034.24743144267;10073.445960472245;10194.643187488402;10203.38779673222;10360.637377691895;8949.731508054769;9003;9111.589978450986;9184.63261469547;9254.814903701888;9851.556679949419;9983;10070.44612546317;10156.339837910513;10236.33463824849;2845.69898917255;2891.3494463745487;2913.1843083936533;2925.6781754007043;2947.410517896429;932.2541966426832;949.6438835436711;972.6109556177586;973.4354074636693;974.1573538888811;20053;20344.898275508825;20538.794612053687;20938.47653808667;20983.8950805245;1002.904724051214;1008.7001636269317;1009.4094954451728;1021.9897801022043;1027.6916924922493;10859.565617375374;10939.890601093886;11151.330920144817;11225.158113141548;11283.266587671775;612323;622187;627139;636118;642696;125482.37258813648;127349;128172;130144.34927825301;130581;1239.8822111899356;1288.4781663426327;1294.0294778915813;1294.8122522234223;1300.3303298801125;19085.713714294576;19315.710264346326;19356.806431936064;19533.902330488825;19772.30796922101;61464.38535614587;62815.6859215701;62927.74144516992;63264.36735632769;65977.67011164913;80944.19055809366;81953.59023205085;82353.58823205845;82422.58788706017;83060;15488.690226195187;15841.603959900634;15847.366105355657;15855.127967961873;15882;24374.634380483953;24498.63252051185;24647.876760616084;25038.37404064913;25078.37304067413;19950.700739488628;19984.100715467546;20281.391558253275;20304.59390812146;20409.285675001305;17822.643547129243;18358.541036474195;18387.816121838612;18399.632007360047;18597.163127659016;20699.172033118506;20810.47973800667;21004.57990840144;21081;21092.578148436638;15473.303701333241;15621.297041633381;15639.530814075595;15647.295871685574;15648.295826688054;19755;20167.596648066665;20171.789692617902;20214;20241.898790505955 16:09:53 INFO - PID 12709 | |3;mootools.html;1424.202446629887;1464.4581504843209;1476.6636194244213;1493.4623535527214;1497.8052853129095;16432.753508697366;17045.744313835272;17051.232694528746;17096.14519274036;17105.23026463809;16650.916745416256;16702;16757.329706811743;16785.580360490992;16809.075500847448;2874.9856250718717;2891.0459548348995;2918.6789453160136;2920.970790292102;2923.6491621005403;2746.6566679165103;2850.144956513038;2859.771218302531;2861.141657502751;2861.8712157952837;2782.735640114186;2837.1772186066014;2848.829070255791;2852.1871266689;2853.0442301828807;2661.281454007417;2701.2436517775054;2706.161090062077;2706.228724813419;2706.7563919247364;2627.7240889706686;2636.93407664808;2641.4056837211665;2645.629611854342;2651.840889546633;15834.76247856283;15915.204239787965;16007.759883601755;16096.59758506035;16117.43588974392;8245.216704413071;8481.575921203977;8503.489790612583;8546.70086546974;8551.914480855174;5271.736413179364;5404.4595540446035;5659.60382773206;5868.970655146697;5871.8532036699;2029.1578994717174;2095.769465358809;2487.6268559716004;2572.6398304237423;2597.4805038992276;29527.55708664372;29695.703042969726;29737.553936690965;29751.404971900658;29758.85120574405;7044.37165703828;7152.392046675986;7171.211166771651;7205.351518363373;7692;18824.905875470537;18942.905285473484;18993.62012759737;19017.14422850975;19026.904865475582;8342.62458189383;8368.665253389796;8369.707060252984;8380.24577788002;8390;7472.962635186789;7566.1298950620285;7621.542707437571;7624.695012199561;7625.733099341496;5391.649542779707;5521;5548.86127846799;5582.944170558242;5667.8866422672145;1654.387876485708;1667.7915260592426;1671.1727694791157;1678.5635734709062;1682.0412364952106;947.4220725357526;960.8880754386736;969.0551712080664;973.9269554783419;982.1406269514175;8338.291245244221;8360.540170290571;8360.874586881202;8409.747707568782;8421;775.1821827971544;787.7321710618303;791.9920800792033;796.935935925943;798.3294033012297;5044.798208071709;5162.251473536391;5315.123004704258;5688.260526131659;5764.913526297109;23403.414914627272;23422;24474.510509789703;24482.143124990896;24624.507509849704;24199.274021779376;24231.878840606038;24422.877885610815;24528.754712453003;24581;11923.642290731292;12362.75274494523;12503.49986000568;12514.12401131912;12608.928241099426;8785.297176225758;9009.864852027356;9096.636134554543;9174.67888623895;9193.126652967958;20894.895525522275;21068.367948960942;21224.7877521229;21307;21453.89273053625;21581.24465643758;21668.566628667024;21699.783002169777;21809.23667671624;21953.451163720394;11905.821412678644;11966.58116965902;12005.39973001329;12014.939925300318;12038.398080096134;15000;15231.162286074372;15234.314455849735;15244.542663720105;15250.77123843121;12778.48886044585;12790.61628151157;12863.29251891155;12874.227546347249;12957.22256664603;12002.39988000579;12222.144449888423;12351.073669474647;12509.874901251234;12599;10209.591616335561;10359.171266298828;10391.740206494898;10409.271351005356;10411;7463.290987356192;7501.3998880088375;7501.474896757175;7556.924430755842;7584.658690359058;7526.24737526243;7528.247175282428;7538.773836784905;7625.6949722010495;7720.189380115007 16:09:53 INFO - PID 12709 | |4;prototype.html;523.8449219471064;558.1070287539935;567.3617180671745;568.8293511946416;573.9942600573993;6254.374562543755;6255.343188965156;6265.5927364721265;6330.3036665966765;6425.132607098052;6208.410201030906;6272.435480806726;6339.397757213057;6372.267189273223;6410.391012853783;5122.385313762354;5161.535461808437;5184.792608295664;5186.247994040862;5201.479852014806;5081.678763521492;5084.898302033957;5141.1517099678695;5182.922256166161;5213.556847667934;4802.207635740098;5044.394672639289;5044.672096313746;5087.592992560586;5101.107306221415;4961.404631444232;4994.175960966454;4998.12532806761;5024.773885175176;5031.949680503185;4498.955010449887;4521.502634710179;4533.886652833673;4546.772661366918;4546.931796023029;6040.728167232441;6053.485453736438;6098.481629061535;6099.84750381244;6173.536984726164;5813.360530341659;5857.179994800731;5886.470217680387;5904.586678932476;5926.8814623707285;6637.838378283781;6662.600243985376;6688.361351468872;6700.93299067008;6713.630750308731;5007.2489126630935;5010.849674509771;5028.673136246132;5029.094762942669;5049.469805670426;6793.524453288272;6806.387425131712;6852.5203235773515;6895.55178913369;6926.238113807528;5192.24712416705;5203.531682148625;5217;5226.790928362899;5233.476652334813;5227.843164705065;5246.6065045121;5248.6588371755715;5288.281308574728;5306;5815.592908496449;5832.50423713989;5855.121731740188;5867.735951882176;5874.94125058744;6653.966730166415;6727.495437842083;6753.831154221184;6765.1057703843;6768.627725475048;1822.098061459586;1824.1882362348892;1836.1186630417474;1836.531684420491;1842.68674325365;1202.0586752394995;1280.4238092858252;1281.8461784585722;1282.4805953588811;1293.5731208701106;6076.5442591805795;6151.323354431009;6176.722047507783;6206.348333424925;6266.749330026748;6723.495737819683;6786.253512113663;6787.979349189563;6876.656167191621;6882.8967565486555;5985.024941769206;6122.479589234965;6139.6009259398;6146.78486252979;6148.508119350441;3515.8417871195857;3537.646235376442;3586.1572530455323;3586.5158203642172;3633.6192246945993;7034.859302814018;7047.59048190364;7054.788356349318;7129.714811407589;7137;6700.631465269457;6868.416184624363;6872.381485666214;6893;6904.965475172592;6877.89683154763;6899.724011039502;6935.167779866348;7062.540934839219;7141.392981596622;6588.308227636029;6629.7679581214425;6645.534812563074;6674.9332506676255;6713.832154196184;6661.966690166712;6676.298988606321;6735.831604209934;6755.324467553205;6775.49183811226;6810.353016463428;6810.455163586802;6838.965805170942;6924.826879328057;6965.756198533027;5118.38579370478;5143.665661731976;5148.43367229612;5171.198464238049;5186.974065129651;6511.641859697573;6767.458603311822;6777.695583501633;6808.285130061272;6835.350641689031;6399.488040956805;6552.984287435455;6616.933830661632;6624.735010599522;6726.8990965136545;6501.674916254263;6548.901766473411;6565.949448088283;6578.736850525926;6590.406863382319;6294.8111556653375;6525.347465253627;6572.572782769104;6578.375054369827;6622.403983641495;6541.34586541342;6572.901406478811;6615.54458019235;6703.765368212089;6777.186737591323;6535.5425120241125;6550.475961922939;6553;6565.113709649137;6604.537682362188 16:09:53 INFO - PID 12709 | |5;yui.html;973.1096047116888;993.4784238274905;1010.8028934357799;1017.9338343007704;1026.4457193115716;20712.171513139474;21235.57528849422;21262.14951401944;21288.148474061058;21292.148314067417;19130;19528.902355488204;20215.191392344288;20547.794522054774;20605.27881524147;1047.287844265899;1076.4563895232895;1076.8976947190004;1076.9946150269238;1080.1143062688598;1065.2436769893386;1091.7761858818963;1093.404094768349;1099.0657940750339;1099.5326986030905;1173.929564226145;1176.682295780139;1176.8646605640374;1177.3995262416145;1177.5937301630925;1117.5976648406568;1149.706824759681;1165.7202271454832;1167.1654766841707;1170.3387586013876;828.5898480252254;829.2826704900278;829.3903980574273;830.2030051150903;832.0182185021672;20824.47938801542;21154;21163.365099047052;21191;21198.046087925926;22536.774632253797;23605.29184124462;23660.76339236603;23675.171369002175;23681.881590591936;18728.15723292455;19000.239990400507;19072.33246836354;19073.904630476758;19223.32718354851;3959.5050618672667;4027.3958906164016;4033.515978082649;4051.169510250392;4051.898702532431;159557;159667;162297;163735;164353;17747.82252177462;17846;18061.7290740639;18412.631747365245;18426.170822313026;32633.673663263537;34260.65739342625;34405.31189376199;34568;34740.826295868355;9124.58939347731;9144.8628270576;9153.359264851397;9156.8626470603;9173.35786494939;4388.758618275961;4521.59305662492;4538.432695913011;4552.772361381918;4557.339185818038;5996.760129594854;6110.144579758838;6119.020956646915;6133.509319254449;6152.969235153795;1209.9637010889687;1217.9634610961684;1252.7557126360273;1257.911946163778;1261.8675039120944;703.7079611961082;706.6148948822904;713.8929160625947;721.5165838887958;727.9781606551812;12775.361231938366;12783.61649150527;12790.168639038433;13378.46486140563;13673.726525469436;724.760828926453;728.1880703016204;733.7211859493358;753.1602263476185;754.3211110000933;5775.209246711887;5863.970680146572;5867.325257595431;5882.147088672119;5985.042393217064;141167;144219;145485;145519;148850;119810;120098.39950800365;121065;122538.38730806288;127313;113491.43254283676;113706;114282;114650.42674786573;115166.84833151729;10971.890281097087;11024.338539687644;11156.44217789091;11443.656690299304;11535.538578456768;53384.46615533795;53482;55239.44760552504;55608.72195639158;56022.43977560172;64525;65181.34818651753;65464.34535654773;65586;66266.66866665636;17766.46700598984;17774.46676599704;17858.5535361617;18053.72919406236;18290.72563911569;18291.26834926641;18450.81549184491;18491.722624160917;18607.255709772;18910.52723681919;16812.831871681617;16923.15384230759;16924.492265232064;17013;17032.403865864635;14029.3686784097;14405.135691858522;14541.709165816414;14601.561953141423;14723.852761472677;16239.18804059816;16383.098929558511;16519.33922643081;16584.75122873182;16586.17069146562;11900.464479098287;11989.700257493632;12177.817332740193;12239.632811015685;12414.006879449804;12945.805812912626;12952.481900723866;13029.15310504814;13038.543650972171;13126.081174317706 16:09:53 INFO - PID 12709 | __end_tp_report 16:09:53 INFO - PID 12709 | __start_cc_report 16:09:53 INFO - PID 12709 | _x_x_mozilla_cycle_collect,297 16:09:53 INFO - PID 12709 | __end_cc_report 16:09:53 INFO - PID 12709 | __startTimestamp1505689793169__endTimestamp 16:09:53 INFO - PID 12709 | 16:09:53 INFO - PID 12709 | ------- Summary: start ------- 16:09:53 INFO - PID 12709 | Number of tests: 6 16:09:53 INFO - PID 12709 | 16:09:53 INFO - PID 12709 | [#0] dojo.html Cycles:165 Average:6252.54 Median:6510.32 stddev:891.49 (13.7%) stddev-sans-first:873.85 16:09:53 INFO - PID 12709 | Values: 3836.8 3846.6 3846.8 3868.3 3875.8 6167.6 6210.6 6242.7 6247.9 6339.0 6230.5 6242.8 6263.3 6267.2 6305.1 5681.6 5724.4 5738.5 5749.7 5772.0 5722.3 5771.9 5788.6 5818.7 5822.8 118.6 5292.2 5601.6 5741.9 5743.0 5622.7 5674.5 5706.1 5727.1 5733.4 5653.4 5665.1 5733.9 5737.7 5752.0 6258.0 6279.7 6285.4 6309.1 6360.1 6750.9 6825.4 6829.2 6885.8 6926.0 6798.1 6868.5 6868.5 6877.4 6898.9 6386.7 6401.5 6447.3 6476.7 6479.0 7023.2 7025.0 7109.1 7111.5 7118.4 6482.3 6592.2 6677.6 6688.9 6774.3 6876.5 6898.1 6904.9 6918.4 6929.7 6675.1 6766.3 6776.5 6819.9 6872.3 6767.0 6778.2 6866.7 6879.4 6906.9 4830.3 4877.3 4881.5 4882.9 4885.8 4515.1 4515.3 4529.0 4589.7 4599.5 6152.5 6230.6 6313.7 6321.1 6321.4 6729.4 6797.1 6869.8 6893.1 6894.7 6179.8 6247.7 6253.8 6267.9 6273.3 5514.9 5523.6 5523.9 5530.8 5603.5 7054.8 7070.5 7156.3 7162.0 7175.1 6792.9 6797.4 6807.9 6897.0 6908.1 6936.5 7030.5 7031.4 7096.8 7104.9 6716.3 6802.8 6818.0 6822.3 6827.1 6810.9 6815.6 6900.7 6918.3 6920.4 6553.1 6557.2 6599.2 6658.0 6661.3 6511.0 6632.5 6635.8 6671.8 6686.8 6499.8 6510.3 6581.3 6585.1 6589.5 6590.2 6596.1 6613.7 6626.2 6711.8 6406.5 6411.1 6506.5 6507.5 6508.1 16:09:53 INFO - PID 12709 | 16:09:53 INFO - PID 12709 | [#1] ext.html Cycles:180 Average:42877.56 Median:36671.73 stddev:64221.32 (175.1%) stddev-sans-first:64324.31 16:09:53 INFO - PID 12709 | Values: 949.4 951.5 985.1 989.4 999.6 45031.8 45935.0 45961.5 46037.5 46069.8 43756.0 43854.0 44016.3 44172.6 44272.3 7441.9 7547.6 7551.5 7560.4 7560.8 7414.5 7513.5 7516.8 7519.3 7519.8 7479.0 7485.1 8084.7 8254.1 8278.4 8178.6 8239.1 8241.8 8248.7 8251.7 7950.9 8032.3 8096.0 8099.6 8132.8 41326.9 41838.8 41877.4 41917.2 42010.8 3636.6 3639.9 3643.5 3657.9 3670.4 32825.5 32827.7 33024.0 33421.2 33700.5 5510.1 5510.8 5519.8 5554.9 5558.4 220013.0 220971.0 221095.0 221732.9 221976.0 47950.5 48917.3 53941.0 54312.5 54396.2 56298.7 56514.7 56533.0 56549.4 56707.7 16139.9 16255.0 16257.7 16261.3 16294.4 12985.1 13059.7 13065.2 13072.2 13073.9 14740.6 14746.8 14823.6 14970.1 15129.3 5708.9 5766.2 5839.4 5848.9 5849.2 1108.7 1110.3 1114.5 1119.1 1127.9 25939.6 26132.5 26192.3 26342.2 26359.7 1201.6 1204.7 1223.5 1229.4 1237.5 13730.2 13832.3 13911.9 13935.7 14088.8 279106.0 279944.0 280808.0 280968.0 281373.0 60148.0 60167.1 60196.0 60274.4 60558.7 1515.3 1536.2 1559.2 1561.8 1579.9 25482.9 25684.4 25919.7 26073.2 26307.7 43365.6 43421.3 43422.6 43493.6 43591.8 234275.0 234419.0 234715.0 236623.0 236889.0 33772.8 34332.5 34420.8 36654.6 37533.4 47826.3 48581.3 49220.5 49631.3 49920.8 36202.5 36328.0 36334.3 36497.8 36549.5 35746.0 36145.3 36314.6 36688.8 36993.3 41415.2 41592.8 41725.8 41862.8 41990.2 34257.1 34795.8 35063.0 35151.3 35548.8 26386.0 26418.7 26512.6 26679.3 27095.9 16:09:53 INFO - PID 12709 | 16:09:53 INFO - PID 12709 | [#2] jquery.html Cycles:180 Average:40436.72 Median:23858.20 stddev:104724.03 (438.9%) stddev-sans-first:104975.03 16:09:53 INFO - PID 12709 | Values: 573.8 578.0 580.8 587.4 601.4 22954.2 23667.0 23806.9 23909.5 23966.2 25467.2 25659.5 26157.0 27337.9 27533.6 6628.8 6770.3 6801.3 6804.8 6805.0 6442.6 6670.8 6710.5 6772.4 6803.0 6630.6 6632.6 6634.3 6639.3 6658.9 6604.5 6636.0 6639.4 6914.9 6915.5 6398.3 6405.8 6407.7 6418.0 6430.0 31772.4 32041.5 32144.2 32185.5 32304.4 2186.6 2187.9 2188.3 2189.8 2208.9 20285.0 21743.1 21835.7 21851.7 21854.0 2500.2 2526.1 2566.2 2578.1 2584.2 135847.0 141457.0 150776.0 154152.0 155476.0 15890.2 16003.8 16222.8 16352.8 16720.2 25535.4 25544.0 25550.5 25582.4 25654.0 10034.2 10073.4 10194.6 10203.4 10360.6 8949.7 9003.0 9111.6 9184.6 9254.8 9851.6 9983.0 10070.4 10156.3 10236.3 2845.7 2891.3 2913.2 2925.7 2947.4 932.3 949.6 972.6 973.4 974.2 20053.0 20344.9 20538.8 20938.5 20983.9 1002.9 1008.7 1009.4 1022.0 1027.7 10859.6 10939.9 11151.3 11225.2 11283.3 612323.0 622187.0 627139.0 636118.0 642696.0 125482.4 127349.0 128172.0 130144.3 130581.0 1239.9 1288.5 1294.0 1294.8 1300.3 19085.7 19315.7 19356.8 19533.9 19772.3 61464.4 62815.7 62927.7 63264.4 65977.7 80944.2 81953.6 82353.6 82422.6 83060.0 15488.7 15841.6 15847.4 15855.1 15882.0 24374.6 24498.6 24647.9 25038.4 25078.4 19950.7 19984.1 20281.4 20304.6 20409.3 17822.6 18358.5 18387.8 18399.6 18597.2 20699.2 20810.5 21004.6 21081.0 21092.6 15473.3 15621.3 15639.5 15647.3 15648.3 19755.0 20167.6 20171.8 20214.0 20241.9 16:09:53 INFO - PID 12709 | 16:09:53 INFO - PID 12709 | [#3] mootools.html Cycles:180 Average:10188.40 Median:2681.26 stddev:7544.84 (281.4%) stddev-sans-first:7537.27 16:09:53 INFO - PID 12709 | Values: 1424.2 1464.5 1476.7 1493.5 1497.8 16432.8 17045.7 17051.2 17096.1 17105.2 16650.9 16702.0 16757.3 16785.6 16809.1 2875.0 2891.0 2918.7 2921.0 2923.6 2746.7 2850.1 2859.8 2861.1 2861.9 2782.7 2837.2 2848.8 2852.2 2853.0 2661.3 2701.2 2706.2 2706.2 2706.8 2627.7 2636.9 2641.4 2645.6 2651.8 15834.8 15915.2 16007.8 16096.6 16117.4 8245.2 8481.6 8503.5 8546.7 8551.9 5271.7 5404.5 5659.6 5869.0 5871.9 2029.2 2095.8 2487.6 2572.6 2597.5 29527.6 29695.7 29737.6 29751.4 29758.9 7044.4 7152.4 7171.2 7205.4 7692.0 18824.9 18942.9 18993.6 19017.1 19026.9 8342.6 8368.7 8369.7 8380.2 8390.0 7473.0 7566.1 7621.5 7624.7 7625.7 5391.6 5521.0 5548.9 5582.9 5667.9 1654.4 1667.8 1671.2 1678.6 1682.0 947.4 960.9 969.1 973.9 982.1 8338.3 8360.5 8360.9 8409.7 8421.0 775.2 787.7 792.0 796.9 798.3 5044.8 5162.3 5315.1 5688.3 5764.9 23403.4 23422.0 24474.5 24482.1 24624.5 24199.3 24231.9 24422.9 24528.8 24581.0 11923.6 12362.8 12503.5 12514.1 12608.9 8785.3 9009.9 9096.6 9174.7 9193.1 20894.9 21068.4 21224.8 21307.0 21453.9 21581.2 21668.6 21699.8 21809.2 21953.5 11905.8 11966.6 12005.4 12014.9 12038.4 15000.0 15231.2 15234.3 15244.5 15250.8 12778.5 12790.6 12863.3 12874.2 12957.2 12002.4 12222.1 12351.1 12509.9 12599.0 10209.6 10359.2 10391.7 10409.3 10411.0 7463.3 7501.4 7501.5 7556.9 7584.7 7526.2 7528.2 7538.8 7625.7 7720.2 16:09:53 INFO - PID 12709 | 16:09:53 INFO - PID 12709 | [#4] prototype.html Cycles:180 Average:5647.69 Median:6254.86 stddev:1574.30 (25.2%) stddev-sans-first:1531.02 16:09:53 INFO - PID 12709 | Values: 523.8 558.1 567.4 568.8 574.0 6254.4 6255.3 6265.6 6330.3 6425.1 6208.4 6272.4 6339.4 6372.3 6410.4 5122.4 5161.5 5184.8 5186.2 5201.5 5081.7 5084.9 5141.2 5182.9 5213.6 4802.2 5044.4 5044.7 5087.6 5101.1 4961.4 4994.2 4998.1 5024.8 5031.9 4499.0 4521.5 4533.9 4546.8 4546.9 6040.7 6053.5 6098.5 6099.8 6173.5 5813.4 5857.2 5886.5 5904.6 5926.9 6637.8 6662.6 6688.4 6700.9 6713.6 5007.2 5010.8 5028.7 5029.1 5049.5 6793.5 6806.4 6852.5 6895.6 6926.2 5192.2 5203.5 5217.0 5226.8 5233.5 5227.8 5246.6 5248.7 5288.3 5306.0 5815.6 5832.5 5855.1 5867.7 5874.9 6654.0 6727.5 6753.8 6765.1 6768.6 1822.1 1824.2 1836.1 1836.5 1842.7 1202.1 1280.4 1281.8 1282.5 1293.6 6076.5 6151.3 6176.7 6206.3 6266.7 6723.5 6786.3 6788.0 6876.7 6882.9 5985.0 6122.5 6139.6 6146.8 6148.5 3515.8 3537.6 3586.2 3586.5 3633.6 7034.9 7047.6 7054.8 7129.7 7137.0 6700.6 6868.4 6872.4 6893.0 6905.0 6877.9 6899.7 6935.2 7062.5 7141.4 6588.3 6629.8 6645.5 6674.9 6713.8 6662.0 6676.3 6735.8 6755.3 6775.5 6810.4 6810.5 6839.0 6924.8 6965.8 5118.4 5143.7 5148.4 5171.2 5187.0 6511.6 6767.5 6777.7 6808.3 6835.4 6399.5 6553.0 6616.9 6624.7 6726.9 6501.7 6548.9 6565.9 6578.7 6590.4 6294.8 6525.3 6572.6 6578.4 6622.4 6541.3 6572.9 6615.5 6703.8 6777.2 6535.5 6550.5 6553.0 6565.1 6604.5 16:09:53 INFO - PID 12709 | 16:09:53 INFO - PID 12709 | [#5] yui.html Cycles:180 Average:27583.46 Median:18291.00 stddev:41308.13 (225.8%) stddev-sans-first:41375.69 16:09:53 INFO - PID 12709 | Values: 973.1 993.5 1010.8 1017.9 1026.4 20712.2 21235.6 21262.1 21288.1 21292.1 19130.0 19528.9 20215.2 20547.8 20605.3 1047.3 1076.5 1076.9 1077.0 1080.1 1065.2 1091.8 1093.4 1099.1 1099.5 1173.9 1176.7 1176.9 1177.4 1177.6 1117.6 1149.7 1165.7 1167.2 1170.3 828.6 829.3 829.4 830.2 832.0 20824.5 21154.0 21163.4 21191.0 21198.0 22536.8 23605.3 23660.8 23675.2 23681.9 18728.2 19000.2 19072.3 19073.9 19223.3 3959.5 4027.4 4033.5 4051.2 4051.9 159557.0 159667.0 162297.0 163735.0 164353.0 17747.8 17846.0 18061.7 18412.6 18426.2 32633.7 34260.7 34405.3 34568.0 34740.8 9124.6 9144.9 9153.4 9156.9 9173.4 4388.8 4521.6 4538.4 4552.8 4557.3 5996.8 6110.1 6119.0 6133.5 6153.0 1210.0 1218.0 1252.8 1257.9 1261.9 703.7 706.6 713.9 721.5 728.0 12775.4 12783.6 12790.2 13378.5 13673.7 724.8 728.2 733.7 753.2 754.3 5775.2 5864.0 5867.3 5882.1 5985.0 141167.0 144219.0 145485.0 145519.0 148850.0 119810.0 120098.4 121065.0 122538.4 127313.0 113491.4 113706.0 114282.0 114650.4 115166.8 10971.9 11024.3 11156.4 11443.7 11535.5 53384.5 53482.0 55239.4 55608.7 56022.4 64525.0 65181.3 65464.3 65586.0 66266.7 17766.5 17774.5 17858.6 18053.7 18290.7 18291.3 18450.8 18491.7 18607.3 18910.5 16812.8 16923.2 16924.5 17013.0 17032.4 14029.4 14405.1 14541.7 14601.6 14723.9 16239.2 16383.1 16519.3 16584.8 16586.2 11900.5 11989.7 12177.8 12239.6 12414.0 12945.8 12952.5 13029.2 13038.5 13126.1 16:09:53 INFO - PID 12709 | -------- Summary: end -------- 16:09:53 INFO - PID 12709 | 16:09:53 INFO - TEST-INFO | 12709: exit 0 16:09:53 INFO - TEST-OK | dromaeo_css | took 1108120ms 16:09:53 INFO - TEST-START | kraken 16:09:53 INFO - Initialising browser for kraken test... 16:09:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60641/getInfo.html -profile /tmp/tmpMYH67l/profile 16:09:53 INFO - TEST-INFO | started process 13087 (/builds/slave/test/build/application/firefox/firefox http://localhost:60641/getInfo.html) 16:09:59 INFO - TEST-INFO | 13087: exit 0 16:09:59 INFO - Browser initialized. 16:09:59 INFO - Running cycle 1/1 for kraken test... 16:09:59 INFO - Using env: {'DISPLAY': ':0', 16:09:59 INFO - 'HOME': '/home/cltbld', 16:09:59 INFO - 'JSGC_DISABLE_POISONING': '1', 16:09:59 INFO - 'LANG': 'en_US.UTF-8', 16:09:59 INFO - 'LANGUAGE': 'en_US:en', 16:09:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:09:59 INFO - 'LOGNAME': 'cltbld', 16:09:59 INFO - 'MAIL': '/var/mail/cltbld', 16:09:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:09:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:09:59 INFO - 'MOZ_CRASHREPORTER': '1', 16:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:09:59 INFO - 'MOZ_NO_REMOTE': '1', 16:09:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:09:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:59 INFO - 'NO_EM_RESTART': '1', 16:09:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:59 INFO - 'PWD': '/builds/slave/test', 16:09:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:09:59 INFO - 'RUST_BACKTRACE': 'full', 16:09:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:09:59 INFO - 'SHELL': '/bin/bash', 16:09:59 INFO - 'SHLVL': '1', 16:09:59 INFO - 'TERM': 'linux', 16:09:59 INFO - 'TMOUT': '86400', 16:09:59 INFO - 'USER': 'cltbld', 16:09:59 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446', 16:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:59 INFO - '_': '/tools/buildbot/bin/python'} 16:09:59 INFO - TEST-INFO | started process 13324 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMYH67l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:10:00 INFO - PID 13324 | 16:10:00 INFO - PID 13324 | (/builds/slave/test/build/application/firefox/firefox:13375): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:10:00 INFO - PID 13324 | 16:10:10 INFO - PID 13324 | RSS: Main: 148193280 16:10:10 INFO - PID 13324 | 16:10:13 INFO - PID 13324 | Cycle 1(1): loaded http://localhost:60641/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60641/tests/kraken/driver.html?testName=audio-beat-detection) 16:10:13 INFO - PID 13324 | RSS: Main: 155033600 16:10:13 INFO - PID 13324 | 16:10:16 INFO - PID 13324 | Cycle 1(1): loaded http://localhost:60641/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60641/tests/kraken/driver.html?testName=audio-dft) 16:10:17 INFO - PID 13324 | RSS: Main: 154996736 16:10:17 INFO - PID 13324 | 16:10:19 INFO - PID 13324 | Cycle 1(1): loaded http://localhost:60641/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60641/tests/kraken/driver.html?testName=audio-fft) 16:10:20 INFO - PID 13324 | RSS: Main: 150421504 16:10:20 INFO - PID 13324 | 16:10:22 INFO - PID 13324 | Cycle 1(1): loaded http://localhost:60641/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60641/tests/kraken/driver.html?testName=audio-oscillator) 16:10:23 INFO - PID 13324 | RSS: Main: 151203840 16:10:23 INFO - PID 13324 | 16:10:25 INFO - PID 13324 | Cycle 1(1): loaded http://localhost:60641/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60641/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:10:25 INFO - PID 13324 | RSS: Main: 151449600 16:10:25 INFO - PID 13324 | 16:10:29 INFO - PID 13324 | Cycle 1(1): loaded http://localhost:60641/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60641/tests/kraken/driver.html?testName=imaging-darkroom) 16:10:29 INFO - PID 13324 | RSS: Main: 151269376 16:10:29 INFO - PID 13324 | 16:10:33 INFO - PID 13324 | Cycle 1(1): loaded http://localhost:60641/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60641/tests/kraken/driver.html?testName=imaging-desaturate) 16:10:33 INFO - PID 13324 | RSS: Main: 152240128 16:10:33 INFO - PID 13324 | 16:10:36 INFO - PID 13324 | Cycle 1(1): loaded http://localhost:60641/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60641/tests/kraken/driver.html?testName=json-parse-financial) 16:10:37 INFO - PID 13324 | RSS: Main: 153546752 16:10:37 INFO - PID 13324 | 16:10:38 INFO - PID 13324 | Cycle 1(1): loaded http://localhost:60641/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60641/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:10:39 INFO - PID 13324 | RSS: Main: 153726976 16:10:39 INFO - PID 13324 | 16:10:40 INFO - PID 13324 | Cycle 1(1): loaded http://localhost:60641/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60641/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:10:41 INFO - PID 13324 | RSS: Main: 153788416 16:10:41 INFO - PID 13324 | 16:10:43 INFO - PID 13324 | Cycle 1(1): loaded http://localhost:60641/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60641/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:10:44 INFO - PID 13324 | RSS: Main: 150720512 16:10:44 INFO - PID 13324 | 16:10:46 INFO - PID 13324 | Cycle 1(1): loaded http://localhost:60641/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60641/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:10:47 INFO - PID 13324 | RSS: Main: 150765568 16:10:47 INFO - PID 13324 | 16:10:50 INFO - PID 13324 | Cycle 1(1): loaded http://localhost:60641/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60641/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:10:50 INFO - PID 13324 | RSS: Main: 151048192 16:10:50 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | Cycle 1(1): loaded http://localhost:60641/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60641/tests/kraken/driver.html?testName=ai-astar) 16:10:52 INFO - PID 13324 | RSS: Main: 152621056 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | __start_tp_report 16:10:52 INFO - PID 13324 | _x_x_mozilla_page_load 16:10:52 INFO - PID 13324 | _x_x_mozilla_page_load_details 16:10:52 INFO - PID 13324 | |i|pagename|runs| 16:10:52 INFO - PID 13324 | |0;ai-astar;105;105;104;104;106;98;106;104;104;104 16:10:52 INFO - PID 13324 | |1;audio-beat-detection;112;114;115;113;112;112;113;113;112;113 16:10:52 INFO - PID 13324 | |2;audio-dft;161;162;161;160;161;162;161;163;165;170 16:10:52 INFO - PID 13324 | |3;audio-fft;73;73;73;73;72;75;76;77;74;74 16:10:52 INFO - PID 13324 | |4;audio-oscillator;80;79;78;78;78;78;77;78;77;78 16:10:52 INFO - PID 13324 | |5;imaging-gaussian-blur;113;113;115;112;114;116;114;117;113;110 16:10:52 INFO - PID 13324 | |6;imaging-darkroom;151;148;149;150;149;149;149;150;163;149 16:10:52 INFO - PID 13324 | |7;imaging-desaturate;94;93;93;94;93;93;95;95;94;94 16:10:52 INFO - PID 13324 | |8;json-parse-financial;44;46;43;49;44;45;45;43;42;44 16:10:52 INFO - PID 13324 | |9;json-stringify-tinderbox;39;39;39;42;39;38;39;39;39;39 16:10:52 INFO - PID 13324 | |10;stanford-crypto-aes;72;76;72;71;71;72;74;72;72;75 16:10:52 INFO - PID 13324 | |11;stanford-crypto-ccm;106;103;105;108;108;112;110;112;109;106 16:10:52 INFO - PID 13324 | |12;stanford-crypto-pbkdf2;152;158;153;162;154;153;150;155;152;154 16:10:52 INFO - PID 13324 | |13;stanford-crypto-sha256-iterative;63;61;62;61;83;60;63;65;64;60 16:10:52 INFO - PID 13324 | __end_tp_report 16:10:52 INFO - PID 13324 | __start_cc_report 16:10:52 INFO - PID 13324 | _x_x_mozilla_cycle_collect,693 16:10:52 INFO - PID 13324 | __end_cc_report 16:10:52 INFO - PID 13324 | __startTimestamp1505689852575__endTimestamp 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | ------- Summary: start ------- 16:10:52 INFO - PID 13324 | Number of tests: 14 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | [#0] ai-astar Cycles:10 Average:104.00 Median:105.00 stddev:2.26 (2.2%) stddev-sans-first:2.37 16:10:52 INFO - PID 13324 | Values: 105.0 105.0 104.0 104.0 106.0 98.0 106.0 104.0 104.0 104.0 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | [#1] audio-beat-detection Cycles:10 Average:112.90 Median:113.00 stddev:0.99 (0.9%) stddev-sans-first:1.00 16:10:52 INFO - PID 13324 | Values: 112.0 114.0 115.0 113.0 112.0 112.0 113.0 113.0 112.0 113.0 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | [#2] audio-dft Cycles:10 Average:162.60 Median:162.00 stddev:2.95 (1.8%) stddev-sans-first:3.07 16:10:52 INFO - PID 13324 | Values: 161.0 162.0 161.0 160.0 161.0 162.0 161.0 163.0 165.0 170.0 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | [#3] audio-fft Cycles:10 Average:74.00 Median:74.00 stddev:1.56 (2.1%) stddev-sans-first:1.62 16:10:52 INFO - PID 13324 | Values: 73.0 73.0 73.0 73.0 72.0 75.0 76.0 77.0 74.0 74.0 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | [#4] audio-oscillator Cycles:10 Average:78.10 Median:78.00 stddev:0.88 (1.1%) stddev-sans-first:0.60 16:10:52 INFO - PID 13324 | Values: 80.0 79.0 78.0 78.0 78.0 78.0 77.0 78.0 77.0 78.0 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:2.00 (1.8%) stddev-sans-first:2.11 16:10:52 INFO - PID 13324 | Values: 113.0 113.0 115.0 112.0 114.0 116.0 114.0 117.0 113.0 110.0 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | [#6] imaging-darkroom Cycles:10 Average:150.70 Median:149.50 stddev:4.40 (2.9%) stddev-sans-first:4.66 16:10:52 INFO - PID 13324 | Values: 151.0 148.0 149.0 150.0 149.0 149.0 149.0 150.0 163.0 149.0 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 16:10:52 INFO - PID 13324 | Values: 94.0 93.0 93.0 94.0 93.0 93.0 95.0 95.0 94.0 94.0 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | [#8] json-parse-financial Cycles:10 Average:44.50 Median:44.50 stddev:1.96 (4.4%) stddev-sans-first:2.07 16:10:52 INFO - PID 13324 | Values: 44.0 46.0 43.0 49.0 44.0 45.0 45.0 43.0 42.0 44.0 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | [#9] json-stringify-tinderbox Cycles:10 Average:39.20 Median:39.00 stddev:1.03 (2.6%) stddev-sans-first:1.09 16:10:52 INFO - PID 13324 | Values: 39.0 39.0 39.0 42.0 39.0 38.0 39.0 39.0 39.0 39.0 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | [#10] stanford-crypto-aes Cycles:10 Average:72.70 Median:72.00 stddev:1.70 (2.4%) stddev-sans-first:1.79 16:10:52 INFO - PID 13324 | Values: 72.0 76.0 72.0 71.0 71.0 72.0 74.0 72.0 72.0 75.0 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | [#11] stanford-crypto-ccm Cycles:10 Average:107.90 Median:108.50 stddev:2.96 (2.7%) stddev-sans-first:3.06 16:10:52 INFO - PID 13324 | Values: 106.0 103.0 105.0 108.0 108.0 112.0 110.0 112.0 109.0 106.0 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.30 Median:154.00 stddev:3.43 (2.2%) stddev-sans-first:3.54 16:10:52 INFO - PID 13324 | Values: 152.0 158.0 153.0 162.0 154.0 153.0 150.0 155.0 152.0 154.0 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:63.00 stddev:6.81 (10.8%) stddev-sans-first:7.21 16:10:52 INFO - PID 13324 | Values: 63.0 61.0 62.0 61.0 83.0 60.0 63.0 65.0 64.0 60.0 16:10:52 INFO - PID 13324 | -------- Summary: end -------- 16:10:52 INFO - PID 13324 | 16:10:52 INFO - PID 13324 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:10:53 INFO - TEST-INFO | 13324: exit 0 16:10:53 INFO - TEST-OK | kraken | took 59380ms 16:10:53 INFO - SUITE-END | took 1167s 16:10:53 INFO - Completed test suite (00:19:27) 16:10:53 INFO - javascript benchmark 16:10:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9014.01146979957, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3836.827342769575, 3846.615338466154, 3846.8268927898243, 3868.2650296443658, 3875.8255878485465, 6167.568270221086, 6210.627362358262, 6242.687865606718, 6247.9062814057825, 6339.0, 6230.470410015154, 6242.781502647408, 6263.279722831876, 6267.247930248376, 6305.148804911336, 5681.630694004897, 5724.398938111501, 5738.483536481726, 5749.655020698771, 5772.0476121439915, 5722.313322401317, 5771.855703607402, 5788.5658575606785, 5818.738156782955, 5822.767089316422, 118.5698979168496, 5292.232626269189, 5601.635893666919, 5741.88516229673, 5743.023685973395, 5622.690752008638, 5674.489295963342, 5706.115552089433, 5727.055035919068, 5733.369329373765, 5653.406392328788, 5665.121906104519, 5733.885322293572, 5737.655740655574, 5752.02215623345, 6257.9687101564205, 6279.748810047593, 6285.371462853724, 6309.148264984215, 6360.141380913564, 6750.852355099645, 6825.35159159884, 6829.248782633907, 6885.758998435031, 6926.0, 6798.116244888182, 6868.450523958071, 6868.519203655746, 6877.449804015667, 6898.931010689929, 6386.744530218739, 6401.455876250524, 6447.323031081763, 6476.74093036278, 6478.967605161944, 7023.157221133394, 7025.0, 7109.111361079865, 7111.502194846415, 7118.430525557943, 6482.286948435668, 6592.241892182365, 6677.632730199788, 6688.933110668831, 6774.3225677431865, 6876.518643694993, 6898.1377327834025, 6904.896426553606, 6918.411934985583, 6929.722811087601, 6675.098861653614, 6766.25571187169, 6776.491763117786, 6819.897701534481, 6872.347127022925, 6766.966165169143, 6778.18661760582, 6866.690998905061, 6879.415249703731, 6906.8618627628175, 4830.3237546743485, 4877.341558889575, 4881.487443818419, 4882.902341953141, 4885.8045678172475, 4515.14212299662, 4515.2549829277805, 4529.048899731093, 4589.655775816827, 4599.540045995441, 6152.538559608047, 6230.56386052981, 6313.684315784192, 6321.146645202839, 6321.3994670507145, 6729.360710732472, 6797.0823938767635, 6869.793906182823, 6893.138357705287, 6894.655267236618, 6179.84550386235, 6247.687615619201, 6253.78111766086, 6267.9373206267355, 6273.309935907047, 5514.944850551524, 5523.6409633373705, 5523.889522209533, 5530.806421775219, 5603.5237004854225, 7054.788356349318, 7070.505064645528, 7156.320149585781, 7162.033125528092, 7175.067241258707, 6792.864142717019, 6797.422219111332, 6807.863842723217, 6896.965515172493, 6908.067410899464, 6936.4797640176175, 7030.472714546328, 7031.367176954099, 7096.822579435349, 7104.928950710633, 6716.328367163244, 6802.795916122524, 6818.0, 6822.317768223138, 6827.146606674166, 6810.931890681228, 6815.625140617115, 6900.689468973807, 6918.273581274095, 6920.377166055079, 6553.115329430657, 6557.180352455943, 6599.241087275025, 6658.034584985053, 6661.333866613494, 6511.0, 6632.502562307943, 6635.834104147435, 6671.766488172891, 6686.7993960181275, 6499.772507962199, 6510.31641677636, 6581.308962559054, 6585.078089067631, 6589.4728421725185, 6590.209174899042, 6596.109525214035, 6613.6693165340575, 6626.237982632059, 6711.832204194934, 6406.455451286693, 6411.134496842867, 6506.512011599244, 6507.511936604868, 6508.121403610452], "value": 6192.947779951003, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [949.4255975135042, 951.457669128597, 985.0838719990406, 989.3865803202016, 999.6101520407038, 45031.77484112579, 45935.0, 45961.54038459614, 46037.53962460374, 46069.7696511517, 43756.0, 43854.0, 44016.3397549037, 44172.55827441725, 44272.33591496122, 7441.851162976737, 7547.584882831469, 7551.546907185559, 7560.357369623589, 7560.848783024337, 7414.480986330959, 7513.474056816025, 7516.849663006737, 7519.323260906517, 7519.812004699872, 7479.0, 7485.139208990954, 8084.676612935505, 8254.050784159808, 8278.379121565877, 8178.591070446483, 8239.052508961457, 8241.752747417588, 8248.711295104642, 8251.669933202666, 7950.880736788953, 8032.3172530335, 8096.028476582789, 8099.635516401776, 8132.796680082986, 41326.93365331722, 41838.790806046076, 41877.371839422434, 41917.161656767, 42010.78994605038, 3636.563612366533, 3639.927201455956, 3643.453481977698, 3657.871974480907, 3670.3576874046935, 32825.50761738576, 32827.6717232827, 33024.00927972165, 33421.16447088818, 33700.49449258263, 5510.1459273812625, 5510.752016159283, 5519.834404967857, 5554.944450555524, 5558.360788509332, 220013.0, 220971.0, 221095.0, 221732.8913355439, 221976.0, 47950.5204947953, 48917.266241006415, 53941.0, 54312.45687543153, 54396.184057239174, 56298.718506407204, 56514.71742641343, 56533.0, 56549.43450565524, 56707.716461417425, 16139.919300403422, 16255.0, 16257.67484650324, 16261.349546018027, 16294.42969496062, 12985.091043627044, 13059.738805223842, 13065.150765200422, 13072.215667060007, 13073.934630326787, 14740.557783266519, 14746.778798318033, 14823.555293341216, 14970.101793892401, 15129.319180636678, 5708.857278568069, 5766.192733017381, 5839.416058394127, 5848.941510584925, 5849.151872978395, 1108.6563165418745, 1110.2894147745449, 1114.4929057278919, 1119.1047162270152, 1127.8984891359817, 25939.610905836427, 26132.477350452886, 26192.34519137037, 26342.20973370802, 26359.73640263611, 1201.6034708546333, 1204.747003129335, 1223.473906220334, 1229.3791635224154, 1237.5359240284893, 13730.244836534086, 13832.290935869067, 13911.930440347733, 13935.651608709863, 14088.788668169986, 279106.0, 279944.0, 280808.0, 280968.0, 281373.0, 60148.0, 60167.097493536756, 60196.0, 60274.397256027754, 60558.69720651457, 1515.2953876447284, 1536.1627912787558, 1559.235974372572, 1561.8360072192484, 1579.8736101111883, 25482.872585636953, 25684.357891052874, 25919.740802591732, 26073.217803465926, 26307.736922630527, 43365.566344336155, 43421.348679769195, 43422.56577434185, 43493.56506434895, 43591.782041089595, 234275.0, 234419.0, 234715.0, 236623.0, 236889.0, 33772.831135844164, 34332.485012724326, 34420.82789586036, 36654.63345366512, 37533.4369984445, 47826.28260576164, 48581.27128093152, 49220.507794923025, 49631.255531167786, 49920.75039624924, 36202.4569631461, 36328.0, 36334.27331453409, 36497.817510912275, 36549.45175822311, 35746.0, 36145.27709445744, 36314.63685363113, 36688.81655591705, 36993.26013479769, 41415.171696566504, 41592.792036039624, 41725.79137104295, 41862.79068604638, 41990.1601967965, 34257.14357141092, 34795.82602087074, 35063.0, 35151.29697406089, 35548.82225588855, 26386.0, 26418.73581264163, 26512.602310965736, 26679.33301667474, 27095.86452067727], "value": 18686.35120651534, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.7848306884913, 577.9653220806752, 580.8470186679443, 587.3861814403949, 601.3565484931124, 22954.196603118893, 23667.0, 23806.88096559519, 23909.521809563797, 23966.161184358552, 25467.235982920494, 25659.486810263785, 26157.0, 27337.863310683468, 27533.586996195074, 6628.834279143024, 6770.255271920085, 6801.285864984155, 6804.761833335834, 6805.047293378906, 6442.645654489002, 6670.833229169285, 6710.5302628816, 6772.356626120511, 6803.047573339736, 6630.6021638701595, 6632.568883022588, 6634.3365663433515, 6639.269680335159, 6658.866822663544, 6604.504662150334, 6635.966820165917, 6639.3692599203, 6914.896276555855, 6915.481338899603, 6398.264199617057, 6405.8398540036405, 6407.743690252384, 6417.967910160466, 6429.967850160766, 31772.364552708816, 32041.51937720936, 32144.196395090075, 32185.517217241526, 32304.353912921844, 2186.6173419651495, 2187.9453013674784, 2188.321620297713, 2189.7810218978134, 2208.8674679519117, 20285.0, 21743.13027478899, 21835.672464913037, 21851.672224916638, 21854.0, 2500.249925022504, 2526.1032333521703, 2566.1659960512907, 2578.1492107604445, 2584.237649893286, 135847.0, 141457.0, 150776.0, 154152.0, 155476.0, 15890.205489725467, 16003.759943600855, 16222.83777162237, 16352.75470867938, 16720.16399180036, 25535.36161595975, 25544.0, 25550.488990220092, 25582.360440989123, 25654.0, 10034.24743144267, 10073.445960472245, 10194.643187488402, 10203.38779673222, 10360.637377691895, 8949.731508054769, 9003.0, 9111.589978450986, 9184.63261469547, 9254.814903701888, 9851.556679949419, 9983.0, 10070.44612546317, 10156.339837910513, 10236.33463824849, 2845.69898917255, 2891.3494463745487, 2913.1843083936533, 2925.6781754007043, 2947.410517896429, 932.2541966426832, 949.6438835436711, 972.6109556177586, 973.4354074636693, 974.1573538888811, 20053.0, 20344.898275508825, 20538.794612053687, 20938.47653808667, 20983.8950805245, 1002.904724051214, 1008.7001636269317, 1009.4094954451728, 1021.9897801022043, 1027.6916924922493, 10859.565617375374, 10939.890601093886, 11151.330920144817, 11225.158113141548, 11283.266587671775, 612323.0, 622187.0, 627139.0, 636118.0, 642696.0, 125482.37258813648, 127349.0, 128172.0, 130144.34927825301, 130581.0, 1239.8822111899356, 1288.4781663426327, 1294.0294778915813, 1294.8122522234223, 1300.3303298801125, 19085.713714294576, 19315.710264346326, 19356.806431936064, 19533.902330488825, 19772.30796922101, 61464.38535614587, 62815.6859215701, 62927.74144516992, 63264.36735632769, 65977.67011164913, 80944.19055809366, 81953.59023205085, 82353.58823205845, 82422.58788706017, 83060.0, 15488.690226195187, 15841.603959900634, 15847.366105355657, 15855.127967961873, 15882.0, 24374.634380483953, 24498.63252051185, 24647.876760616084, 25038.37404064913, 25078.37304067413, 19950.700739488628, 19984.100715467546, 20281.391558253275, 20304.59390812146, 20409.285675001305, 17822.643547129243, 18358.541036474195, 18387.816121838612, 18399.632007360047, 18597.163127659016, 20699.172033118506, 20810.47973800667, 21004.57990840144, 21081.0, 21092.578148436638, 15473.303701333241, 15621.297041633381, 15639.530814075595, 15647.295871685574, 15648.295826688054, 19755.0, 20167.596648066665, 20171.789692617902, 20214.0, 20241.898790505955], "value": 12772.707034694991, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.202446629887, 1464.4581504843209, 1476.6636194244213, 1493.4623535527214, 1497.8052853129095, 16432.753508697366, 17045.744313835272, 17051.232694528746, 17096.14519274036, 17105.23026463809, 16650.916745416256, 16702.0, 16757.329706811743, 16785.580360490992, 16809.075500847448, 2874.9856250718717, 2891.0459548348995, 2918.6789453160136, 2920.970790292102, 2923.6491621005403, 2746.6566679165103, 2850.144956513038, 2859.771218302531, 2861.141657502751, 2861.8712157952837, 2782.735640114186, 2837.1772186066014, 2848.829070255791, 2852.1871266689, 2853.0442301828807, 2661.281454007417, 2701.2436517775054, 2706.161090062077, 2706.228724813419, 2706.7563919247364, 2627.7240889706686, 2636.93407664808, 2641.4056837211665, 2645.629611854342, 2651.840889546633, 15834.76247856283, 15915.204239787965, 16007.759883601755, 16096.59758506035, 16117.43588974392, 8245.216704413071, 8481.575921203977, 8503.489790612583, 8546.70086546974, 8551.914480855174, 5271.736413179364, 5404.4595540446035, 5659.60382773206, 5868.970655146697, 5871.8532036699, 2029.1578994717174, 2095.769465358809, 2487.6268559716004, 2572.6398304237423, 2597.4805038992276, 29527.55708664372, 29695.703042969726, 29737.553936690965, 29751.404971900658, 29758.85120574405, 7044.37165703828, 7152.392046675986, 7171.211166771651, 7205.351518363373, 7692.0, 18824.905875470537, 18942.905285473484, 18993.62012759737, 19017.14422850975, 19026.904865475582, 8342.62458189383, 8368.665253389796, 8369.707060252984, 8380.24577788002, 8390.0, 7472.962635186789, 7566.1298950620285, 7621.542707437571, 7624.695012199561, 7625.733099341496, 5391.649542779707, 5521.0, 5548.86127846799, 5582.944170558242, 5667.8866422672145, 1654.387876485708, 1667.7915260592426, 1671.1727694791157, 1678.5635734709062, 1682.0412364952106, 947.4220725357526, 960.8880754386736, 969.0551712080664, 973.9269554783419, 982.1406269514175, 8338.291245244221, 8360.540170290571, 8360.874586881202, 8409.747707568782, 8421.0, 775.1821827971544, 787.7321710618303, 791.9920800792033, 796.935935925943, 798.3294033012297, 5044.798208071709, 5162.251473536391, 5315.123004704258, 5688.260526131659, 5764.913526297109, 23403.414914627272, 23422.0, 24474.510509789703, 24482.143124990896, 24624.507509849704, 24199.274021779376, 24231.878840606038, 24422.877885610815, 24528.754712453003, 24581.0, 11923.642290731292, 12362.75274494523, 12503.49986000568, 12514.12401131912, 12608.928241099426, 8785.297176225758, 9009.864852027356, 9096.636134554543, 9174.67888623895, 9193.126652967958, 20894.895525522275, 21068.367948960942, 21224.7877521229, 21307.0, 21453.89273053625, 21581.24465643758, 21668.566628667024, 21699.783002169777, 21809.23667671624, 21953.451163720394, 11905.821412678644, 11966.58116965902, 12005.39973001329, 12014.939925300318, 12038.398080096134, 15000.0, 15231.162286074372, 15234.314455849735, 15244.542663720105, 15250.77123843121, 12778.48886044585, 12790.61628151157, 12863.29251891155, 12874.227546347249, 12957.22256664603, 12002.39988000579, 12222.144449888423, 12351.073669474647, 12509.874901251234, 12599.0, 10209.591616335561, 10359.171266298828, 10391.740206494898, 10409.271351005356, 10411.0, 7463.290987356192, 7501.3998880088375, 7501.474896757175, 7556.924430755842, 7584.658690359058, 7526.24737526243, 7528.247175282428, 7538.773836784905, 7625.6949722010495, 7720.189380115007], "value": 7137.918840517417, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [523.8449219471064, 558.1070287539935, 567.3617180671745, 568.8293511946416, 573.9942600573993, 6254.374562543755, 6255.343188965156, 6265.5927364721265, 6330.3036665966765, 6425.132607098052, 6208.410201030906, 6272.435480806726, 6339.397757213057, 6372.267189273223, 6410.391012853783, 5122.385313762354, 5161.535461808437, 5184.792608295664, 5186.247994040862, 5201.479852014806, 5081.678763521492, 5084.898302033957, 5141.1517099678695, 5182.922256166161, 5213.556847667934, 4802.207635740098, 5044.394672639289, 5044.672096313746, 5087.592992560586, 5101.107306221415, 4961.404631444232, 4994.175960966454, 4998.12532806761, 5024.773885175176, 5031.949680503185, 4498.955010449887, 4521.502634710179, 4533.886652833673, 4546.772661366918, 4546.931796023029, 6040.728167232441, 6053.485453736438, 6098.481629061535, 6099.84750381244, 6173.536984726164, 5813.360530341659, 5857.179994800731, 5886.470217680387, 5904.586678932476, 5926.8814623707285, 6637.838378283781, 6662.600243985376, 6688.361351468872, 6700.93299067008, 6713.630750308731, 5007.2489126630935, 5010.849674509771, 5028.673136246132, 5029.094762942669, 5049.469805670426, 6793.524453288272, 6806.387425131712, 6852.5203235773515, 6895.55178913369, 6926.238113807528, 5192.24712416705, 5203.531682148625, 5217.0, 5226.790928362899, 5233.476652334813, 5227.843164705065, 5246.6065045121, 5248.6588371755715, 5288.281308574728, 5306.0, 5815.592908496449, 5832.50423713989, 5855.121731740188, 5867.735951882176, 5874.94125058744, 6653.966730166415, 6727.495437842083, 6753.831154221184, 6765.1057703843, 6768.627725475048, 1822.098061459586, 1824.1882362348892, 1836.1186630417474, 1836.531684420491, 1842.68674325365, 1202.0586752394995, 1280.4238092858252, 1281.8461784585722, 1282.4805953588811, 1293.5731208701106, 6076.5442591805795, 6151.323354431009, 6176.722047507783, 6206.348333424925, 6266.749330026748, 6723.495737819683, 6786.253512113663, 6787.979349189563, 6876.656167191621, 6882.8967565486555, 5985.024941769206, 6122.479589234965, 6139.6009259398, 6146.78486252979, 6148.508119350441, 3515.8417871195857, 3537.646235376442, 3586.1572530455323, 3586.5158203642172, 3633.6192246945993, 7034.859302814018, 7047.59048190364, 7054.788356349318, 7129.714811407589, 7137.0, 6700.631465269457, 6868.416184624363, 6872.381485666214, 6893.0, 6904.965475172592, 6877.89683154763, 6899.724011039502, 6935.167779866348, 7062.540934839219, 7141.392981596622, 6588.308227636029, 6629.7679581214425, 6645.534812563074, 6674.9332506676255, 6713.832154196184, 6661.966690166712, 6676.298988606321, 6735.831604209934, 6755.324467553205, 6775.49183811226, 6810.353016463428, 6810.455163586802, 6838.965805170942, 6924.826879328057, 6965.756198533027, 5118.38579370478, 5143.665661731976, 5148.43367229612, 5171.198464238049, 5186.974065129651, 6511.641859697573, 6767.458603311822, 6777.695583501633, 6808.285130061272, 6835.350641689031, 6399.488040956805, 6552.984287435455, 6616.933830661632, 6624.735010599522, 6726.8990965136545, 6501.674916254263, 6548.901766473411, 6565.949448088283, 6578.736850525926, 6590.406863382319, 6294.8111556653375, 6525.347465253627, 6572.572782769104, 6578.375054369827, 6622.403983641495, 6541.34586541342, 6572.901406478811, 6615.54458019235, 6703.765368212089, 6777.186737591323, 6535.5425120241125, 6550.475961922939, 6553.0, 6565.113709649137, 6604.537682362188], "value": 5192.537672472784, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [973.1096047116888, 993.4784238274905, 1010.8028934357799, 1017.9338343007704, 1026.4457193115716, 20712.171513139474, 21235.57528849422, 21262.14951401944, 21288.148474061058, 21292.148314067417, 19130.0, 19528.902355488204, 20215.191392344288, 20547.794522054774, 20605.27881524147, 1047.287844265899, 1076.4563895232895, 1076.8976947190004, 1076.9946150269238, 1080.1143062688598, 1065.2436769893386, 1091.7761858818963, 1093.404094768349, 1099.0657940750339, 1099.5326986030905, 1173.929564226145, 1176.682295780139, 1176.8646605640374, 1177.3995262416145, 1177.5937301630925, 1117.5976648406568, 1149.706824759681, 1165.7202271454832, 1167.1654766841707, 1170.3387586013876, 828.5898480252254, 829.2826704900278, 829.3903980574273, 830.2030051150903, 832.0182185021672, 20824.47938801542, 21154.0, 21163.365099047052, 21191.0, 21198.046087925926, 22536.774632253797, 23605.29184124462, 23660.76339236603, 23675.171369002175, 23681.881590591936, 18728.15723292455, 19000.239990400507, 19072.33246836354, 19073.904630476758, 19223.32718354851, 3959.5050618672667, 4027.3958906164016, 4033.515978082649, 4051.169510250392, 4051.898702532431, 159557.0, 159667.0, 162297.0, 163735.0, 164353.0, 17747.82252177462, 17846.0, 18061.7290740639, 18412.631747365245, 18426.170822313026, 32633.673663263537, 34260.65739342625, 34405.31189376199, 34568.0, 34740.826295868355, 9124.58939347731, 9144.8628270576, 9153.359264851397, 9156.8626470603, 9173.35786494939, 4388.758618275961, 4521.59305662492, 4538.432695913011, 4552.772361381918, 4557.339185818038, 5996.760129594854, 6110.144579758838, 6119.020956646915, 6133.509319254449, 6152.969235153795, 1209.9637010889687, 1217.9634610961684, 1252.7557126360273, 1257.911946163778, 1261.8675039120944, 703.7079611961082, 706.6148948822904, 713.8929160625947, 721.5165838887958, 727.9781606551812, 12775.361231938366, 12783.61649150527, 12790.168639038433, 13378.46486140563, 13673.726525469436, 724.760828926453, 728.1880703016204, 733.7211859493358, 753.1602263476185, 754.3211110000933, 5775.209246711887, 5863.970680146572, 5867.325257595431, 5882.147088672119, 5985.042393217064, 141167.0, 144219.0, 145485.0, 145519.0, 148850.0, 119810.0, 120098.39950800365, 121065.0, 122538.38730806288, 127313.0, 113491.43254283676, 113706.0, 114282.0, 114650.42674786573, 115166.84833151729, 10971.890281097087, 11024.338539687644, 11156.44217789091, 11443.656690299304, 11535.538578456768, 53384.46615533795, 53482.0, 55239.44760552504, 55608.72195639158, 56022.43977560172, 64525.0, 65181.34818651753, 65464.34535654773, 65586.0, 66266.66866665636, 17766.46700598984, 17774.46676599704, 17858.5535361617, 18053.72919406236, 18290.72563911569, 18291.26834926641, 18450.81549184491, 18491.722624160917, 18607.255709772, 18910.52723681919, 16812.831871681617, 16923.15384230759, 16924.492265232064, 17013.0, 17032.403865864635, 14029.3686784097, 14405.135691858522, 14541.709165816414, 14601.561953141423, 14723.852761472677, 16239.18804059816, 16383.098929558511, 16519.33922643081, 16584.75122873182, 16586.17069146562, 11900.464479098287, 11989.700257493632, 12177.817332740193, 12239.632811015685, 12414.006879449804, 12945.805812912626, 12952.481900723866, 13029.15310504814, 13038.543650972171, 13126.081174317706], "value": 9790.962285933507, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1372.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 105.0, 104.0, 104.0, 106.0, 98.0, 106.0, 104.0, 104.0, 104.0], "value": 104.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 115.0, 113.0, 112.0, 112.0, 113.0, 113.0, 112.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 160.0, 161.0, 162.0, 161.0, 163.0, 165.0, 170.0], "value": 162.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 73.0, 72.0, 75.0, 76.0, 77.0, 74.0, 74.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 78.0, 78.0, 78.0, 78.0, 77.0, 78.0, 77.0, 78.0], "value": 78.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 115.0, 112.0, 114.0, 116.0, 114.0, 117.0, 113.0, 110.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 148.0, 149.0, 150.0, 149.0, 149.0, 149.0, 150.0, 163.0, 149.0], "value": 150.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 94.0, 93.0, 93.0, 95.0, 95.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 46.0, 43.0, 49.0, 44.0, 45.0, 45.0, 43.0, 42.0, 44.0], "value": 44.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 39.0, 42.0, 39.0, 38.0, 39.0, 39.0, 39.0, 39.0], "value": 39.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 76.0, 72.0, 71.0, 71.0, 72.0, 74.0, 72.0, 72.0, 75.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 103.0, 105.0, 108.0, 108.0, 112.0, 110.0, 112.0, 109.0, 106.0], "value": 107.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 158.0, 153.0, 162.0, 154.0, 153.0, 150.0, 155.0, 152.0, 154.0], "value": 154.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 62.0, 61.0, 83.0, 60.0, 63.0, 65.0, 64.0, 60.0], "value": 64.2, "unit": "score"}]}]} 16:10:53 INFO - Return code: 0 16:10:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:10:53 INFO - # TBPL SUCCESS # 16:10:53 INFO - Running post-action listener: _package_coverage_data 16:10:53 INFO - Running post-action listener: _resource_record_post_action 16:10:53 INFO - [mozharness: 2017-09-17 23:10:53.347961Z] Finished run-tests step (success) 16:10:53 INFO - Running post-run listener: _resource_record_post_run 16:10:53 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 163840; Write bytes: 390053888; Read time: 452; Write time: 215132 16:10:53 INFO - TinderboxPrint: CPU usage
13.1% 16:10:53 INFO - TinderboxPrint: I/O read bytes / time
163,840 / 452 16:10:53 INFO - TinderboxPrint: I/O write bytes / time
390,053,888 / 215,132 16:10:53 INFO - TinderboxPrint: CPU idle
8,215.2 (86.9%) 16:10:53 INFO - TinderboxPrint: CPU user
1,185.8 (12.5%) 16:10:53 INFO - TinderboxPrint: Swap in / out
0 / 0 16:10:53 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 16384; Write bytes: 75239424; Read time: 164; Write time: 30020 16:10:53 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:10:53 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 147456; Write bytes: 314814464; Read time: 288; Write time: 185112 16:10:53 INFO - Running post-run listener: _upload_blobber_files 16:10:53 INFO - Blob upload gear active. 16:10:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:10:53 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 16:10:53 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:10:53 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:10:54 INFO - (blobuploader) - INFO - Open directory for files ... 16:10:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:10:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:10:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:55 INFO - (blobuploader) - INFO - Done attempting. 16:10:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:10:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:10:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:56 INFO - (blobuploader) - INFO - Done attempting. 16:10:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:10:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:11:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:03 INFO - (blobuploader) - INFO - Done attempting. 16:11:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:11:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:11:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:04 INFO - (blobuploader) - INFO - Done attempting. 16:11:04 INFO - (blobuploader) - INFO - Iteration through files over. 16:11:04 INFO - Return code: 0 16:11:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:11:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:11:04 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4732534f4fe114e03e566de308109797a8b2282cc0154688eafa1ae31c534b44c3747ac810a103fb9769c6bd975500319d89260d4da707d8347f30d155368e14", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/555fd902594944032031ad21b52e43fb6417042844761ba135b111837abba81fa0235417d9e7591f4a5ed4f1d36c0cec2ebf302c2297ccfeddcaec564cc22eb6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e33cf78494dae1b54076d160d7786f4be9a7689f6c951c3ce0e5b909adab38728290b130c2c934bbaed8296fa5f35707b6016ccb9cf8e44c02d512168e33da20", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa399d25293c410a7385041f11a1fc546f881d0726024779ec1830cce7bd554c7f08ad4a17e26707db581876a29d1cd9ffd422d4b5c71b370cc17457a76b6bfa"} 16:11:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:11:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:11:04 INFO - Contents: 16:11:04 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4732534f4fe114e03e566de308109797a8b2282cc0154688eafa1ae31c534b44c3747ac810a103fb9769c6bd975500319d89260d4da707d8347f30d155368e14", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/555fd902594944032031ad21b52e43fb6417042844761ba135b111837abba81fa0235417d9e7591f4a5ed4f1d36c0cec2ebf302c2297ccfeddcaec564cc22eb6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e33cf78494dae1b54076d160d7786f4be9a7689f6c951c3ce0e5b909adab38728290b130c2c934bbaed8296fa5f35707b6016ccb9cf8e44c02d512168e33da20", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa399d25293c410a7385041f11a1fc546f881d0726024779ec1830cce7bd554c7f08ad4a17e26707db581876a29d1cd9ffd422d4b5c71b370cc17457a76b6bfa"} 16:11:04 INFO - Running post-run listener: copy_logs_to_upload_dir 16:11:04 INFO - Copying logs to upload dir... 16:11:04 INFO - mkdir: /builds/slave/test/build/upload/logs 16:11:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.924054 ========= master_lag: -0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-17 16:11:04.325520) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-17 16:11:04.329750) ========= 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=a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4732534f4fe114e03e566de308109797a8b2282cc0154688eafa1ae31c534b44c3747ac810a103fb9769c6bd975500319d89260d4da707d8347f30d155368e14", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/555fd902594944032031ad21b52e43fb6417042844761ba135b111837abba81fa0235417d9e7591f4a5ed4f1d36c0cec2ebf302c2297ccfeddcaec564cc22eb6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e33cf78494dae1b54076d160d7786f4be9a7689f6c951c3ce0e5b909adab38728290b130c2c934bbaed8296fa5f35707b6016ccb9cf8e44c02d512168e33da20", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa399d25293c410a7385041f11a1fc546f881d0726024779ec1830cce7bd554c7f08ad4a17e26707db581876a29d1cd9ffd422d4b5c71b370cc17457a76b6bfa"} build_url:https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011385 build_url: 'https://queue.taskcluster.net/v1/task/Tm_s3FMBRbu6xkB0TPt4pA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4732534f4fe114e03e566de308109797a8b2282cc0154688eafa1ae31c534b44c3747ac810a103fb9769c6bd975500319d89260d4da707d8347f30d155368e14", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/555fd902594944032031ad21b52e43fb6417042844761ba135b111837abba81fa0235417d9e7591f4a5ed4f1d36c0cec2ebf302c2297ccfeddcaec564cc22eb6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e33cf78494dae1b54076d160d7786f4be9a7689f6c951c3ce0e5b909adab38728290b130c2c934bbaed8296fa5f35707b6016ccb9cf8e44c02d512168e33da20", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa399d25293c410a7385041f11a1fc546f881d0726024779ec1830cce7bd554c7f08ad4a17e26707db581876a29d1cd9ffd422d4b5c71b370cc17457a76b6bfa"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-17 16:11:04.358321) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 16:11:04.358661) ========= 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=a0d2d2de893abaa8702a9954000001a7-1505688137.108791-925353446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004588 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 16:11:04.444284) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-17 16:11:04.444614) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-17 16:11:04.444980) ========= ========= Total master_lag: 0.20 =========