builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-064 starttime: 1503934731.54 results: success (0) revision: d5b6d113cf17f4c91b574eaa2d077a233bc4bc69 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 08:38:51.543865) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 08:38:51.544337) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 08:38:51.544612) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 08:38:51.558557) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:38:51.558968) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-28 08:38:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.5M=0s 2017-08-28 08:38:51 (42.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.089637 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:38:51.664174) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:38:51.664502) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019514 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:38:51.714710) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:38:51.715122) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d5b6d113cf17f4c91b574eaa2d077a233bc4bc69 --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 d5b6d113cf17f4c91b574eaa2d077a233bc4bc69 --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=a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-28 08:38:51,561 truncating revision to first 12 chars 2017-08-28 08:38:51,561 Setting DEBUG logging. 2017-08-28 08:38:51,561 attempt 1/10 2017-08-28 08:38:51,561 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d5b6d113cf17?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-28 08:38:52,177 unpacking tar archive at: mozilla-central-d5b6d113cf17/testing/mozharness/ program finished with exit code 0 elapsedTime=0.953263 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:38:52.683826) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 08:38:52.684158) ========= script_repo_revision: d5b6d113cf17f4c91b574eaa2d077a233bc4bc69 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 08:38:52.684583) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 08:38:52.684856) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 08:38:52.696710) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-28 08:38:52.697099) ========= /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=a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:38:52 INFO - MultiFileLogger online at 20170828 08:38:52 in /builds/slave/test 08:38:52 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 08:38:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:38:52 INFO - {'append_to_log': False, 08:38:52 INFO - 'base_work_dir': '/builds/slave/test', 08:38:52 INFO - 'blob_upload_branch': 'Firefox', 08:38:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:38:52 INFO - 'branch': 'Firefox', 08:38:52 INFO - 'buildbot_json_path': 'buildprops.json', 08:38:52 INFO - 'code_coverage': False, 08:38:52 INFO - 'config_files': ('talos/linux_config.py',), 08:38:52 INFO - 'default_actions': ('clobber', 08:38:52 INFO - 'read-buildbot-config', 08:38:52 INFO - 'download-and-extract', 08:38:52 INFO - 'populate-webroot', 08:38:52 INFO - 'create-virtualenv', 08:38:52 INFO - 'install', 08:38:52 INFO - 'setup-mitmproxy', 08:38:52 INFO - 'run-tests'), 08:38:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:38:52 INFO - 'disable_ccov_upload': False, 08:38:52 INFO - 'download_minidump_stackwalk': True, 08:38:52 INFO - 'download_symbols': 'ondemand', 08:38:52 INFO - 'enable_stylo': False, 08:38:52 INFO - 'enable_webrender': False, 08:38:52 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 08:38:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:38:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:38:52 INFO - 'gecko_profile': False, 08:38:52 INFO - 'gecko_profile_interval': 0, 08:38:52 INFO - 'installer_path': 'installer.exe', 08:38:52 INFO - 'log_level': 'info', 08:38:52 INFO - 'log_name': 'talos', 08:38:52 INFO - 'log_to_console': True, 08:38:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:38:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:38:52 INFO - 'opt_config_files': (), 08:38:52 INFO - 'pip_index': False, 08:38:52 INFO - 'suite': 'dromaeojs-e10s', 08:38:52 INFO - 'system_bits': '32', 08:38:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:38:52 INFO - 'title': 'talos-linux64-ix-064', 08:38:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:38:52 INFO - 'use_talos_json': True, 08:38:52 INFO - 'verify': 'False', 08:38:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:38:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:38:52 INFO - 'work_dir': 'build'} 08:38:52 INFO - [mozharness: 2017-08-28 15:38:52.685014Z] Running clobber step. 08:38:52 INFO - Running pre-action listener: _resource_record_pre_action 08:38:52 INFO - Running main action method: clobber 08:38:52 INFO - rmtree: /builds/slave/test/build 08:38:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:38:53 INFO - Running post-action listener: _resource_record_post_action 08:38:53 INFO - [mozharness: 2017-08-28 15:38:53.809056Z] Finished clobber step (success) 08:38:53 INFO - [mozharness: 2017-08-28 15:38:53.809173Z] Running read-buildbot-config step. 08:38:53 INFO - Running pre-action listener: _resource_record_pre_action 08:38:53 INFO - Running main action method: read_buildbot_config 08:38:53 INFO - Using buildbot properties: 08:38:53 INFO - { 08:38:53 INFO - "project": "", 08:38:53 INFO - "product": "firefox", 08:38:53 INFO - "who": "archaeopteryx@coole-files.de", 08:38:53 INFO - "installer_path": "public/build/target.tar.bz2", 08:38:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 08:38:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 08:38:53 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 08:38:53 INFO - "stage_platform": "linux64-stylosequential", 08:38:53 INFO - "basedir": "/builds/slave/test", 08:38:53 INFO - "buildnumber": 215, 08:38:53 INFO - "platform": "ubuntu64_hw_styloseq", 08:38:53 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:38:53 INFO - "slavebuilddir": "test", 08:38:53 INFO - "taskId": "RK87M8wZSKCvzbTj587ZyA", 08:38:53 INFO - "branch": "mozilla-central", 08:38:53 INFO - "script_repo_revision": "production", 08:38:53 INFO - "revision": "d5b6d113cf17f4c91b574eaa2d077a233bc4bc69", 08:38:53 INFO - "slavename": "talos-linux64-ix-064", 08:38:53 INFO - "repo_path": "mozilla-central" 08:38:53 INFO - } 08:38:53 INFO - Finding installer, test and symbols from parent task. 08:38:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RK87M8wZSKCvzbTj587ZyA'}, attempt #1 08:38:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RK87M8wZSKCvzbTj587ZyA'}, attempt #1 08:38:54 INFO - Task dependencies: Sv4qr0ycSAaRWiVssPK9WA 08:38:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA'}, attempt #1 08:38:55 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2 08:38:55 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.test_packages.json 08:38:55 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.crashreporter-symbols.zip 08:38:55 INFO - Running post-action listener: _resource_record_post_action 08:38:55 INFO - [mozharness: 2017-08-28 15:38:55.188845Z] Finished read-buildbot-config step (success) 08:38:55 INFO - [mozharness: 2017-08-28 15:38:55.189061Z] Running download-and-extract step. 08:38:55 INFO - Running pre-action listener: _resource_record_pre_action 08:38:55 INFO - Running main action method: download_and_extract 08:38:55 INFO - mkdir: /builds/slave/test/build/tests 08:38: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')]} 08:38:55 INFO - trying https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.test_packages.json 08:38:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:38:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:38:55 INFO - Downloaded 989 bytes. 08:38:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:38:55 INFO - Using the following test package requirements: 08:38:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:38:55 INFO - u'common': [u'target.common.tests.zip'], 08:38:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:38:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:38:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:38:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:38:55 INFO - u'mozbase': [u'target.common.tests.zip'], 08:38:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:38:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:38:55 INFO - u'web-platform': [u'target.common.tests.zip', 08:38:55 INFO - u'target.web-platform.tests.tar.gz'], 08:38:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:38:55 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:38:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.common.tests.zip 08:38:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:38:55 INFO - Fetch https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.common.tests.zip into memory 08:38:56 INFO - Content-Length response header: 54977971 08:38:56 INFO - Bytes received: 54977971 08:39:07 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:39:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.common.tests.zip 08:39:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:39:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.common.tests.zip into memory 08:39:08 INFO - Content-Length response header: 54977971 08:39:08 INFO - Bytes received: 54977971 08:39:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.talos.tests.zip 08:39:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:39:20 INFO - Fetch https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.talos.tests.zip into memory 08:39:21 INFO - Content-Length response header: 14496611 08:39:21 INFO - Bytes received: 14496611 08:39:22 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')]} 08:39:22 INFO - trying https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2 08:39:22 INFO - Downloading https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:39:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:39:23 INFO - Downloaded 61081399 bytes. 08:39:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2 08:39:23 INFO - mkdir: /builds/slave/test/properties 08:39:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:39:23 INFO - Writing to file /builds/slave/test/properties/build_url 08:39:23 INFO - Contents: 08:39:23 INFO - build_url:https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2 08:39:23 INFO - Running post-action listener: _resource_record_post_action 08:39:23 INFO - Running post-action listener: find_tests_for_verification 08:39:23 INFO - Running post-action listener: set_extra_try_arguments 08:39:23 INFO - [mozharness: 2017-08-28 15:39:23.159089Z] Finished download-and-extract step (success) 08:39:23 INFO - [mozharness: 2017-08-28 15:39:23.159184Z] Running populate-webroot step. 08:39:23 INFO - Running pre-action listener: _resource_record_pre_action 08:39:23 INFO - Running main action method: populate_webroot 08:39:23 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:39:23 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 08:39:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:39:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:39:23 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 08:39:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:39:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:39:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:39:23 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:39:23 INFO - 'talos_options': ['--stylo'], 08:39:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:39:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:39:23 INFO - 'tests': ['damp', 'tps']}, 08:39:23 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:39:23 INFO - 'talos_options': ['--stylo'], 08:39:23 INFO - 'tests': ['damp', 'tps']}, 08:39:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:39:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:39:23 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 08:39:23 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:39:23 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:39:23 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:39:23 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:39:23 INFO - 'talos_options': ['--stylo'], 08:39:23 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:39:23 INFO - 'other-e10s': {'tests': ['a11yr', 08:39:23 INFO - 'ts_paint', 08:39:23 INFO - 'tpaint', 08:39:23 INFO - 'sessionrestore', 08:39:23 INFO - 'sessionrestore_many_windows', 08:39:23 INFO - 'sessionrestore_no_auto_restore', 08:39:23 INFO - 'tabpaint']}, 08:39:23 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 08:39:23 INFO - 'tests': ['a11yr', 08:39:23 INFO - 'ts_paint', 08:39:23 INFO - 'tpaint', 08:39:23 INFO - 'sessionrestore', 08:39:23 INFO - 'sessionrestore_many_windows', 08:39:23 INFO - 'sessionrestore_no_auto_restore', 08:39:23 INFO - 'tabpaint']}, 08:39:23 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 08:39:23 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 08:39:23 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 08:39:23 INFO - 'tests': ['bloom_basic_singleton']}, 08:39:23 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 08:39:23 INFO - 'tests': ['bloom_basic']}, 08:39:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:39:23 INFO - 'tsvgr_opacity', 08:39:23 INFO - 'tart', 08:39:23 INFO - 'tscrollx', 08:39:23 INFO - 'tsvg_static']}, 08:39:23 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 08:39:23 INFO - 'tests': ['tsvgx', 08:39:23 INFO - 'tsvgr_opacity', 08:39:23 INFO - 'tart', 08:39:23 INFO - 'tscrollx', 08:39:23 INFO - 'tsvg_static']}, 08:39:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:39:23 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:39:23 INFO - 'talos_options': ['--stylo'], 08:39:23 INFO - 'tests': ['tp5o']}, 08:39:23 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:39:23 INFO - 'talos_options': ['--mitmproxy', 08:39:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:39:23 INFO - '--firstNonBlankPaint'], 08:39:23 INFO - 'tests': ['tp6_google', 08:39:23 INFO - 'tp6_youtube', 08:39:23 INFO - 'tp6_amazon', 08:39:23 INFO - 'tp6_facebook']}, 08:39:23 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:39:23 INFO - 'talos_options': ['--stylo', 08:39:23 INFO - '--mitmproxy', 08:39:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:39:23 INFO - '--firstNonBlankPaint'], 08:39:23 INFO - 'tests': ['tp6_google', 08:39:23 INFO - 'tp6_youtube', 08:39:23 INFO - 'tp6_amazon', 08:39:23 INFO - 'tp6_facebook']}, 08:39:23 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:39:23 INFO - 'talos_options': ['--stylo', 08:39:23 INFO - '--stylo-threads=1', 08:39:23 INFO - '--mitmproxy', 08:39:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:39:23 INFO - '--firstNonBlankPaint'], 08:39:23 INFO - 'tests': ['tp6_google', 08:39:23 INFO - 'tp6_youtube', 08:39:23 INFO - 'tp6_amazon', 08:39:23 INFO - 'tp6_facebook']}, 08:39:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:39:23 INFO - 'talos_options': ['--xperf_path', 08:39:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:39:23 INFO - 'tests': ['tp5n']}, 08:39:23 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:39:23 INFO - 'talos_options': ['--stylo', 08:39:23 INFO - '--xperf_path', 08:39:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:39:23 INFO - 'tests': ['tp5n']}}} 08:39:23 INFO - Running post-action listener: _resource_record_post_action 08:39:23 INFO - [mozharness: 2017-08-28 15:39:23.173348Z] Finished populate-webroot step (success) 08:39:23 INFO - [mozharness: 2017-08-28 15:39:23.173437Z] Running create-virtualenv step. 08:39:23 INFO - Running pre-action listener: _resource_record_pre_action 08:39:23 INFO - Running main action method: create_virtualenv 08:39:23 INFO - Creating virtualenv /builds/slave/test/build/venv 08:39:23 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:39:23 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 08:39:23 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:39:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:39:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:39:23 INFO - Installing distribute.............................................................................................................................................................................................done. 08:39:24 INFO - Installing pip...............done. 08:39:24 INFO - Return code: 0 08:39:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:39:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:39:24 INFO - Reading from file tmpfile_stdout 08:39:24 INFO - Output received: 08:39:24 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 08:39:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b130>, '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 0x201c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c4c0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', '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 08:39:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:39:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:39:24 INFO - Using env: {'DISPLAY': ':0', 08:39:24 INFO - 'HOME': '/home/cltbld', 08:39:24 INFO - 'LANG': 'en_US.UTF-8', 08:39:24 INFO - 'LANGUAGE': 'en_US:en', 08:39:24 INFO - 'LOGNAME': 'cltbld', 08:39:24 INFO - 'MAIL': '/var/mail/cltbld', 08:39:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:24 INFO - 'MOZ_NO_REMOTE': '1', 08:39:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:24 INFO - 'NO_EM_RESTART': '1', 08:39:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:24 INFO - 'PWD': '/builds/slave/test', 08:39:24 INFO - 'SHELL': '/bin/bash', 08:39:24 INFO - 'SHLVL': '1', 08:39:24 INFO - 'TERM': 'linux', 08:39:24 INFO - 'TMOUT': '86400', 08:39:24 INFO - 'USER': 'cltbld', 08:39:24 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', 08:39:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:24 INFO - '_': '/tools/buildbot/bin/python'} 08:39:25 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:39:25 INFO - Downloading/unpacking pip>=1.5 08:39:25 INFO - Running setup.py egg_info for package pip 08:39:25 INFO - warning: no files found matching 'pip/cacert.pem' 08:39:25 INFO - warning: no files found matching '*.html' under directory 'docs' 08:39:25 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:39:25 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:39:25 INFO - Installing collected packages: pip 08:39:25 INFO - Found existing installation: pip 1.1 08:39:25 INFO - Uninstalling pip: 08:39:25 INFO - Successfully uninstalled pip 08:39:25 INFO - Running setup.py install for pip 08:39:25 INFO - warning: no files found matching 'pip/cacert.pem' 08:39:25 INFO - warning: no files found matching '*.html' under directory 'docs' 08:39:25 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:39:25 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:39:25 INFO - Installing pip script to /builds/slave/test/build/venv/bin 08:39:25 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 08:39:25 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 08:39:25 INFO - Successfully installed pip 08:39:25 INFO - Cleaning up... 08:39:25 INFO - Return code: 0 08:39:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:39: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')]} 08:39:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39: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')]} 08:39:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:25 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 0x1caf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b130>, '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 0x201c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c4c0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', '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 08:39:25 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 08:39:25 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 08:39:25 INFO - Using env: {'DISPLAY': ':0', 08:39:25 INFO - 'HOME': '/home/cltbld', 08:39:25 INFO - 'LANG': 'en_US.UTF-8', 08:39:25 INFO - 'LANGUAGE': 'en_US:en', 08:39:25 INFO - 'LOGNAME': 'cltbld', 08:39:25 INFO - 'MAIL': '/var/mail/cltbld', 08:39:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:25 INFO - 'MOZ_NO_REMOTE': '1', 08:39:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:25 INFO - 'NO_EM_RESTART': '1', 08:39:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:25 INFO - 'PWD': '/builds/slave/test', 08:39:25 INFO - 'SHELL': '/bin/bash', 08:39:25 INFO - 'SHLVL': '1', 08:39:25 INFO - 'TERM': 'linux', 08:39:25 INFO - 'TMOUT': '86400', 08:39:25 INFO - 'USER': 'cltbld', 08:39:25 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', 08:39:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:25 INFO - '_': '/tools/buildbot/bin/python'} 08:39:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:25 INFO - Downloading/unpacking psutil>=3.1.1 08:39:25 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 08:39:25 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 08:39:25 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 08:39:25 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 08:39:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:39:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:39:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:39:26 INFO - Installing collected packages: psutil 08:39:26 INFO - Running setup.py install for psutil 08:39:26 INFO - building 'psutil._psutil_linux' extension 08:39:26 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 08:39:27 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:39:27 INFO - building 'psutil._psutil_posix' extension 08:39:27 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:39:27 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:39:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:39:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:39:27 INFO - Successfully installed psutil 08:39:27 INFO - Cleaning up... 08:39:27 INFO - Return code: 0 08:39:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:39:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b130>, '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 0x201c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c4c0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', '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 08:39:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:39:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:39:27 INFO - Using env: {'DISPLAY': ':0', 08:39:27 INFO - 'HOME': '/home/cltbld', 08:39:27 INFO - 'LANG': 'en_US.UTF-8', 08:39:27 INFO - 'LANGUAGE': 'en_US:en', 08:39:27 INFO - 'LOGNAME': 'cltbld', 08:39:27 INFO - 'MAIL': '/var/mail/cltbld', 08:39:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:27 INFO - 'MOZ_NO_REMOTE': '1', 08:39:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:27 INFO - 'NO_EM_RESTART': '1', 08:39:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:27 INFO - 'PWD': '/builds/slave/test', 08:39:27 INFO - 'SHELL': '/bin/bash', 08:39:27 INFO - 'SHLVL': '1', 08:39:27 INFO - 'TERM': 'linux', 08:39:27 INFO - 'TMOUT': '86400', 08:39:27 INFO - 'USER': 'cltbld', 08:39:27 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', 08:39:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:27 INFO - '_': '/tools/buildbot/bin/python'} 08:39:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:39:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:39:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:39:28 INFO - Installing collected packages: mozsystemmonitor 08:39:28 INFO - Running setup.py install for mozsystemmonitor 08:39:28 INFO - Successfully installed mozsystemmonitor 08:39:28 INFO - Cleaning up... 08:39:28 INFO - Return code: 0 08:39:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b130>, '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 0x201c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c4c0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', '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 08:39:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:39:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:39:28 INFO - Using env: {'DISPLAY': ':0', 08:39:28 INFO - 'HOME': '/home/cltbld', 08:39:28 INFO - 'LANG': 'en_US.UTF-8', 08:39:28 INFO - 'LANGUAGE': 'en_US:en', 08:39:28 INFO - 'LOGNAME': 'cltbld', 08:39:28 INFO - 'MAIL': '/var/mail/cltbld', 08:39:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:28 INFO - 'MOZ_NO_REMOTE': '1', 08:39:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:28 INFO - 'NO_EM_RESTART': '1', 08:39:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:28 INFO - 'PWD': '/builds/slave/test', 08:39:28 INFO - 'SHELL': '/bin/bash', 08:39:28 INFO - 'SHLVL': '1', 08:39:28 INFO - 'TERM': 'linux', 08:39:28 INFO - 'TMOUT': '86400', 08:39:28 INFO - 'USER': 'cltbld', 08:39:28 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', 08:39:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:28 INFO - '_': '/tools/buildbot/bin/python'} 08:39:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:29 INFO - Downloading/unpacking jsonschema==2.5.1 08:39:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:39:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:30 INFO - Downloading functools32-3.2.3-2.tar.gz 08:39:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:39:30 INFO - warning: no files found matching '*.txt' 08:39:30 INFO - no previously-included directories found matching 'build' 08:39:30 INFO - no previously-included directories found matching 'dist' 08:39:30 INFO - no previously-included directories found matching '.git*' 08:39:30 INFO - Installing collected packages: jsonschema, functools32 08:39:30 INFO - Running setup.py install for functools32 08:39:30 INFO - warning: no files found matching '*.txt' 08:39:30 INFO - no previously-included directories found matching 'build' 08:39:30 INFO - no previously-included directories found matching 'dist' 08:39:30 INFO - no previously-included directories found matching '.git*' 08:39:30 INFO - Successfully installed jsonschema functools32 08:39:30 INFO - Cleaning up... 08:39:30 INFO - Return code: 0 08:39:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b130>, '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 0x201c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c4c0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', '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 08:39:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:39:30 INFO - Using env: {'DISPLAY': ':0', 08:39:30 INFO - 'HOME': '/home/cltbld', 08:39:30 INFO - 'LANG': 'en_US.UTF-8', 08:39:30 INFO - 'LANGUAGE': 'en_US:en', 08:39:30 INFO - 'LOGNAME': 'cltbld', 08:39:30 INFO - 'MAIL': '/var/mail/cltbld', 08:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:30 INFO - 'MOZ_NO_REMOTE': '1', 08:39:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:30 INFO - 'NO_EM_RESTART': '1', 08:39:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:30 INFO - 'PWD': '/builds/slave/test', 08:39:30 INFO - 'SHELL': '/bin/bash', 08:39:30 INFO - 'SHLVL': '1', 08:39:30 INFO - 'TERM': 'linux', 08:39:30 INFO - 'TMOUT': '86400', 08:39:30 INFO - 'USER': 'cltbld', 08:39:30 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', 08:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:30 INFO - '_': '/tools/buildbot/bin/python'} 08:39:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:39:30 INFO - Cleaning up... 08:39:30 INFO - Return code: 0 08:39:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b130>, '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 0x201c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c4c0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', '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 08:39:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:39:30 INFO - Using env: {'DISPLAY': ':0', 08:39:30 INFO - 'HOME': '/home/cltbld', 08:39:30 INFO - 'LANG': 'en_US.UTF-8', 08:39:30 INFO - 'LANGUAGE': 'en_US:en', 08:39:30 INFO - 'LOGNAME': 'cltbld', 08:39:30 INFO - 'MAIL': '/var/mail/cltbld', 08:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:30 INFO - 'MOZ_NO_REMOTE': '1', 08:39:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:30 INFO - 'NO_EM_RESTART': '1', 08:39:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:30 INFO - 'PWD': '/builds/slave/test', 08:39:30 INFO - 'SHELL': '/bin/bash', 08:39:30 INFO - 'SHLVL': '1', 08:39:30 INFO - 'TERM': 'linux', 08:39:30 INFO - 'TMOUT': '86400', 08:39:30 INFO - 'USER': 'cltbld', 08:39:30 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', 08:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:30 INFO - '_': '/tools/buildbot/bin/python'} 08:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:31 INFO - Downloading/unpacking blobuploader==1.2.4 08:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:32 INFO - Downloading blobuploader-1.2.4.tar.gz 08:39:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:39:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:39:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:39:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:39:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:32 INFO - Downloading docopt-0.6.1.tar.gz 08:39:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:39:32 INFO - Installing collected packages: blobuploader, requests, docopt 08:39:32 INFO - Running setup.py install for blobuploader 08:39:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:39:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:39:32 INFO - Running setup.py install for requests 08:39:33 INFO - Running setup.py install for docopt 08:39:33 INFO - Successfully installed blobuploader requests docopt 08:39:33 INFO - Cleaning up... 08:39:33 INFO - Return code: 0 08:39:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:39:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b130>, '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 0x201c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c4c0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', '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 08:39:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:39:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:39:33 INFO - Using env: {'DISPLAY': ':0', 08:39:33 INFO - 'HOME': '/home/cltbld', 08:39:33 INFO - 'LANG': 'en_US.UTF-8', 08:39:33 INFO - 'LANGUAGE': 'en_US:en', 08:39:33 INFO - 'LOGNAME': 'cltbld', 08:39:33 INFO - 'MAIL': '/var/mail/cltbld', 08:39:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:33 INFO - 'MOZ_NO_REMOTE': '1', 08:39:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:33 INFO - 'NO_EM_RESTART': '1', 08:39:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:33 INFO - 'PWD': '/builds/slave/test', 08:39:33 INFO - 'SHELL': '/bin/bash', 08:39:33 INFO - 'SHLVL': '1', 08:39:33 INFO - 'TERM': 'linux', 08:39:33 INFO - 'TMOUT': '86400', 08:39:33 INFO - 'USER': 'cltbld', 08:39:33 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', 08:39:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:33 INFO - '_': '/tools/buildbot/bin/python'} 08:39:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:39:33 INFO - Running setup.py (path:/tmp/pip-0Zq_YB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:39:33 INFO - Running setup.py (path:/tmp/pip-AMsM9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:39:33 INFO - Running setup.py (path:/tmp/pip-_LCvsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:39:33 INFO - Running setup.py (path:/tmp/pip-OJMKXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:39:33 INFO - Running setup.py (path:/tmp/pip-CfXSC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:39:33 INFO - Running setup.py (path:/tmp/pip-4BRs5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:39:34 INFO - Running setup.py (path:/tmp/pip-xjy5Fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:39:34 INFO - Running setup.py (path:/tmp/pip-Qr3GXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:39:34 INFO - Running setup.py (path:/tmp/pip-SGmCAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:39:34 INFO - Running setup.py (path:/tmp/pip-V3wEhb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:39:34 INFO - Running setup.py (path:/tmp/pip-l4677P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:39:34 INFO - Running setup.py (path:/tmp/pip-dyDdKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:39:34 INFO - Running setup.py (path:/tmp/pip-hpdgol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:39:34 INFO - Running setup.py (path:/tmp/pip-SttDZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:39:34 INFO - Running setup.py (path:/tmp/pip-5JoCl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:39:34 INFO - Running setup.py (path:/tmp/pip-3NVAUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:39:34 INFO - Running setup.py (path:/tmp/pip-umIoTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:39:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:39:34 INFO - Running setup.py install for manifestparser 08:39:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:39:34 INFO - Running setup.py install for mozcrash 08:39:35 INFO - Running setup.py install for mozdebug 08:39:35 INFO - Running setup.py install for mozdevice 08:39:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:39:35 INFO - Running setup.py install for mozfile 08:39:35 INFO - Running setup.py install for mozhttpd 08:39:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:39:35 INFO - Running setup.py install for mozinfo 08:39:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:39:35 INFO - Running setup.py install for mozInstall 08:39:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:39:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:39:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:39:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:39:35 INFO - Running setup.py install for mozleak 08:39:35 INFO - Running setup.py install for mozlog 08:39:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:39:35 INFO - Running setup.py install for moznetwork 08:39:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:39:35 INFO - Running setup.py install for mozprocess 08:39:36 INFO - Running setup.py install for mozprofile 08:39:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:39:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:39:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:39:36 INFO - Running setup.py install for mozrunner 08:39:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:39:36 INFO - Running setup.py install for mozscreenshot 08:39:36 INFO - Running setup.py install for moztest 08:39:36 INFO - Running setup.py install for mozversion 08:39:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:39:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:39:36 INFO - Cleaning up... 08:39:36 INFO - Return code: 0 08:39:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:39:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b130>, '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 0x201c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c4c0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', '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 08:39:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:39:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:39:36 INFO - Using env: {'DISPLAY': ':0', 08:39:36 INFO - 'HOME': '/home/cltbld', 08:39:36 INFO - 'LANG': 'en_US.UTF-8', 08:39:36 INFO - 'LANGUAGE': 'en_US:en', 08:39:36 INFO - 'LOGNAME': 'cltbld', 08:39:36 INFO - 'MAIL': '/var/mail/cltbld', 08:39:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:36 INFO - 'MOZ_NO_REMOTE': '1', 08:39:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:36 INFO - 'NO_EM_RESTART': '1', 08:39:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:36 INFO - 'PWD': '/builds/slave/test', 08:39:36 INFO - 'SHELL': '/bin/bash', 08:39:36 INFO - 'SHLVL': '1', 08:39:36 INFO - 'TERM': 'linux', 08:39:36 INFO - 'TMOUT': '86400', 08:39:36 INFO - 'USER': 'cltbld', 08:39:36 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', 08:39:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:36 INFO - '_': '/tools/buildbot/bin/python'} 08:39:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:39:36 INFO - Running setup.py (path:/tmp/pip-8m9Mzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:39:36 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:39:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:39:36 INFO - Running setup.py (path:/tmp/pip-AdrCOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:39:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:39:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:39:36 INFO - Running setup.py (path:/tmp/pip-8JtaYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:39:37 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)) 08:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:39:37 INFO - Running setup.py (path:/tmp/pip-Sy9gcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:39:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:39:37 INFO - Running setup.py (path:/tmp/pip-WK53MS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:39:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:39:37 INFO - Running setup.py (path:/tmp/pip-P3V6pF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:39:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:39:37 INFO - Running setup.py (path:/tmp/pip-rJyi0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:39:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:39:37 INFO - Running setup.py (path:/tmp/pip-OqIC2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:39:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:39:37 INFO - Running setup.py (path:/tmp/pip-qj5wGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:39:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:39:37 INFO - Running setup.py (path:/tmp/pip-jIB3sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:39:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:39:37 INFO - Running setup.py (path:/tmp/pip-FyoyxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:39:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:39:37 INFO - Running setup.py (path:/tmp/pip-8_wvYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:39:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:39:37 INFO - Running setup.py (path:/tmp/pip-Z2w9ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:39:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:39:37 INFO - Running setup.py (path:/tmp/pip-a7TTf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:39:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:39:37 INFO - Running setup.py (path:/tmp/pip-urzSNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:39:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:39:37 INFO - Running setup.py (path:/tmp/pip-RDgCzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:39:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:39:37 INFO - Running setup.py (path:/tmp/pip-OY_sBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:39:38 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)) 08:39:38 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)) 08:39:38 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)) 08:39:38 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)) 08:39:38 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)) 08:39:38 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)) 08:39:38 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)) 08:39:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:39:38 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 08:39:38 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 08:39:38 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 08:39:38 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 08:39:39 INFO - Downloading blessings-1.6.tar.gz 08:39:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:39:39 INFO - Installing collected packages: blessings 08:39:39 INFO - Running setup.py install for blessings 08:39:39 INFO - Successfully installed blessings 08:39:39 INFO - Cleaning up... 08:39:39 INFO - Return code: 0 08:39:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:39:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:39:39 INFO - Reading from file tmpfile_stdout 08:39:39 INFO - Current package versions: 08:39:39 INFO - argparse == 1.2.1 08:39:39 INFO - blessings == 1.6 08:39:39 INFO - blobuploader == 1.2.4 08:39:39 INFO - docopt == 0.6.1 08:39:39 INFO - functools32 == 3.2.3-2 08:39:39 INFO - jsonschema == 2.5.1 08:39:39 INFO - manifestparser == 1.1 08:39:39 INFO - mozInstall == 1.13 08:39:39 INFO - mozcrash == 1.0 08:39:39 INFO - mozdebug == 0.1 08:39:39 INFO - mozdevice == 0.51 08:39:39 INFO - mozfile == 1.2 08:39:39 INFO - mozhttpd == 0.7 08:39:39 INFO - mozinfo == 0.10 08:39:39 INFO - mozleak == 0.1 08:39:39 INFO - mozlog == 3.5 08:39:39 INFO - moznetwork == 0.27 08:39:39 INFO - mozprocess == 0.25 08:39:39 INFO - mozprofile == 0.28 08:39:39 INFO - mozrunner == 6.13 08:39:39 INFO - mozscreenshot == 0.1 08:39:39 INFO - mozsystemmonitor == 0.3 08:39:39 INFO - moztest == 0.8 08:39:39 INFO - mozversion == 1.4 08:39:39 INFO - psutil == 3.1.1 08:39:39 INFO - requests == 1.2.3 08:39:39 INFO - wsgiref == 0.1.2 08:39:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:39:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b130>, '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 0x201c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c4c0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', '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 08:39:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:39:39 INFO - Using env: {'DISPLAY': ':0', 08:39:39 INFO - 'HOME': '/home/cltbld', 08:39:39 INFO - 'LANG': 'en_US.UTF-8', 08:39:39 INFO - 'LANGUAGE': 'en_US:en', 08:39:39 INFO - 'LOGNAME': 'cltbld', 08:39:39 INFO - 'MAIL': '/var/mail/cltbld', 08:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:39 INFO - 'MOZ_NO_REMOTE': '1', 08:39:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:39 INFO - 'NO_EM_RESTART': '1', 08:39:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:39 INFO - 'PWD': '/builds/slave/test', 08:39:39 INFO - 'SHELL': '/bin/bash', 08:39:39 INFO - 'SHLVL': '1', 08:39:39 INFO - 'TERM': 'linux', 08:39:39 INFO - 'TMOUT': '86400', 08:39:39 INFO - 'USER': 'cltbld', 08:39:39 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', 08:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:39 INFO - '_': '/tools/buildbot/bin/python'} 08:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:39:39 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 08:39:40 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:39:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:39:40 INFO - Installing collected packages: simplejson 08:39:40 INFO - Running setup.py install for simplejson 08:39:41 INFO - building 'simplejson._speedups' extension 08:39:41 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 08:39:41 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 08:39:41 INFO - Successfully installed simplejson 08:39:41 INFO - Cleaning up... 08:39:42 INFO - Return code: 0 08:39:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:39:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201b130>, '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 0x201c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c4c0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', '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 08:39:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:39:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:39:42 INFO - Using env: {'DISPLAY': ':0', 08:39:42 INFO - 'HOME': '/home/cltbld', 08:39:42 INFO - 'LANG': 'en_US.UTF-8', 08:39:42 INFO - 'LANGUAGE': 'en_US:en', 08:39:42 INFO - 'LOGNAME': 'cltbld', 08:39:42 INFO - 'MAIL': '/var/mail/cltbld', 08:39:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:42 INFO - 'MOZ_NO_REMOTE': '1', 08:39:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:42 INFO - 'NO_EM_RESTART': '1', 08:39:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:42 INFO - 'PWD': '/builds/slave/test', 08:39:42 INFO - 'SHELL': '/bin/bash', 08:39:42 INFO - 'SHLVL': '1', 08:39:42 INFO - 'TERM': 'linux', 08:39:42 INFO - 'TMOUT': '86400', 08:39:42 INFO - 'USER': 'cltbld', 08:39:42 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', 08:39:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:42 INFO - '_': '/tools/buildbot/bin/python'} 08:39:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:39:42 INFO - Cleaning up... 08:39:42 INFO - Return code: 0 08:39:42 INFO - Running post-action listener: _resource_record_post_action 08:39:42 INFO - Running post-action listener: _start_resource_monitoring 08:39:42 INFO - Starting resource monitoring. 08:39:42 INFO - [mozharness: 2017-08-28 15:39:42.278674Z] Finished create-virtualenv step (success) 08:39:42 INFO - [mozharness: 2017-08-28 15:39:42.279146Z] Running install step. 08:39:42 INFO - Running pre-action listener: _resource_record_pre_action 08:39:42 INFO - Running main action method: install 08:39:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:39:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:39:42 INFO - Reading from file tmpfile_stdout 08:39:42 INFO - Detecting whether we're running mozinstall >=1.0... 08:39:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:39:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:39:42 INFO - Reading from file tmpfile_stdout 08:39:42 INFO - Output received: 08:39:42 INFO - Usage: mozinstall [options] installer 08:39:42 INFO - Options: 08:39:42 INFO - -h, --help show this help message and exit 08:39:42 INFO - -d DEST, --destination=DEST 08:39:42 INFO - Directory to install application into. [default: 08:39:42 INFO - "/builds/slave/test"] 08:39:42 INFO - --app=APP Application being installed. [default: firefox] 08:39:42 INFO - mkdir: /builds/slave/test/build/application 08:39:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:39:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:40:00 INFO - Reading from file tmpfile_stdout 08:40:00 INFO - Output received: 08:40:00 INFO - /builds/slave/test/build/application/firefox/firefox 08:40:00 INFO - Running post-action listener: _resource_record_post_action 08:40:00 INFO - [mozharness: 2017-08-28 15:40:00.361229Z] Finished install step (success) 08:40:00 INFO - [mozharness: 2017-08-28 15:40:00.361488Z] Running setup-mitmproxy step. 08:40:00 INFO - Running pre-action listener: _resource_record_pre_action 08:40:00 INFO - Running main action method: setup_mitmproxy 08:40:00 INFO - Skipping: mitmproxy is not required 08:40:00 INFO - Running post-action listener: _resource_record_post_action 08:40:00 INFO - [mozharness: 2017-08-28 15:40:00.362625Z] Finished setup-mitmproxy step (success) 08:40:00 INFO - [mozharness: 2017-08-28 15:40:00.362846Z] Running run-tests step. 08:40:00 INFO - Running pre-action listener: _resource_record_pre_action 08:40:00 INFO - Running pre-action listener: _set_gcov_prefix 08:40:00 INFO - Running main action method: run_tests 08:40:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:40:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:40:00 INFO - Python 2.7.3 08:40:00 INFO - Return code: 0 08:40:00 INFO - grabbing minidump binary from tooltool 08:40:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:00 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 0x201b130>, '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 0x201c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x201c4c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:40:00 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 08:40:00 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 08:40:00 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 08:40:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:40:00 INFO - Return code: 0 08:40:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:40:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:40:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:40:00 INFO - ENV: RUST_BACKTRACE is now full 08:40:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:40:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:40:00 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/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 08:40:00 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/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-064 --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 08:40:00 INFO - Using env: {'DISPLAY': ':0', 08:40:00 INFO - 'HOME': '/home/cltbld', 08:40:00 INFO - 'LANG': 'en_US.UTF-8', 08:40:00 INFO - 'LANGUAGE': 'en_US:en', 08:40:00 INFO - 'LOGNAME': 'cltbld', 08:40:00 INFO - 'MAIL': '/var/mail/cltbld', 08:40:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:40:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:40:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:00 INFO - 'MOZ_NO_REMOTE': '1', 08:40:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:40:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:40:00 INFO - 'NO_EM_RESTART': '1', 08:40:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:40:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:00 INFO - 'PWD': '/builds/slave/test', 08:40:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:40:00 INFO - 'RUST_BACKTRACE': 'full', 08:40:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:40:00 INFO - 'SHELL': '/bin/bash', 08:40:00 INFO - 'SHLVL': '1', 08:40:00 INFO - 'STYLO_FORCE_ENABLED': '1', 08:40:00 INFO - 'TERM': 'linux', 08:40:00 INFO - 'TMOUT': '86400', 08:40:00 INFO - 'USER': 'cltbld', 08:40:00 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', 08:40:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:00 INFO - '_': '/tools/buildbot/bin/python'} 08:40:00 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/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 08:40:00 INFO - mozversion application_buildid: 20170828144704 08:40:00 INFO - mozversion application_changeset: d5b6d113cf17f4c91b574eaa2d077a233bc4bc69 08:40:00 INFO - mozversion application_display_name: Nightly 08:40:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:40:00 INFO - mozversion application_name: Firefox 08:40:00 INFO - mozversion application_remotingname: firefox 08:40:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:40:00 INFO - mozversion application_vendor: Mozilla 08:40:00 INFO - mozversion application_version: 57.0a1 08:40:00 INFO - mozversion platform_buildid: 20170828144704 08:40:00 INFO - mozversion platform_changeset: d5b6d113cf17f4c91b574eaa2d077a233bc4bc69 08:40:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:40:00 INFO - mozversion platform_version: 57.0a1 08:40:00 INFO - using testdate: 1503934800 08:40:00 INFO - actual date: 1503934800 08:40:00 INFO - starting webserver on 'localhost:55132' 08:40:00 INFO - SUITE-START | Running 2 tests 08:40:00 INFO - TEST-START | dromaeo_css 08:40:00 INFO - Initialising browser for dromaeo_css test... 08:40:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55132/getInfo.html -profile /tmp/tmp3irkZi/profile 08:40:00 INFO - TEST-INFO | started process 10917 (/builds/slave/test/build/application/firefox/firefox http://localhost:55132/getInfo.html) 08:40:06 INFO - TEST-INFO | 10917: exit 0 08:40:07 INFO - Browser initialized. 08:40:07 INFO - Running cycle 1/1 for dromaeo_css test... 08:40:07 INFO - Using env: {'DISPLAY': ':0', 08:40:07 INFO - 'HOME': '/home/cltbld', 08:40:07 INFO - 'JSGC_DISABLE_POISONING': '1', 08:40:07 INFO - 'LANG': 'en_US.UTF-8', 08:40:07 INFO - 'LANGUAGE': 'en_US:en', 08:40:07 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:40:07 INFO - 'LOGNAME': 'cltbld', 08:40:07 INFO - 'MAIL': '/var/mail/cltbld', 08:40:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:40:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:40:07 INFO - 'MOZ_CRASHREPORTER': '1', 08:40:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:07 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:40:07 INFO - 'MOZ_NO_REMOTE': '1', 08:40:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:40:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:40:07 INFO - 'NO_EM_RESTART': '1', 08:40:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:40:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:07 INFO - 'PWD': '/builds/slave/test', 08:40:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:40:07 INFO - 'RUST_BACKTRACE': 'full', 08:40:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:40:07 INFO - 'SHELL': '/bin/bash', 08:40:07 INFO - 'SHLVL': '1', 08:40:07 INFO - 'STYLO_FORCE_ENABLED': '1', 08:40:07 INFO - 'TERM': 'linux', 08:40:07 INFO - 'TMOUT': '86400', 08:40:07 INFO - 'USER': 'cltbld', 08:40:07 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', 08:40:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:07 INFO - '_': '/tools/buildbot/bin/python'} 08:40:07 INFO - TEST-INFO | started process 11102 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3irkZi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:40:08 INFO - PID 11102 | 08:40:08 INFO - PID 11102 | (/builds/slave/test/build/application/firefox/firefox:11152): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:40:08 INFO - PID 11102 | 08:40:18 INFO - PID 11102 | RSS: Main: 173314048 08:40:18 INFO - PID 11102 | 08:43:17 INFO - PID 11102 | Cycle 1(1): loaded http://localhost:55132/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55132/tests/dromaeo/cssquery-ext.html) 08:43:18 INFO - PID 11102 | RSS: Main: 177303552 08:43:18 INFO - PID 11102 | 08:46:20 INFO - PID 11102 | Cycle 1(1): loaded http://localhost:55132/tests/dromaeo/cssquery-ext.html (next: http://localhost:55132/tests/dromaeo/cssquery-jquery.html) 08:46:21 INFO - PID 11102 | RSS: Main: 173408256 08:46:21 INFO - PID 11102 | 08:49:22 INFO - PID 11102 | Cycle 1(1): loaded http://localhost:55132/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55132/tests/dromaeo/cssquery-mootools.html) 08:49:23 INFO - PID 11102 | RSS: Main: 173318144 08:49:23 INFO - PID 11102 | 08:52:25 INFO - PID 11102 | Cycle 1(1): loaded http://localhost:55132/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55132/tests/dromaeo/cssquery-prototype.html) 08:52:25 INFO - PID 11102 | RSS: Main: 173862912 08:52:25 INFO - PID 11102 | 08:55:29 INFO - PID 11102 | Cycle 1(1): loaded http://localhost:55132/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55132/tests/dromaeo/cssquery-yui.html) 08:55:29 INFO - PID 11102 | RSS: Main: 175796224 08:55:29 INFO - PID 11102 | 08:58:31 INFO - PID 11102 | Cycle 1(1): loaded http://localhost:55132/tests/dromaeo/cssquery-yui.html (next: http://localhost:55132/tests/dromaeo/cssquery-dojo.html) 08:58:31 INFO - PID 11102 | RSS: Main: 178196480 08:58:31 INFO - PID 11102 | 08:58:31 INFO - PID 11102 | __start_tp_report 08:58:31 INFO - PID 11102 | _x_x_mozilla_page_load 08:58:31 INFO - PID 11102 | _x_x_mozilla_page_load_details 08:58:31 INFO - PID 11102 | |i|pagename|runs| 08:58:31 INFO - PID 11102 | |0;dojo.html;3316.436205845006;3324.7672662913574;3349.129226401136;3361.966380336197;3366.8821591244296;6276.811695649132;6318.652474113927;6328.58864173829;6412.903806442907;6465.644389558573;6402.5518213724945;6449.871002579957;6467.644279564622;6476.935230647692;6573.0797688323555;5862.853428664285;5894.6168499047635;5961.463468287875;5973.581849270553;5984.820455386345;5793.391693872146;5799.449052340021;5847.79532716355;5861.8827623447505;5889.263842019747;5811.215485909391;5840.737166827482;5966.612170208944;5983.252093488314;5985.1022346647915;5831.067029275296;5880.647161170344;5960.970195148997;5975.223220981245;5980;5816.738246778863;5847.2983242010805;5860.2967643883;5929.9703501482645;5930;6401.3598640135615;6437.774677886251;6438.1630388049725;6451.967740161316;6470.514711396664;6874.931250687479;6927.168739751211;6970.163580370388;6976.093107895992;6983;6945.0971373721595;6951.478639102088;6984.301569843026;7050.400715939151;7051.3653771160325;6317.273513545909;6416.422521973045;6433.228012638419;6458.483321334329;6556.967215163893;7135.678894449762;7141.785746427511;7147.356737893615;7153.427725781925;7162.033125527987;6768.864622707519;6793.660316984131;6852.211995620568;6862.107925969692;6867.244603093655;6902.275261097613;6916.377526022682;6969.16370035599;6995.125609298838;7001.649917504104;6738.663066846638;6817.931820681829;6834;6836.111305530249;6889.242183359826;6870.519063665595;6902.102726645604;6904.378605925391;6920.238773734885;6924.4114250288285;5413.10683737179;5421.051316019681;5495.560355171577;5495.670259784426;5497.8075767347955;4753.310769938409;4770.403699537558;4787.832425865078;4804.159272127364;4814.470408255089;5980.700964951735;6047.909281360783;6052.152698622196;6059.484943779828;6060.606060606135;6898.2756810535175;6985.511014229057;6987.1266091738535;6987.89518157228;6990.231074581792;6035.577509574287;6050;6094.634321940609;6130.724117414727;6145.692715364214;5944.316403613553;5971.641701497924;6028.186194863725;6037.184980027728;6064.969675151596;6479.870402591921;6548.70530826114;6590.275069742324;6611.933880661132;6723.125993620798;6947.3747362737595;6959.338862808025;6970.337817907291;6985.441164706913;6993.19578248508;6602.537822352389;6607.900881486878;6612.107365505787;6693.665316734241;6702.19573651165;6759.5606285591275;6839.384455399038;6850.588964662136;6881.621510816954;6902.930970690229;6767;6856.074429952092;6907.240203577502;6997.020417141706;6999.720011199699;6721.764738234138;6739.898901516579;6744.359285867835;6747.122874026345;6770.086038384755;6645.335466453316;6681;6696.665166741546;6740.66296685154;6763.830904227434;6575.50683698715;6624.403803657694;6654.401103900478;6674;6730.394264516021;6633.568818026813;6680.198376194888;6761.22245941723;6762.594244345355;6762.763303284361;6450.322716114741;6463.57986730861;6504;6576.046473261446;6584.7036883341325 08:58:31 INFO - PID 11102 | |1;ext.html;754.7358424551404;837.1419295222398;859.7205908079876;861.3496809908518;1111.288775183883;29488.557671634917;30048.699513004864;39442.60557394425;39820.402693959615;39955.60044399555;40245;40620.187596248055;40673.38989915154;40773.59226407735;40856.59143408565;7473.439492038092;7576.356009739179;7648.579328136951;7651.502652327608;7656.3109320161175;7582.203868593773;7671.194524574923;7676.923230767705;7685.0777906651;7691.077070751518;7633.08402991639;7690.769276921701;7692.076950765916;7692.615369231544;7697.0763508378795;7630.313271805537;7637.656305466267;7639.579823109727;7640.083190017206;7659.119201291811;7578.583177925212;7612.923870761332;7613;7616.352610028099;7623.2376762323875;35978.280434391425;36023.27953440943;36229.81885090584;36263.63736362629;36295.27409451796;3403.2512847173584;3435.707964822993;3447.0175999839926;3451.8101504417245;3453.879114231015;30502.54246186309;30537.23656908595;30584.69415305863;30603.387932241454;30643.233919151975;5040.067587496325;5042.873928151789;5072.467390923936;5111.131107711698;5142.845714628567;183253;184502;184603;185371;185416;45153.54846451559;45193.32210016852;45227.32159017617;45315.320270195974;45391.546084538735;47388;47538.76230618825;47596.5240347599;47608.5239147611;47626.76186619045;13704.24626645524;13714.588562343146;14303.570892873231;15422.228888555528;15682;11442.828357574643;11444.713882152846;11448.084153267719;11454.656360309204;11524.539018439169;13324.73350532984;13366.93316533411;13368.933155334162;13397.598072057659;13398.196108233537;5619.43805619443;5683.715814209273;5683.9715801420725;5698.57535616096;5722.025901064127;975.2929126383316;982.9655962041294;1001.4592120254981;1006.3760468509571;1011.8431643095332;21749.78250217509;22092.802367550114;22139.225127120473;22182;22399.88800055989;1060.4750648429076;1080.9459527023776;1088.586337191862;1096.7477480179602;1103.939283339408;11570.59502917394;11592.188546801812;11652.94173529127;11753.588624398106;11844.170908036354;236465;238133;240220;240272;240929;55953.440465594824;56915.430845691844;56965.99999999917;57317;57391.42608573944;1420.453125546672;1430.1347684650846;1430.4278288684402;1436.791665208539;1438.503716217903;26569.7343026575;26859.059932903037;26932.192034239004;27065.1880443587;27208;34473.13817154491;34543.65456345505;34720;34736.131596709274;34839.825800870836;196912;197738;199970;200110;200128;33008.1747956303;33307.666923331424;34455.827720861234;35562.46656300106;35682;43378.13243735045;43880.78059609682;43887;44292.55707442884;44502.10995780131;31804;32333.191670208435;32423;32776.180595485304;33013.17467063342;31520.2119946994;31646;31798.20504487314;31932.52101218437;32463.675363246064;35988.64011359853;36074.4588831173;36224.818875905454;36307.45538816867;36415.453768192965;30882.227944301572;31002;31491.685083148874;31503.842480788368;31780.682193177774;22317.442063948532;22806.63160210326;23093.422664433525;23334.416639584146;23506.412339691644 08:58:31 INFO - PID 11102 | |2;jquery.html;545.8116949652368;549.6509716330131;553.7508121345395;562.7017680629267;567.4524084258691;21236;21980.670289945665;21986.560268794616;22017.449563760914;22607.208747693832;24550.631740523906;25064.49871002579;25068.623970640456;25090;25154.62268065976;6396.264429590598;6541.149650545422;6543.247526534462;6545.443637290836;6546.934530654704;6025.3372129065765;6412.134361861135;6464.127342808732;6558.049082882979;6593.67031648418;6567.737290508374;6572.80281591553;6573.506986975972;6574.539782215247;6576.934230657704;6539.509536784736;6541.182352205974;6541.182352205974;6541.967290163542;6569.7700580479695;6052.425019623129;6054.515638748933;6059.0608455689435;6093.116498107794;6105.572609917263;27716.168514944584;28221.435571288665;28767.28081797951;28811.27971800701;28990.130296091153;1994.9301774437824;2027.0574183004974;2027.1384661518796;2027.4221846773598;2028.6348457277684;19163.520911977314;19669.016549172484;19699.310524131586;19732.50668733279;19737.309194178135;2378.1703209842594;2393.6170212765874;2452.1662634704285;2466.728659847415;2486.105002199207;130046;137129.3143534286;137683;137726;138250;14039.719205616035;14130.293485325692;14634.707305853823;14883.553493395215;15240.695186096216;22833.40166188351;22989.080436782675;23036.005319308384;23040.4239894004;23048.884755576113;10146;10298.845517317246;10352.741181470374;10464;10495.895041049493;9698.27262955267;9767.365121267096;9784.874739405059;9833.852492212622;9882.308238423242;7722.884156737653;8824.735257942271;8967.282617390592;8986.14631609996;9044.321675874337;2629.316377741801;2637.815352925315;2665.3736371952577;2701.2711864406797;2763.765079968185;951.9714408567754;957.8515330123842;965.478641533564;971.4220039076738;975.8482545986084;19081.522961926064;19499.220031198878;19667.803321966596;19677.704834427495;19927.20291188365;1030.4281123976116;1033.405791669778;1034.095166729112;1035.3270374256792;1042.213129087534;10528.789424211473;10795.19036072282;10859.945700271448;10864.511097000654;10967.232293739362;483672;496899;500917;513281;514878;106694;108666.45666771615;108798.45600771946;114332.42833785778;116536.41731791456;1017.9338343007681;1156.3293289891842;1176.053277111933;1186.0393081603756;1187.9703007424885;20076.19695212175;20300.390988270374;20375;20673.379798606067;20751.481212969193;56879.715601421725;58203.4179658198;60165.39834601598;61151.38848611457;62566.374336256056;70124.64937675279;70895.64552177206;71232.64383678048;71497.64251178711;71535.64232179015;11613.709657258298;14166.858331416553;14559.563213103624;14893.404263829325;14986.775198371815;23029;23259.53480930406;23402.88298558496;24398;24497.142600008916;17979.550511237325;18471.90764046171;18858.71711924295;18878.24487020504;18910.243590256236;16913.66172676564;17205.13974301305;17249.482515524553;17252.65494690074;17320.480385588453;18840.43478695641;19271.22915083381;19291.324803632368;19471.610567788848;19502;14145.151290922578;14506.492272770402;14523.78214326807;14554.126752394466;14570.4900328488;17777.644447110728;18343.81656183421;18348.90825545864;18356.632867342847;18690.065496725383 08:58:31 INFO - PID 11102 | |3;mootools.html;1297.824793652857;1341.6712905338188;1341.7584834729744;1345.4752646467875;1353.1948490648067;14370.712585748292;14913.478028269014;15005.62485937852;15064.77402838957;15067.09597424154;15375.846241537582;15634.218289085557;15634.84365156351;15641.687166256697;15656.843431565681;2743.684476285233;2802.7897907657007;2806.929826754327;2807.368342123016;2808.7612552933024;2573.897044118233;2706.350475885793;2738.835669859805;2778.9722102779015;2781.0961437532783;2746.1761471558452;2786.9827512957727;2789.0656630028966;2789.121426750577;2790.4698107359636;2687.784977201829;2704.5537486314834;2750.8899644014214;2752.0642981386322;2753.1465245773875;2667.5198464276423;2715.6469658944407;2719.279390961397;2719.904803331874;2721.6189733437336;14599.635009124751;14851.257437128208;14932.776008359884;14938.103713777207;14957.551273461813;7676.539407635504;7922.088959769585;7937.880931786028;7941.6029198539845;7942.602869856484;5240.266362709223;5241.633085684004;5584.497395234408;5587.106063029936;5642.943570564283;1888.1031510032763;1898.9620207595908;1922.9903850480657;1948.3180886689686;1957.0410498855458;24887.50224995508;25008.374790630016;25075.247742567753;25101.87449062761;25120.120795772244;6437.452816510556;6461.321561236096;6514.478841692653;6792.45660347171;6831.49707064445;16158.838411615969;16187.595310117342;16212.75680864788;16224.67550649004;16280;7554.546727196386;7556.65995030225;7589.848203035908;7592.544447333178;7593.772186834404;6977.406920411822;6979.267176946449;7054.858902822018;7055.294470553006;7087.858242835217;4995.450500445021;5160.664556803796;5227.215917612389;5229.555487783581;5239.764210610456;1365.160426337815;1374.4227424481542;1561.0555613853455;1576.3143032780777;1611.2266112266177;860.3160487412522;864.8572985457455;874.1826392323198;876.8553188723916;881.3918396306528;7378.778636640909;7429.554226746304;7471.8505629887095;7487.605280449149;7546.056742907137;931.7577429868284;966.1690945786618;974.3423189347162;984.7193549838262;992.8510723391405;4541.978054937678;4587;4628.074385122989;4645.651576131803;4660.231061874821;20165.19339226444;20552.486187845123;20804;20804;20812.791872081085;19403.320883769;20806.167753290003;20812.479688007617;20845;20908.268210612554;10568.947155264175;10889.421033950191;10904.400257985886;10908.181886358554;10913.618023369145;8035.959820200862;8598.097199793932;8613.181747733955;8640.351973601879;8646.697365592174;17985.460436186935;18597.90701046486;18662.160202790634;18788.7181692272;18798.15408306657;18301.359452419103;18545.44363669092;18701;18779.90610046941;19062.523436914187;12799.10406271552;13302.534411295559;13392.330383480981;13633.522826701017;13641.79537306961;11544.307341559534;13276.867231327564;13411.597652070453;13471.663208419868;13523.391447384694;9633.32566720351;10017.599296028076;10147.34042287249;10461.320014199053;10938.507767150657;9323;10304.278700490893;10366.775995566913;10728.839067413839;10911.072558832588;9014.549272536216;9036.593353298986;9114.088591140833;9129.680461183827;9169.128932751379;6554.213494380705;6594.901076483952;6626.171728533934;6642;6720.731170753115;6435.646039467687;6492.091107245084;6503.187101612299;6599.637019963948;6652.10196623469 08:58:31 INFO - PID 11102 | |4;prototype.html;486.16622492425483;508.2554058304583;510.5124606001267;512.1523877981939;524.6878107526021;6407.391297826711;6440.484761219097;6478.708458119384;6512;6524.889319728564;6292.496600271978;6301.78608909456;6336.42972132508;6349.777757778479;6390.297067322591;5209.374875014994;5216.713080780555;5229.137192363274;5267.55225805807;5267.736613169345;4878.341423907763;5152.74236288186;5188.558972487324;5226.137687281613;5320.042392369372;4941.6540842140885;4959.851204463872;4994.1545010342015;5041.369828771403;5066.6706664066905;4940.456549779521;4986.252062190664;5023.999999999982;5025.547700706936;5066.341375621182;4396.164728701537;4477.2164871147415;4510.661700372485;4513.796879140446;4523.298888672228;6152.723127459275;6224.101648997054;6285.120083188357;6301.779437719658;6326.291901186653;5656.632318899299;5715.542756579505;5757.625754325997;5762.394948530427;5801.767929282866;6594.868102637968;6596.868062638769;6720.462363010947;6737.629430381327;6740.52816302859;4887.291342755316;4910.779014944336;4913.926291105637;4922.950770492285;4937.136001199811;6693.954250815916;6873.20958089816;6930.203026651899;7036.73338799016;7053.92946070543;4990.4011518618;4992.152903425683;5024.693579669312;5046.798128074909;5058.747062646853;5053.267276244924;5064.848054558369;5083.593312534988;5093.23601459784;5096.1336572781975;5746.2529871116485;5764.913526297109;5772.855678607984;5773.1051686988585;5815.3021637402935;6741.224759152662;6766.289539598271;6805.625690586966;6858.965705171542;6861.828454288583;1768.1159420289855;1769.761082253905;1770.6370194110177;1775.8224177582138;1792.7988247873957;1144.1876267849752;1207.637708687408;1216.9878301216875;1220.7009282725612;1235.6416639174538;5911.645301281937;5913.142594323885;5937.287525496881;5953.672548009815;6062.242219722535;6763;6860.6912688929115;6861.828454288583;6912.205096413876;6916.757913473004;5928.436798504135;5942.8811423771285;5976.551758618121;5978.461938425476;6010.819675409744;3653.12325041988;3670.4861319415304;3690.981545092311;3698.334299826003;3717.0671250949354;6595.901061484082;6655.633940133339;6675.5647535780045;6683;6764.120664313607;6923.411510021604;6967.303269672992;7024.719011239596;7059.752908648173;7072.823179420556;6053.334133245339;6461.870762584722;6497.610143391223;6543.149390579194;6601.240857301375;6751.257361690308;6764.357386048514;6868.62222577763;6883.965580172067;6917.792466226021;6675.23234828001;6679.265280819009;6710.221791225426;6710.932890671031;6744.763933262312;6527.249366322933;6570.207661743835;6647.634380109141;6672.799816005528;6690.83272918181;4935.728534930613;4980.651354405157;4992.326035985241;5018.3977922648055;5022.321986531918;6642.269350371552;6737.225219099866;6778.118844550177;6833.983974727368;6971.3028697129885;6552.57408268461;6607.414220586928;6618.867622647616;6669.433098186709;6756.459483241427;6540.444062254572;6586.571872828251;6596.670166491752;6690.498212634169;6701;6450.645214513247;6488.513361498001;6520.413162815369;6585.275619681735;6652.268250492539;6654.567453115532;6669;6671.799846004627;6686.1642294713165;6777.322267773183;6407;6440.323766004502;6449.322821103717;6518.478521718346;6525.249596296297 08:58:31 INFO - PID 11102 | |5;yui.html;814.2793627639537;825.492322221833;845.6321500147433;872.319590719239;893.5085702863425;16019.279132439038;16673.99956002639;16711.331546738114;16772.83227167728;16800.327986880526;18478.907605461955;18549.629007419877;18607.069646517688;18643.25426982919;18652.34716784913;858.0389963241179;858.2404571953834;858.6522458404356;862.9989212513485;887.4098724348316;889.5329951775306;927.8561822917459;936.6440752514069;953.0231512699504;956.9569369378395;980.0297705271801;980.308882238024;980.6567701304558;980.872486576744;980.9068138526865;960.3661583354988;969.8254314223437;970.5729479029239;971.2133172130526;973.756560859785;719.1047146302886;720.7117153138734;721.5490318550906;721.9314165154302;723.2405973727617;17242.396516122;17372.739408908878;17414.825851741447;17439.73840392395;17482.213300401512;20805.791942080687;20812;21333.146674133015;21338.466538336666;21413.571728565497;16564.585885352964;16985.745213821803;16991.40530081441;17128.57178570533;17152.656946861116;3628.6008539060945;3663.102539877747;3667.3032123896373;3675.5221821163077;3683.3001729671546;128201.35899320537;129051;130954;131295;132013;16572.668546629;17266.568335791453;17280.567985800455;17282;17308.91345543264;29900.55149172764;29921.251968700955;30060.398792024036;30523;30675.539866902014;8663.350248731243;8690.695825646071;8692.913070869337;8695.695650652196;8722.084181161013;4154.459920210354;4213.768242746678;4285.785710714452;4287.592678695519;4294.742315461082;5012.223105418666;5098.694078355311;5115.411727651368;5125.077486052546;5144.048351055067;951.8524628682566;1012.9037741414554;1044.7179261599326;1045.717656232813;1046.0899017854515;679.4700133895569;692.8995295682098;699.909011828459;703.1351437731516;706.7137809187287;11390.829137562943;11422.25755325901;11598.536058557564;11735.826417358196;11966.521339146511;673.1350214973735;682.5495173185674;689.6103701408688;694.7950354645392;704.5772536478072;5725.5133313667975;5728.570357223225;5761.567882408753;5770.336411312753;5782.739776710058;112554;114391.42804285925;115569;118965;119582;101994.49002754939;102290.48854755679;103780.96219037865;104239.4788026055;105703.4714826421;84918;85354.86612834729;86019.56990215009;88435;88573.55713221521;10510.369377837354;10533.473326333806;10718;10770.08454281395;11677.76644467123;38810.417843731804;39987.80006100067;40507.79746101251;40994.59005410027;41484;68584.3141568578;70140.29859701337;70881.64559177171;74903.25096748963;77743.61128194323;16385.754213686567;18454;18504.16731247124;18675.906620466812;18862.43412697621;21001.369958901258;21601;21705.674414883473;21776.89111554432;22000.559988799814;16985.745213821556;17044.914775426045;17157.91421042887;18013.909930450787;18124.909375453564;17484.77606567528;18458.169382377557;18587;19381.4185574433;19618.411447656592;17864.374746883797;18211.45365639033;18359.35742249015;18877.90561047186;19001.714974275674;11493.54025838957;11771.646850594496;11841.60591231413;11890.405479726152;11891.524339026342;12446.626601201979;12535.561255356019;13367.799483007957;13461.528846490326;13530.458781648624 08:58:31 INFO - PID 11102 | __end_tp_report 08:58:31 INFO - PID 11102 | __start_cc_report 08:58:31 INFO - PID 11102 | _x_x_mozilla_cycle_collect,373 08:58:31 INFO - PID 11102 | __end_cc_report 08:58:31 INFO - PID 11102 | __startTimestamp1503935911918__endTimestamp 08:58:31 INFO - PID 11102 | 08:58:31 INFO - PID 11102 | ------- Summary: start ------- 08:58:31 INFO - PID 11102 | Number of tests: 6 08:58:31 INFO - PID 11102 | 08:58:31 INFO - PID 11102 | [#0] dojo.html Cycles:165 Average:6357.86 Median:6584.70 stddev:745.37 (11.3%) stddev-sans-first:708.44 08:58:31 INFO - PID 11102 | Values: 3316.4 3324.8 3349.1 3362.0 3366.9 6276.8 6318.7 6328.6 6412.9 6465.6 6402.6 6449.9 6467.6 6476.9 6573.1 5862.9 5894.6 5961.5 5973.6 5984.8 5793.4 5799.4 5847.8 5861.9 5889.3 5811.2 5840.7 5966.6 5983.3 5985.1 5831.1 5880.6 5961.0 5975.2 5980.0 5816.7 5847.3 5860.3 5930.0 5930.0 6401.4 6437.8 6438.2 6452.0 6470.5 6874.9 6927.2 6970.2 6976.1 6983.0 6945.1 6951.5 6984.3 7050.4 7051.4 6317.3 6416.4 6433.2 6458.5 6557.0 7135.7 7141.8 7147.4 7153.4 7162.0 6768.9 6793.7 6852.2 6862.1 6867.2 6902.3 6916.4 6969.2 6995.1 7001.6 6738.7 6817.9 6834.0 6836.1 6889.2 6870.5 6902.1 6904.4 6920.2 6924.4 5413.1 5421.1 5495.6 5495.7 5497.8 4753.3 4770.4 4787.8 4804.2 4814.5 5980.7 6047.9 6052.2 6059.5 6060.6 6898.3 6985.5 6987.1 6987.9 6990.2 6035.6 6050.0 6094.6 6130.7 6145.7 5944.3 5971.6 6028.2 6037.2 6065.0 6479.9 6548.7 6590.3 6611.9 6723.1 6947.4 6959.3 6970.3 6985.4 6993.2 6602.5 6607.9 6612.1 6693.7 6702.2 6759.6 6839.4 6850.6 6881.6 6902.9 6767.0 6856.1 6907.2 6997.0 6999.7 6721.8 6739.9 6744.4 6747.1 6770.1 6645.3 6681.0 6696.7 6740.7 6763.8 6575.5 6624.4 6654.4 6674.0 6730.4 6633.6 6680.2 6761.2 6762.6 6762.8 6450.3 6463.6 6504.0 6576.0 6584.7 08:58:31 INFO - PID 11102 | 08:58:31 INFO - PID 11102 | [#1] ext.html Cycles:180 Average:37235.98 Median:18951.42 stddev:54204.39 (286.0%) stddev-sans-first:54287.23 08:58:31 INFO - PID 11102 | Values: 754.7 837.1 859.7 861.3 1111.3 29488.6 30048.7 39442.6 39820.4 39955.6 40245.0 40620.2 40673.4 40773.6 40856.6 7473.4 7576.4 7648.6 7651.5 7656.3 7582.2 7671.2 7676.9 7685.1 7691.1 7633.1 7690.8 7692.1 7692.6 7697.1 7630.3 7637.7 7639.6 7640.1 7659.1 7578.6 7612.9 7613.0 7616.4 7623.2 35978.3 36023.3 36229.8 36263.6 36295.3 3403.3 3435.7 3447.0 3451.8 3453.9 30502.5 30537.2 30584.7 30603.4 30643.2 5040.1 5042.9 5072.5 5111.1 5142.8 183253.0 184502.0 184603.0 185371.0 185416.0 45153.5 45193.3 45227.3 45315.3 45391.5 47388.0 47538.8 47596.5 47608.5 47626.8 13704.2 13714.6 14303.6 15422.2 15682.0 11442.8 11444.7 11448.1 11454.7 11524.5 13324.7 13366.9 13368.9 13397.6 13398.2 5619.4 5683.7 5684.0 5698.6 5722.0 975.3 983.0 1001.5 1006.4 1011.8 21749.8 22092.8 22139.2 22182.0 22399.9 1060.5 1080.9 1088.6 1096.7 1103.9 11570.6 11592.2 11652.9 11753.6 11844.2 236465.0 238133.0 240220.0 240272.0 240929.0 55953.4 56915.4 56966.0 57317.0 57391.4 1420.5 1430.1 1430.4 1436.8 1438.5 26569.7 26859.1 26932.2 27065.2 27208.0 34473.1 34543.7 34720.0 34736.1 34839.8 196912.0 197738.0 199970.0 200110.0 200128.0 33008.2 33307.7 34455.8 35562.5 35682.0 43378.1 43880.8 43887.0 44292.6 44502.1 31804.0 32333.2 32423.0 32776.2 33013.2 31520.2 31646.0 31798.2 31932.5 32463.7 35988.6 36074.5 36224.8 36307.5 36415.5 30882.2 31002.0 31491.7 31503.8 31780.7 22317.4 22806.6 23093.4 23334.4 23506.4 08:58:31 INFO - PID 11102 | 08:58:31 INFO - PID 11102 | [#2] jquery.html Cycles:180 Average:35022.39 Median:22911.24 stddev:84266.97 (367.8%) stddev-sans-first:84463.60 08:58:31 INFO - PID 11102 | Values: 545.8 549.7 553.8 562.7 567.5 21236.0 21980.7 21986.6 22017.4 22607.2 24550.6 25064.5 25068.6 25090.0 25154.6 6396.3 6541.1 6543.2 6545.4 6546.9 6025.3 6412.1 6464.1 6558.0 6593.7 6567.7 6572.8 6573.5 6574.5 6576.9 6539.5 6541.2 6541.2 6542.0 6569.8 6052.4 6054.5 6059.1 6093.1 6105.6 27716.2 28221.4 28767.3 28811.3 28990.1 1994.9 2027.1 2027.1 2027.4 2028.6 19163.5 19669.0 19699.3 19732.5 19737.3 2378.2 2393.6 2452.2 2466.7 2486.1 130046.0 137129.3 137683.0 137726.0 138250.0 14039.7 14130.3 14634.7 14883.6 15240.7 22833.4 22989.1 23036.0 23040.4 23048.9 10146.0 10298.8 10352.7 10464.0 10495.9 9698.3 9767.4 9784.9 9833.9 9882.3 7722.9 8824.7 8967.3 8986.1 9044.3 2629.3 2637.8 2665.4 2701.3 2763.8 952.0 957.9 965.5 971.4 975.8 19081.5 19499.2 19667.8 19677.7 19927.2 1030.4 1033.4 1034.1 1035.3 1042.2 10528.8 10795.2 10859.9 10864.5 10967.2 483672.0 496899.0 500917.0 513281.0 514878.0 106694.0 108666.5 108798.5 114332.4 116536.4 1017.9 1156.3 1176.1 1186.0 1188.0 20076.2 20300.4 20375.0 20673.4 20751.5 56879.7 58203.4 60165.4 61151.4 62566.4 70124.6 70895.6 71232.6 71497.6 71535.6 11613.7 14166.9 14559.6 14893.4 14986.8 23029.0 23259.5 23402.9 24398.0 24497.1 17979.6 18471.9 18858.7 18878.2 18910.2 16913.7 17205.1 17249.5 17252.7 17320.5 18840.4 19271.2 19291.3 19471.6 19502.0 14145.2 14506.5 14523.8 14554.1 14570.5 17777.6 18343.8 18348.9 18356.6 18690.1 08:58:31 INFO - PID 11102 | 08:58:31 INFO - PID 11102 | [#3] mootools.html Cycles:180 Average:9047.39 Median:2720.76 stddev:6439.01 (236.7%) stddev-sans-first:6430.74 08:58:31 INFO - PID 11102 | Values: 1297.8 1341.7 1341.8 1345.5 1353.2 14370.7 14913.5 15005.6 15064.8 15067.1 15375.8 15634.2 15634.8 15641.7 15656.8 2743.7 2802.8 2806.9 2807.4 2808.8 2573.9 2706.4 2738.8 2779.0 2781.1 2746.2 2787.0 2789.1 2789.1 2790.5 2687.8 2704.6 2750.9 2752.1 2753.1 2667.5 2715.6 2719.3 2719.9 2721.6 14599.6 14851.3 14932.8 14938.1 14957.6 7676.5 7922.1 7937.9 7941.6 7942.6 5240.3 5241.6 5584.5 5587.1 5642.9 1888.1 1899.0 1923.0 1948.3 1957.0 24887.5 25008.4 25075.2 25101.9 25120.1 6437.5 6461.3 6514.5 6792.5 6831.5 16158.8 16187.6 16212.8 16224.7 16280.0 7554.5 7556.7 7589.8 7592.5 7593.8 6977.4 6979.3 7054.9 7055.3 7087.9 4995.5 5160.7 5227.2 5229.6 5239.8 1365.2 1374.4 1561.1 1576.3 1611.2 860.3 864.9 874.2 876.9 881.4 7378.8 7429.6 7471.9 7487.6 7546.1 931.8 966.2 974.3 984.7 992.9 4542.0 4587.0 4628.1 4645.7 4660.2 20165.2 20552.5 20804.0 20804.0 20812.8 19403.3 20806.2 20812.5 20845.0 20908.3 10568.9 10889.4 10904.4 10908.2 10913.6 8036.0 8598.1 8613.2 8640.4 8646.7 17985.5 18597.9 18662.2 18788.7 18798.2 18301.4 18545.4 18701.0 18779.9 19062.5 12799.1 13302.5 13392.3 13633.5 13641.8 11544.3 13276.9 13411.6 13471.7 13523.4 9633.3 10017.6 10147.3 10461.3 10938.5 9323.0 10304.3 10366.8 10728.8 10911.1 9014.5 9036.6 9114.1 9129.7 9169.1 6554.2 6594.9 6626.2 6642.0 6720.7 6435.6 6492.1 6503.2 6599.6 6652.1 08:58:31 INFO - PID 11102 | 08:58:31 INFO - PID 11102 | [#4] prototype.html Cycles:180 Average:5607.39 Median:6301.78 stddev:1580.44 (25.1%) stddev-sans-first:1537.41 08:58:31 INFO - PID 11102 | Values: 486.2 508.3 510.5 512.2 524.7 6407.4 6440.5 6478.7 6512.0 6524.9 6292.5 6301.8 6336.4 6349.8 6390.3 5209.4 5216.7 5229.1 5267.6 5267.7 4878.3 5152.7 5188.6 5226.1 5320.0 4941.7 4959.9 4994.2 5041.4 5066.7 4940.5 4986.3 5024.0 5025.5 5066.3 4396.2 4477.2 4510.7 4513.8 4523.3 6152.7 6224.1 6285.1 6301.8 6326.3 5656.6 5715.5 5757.6 5762.4 5801.8 6594.9 6596.9 6720.5 6737.6 6740.5 4887.3 4910.8 4913.9 4923.0 4937.1 6694.0 6873.2 6930.2 7036.7 7053.9 4990.4 4992.2 5024.7 5046.8 5058.7 5053.3 5064.8 5083.6 5093.2 5096.1 5746.3 5764.9 5772.9 5773.1 5815.3 6741.2 6766.3 6805.6 6859.0 6861.8 1768.1 1769.8 1770.6 1775.8 1792.8 1144.2 1207.6 1217.0 1220.7 1235.6 5911.6 5913.1 5937.3 5953.7 6062.2 6763.0 6860.7 6861.8 6912.2 6916.8 5928.4 5942.9 5976.6 5978.5 6010.8 3653.1 3670.5 3691.0 3698.3 3717.1 6595.9 6655.6 6675.6 6683.0 6764.1 6923.4 6967.3 7024.7 7059.8 7072.8 6053.3 6461.9 6497.6 6543.1 6601.2 6751.3 6764.4 6868.6 6884.0 6917.8 6675.2 6679.3 6710.2 6710.9 6744.8 6527.2 6570.2 6647.6 6672.8 6690.8 4935.7 4980.7 4992.3 5018.4 5022.3 6642.3 6737.2 6778.1 6834.0 6971.3 6552.6 6607.4 6618.9 6669.4 6756.5 6540.4 6586.6 6596.7 6690.5 6701.0 6450.6 6488.5 6520.4 6585.3 6652.3 6654.6 6669.0 6671.8 6686.2 6777.3 6407.0 6440.3 6449.3 6518.5 6525.2 08:58:31 INFO - PID 11102 | 08:58:31 INFO - PID 11102 | [#5] yui.html Cycles:180 Average:24015.01 Median:21653.34 stddev:33536.95 (154.9%) stddev-sans-first:33585.78 08:58:31 INFO - PID 11102 | Values: 814.3 825.5 845.6 872.3 893.5 16019.3 16674.0 16711.3 16772.8 16800.3 18478.9 18549.6 18607.1 18643.3 18652.3 858.0 858.2 858.7 863.0 887.4 889.5 927.9 936.6 953.0 957.0 980.0 980.3 980.7 980.9 980.9 960.4 969.8 970.6 971.2 973.8 719.1 720.7 721.5 721.9 723.2 17242.4 17372.7 17414.8 17439.7 17482.2 20805.8 20812.0 21333.1 21338.5 21413.6 16564.6 16985.7 16991.4 17128.6 17152.7 3628.6 3663.1 3667.3 3675.5 3683.3 128201.4 129051.0 130954.0 131295.0 132013.0 16572.7 17266.6 17280.6 17282.0 17308.9 29900.6 29921.3 30060.4 30523.0 30675.5 8663.4 8690.7 8692.9 8695.7 8722.1 4154.5 4213.8 4285.8 4287.6 4294.7 5012.2 5098.7 5115.4 5125.1 5144.0 951.9 1012.9 1044.7 1045.7 1046.1 679.5 692.9 699.9 703.1 706.7 11390.8 11422.3 11598.5 11735.8 11966.5 673.1 682.5 689.6 694.8 704.6 5725.5 5728.6 5761.6 5770.3 5782.7 112554.0 114391.4 115569.0 118965.0 119582.0 101994.5 102290.5 103781.0 104239.5 105703.5 84918.0 85354.9 86019.6 88435.0 88573.6 10510.4 10533.5 10718.0 10770.1 11677.8 38810.4 39987.8 40507.8 40994.6 41484.0 68584.3 70140.3 70881.6 74903.3 77743.6 16385.8 18454.0 18504.2 18675.9 18862.4 21001.4 21601.0 21705.7 21776.9 22000.6 16985.7 17044.9 17157.9 18013.9 18124.9 17484.8 18458.2 18587.0 19381.4 19618.4 17864.4 18211.5 18359.4 18877.9 19001.7 11493.5 11771.6 11841.6 11890.4 11891.5 12446.6 12535.6 13367.8 13461.5 13530.5 08:58:31 INFO - PID 11102 | -------- Summary: end -------- 08:58:31 INFO - PID 11102 | 08:58:32 INFO - TEST-INFO | 11102: exit 0 08:58:32 INFO - TEST-OK | dromaeo_css | took 1111968ms 08:58:32 INFO - TEST-START | kraken 08:58:32 INFO - Initialising browser for kraken test... 08:58:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55132/getInfo.html -profile /tmp/tmpRIQcqR/profile 08:58:32 INFO - TEST-INFO | started process 11473 (/builds/slave/test/build/application/firefox/firefox http://localhost:55132/getInfo.html) 08:58:38 INFO - TEST-INFO | 11473: exit 0 08:58:38 INFO - Browser initialized. 08:58:38 INFO - Running cycle 1/1 for kraken test... 08:58:38 INFO - Using env: {'DISPLAY': ':0', 08:58:38 INFO - 'HOME': '/home/cltbld', 08:58:38 INFO - 'JSGC_DISABLE_POISONING': '1', 08:58:38 INFO - 'LANG': 'en_US.UTF-8', 08:58:38 INFO - 'LANGUAGE': 'en_US:en', 08:58:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:58:38 INFO - 'LOGNAME': 'cltbld', 08:58:38 INFO - 'MAIL': '/var/mail/cltbld', 08:58:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:58:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:58:38 INFO - 'MOZ_CRASHREPORTER': '1', 08:58:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:58:38 INFO - 'MOZ_NO_REMOTE': '1', 08:58:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:58:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:58:38 INFO - 'NO_EM_RESTART': '1', 08:58:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:58:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:38 INFO - 'PWD': '/builds/slave/test', 08:58:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:58:38 INFO - 'RUST_BACKTRACE': 'full', 08:58:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:58:38 INFO - 'SHELL': '/bin/bash', 08:58:38 INFO - 'SHLVL': '1', 08:58:38 INFO - 'STYLO_FORCE_ENABLED': '1', 08:58:38 INFO - 'TERM': 'linux', 08:58:38 INFO - 'TMOUT': '86400', 08:58:38 INFO - 'USER': 'cltbld', 08:58:38 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823', 08:58:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:38 INFO - '_': '/tools/buildbot/bin/python'} 08:58:38 INFO - TEST-INFO | started process 11660 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRIQcqR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:58:39 INFO - PID 11660 | 08:58:39 INFO - PID 11660 | (/builds/slave/test/build/application/firefox/firefox:11709): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:58:39 INFO - PID 11660 | 08:58:50 INFO - PID 11660 | RSS: Main: 169750528 08:58:50 INFO - PID 11660 | 08:58:52 INFO - PID 11660 | Cycle 1(1): loaded http://localhost:55132/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55132/tests/kraken/driver.html?testName=audio-beat-detection) 08:58:53 INFO - PID 11660 | RSS: Main: 175955968 08:58:53 INFO - PID 11660 | 08:58:56 INFO - PID 11660 | Cycle 1(1): loaded http://localhost:55132/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55132/tests/kraken/driver.html?testName=audio-dft) 08:58:57 INFO - PID 11660 | RSS: Main: 179802112 08:58:57 INFO - PID 11660 | 08:59:00 INFO - PID 11660 | Cycle 1(1): loaded http://localhost:55132/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55132/tests/kraken/driver.html?testName=audio-fft) 08:59:00 INFO - PID 11660 | RSS: Main: 172150784 08:59:00 INFO - PID 11660 | 08:59:02 INFO - PID 11660 | Cycle 1(1): loaded http://localhost:55132/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55132/tests/kraken/driver.html?testName=audio-oscillator) 08:59:03 INFO - PID 11660 | RSS: Main: 172400640 08:59:03 INFO - PID 11660 | 08:59:05 INFO - PID 11660 | Cycle 1(1): loaded http://localhost:55132/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55132/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:59:06 INFO - PID 11660 | RSS: Main: 172576768 08:59:06 INFO - PID 11660 | 08:59:09 INFO - PID 11660 | Cycle 1(1): loaded http://localhost:55132/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55132/tests/kraken/driver.html?testName=imaging-darkroom) 08:59:10 INFO - PID 11660 | RSS: Main: 174211072 08:59:10 INFO - PID 11660 | 08:59:13 INFO - PID 11660 | Cycle 1(1): loaded http://localhost:55132/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55132/tests/kraken/driver.html?testName=imaging-desaturate) 08:59:14 INFO - PID 11660 | RSS: Main: 174252032 08:59:14 INFO - PID 11660 | 08:59:17 INFO - PID 11660 | Cycle 1(1): loaded http://localhost:55132/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55132/tests/kraken/driver.html?testName=json-parse-financial) 08:59:17 INFO - PID 11660 | RSS: Main: 174219264 08:59:17 INFO - PID 11660 | 08:59:19 INFO - PID 11660 | Cycle 1(1): loaded http://localhost:55132/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55132/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:59:19 INFO - PID 11660 | RSS: Main: 174424064 08:59:19 INFO - PID 11660 | 08:59:21 INFO - PID 11660 | Cycle 1(1): loaded http://localhost:55132/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55132/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:59:22 INFO - PID 11660 | RSS: Main: 174452736 08:59:22 INFO - PID 11660 | 08:59:24 INFO - PID 11660 | Cycle 1(1): loaded http://localhost:55132/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55132/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:59:24 INFO - PID 11660 | RSS: Main: 174215168 08:59:24 INFO - PID 11660 | 08:59:27 INFO - PID 11660 | Cycle 1(1): loaded http://localhost:55132/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55132/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:59:28 INFO - PID 11660 | RSS: Main: 174796800 08:59:28 INFO - PID 11660 | 08:59:31 INFO - PID 11660 | Cycle 1(1): loaded http://localhost:55132/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55132/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:59:31 INFO - PID 11660 | RSS: Main: 175099904 08:59:31 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | Cycle 1(1): loaded http://localhost:55132/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55132/tests/kraken/driver.html?testName=ai-astar) 08:59:33 INFO - PID 11660 | RSS: Main: 176390144 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | __start_tp_report 08:59:33 INFO - PID 11660 | _x_x_mozilla_page_load 08:59:33 INFO - PID 11660 | _x_x_mozilla_page_load_details 08:59:33 INFO - PID 11660 | |i|pagename|runs| 08:59:33 INFO - PID 11660 | |0;ai-astar;100;109;100;107;112;109;109;100;108;109 08:59:33 INFO - PID 11660 | |1;audio-beat-detection;148;118;151;180;123;142;146;121;174;119 08:59:33 INFO - PID 11660 | |2;audio-dft;163;162;164;161;162;161;163;168;170;164 08:59:33 INFO - PID 11660 | |3;audio-fft;75;75;75;166;77;77;73;73;154;74 08:59:33 INFO - PID 11660 | |4;audio-oscillator;79;79;134;80;80;79;132;79;79;130 08:59:33 INFO - PID 11660 | |5;imaging-gaussian-blur;113;114;114;114;114;113;113;115;115;116 08:59:33 INFO - PID 11660 | |6;imaging-darkroom;148;147;148;149;147;148;149;148;147;148 08:59:33 INFO - PID 11660 | |7;imaging-desaturate;94;94;93;94;95;94;93;94;94;94 08:59:33 INFO - PID 11660 | |8;json-parse-financial;52;52;52;53;53;51;52;52;50;51 08:59:33 INFO - PID 11660 | |9;json-stringify-tinderbox;39;39;38;38;38;38;38;39;38;38 08:59:33 INFO - PID 11660 | |10;stanford-crypto-aes;77;77;75;76;74;77;75;75;78;76 08:59:33 INFO - PID 11660 | |11;stanford-crypto-ccm;118;131;136;124;119;126;119;123;128;159 08:59:33 INFO - PID 11660 | |12;stanford-crypto-pbkdf2;177;178;184;184;170;172;172;173;172;172 08:59:33 INFO - PID 11660 | |13;stanford-crypto-sha256-iterative;65;68;65;65;67;66;69;64;65;64 08:59:33 INFO - PID 11660 | __end_tp_report 08:59:33 INFO - PID 11660 | __start_cc_report 08:59:33 INFO - PID 11660 | _x_x_mozilla_cycle_collect,956 08:59:33 INFO - PID 11660 | __end_cc_report 08:59:33 INFO - PID 11660 | __startTimestamp1503935973947__endTimestamp 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | ------- Summary: start ------- 08:59:33 INFO - PID 11660 | Number of tests: 14 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | [#0] ai-astar Cycles:10 Average:106.30 Median:109.00 stddev:4.52 (4.1%) stddev-sans-first:4.18 08:59:33 INFO - PID 11660 | Values: 100.0 109.0 100.0 107.0 112.0 109.0 109.0 100.0 108.0 109.0 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:147.00 stddev:22.38 (15.2%) stddev-sans-first:23.64 08:59:33 INFO - PID 11660 | Values: 148.0 118.0 151.0 180.0 123.0 142.0 146.0 121.0 174.0 119.0 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.50 stddev:2.97 (1.8%) stddev-sans-first:3.14 08:59:33 INFO - PID 11660 | Values: 163.0 162.0 164.0 161.0 162.0 161.0 163.0 168.0 170.0 164.0 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | [#3] audio-fft Cycles:10 Average:91.90 Median:75.00 stddev:36.03 (48.0%) stddev-sans-first:37.69 08:59:33 INFO - PID 11660 | Values: 75.0 75.0 75.0 166.0 77.0 77.0 73.0 73.0 154.0 74.0 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:79.00 stddev:25.48 (32.3%) stddev-sans-first:26.36 08:59:33 INFO - PID 11660 | Values: 79.0 79.0 134.0 80.0 80.0 79.0 132.0 79.0 79.0 130.0 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.99 (0.9%) stddev-sans-first:0.97 08:59:33 INFO - PID 11660 | Values: 113.0 114.0 114.0 114.0 114.0 113.0 113.0 115.0 115.0 116.0 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 08:59:33 INFO - PID 11660 | Values: 148.0 147.0 148.0 149.0 147.0 148.0 149.0 148.0 147.0 148.0 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 08:59:33 INFO - PID 11660 | Values: 94.0 94.0 93.0 94.0 95.0 94.0 93.0 94.0 94.0 94.0 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:0.92 (1.8%) stddev-sans-first:0.97 08:59:33 INFO - PID 11660 | Values: 52.0 52.0 52.0 53.0 53.0 51.0 52.0 52.0 50.0 51.0 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | [#9] json-stringify-tinderbox Cycles:10 Average:38.30 Median:38.00 stddev:0.48 (1.3%) stddev-sans-first:0.44 08:59:33 INFO - PID 11660 | Values: 39.0 39.0 38.0 38.0 38.0 38.0 38.0 39.0 38.0 38.0 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:76.50 stddev:1.25 (1.6%) stddev-sans-first:1.27 08:59:33 INFO - PID 11660 | Values: 77.0 77.0 75.0 76.0 74.0 77.0 75.0 75.0 78.0 76.0 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | [#11] stanford-crypto-ccm Cycles:10 Average:128.30 Median:127.00 stddev:12.20 (9.6%) stddev-sans-first:12.36 08:59:33 INFO - PID 11660 | Values: 118.0 131.0 136.0 124.0 119.0 126.0 119.0 123.0 128.0 159.0 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.40 Median:175.00 stddev:5.15 (2.9%) stddev-sans-first:5.43 08:59:33 INFO - PID 11660 | Values: 177.0 178.0 184.0 184.0 170.0 172.0 172.0 173.0 172.0 172.0 08:59:33 INFO - PID 11660 | 08:59:33 INFO - PID 11660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:65.50 stddev:1.69 (2.6%) stddev-sans-first:1.76 08:59:33 INFO - PID 11660 | Values: 65.0 68.0 65.0 65.0 67.0 66.0 69.0 64.0 65.0 64.0 08:59:33 INFO - PID 11660 | -------- Summary: end -------- 08:59:33 INFO - PID 11660 | 08:59:34 INFO - PID 11660 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 08:59:34 INFO - TEST-INFO | 11660: exit 0 08:59:34 INFO - TEST-OK | kraken | took 62025ms 08:59:34 INFO - SUITE-END | took 1173s 08:59:35 INFO - Completed test suite (00:19:34) 08:59:35 INFO - javascript benchmark 08:59:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8504.182582281683, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3316.436205845006, 3324.7672662913574, 3349.129226401136, 3361.966380336197, 3366.8821591244296, 6276.811695649132, 6318.652474113927, 6328.58864173829, 6412.903806442907, 6465.644389558573, 6402.5518213724945, 6449.871002579957, 6467.644279564622, 6476.935230647692, 6573.0797688323555, 5862.853428664285, 5894.6168499047635, 5961.463468287875, 5973.581849270553, 5984.820455386345, 5793.391693872146, 5799.449052340021, 5847.79532716355, 5861.8827623447505, 5889.263842019747, 5811.215485909391, 5840.737166827482, 5966.612170208944, 5983.252093488314, 5985.1022346647915, 5831.067029275296, 5880.647161170344, 5960.970195148997, 5975.223220981245, 5980.0, 5816.738246778863, 5847.2983242010805, 5860.2967643883, 5929.9703501482645, 5930.0, 6401.3598640135615, 6437.774677886251, 6438.1630388049725, 6451.967740161316, 6470.514711396664, 6874.931250687479, 6927.168739751211, 6970.163580370388, 6976.093107895992, 6983.0, 6945.0971373721595, 6951.478639102088, 6984.301569843026, 7050.400715939151, 7051.3653771160325, 6317.273513545909, 6416.422521973045, 6433.228012638419, 6458.483321334329, 6556.967215163893, 7135.678894449762, 7141.785746427511, 7147.356737893615, 7153.427725781925, 7162.033125527987, 6768.864622707519, 6793.660316984131, 6852.211995620568, 6862.107925969692, 6867.244603093655, 6902.275261097613, 6916.377526022682, 6969.16370035599, 6995.125609298838, 7001.649917504104, 6738.663066846638, 6817.931820681829, 6834.0, 6836.111305530249, 6889.242183359826, 6870.519063665595, 6902.102726645604, 6904.378605925391, 6920.238773734885, 6924.4114250288285, 5413.10683737179, 5421.051316019681, 5495.560355171577, 5495.670259784426, 5497.8075767347955, 4753.310769938409, 4770.403699537558, 4787.832425865078, 4804.159272127364, 4814.470408255089, 5980.700964951735, 6047.909281360783, 6052.152698622196, 6059.484943779828, 6060.606060606135, 6898.2756810535175, 6985.511014229057, 6987.1266091738535, 6987.89518157228, 6990.231074581792, 6035.577509574287, 6050.0, 6094.634321940609, 6130.724117414727, 6145.692715364214, 5944.316403613553, 5971.641701497924, 6028.186194863725, 6037.184980027728, 6064.969675151596, 6479.870402591921, 6548.70530826114, 6590.275069742324, 6611.933880661132, 6723.125993620798, 6947.3747362737595, 6959.338862808025, 6970.337817907291, 6985.441164706913, 6993.19578248508, 6602.537822352389, 6607.900881486878, 6612.107365505787, 6693.665316734241, 6702.19573651165, 6759.5606285591275, 6839.384455399038, 6850.588964662136, 6881.621510816954, 6902.930970690229, 6767.0, 6856.074429952092, 6907.240203577502, 6997.020417141706, 6999.720011199699, 6721.764738234138, 6739.898901516579, 6744.359285867835, 6747.122874026345, 6770.086038384755, 6645.335466453316, 6681.0, 6696.665166741546, 6740.66296685154, 6763.830904227434, 6575.50683698715, 6624.403803657694, 6654.401103900478, 6674.0, 6730.394264516021, 6633.568818026813, 6680.198376194888, 6761.22245941723, 6762.594244345355, 6762.763303284361, 6450.322716114741, 6463.57986730861, 6504.0, 6576.046473261446, 6584.7036883341325], "value": 6303.080553582792, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [754.7358424551404, 837.1419295222398, 859.7205908079876, 861.3496809908518, 1111.288775183883, 29488.557671634917, 30048.699513004864, 39442.60557394425, 39820.402693959615, 39955.60044399555, 40245.0, 40620.187596248055, 40673.38989915154, 40773.59226407735, 40856.59143408565, 7473.439492038092, 7576.356009739179, 7648.579328136951, 7651.502652327608, 7656.3109320161175, 7582.203868593773, 7671.194524574923, 7676.923230767705, 7685.0777906651, 7691.077070751518, 7633.08402991639, 7690.769276921701, 7692.076950765916, 7692.615369231544, 7697.0763508378795, 7630.313271805537, 7637.656305466267, 7639.579823109727, 7640.083190017206, 7659.119201291811, 7578.583177925212, 7612.923870761332, 7613.0, 7616.352610028099, 7623.2376762323875, 35978.280434391425, 36023.27953440943, 36229.81885090584, 36263.63736362629, 36295.27409451796, 3403.2512847173584, 3435.707964822993, 3447.0175999839926, 3451.8101504417245, 3453.879114231015, 30502.54246186309, 30537.23656908595, 30584.69415305863, 30603.387932241454, 30643.233919151975, 5040.067587496325, 5042.873928151789, 5072.467390923936, 5111.131107711698, 5142.845714628567, 183253.0, 184502.0, 184603.0, 185371.0, 185416.0, 45153.54846451559, 45193.32210016852, 45227.32159017617, 45315.320270195974, 45391.546084538735, 47388.0, 47538.76230618825, 47596.5240347599, 47608.5239147611, 47626.76186619045, 13704.24626645524, 13714.588562343146, 14303.570892873231, 15422.228888555528, 15682.0, 11442.828357574643, 11444.713882152846, 11448.084153267719, 11454.656360309204, 11524.539018439169, 13324.73350532984, 13366.93316533411, 13368.933155334162, 13397.598072057659, 13398.196108233537, 5619.43805619443, 5683.715814209273, 5683.9715801420725, 5698.57535616096, 5722.025901064127, 975.2929126383316, 982.9655962041294, 1001.4592120254981, 1006.3760468509571, 1011.8431643095332, 21749.78250217509, 22092.802367550114, 22139.225127120473, 22182.0, 22399.88800055989, 1060.4750648429076, 1080.9459527023776, 1088.586337191862, 1096.7477480179602, 1103.939283339408, 11570.59502917394, 11592.188546801812, 11652.94173529127, 11753.588624398106, 11844.170908036354, 236465.0, 238133.0, 240220.0, 240272.0, 240929.0, 55953.440465594824, 56915.430845691844, 56965.99999999917, 57317.0, 57391.42608573944, 1420.453125546672, 1430.1347684650846, 1430.4278288684402, 1436.791665208539, 1438.503716217903, 26569.7343026575, 26859.059932903037, 26932.192034239004, 27065.1880443587, 27208.0, 34473.13817154491, 34543.65456345505, 34720.0, 34736.131596709274, 34839.825800870836, 196912.0, 197738.0, 199970.0, 200110.0, 200128.0, 33008.1747956303, 33307.666923331424, 34455.827720861234, 35562.46656300106, 35682.0, 43378.13243735045, 43880.78059609682, 43887.0, 44292.55707442884, 44502.10995780131, 31804.0, 32333.191670208435, 32423.0, 32776.180595485304, 33013.17467063342, 31520.2119946994, 31646.0, 31798.20504487314, 31932.52101218437, 32463.675363246064, 35988.64011359853, 36074.4588831173, 36224.818875905454, 36307.45538816867, 36415.453768192965, 30882.227944301572, 31002.0, 31491.685083148874, 31503.842480788368, 31780.682193177774, 22317.442063948532, 22806.63160210326, 23093.422664433525, 23334.416639584146, 23506.412339691644], "value": 16820.56773552952, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.8116949652368, 549.6509716330131, 553.7508121345395, 562.7017680629267, 567.4524084258691, 21236.0, 21980.670289945665, 21986.560268794616, 22017.449563760914, 22607.208747693832, 24550.631740523906, 25064.49871002579, 25068.623970640456, 25090.0, 25154.62268065976, 6396.264429590598, 6541.149650545422, 6543.247526534462, 6545.443637290836, 6546.934530654704, 6025.3372129065765, 6412.134361861135, 6464.127342808732, 6558.049082882979, 6593.67031648418, 6567.737290508374, 6572.80281591553, 6573.506986975972, 6574.539782215247, 6576.934230657704, 6539.509536784736, 6541.182352205974, 6541.182352205974, 6541.967290163542, 6569.7700580479695, 6052.425019623129, 6054.515638748933, 6059.0608455689435, 6093.116498107794, 6105.572609917263, 27716.168514944584, 28221.435571288665, 28767.28081797951, 28811.27971800701, 28990.130296091153, 1994.9301774437824, 2027.0574183004974, 2027.1384661518796, 2027.4221846773598, 2028.6348457277684, 19163.520911977314, 19669.016549172484, 19699.310524131586, 19732.50668733279, 19737.309194178135, 2378.1703209842594, 2393.6170212765874, 2452.1662634704285, 2466.728659847415, 2486.105002199207, 130046.0, 137129.3143534286, 137683.0, 137726.0, 138250.0, 14039.719205616035, 14130.293485325692, 14634.707305853823, 14883.553493395215, 15240.695186096216, 22833.40166188351, 22989.080436782675, 23036.005319308384, 23040.4239894004, 23048.884755576113, 10146.0, 10298.845517317246, 10352.741181470374, 10464.0, 10495.895041049493, 9698.27262955267, 9767.365121267096, 9784.874739405059, 9833.852492212622, 9882.308238423242, 7722.884156737653, 8824.735257942271, 8967.282617390592, 8986.14631609996, 9044.321675874337, 2629.316377741801, 2637.815352925315, 2665.3736371952577, 2701.2711864406797, 2763.765079968185, 951.9714408567754, 957.8515330123842, 965.478641533564, 971.4220039076738, 975.8482545986084, 19081.522961926064, 19499.220031198878, 19667.803321966596, 19677.704834427495, 19927.20291188365, 1030.4281123976116, 1033.405791669778, 1034.095166729112, 1035.3270374256792, 1042.213129087534, 10528.789424211473, 10795.19036072282, 10859.945700271448, 10864.511097000654, 10967.232293739362, 483672.0, 496899.0, 500917.0, 513281.0, 514878.0, 106694.0, 108666.45666771615, 108798.45600771946, 114332.42833785778, 116536.41731791456, 1017.9338343007681, 1156.3293289891842, 1176.053277111933, 1186.0393081603756, 1187.9703007424885, 20076.19695212175, 20300.390988270374, 20375.0, 20673.379798606067, 20751.481212969193, 56879.715601421725, 58203.4179658198, 60165.39834601598, 61151.38848611457, 62566.374336256056, 70124.64937675279, 70895.64552177206, 71232.64383678048, 71497.64251178711, 71535.64232179015, 11613.709657258298, 14166.858331416553, 14559.563213103624, 14893.404263829325, 14986.775198371815, 23029.0, 23259.53480930406, 23402.88298558496, 24398.0, 24497.142600008916, 17979.550511237325, 18471.90764046171, 18858.71711924295, 18878.24487020504, 18910.243590256236, 16913.66172676564, 17205.13974301305, 17249.482515524553, 17252.65494690074, 17320.480385588453, 18840.43478695641, 19271.22915083381, 19291.324803632368, 19471.610567788848, 19502.0, 14145.151290922578, 14506.492272770402, 14523.78214326807, 14554.126752394466, 14570.4900328488, 17777.644447110728, 18343.81656183421, 18348.90825545864, 18356.632867342847, 18690.065496725383], "value": 11961.74205673398, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1297.824793652857, 1341.6712905338188, 1341.7584834729744, 1345.4752646467875, 1353.1948490648067, 14370.712585748292, 14913.478028269014, 15005.62485937852, 15064.77402838957, 15067.09597424154, 15375.846241537582, 15634.218289085557, 15634.84365156351, 15641.687166256697, 15656.843431565681, 2743.684476285233, 2802.7897907657007, 2806.929826754327, 2807.368342123016, 2808.7612552933024, 2573.897044118233, 2706.350475885793, 2738.835669859805, 2778.9722102779015, 2781.0961437532783, 2746.1761471558452, 2786.9827512957727, 2789.0656630028966, 2789.121426750577, 2790.4698107359636, 2687.784977201829, 2704.5537486314834, 2750.8899644014214, 2752.0642981386322, 2753.1465245773875, 2667.5198464276423, 2715.6469658944407, 2719.279390961397, 2719.904803331874, 2721.6189733437336, 14599.635009124751, 14851.257437128208, 14932.776008359884, 14938.103713777207, 14957.551273461813, 7676.539407635504, 7922.088959769585, 7937.880931786028, 7941.6029198539845, 7942.602869856484, 5240.266362709223, 5241.633085684004, 5584.497395234408, 5587.106063029936, 5642.943570564283, 1888.1031510032763, 1898.9620207595908, 1922.9903850480657, 1948.3180886689686, 1957.0410498855458, 24887.50224995508, 25008.374790630016, 25075.247742567753, 25101.87449062761, 25120.120795772244, 6437.452816510556, 6461.321561236096, 6514.478841692653, 6792.45660347171, 6831.49707064445, 16158.838411615969, 16187.595310117342, 16212.75680864788, 16224.67550649004, 16280.0, 7554.546727196386, 7556.65995030225, 7589.848203035908, 7592.544447333178, 7593.772186834404, 6977.406920411822, 6979.267176946449, 7054.858902822018, 7055.294470553006, 7087.858242835217, 4995.450500445021, 5160.664556803796, 5227.215917612389, 5229.555487783581, 5239.764210610456, 1365.160426337815, 1374.4227424481542, 1561.0555613853455, 1576.3143032780777, 1611.2266112266177, 860.3160487412522, 864.8572985457455, 874.1826392323198, 876.8553188723916, 881.3918396306528, 7378.778636640909, 7429.554226746304, 7471.8505629887095, 7487.605280449149, 7546.056742907137, 931.7577429868284, 966.1690945786618, 974.3423189347162, 984.7193549838262, 992.8510723391405, 4541.978054937678, 4587.0, 4628.074385122989, 4645.651576131803, 4660.231061874821, 20165.19339226444, 20552.486187845123, 20804.0, 20804.0, 20812.791872081085, 19403.320883769, 20806.167753290003, 20812.479688007617, 20845.0, 20908.268210612554, 10568.947155264175, 10889.421033950191, 10904.400257985886, 10908.181886358554, 10913.618023369145, 8035.959820200862, 8598.097199793932, 8613.181747733955, 8640.351973601879, 8646.697365592174, 17985.460436186935, 18597.90701046486, 18662.160202790634, 18788.7181692272, 18798.15408306657, 18301.359452419103, 18545.44363669092, 18701.0, 18779.90610046941, 19062.523436914187, 12799.10406271552, 13302.534411295559, 13392.330383480981, 13633.522826701017, 13641.79537306961, 11544.307341559534, 13276.867231327564, 13411.597652070453, 13471.663208419868, 13523.391447384694, 9633.32566720351, 10017.599296028076, 10147.34042287249, 10461.320014199053, 10938.507767150657, 9323.0, 10304.278700490893, 10366.775995566913, 10728.839067413839, 10911.072558832588, 9014.549272536216, 9036.593353298986, 9114.088591140833, 9129.680461183827, 9169.128932751379, 6554.213494380705, 6594.901076483952, 6626.171728533934, 6642.0, 6720.731170753115, 6435.646039467687, 6492.091107245084, 6503.187101612299, 6599.637019963948, 6652.10196623469], "value": 6504.743975322746, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.16622492425483, 508.2554058304583, 510.5124606001267, 512.1523877981939, 524.6878107526021, 6407.391297826711, 6440.484761219097, 6478.708458119384, 6512.0, 6524.889319728564, 6292.496600271978, 6301.78608909456, 6336.42972132508, 6349.777757778479, 6390.297067322591, 5209.374875014994, 5216.713080780555, 5229.137192363274, 5267.55225805807, 5267.736613169345, 4878.341423907763, 5152.74236288186, 5188.558972487324, 5226.137687281613, 5320.042392369372, 4941.6540842140885, 4959.851204463872, 4994.1545010342015, 5041.369828771403, 5066.6706664066905, 4940.456549779521, 4986.252062190664, 5023.999999999982, 5025.547700706936, 5066.341375621182, 4396.164728701537, 4477.2164871147415, 4510.661700372485, 4513.796879140446, 4523.298888672228, 6152.723127459275, 6224.101648997054, 6285.120083188357, 6301.779437719658, 6326.291901186653, 5656.632318899299, 5715.542756579505, 5757.625754325997, 5762.394948530427, 5801.767929282866, 6594.868102637968, 6596.868062638769, 6720.462363010947, 6737.629430381327, 6740.52816302859, 4887.291342755316, 4910.779014944336, 4913.926291105637, 4922.950770492285, 4937.136001199811, 6693.954250815916, 6873.20958089816, 6930.203026651899, 7036.73338799016, 7053.92946070543, 4990.4011518618, 4992.152903425683, 5024.693579669312, 5046.798128074909, 5058.747062646853, 5053.267276244924, 5064.848054558369, 5083.593312534988, 5093.23601459784, 5096.1336572781975, 5746.2529871116485, 5764.913526297109, 5772.855678607984, 5773.1051686988585, 5815.3021637402935, 6741.224759152662, 6766.289539598271, 6805.625690586966, 6858.965705171542, 6861.828454288583, 1768.1159420289855, 1769.761082253905, 1770.6370194110177, 1775.8224177582138, 1792.7988247873957, 1144.1876267849752, 1207.637708687408, 1216.9878301216875, 1220.7009282725612, 1235.6416639174538, 5911.645301281937, 5913.142594323885, 5937.287525496881, 5953.672548009815, 6062.242219722535, 6763.0, 6860.6912688929115, 6861.828454288583, 6912.205096413876, 6916.757913473004, 5928.436798504135, 5942.8811423771285, 5976.551758618121, 5978.461938425476, 6010.819675409744, 3653.12325041988, 3670.4861319415304, 3690.981545092311, 3698.334299826003, 3717.0671250949354, 6595.901061484082, 6655.633940133339, 6675.5647535780045, 6683.0, 6764.120664313607, 6923.411510021604, 6967.303269672992, 7024.719011239596, 7059.752908648173, 7072.823179420556, 6053.334133245339, 6461.870762584722, 6497.610143391223, 6543.149390579194, 6601.240857301375, 6751.257361690308, 6764.357386048514, 6868.62222577763, 6883.965580172067, 6917.792466226021, 6675.23234828001, 6679.265280819009, 6710.221791225426, 6710.932890671031, 6744.763933262312, 6527.249366322933, 6570.207661743835, 6647.634380109141, 6672.799816005528, 6690.83272918181, 4935.728534930613, 4980.651354405157, 4992.326035985241, 5018.3977922648055, 5022.321986531918, 6642.269350371552, 6737.225219099866, 6778.118844550177, 6833.983974727368, 6971.3028697129885, 6552.57408268461, 6607.414220586928, 6618.867622647616, 6669.433098186709, 6756.459483241427, 6540.444062254572, 6586.571872828251, 6596.670166491752, 6690.498212634169, 6701.0, 6450.645214513247, 6488.513361498001, 6520.413162815369, 6585.275619681735, 6652.268250492539, 6654.567453115532, 6669.0, 6671.799846004627, 6686.1642294713165, 6777.322267773183, 6407.0, 6440.323766004502, 6449.322821103717, 6518.478521718346, 6525.249596296297], "value": 5136.453254001077, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [814.2793627639537, 825.492322221833, 845.6321500147433, 872.319590719239, 893.5085702863425, 16019.279132439038, 16673.99956002639, 16711.331546738114, 16772.83227167728, 16800.327986880526, 18478.907605461955, 18549.629007419877, 18607.069646517688, 18643.25426982919, 18652.34716784913, 858.0389963241179, 858.2404571953834, 858.6522458404356, 862.9989212513485, 887.4098724348316, 889.5329951775306, 927.8561822917459, 936.6440752514069, 953.0231512699504, 956.9569369378395, 980.0297705271801, 980.308882238024, 980.6567701304558, 980.872486576744, 980.9068138526865, 960.3661583354988, 969.8254314223437, 970.5729479029239, 971.2133172130526, 973.756560859785, 719.1047146302886, 720.7117153138734, 721.5490318550906, 721.9314165154302, 723.2405973727617, 17242.396516122, 17372.739408908878, 17414.825851741447, 17439.73840392395, 17482.213300401512, 20805.791942080687, 20812.0, 21333.146674133015, 21338.466538336666, 21413.571728565497, 16564.585885352964, 16985.745213821803, 16991.40530081441, 17128.57178570533, 17152.656946861116, 3628.6008539060945, 3663.102539877747, 3667.3032123896373, 3675.5221821163077, 3683.3001729671546, 128201.35899320537, 129051.0, 130954.0, 131295.0, 132013.0, 16572.668546629, 17266.568335791453, 17280.567985800455, 17282.0, 17308.91345543264, 29900.55149172764, 29921.251968700955, 30060.398792024036, 30523.0, 30675.539866902014, 8663.350248731243, 8690.695825646071, 8692.913070869337, 8695.695650652196, 8722.084181161013, 4154.459920210354, 4213.768242746678, 4285.785710714452, 4287.592678695519, 4294.742315461082, 5012.223105418666, 5098.694078355311, 5115.411727651368, 5125.077486052546, 5144.048351055067, 951.8524628682566, 1012.9037741414554, 1044.7179261599326, 1045.717656232813, 1046.0899017854515, 679.4700133895569, 692.8995295682098, 699.909011828459, 703.1351437731516, 706.7137809187287, 11390.829137562943, 11422.25755325901, 11598.536058557564, 11735.826417358196, 11966.521339146511, 673.1350214973735, 682.5495173185674, 689.6103701408688, 694.7950354645392, 704.5772536478072, 5725.5133313667975, 5728.570357223225, 5761.567882408753, 5770.336411312753, 5782.739776710058, 112554.0, 114391.42804285925, 115569.0, 118965.0, 119582.0, 101994.49002754939, 102290.48854755679, 103780.96219037865, 104239.4788026055, 105703.4714826421, 84918.0, 85354.86612834729, 86019.56990215009, 88435.0, 88573.55713221521, 10510.369377837354, 10533.473326333806, 10718.0, 10770.08454281395, 11677.76644467123, 38810.417843731804, 39987.80006100067, 40507.79746101251, 40994.59005410027, 41484.0, 68584.3141568578, 70140.29859701337, 70881.64559177171, 74903.25096748963, 77743.61128194323, 16385.754213686567, 18454.0, 18504.16731247124, 18675.906620466812, 18862.43412697621, 21001.369958901258, 21601.0, 21705.674414883473, 21776.89111554432, 22000.559988799814, 16985.745213821556, 17044.914775426045, 17157.91421042887, 18013.909930450787, 18124.909375453564, 17484.77606567528, 18458.169382377557, 18587.0, 19381.4185574433, 19618.411447656592, 17864.374746883797, 18211.45365639033, 18359.35742249015, 18877.90561047186, 19001.714974275674, 11493.54025838957, 11771.646850594496, 11841.60591231413, 11890.405479726152, 11891.524339026342, 12446.626601201979, 12535.561255356019, 13367.799483007957, 13461.528846490326, 13530.458781648624], "value": 8926.668056318033, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1490.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 109.0, 100.0, 107.0, 112.0, 109.0, 109.0, 100.0, 108.0, 109.0], "value": 106.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 118.0, 151.0, 180.0, 123.0, 142.0, 146.0, 121.0, 174.0, 119.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 164.0, 161.0, 162.0, 161.0, 163.0, 168.0, 170.0, 164.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 166.0, 77.0, 77.0, 73.0, 73.0, 154.0, 74.0], "value": 91.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 134.0, 80.0, 80.0, 79.0, 132.0, 79.0, 79.0, 130.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 115.0, 115.0, 116.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 148.0, 149.0, 147.0, 148.0, 149.0, 148.0, 147.0, 148.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 95.0, 94.0, 93.0, 94.0, 94.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 53.0, 53.0, 51.0, 52.0, 52.0, 50.0, 51.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 38.0, 38.0, 38.0, 38.0, 38.0, 39.0, 38.0, 38.0], "value": 38.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 75.0, 76.0, 74.0, 77.0, 75.0, 75.0, 78.0, 76.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 131.0, 136.0, 124.0, 119.0, 126.0, 119.0, 123.0, 128.0, 159.0], "value": 128.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 178.0, 184.0, 184.0, 170.0, 172.0, 172.0, 173.0, 172.0, 172.0], "value": 175.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 65.0, 65.0, 67.0, 66.0, 69.0, 64.0, 65.0, 64.0], "value": 65.8, "unit": "score"}]}]} 08:59:35 INFO - Return code: 0 08:59:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:59:35 INFO - # TBPL SUCCESS # 08:59:35 INFO - Running post-action listener: _package_coverage_data 08:59:35 INFO - Running post-action listener: _resource_record_post_action 08:59:35 INFO - [mozharness: 2017-08-28 15:59:35.238381Z] Finished run-tests step (success) 08:59:35 INFO - Running post-run listener: _resource_record_post_run 08:59:35 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 8192; Write bytes: 441212928; Read time: 264; Write time: 372564 08:59:35 INFO - TinderboxPrint: CPU usage
13.1% 08:59:35 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 264 08:59:35 INFO - TinderboxPrint: I/O write bytes / time
441,212,928 / 372,564 08:59:35 INFO - TinderboxPrint: CPU idle
8,255.0 (86.9%) 08:59:35 INFO - TinderboxPrint: CPU user
1,193.8 (12.6%) 08:59:35 INFO - TinderboxPrint: Swap in / out
0 / 0 08:59:35 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 119242752; Read time: 0; Write time: 90472 08:59:35 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 08:59:35 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 321970176; Read time: 248; Write time: 282092 08:59:35 INFO - Running post-run listener: _upload_blobber_files 08:59:35 INFO - Blob upload gear active. 08:59:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:59:35 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 08:59:35 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'] 08:59:35 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 08:59:36 INFO - (blobuploader) - INFO - Open directory for files ... 08:59:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:59:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:59:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:59:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:59:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:59:37 INFO - (blobuploader) - INFO - Done attempting. 08:59:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:59:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:59:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:59:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:59:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:59:38 INFO - (blobuploader) - INFO - Done attempting. 08:59:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:59:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:59:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:59:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:59:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:59:41 INFO - (blobuploader) - INFO - Done attempting. 08:59:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:59:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:59:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:59:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:59:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:59:42 INFO - (blobuploader) - INFO - Done attempting. 08:59:42 INFO - (blobuploader) - INFO - Iteration through files over. 08:59:42 INFO - Return code: 0 08:59:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:59:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:59:42 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f285711cf24a7e3517c008dc5548ca1b15c8cc497c83b22bb89b0140eab43325a16a885362a16ae211708ec675999c7c1da4449c34eb439ccfd61e64500b314", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf74b460f8a4265f23b26bca41641f920cfa3a51a18eeed543d00d022311fd7973663d928676a324b1e49ae30f582321e9209d9b7c56dfee8fa6de6baff8812d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f18443b7343b32256cd23e6dab7b175cab67f47c61be3a0670d30939824eb189fd14711dc40c6c3ac354007de366ffb4480fc964582d82c592f12131948d4326", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b616a224a12bd0f765d5819b5574a6f8136303a656da3bbc4e97e33e82c056c83b3cd0551746ffd0acfd00921979115235d725741c60d72dd9b17d438f81cf4"} 08:59:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:59:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:59:42 INFO - Contents: 08:59:42 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f285711cf24a7e3517c008dc5548ca1b15c8cc497c83b22bb89b0140eab43325a16a885362a16ae211708ec675999c7c1da4449c34eb439ccfd61e64500b314", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf74b460f8a4265f23b26bca41641f920cfa3a51a18eeed543d00d022311fd7973663d928676a324b1e49ae30f582321e9209d9b7c56dfee8fa6de6baff8812d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f18443b7343b32256cd23e6dab7b175cab67f47c61be3a0670d30939824eb189fd14711dc40c6c3ac354007de366ffb4480fc964582d82c592f12131948d4326", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b616a224a12bd0f765d5819b5574a6f8136303a656da3bbc4e97e33e82c056c83b3cd0551746ffd0acfd00921979115235d725741c60d72dd9b17d438f81cf4"} 08:59:42 INFO - Running post-run listener: copy_logs_to_upload_dir 08:59:42 INFO - Copying logs to upload dir... 08:59:42 INFO - mkdir: /builds/slave/test/build/upload/logs 08:59:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.035093 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-28 08:59:42.761200) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 08:59:42.764897) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823 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/2f285711cf24a7e3517c008dc5548ca1b15c8cc497c83b22bb89b0140eab43325a16a885362a16ae211708ec675999c7c1da4449c34eb439ccfd61e64500b314", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf74b460f8a4265f23b26bca41641f920cfa3a51a18eeed543d00d022311fd7973663d928676a324b1e49ae30f582321e9209d9b7c56dfee8fa6de6baff8812d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f18443b7343b32256cd23e6dab7b175cab67f47c61be3a0670d30939824eb189fd14711dc40c6c3ac354007de366ffb4480fc964582d82c592f12131948d4326", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b616a224a12bd0f765d5819b5574a6f8136303a656da3bbc4e97e33e82c056c83b3cd0551746ffd0acfd00921979115235d725741c60d72dd9b17d438f81cf4"} build_url:https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011439 build_url: 'https://queue.taskcluster.net/v1/task/Sv4qr0ycSAaRWiVssPK9WA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f285711cf24a7e3517c008dc5548ca1b15c8cc497c83b22bb89b0140eab43325a16a885362a16ae211708ec675999c7c1da4449c34eb439ccfd61e64500b314", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf74b460f8a4265f23b26bca41641f920cfa3a51a18eeed543d00d022311fd7973663d928676a324b1e49ae30f582321e9209d9b7c56dfee8fa6de6baff8812d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f18443b7343b32256cd23e6dab7b175cab67f47c61be3a0670d30939824eb189fd14711dc40c6c3ac354007de366ffb4480fc964582d82c592f12131948d4326", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b616a224a12bd0f765d5819b5574a6f8136303a656da3bbc4e97e33e82c056c83b3cd0551746ffd0acfd00921979115235d725741c60d72dd9b17d438f81cf4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 08:59:42.792074) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:59:42.792415) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1503934730.580939-755089823 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004476 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:59:42.874313) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 08:59:42.874622) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 08:59:42.874928) ========= ========= Total master_lag: 0.18 =========