builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-035 starttime: 1503576863.02 results: success (0) revision: 8d1350135a04225958ada4c570e818366a3ae964 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 05:14:23.022480) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 05:14:23.022954) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 05:14:23.023241) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 05:14:23.040246) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:14:23.040612) ========= 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=ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-24 05:14:22-- 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% 38.3M=0s 2017-08-24 05:14:22 (38.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.235519 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:14:23.291791) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:14:23.295154) ========= 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=ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.066697 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:14:23.375414) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 52 secs) (at 2017-08-24 05:14:23.375762) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8d1350135a04225958ada4c570e818366a3ae964 --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 8d1350135a04225958ada4c570e818366a3ae964 --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=ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-24 05:14:23,051 truncating revision to first 12 chars 2017-08-24 05:14:23,051 Setting DEBUG logging. 2017-08-24 05:14:23,051 attempt 1/10 2017-08-24 05:14:23,051 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8d1350135a04?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-24 05:14:24,480 attempt 1/10 2017-08-24 05:14:25,032 current task status: no status available at this point. state: PENDING 2017-08-24 05:14:25,032 sleeping for 11.00s (attempt 1/10) 2017-08-24 05:14:36,044 attempt 2/10 2017-08-24 05:14:36,600 current task status: no status available at this point. state: PENDING 2017-08-24 05:14:36,600 sleeping for 15.50s (attempt 2/10) 2017-08-24 05:14:52,116 attempt 3/10 2017-08-24 05:14:52,724 current task status: no status available at this point. state: STARTED 2017-08-24 05:14:52,724 sleeping for 22.25s (attempt 3/10) 2017-08-24 05:15:14,997 attempt 4/10 2017-08-24 05:15:15,397 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-24 05:15:15,680 unpacking tar archive at: mozilla-central-8d1350135a04/testing/mozharness/ program finished with exit code 0 elapsedTime=52.957215 ========= master_lag: -0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 52 secs) (at 2017-08-24 05:15:16.322552) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 05:15:16.322883) ========= script_repo_revision: 8d1350135a04225958ada4c570e818366a3ae964 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 05:15:16.323266) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 05:15:16.323528) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 05:15:16.340548) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-24 05:15:16.340840) ========= /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=ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:15:16 INFO - MultiFileLogger online at 20170824 05:15:16 in /builds/slave/test 05:15:16 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 05:15:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:15:16 INFO - {'append_to_log': False, 05:15:16 INFO - 'base_work_dir': '/builds/slave/test', 05:15:16 INFO - 'blob_upload_branch': 'Firefox', 05:15:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:15:16 INFO - 'branch': 'Firefox', 05:15:16 INFO - 'buildbot_json_path': 'buildprops.json', 05:15:16 INFO - 'code_coverage': False, 05:15:16 INFO - 'config_files': ('talos/linux_config.py',), 05:15:16 INFO - 'default_actions': ('clobber', 05:15:16 INFO - 'read-buildbot-config', 05:15:16 INFO - 'download-and-extract', 05:15:16 INFO - 'populate-webroot', 05:15:16 INFO - 'create-virtualenv', 05:15:16 INFO - 'install', 05:15:16 INFO - 'setup-mitmproxy', 05:15:16 INFO - 'run-tests'), 05:15:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:15:16 INFO - 'disable_ccov_upload': False, 05:15:16 INFO - 'download_minidump_stackwalk': True, 05:15:16 INFO - 'download_symbols': 'ondemand', 05:15:16 INFO - 'enable_stylo': False, 05:15:16 INFO - 'enable_webrender': False, 05:15:16 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 05:15:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:15:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:15:16 INFO - 'gecko_profile': False, 05:15:16 INFO - 'gecko_profile_interval': 0, 05:15:16 INFO - 'installer_path': 'installer.exe', 05:15:16 INFO - 'log_level': 'info', 05:15:16 INFO - 'log_name': 'talos', 05:15:16 INFO - 'log_to_console': True, 05:15:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:15:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:15:16 INFO - 'opt_config_files': (), 05:15:16 INFO - 'pip_index': False, 05:15:16 INFO - 'suite': 'dromaeojs-e10s', 05:15:16 INFO - 'system_bits': '32', 05:15:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:15:16 INFO - 'title': 'talos-linux64-ix-035', 05:15:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:15:16 INFO - 'use_talos_json': True, 05:15:16 INFO - 'verify': 'False', 05:15:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:15:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:15:16 INFO - 'work_dir': 'build'} 05:15:16 INFO - [mozharness: 2017-08-24 12:15:16.298941Z] Running clobber step. 05:15:16 INFO - Running pre-action listener: _resource_record_pre_action 05:15:16 INFO - Running main action method: clobber 05:15:16 INFO - rmtree: /builds/slave/test/build 05:15:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:15:18 INFO - Running post-action listener: _resource_record_post_action 05:15:18 INFO - [mozharness: 2017-08-24 12:15:18.249494Z] Finished clobber step (success) 05:15:18 INFO - [mozharness: 2017-08-24 12:15:18.249659Z] Running read-buildbot-config step. 05:15:18 INFO - Running pre-action listener: _resource_record_pre_action 05:15:18 INFO - Running main action method: read_buildbot_config 05:15:18 INFO - Using buildbot properties: 05:15:18 INFO - { 05:15:18 INFO - "project": "", 05:15:18 INFO - "product": "firefox", 05:15:18 INFO - "who": "archaeopteryx@coole-files.de", 05:15:18 INFO - "installer_path": "public/build/target.tar.bz2", 05:15:18 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 05:15:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:15:18 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 05:15:18 INFO - "stage_platform": "linux64-stylosequential", 05:15:18 INFO - "basedir": "/builds/slave/test", 05:15:18 INFO - "buildnumber": 46, 05:15:18 INFO - "platform": "ubuntu64_hw_styloseq", 05:15:18 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:15:18 INFO - "slavebuilddir": "test", 05:15:18 INFO - "taskId": "RdsSmQ4eQ_69xNQIvJpiKQ", 05:15:18 INFO - "branch": "mozilla-central", 05:15:18 INFO - "script_repo_revision": "production", 05:15:18 INFO - "revision": "8d1350135a04225958ada4c570e818366a3ae964", 05:15:18 INFO - "slavename": "talos-linux64-ix-035", 05:15:18 INFO - "repo_path": "mozilla-central" 05:15:18 INFO - } 05:15:18 INFO - Finding installer, test and symbols from parent task. 05:15:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RdsSmQ4eQ_69xNQIvJpiKQ'}, attempt #1 05:15:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RdsSmQ4eQ_69xNQIvJpiKQ'}, attempt #1 05:15:19 INFO - Task dependencies: MFQIylfTTO67nd9VpmjjPg 05:15:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg'}, attempt #1 05:15:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.tar.bz2 05:15:19 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.test_packages.json 05:15:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.crashreporter-symbols.zip 05:15:19 INFO - Running post-action listener: _resource_record_post_action 05:15:19 INFO - [mozharness: 2017-08-24 12:15:19.610058Z] Finished read-buildbot-config step (success) 05:15:19 INFO - [mozharness: 2017-08-24 12:15:19.610279Z] Running download-and-extract step. 05:15:19 INFO - Running pre-action listener: _resource_record_pre_action 05:15:19 INFO - Running main action method: download_and_extract 05:15:19 INFO - mkdir: /builds/slave/test/build/tests 05:15:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:19 INFO - trying https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.test_packages.json 05:15:19 INFO - Downloading https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:15:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:15:20 INFO - Downloaded 989 bytes. 05:15:20 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:15:20 INFO - Using the following test package requirements: 05:15:20 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:15:20 INFO - u'common': [u'target.common.tests.zip'], 05:15:20 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:15:20 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:15:20 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:15:20 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:15:20 INFO - u'mozbase': [u'target.common.tests.zip'], 05:15:20 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:15:20 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:15:20 INFO - u'web-platform': [u'target.common.tests.zip', 05:15:20 INFO - u'target.web-platform.tests.tar.gz'], 05:15:20 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:15:20 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:15:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.common.tests.zip 05:15:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:15:20 INFO - Fetch https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.common.tests.zip into memory 05:15:21 INFO - Content-Length response header: 54979823 05:15:21 INFO - Bytes received: 54979823 05:15:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:15:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.common.tests.zip 05:15:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:15:33 INFO - Fetch https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.common.tests.zip into memory 05:15:34 INFO - Content-Length response header: 54979823 05:15:34 INFO - Bytes received: 54979823 05:15:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.talos.tests.zip 05:15:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:15:46 INFO - Fetch https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.talos.tests.zip into memory 05:15:47 INFO - Content-Length response header: 14496384 05:15:47 INFO - Bytes received: 14496384 05:15:47 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')]} 05:15:47 INFO - trying https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.tar.bz2 05:15:47 INFO - Downloading https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:15:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:15:48 INFO - Downloaded 61016854 bytes. 05:15:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.tar.bz2 05:15:48 INFO - mkdir: /builds/slave/test/properties 05:15:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:15:48 INFO - Writing to file /builds/slave/test/properties/build_url 05:15:48 INFO - Contents: 05:15:48 INFO - build_url:https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.tar.bz2 05:15:48 INFO - Running post-action listener: _resource_record_post_action 05:15:48 INFO - Running post-action listener: find_tests_for_verification 05:15:48 INFO - Running post-action listener: set_extra_try_arguments 05:15:48 INFO - [mozharness: 2017-08-24 12:15:48.515249Z] Finished download-and-extract step (success) 05:15:48 INFO - [mozharness: 2017-08-24 12:15:48.515347Z] Running populate-webroot step. 05:15:48 INFO - Running pre-action listener: _resource_record_pre_action 05:15:48 INFO - Running main action method: populate_webroot 05:15:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:15:48 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 05:15:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:15:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:15:48 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 05:15:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:15:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:15:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:15:48 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:15:48 INFO - 'talos_options': ['--stylo'], 05:15:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:15:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:15:48 INFO - 'tests': ['damp', 'tps']}, 05:15:48 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:15:48 INFO - 'talos_options': ['--stylo'], 05:15:48 INFO - 'tests': ['damp', 'tps']}, 05:15:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:15:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:15:48 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 05:15:48 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 05:15:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:15:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:15:48 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:15:48 INFO - 'talos_options': ['--stylo'], 05:15:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:15:48 INFO - 'other-e10s': {'tests': ['a11yr', 05:15:48 INFO - 'ts_paint', 05:15:48 INFO - 'tpaint', 05:15:48 INFO - 'sessionrestore', 05:15:48 INFO - 'sessionrestore_many_windows', 05:15:48 INFO - 'sessionrestore_no_auto_restore', 05:15:48 INFO - 'tabpaint']}, 05:15:48 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 05:15:48 INFO - 'tests': ['a11yr', 05:15:48 INFO - 'ts_paint', 05:15:48 INFO - 'tpaint', 05:15:48 INFO - 'sessionrestore', 05:15:48 INFO - 'sessionrestore_many_windows', 05:15:48 INFO - 'sessionrestore_no_auto_restore', 05:15:48 INFO - 'tabpaint']}, 05:15:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:15:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:15:48 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 05:15:48 INFO - 'tests': ['bloom_basic_singleton']}, 05:15:48 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 05:15:48 INFO - 'tests': ['bloom_basic']}, 05:15:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:15:48 INFO - 'tsvgr_opacity', 05:15:48 INFO - 'tart', 05:15:48 INFO - 'tscrollx', 05:15:48 INFO - 'tsvg_static']}, 05:15:48 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 05:15:48 INFO - 'tests': ['tsvgx', 05:15:48 INFO - 'tsvgr_opacity', 05:15:48 INFO - 'tart', 05:15:48 INFO - 'tscrollx', 05:15:48 INFO - 'tsvg_static']}, 05:15:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:15:48 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:15:48 INFO - 'talos_options': ['--stylo'], 05:15:48 INFO - 'tests': ['tp5o']}, 05:15:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:15:48 INFO - 'talos_options': ['--mitmproxy', 05:15:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:15:48 INFO - '--firstNonBlankPaint'], 05:15:48 INFO - 'tests': ['tp6_google', 05:15:48 INFO - 'tp6_youtube', 05:15:48 INFO - 'tp6_amazon', 05:15:48 INFO - 'tp6_facebook']}, 05:15:48 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:15:48 INFO - 'talos_options': ['--stylo', 05:15:48 INFO - '--mitmproxy', 05:15:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:15:48 INFO - '--firstNonBlankPaint'], 05:15:48 INFO - 'tests': ['tp6_google', 05:15:48 INFO - 'tp6_youtube', 05:15:48 INFO - 'tp6_amazon', 05:15:48 INFO - 'tp6_facebook']}, 05:15:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:15:48 INFO - 'talos_options': ['--stylo', 05:15:48 INFO - '--stylo-threads=1', 05:15:48 INFO - '--mitmproxy', 05:15:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:15:48 INFO - '--firstNonBlankPaint'], 05:15:48 INFO - 'tests': ['tp6_google', 05:15:48 INFO - 'tp6_youtube', 05:15:48 INFO - 'tp6_amazon', 05:15:48 INFO - 'tp6_facebook']}, 05:15:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:15:48 INFO - 'talos_options': ['--xperf_path', 05:15:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:15:48 INFO - 'tests': ['tp5n']}, 05:15:48 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:15:48 INFO - 'talos_options': ['--stylo', 05:15:48 INFO - '--xperf_path', 05:15:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:15:48 INFO - 'tests': ['tp5n']}}} 05:15:48 INFO - Running post-action listener: _resource_record_post_action 05:15:48 INFO - [mozharness: 2017-08-24 12:15:48.529669Z] Finished populate-webroot step (success) 05:15:48 INFO - [mozharness: 2017-08-24 12:15:48.529760Z] Running create-virtualenv step. 05:15:48 INFO - Running pre-action listener: _resource_record_pre_action 05:15:48 INFO - Running main action method: create_virtualenv 05:15:48 INFO - Creating virtualenv /builds/slave/test/build/venv 05:15:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:15:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 05:15:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:15:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:15:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:15:49 INFO - Installing distribute.............................................................................................................................................................................................done. 05:15:49 INFO - Installing pip...............done. 05:15:49 INFO - Return code: 0 05:15:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:15:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:15:49 INFO - Reading from file tmpfile_stdout 05:15:49 INFO - Output received: 05:15:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 05:15:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7b130>, '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 0x1d7c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c4c0>, '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': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', '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 05:15:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:15:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:15:49 INFO - Using env: {'DISPLAY': ':0', 05:15:49 INFO - 'HOME': '/home/cltbld', 05:15:49 INFO - 'LANG': 'en_US.UTF-8', 05:15:49 INFO - 'LANGUAGE': 'en_US:en', 05:15:49 INFO - 'LOGNAME': 'cltbld', 05:15:49 INFO - 'MAIL': '/var/mail/cltbld', 05:15:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:49 INFO - 'MOZ_NO_REMOTE': '1', 05:15:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:49 INFO - 'NO_EM_RESTART': '1', 05:15:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:49 INFO - 'PWD': '/builds/slave/test', 05:15:49 INFO - 'SHELL': '/bin/bash', 05:15:49 INFO - 'SHLVL': '1', 05:15:49 INFO - 'TERM': 'linux', 05:15:49 INFO - 'TMOUT': '86400', 05:15:49 INFO - 'USER': 'cltbld', 05:15:49 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', 05:15:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:49 INFO - '_': '/tools/buildbot/bin/python'} 05:15:51 INFO - Ignoring indexes: http://pypi.python.org/simple/ 05:15:51 INFO - Downloading/unpacking pip>=1.5 05:15:51 INFO - Running setup.py egg_info for package pip 05:15:51 INFO - warning: no files found matching 'pip/cacert.pem' 05:15:51 INFO - warning: no files found matching '*.html' under directory 'docs' 05:15:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:15:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:15:51 INFO - Installing collected packages: pip 05:15:51 INFO - Found existing installation: pip 1.1 05:15:51 INFO - Uninstalling pip: 05:15:51 INFO - Successfully uninstalled pip 05:15:51 INFO - Running setup.py install for pip 05:15:51 INFO - warning: no files found matching 'pip/cacert.pem' 05:15:51 INFO - warning: no files found matching '*.html' under directory 'docs' 05:15:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:15:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:15:51 INFO - Installing pip script to /builds/slave/test/build/venv/bin 05:15:51 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 05:15:51 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 05:15:51 INFO - Successfully installed pip 05:15:51 INFO - Cleaning up... 05:15:51 INFO - Return code: 0 05:15:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:51 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 0x1a0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7b130>, '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 0x1d7c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c4c0>, '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': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', '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 05:15:51 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 05:15:51 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 05:15:51 INFO - Using env: {'DISPLAY': ':0', 05:15:51 INFO - 'HOME': '/home/cltbld', 05:15:51 INFO - 'LANG': 'en_US.UTF-8', 05:15:51 INFO - 'LANGUAGE': 'en_US:en', 05:15:51 INFO - 'LOGNAME': 'cltbld', 05:15:51 INFO - 'MAIL': '/var/mail/cltbld', 05:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:51 INFO - 'MOZ_NO_REMOTE': '1', 05:15:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:51 INFO - 'NO_EM_RESTART': '1', 05:15:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:51 INFO - 'PWD': '/builds/slave/test', 05:15:51 INFO - 'SHELL': '/bin/bash', 05:15:51 INFO - 'SHLVL': '1', 05:15:51 INFO - 'TERM': 'linux', 05:15:51 INFO - 'TMOUT': '86400', 05:15:51 INFO - 'USER': 'cltbld', 05:15:51 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', 05:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:51 INFO - '_': '/tools/buildbot/bin/python'} 05:15:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:51 INFO - Downloading/unpacking psutil>=3.1.1 05:15:51 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 05:15:51 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 05:15:51 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 05:15:51 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 05:15:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:15:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:15:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:15:52 INFO - Installing collected packages: psutil 05:15:52 INFO - Running setup.py install for psutil 05:15:52 INFO - building 'psutil._psutil_linux' extension 05:15:52 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 05:15:53 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 05:15:53 INFO - building 'psutil._psutil_posix' extension 05:15:53 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 05:15:53 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 05:15:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:15:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:15:53 INFO - Successfully installed psutil 05:15:53 INFO - Cleaning up... 05:15:54 INFO - Return code: 0 05:15:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:15:54 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')]} 05:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:54 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')]} 05:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:54 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 0x1a0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7b130>, '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 0x1d7c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c4c0>, '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': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', '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 05:15:54 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 05:15:54 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 05:15:54 INFO - Using env: {'DISPLAY': ':0', 05:15:54 INFO - 'HOME': '/home/cltbld', 05:15:54 INFO - 'LANG': 'en_US.UTF-8', 05:15:54 INFO - 'LANGUAGE': 'en_US:en', 05:15:54 INFO - 'LOGNAME': 'cltbld', 05:15:54 INFO - 'MAIL': '/var/mail/cltbld', 05:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:54 INFO - 'MOZ_NO_REMOTE': '1', 05:15:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:54 INFO - 'NO_EM_RESTART': '1', 05:15:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:54 INFO - 'PWD': '/builds/slave/test', 05:15:54 INFO - 'SHELL': '/bin/bash', 05:15:54 INFO - 'SHLVL': '1', 05:15:54 INFO - 'TERM': 'linux', 05:15:54 INFO - 'TMOUT': '86400', 05:15:54 INFO - 'USER': 'cltbld', 05:15:54 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', 05:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:54 INFO - '_': '/tools/buildbot/bin/python'} 05:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:15:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:15:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:15:55 INFO - Installing collected packages: mozsystemmonitor 05:15:55 INFO - Running setup.py install for mozsystemmonitor 05:15:55 INFO - Successfully installed mozsystemmonitor 05:15:55 INFO - Cleaning up... 05:15:55 INFO - Return code: 0 05:15:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:15: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')]} 05:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15: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')]} 05:15:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7b130>, '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 0x1d7c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c4c0>, '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': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', '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 05:15:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:15:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:15:55 INFO - Using env: {'DISPLAY': ':0', 05:15:55 INFO - 'HOME': '/home/cltbld', 05:15:55 INFO - 'LANG': 'en_US.UTF-8', 05:15:55 INFO - 'LANGUAGE': 'en_US:en', 05:15:55 INFO - 'LOGNAME': 'cltbld', 05:15:55 INFO - 'MAIL': '/var/mail/cltbld', 05:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:55 INFO - 'MOZ_NO_REMOTE': '1', 05:15:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:55 INFO - 'NO_EM_RESTART': '1', 05:15:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:55 INFO - 'PWD': '/builds/slave/test', 05:15:55 INFO - 'SHELL': '/bin/bash', 05:15:55 INFO - 'SHLVL': '1', 05:15:55 INFO - 'TERM': 'linux', 05:15:55 INFO - 'TMOUT': '86400', 05:15:55 INFO - 'USER': 'cltbld', 05:15:55 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', 05:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:55 INFO - '_': '/tools/buildbot/bin/python'} 05:15:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:55 INFO - Downloading/unpacking jsonschema==2.5.1 05:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:15:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:15:56 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 05:15:56 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 05:15:56 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 05:15:56 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 05:15:56 INFO - Downloading functools32-3.2.3-2.tar.gz 05:15:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:15:56 INFO - warning: no files found matching '*.txt' 05:15:56 INFO - no previously-included directories found matching 'build' 05:15:56 INFO - no previously-included directories found matching 'dist' 05:15:56 INFO - no previously-included directories found matching '.git*' 05:15:57 INFO - Installing collected packages: jsonschema, functools32 05:15:57 INFO - Running setup.py install for functools32 05:15:57 INFO - warning: no files found matching '*.txt' 05:15:57 INFO - no previously-included directories found matching 'build' 05:15:57 INFO - no previously-included directories found matching 'dist' 05:15:57 INFO - no previously-included directories found matching '.git*' 05:15:57 INFO - Successfully installed jsonschema functools32 05:15:57 INFO - Cleaning up... 05:15:57 INFO - Return code: 0 05:15:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:15:57 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')]} 05:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:57 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')]} 05:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:57 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 0x1a0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7b130>, '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 0x1d7c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c4c0>, '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': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', '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 05:15:57 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 05:15:57 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 05:15:57 INFO - Using env: {'DISPLAY': ':0', 05:15:57 INFO - 'HOME': '/home/cltbld', 05:15:57 INFO - 'LANG': 'en_US.UTF-8', 05:15:57 INFO - 'LANGUAGE': 'en_US:en', 05:15:57 INFO - 'LOGNAME': 'cltbld', 05:15:57 INFO - 'MAIL': '/var/mail/cltbld', 05:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:57 INFO - 'MOZ_NO_REMOTE': '1', 05:15:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:57 INFO - 'NO_EM_RESTART': '1', 05:15:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:57 INFO - 'PWD': '/builds/slave/test', 05:15:57 INFO - 'SHELL': '/bin/bash', 05:15:57 INFO - 'SHLVL': '1', 05:15:57 INFO - 'TERM': 'linux', 05:15:57 INFO - 'TMOUT': '86400', 05:15:57 INFO - 'USER': 'cltbld', 05:15:57 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', 05:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:57 INFO - '_': '/tools/buildbot/bin/python'} 05:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:15:57 INFO - Cleaning up... 05:15:57 INFO - Return code: 0 05:15:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:15:57 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')]} 05:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:57 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')]} 05:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:57 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 0x1a0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7b130>, '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 0x1d7c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c4c0>, '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': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', '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 05:15:57 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 05:15:57 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 05:15:57 INFO - Using env: {'DISPLAY': ':0', 05:15:57 INFO - 'HOME': '/home/cltbld', 05:15:57 INFO - 'LANG': 'en_US.UTF-8', 05:15:57 INFO - 'LANGUAGE': 'en_US:en', 05:15:57 INFO - 'LOGNAME': 'cltbld', 05:15:57 INFO - 'MAIL': '/var/mail/cltbld', 05:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:57 INFO - 'MOZ_NO_REMOTE': '1', 05:15:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:57 INFO - 'NO_EM_RESTART': '1', 05:15:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:57 INFO - 'PWD': '/builds/slave/test', 05:15:57 INFO - 'SHELL': '/bin/bash', 05:15:57 INFO - 'SHLVL': '1', 05:15:57 INFO - 'TERM': 'linux', 05:15:57 INFO - 'TMOUT': '86400', 05:15:57 INFO - 'USER': 'cltbld', 05:15:57 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', 05:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:57 INFO - '_': '/tools/buildbot/bin/python'} 05:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:57 INFO - Downloading/unpacking blobuploader==1.2.4 05:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:58 INFO - Downloading blobuploader-1.2.4.tar.gz 05:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:15:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:15:58 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 05:15:58 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 05:15:58 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 05:15:58 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 05:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:15:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:15:59 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 05:15:59 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 05:15:59 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 05:15:59 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 05:15:59 INFO - Downloading docopt-0.6.1.tar.gz 05:15:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:15:59 INFO - Installing collected packages: blobuploader, requests, docopt 05:15:59 INFO - Running setup.py install for blobuploader 05:15:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:15:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:15:59 INFO - Running setup.py install for requests 05:15:59 INFO - Running setup.py install for docopt 05:15:59 INFO - Successfully installed blobuploader requests docopt 05:15:59 INFO - Cleaning up... 05:15:59 INFO - Return code: 0 05:15:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:15:59 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')]} 05:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:59 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')]} 05:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:59 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 0x1a0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7b130>, '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 0x1d7c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c4c0>, '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': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', '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 05:15:59 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 05:15:59 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 05:15:59 INFO - Using env: {'DISPLAY': ':0', 05:15:59 INFO - 'HOME': '/home/cltbld', 05:15:59 INFO - 'LANG': 'en_US.UTF-8', 05:15:59 INFO - 'LANGUAGE': 'en_US:en', 05:15:59 INFO - 'LOGNAME': 'cltbld', 05:15:59 INFO - 'MAIL': '/var/mail/cltbld', 05:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:59 INFO - 'MOZ_NO_REMOTE': '1', 05:15:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:59 INFO - 'NO_EM_RESTART': '1', 05:15:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:59 INFO - 'PWD': '/builds/slave/test', 05:15:59 INFO - 'SHELL': '/bin/bash', 05:15:59 INFO - 'SHLVL': '1', 05:15:59 INFO - 'TERM': 'linux', 05:15:59 INFO - 'TMOUT': '86400', 05:15:59 INFO - 'USER': 'cltbld', 05:15:59 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', 05:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:59 INFO - '_': '/tools/buildbot/bin/python'} 05:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:16:00 INFO - Running setup.py (path:/tmp/pip-pV9h6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:16:00 INFO - Running setup.py (path:/tmp/pip-jDM1UP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:16:00 INFO - Running setup.py (path:/tmp/pip-TjhNjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:16:00 INFO - Running setup.py (path:/tmp/pip-XjNwNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:16:00 INFO - Running setup.py (path:/tmp/pip-SdfQ7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:16:00 INFO - Running setup.py (path:/tmp/pip-GXZhsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:16:00 INFO - Running setup.py (path:/tmp/pip-e_nuQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:16:00 INFO - Running setup.py (path:/tmp/pip-K1ZIgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:16:00 INFO - Running setup.py (path:/tmp/pip-HD5Nmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:16:00 INFO - Running setup.py (path:/tmp/pip-3jqvVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:16:00 INFO - Running setup.py (path:/tmp/pip-1MxqEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:16:00 INFO - Running setup.py (path:/tmp/pip-T1Bmzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:16:00 INFO - Running setup.py (path:/tmp/pip-NGfFZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:16:01 INFO - Running setup.py (path:/tmp/pip-9Ob3_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:01 INFO - Running setup.py (path:/tmp/pip-8OEy_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:16:01 INFO - Running setup.py (path:/tmp/pip-JrJqWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:16:01 INFO - Running setup.py (path:/tmp/pip-y7ClSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:16:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:16:01 INFO - Running setup.py install for manifestparser 05:16:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:16:01 INFO - Running setup.py install for mozcrash 05:16:01 INFO - Running setup.py install for mozdebug 05:16:01 INFO - Running setup.py install for mozdevice 05:16:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:16:01 INFO - Running setup.py install for mozfile 05:16:01 INFO - Running setup.py install for mozhttpd 05:16:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:16:01 INFO - Running setup.py install for mozinfo 05:16:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:16:01 INFO - Running setup.py install for mozInstall 05:16:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:16:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:16:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:16:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:16:02 INFO - Running setup.py install for mozleak 05:16:02 INFO - Running setup.py install for mozlog 05:16:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:16:02 INFO - Running setup.py install for moznetwork 05:16:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:16:02 INFO - Running setup.py install for mozprocess 05:16:02 INFO - Running setup.py install for mozprofile 05:16:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:16:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:16:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:16:02 INFO - Running setup.py install for mozrunner 05:16:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:16:02 INFO - Running setup.py install for mozscreenshot 05:16:02 INFO - Running setup.py install for moztest 05:16:02 INFO - Running setup.py install for mozversion 05:16:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:16:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:16:03 INFO - Cleaning up... 05:16:03 INFO - Return code: 0 05:16:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:03 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')]} 05:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:03 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')]} 05:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:03 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 0x1a0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7b130>, '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 0x1d7c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c4c0>, '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': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', '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 05:16:03 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 05:16:03 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 05:16:03 INFO - Using env: {'DISPLAY': ':0', 05:16:03 INFO - 'HOME': '/home/cltbld', 05:16:03 INFO - 'LANG': 'en_US.UTF-8', 05:16:03 INFO - 'LANGUAGE': 'en_US:en', 05:16:03 INFO - 'LOGNAME': 'cltbld', 05:16:03 INFO - 'MAIL': '/var/mail/cltbld', 05:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:03 INFO - 'MOZ_NO_REMOTE': '1', 05:16:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:16:03 INFO - 'NO_EM_RESTART': '1', 05:16:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:03 INFO - 'PWD': '/builds/slave/test', 05:16:03 INFO - 'SHELL': '/bin/bash', 05:16:03 INFO - 'SHLVL': '1', 05:16:03 INFO - 'TERM': 'linux', 05:16:03 INFO - 'TMOUT': '86400', 05:16:03 INFO - 'USER': 'cltbld', 05:16:03 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', 05:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:03 INFO - '_': '/tools/buildbot/bin/python'} 05:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:16:03 INFO - Running setup.py (path:/tmp/pip-3IKrPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:16:03 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)) 05:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:16:03 INFO - Running setup.py (path:/tmp/pip-WQCATj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:16:03 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)) 05:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:16:03 INFO - Running setup.py (path:/tmp/pip-7lyy61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:16:03 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)) 05:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:16:03 INFO - Running setup.py (path:/tmp/pip-ToQlRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:16:03 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)) 05:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:16:03 INFO - Running setup.py (path:/tmp/pip-mkUCG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:16:03 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)) 05:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:16:03 INFO - Running setup.py (path:/tmp/pip-f87aYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:16:03 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)) 05:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:16:03 INFO - Running setup.py (path:/tmp/pip-jVshFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:16:03 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)) 05:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:16:03 INFO - Running setup.py (path:/tmp/pip-3O7p31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:16:03 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)) 05:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:16:03 INFO - Running setup.py (path:/tmp/pip-BLxvs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:16:03 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)) 05:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:16:03 INFO - Running setup.py (path:/tmp/pip-KIivTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:16:03 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)) 05:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:16:03 INFO - Running setup.py (path:/tmp/pip-i_jKy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:16:04 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)) 05:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:16:04 INFO - Running setup.py (path:/tmp/pip-NnwTj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:16:04 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)) 05:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:16:04 INFO - Running setup.py (path:/tmp/pip-OK8JXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:16:04 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)) 05:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:16:04 INFO - Running setup.py (path:/tmp/pip-os5p3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:16:04 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)) 05:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:04 INFO - Running setup.py (path:/tmp/pip-JeHglj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:04 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)) 05:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:16:04 INFO - Running setup.py (path:/tmp/pip-sUtoWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:16:04 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)) 05:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:16:04 INFO - Running setup.py (path:/tmp/pip-uejgoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:16:04 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)) 05:16:04 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)) 05:16:04 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)) 05:16:04 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)) 05:16:04 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)) 05:16:04 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)) 05:16:04 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)) 05:16:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:05 INFO - Downloading blessings-1.6.tar.gz 05:16:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:16:05 INFO - Installing collected packages: blessings 05:16:05 INFO - Running setup.py install for blessings 05:16:05 INFO - Successfully installed blessings 05:16:05 INFO - Cleaning up... 05:16:05 INFO - Return code: 0 05:16:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:16:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:16:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:16:06 INFO - Reading from file tmpfile_stdout 05:16:06 INFO - Current package versions: 05:16:06 INFO - argparse == 1.2.1 05:16:06 INFO - blessings == 1.6 05:16:06 INFO - blobuploader == 1.2.4 05:16:06 INFO - docopt == 0.6.1 05:16:06 INFO - functools32 == 3.2.3-2 05:16:06 INFO - jsonschema == 2.5.1 05:16:06 INFO - manifestparser == 1.1 05:16:06 INFO - mozInstall == 1.13 05:16:06 INFO - mozcrash == 1.0 05:16:06 INFO - mozdebug == 0.1 05:16:06 INFO - mozdevice == 0.51 05:16:06 INFO - mozfile == 1.2 05:16:06 INFO - mozhttpd == 0.7 05:16:06 INFO - mozinfo == 0.10 05:16:06 INFO - mozleak == 0.1 05:16:06 INFO - mozlog == 3.5 05:16:06 INFO - moznetwork == 0.27 05:16:06 INFO - mozprocess == 0.25 05:16:06 INFO - mozprofile == 0.28 05:16:06 INFO - mozrunner == 6.13 05:16:06 INFO - mozscreenshot == 0.1 05:16:06 INFO - mozsystemmonitor == 0.3 05:16:06 INFO - moztest == 0.8 05:16:06 INFO - mozversion == 1.4 05:16:06 INFO - psutil == 3.1.1 05:16:06 INFO - requests == 1.2.3 05:16:06 INFO - wsgiref == 0.1.2 05:16:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:06 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')]} 05:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:06 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')]} 05:16:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:06 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 0x1a0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7b130>, '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 0x1d7c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c4c0>, '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': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', '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 05:16:06 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 05:16:06 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 05:16:06 INFO - Using env: {'DISPLAY': ':0', 05:16:06 INFO - 'HOME': '/home/cltbld', 05:16:06 INFO - 'LANG': 'en_US.UTF-8', 05:16:06 INFO - 'LANGUAGE': 'en_US:en', 05:16:06 INFO - 'LOGNAME': 'cltbld', 05:16:06 INFO - 'MAIL': '/var/mail/cltbld', 05:16:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:06 INFO - 'MOZ_NO_REMOTE': '1', 05:16:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:16:06 INFO - 'NO_EM_RESTART': '1', 05:16:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:16:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:06 INFO - 'PWD': '/builds/slave/test', 05:16:06 INFO - 'SHELL': '/bin/bash', 05:16:06 INFO - 'SHLVL': '1', 05:16:06 INFO - 'TERM': 'linux', 05:16:06 INFO - 'TMOUT': '86400', 05:16:06 INFO - 'USER': 'cltbld', 05:16:06 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', 05:16:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:06 INFO - '_': '/tools/buildbot/bin/python'} 05:16:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:06 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)) 05:16:06 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)) 05:16:06 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)) 05:16:06 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)) 05:16:06 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)) 05:16:06 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)) 05:16:06 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)) 05:16:06 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)) 05:16:06 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)) 05:16:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:16:06 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 05:16:06 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 05:16:06 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 05:16:06 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 05:16:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:16:07 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)) 05:16:07 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)) 05:16:07 INFO - Installing collected packages: simplejson 05:16:07 INFO - Running setup.py install for simplejson 05:16:07 INFO - building 'simplejson._speedups' extension 05:16:07 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 05:16:08 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 05:16:08 INFO - Successfully installed simplejson 05:16:08 INFO - Cleaning up... 05:16:08 INFO - Return code: 0 05:16:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:16:08 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')]} 05:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:08 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')]} 05:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:08 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 0x1a0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7df90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7b130>, '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 0x1d7c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c4c0>, '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': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', '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 05:16:08 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 05:16:08 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 05:16:08 INFO - Using env: {'DISPLAY': ':0', 05:16:08 INFO - 'HOME': '/home/cltbld', 05:16:08 INFO - 'LANG': 'en_US.UTF-8', 05:16:08 INFO - 'LANGUAGE': 'en_US:en', 05:16:08 INFO - 'LOGNAME': 'cltbld', 05:16:08 INFO - 'MAIL': '/var/mail/cltbld', 05:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:08 INFO - 'MOZ_NO_REMOTE': '1', 05:16:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:16:08 INFO - 'NO_EM_RESTART': '1', 05:16:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:08 INFO - 'PWD': '/builds/slave/test', 05:16:08 INFO - 'SHELL': '/bin/bash', 05:16:08 INFO - 'SHLVL': '1', 05:16:08 INFO - 'TERM': 'linux', 05:16:08 INFO - 'TMOUT': '86400', 05:16:08 INFO - 'USER': 'cltbld', 05:16:08 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', 05:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:08 INFO - '_': '/tools/buildbot/bin/python'} 05:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:16:08 INFO - Cleaning up... 05:16:08 INFO - Return code: 0 05:16:08 INFO - Running post-action listener: _resource_record_post_action 05:16:08 INFO - Running post-action listener: _start_resource_monitoring 05:16:08 INFO - Starting resource monitoring. 05:16:08 INFO - [mozharness: 2017-08-24 12:16:08.753087Z] Finished create-virtualenv step (success) 05:16:08 INFO - [mozharness: 2017-08-24 12:16:08.754288Z] Running install step. 05:16:08 INFO - Running pre-action listener: _resource_record_pre_action 05:16:08 INFO - Running main action method: install 05:16:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:16:08 INFO - Reading from file tmpfile_stdout 05:16:08 INFO - Detecting whether we're running mozinstall >=1.0... 05:16:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:16:09 INFO - Reading from file tmpfile_stdout 05:16:09 INFO - Output received: 05:16:09 INFO - Usage: mozinstall [options] installer 05:16:09 INFO - Options: 05:16:09 INFO - -h, --help show this help message and exit 05:16:09 INFO - -d DEST, --destination=DEST 05:16:09 INFO - Directory to install application into. [default: 05:16:09 INFO - "/builds/slave/test"] 05:16:09 INFO - --app=APP Application being installed. [default: firefox] 05:16:09 INFO - mkdir: /builds/slave/test/build/application 05:16:09 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'] 05:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:16:26 INFO - Reading from file tmpfile_stdout 05:16:26 INFO - Output received: 05:16:26 INFO - /builds/slave/test/build/application/firefox/firefox 05:16:26 INFO - Running post-action listener: _resource_record_post_action 05:16:26 INFO - [mozharness: 2017-08-24 12:16:26.836718Z] Finished install step (success) 05:16:26 INFO - [mozharness: 2017-08-24 12:16:26.836969Z] Running setup-mitmproxy step. 05:16:26 INFO - Running pre-action listener: _resource_record_pre_action 05:16:26 INFO - Running main action method: setup_mitmproxy 05:16:26 INFO - Skipping: mitmproxy is not required 05:16:26 INFO - Running post-action listener: _resource_record_post_action 05:16:26 INFO - [mozharness: 2017-08-24 12:16:26.838160Z] Finished setup-mitmproxy step (success) 05:16:26 INFO - [mozharness: 2017-08-24 12:16:26.838380Z] Running run-tests step. 05:16:26 INFO - Running pre-action listener: _resource_record_pre_action 05:16:26 INFO - Running pre-action listener: _set_gcov_prefix 05:16:26 INFO - Running main action method: run_tests 05:16:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:16:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:16:26 INFO - Python 2.7.3 05:16:26 INFO - Return code: 0 05:16:26 INFO - grabbing minidump binary from tooltool 05:16:26 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')]} 05:16:26 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 0x1d7b130>, '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 0x1d7c030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c4c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:16:26 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 05:16:26 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 05:16:26 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 05:16:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:16:27 INFO - Return code: 0 05:16:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:16:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:16:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:16:27 INFO - ENV: RUST_BACKTRACE is now full 05:16:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:16:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:16:27 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/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 05:16:27 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/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 05:16:27 INFO - Using env: {'DISPLAY': ':0', 05:16:27 INFO - 'HOME': '/home/cltbld', 05:16:27 INFO - 'LANG': 'en_US.UTF-8', 05:16:27 INFO - 'LANGUAGE': 'en_US:en', 05:16:27 INFO - 'LOGNAME': 'cltbld', 05:16:27 INFO - 'MAIL': '/var/mail/cltbld', 05:16:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:16:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:16:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:27 INFO - 'MOZ_NO_REMOTE': '1', 05:16:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:16:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:16:27 INFO - 'NO_EM_RESTART': '1', 05:16:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:16:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:27 INFO - 'PWD': '/builds/slave/test', 05:16:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:16:27 INFO - 'RUST_BACKTRACE': 'full', 05:16:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:16:27 INFO - 'SHELL': '/bin/bash', 05:16:27 INFO - 'SHLVL': '1', 05:16:27 INFO - 'STYLO_FORCE_ENABLED': '1', 05:16:27 INFO - 'TERM': 'linux', 05:16:27 INFO - 'TMOUT': '86400', 05:16:27 INFO - 'USER': 'cltbld', 05:16:27 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074', 05:16:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:27 INFO - '_': '/tools/buildbot/bin/python'} 05:16:27 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/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 05:16:27 INFO - mozversion application_buildid: 20170824112712 05:16:27 INFO - mozversion application_changeset: 8d1350135a04225958ada4c570e818366a3ae964 05:16:27 INFO - mozversion application_display_name: Nightly 05:16:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:16:27 INFO - mozversion application_name: Firefox 05:16:27 INFO - mozversion application_remotingname: firefox 05:16:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:16:27 INFO - mozversion application_vendor: Mozilla 05:16:27 INFO - mozversion application_version: 57.0a1 05:16:27 INFO - mozversion platform_buildid: 20170824112712 05:16:27 INFO - mozversion platform_changeset: 8d1350135a04225958ada4c570e818366a3ae964 05:16:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:16:27 INFO - mozversion platform_version: 57.0a1 05:16:27 INFO - using testdate: 1503576987 05:16:27 INFO - actual date: 1503576987 05:16:27 INFO - starting webserver on 'localhost:43442' 05:16:27 INFO - SUITE-START | Running 2 tests 05:16:27 INFO - TEST-START | dromaeo_css 05:16:27 INFO - Initialising browser for dromaeo_css test... 05:16:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43442/getInfo.html -profile /tmp/tmpKeDLQU/profile 05:16:27 INFO - TEST-INFO | started process 3321 (/builds/slave/test/build/application/firefox/firefox http://localhost:43442/getInfo.html) 05:16:34 INFO - TEST-INFO | 3321: exit 0 05:16:34 INFO - Browser initialized. 05:16:34 INFO - Running cycle 1/1 for dromaeo_css test... 05:16:34 INFO - TEST-INFO | started process 3546 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKeDLQU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:16:35 INFO - PID 3546 | 05:16:35 INFO - PID 3546 | (/builds/slave/test/build/application/firefox/firefox:3596): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:16:35 INFO - PID 3546 | 05:16:35 INFO - PID 3546 | 05:16:35 INFO - PID 3546 | (/builds/slave/test/build/application/firefox/firefox:3657): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:16:35 INFO - PID 3546 | 05:16:35 INFO - PID 3546 | __metrics Screen width/height:1600/1200 05:16:35 INFO - PID 3546 | colorDepth:24 05:16:35 INFO - PID 3546 | Browser inner width/height: 1024/768 05:16:35 INFO - PID 3546 | __metrics 05:16:45 INFO - PID 3546 | RSS: Main: 174764032 05:16:45 INFO - PID 3546 | 05:19:43 INFO - PID 3546 | Cycle 1(1): loaded http://localhost:43442/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43442/tests/dromaeo/cssquery-ext.html) 05:19:45 INFO - PID 3546 | RSS: Main: 178057216 05:19:45 INFO - PID 3546 | 05:22:47 INFO - PID 3546 | Cycle 1(1): loaded http://localhost:43442/tests/dromaeo/cssquery-ext.html (next: http://localhost:43442/tests/dromaeo/cssquery-jquery.html) 05:22:47 INFO - PID 3546 | RSS: Main: 174817280 05:22:47 INFO - PID 3546 | 05:25:49 INFO - PID 3546 | Cycle 1(1): loaded http://localhost:43442/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43442/tests/dromaeo/cssquery-mootools.html) 05:25:50 INFO - PID 3546 | RSS: Main: 177057792 05:25:50 INFO - PID 3546 | 05:28:52 INFO - PID 3546 | Cycle 1(1): loaded http://localhost:43442/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43442/tests/dromaeo/cssquery-prototype.html) 05:28:52 INFO - PID 3546 | RSS: Main: 175083520 05:28:52 INFO - PID 3546 | 05:31:56 INFO - PID 3546 | Cycle 1(1): loaded http://localhost:43442/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43442/tests/dromaeo/cssquery-yui.html) 05:31:57 INFO - PID 3546 | RSS: Main: 176664576 05:31:57 INFO - PID 3546 | 05:34:59 INFO - PID 3546 | Cycle 1(1): loaded http://localhost:43442/tests/dromaeo/cssquery-yui.html (next: http://localhost:43442/tests/dromaeo/cssquery-dojo.html) 05:34:59 INFO - PID 3546 | RSS: Main: 179118080 05:34:59 INFO - PID 3546 | 05:34:59 INFO - PID 3546 | __start_tp_report 05:34:59 INFO - PID 3546 | _x_x_mozilla_page_load 05:34:59 INFO - PID 3546 | _x_x_mozilla_page_load_details 05:34:59 INFO - PID 3546 | |i|pagename|runs| 05:34:59 INFO - PID 3546 | |0;dojo.html;3294.028261662808;3300.702936735693;3300.834958252087;3350.9162270943225;3358.580177477815;6266.060090986354;6319.968400157998;6345.7144428500715;6363.4272915437605;6402.6798660066925;6112.7860524881635;6211.0372892201785;6365.904511432333;6452.741890324393;6473.996530537763;5800.129980502916;5810.128480727883;5845.7369418376065;5853.8829223415505;5908.438698323653;906.1090767462553;5545.334559852803;5716.8284951451315;5751;5876.265466816648;5780.450857168562;5795.71021448928;5855.8536036599;5865.58940874139;5869.735861886225;5782.913256301159;5788.3343415506915;5865.002949498596;5868.2664666916635;5869.06095024794;5728.112142617901;5757.68332741699;5813.244278243844;5830.300363956352;5856.824295271149;6144.0783882417545;6240.968795156041;6252.562320637558;6270.435660790505;6323.05154226865;6602.537822352438;6737.157855268091;6777;6843.726250949956;6862;6834.555753875982;6884.380405763505;6930.13373328334;6946.826329341757;6958.512904096715;6334.778282760081;6355.428011478943;6430.871382572369;6431.067495213215;6434;6857.55425897315;6911.75808846688;6912.688928998106;6923.2038315593345;6945.201301850251;6630.569013014138;6648.235452922976;6657.500687448461;6657.600543967377;6671.066050752898;6669.166354205724;6722.428593569503;6734.46124310054;6748.460123190133;6753.29090445506;6632.568883022588;6709.630970296681;6755.425788807908;6785.728570857209;6791.456683465311;6771.695273712695;6833.384995350442;6856.108705868205;6858.279880612564;6872.140982377203;5341.3857406398765;5355.036093503207;5408.567314614821;5422.728863556807;5443.755031023614;4630.305454181832;4666.65000124992;4668.50980647034;4702.553257440538;4761.47623761386;5995.790147344822;6008.068749343859;6008.369121242206;6013.4888534474185;6029.788957386558;6854.6229957351825;6907;6925.896111558331;6931.68807403658;6944.479164062715;5927.2590926134235;5951.166836642873;6010.819675409657;6012.188354572164;6031.4270144336215;5641.435856414408;5715.857103572361;5746.454086861741;5769.567282453832;5831.154482599999;6634.336566343327;6661.26726060133;6661.833454163588;6665.200175978914;6676;6805.251422343637;6914.204866440323;6918.238993710587;6929.7228110875;6940.13248343957;6624.867502650017;6648.933510664831;6666.666666666744;6673.132492775908;6685.933140668531;6746.987951807072;6752.054712340375;6826.692798823966;6840.794776156723;6849.04113424131;6727.159105111861;6832.350926661959;6884.724611015704;6981.5112942093565;6982.581045137308;6520.576162549419;6552.3775241352;6553.574017688835;6562.179727534059;6574.3754343337305;6564.573302735307;6567.802965911031;6570.474362050929;6571.080048793268;6685.197776266879;6580.045893345534;6595.835104122435;6618.238902526271;6651.900221496585;6674.065630811593;6411.3909178627955;6499.6750162492635;6645.1361323027695;6704.927211646113;6769.28922463154;6342.587731797418;6360.141380913517;6362.045693146065;6372.330905254881;6393.1369265148605 05:34:59 INFO - PID 3546 | |1;ext.html;758.2644834510527;803.3773825285399;825.6821123867311;851.8935133108359;853.1085016158114;38716.22567548647;39770.00574985627;39870;39899;39934.60065399345;35552;35726.2854742905;35797.2840543189;35816;35872.82063589685;6757.628330441811;6854.862902741942;6871.278515755848;6871.450283977295;6876.312368763109;6745.29174436682;6832.624205668686;6841.384275415212;6846.144231971004;6847.075644787939;6825.795226143224;7488.438367122488;7631.275028872248;7646.273604007611;7655.540667559964;7541.472096953188;7589.506682065703;7592.240775922363;7604;7625.580593067351;7419.146798118231;7443.813904652373;7520;7625.275598818103;7637.885431718529;35387.469187962204;35866.46200306997;35915.461268081;35919.640803591894;35954.28091438183;3393.4400823864034;3412.0616830371846;3422.709069729076;3431.9485207721905;3433.2446861690387;29763.40473190524;29954.95157669493;30045.098647040624;30283.545746813816;30320.090397287895;5072.873178170538;5144.228365745131;5149.768260428253;5164.173732202867;5166.070107380708;184925;185202.99999999866;185953;186042;186547;46446.30330545045;46667;46683.066338673714;46703.53296466992;46731.76634116808;47699.76150119297;48033;48044;48049.75975120172;48234.758826205645;14392.280385980659;14618.195999220145;14623.04950178235;14676.853231467549;14687.339069741887;11810;11826.881731182579;11828.408579570987;11828.467718952668;11833.171677982458;13805.792913106312;13819.032667713163;14015.719685606231;14120.505782297776;14193;5588.888222235532;5594.468525490072;5644.153376993402;5704.058830293038;5734.455226753452;975.1564896364594;1010.2372708605103;1015.3451024089421;1017.4251547875575;1020.6172685243034;22367.55264894693;22399.88000599996;22418.103275869107;22438.43903902383;22440.438989025406;1093.3494570730402;1095.4030053620795;1109.5284504085732;1113.760541483585;1118.4799068433217;11935.522579096913;11954.76090478203;12040.096992725405;12057.336846473529;15863;230524;231986;232329;232494;233228;49295.75352123288;52995.73502132542;53142.468575314524;53481;53620.19569706458;1374.0587697427295;1409.0840953380384;1417.1780367386891;1421.623269833495;1425.2090089999942;26389.60415593806;26574.86712566502;26713.130080894927;26927.596086059115;27701.58447623247;48555.27167092426;48760.75619621879;49032.75483622702;49038.75480622574;49153;211760;212775;213166;213397;213617;33276.66723332833;34027.447941403996;34125.31749365049;34216;34358.31283374268;41810.790946045076;42039.78980105202;42186.36720449134;42211;42227.57772422236;30032.24919377033;30187.245318867204;30513.847430762704;30646.84676576603;30724.692753072184;30763;30820.075397737208;30909.227269318446;31317.530237046918;31321.373572527966;33871.49192762061;34027;34049;34261.48607770835;34339.313213735084;29635.666395011845;30296.697033029388;30447.391052179275;30595;30691.846540767154;22820.885895570416;23191.768082318962;23435.296941091794;23460.296191114296;23592.646110308015 05:34:59 INFO - PID 3546 | |2;jquery.html;534.478883088988;554.3680204566795;558.1599692462843;571.7541457173419;576.9365369809321;20993.47516312091;22066.448338791517;22092.779072209272;22095.226667066658;22122.778772212274;25524;26054.609180862302;26142.738572614267;26181.607275890878;26194.345141371476;6417.807465776023;6569.868602627945;6572.934270657304;6573.835654108638;6575.967120164392;5995.430434108753;6433.324500927405;6544.214694236698;6547.410733034026;6579;6528.347165283458;6539.182602174728;6540.084388185658;6541.985992171221;6542.280349161589;6539.705713242892;6541.21505419348;6543.018547217908;6553.049807777893;6558.672066396685;6031.819045428644;6042.758289668408;6053.878922421571;6056.848578785522;6063.575549711522;27847.02535411251;28446.43107137866;28976.42047159066;29090;29154.85422572895;2010.7587089549202;2042.1525067097136;2043.4176259765895;2047.6621357476;2049.4876280929766;18103.909480452647;18831.152598133118;19892.20431182751;19963.700544491843;20008.599828003506;2408.844885137429;2413.818963577721;2462.6429167770752;2480.516299321633;2497.7377375375504;134508.32745836306;137123;137858;147761.26119369443;148270.25864870715;14284.881007932812;14591.124532528082;14658;14751.188684622233;15043.022203556733;22465.101395944304;22896.65655015176;22950.65574016391;22951.31146065621;22989.655155172684;9374.531273436301;9746.512674366253;9759.707208783748;9768.023197680317;9853.75365615851;8680.782980425414;8702.956485217534;8719.215270625675;8875.068117847663;8906;8612.397132200686;8639.321810238846;8673.349498787617;8869.866951995726;9019.549022548978;2493.0180506497377;2603.38820377215;2624.8818803153904;2675.518406686815;2704.7971402144917;932.1424289653535;950.5532399772096;954.2127744610724;954.961801527945;964.9565769540247;19258.8074119257;19667;23326.53346933052;24541.38646533851;24578.877105614716;999.1715161254887;1013.3008224325192;1025.8153532364097;1027.3887037212846;1032.9690109296732;10965.56137754481;11434.828477572843;13066.216027038217;14200.857991419953;14248.430062797579;510735;515728;572964;576462;577171;115248;117582.41208793901;119125;124236;125345;1066.2110038571402;1122.231271578971;1161.5143592711604;1163.7148898519918;1227.312704885285;20958.161673533497;21087.683684745047;21775.891120544296;22099.558008840053;22343.664845027663;57755.133672994096;58880.41119588921;59037.70481147567;60201.39798602133;63474.36525634684;70468.64765676139;70975;70994.45449246008;71035.64482177555;72166.63916680383;14012.789808152682;14458.927705361406;14758.926205368904;14865.331060102537;14923.776143357642;23571.528569428858;23634.64548031815;23682.407939801644;23733.16933907305;23878;18294.542636433664;18873.811261887204;19019.90490047596;19021.42935711931;19027.143778530277;16051.678966420839;17540.561485962444;17708.114594270493;17904.373346932796;18260.178291976626;20295.79704202939;20383.286584969454;20615.278465253643;20634.07146678433;20639.690404643643;13963.58109256724;14444.566663000127;14697.19165445868;14701.92649036748;14719.92640036793;19133.712994304817;19300.8069919299;19302.4209273722;19308.4207473776;19320.613587727887 05:34:59 INFO - PID 3546 | |3;mootools.html;1288.2206265209547;1334.2461509247275;1341.4164838295346;1357.3620398412743;1358.4430383542738;14275.357608907598;14831.332590033448;14869.479568215114;14880.702385952274;14885.627859303495;14394.856051439509;14839.406423743065;15542.922285388586;15555.222238888038;15610.141442220673;2658.7208343123984;2730.085421383839;2730.7269273072634;2730.795190360721;2732.27594687407;2601.7528334808258;2618.0313284084914;2641.379275870169;2649.7482739139846;2757.9448411031767;2738.2332946774936;2745.3822889849725;2770.986145069272;2771.237909574873;2771.88912443502;2523.7728604425506;2547.694276686791;2618.528664840328;2634.473105378932;2636.459525797207;2454.8158888083467;2473.5300292944285;2557.9360515987064;2606.0487921908557;2608.9478210435873;14568.198749068797;14640.194789286585;14657.120572765669;14674.413023479048;14723.043002204822;7702.537847729098;7965.163657815961;7993.360531157494;7997.360211183091;8004.479708818908;5196;5199.771547763892;5306.257124002643;5341.359036915595;5368.087425137736;1914.2151717795637;1934.2456441987636;1959.5395082155683;1991.1736629298823;2006.6588679924448;25027.749722502725;25112.874435627888;25269.368265793502;25289;25443.87278063598;6737.528373013842;6759.121314229119;6763.661816909135;6781.559198652072;6899.448044156456;16656.6668666626;16669.91665041667;16700.66598668044;16700.83299167017;16724.331026759035;7635.541867487968;7784.182660820532;7794.10367807698;7794.181610930884;7798.610069496503;6997.965010174917;7055.964720176366;7202.819929501804;7252.238514955854;7278.417726581861;4602.723836569817;5009.5491405773655;5171.7672704728375;5194.92207616886;5225.320708307896;1343.9664008399673;1353.8375394952668;1462.334637739826;1471.3599584180915;1483.2287210650402;913.4427998920653;921.9078092190862;927.2906226736552;943.5518128888723;944.527736131934;8611;8900.777480563038;8912.465252084767;8926.375153739305;9022.548872556346;983.2134292565919;985.4284514981293;986.8125056239435;993.3989936088562;1001.5392919257077;5223.216517522402;5465.289512363367;5468.945310546844;5581.190727344593;5681.233033540502;19813.10841012158;19894.204231830856;20126.798732012787;20987.685184722242;21182;19414.320498782476;19700.211991520464;19734.210631574577;19820.306289280095;19839.801601984083;10311.84532232017;10627.36235825853;10781.353118812896;10938.289011214245;10947.726306842393;8224.753257402048;8406.159384061544;8440.53577053268;8533.573321334032;8543.829123417621;17302;17318;17428.47714568565;17579.384721534683;17768.200430980378;17923.28306867763;18033.549161271072;18076.09619519045;18172.818271817112;18187.363442279457;10643.680689579325;10680.198985075995;10785.083267922;10788.136949043901;10789.244752867224;11918.582849600223;12889.935550322189;13069;13075.738485230433;13091;11201.495932683212;11293.153013523854;11426.657200284004;11471.713207169887;11492;10576.576936922436;10712.089472394622;10859.2398532102;10865.78268434611;10889.183311251847;8721.781955451164;8812.86780698303;8818.867716984121;8829.16122968317;8843.557822108998;7352.632578218037;7596.202398748273;7604;7626.8093297668;7683.654235559525;7861.095973962839;7984.682527382916;8014.639341229542;8040.959795200986;8099.595020249082 05:34:59 INFO - PID 3546 | |4;prototype.html;491.8024804374608;506.9834980863371;516.5480204820782;518;524.6144084098187;6402.487800975917;6533;6539.215294164695;6568.605883646985;6625.939849624061;6189.442950134487;6314.273858506273;6333.968330158355;6377.649229292387;6425.614463132215;5038.823641172559;5092.421349381685;5108.4636113208135;5145.614078944075;5168.32811734474;4991.725455099968;5020.322256495363;5099.260607211952;5103.336566246401;5137.2037334213255;5073.340465739467;5105.157648987913;5134.717590532519;5153.149730294497;5156.871078223037;5034.823781167678;5041.185667687349;5072.137736584789;5120.769565369567;5129.358830146231;4524.479684836253;4528.705634133804;4551.294549344857;4578.427696537933;4590.291727880566;6316.368363163693;6332.715027823713;6375.968120159416;6387.041943708435;6420.646864422455;5823.912641310384;5873.20711703923;5876.853078673024;5937.8812423751715;5952.761889524414;6664.600123992576;6724.529282950195;6743.15710536183;6785.491088168407;6823.590584564892;4901.730404827733;4943.332650092228;4996.777078784164;5015.423226328982;5026.346574945233;7096.290370962863;7120.216776154619;7152.821179470555;7299.525530840531;7323.853522929565;5078.892259712178;5096.439391666913;5101.489851014869;5119.436861945183;5138.717370544581;5163.974180129076;5209.374875015023;5210.7915683373;5211.530962213419;5257.316548848626;5853.765849366063;5887.175795388649;5889.469947704727;5921.940780592225;5926.881462370815;6725.495587830932;6730.629815360202;6801.863962720718;6818.863622727517;6835.453163746888;1672.3979367427717;1720.1743163281694;1723.2417736196285;1769.1066011664027;1777.8222177782118;1214.1258294028285;1221.6396163131897;1233.3524899427694;1239.114033466076;1244.166408506303;5865.442782935615;5896.439838215363;5898.557608179403;5902.557308201902;5967.970889897456;6698.330166983263;6892.243102758936;7006.789796306119;7008.404285635778;7042.225355210922;5942.940570594239;6008.759649614054;6013.789517366871;6026.849328766816;6027.336992930774;3640.3265395901844;3704.518412606344;3718.6984555405393;3743.9221232216382;3774.4338349247832;6675.899861502081;6705.463562914955;6715.899261511081;6731.225909020525;6756.763513277012;7128.465365097639;7139.286071392923;7178.989882832815;7202.135743710788;7231.5536892621685;6691.063251144844;6733.528652994243;6750.594964302158;6797.558158719767;6805.149356330359;6843.460221450134;6995.166957382268;7003.439724822104;7004.649767511502;7079.39825114871;6988.938263212466;7022.08712867324;7062.117235345582;7065.364117229268;7083.264600172991;6712.429443497357;6806.727730890907;6826.658667066726;6830.316968303328;6849.013786002203;5589.357223919139;5677.3471050829685;5679.971600141973;5699.116636921284;5722.341930678025;6715.5299129060495;6758.7296508139125;7094;7165.498415111101;7171.677274522764;6742.494312926452;6815.965920170367;6852.862942741018;6859.485538584527;6903.413209877217;6666.300038495888;6690.364415380531;6788.796336109925;6845;6845.897311540231;6714.764983225564;6737.932620673927;6749.730010799513;6754.8649027020165;6758.662066896537;6801.32728326698;6838.93161068383;6902.896456553055;6921.723131074902;6969.7909062728195;6430.356964303719;6568.770093046721;6577;6600.174978127734;6624.8343791405605 05:34:59 INFO - PID 3546 | |5;yui.html;871.9825603487931;875.8248350329932;891.189017993626;892.9196372326492;894.9642014319428;17198.39805606804;18038.5490362741;18058.27766889326;18094.095295235253;20133.496662583442;18990.335338263165;19511.317103901365;20565.794342056575;21113.788862111414;21140.365789026353;917.064594114005;933.780561568031;937.2033771294414;940.2524992630875;946.0964778636386;877.2061284537504;940.8212439636482;947.5593848860278;951.8667386565886;955.6846240740574;1008.606643409071;1010.343276870033;1012.7265638277661;1013.4020927652683;1014.1937159957814;1000.3547711725932;1001.6994901529548;1002.9799404011952;1004.1113614451227;1011.7167193185846;740.0970815604953;742.5359150530918;743.156517352803;743.2418932688681;743.6950850151412;18649.7202541962;18917.14872830726;19107.331243406414;19213.9039304804;19856.503587410287;20543.2809851656;20606.38180854562;21111.838848863306;21112;21451.356459306247;16742.497725068268;17084.48746537606;17117.31530738769;17222.397216097375;17228.91385543077;3570.785752854837;3588.982055089708;3589.4436362363876;3598.226381328001;3600.8199590020395;128051.35974320162;128264;129446.70553294441;130777;133709.3314533431;16042.598935026717;16334.264958076916;16347.918260408622;16351.59121021984;16541.172941352885;29069.563956540667;29364.853175733984;29624.555631665542;29654.55518167229;29667.96162134315;8740.78148046304;8867.423617464845;8914.242289405343;8915.687950921687;8941.731748047569;4269.124829410026;4310;4393.890152746143;4393.978030109829;4411.139827733561;5115.514026167582;5230.293910322134;5234.18870075139;5244.318238628954;5248.265242866002;969.8060387922303;994.7761753605381;995.2485873165714;1001.8647482589757;1004.8040632076675;668.2181847238743;681.9488538359643;688.721067967474;691.5228492340269;700.467644590105;11809.52761889532;11845;11976.461059252355;12032.217905836093;12943.805842912363;687.3229868579446;693.5630552751734;700.471144286071;701.4248316380621;707.702764838769;6063.878722425527;6351.904721429182;6480;6603.141591593062;6692.698828552727;107495;108205;110407.44796275967;111254.44372778085;115041;83819.5809020951;99246;99545;102307.4884625587;103513;86156.56921715477;90521.54739226394;90639.54680226557;92288;93638;12394.876051239733;12537.185082969578;12733.363331833556;12837.405526452609;12918;37729.434058488594;38394.42408363821;38505.229895401375;39222;40379;49433;50245;51262.231066533284;51624.22563661623;51663.48336516587;18074.548136296697;18304.542386439913;18465.630687385907;18855.622887541896;18912.054397279804;20836;20875.26936557213;21022.159113635284;21145.365639030853;22165.889170554045;17684.557886052953;18582;18657.160427781055;19248.23007079701;19785.604287914448;18799.24803007919;18827;18828.52928676794;18854.717179242598;19703.507412314233;17887;17993.280268789626;18128;18426.907865460587;19126;14473.85526144725;14665.56003319902;14792.778108328168;14924.55226343168;15088.094714317176;13579.59261222165;14238.003339766117;14253;14293.098991987561;14382.712345753236 05:34:59 INFO - PID 3546 | __end_tp_report 05:34:59 INFO - PID 3546 | __start_cc_report 05:34:59 INFO - PID 3546 | _x_x_mozilla_cycle_collect,355 05:34:59 INFO - PID 3546 | __end_cc_report 05:34:59 INFO - PID 3546 | __startTimestamp1503578099370__endTimestamp 05:34:59 INFO - PID 3546 | 05:34:59 INFO - PID 3546 | ------- Summary: start ------- 05:34:59 INFO - PID 3546 | Number of tests: 6 05:34:59 INFO - PID 3546 | 05:34:59 INFO - PID 3546 | [#0] dojo.html Cycles:165 Average:6230.63 Median:6552.38 stddev:845.09 (12.9%) stddev-sans-first:815.68 05:34:59 INFO - PID 3546 | Values: 3294.0 3300.7 3300.8 3350.9 3358.6 6266.1 6320.0 6345.7 6363.4 6402.7 6112.8 6211.0 6365.9 6452.7 6474.0 5800.1 5810.1 5845.7 5853.9 5908.4 906.1 5545.3 5716.8 5751.0 5876.3 5780.5 5795.7 5855.9 5865.6 5869.7 5782.9 5788.3 5865.0 5868.3 5869.1 5728.1 5757.7 5813.2 5830.3 5856.8 6144.1 6241.0 6252.6 6270.4 6323.1 6602.5 6737.2 6777.0 6843.7 6862.0 6834.6 6884.4 6930.1 6946.8 6958.5 6334.8 6355.4 6430.9 6431.1 6434.0 6857.6 6911.8 6912.7 6923.2 6945.2 6630.6 6648.2 6657.5 6657.6 6671.1 6669.2 6722.4 6734.5 6748.5 6753.3 6632.6 6709.6 6755.4 6785.7 6791.5 6771.7 6833.4 6856.1 6858.3 6872.1 5341.4 5355.0 5408.6 5422.7 5443.8 4630.3 4666.7 4668.5 4702.6 4761.5 5995.8 6008.1 6008.4 6013.5 6029.8 6854.6 6907.0 6925.9 6931.7 6944.5 5927.3 5951.2 6010.8 6012.2 6031.4 5641.4 5715.9 5746.5 5769.6 5831.2 6634.3 6661.3 6661.8 6665.2 6676.0 6805.3 6914.2 6918.2 6929.7 6940.1 6624.9 6648.9 6666.7 6673.1 6685.9 6747.0 6752.1 6826.7 6840.8 6849.0 6727.2 6832.4 6884.7 6981.5 6982.6 6520.6 6552.4 6553.6 6562.2 6574.4 6564.6 6567.8 6570.5 6571.1 6685.2 6580.0 6595.8 6618.2 6651.9 6674.1 6411.4 6499.7 6645.1 6704.9 6769.3 6342.6 6360.1 6362.0 6372.3 6393.1 05:34:59 INFO - PID 3546 | 05:34:59 INFO - PID 3546 | [#1] ext.html Cycles:180 Average:37518.70 Median:18768.69 stddev:54780.64 (291.9%) stddev-sans-first:54864.78 05:34:59 INFO - PID 3546 | Values: 758.3 803.4 825.7 851.9 853.1 38716.2 39770.0 39870.0 39899.0 39934.6 35552.0 35726.3 35797.3 35816.0 35872.8 6757.6 6854.9 6871.3 6871.5 6876.3 6745.3 6832.6 6841.4 6846.1 6847.1 6825.8 7488.4 7631.3 7646.3 7655.5 7541.5 7589.5 7592.2 7604.0 7625.6 7419.1 7443.8 7520.0 7625.3 7637.9 35387.5 35866.5 35915.5 35919.6 35954.3 3393.4 3412.1 3422.7 3431.9 3433.2 29763.4 29955.0 30045.1 30283.5 30320.1 5072.9 5144.2 5149.8 5164.2 5166.1 184925.0 185203.0 185953.0 186042.0 186547.0 46446.3 46667.0 46683.1 46703.5 46731.8 47699.8 48033.0 48044.0 48049.8 48234.8 14392.3 14618.2 14623.0 14676.9 14687.3 11810.0 11826.9 11828.4 11828.5 11833.2 13805.8 13819.0 14015.7 14120.5 14193.0 5588.9 5594.5 5644.2 5704.1 5734.5 975.2 1010.2 1015.3 1017.4 1020.6 22367.6 22399.9 22418.1 22438.4 22440.4 1093.3 1095.4 1109.5 1113.8 1118.5 11935.5 11954.8 12040.1 12057.3 15863.0 230524.0 231986.0 232329.0 232494.0 233228.0 49295.8 52995.7 53142.5 53481.0 53620.2 1374.1 1409.1 1417.2 1421.6 1425.2 26389.6 26574.9 26713.1 26927.6 27701.6 48555.3 48760.8 49032.8 49038.8 49153.0 211760.0 212775.0 213166.0 213397.0 213617.0 33276.7 34027.4 34125.3 34216.0 34358.3 41810.8 42039.8 42186.4 42211.0 42227.6 30032.2 30187.2 30513.8 30646.8 30724.7 30763.0 30820.1 30909.2 31317.5 31321.4 33871.5 34027.0 34049.0 34261.5 34339.3 29635.7 30296.7 30447.4 30595.0 30691.8 22820.9 23191.8 23435.3 23460.3 23592.6 05:34:59 INFO - PID 3546 | 05:34:59 INFO - PID 3546 | [#2] jquery.html Cycles:180 Average:37007.47 Median:23805.58 stddev:92305.84 (387.7%) stddev-sans-first:92524.16 05:34:59 INFO - PID 3546 | Values: 534.5 554.4 558.2 571.8 576.9 20993.5 22066.4 22092.8 22095.2 22122.8 25524.0 26054.6 26142.7 26181.6 26194.3 6417.8 6569.9 6572.9 6573.8 6576.0 5995.4 6433.3 6544.2 6547.4 6579.0 6528.3 6539.2 6540.1 6542.0 6542.3 6539.7 6541.2 6543.0 6553.0 6558.7 6031.8 6042.8 6053.9 6056.8 6063.6 27847.0 28446.4 28976.4 29090.0 29154.9 2010.8 2042.2 2043.4 2047.7 2049.5 18103.9 18831.2 19892.2 19963.7 20008.6 2408.8 2413.8 2462.6 2480.5 2497.7 134508.3 137123.0 137858.0 147761.3 148270.3 14284.9 14591.1 14658.0 14751.2 15043.0 22465.1 22896.7 22950.7 22951.3 22989.7 9374.5 9746.5 9759.7 9768.0 9853.8 8680.8 8703.0 8719.2 8875.1 8906.0 8612.4 8639.3 8673.3 8869.9 9019.5 2493.0 2603.4 2624.9 2675.5 2704.8 932.1 950.6 954.2 955.0 965.0 19258.8 19667.0 23326.5 24541.4 24578.9 999.2 1013.3 1025.8 1027.4 1033.0 10965.6 11434.8 13066.2 14200.9 14248.4 510735.0 515728.0 572964.0 576462.0 577171.0 115248.0 117582.4 119125.0 124236.0 125345.0 1066.2 1122.2 1161.5 1163.7 1227.3 20958.2 21087.7 21775.9 22099.6 22343.7 57755.1 58880.4 59037.7 60201.4 63474.4 70468.6 70975.0 70994.5 71035.6 72166.6 14012.8 14458.9 14758.9 14865.3 14923.8 23571.5 23634.6 23682.4 23733.2 23878.0 18294.5 18873.8 19019.9 19021.4 19027.1 16051.7 17540.6 17708.1 17904.4 18260.2 20295.8 20383.3 20615.3 20634.1 20639.7 13963.6 14444.6 14697.2 14701.9 14719.9 19133.7 19300.8 19302.4 19308.4 19320.6 05:34:59 INFO - PID 3546 | 05:34:59 INFO - PID 3546 | [#3] mootools.html Cycles:180 Average:9045.16 Median:2579.84 stddev:6270.77 (243.1%) stddev-sans-first:6261.27 05:34:59 INFO - PID 3546 | Values: 1288.2 1334.2 1341.4 1357.4 1358.4 14275.4 14831.3 14869.5 14880.7 14885.6 14394.9 14839.4 15542.9 15555.2 15610.1 2658.7 2730.1 2730.7 2730.8 2732.3 2601.8 2618.0 2641.4 2649.7 2757.9 2738.2 2745.4 2771.0 2771.2 2771.9 2523.8 2547.7 2618.5 2634.5 2636.5 2454.8 2473.5 2557.9 2606.0 2608.9 14568.2 14640.2 14657.1 14674.4 14723.0 7702.5 7965.2 7993.4 7997.4 8004.5 5196.0 5199.8 5306.3 5341.4 5368.1 1914.2 1934.2 1959.5 1991.2 2006.7 25027.7 25112.9 25269.4 25289.0 25443.9 6737.5 6759.1 6763.7 6781.6 6899.4 16656.7 16669.9 16700.7 16700.8 16724.3 7635.5 7784.2 7794.1 7794.2 7798.6 6998.0 7056.0 7202.8 7252.2 7278.4 4602.7 5009.5 5171.8 5194.9 5225.3 1344.0 1353.8 1462.3 1471.4 1483.2 913.4 921.9 927.3 943.6 944.5 8611.0 8900.8 8912.5 8926.4 9022.5 983.2 985.4 986.8 993.4 1001.5 5223.2 5465.3 5468.9 5581.2 5681.2 19813.1 19894.2 20126.8 20987.7 21182.0 19414.3 19700.2 19734.2 19820.3 19839.8 10311.8 10627.4 10781.4 10938.3 10947.7 8224.8 8406.2 8440.5 8533.6 8543.8 17302.0 17318.0 17428.5 17579.4 17768.2 17923.3 18033.5 18076.1 18172.8 18187.4 10643.7 10680.2 10785.1 10788.1 10789.2 11918.6 12889.9 13069.0 13075.7 13091.0 11201.5 11293.2 11426.7 11471.7 11492.0 10576.6 10712.1 10859.2 10865.8 10889.2 8721.8 8812.9 8818.9 8829.2 8843.6 7352.6 7596.2 7604.0 7626.8 7683.7 7861.1 7984.7 8014.6 8041.0 8099.6 05:34:59 INFO - PID 3546 | 05:34:59 INFO - PID 3546 | [#4] prototype.html Cycles:180 Average:5720.62 Median:6376.81 stddev:1626.12 (25.5%) stddev-sans-first:1582.62 05:34:59 INFO - PID 3546 | Values: 491.8 507.0 516.5 518.0 524.6 6402.5 6533.0 6539.2 6568.6 6625.9 6189.4 6314.3 6334.0 6377.6 6425.6 5038.8 5092.4 5108.5 5145.6 5168.3 4991.7 5020.3 5099.3 5103.3 5137.2 5073.3 5105.2 5134.7 5153.1 5156.9 5034.8 5041.2 5072.1 5120.8 5129.4 4524.5 4528.7 4551.3 4578.4 4590.3 6316.4 6332.7 6376.0 6387.0 6420.6 5823.9 5873.2 5876.9 5937.9 5952.8 6664.6 6724.5 6743.2 6785.5 6823.6 4901.7 4943.3 4996.8 5015.4 5026.3 7096.3 7120.2 7152.8 7299.5 7323.9 5078.9 5096.4 5101.5 5119.4 5138.7 5164.0 5209.4 5210.8 5211.5 5257.3 5853.8 5887.2 5889.5 5921.9 5926.9 6725.5 6730.6 6801.9 6818.9 6835.5 1672.4 1720.2 1723.2 1769.1 1777.8 1214.1 1221.6 1233.4 1239.1 1244.2 5865.4 5896.4 5898.6 5902.6 5968.0 6698.3 6892.2 7006.8 7008.4 7042.2 5942.9 6008.8 6013.8 6026.8 6027.3 3640.3 3704.5 3718.7 3743.9 3774.4 6675.9 6705.5 6715.9 6731.2 6756.8 7128.5 7139.3 7179.0 7202.1 7231.6 6691.1 6733.5 6750.6 6797.6 6805.1 6843.5 6995.2 7003.4 7004.6 7079.4 6988.9 7022.1 7062.1 7065.4 7083.3 6712.4 6806.7 6826.7 6830.3 6849.0 5589.4 5677.3 5680.0 5699.1 5722.3 6715.5 6758.7 7094.0 7165.5 7171.7 6742.5 6816.0 6852.9 6859.5 6903.4 6666.3 6690.4 6788.8 6845.0 6845.9 6714.8 6737.9 6749.7 6754.9 6758.7 6801.3 6838.9 6902.9 6921.7 6969.8 6430.4 6568.8 6577.0 6600.2 6624.8 05:34:59 INFO - PID 3546 | 05:34:59 INFO - PID 3546 | [#5] yui.html Cycles:180 Average:23501.81 Median:20948.71 stddev:32103.63 (153.2%) stddev-sans-first:32148.72 05:34:59 INFO - PID 3546 | Values: 872.0 875.8 891.2 892.9 895.0 17198.4 18038.5 18058.3 18094.1 20133.5 18990.3 19511.3 20565.8 21113.8 21140.4 917.1 933.8 937.2 940.3 946.1 877.2 940.8 947.6 951.9 955.7 1008.6 1010.3 1012.7 1013.4 1014.2 1000.4 1001.7 1003.0 1004.1 1011.7 740.1 742.5 743.2 743.2 743.7 18649.7 18917.1 19107.3 19213.9 19856.5 20543.3 20606.4 21111.8 21112.0 21451.4 16742.5 17084.5 17117.3 17222.4 17228.9 3570.8 3589.0 3589.4 3598.2 3600.8 128051.4 128264.0 129446.7 130777.0 133709.3 16042.6 16334.3 16347.9 16351.6 16541.2 29069.6 29364.9 29624.6 29654.6 29668.0 8740.8 8867.4 8914.2 8915.7 8941.7 4269.1 4310.0 4393.9 4394.0 4411.1 5115.5 5230.3 5234.2 5244.3 5248.3 969.8 994.8 995.2 1001.9 1004.8 668.2 681.9 688.7 691.5 700.5 11809.5 11845.0 11976.5 12032.2 12943.8 687.3 693.6 700.5 701.4 707.7 6063.9 6351.9 6480.0 6603.1 6692.7 107495.0 108205.0 110407.4 111254.4 115041.0 83819.6 99246.0 99545.0 102307.5 103513.0 86156.6 90521.5 90639.5 92288.0 93638.0 12394.9 12537.2 12733.4 12837.4 12918.0 37729.4 38394.4 38505.2 39222.0 40379.0 49433.0 50245.0 51262.2 51624.2 51663.5 18074.5 18304.5 18465.6 18855.6 18912.1 20836.0 20875.3 21022.2 21145.4 22165.9 17684.6 18582.0 18657.2 19248.2 19785.6 18799.2 18827.0 18828.5 18854.7 19703.5 17887.0 17993.3 18128.0 18426.9 19126.0 14473.9 14665.6 14792.8 14924.6 15088.1 13579.6 14238.0 14253.0 14293.1 14382.7 05:34:59 INFO - PID 3546 | -------- Summary: end -------- 05:34:59 INFO - PID 3546 | 05:35:00 INFO - TEST-INFO | 3546: exit 0 05:35:00 INFO - TEST-OK | dromaeo_css | took 1112640ms 05:35:00 INFO - TEST-START | kraken 05:35:00 INFO - Initialising browser for kraken test... 05:35:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43442/getInfo.html -profile /tmp/tmp5UlNot/profile 05:35:00 INFO - TEST-INFO | started process 3976 (/builds/slave/test/build/application/firefox/firefox http://localhost:43442/getInfo.html) 05:35:05 INFO - TEST-INFO | 3976: exit 0 05:35:05 INFO - Browser initialized. 05:35:05 INFO - Running cycle 1/1 for kraken test... 05:35:05 INFO - TEST-INFO | started process 4161 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5UlNot/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:35:06 INFO - PID 4161 | 05:35:06 INFO - PID 4161 | (/builds/slave/test/build/application/firefox/firefox:4213): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:35:06 INFO - PID 4161 | 05:35:07 INFO - PID 4161 | 05:35:07 INFO - PID 4161 | (/builds/slave/test/build/application/firefox/firefox:4275): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:35:07 INFO - PID 4161 | 05:35:07 INFO - PID 4161 | __metrics Screen width/height:1600/1200 05:35:07 INFO - PID 4161 | colorDepth:24 05:35:07 INFO - PID 4161 | Browser inner width/height: 1024/768 05:35:07 INFO - PID 4161 | __metrics 05:35:16 INFO - PID 4161 | RSS: Main: 174141440 05:35:16 INFO - PID 4161 | 05:35:19 INFO - PID 4161 | Cycle 1(1): loaded http://localhost:43442/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43442/tests/kraken/driver.html?testName=audio-beat-detection) 05:35:20 INFO - PID 4161 | RSS: Main: 179740672 05:35:20 INFO - PID 4161 | 05:35:23 INFO - PID 4161 | Cycle 1(1): loaded http://localhost:43442/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43442/tests/kraken/driver.html?testName=audio-dft) 05:35:23 INFO - PID 4161 | RSS: Main: 181997568 05:35:23 INFO - PID 4161 | 05:35:26 INFO - PID 4161 | Cycle 1(1): loaded http://localhost:43442/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43442/tests/kraken/driver.html?testName=audio-fft) 05:35:27 INFO - PID 4161 | RSS: Main: 174137344 05:35:27 INFO - PID 4161 | 05:35:29 INFO - PID 4161 | Cycle 1(1): loaded http://localhost:43442/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43442/tests/kraken/driver.html?testName=audio-oscillator) 05:35:30 INFO - PID 4161 | RSS: Main: 174841856 05:35:30 INFO - PID 4161 | 05:35:32 INFO - PID 4161 | Cycle 1(1): loaded http://localhost:43442/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43442/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:35:33 INFO - PID 4161 | RSS: Main: 175685632 05:35:33 INFO - PID 4161 | 05:35:36 INFO - PID 4161 | Cycle 1(1): loaded http://localhost:43442/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43442/tests/kraken/driver.html?testName=imaging-darkroom) 05:35:36 INFO - PID 4161 | RSS: Main: 176484352 05:35:36 INFO - PID 4161 | 05:35:40 INFO - PID 4161 | Cycle 1(1): loaded http://localhost:43442/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43442/tests/kraken/driver.html?testName=imaging-desaturate) 05:35:41 INFO - PID 4161 | RSS: Main: 176664576 05:35:41 INFO - PID 4161 | 05:35:43 INFO - PID 4161 | Cycle 1(1): loaded http://localhost:43442/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43442/tests/kraken/driver.html?testName=json-parse-financial) 05:35:44 INFO - PID 4161 | RSS: Main: 176865280 05:35:44 INFO - PID 4161 | 05:35:46 INFO - PID 4161 | Cycle 1(1): loaded http://localhost:43442/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43442/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:35:46 INFO - PID 4161 | RSS: Main: 177008640 05:35:46 INFO - PID 4161 | 05:35:48 INFO - PID 4161 | Cycle 1(1): loaded http://localhost:43442/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43442/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:35:49 INFO - PID 4161 | RSS: Main: 177438720 05:35:49 INFO - PID 4161 | 05:35:51 INFO - PID 4161 | Cycle 1(1): loaded http://localhost:43442/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43442/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:35:52 INFO - PID 4161 | RSS: Main: 177106944 05:35:52 INFO - PID 4161 | 05:35:54 INFO - PID 4161 | Cycle 1(1): loaded http://localhost:43442/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43442/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:35:55 INFO - PID 4161 | RSS: Main: 177287168 05:35:55 INFO - PID 4161 | 05:35:58 INFO - PID 4161 | Cycle 1(1): loaded http://localhost:43442/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43442/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:35:59 INFO - PID 4161 | RSS: Main: 177606656 05:35:59 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | Cycle 1(1): loaded http://localhost:43442/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43442/tests/kraken/driver.html?testName=ai-astar) 05:36:01 INFO - PID 4161 | RSS: Main: 178810880 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | __start_tp_report 05:36:01 INFO - PID 4161 | _x_x_mozilla_page_load 05:36:01 INFO - PID 4161 | _x_x_mozilla_page_load_details 05:36:01 INFO - PID 4161 | |i|pagename|runs| 05:36:01 INFO - PID 4161 | |0;ai-astar;96;112;99;107;110;108;106;98;106;111 05:36:01 INFO - PID 4161 | |1;audio-beat-detection;154;119;147;170;125;148;142;116;164;120 05:36:01 INFO - PID 4161 | |2;audio-dft;162;161;171;171;169;159;164;163;164;164 05:36:01 INFO - PID 4161 | |3;audio-fft;76;73;73;169;76;73;72;74;149;75 05:36:01 INFO - PID 4161 | |4;audio-oscillator;79;79;138;85;79;82;139;79;79;127 05:36:01 INFO - PID 4161 | |5;imaging-gaussian-blur;114;118;117;113;114;113;114;118;120;118 05:36:01 INFO - PID 4161 | |6;imaging-darkroom;149;147;148;148;148;147;148;153;152;149 05:36:01 INFO - PID 4161 | |7;imaging-desaturate;94;93;99;94;94;94;94;93;96;94 05:36:01 INFO - PID 4161 | |8;json-parse-financial;52;57;56;52;52;62;58;55;51;55 05:36:01 INFO - PID 4161 | |9;json-stringify-tinderbox;61;60;55;55;56;55;55;55;59;64 05:36:01 INFO - PID 4161 | |10;stanford-crypto-aes;75;76;80;81;75;73;83;76;74;73 05:36:01 INFO - PID 4161 | |11;stanford-crypto-ccm;130;126;119;124;119;121;123;124;127;166 05:36:01 INFO - PID 4161 | |12;stanford-crypto-pbkdf2;176;176;176;176;174;176;173;172;174;173 05:36:01 INFO - PID 4161 | |13;stanford-crypto-sha256-iterative;67;69;68;62;68;66;75;65;62;68 05:36:01 INFO - PID 4161 | __end_tp_report 05:36:01 INFO - PID 4161 | __start_cc_report 05:36:01 INFO - PID 4161 | _x_x_mozilla_cycle_collect,955 05:36:01 INFO - PID 4161 | __end_cc_report 05:36:01 INFO - PID 4161 | __startTimestamp1503578161471__endTimestamp 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | ------- Summary: start ------- 05:36:01 INFO - PID 4161 | Number of tests: 14 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | [#0] ai-astar Cycles:10 Average:105.30 Median:111.50 stddev:5.68 (5.1%) stddev-sans-first:4.92 05:36:01 INFO - PID 4161 | Values: 96.0 112.0 99.0 107.0 110.0 108.0 106.0 98.0 106.0 111.0 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:147.50 stddev:19.52 (13.2%) stddev-sans-first:20.08 05:36:01 INFO - PID 4161 | Values: 154.0 119.0 147.0 170.0 125.0 148.0 142.0 116.0 164.0 120.0 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | [#2] audio-dft Cycles:10 Average:164.80 Median:164.00 stddev:4.16 (2.5%) stddev-sans-first:4.28 05:36:01 INFO - PID 4161 | Values: 162.0 161.0 171.0 171.0 169.0 159.0 164.0 163.0 164.0 164.0 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | [#3] audio-fft Cycles:10 Average:91.00 Median:73.50 stddev:36.17 (49.2%) stddev-sans-first:37.96 05:36:01 INFO - PID 4161 | Values: 76.0 73.0 73.0 169.0 76.0 73.0 72.0 74.0 149.0 75.0 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:79.00 stddev:26.53 (33.6%) stddev-sans-first:27.36 05:36:01 INFO - PID 4161 | Values: 79.0 79.0 138.0 85.0 79.0 82.0 139.0 79.0 79.0 127.0 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | [#5] imaging-gaussian-blur Cycles:10 Average:115.90 Median:117.50 stddev:2.56 (2.2%) stddev-sans-first:2.62 05:36:01 INFO - PID 4161 | Values: 114.0 118.0 117.0 113.0 114.0 113.0 114.0 118.0 120.0 118.0 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:148.50 stddev:2.02 (1.4%) stddev-sans-first:2.15 05:36:01 INFO - PID 4161 | Values: 149.0 147.0 148.0 148.0 148.0 147.0 148.0 153.0 152.0 149.0 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.00 stddev:1.78 (1.9%) stddev-sans-first:1.88 05:36:01 INFO - PID 4161 | Values: 94.0 93.0 99.0 94.0 94.0 94.0 94.0 93.0 96.0 94.0 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.50 stddev:3.43 (6.2%) stddev-sans-first:3.46 05:36:01 INFO - PID 4161 | Values: 52.0 57.0 56.0 52.0 52.0 62.0 58.0 55.0 51.0 55.0 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | [#9] json-stringify-tinderbox Cycles:10 Average:57.50 Median:57.50 stddev:3.27 (5.7%) stddev-sans-first:3.22 05:36:01 INFO - PID 4161 | Values: 61.0 60.0 55.0 55.0 56.0 55.0 55.0 55.0 59.0 64.0 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | [#10] stanford-crypto-aes Cycles:10 Average:76.60 Median:76.00 stddev:3.50 (4.6%) stddev-sans-first:3.67 05:36:01 INFO - PID 4161 | Values: 75.0 76.0 80.0 81.0 75.0 73.0 83.0 76.0 74.0 73.0 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:125.00 stddev:13.83 (11.1%) stddev-sans-first:14.65 05:36:01 INFO - PID 4161 | Values: 130.0 126.0 119.0 124.0 119.0 121.0 123.0 124.0 127.0 166.0 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.60 Median:176.00 stddev:1.58 (0.9%) stddev-sans-first:1.59 05:36:01 INFO - PID 4161 | Values: 176.0 176.0 176.0 176.0 174.0 176.0 173.0 172.0 174.0 173.0 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:68.00 stddev:3.74 (5.5%) stddev-sans-first:3.97 05:36:01 INFO - PID 4161 | Values: 67.0 69.0 68.0 62.0 68.0 66.0 75.0 65.0 62.0 68.0 05:36:01 INFO - PID 4161 | -------- Summary: end -------- 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150081,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | 05:36:01 INFO - PID 4161 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 05:36:01 INFO - PID 4161 | 05:36:02 INFO - PID 4161 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:36:02 INFO - TEST-INFO | 4161: exit 0 05:36:02 INFO - TEST-OK | kraken | took 62267ms 05:36:02 INFO - SUITE-END | took 1174s 05:36:02 INFO - Completed test suite (00:19:35) 05:36:02 INFO - javascript benchmark 05:36:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8558.07379035771, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3294.028261662808, 3300.702936735693, 3300.834958252087, 3350.9162270943225, 3358.580177477815, 6266.060090986354, 6319.968400157998, 6345.7144428500715, 6363.4272915437605, 6402.6798660066925, 6112.7860524881635, 6211.0372892201785, 6365.904511432333, 6452.741890324393, 6473.996530537763, 5800.129980502916, 5810.128480727883, 5845.7369418376065, 5853.8829223415505, 5908.438698323653, 906.1090767462553, 5545.334559852803, 5716.8284951451315, 5751.0, 5876.265466816648, 5780.450857168562, 5795.71021448928, 5855.8536036599, 5865.58940874139, 5869.735861886225, 5782.913256301159, 5788.3343415506915, 5865.002949498596, 5868.2664666916635, 5869.06095024794, 5728.112142617901, 5757.68332741699, 5813.244278243844, 5830.300363956352, 5856.824295271149, 6144.0783882417545, 6240.968795156041, 6252.562320637558, 6270.435660790505, 6323.05154226865, 6602.537822352438, 6737.157855268091, 6777.0, 6843.726250949956, 6862.0, 6834.555753875982, 6884.380405763505, 6930.13373328334, 6946.826329341757, 6958.512904096715, 6334.778282760081, 6355.428011478943, 6430.871382572369, 6431.067495213215, 6434.0, 6857.55425897315, 6911.75808846688, 6912.688928998106, 6923.2038315593345, 6945.201301850251, 6630.569013014138, 6648.235452922976, 6657.500687448461, 6657.600543967377, 6671.066050752898, 6669.166354205724, 6722.428593569503, 6734.46124310054, 6748.460123190133, 6753.29090445506, 6632.568883022588, 6709.630970296681, 6755.425788807908, 6785.728570857209, 6791.456683465311, 6771.695273712695, 6833.384995350442, 6856.108705868205, 6858.279880612564, 6872.140982377203, 5341.3857406398765, 5355.036093503207, 5408.567314614821, 5422.728863556807, 5443.755031023614, 4630.305454181832, 4666.65000124992, 4668.50980647034, 4702.553257440538, 4761.47623761386, 5995.790147344822, 6008.068749343859, 6008.369121242206, 6013.4888534474185, 6029.788957386558, 6854.6229957351825, 6907.0, 6925.896111558331, 6931.68807403658, 6944.479164062715, 5927.2590926134235, 5951.166836642873, 6010.819675409657, 6012.188354572164, 6031.4270144336215, 5641.435856414408, 5715.857103572361, 5746.454086861741, 5769.567282453832, 5831.154482599999, 6634.336566343327, 6661.26726060133, 6661.833454163588, 6665.200175978914, 6676.0, 6805.251422343637, 6914.204866440323, 6918.238993710587, 6929.7228110875, 6940.13248343957, 6624.867502650017, 6648.933510664831, 6666.666666666744, 6673.132492775908, 6685.933140668531, 6746.987951807072, 6752.054712340375, 6826.692798823966, 6840.794776156723, 6849.04113424131, 6727.159105111861, 6832.350926661959, 6884.724611015704, 6981.5112942093565, 6982.581045137308, 6520.576162549419, 6552.3775241352, 6553.574017688835, 6562.179727534059, 6574.3754343337305, 6564.573302735307, 6567.802965911031, 6570.474362050929, 6571.080048793268, 6685.197776266879, 6580.045893345534, 6595.835104122435, 6618.238902526271, 6651.900221496585, 6674.065630811593, 6411.3909178627955, 6499.6750162492635, 6645.1361323027695, 6704.927211646113, 6769.28922463154, 6342.587731797418, 6360.141380913517, 6362.045693146065, 6372.330905254881, 6393.1369265148605], "value": 6171.4105568155355, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.2644834510527, 803.3773825285399, 825.6821123867311, 851.8935133108359, 853.1085016158114, 38716.22567548647, 39770.00574985627, 39870.0, 39899.0, 39934.60065399345, 35552.0, 35726.2854742905, 35797.2840543189, 35816.0, 35872.82063589685, 6757.628330441811, 6854.862902741942, 6871.278515755848, 6871.450283977295, 6876.312368763109, 6745.29174436682, 6832.624205668686, 6841.384275415212, 6846.144231971004, 6847.075644787939, 6825.795226143224, 7488.438367122488, 7631.275028872248, 7646.273604007611, 7655.540667559964, 7541.472096953188, 7589.506682065703, 7592.240775922363, 7604.0, 7625.580593067351, 7419.146798118231, 7443.813904652373, 7520.0, 7625.275598818103, 7637.885431718529, 35387.469187962204, 35866.46200306997, 35915.461268081, 35919.640803591894, 35954.28091438183, 3393.4400823864034, 3412.0616830371846, 3422.709069729076, 3431.9485207721905, 3433.2446861690387, 29763.40473190524, 29954.95157669493, 30045.098647040624, 30283.545746813816, 30320.090397287895, 5072.873178170538, 5144.228365745131, 5149.768260428253, 5164.173732202867, 5166.070107380708, 184925.0, 185202.99999999866, 185953.0, 186042.0, 186547.0, 46446.30330545045, 46667.0, 46683.066338673714, 46703.53296466992, 46731.76634116808, 47699.76150119297, 48033.0, 48044.0, 48049.75975120172, 48234.758826205645, 14392.280385980659, 14618.195999220145, 14623.04950178235, 14676.853231467549, 14687.339069741887, 11810.0, 11826.881731182579, 11828.408579570987, 11828.467718952668, 11833.171677982458, 13805.792913106312, 13819.032667713163, 14015.719685606231, 14120.505782297776, 14193.0, 5588.888222235532, 5594.468525490072, 5644.153376993402, 5704.058830293038, 5734.455226753452, 975.1564896364594, 1010.2372708605103, 1015.3451024089421, 1017.4251547875575, 1020.6172685243034, 22367.55264894693, 22399.88000599996, 22418.103275869107, 22438.43903902383, 22440.438989025406, 1093.3494570730402, 1095.4030053620795, 1109.5284504085732, 1113.760541483585, 1118.4799068433217, 11935.522579096913, 11954.76090478203, 12040.096992725405, 12057.336846473529, 15863.0, 230524.0, 231986.0, 232329.0, 232494.0, 233228.0, 49295.75352123288, 52995.73502132542, 53142.468575314524, 53481.0, 53620.19569706458, 1374.0587697427295, 1409.0840953380384, 1417.1780367386891, 1421.623269833495, 1425.2090089999942, 26389.60415593806, 26574.86712566502, 26713.130080894927, 26927.596086059115, 27701.58447623247, 48555.27167092426, 48760.75619621879, 49032.75483622702, 49038.75480622574, 49153.0, 211760.0, 212775.0, 213166.0, 213397.0, 213617.0, 33276.66723332833, 34027.447941403996, 34125.31749365049, 34216.0, 34358.31283374268, 41810.790946045076, 42039.78980105202, 42186.36720449134, 42211.0, 42227.57772422236, 30032.24919377033, 30187.245318867204, 30513.847430762704, 30646.84676576603, 30724.692753072184, 30763.0, 30820.075397737208, 30909.227269318446, 31317.530237046918, 31321.373572527966, 33871.49192762061, 34027.0, 34049.0, 34261.48607770835, 34339.313213735084, 29635.666395011845, 30296.697033029388, 30447.391052179275, 30595.0, 30691.846540767154, 22820.885895570416, 23191.768082318962, 23435.296941091794, 23460.296191114296, 23592.646110308015], "value": 16781.91889958348, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [534.478883088988, 554.3680204566795, 558.1599692462843, 571.7541457173419, 576.9365369809321, 20993.47516312091, 22066.448338791517, 22092.779072209272, 22095.226667066658, 22122.778772212274, 25524.0, 26054.609180862302, 26142.738572614267, 26181.607275890878, 26194.345141371476, 6417.807465776023, 6569.868602627945, 6572.934270657304, 6573.835654108638, 6575.967120164392, 5995.430434108753, 6433.324500927405, 6544.214694236698, 6547.410733034026, 6579.0, 6528.347165283458, 6539.182602174728, 6540.084388185658, 6541.985992171221, 6542.280349161589, 6539.705713242892, 6541.21505419348, 6543.018547217908, 6553.049807777893, 6558.672066396685, 6031.819045428644, 6042.758289668408, 6053.878922421571, 6056.848578785522, 6063.575549711522, 27847.02535411251, 28446.43107137866, 28976.42047159066, 29090.0, 29154.85422572895, 2010.7587089549202, 2042.1525067097136, 2043.4176259765895, 2047.6621357476, 2049.4876280929766, 18103.909480452647, 18831.152598133118, 19892.20431182751, 19963.700544491843, 20008.599828003506, 2408.844885137429, 2413.818963577721, 2462.6429167770752, 2480.516299321633, 2497.7377375375504, 134508.32745836306, 137123.0, 137858.0, 147761.26119369443, 148270.25864870715, 14284.881007932812, 14591.124532528082, 14658.0, 14751.188684622233, 15043.022203556733, 22465.101395944304, 22896.65655015176, 22950.65574016391, 22951.31146065621, 22989.655155172684, 9374.531273436301, 9746.512674366253, 9759.707208783748, 9768.023197680317, 9853.75365615851, 8680.782980425414, 8702.956485217534, 8719.215270625675, 8875.068117847663, 8906.0, 8612.397132200686, 8639.321810238846, 8673.349498787617, 8869.866951995726, 9019.549022548978, 2493.0180506497377, 2603.38820377215, 2624.8818803153904, 2675.518406686815, 2704.7971402144917, 932.1424289653535, 950.5532399772096, 954.2127744610724, 954.961801527945, 964.9565769540247, 19258.8074119257, 19667.0, 23326.53346933052, 24541.38646533851, 24578.877105614716, 999.1715161254887, 1013.3008224325192, 1025.8153532364097, 1027.3887037212846, 1032.9690109296732, 10965.56137754481, 11434.828477572843, 13066.216027038217, 14200.857991419953, 14248.430062797579, 510735.0, 515728.0, 572964.0, 576462.0, 577171.0, 115248.0, 117582.41208793901, 119125.0, 124236.0, 125345.0, 1066.2110038571402, 1122.231271578971, 1161.5143592711604, 1163.7148898519918, 1227.312704885285, 20958.161673533497, 21087.683684745047, 21775.891120544296, 22099.558008840053, 22343.664845027663, 57755.133672994096, 58880.41119588921, 59037.70481147567, 60201.39798602133, 63474.36525634684, 70468.64765676139, 70975.0, 70994.45449246008, 71035.64482177555, 72166.63916680383, 14012.789808152682, 14458.927705361406, 14758.926205368904, 14865.331060102537, 14923.776143357642, 23571.528569428858, 23634.64548031815, 23682.407939801644, 23733.16933907305, 23878.0, 18294.542636433664, 18873.811261887204, 19019.90490047596, 19021.42935711931, 19027.143778530277, 16051.678966420839, 17540.561485962444, 17708.114594270493, 17904.373346932796, 18260.178291976626, 20295.79704202939, 20383.286584969454, 20615.278465253643, 20634.07146678433, 20639.690404643643, 13963.58109256724, 14444.566663000127, 14697.19165445868, 14701.92649036748, 14719.92640036793, 19133.712994304817, 19300.8069919299, 19302.4209273722, 19308.4207473776, 19320.613587727887], "value": 12157.340195719244, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1288.2206265209547, 1334.2461509247275, 1341.4164838295346, 1357.3620398412743, 1358.4430383542738, 14275.357608907598, 14831.332590033448, 14869.479568215114, 14880.702385952274, 14885.627859303495, 14394.856051439509, 14839.406423743065, 15542.922285388586, 15555.222238888038, 15610.141442220673, 2658.7208343123984, 2730.085421383839, 2730.7269273072634, 2730.795190360721, 2732.27594687407, 2601.7528334808258, 2618.0313284084914, 2641.379275870169, 2649.7482739139846, 2757.9448411031767, 2738.2332946774936, 2745.3822889849725, 2770.986145069272, 2771.237909574873, 2771.88912443502, 2523.7728604425506, 2547.694276686791, 2618.528664840328, 2634.473105378932, 2636.459525797207, 2454.8158888083467, 2473.5300292944285, 2557.9360515987064, 2606.0487921908557, 2608.9478210435873, 14568.198749068797, 14640.194789286585, 14657.120572765669, 14674.413023479048, 14723.043002204822, 7702.537847729098, 7965.163657815961, 7993.360531157494, 7997.360211183091, 8004.479708818908, 5196.0, 5199.771547763892, 5306.257124002643, 5341.359036915595, 5368.087425137736, 1914.2151717795637, 1934.2456441987636, 1959.5395082155683, 1991.1736629298823, 2006.6588679924448, 25027.749722502725, 25112.874435627888, 25269.368265793502, 25289.0, 25443.87278063598, 6737.528373013842, 6759.121314229119, 6763.661816909135, 6781.559198652072, 6899.448044156456, 16656.6668666626, 16669.91665041667, 16700.66598668044, 16700.83299167017, 16724.331026759035, 7635.541867487968, 7784.182660820532, 7794.10367807698, 7794.181610930884, 7798.610069496503, 6997.965010174917, 7055.964720176366, 7202.819929501804, 7252.238514955854, 7278.417726581861, 4602.723836569817, 5009.5491405773655, 5171.7672704728375, 5194.92207616886, 5225.320708307896, 1343.9664008399673, 1353.8375394952668, 1462.334637739826, 1471.3599584180915, 1483.2287210650402, 913.4427998920653, 921.9078092190862, 927.2906226736552, 943.5518128888723, 944.527736131934, 8611.0, 8900.777480563038, 8912.465252084767, 8926.375153739305, 9022.548872556346, 983.2134292565919, 985.4284514981293, 986.8125056239435, 993.3989936088562, 1001.5392919257077, 5223.216517522402, 5465.289512363367, 5468.945310546844, 5581.190727344593, 5681.233033540502, 19813.10841012158, 19894.204231830856, 20126.798732012787, 20987.685184722242, 21182.0, 19414.320498782476, 19700.211991520464, 19734.210631574577, 19820.306289280095, 19839.801601984083, 10311.84532232017, 10627.36235825853, 10781.353118812896, 10938.289011214245, 10947.726306842393, 8224.753257402048, 8406.159384061544, 8440.53577053268, 8533.573321334032, 8543.829123417621, 17302.0, 17318.0, 17428.47714568565, 17579.384721534683, 17768.200430980378, 17923.28306867763, 18033.549161271072, 18076.09619519045, 18172.818271817112, 18187.363442279457, 10643.680689579325, 10680.198985075995, 10785.083267922, 10788.136949043901, 10789.244752867224, 11918.582849600223, 12889.935550322189, 13069.0, 13075.738485230433, 13091.0, 11201.495932683212, 11293.153013523854, 11426.657200284004, 11471.713207169887, 11492.0, 10576.576936922436, 10712.089472394622, 10859.2398532102, 10865.78268434611, 10889.183311251847, 8721.781955451164, 8812.86780698303, 8818.867716984121, 8829.16122968317, 8843.557822108998, 7352.632578218037, 7596.202398748273, 7604.0, 7626.8093297668, 7683.654235559525, 7861.095973962839, 7984.682527382916, 8014.639341229542, 8040.959795200986, 8099.595020249082], "value": 6555.3141484435455, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.8024804374608, 506.9834980863371, 516.5480204820782, 518.0, 524.6144084098187, 6402.487800975917, 6533.0, 6539.215294164695, 6568.605883646985, 6625.939849624061, 6189.442950134487, 6314.273858506273, 6333.968330158355, 6377.649229292387, 6425.614463132215, 5038.823641172559, 5092.421349381685, 5108.4636113208135, 5145.614078944075, 5168.32811734474, 4991.725455099968, 5020.322256495363, 5099.260607211952, 5103.336566246401, 5137.2037334213255, 5073.340465739467, 5105.157648987913, 5134.717590532519, 5153.149730294497, 5156.871078223037, 5034.823781167678, 5041.185667687349, 5072.137736584789, 5120.769565369567, 5129.358830146231, 4524.479684836253, 4528.705634133804, 4551.294549344857, 4578.427696537933, 4590.291727880566, 6316.368363163693, 6332.715027823713, 6375.968120159416, 6387.041943708435, 6420.646864422455, 5823.912641310384, 5873.20711703923, 5876.853078673024, 5937.8812423751715, 5952.761889524414, 6664.600123992576, 6724.529282950195, 6743.15710536183, 6785.491088168407, 6823.590584564892, 4901.730404827733, 4943.332650092228, 4996.777078784164, 5015.423226328982, 5026.346574945233, 7096.290370962863, 7120.216776154619, 7152.821179470555, 7299.525530840531, 7323.853522929565, 5078.892259712178, 5096.439391666913, 5101.489851014869, 5119.436861945183, 5138.717370544581, 5163.974180129076, 5209.374875015023, 5210.7915683373, 5211.530962213419, 5257.316548848626, 5853.765849366063, 5887.175795388649, 5889.469947704727, 5921.940780592225, 5926.881462370815, 6725.495587830932, 6730.629815360202, 6801.863962720718, 6818.863622727517, 6835.453163746888, 1672.3979367427717, 1720.1743163281694, 1723.2417736196285, 1769.1066011664027, 1777.8222177782118, 1214.1258294028285, 1221.6396163131897, 1233.3524899427694, 1239.114033466076, 1244.166408506303, 5865.442782935615, 5896.439838215363, 5898.557608179403, 5902.557308201902, 5967.970889897456, 6698.330166983263, 6892.243102758936, 7006.789796306119, 7008.404285635778, 7042.225355210922, 5942.940570594239, 6008.759649614054, 6013.789517366871, 6026.849328766816, 6027.336992930774, 3640.3265395901844, 3704.518412606344, 3718.6984555405393, 3743.9221232216382, 3774.4338349247832, 6675.899861502081, 6705.463562914955, 6715.899261511081, 6731.225909020525, 6756.763513277012, 7128.465365097639, 7139.286071392923, 7178.989882832815, 7202.135743710788, 7231.5536892621685, 6691.063251144844, 6733.528652994243, 6750.594964302158, 6797.558158719767, 6805.149356330359, 6843.460221450134, 6995.166957382268, 7003.439724822104, 7004.649767511502, 7079.39825114871, 6988.938263212466, 7022.08712867324, 7062.117235345582, 7065.364117229268, 7083.264600172991, 6712.429443497357, 6806.727730890907, 6826.658667066726, 6830.316968303328, 6849.013786002203, 5589.357223919139, 5677.3471050829685, 5679.971600141973, 5699.116636921284, 5722.341930678025, 6715.5299129060495, 6758.7296508139125, 7094.0, 7165.498415111101, 7171.677274522764, 6742.494312926452, 6815.965920170367, 6852.862942741018, 6859.485538584527, 6903.413209877217, 6666.300038495888, 6690.364415380531, 6788.796336109925, 6845.0, 6845.897311540231, 6714.764983225564, 6737.932620673927, 6749.730010799513, 6754.8649027020165, 6758.662066896537, 6801.32728326698, 6838.93161068383, 6902.896456553055, 6921.723131074902, 6969.7909062728195, 6430.356964303719, 6568.770093046721, 6577.0, 6600.174978127734, 6624.8343791405605], "value": 5231.9712754876355, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [871.9825603487931, 875.8248350329932, 891.189017993626, 892.9196372326492, 894.9642014319428, 17198.39805606804, 18038.5490362741, 18058.27766889326, 18094.095295235253, 20133.496662583442, 18990.335338263165, 19511.317103901365, 20565.794342056575, 21113.788862111414, 21140.365789026353, 917.064594114005, 933.780561568031, 937.2033771294414, 940.2524992630875, 946.0964778636386, 877.2061284537504, 940.8212439636482, 947.5593848860278, 951.8667386565886, 955.6846240740574, 1008.606643409071, 1010.343276870033, 1012.7265638277661, 1013.4020927652683, 1014.1937159957814, 1000.3547711725932, 1001.6994901529548, 1002.9799404011952, 1004.1113614451227, 1011.7167193185846, 740.0970815604953, 742.5359150530918, 743.156517352803, 743.2418932688681, 743.6950850151412, 18649.7202541962, 18917.14872830726, 19107.331243406414, 19213.9039304804, 19856.503587410287, 20543.2809851656, 20606.38180854562, 21111.838848863306, 21112.0, 21451.356459306247, 16742.497725068268, 17084.48746537606, 17117.31530738769, 17222.397216097375, 17228.91385543077, 3570.785752854837, 3588.982055089708, 3589.4436362363876, 3598.226381328001, 3600.8199590020395, 128051.35974320162, 128264.0, 129446.70553294441, 130777.0, 133709.3314533431, 16042.598935026717, 16334.264958076916, 16347.918260408622, 16351.59121021984, 16541.172941352885, 29069.563956540667, 29364.853175733984, 29624.555631665542, 29654.55518167229, 29667.96162134315, 8740.78148046304, 8867.423617464845, 8914.242289405343, 8915.687950921687, 8941.731748047569, 4269.124829410026, 4310.0, 4393.890152746143, 4393.978030109829, 4411.139827733561, 5115.514026167582, 5230.293910322134, 5234.18870075139, 5244.318238628954, 5248.265242866002, 969.8060387922303, 994.7761753605381, 995.2485873165714, 1001.8647482589757, 1004.8040632076675, 668.2181847238743, 681.9488538359643, 688.721067967474, 691.5228492340269, 700.467644590105, 11809.52761889532, 11845.0, 11976.461059252355, 12032.217905836093, 12943.805842912363, 687.3229868579446, 693.5630552751734, 700.471144286071, 701.4248316380621, 707.702764838769, 6063.878722425527, 6351.904721429182, 6480.0, 6603.141591593062, 6692.698828552727, 107495.0, 108205.0, 110407.44796275967, 111254.44372778085, 115041.0, 83819.5809020951, 99246.0, 99545.0, 102307.4884625587, 103513.0, 86156.56921715477, 90521.54739226394, 90639.54680226557, 92288.0, 93638.0, 12394.876051239733, 12537.185082969578, 12733.363331833556, 12837.405526452609, 12918.0, 37729.434058488594, 38394.42408363821, 38505.229895401375, 39222.0, 40379.0, 49433.0, 50245.0, 51262.231066533284, 51624.22563661623, 51663.48336516587, 18074.548136296697, 18304.542386439913, 18465.630687385907, 18855.622887541896, 18912.054397279804, 20836.0, 20875.26936557213, 21022.159113635284, 21145.365639030853, 22165.889170554045, 17684.557886052953, 18582.0, 18657.160427781055, 19248.23007079701, 19785.604287914448, 18799.24803007919, 18827.0, 18828.52928676794, 18854.717179242598, 19703.507412314233, 17887.0, 17993.280268789626, 18128.0, 18426.907865460587, 19126.0, 14473.85526144725, 14665.56003319902, 14792.778108328168, 14924.55226343168, 15088.094714317176, 13579.59261222165, 14238.003339766117, 14253.0, 14293.098991987561, 14382.712345753236], "value": 9097.209346173086, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1516.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 112.0, 99.0, 107.0, 110.0, 108.0, 106.0, 98.0, 106.0, 111.0], "value": 105.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 119.0, 147.0, 170.0, 125.0, 148.0, 142.0, 116.0, 164.0, 120.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 171.0, 171.0, 169.0, 159.0, 164.0, 163.0, 164.0, 164.0], "value": 164.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 73.0, 169.0, 76.0, 73.0, 72.0, 74.0, 149.0, 75.0], "value": 91.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 138.0, 85.0, 79.0, 82.0, 139.0, 79.0, 79.0, 127.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 117.0, 113.0, 114.0, 113.0, 114.0, 118.0, 120.0, 118.0], "value": 115.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 148.0, 148.0, 148.0, 147.0, 148.0, 153.0, 152.0, 149.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 99.0, 94.0, 94.0, 94.0, 94.0, 93.0, 96.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 57.0, 56.0, 52.0, 52.0, 62.0, 58.0, 55.0, 51.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 55.0, 55.0, 56.0, 55.0, 55.0, 55.0, 59.0, 64.0], "value": 57.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 80.0, 81.0, 75.0, 73.0, 83.0, 76.0, 74.0, 73.0], "value": 76.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 126.0, 119.0, 124.0, 119.0, 121.0, 123.0, 124.0, 127.0, 166.0], "value": 127.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 176.0, 176.0, 174.0, 176.0, 173.0, 172.0, 174.0, 173.0], "value": 174.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 62.0, 68.0, 66.0, 75.0, 65.0, 62.0, 68.0], "value": 67.0, "unit": "score"}]}]} 05:36:02 INFO - Return code: 0 05:36:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:36:02 INFO - # TBPL SUCCESS # 05:36:02 INFO - Running post-action listener: _package_coverage_data 05:36:02 INFO - Running post-action listener: _resource_record_post_action 05:36:02 INFO - [mozharness: 2017-08-24 12:36:02.766969Z] Finished run-tests step (success) 05:36:02 INFO - Running post-run listener: _resource_record_post_run 05:36:02 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 5279744; Write bytes: 440905728; Read time: 2200; Write time: 252540 05:36:02 INFO - TinderboxPrint: CPU usage
13.2% 05:36:02 INFO - TinderboxPrint: I/O read bytes / time
5,279,744 / 2,200 05:36:02 INFO - TinderboxPrint: I/O write bytes / time
440,905,728 / 252,540 05:36:02 INFO - TinderboxPrint: CPU idle
8,258.8 (86.8%) 05:36:02 INFO - TinderboxPrint: CPU user
1,199.4 (12.6%) 05:36:02 INFO - TinderboxPrint: Swap in / out
0 / 0 05:36:02 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 8192; Write bytes: 272896000; Read time: 124; Write time: 191436 05:36:02 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:36:02 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 2899968; Write bytes: 149544960; Read time: 1432; Write time: 37080 05:36:03 INFO - Running post-run listener: _upload_blobber_files 05:36:03 INFO - Blob upload gear active. 05:36:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:36:03 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 05:36:03 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'] 05:36:03 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 05:36:03 INFO - (blobuploader) - INFO - Open directory for files ... 05:36:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:36:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:36:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:36:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:36:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:36:04 INFO - (blobuploader) - INFO - Done attempting. 05:36:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:36:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:36:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:36:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:36:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:36:08 INFO - (blobuploader) - INFO - Done attempting. 05:36:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:36:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:36:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:36:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:36:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:36:10 INFO - (blobuploader) - INFO - Done attempting. 05:36:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:36:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:36:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:36:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:36:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:36:11 INFO - (blobuploader) - INFO - Done attempting. 05:36:11 INFO - (blobuploader) - INFO - Iteration through files over. 05:36:11 INFO - Return code: 0 05:36:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:36:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:36:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4712bc510ad6144a321148181d969f9fda61930592daf0f2d20340aa30af026b6e43cd6d6a3521bc6e205535d7a0a23c07dd877b63418d7a70f790f95cd8c58", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00c275bd0d9509c640e0ba692ea01197e3d991635d3a1f2c58ab71dd9af463ec184f06192c659ab0284d0cdd9ff7786026ceb0882f3a9b6665c21365840e504c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e6e602db063aa1480c1f3ac00f39f4d1dde21c1c8d5d994dbbdcc324d5bc9bfab810ffbb00885e085e671cb95bc11d561db45aa2e2f434dcadd5ccdde33482f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee810992ec728090f7abe3849678ba7abb2e980f1f4aa0b9d7bce1f0b7fb43384b727fc2433590e6498554d249b04294b709f557e5b7957873ed0ee04ae75aab"} 05:36:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:36:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:36:11 INFO - Contents: 05:36:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4712bc510ad6144a321148181d969f9fda61930592daf0f2d20340aa30af026b6e43cd6d6a3521bc6e205535d7a0a23c07dd877b63418d7a70f790f95cd8c58", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00c275bd0d9509c640e0ba692ea01197e3d991635d3a1f2c58ab71dd9af463ec184f06192c659ab0284d0cdd9ff7786026ceb0882f3a9b6665c21365840e504c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e6e602db063aa1480c1f3ac00f39f4d1dde21c1c8d5d994dbbdcc324d5bc9bfab810ffbb00885e085e671cb95bc11d561db45aa2e2f434dcadd5ccdde33482f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee810992ec728090f7abe3849678ba7abb2e980f1f4aa0b9d7bce1f0b7fb43384b727fc2433590e6498554d249b04294b709f557e5b7957873ed0ee04ae75aab"} 05:36:11 INFO - Running post-run listener: copy_logs_to_upload_dir 05:36:11 INFO - Copying logs to upload dir... 05:36:11 INFO - mkdir: /builds/slave/test/build/upload/logs 05:36:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.814338 ========= master_lag: -0.38 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-24 05:36:11.777818) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 05:36:11.786657) ========= 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=ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4712bc510ad6144a321148181d969f9fda61930592daf0f2d20340aa30af026b6e43cd6d6a3521bc6e205535d7a0a23c07dd877b63418d7a70f790f95cd8c58", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00c275bd0d9509c640e0ba692ea01197e3d991635d3a1f2c58ab71dd9af463ec184f06192c659ab0284d0cdd9ff7786026ceb0882f3a9b6665c21365840e504c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e6e602db063aa1480c1f3ac00f39f4d1dde21c1c8d5d994dbbdcc324d5bc9bfab810ffbb00885e085e671cb95bc11d561db45aa2e2f434dcadd5ccdde33482f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee810992ec728090f7abe3849678ba7abb2e980f1f4aa0b9d7bce1f0b7fb43384b727fc2433590e6498554d249b04294b709f557e5b7957873ed0ee04ae75aab"} build_url:https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011391 build_url: 'https://queue.taskcluster.net/v1/task/MFQIylfTTO67nd9VpmjjPg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4712bc510ad6144a321148181d969f9fda61930592daf0f2d20340aa30af026b6e43cd6d6a3521bc6e205535d7a0a23c07dd877b63418d7a70f790f95cd8c58", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00c275bd0d9509c640e0ba692ea01197e3d991635d3a1f2c58ab71dd9af463ec184f06192c659ab0284d0cdd9ff7786026ceb0882f3a9b6665c21365840e504c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e6e602db063aa1480c1f3ac00f39f4d1dde21c1c8d5d994dbbdcc324d5bc9bfab810ffbb00885e085e671cb95bc11d561db45aa2e2f434dcadd5ccdde33482f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee810992ec728090f7abe3849678ba7abb2e980f1f4aa0b9d7bce1f0b7fb43384b727fc2433590e6498554d249b04294b709f557e5b7957873ed0ee04ae75aab"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 05:36:11.813971) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:36:11.818516) ========= 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=ed14279e38b05397cf4aa391000001a4-1503576861.159028-308411074 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004384 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:36:11.868050) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 05:36:11.868357) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 05:36:11.868694) ========= ========= Total master_lag: -0.30 =========