builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-029 starttime: 1507028763.58 results: success (0) revision: 14841c4d8a9783ad55a0c560ec9312ba16200ac1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 04:06:03.583826) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 04:06:03.584268) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 04:06:03.584534) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 04:06:03.602627) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 04:06:03.602989) ========= 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=ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-03 04:06:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 198K=0.06s 2017-10-03 04:06:03 (198 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.187502 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 04:06:03.808244) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 04:06:03.808538) ========= 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=ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022612 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 04:06:03.866890) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-03 04:06:03.867176) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 14841c4d8a9783ad55a0c560ec9312ba16200ac1 --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 14841c4d8a9783ad55a0c560ec9312ba16200ac1 --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=ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-03 04:06:03,907 truncating revision to first 12 chars 2017-10-03 04:06:03,907 Setting DEBUG logging. 2017-10-03 04:06:03,907 attempt 1/10 2017-10-03 04:06:03,907 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/14841c4d8a97?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-03 04:06:04,783 unpacking tar archive at: mozilla-central-14841c4d8a97/testing/mozharness/ program finished with exit code 0 elapsedTime=1.176497 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-03 04:06:05.059752) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 04:06:05.060026) ========= script_repo_revision: 14841c4d8a9783ad55a0c560ec9312ba16200ac1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 04:06:05.060368) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 04:06:05.060651) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 04:06:05.069643) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-10-03 04:06:05.069968) ========= /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=ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:06:05 INFO - MultiFileLogger online at 20171003 04:06:05 in /builds/slave/test 04:06:05 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 04:06:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:06:05 INFO - {'append_to_log': False, 04:06:05 INFO - 'base_work_dir': '/builds/slave/test', 04:06:05 INFO - 'blob_upload_branch': 'Firefox', 04:06:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:06:05 INFO - 'branch': 'Firefox', 04:06:05 INFO - 'buildbot_json_path': 'buildprops.json', 04:06:05 INFO - 'code_coverage': False, 04:06:05 INFO - 'config_files': ('talos/linux_config.py',), 04:06:05 INFO - 'default_actions': ('clobber', 04:06:05 INFO - 'read-buildbot-config', 04:06:05 INFO - 'download-and-extract', 04:06:05 INFO - 'populate-webroot', 04:06:05 INFO - 'create-virtualenv', 04:06:05 INFO - 'install', 04:06:05 INFO - 'setup-mitmproxy', 04:06:05 INFO - 'run-tests'), 04:06:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:06:05 INFO - 'disable_ccov_upload': False, 04:06:05 INFO - 'disable_stylo': False, 04:06:05 INFO - 'download_minidump_stackwalk': True, 04:06:05 INFO - 'download_symbols': 'ondemand', 04:06:05 INFO - 'enable_stylo': False, 04:06:05 INFO - 'enable_webrender': False, 04:06:05 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:06:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:06:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:06:05 INFO - 'gecko_profile': False, 04:06:05 INFO - 'gecko_profile_interval': 0, 04:06:05 INFO - 'installer_path': 'installer.exe', 04:06:05 INFO - 'log_level': 'info', 04:06:05 INFO - 'log_name': 'talos', 04:06:05 INFO - 'log_to_console': True, 04:06:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:06:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:06:05 INFO - 'opt_config_files': (), 04:06:05 INFO - 'pip_index': False, 04:06:05 INFO - 'suite': 'dromaeojs-e10s', 04:06:05 INFO - 'system_bits': '32', 04:06:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:06:05 INFO - 'title': 'talos-linux64-ix-029', 04:06:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:06:05 INFO - 'use_talos_json': True, 04:06:05 INFO - 'verify': 'False', 04:06:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:06:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:06:05 INFO - 'work_dir': 'build'} 04:06:05 INFO - [mozharness: 2017-10-03 11:06:05.254851Z] Running clobber step. 04:06:05 INFO - Running pre-action listener: _resource_record_pre_action 04:06:05 INFO - Running main action method: clobber 04:06:05 INFO - rmtree: /builds/slave/test/build 04:06:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:06:06 INFO - Running post-action listener: _resource_record_post_action 04:06:06 INFO - [mozharness: 2017-10-03 11:06:06.752335Z] Finished clobber step (success) 04:06:06 INFO - [mozharness: 2017-10-03 11:06:06.752432Z] Running read-buildbot-config step. 04:06:06 INFO - Running pre-action listener: _resource_record_pre_action 04:06:06 INFO - Running main action method: read_buildbot_config 04:06:06 INFO - Using buildbot properties: 04:06:06 INFO - { 04:06:06 INFO - "project": "", 04:06:06 INFO - "product": "firefox", 04:06:06 INFO - "who": "archaeopteryx@coole-files.de", 04:06:06 INFO - "installer_path": "public/build/target.tar.bz2", 04:06:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:06:06 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:06:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 04:06:06 INFO - "stage_platform": "linux64-pgo", 04:06:06 INFO - "basedir": "/builds/slave/test", 04:06:06 INFO - "buildnumber": 11, 04:06:06 INFO - "platform": "ubuntu64_hw", 04:06:06 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:06:06 INFO - "slavebuilddir": "test", 04:06:06 INFO - "taskId": "AslzN8_uSY6hB72uu6xtpA", 04:06:06 INFO - "branch": "mozilla-central", 04:06:06 INFO - "script_repo_revision": "production", 04:06:06 INFO - "revision": "14841c4d8a9783ad55a0c560ec9312ba16200ac1", 04:06:06 INFO - "slavename": "talos-linux64-ix-029", 04:06:06 INFO - "repo_path": "mozilla-central" 04:06:06 INFO - } 04:06:06 INFO - Finding installer, test and symbols from parent task. 04:06:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AslzN8_uSY6hB72uu6xtpA'}, attempt #1 04:06:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AslzN8_uSY6hB72uu6xtpA'}, attempt #1 04:06:07 INFO - Task dependencies: LJwLOi3zTVSndmo25vI-Gw 04:06:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw'}, attempt #1 04:06:08 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.tar.bz2 04:06:08 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.test_packages.json 04:06:08 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.crashreporter-symbols.zip 04:06:08 INFO - Running post-action listener: _resource_record_post_action 04:06:08 INFO - [mozharness: 2017-10-03 11:06:08.171943Z] Finished read-buildbot-config step (success) 04:06:08 INFO - [mozharness: 2017-10-03 11:06:08.172160Z] Running download-and-extract step. 04:06:08 INFO - Running pre-action listener: _resource_record_pre_action 04:06:08 INFO - Running main action method: download_and_extract 04:06:08 INFO - mkdir: /builds/slave/test/build/tests 04:06: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')]} 04:06:08 INFO - trying https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.test_packages.json 04:06:08 INFO - Downloading https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:06:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:06:08 INFO - Downloaded 989 bytes. 04:06:08 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:06:08 INFO - Using the following test package requirements: 04:06:08 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:06:08 INFO - u'common': [u'target.common.tests.zip'], 04:06:08 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:06:08 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:06:08 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:06:08 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:06:08 INFO - u'mozbase': [u'target.common.tests.zip'], 04:06:08 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:06:08 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:06:08 INFO - u'web-platform': [u'target.common.tests.zip', 04:06:08 INFO - u'target.web-platform.tests.tar.gz'], 04:06:08 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:06:08 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 04:06:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.common.tests.zip 04:06:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:06:08 INFO - Fetch https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.common.tests.zip into memory 04:06:10 INFO - Content-Length response header: 55898757 04:06:10 INFO - Bytes received: 55898757 04:06:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.talos.tests.zip 04:06:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:06:21 INFO - Fetch https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.talos.tests.zip into memory 04:06:22 INFO - Content-Length response header: 14522427 04:06:22 INFO - Bytes received: 14522427 04:06:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:06:22 INFO - trying https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.tar.bz2 04:06:22 INFO - Downloading https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:06:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:06:25 INFO - Downloaded 62357761 bytes. 04:06:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.tar.bz2 04:06:25 INFO - mkdir: /builds/slave/test/properties 04:06:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:06:25 INFO - Writing to file /builds/slave/test/properties/build_url 04:06:25 INFO - Contents: 04:06:25 INFO - build_url:https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.tar.bz2 04:06:25 INFO - Running post-action listener: _resource_record_post_action 04:06:25 INFO - Running post-action listener: find_tests_for_verification 04:06:25 INFO - Running post-action listener: set_extra_try_arguments 04:06:25 INFO - [mozharness: 2017-10-03 11:06:25.081424Z] Finished download-and-extract step (success) 04:06:25 INFO - [mozharness: 2017-10-03 11:06:25.081533Z] Running populate-webroot step. 04:06:25 INFO - Running pre-action listener: _resource_record_pre_action 04:06:25 INFO - Running main action method: populate_webroot 04:06:25 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:06:25 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:06:25 INFO - 'tests': ['tresize', 04:06:25 INFO - 'tcanvasmark']}, 04:06:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:06:25 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:06:25 INFO - 'tests': ['dromaeo_css', 04:06:25 INFO - 'kraken']}, 04:06:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:06:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:06:25 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:06:25 INFO - 'talos_options': ['--disable-stylo'], 04:06:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:06:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:06:25 INFO - 'tests': ['damp', 'tps']}, 04:06:25 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:06:25 INFO - 'talos_options': ['--disable-stylo'], 04:06:25 INFO - 'tests': ['damp', 'tps']}, 04:06:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:06:25 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:06:25 INFO - 'tests': ['dromaeo_dom']}, 04:06:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:06:25 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:06:25 INFO - 'tests': ['basic_compositor_video', 04:06:25 INFO - 'glvideo']}, 04:06:25 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:06:25 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:06:25 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:06:25 INFO - 'talos_options': ['--disable-stylo'], 04:06:25 INFO - 'tests': ['ts_paint_webext', 04:06:25 INFO - 'tp5o_webext']}, 04:06:25 INFO - 'other-e10s': {'tests': ['a11yr', 04:06:25 INFO - 'ts_paint', 04:06:25 INFO - 'tpaint', 04:06:25 INFO - 'sessionrestore', 04:06:25 INFO - 'sessionrestore_many_windows', 04:06:25 INFO - 'sessionrestore_no_auto_restore', 04:06:25 INFO - 'tabpaint', 04:06:25 INFO - 'cpstartup']}, 04:06:25 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:06:25 INFO - 'tests': ['a11yr', 04:06:25 INFO - 'ts_paint', 04:06:25 INFO - 'tpaint', 04:06:25 INFO - 'sessionrestore', 04:06:25 INFO - 'sessionrestore_many_windows', 04:06:25 INFO - 'sessionrestore_no_auto_restore', 04:06:25 INFO - 'tabpaint', 04:06:25 INFO - 'cpstartup']}, 04:06:25 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 04:06:25 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 04:06:25 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:06:25 INFO - 'tests': ['perf_reftest_singletons']}, 04:06:25 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:06:25 INFO - 'tests': ['perf_reftest']}, 04:06:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:06:25 INFO - 'tsvgr_opacity', 04:06:25 INFO - 'tart', 04:06:25 INFO - 'tscrollx', 04:06:25 INFO - 'tsvg_static']}, 04:06:25 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:06:25 INFO - 'tests': ['tsvgx', 04:06:25 INFO - 'tsvgr_opacity', 04:06:25 INFO - 'tart', 04:06:25 INFO - 'tscrollx', 04:06:25 INFO - 'tsvg_static']}, 04:06:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:06:25 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:06:25 INFO - 'talos_options': ['--disable-stylo'], 04:06:25 INFO - 'tests': ['tp5o']}, 04:06:25 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:06:25 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:06:25 INFO - 'talos_options': ['--mitmproxy', 04:06:25 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:06:25 INFO - '--firstNonBlankPaint'], 04:06:25 INFO - 'tests': ['tp6_google', 04:06:25 INFO - 'tp6_youtube', 04:06:25 INFO - 'tp6_amazon', 04:06:25 INFO - 'tp6_facebook']}, 04:06:25 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:06:25 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:06:25 INFO - 'talos_options': ['--disable-stylo', 04:06:25 INFO - '--mitmproxy', 04:06:25 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:06:25 INFO - '--firstNonBlankPaint'], 04:06:25 INFO - 'tests': ['tp6_google', 04:06:25 INFO - 'tp6_youtube', 04:06:25 INFO - 'tp6_amazon', 04:06:25 INFO - 'tp6_facebook']}, 04:06:25 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:06:25 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:06:25 INFO - 'talos_options': ['--stylo-threads=1', 04:06:25 INFO - '--mitmproxy', 04:06:25 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:06:25 INFO - '--firstNonBlankPaint'], 04:06:25 INFO - 'tests': ['tp6_google', 04:06:25 INFO - 'tp6_youtube', 04:06:25 INFO - 'tp6_amazon', 04:06:25 INFO - 'tp6_facebook']}, 04:06:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:06:25 INFO - 'talos_options': ['--xperf_path', 04:06:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:06:25 INFO - 'tests': ['tp5n']}, 04:06:25 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:06:25 INFO - 'talos_options': ['--disable-stylo', 04:06:25 INFO - '--xperf_path', 04:06:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:06:25 INFO - 'tests': ['tp5n']}}} 04:06:25 INFO - Running post-action listener: _resource_record_post_action 04:06:25 INFO - [mozharness: 2017-10-03 11:06:25.097074Z] Finished populate-webroot step (success) 04:06:25 INFO - [mozharness: 2017-10-03 11:06:25.097163Z] Running create-virtualenv step. 04:06:25 INFO - Running pre-action listener: _resource_record_pre_action 04:06:25 INFO - Running main action method: create_virtualenv 04:06:25 INFO - Creating virtualenv /builds/slave/test/build/venv 04:06:25 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:06:25 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:06:25 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:06:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:06:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:06:25 INFO - Installing distribute.............................................................................................................................................................................................done. 04:06:25 INFO - Installing pip...............done. 04:06:25 INFO - Return code: 0 04:06:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:06:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:06:26 INFO - Reading from file tmpfile_stdout 04:06:26 INFO - Output received: 04:06:26 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 04:06:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:06: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')]} 04:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06: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')]} 04:06:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:26 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 0x23311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269bd60>, '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 0x269ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269cef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', '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 04:06:26 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 04:06:26 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 04:06:26 INFO - Using env: {'DISPLAY': ':0', 04:06:26 INFO - 'HOME': '/home/cltbld', 04:06:26 INFO - 'LANG': 'en_US.UTF-8', 04:06:26 INFO - 'LANGUAGE': 'en_US:en', 04:06:26 INFO - 'LOGNAME': 'cltbld', 04:06:26 INFO - 'MAIL': '/var/mail/cltbld', 04:06:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:26 INFO - 'MOZ_NO_REMOTE': '1', 04:06:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:26 INFO - 'NO_EM_RESTART': '1', 04:06:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:26 INFO - 'PWD': '/builds/slave/test', 04:06:26 INFO - 'SHELL': '/bin/bash', 04:06:26 INFO - 'SHLVL': '1', 04:06:26 INFO - 'TERM': 'linux', 04:06:26 INFO - 'TMOUT': '86400', 04:06:26 INFO - 'USER': 'cltbld', 04:06:26 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', 04:06:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:26 INFO - '_': '/tools/buildbot/bin/python'} 04:06:27 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:06:27 INFO - Downloading/unpacking pip>=1.5 04:06:27 INFO - Running setup.py egg_info for package pip 04:06:27 INFO - warning: no files found matching 'pip/cacert.pem' 04:06:27 INFO - warning: no files found matching '*.html' under directory 'docs' 04:06:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:06:27 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:06:27 INFO - Installing collected packages: pip 04:06:27 INFO - Found existing installation: pip 1.1 04:06:27 INFO - Uninstalling pip: 04:06:27 INFO - Successfully uninstalled pip 04:06:27 INFO - Running setup.py install for pip 04:06:27 INFO - warning: no files found matching 'pip/cacert.pem' 04:06:27 INFO - warning: no files found matching '*.html' under directory 'docs' 04:06:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:06:27 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:06:27 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:06:27 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:06:27 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:06:27 INFO - Successfully installed pip 04:06:27 INFO - Cleaning up... 04:06:27 INFO - Return code: 0 04:06:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:06:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269bd60>, '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 0x269ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269cef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', '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 04:06:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:06:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:06:27 INFO - Using env: {'DISPLAY': ':0', 04:06:27 INFO - 'HOME': '/home/cltbld', 04:06:27 INFO - 'LANG': 'en_US.UTF-8', 04:06:27 INFO - 'LANGUAGE': 'en_US:en', 04:06:27 INFO - 'LOGNAME': 'cltbld', 04:06:27 INFO - 'MAIL': '/var/mail/cltbld', 04:06:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:27 INFO - 'MOZ_NO_REMOTE': '1', 04:06:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:27 INFO - 'NO_EM_RESTART': '1', 04:06:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:27 INFO - 'PWD': '/builds/slave/test', 04:06:27 INFO - 'SHELL': '/bin/bash', 04:06:27 INFO - 'SHLVL': '1', 04:06:27 INFO - 'TERM': 'linux', 04:06:27 INFO - 'TMOUT': '86400', 04:06:27 INFO - 'USER': 'cltbld', 04:06:27 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', 04:06:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:27 INFO - '_': '/tools/buildbot/bin/python'} 04:06:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:06:27 INFO - Downloading/unpacking psutil>=3.1.1 04:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:06:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:06:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:06:28 INFO - Installing collected packages: psutil 04:06:28 INFO - Running setup.py install for psutil 04:06:30 INFO - building 'psutil._psutil_linux' extension 04:06:30 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 04:06:30 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 04:06:30 INFO - building 'psutil._psutil_posix' extension 04:06:30 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 04:06:30 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 04:06:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:06:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:06:31 INFO - Successfully installed psutil 04:06:31 INFO - Cleaning up... 04:06:32 INFO - Return code: 0 04:06:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:06:32 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')]} 04:06:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06:32 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')]} 04:06:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:32 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 0x23311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269bd60>, '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 0x269ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269cef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', '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 04:06:32 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 04:06:32 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 04:06:32 INFO - Using env: {'DISPLAY': ':0', 04:06:32 INFO - 'HOME': '/home/cltbld', 04:06:32 INFO - 'LANG': 'en_US.UTF-8', 04:06:32 INFO - 'LANGUAGE': 'en_US:en', 04:06:32 INFO - 'LOGNAME': 'cltbld', 04:06:32 INFO - 'MAIL': '/var/mail/cltbld', 04:06:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:32 INFO - 'MOZ_NO_REMOTE': '1', 04:06:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:32 INFO - 'NO_EM_RESTART': '1', 04:06:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:32 INFO - 'PWD': '/builds/slave/test', 04:06:32 INFO - 'SHELL': '/bin/bash', 04:06:32 INFO - 'SHLVL': '1', 04:06:32 INFO - 'TERM': 'linux', 04:06:32 INFO - 'TMOUT': '86400', 04:06:32 INFO - 'USER': 'cltbld', 04:06:32 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', 04:06:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:32 INFO - '_': '/tools/buildbot/bin/python'} 04:06:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:06:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:06:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:06:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:06:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:06:35 INFO - Installing collected packages: mozsystemmonitor 04:06:35 INFO - Running setup.py install for mozsystemmonitor 04:06:36 INFO - Successfully installed mozsystemmonitor 04:06:36 INFO - Cleaning up... 04:06:37 INFO - Return code: 0 04:06:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:06:37 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')]} 04:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06:37 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')]} 04:06:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:37 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 0x23311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269bd60>, '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 0x269ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269cef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', '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 04:06:37 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 04:06:37 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 04:06:37 INFO - Using env: {'DISPLAY': ':0', 04:06:37 INFO - 'HOME': '/home/cltbld', 04:06:37 INFO - 'LANG': 'en_US.UTF-8', 04:06:37 INFO - 'LANGUAGE': 'en_US:en', 04:06:37 INFO - 'LOGNAME': 'cltbld', 04:06:37 INFO - 'MAIL': '/var/mail/cltbld', 04:06:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:37 INFO - 'MOZ_NO_REMOTE': '1', 04:06:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:37 INFO - 'NO_EM_RESTART': '1', 04:06:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:37 INFO - 'PWD': '/builds/slave/test', 04:06:37 INFO - 'SHELL': '/bin/bash', 04:06:37 INFO - 'SHLVL': '1', 04:06:37 INFO - 'TERM': 'linux', 04:06:37 INFO - 'TMOUT': '86400', 04:06:37 INFO - 'USER': 'cltbld', 04:06:37 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', 04:06:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:37 INFO - '_': '/tools/buildbot/bin/python'} 04:06:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:06:37 INFO - Downloading/unpacking jsonschema==2.5.1 04:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:06:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:38 INFO - Downloading functools32-3.2.3-2.tar.gz 04:06:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:06:38 INFO - warning: no files found matching '*.txt' 04:06:38 INFO - no previously-included directories found matching 'build' 04:06:38 INFO - no previously-included directories found matching 'dist' 04:06:38 INFO - no previously-included directories found matching '.git*' 04:06:38 INFO - Installing collected packages: jsonschema, functools32 04:06:38 INFO - Running setup.py install for functools32 04:06:38 INFO - warning: no files found matching '*.txt' 04:06:38 INFO - no previously-included directories found matching 'build' 04:06:38 INFO - no previously-included directories found matching 'dist' 04:06:38 INFO - no previously-included directories found matching '.git*' 04:06:38 INFO - Successfully installed jsonschema functools32 04:06:38 INFO - Cleaning up... 04:06:39 INFO - Return code: 0 04:06:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:39 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 0x23311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269bd60>, '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 0x269ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269cef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', '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 04:06:39 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 04:06:39 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 04:06:39 INFO - Using env: {'DISPLAY': ':0', 04:06:39 INFO - 'HOME': '/home/cltbld', 04:06:39 INFO - 'LANG': 'en_US.UTF-8', 04:06:39 INFO - 'LANGUAGE': 'en_US:en', 04:06:39 INFO - 'LOGNAME': 'cltbld', 04:06:39 INFO - 'MAIL': '/var/mail/cltbld', 04:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:39 INFO - 'MOZ_NO_REMOTE': '1', 04:06:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:39 INFO - 'NO_EM_RESTART': '1', 04:06:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:39 INFO - 'PWD': '/builds/slave/test', 04:06:39 INFO - 'SHELL': '/bin/bash', 04:06:39 INFO - 'SHLVL': '1', 04:06:39 INFO - 'TERM': 'linux', 04:06:39 INFO - 'TMOUT': '86400', 04:06:39 INFO - 'USER': 'cltbld', 04:06:39 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', 04:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:39 INFO - '_': '/tools/buildbot/bin/python'} 04:06:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:06:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:06:39 INFO - Cleaning up... 04:06:39 INFO - Return code: 0 04:06:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:39 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 0x23311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269bd60>, '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 0x269ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269cef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', '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 04:06:39 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 04:06:39 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 04:06:39 INFO - Using env: {'DISPLAY': ':0', 04:06:39 INFO - 'HOME': '/home/cltbld', 04:06:39 INFO - 'LANG': 'en_US.UTF-8', 04:06:39 INFO - 'LANGUAGE': 'en_US:en', 04:06:39 INFO - 'LOGNAME': 'cltbld', 04:06:39 INFO - 'MAIL': '/var/mail/cltbld', 04:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:39 INFO - 'MOZ_NO_REMOTE': '1', 04:06:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:39 INFO - 'NO_EM_RESTART': '1', 04:06:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:39 INFO - 'PWD': '/builds/slave/test', 04:06:39 INFO - 'SHELL': '/bin/bash', 04:06:39 INFO - 'SHLVL': '1', 04:06:39 INFO - 'TERM': 'linux', 04:06:39 INFO - 'TMOUT': '86400', 04:06:39 INFO - 'USER': 'cltbld', 04:06:39 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', 04:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:39 INFO - '_': '/tools/buildbot/bin/python'} 04:06:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:06:39 INFO - Downloading/unpacking blobuploader==1.2.4 04:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:40 INFO - Downloading blobuploader-1.2.4.tar.gz 04:06:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:06:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:06:40 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 04:06:40 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 04:06:40 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 04:06:40 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 04:06:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:06:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:06:40 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 04:06:40 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 04:06:40 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 04:06:40 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 04:06:41 INFO - Downloading docopt-0.6.1.tar.gz 04:06:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:06:41 INFO - Installing collected packages: blobuploader, requests, docopt 04:06:41 INFO - Running setup.py install for blobuploader 04:06:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:06:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:06:41 INFO - Running setup.py install for requests 04:06:41 INFO - Running setup.py install for docopt 04:06:41 INFO - Successfully installed blobuploader requests docopt 04:06:41 INFO - Cleaning up... 04:06:41 INFO - Return code: 0 04:06:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:06:41 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')]} 04:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06:41 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')]} 04:06:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:41 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 0x23311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269bd60>, '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 0x269ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269cef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', '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 04:06:41 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 04:06:41 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 04:06:41 INFO - Using env: {'DISPLAY': ':0', 04:06:41 INFO - 'HOME': '/home/cltbld', 04:06:41 INFO - 'LANG': 'en_US.UTF-8', 04:06:41 INFO - 'LANGUAGE': 'en_US:en', 04:06:41 INFO - 'LOGNAME': 'cltbld', 04:06:41 INFO - 'MAIL': '/var/mail/cltbld', 04:06:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:41 INFO - 'MOZ_NO_REMOTE': '1', 04:06:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:41 INFO - 'NO_EM_RESTART': '1', 04:06:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:41 INFO - 'PWD': '/builds/slave/test', 04:06:41 INFO - 'SHELL': '/bin/bash', 04:06:41 INFO - 'SHLVL': '1', 04:06:41 INFO - 'TERM': 'linux', 04:06:41 INFO - 'TMOUT': '86400', 04:06:41 INFO - 'USER': 'cltbld', 04:06:41 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', 04:06:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:41 INFO - '_': '/tools/buildbot/bin/python'} 04:06:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:06:41 INFO - Running setup.py (path:/tmp/pip-lEcwqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:06:42 INFO - Running setup.py (path:/tmp/pip-dPqEfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:06:42 INFO - Running setup.py (path:/tmp/pip-7bI21B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:06:42 INFO - Running setup.py (path:/tmp/pip-FEKqp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:06:42 INFO - Running setup.py (path:/tmp/pip-vmZ2d2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:06:42 INFO - Running setup.py (path:/tmp/pip-zkIsWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:06:42 INFO - Running setup.py (path:/tmp/pip-JI8GsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:06:42 INFO - Running setup.py (path:/tmp/pip-zE5iLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:06:42 INFO - Running setup.py (path:/tmp/pip-jIZoAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:06:42 INFO - Running setup.py (path:/tmp/pip-ow2gK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:06:42 INFO - Running setup.py (path:/tmp/pip-E0ZOCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:06:42 INFO - Running setup.py (path:/tmp/pip-SNLDoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:06:42 INFO - Running setup.py (path:/tmp/pip-340AON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:06:42 INFO - Running setup.py (path:/tmp/pip-OBRYEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:06:42 INFO - Running setup.py (path:/tmp/pip-_Rw4Ix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:06:43 INFO - Running setup.py (path:/tmp/pip-1ARQw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:06:43 INFO - Running setup.py (path:/tmp/pip-KH1S7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:06:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:06:43 INFO - Running setup.py install for manifestparser 04:06:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:06:43 INFO - Running setup.py install for mozcrash 04:06:43 INFO - Running setup.py install for mozdebug 04:06:43 INFO - Running setup.py install for mozdevice 04:06:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:06:43 INFO - Running setup.py install for mozfile 04:06:43 INFO - Running setup.py install for mozhttpd 04:06:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:06:43 INFO - Running setup.py install for mozinfo 04:06:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:06:43 INFO - Running setup.py install for mozInstall 04:06:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:06:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:06:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:06:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:06:43 INFO - Running setup.py install for mozleak 04:06:44 INFO - Running setup.py install for mozlog 04:06:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:06:44 INFO - Running setup.py install for moznetwork 04:06:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:06:44 INFO - Running setup.py install for mozprocess 04:06:44 INFO - Running setup.py install for mozprofile 04:06:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:06:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:06:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:06:44 INFO - Running setup.py install for mozrunner 04:06:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:06:44 INFO - Running setup.py install for mozscreenshot 04:06:44 INFO - Running setup.py install for moztest 04:06:44 INFO - Running setup.py install for mozversion 04:06:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:06:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:06:44 INFO - Cleaning up... 04:06:44 INFO - Return code: 0 04:06:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:06:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:06:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:44 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 0x23311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269bd60>, '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 0x269ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269cef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', '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 04:06:44 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 04:06:44 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 04:06:44 INFO - Using env: {'DISPLAY': ':0', 04:06:44 INFO - 'HOME': '/home/cltbld', 04:06:44 INFO - 'LANG': 'en_US.UTF-8', 04:06:44 INFO - 'LANGUAGE': 'en_US:en', 04:06:44 INFO - 'LOGNAME': 'cltbld', 04:06:44 INFO - 'MAIL': '/var/mail/cltbld', 04:06:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:44 INFO - 'MOZ_NO_REMOTE': '1', 04:06:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:44 INFO - 'NO_EM_RESTART': '1', 04:06:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:44 INFO - 'PWD': '/builds/slave/test', 04:06:44 INFO - 'SHELL': '/bin/bash', 04:06:44 INFO - 'SHLVL': '1', 04:06:44 INFO - 'TERM': 'linux', 04:06:44 INFO - 'TMOUT': '86400', 04:06:44 INFO - 'USER': 'cltbld', 04:06:44 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', 04:06:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:44 INFO - '_': '/tools/buildbot/bin/python'} 04:06:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:06:45 INFO - Running setup.py (path:/tmp/pip-04XvTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:06:45 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)) 04:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:06:45 INFO - Running setup.py (path:/tmp/pip-OZRoc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:06:45 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)) 04:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:06:45 INFO - Running setup.py (path:/tmp/pip-KYtlat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:06:45 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)) 04:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:06:45 INFO - Running setup.py (path:/tmp/pip-zj1tnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:06:45 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)) 04:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:06:45 INFO - Running setup.py (path:/tmp/pip-EXcPMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:06:45 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)) 04:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:06:45 INFO - Running setup.py (path:/tmp/pip-eDUth1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:06:45 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)) 04:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:06:45 INFO - Running setup.py (path:/tmp/pip-2VU2Cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:06:45 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)) 04:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:06:45 INFO - Running setup.py (path:/tmp/pip-AcNHWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:06:45 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)) 04:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:06:45 INFO - Running setup.py (path:/tmp/pip-wTvqZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:06:45 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)) 04:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:06:45 INFO - Running setup.py (path:/tmp/pip-zsikjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:06:45 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)) 04:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:06:45 INFO - Running setup.py (path:/tmp/pip-ZUCrTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:06:45 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)) 04:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:06:45 INFO - Running setup.py (path:/tmp/pip-Fwp1Sh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:06:45 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)) 04:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:06:45 INFO - Running setup.py (path:/tmp/pip-QfollO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:06:46 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)) 04:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:06:46 INFO - Running setup.py (path:/tmp/pip-ashYhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:06:46 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)) 04:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:06:46 INFO - Running setup.py (path:/tmp/pip-0Z1Mit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:06:46 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)) 04:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:06:46 INFO - Running setup.py (path:/tmp/pip-pzQKth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:06:46 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)) 04:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:06:46 INFO - Running setup.py (path:/tmp/pip-oHAH7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:06:46 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)) 04:06:46 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)) 04:06:46 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)) 04:06:46 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)) 04:06:46 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)) 04:06:46 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)) 04:06:46 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)) 04:06:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:06:46 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 04:06:46 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 04:06:46 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 04:06:46 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 04:06:47 INFO - Downloading blessings-1.6.tar.gz 04:06:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:06:47 INFO - Installing collected packages: blessings 04:06:47 INFO - Running setup.py install for blessings 04:06:47 INFO - Successfully installed blessings 04:06:47 INFO - Cleaning up... 04:06:47 INFO - Return code: 0 04:06:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:06:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:06:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:06:47 INFO - Reading from file tmpfile_stdout 04:06:47 INFO - Current package versions: 04:06:47 INFO - argparse == 1.2.1 04:06:47 INFO - blessings == 1.6 04:06:47 INFO - blobuploader == 1.2.4 04:06:47 INFO - docopt == 0.6.1 04:06:47 INFO - functools32 == 3.2.3-2 04:06:47 INFO - jsonschema == 2.5.1 04:06:47 INFO - manifestparser == 1.1 04:06:47 INFO - mozInstall == 1.13 04:06:47 INFO - mozcrash == 1.0 04:06:47 INFO - mozdebug == 0.1 04:06:47 INFO - mozdevice == 0.51 04:06:47 INFO - mozfile == 1.2 04:06:47 INFO - mozhttpd == 0.7 04:06:47 INFO - mozinfo == 0.10 04:06:47 INFO - mozleak == 0.1 04:06:47 INFO - mozlog == 3.5 04:06:47 INFO - moznetwork == 0.27 04:06:47 INFO - mozprocess == 0.25 04:06:47 INFO - mozprofile == 0.28 04:06:47 INFO - mozrunner == 6.13 04:06:47 INFO - mozscreenshot == 0.1 04:06:47 INFO - mozsystemmonitor == 0.3 04:06:47 INFO - moztest == 0.8 04:06:47 INFO - mozversion == 1.4 04:06:47 INFO - psutil == 3.1.1 04:06:47 INFO - requests == 1.2.3 04:06:47 INFO - wsgiref == 0.1.2 04:06:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:06: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')]} 04:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06: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')]} 04:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:47 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 0x23311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269bd60>, '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 0x269ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269cef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', '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 04:06:47 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 04:06:47 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 04:06:47 INFO - Using env: {'DISPLAY': ':0', 04:06:47 INFO - 'HOME': '/home/cltbld', 04:06:47 INFO - 'LANG': 'en_US.UTF-8', 04:06:47 INFO - 'LANGUAGE': 'en_US:en', 04:06:47 INFO - 'LOGNAME': 'cltbld', 04:06:47 INFO - 'MAIL': '/var/mail/cltbld', 04:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:47 INFO - 'MOZ_NO_REMOTE': '1', 04:06:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:47 INFO - 'NO_EM_RESTART': '1', 04:06:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:47 INFO - 'PWD': '/builds/slave/test', 04:06:47 INFO - 'SHELL': '/bin/bash', 04:06:47 INFO - 'SHLVL': '1', 04:06:47 INFO - 'TERM': 'linux', 04:06:47 INFO - 'TMOUT': '86400', 04:06:47 INFO - 'USER': 'cltbld', 04:06:47 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', 04:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:47 INFO - '_': '/tools/buildbot/bin/python'} 04:06:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:06:48 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)) 04:06:48 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)) 04:06:48 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)) 04:06:48 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)) 04:06:48 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)) 04:06:48 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)) 04:06:48 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)) 04:06:48 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)) 04:06:48 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)) 04:06:48 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:06:48 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 04:06:48 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 04:06:48 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 04:06:48 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 04:06:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:06:49 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)) 04:06:49 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)) 04:06:49 INFO - Installing collected packages: simplejson 04:06:49 INFO - Running setup.py install for simplejson 04:06:49 INFO - building 'simplejson._speedups' extension 04:06:49 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 04:06:50 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 04:06:50 INFO - Successfully installed simplejson 04:06:50 INFO - Cleaning up... 04:06:50 INFO - Return code: 0 04:06:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:06:50 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')]} 04:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06:50 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')]} 04:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:50 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 0x23311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x269e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269bd60>, '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 0x269ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269cef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', '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 04:06:50 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 04:06:50 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 04:06:50 INFO - Using env: {'DISPLAY': ':0', 04:06:50 INFO - 'HOME': '/home/cltbld', 04:06:50 INFO - 'LANG': 'en_US.UTF-8', 04:06:50 INFO - 'LANGUAGE': 'en_US:en', 04:06:50 INFO - 'LOGNAME': 'cltbld', 04:06:50 INFO - 'MAIL': '/var/mail/cltbld', 04:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:50 INFO - 'MOZ_NO_REMOTE': '1', 04:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:50 INFO - 'NO_EM_RESTART': '1', 04:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:50 INFO - 'PWD': '/builds/slave/test', 04:06:50 INFO - 'SHELL': '/bin/bash', 04:06:50 INFO - 'SHLVL': '1', 04:06:50 INFO - 'TERM': 'linux', 04:06:50 INFO - 'TMOUT': '86400', 04:06:50 INFO - 'USER': 'cltbld', 04:06:50 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', 04:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:50 INFO - '_': '/tools/buildbot/bin/python'} 04:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:06:50 INFO - Cleaning up... 04:06:50 INFO - Return code: 0 04:06:50 INFO - Running post-action listener: _resource_record_post_action 04:06:50 INFO - Running post-action listener: _start_resource_monitoring 04:06:50 INFO - Starting resource monitoring. 04:06:50 INFO - [mozharness: 2017-10-03 11:06:50.674676Z] Finished create-virtualenv step (success) 04:06:50 INFO - [mozharness: 2017-10-03 11:06:50.675198Z] Running install step. 04:06:50 INFO - Running pre-action listener: _resource_record_pre_action 04:06:50 INFO - Running main action method: install 04:06:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:06:50 INFO - Reading from file tmpfile_stdout 04:06:50 INFO - Detecting whether we're running mozinstall >=1.0... 04:06:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:06:51 INFO - Reading from file tmpfile_stdout 04:06:51 INFO - Output received: 04:06:51 INFO - Usage: mozinstall [options] installer 04:06:51 INFO - Options: 04:06:51 INFO - -h, --help show this help message and exit 04:06:51 INFO - -d DEST, --destination=DEST 04:06:51 INFO - Directory to install application into. [default: 04:06:51 INFO - "/builds/slave/test"] 04:06:51 INFO - --app=APP Application being installed. [default: firefox] 04:06:51 INFO - mkdir: /builds/slave/test/build/application 04:06:51 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'] 04:06:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:07:09 INFO - Reading from file tmpfile_stdout 04:07:09 INFO - Output received: 04:07:09 INFO - /builds/slave/test/build/application/firefox/firefox 04:07:09 INFO - Running post-action listener: _resource_record_post_action 04:07:09 INFO - [mozharness: 2017-10-03 11:07:09.469027Z] Finished install step (success) 04:07:09 INFO - [mozharness: 2017-10-03 11:07:09.469173Z] Running setup-mitmproxy step. 04:07:09 INFO - Running pre-action listener: _resource_record_pre_action 04:07:09 INFO - Running main action method: setup_mitmproxy 04:07:09 INFO - Skipping: mitmproxy is not required 04:07:09 INFO - Running post-action listener: _resource_record_post_action 04:07:09 INFO - [mozharness: 2017-10-03 11:07:09.469918Z] Finished setup-mitmproxy step (success) 04:07:09 INFO - [mozharness: 2017-10-03 11:07:09.470155Z] Running run-tests step. 04:07:09 INFO - Running pre-action listener: _resource_record_pre_action 04:07:09 INFO - Running pre-action listener: _set_gcov_prefix 04:07:09 INFO - Running main action method: run_tests 04:07:09 WARNING - Try message not found. 04:07:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:07:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:07:09 INFO - Python 2.7.3 04:07:09 INFO - Return code: 0 04:07:09 INFO - grabbing minidump binary from tooltool 04:07:09 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')]} 04:07:09 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 0x269bd60>, '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 0x269ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269cef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:07:09 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 04:07:09 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 04:07:09 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 04:07:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:07:09 INFO - Return code: 0 04:07:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:07:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:07:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:07:09 INFO - ENV: RUST_BACKTRACE is now full 04:07:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:07:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:07:09 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/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 04:07:09 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/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 04:07:09 INFO - Using env: {'DISPLAY': ':0', 04:07:09 INFO - 'HOME': '/home/cltbld', 04:07:09 INFO - 'LANG': 'en_US.UTF-8', 04:07:09 INFO - 'LANGUAGE': 'en_US:en', 04:07:09 INFO - 'LOGNAME': 'cltbld', 04:07:09 INFO - 'MAIL': '/var/mail/cltbld', 04:07:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:07:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:07:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:09 INFO - 'MOZ_NO_REMOTE': '1', 04:07:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:07:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:07:09 INFO - 'NO_EM_RESTART': '1', 04:07:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:07:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:09 INFO - 'PWD': '/builds/slave/test', 04:07:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:07:09 INFO - 'RUST_BACKTRACE': 'full', 04:07:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:07:09 INFO - 'SHELL': '/bin/bash', 04:07:09 INFO - 'SHLVL': '1', 04:07:09 INFO - 'TERM': 'linux', 04:07:09 INFO - 'TMOUT': '86400', 04:07:09 INFO - 'USER': 'cltbld', 04:07:09 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', 04:07:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:09 INFO - '_': '/tools/buildbot/bin/python'} 04:07:09 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/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 04:07:09 INFO - mozversion application_buildid: 20171003094133 04:07:09 INFO - mozversion application_changeset: 14841c4d8a9783ad55a0c560ec9312ba16200ac1 04:07:09 INFO - mozversion application_display_name: Nightly 04:07:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:07:09 INFO - mozversion application_name: Firefox 04:07:09 INFO - mozversion application_remotingname: firefox 04:07:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:07:09 INFO - mozversion application_vendor: Mozilla 04:07:09 INFO - mozversion application_version: 58.0a1 04:07:09 INFO - mozversion platform_buildid: 20171003094133 04:07:09 INFO - mozversion platform_changeset: 14841c4d8a9783ad55a0c560ec9312ba16200ac1 04:07:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:07:09 INFO - mozversion platform_version: 58.0a1 04:07:09 INFO - using testdate: 1507028829 04:07:09 INFO - actual date: 1507028829 04:07:09 INFO - starting webserver on 'localhost:52081' 04:07:09 INFO - SUITE-START | Running 2 tests 04:07:09 INFO - TEST-START | dromaeo_css 04:07:09 INFO - Initialising browser for dromaeo_css test... 04:07:09 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52081/getInfo.html -profile /tmp/tmppROBMt/profile 04:07:09 INFO - TEST-INFO | started process 13473 (/builds/slave/test/build/application/firefox/firefox http://localhost:52081/getInfo.html) 04:07:16 INFO - TEST-INFO | 13473: exit 0 04:07:16 INFO - Browser initialized. 04:07:16 INFO - Running cycle 1/1 for dromaeo_css test... 04:07:16 INFO - Using env: {'DISPLAY': ':0', 04:07:16 INFO - 'HOME': '/home/cltbld', 04:07:16 INFO - 'JSGC_DISABLE_POISONING': '1', 04:07:16 INFO - 'LANG': 'en_US.UTF-8', 04:07:16 INFO - 'LANGUAGE': 'en_US:en', 04:07:16 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:07:16 INFO - 'LOGNAME': 'cltbld', 04:07:16 INFO - 'MAIL': '/var/mail/cltbld', 04:07:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:07:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:07:16 INFO - 'MOZ_CRASHREPORTER': '1', 04:07:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:16 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:07:16 INFO - 'MOZ_NO_REMOTE': '1', 04:07:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:07:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:07:16 INFO - 'NO_EM_RESTART': '1', 04:07:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:07:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:16 INFO - 'PWD': '/builds/slave/test', 04:07:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:07:16 INFO - 'RUST_BACKTRACE': 'full', 04:07:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:07:16 INFO - 'SHELL': '/bin/bash', 04:07:16 INFO - 'SHLVL': '1', 04:07:16 INFO - 'TERM': 'linux', 04:07:16 INFO - 'TMOUT': '86400', 04:07:16 INFO - 'USER': 'cltbld', 04:07:16 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', 04:07:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:16 INFO - '_': '/tools/buildbot/bin/python'} 04:07:16 INFO - TEST-INFO | started process 13748 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppROBMt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:07:17 INFO - PID 13748 | 04:07:17 INFO - PID 13748 | (/builds/slave/test/build/application/firefox/firefox:13798): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:07:17 INFO - PID 13748 | 04:07:27 INFO - PID 13748 | RSS: Main: 142589952 04:07:27 INFO - PID 13748 | 04:10:25 INFO - PID 13748 | Cycle 1(1): loaded http://localhost:52081/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52081/tests/dromaeo/cssquery-ext.html) 04:10:26 INFO - PID 13748 | RSS: Main: 142528512 04:10:26 INFO - PID 13748 | 04:13:27 INFO - PID 13748 | Cycle 1(1): loaded http://localhost:52081/tests/dromaeo/cssquery-ext.html (next: http://localhost:52081/tests/dromaeo/cssquery-jquery.html) 04:13:28 INFO - PID 13748 | RSS: Main: 144203776 04:13:28 INFO - PID 13748 | 04:16:30 INFO - PID 13748 | Cycle 1(1): loaded http://localhost:52081/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52081/tests/dromaeo/cssquery-mootools.html) 04:16:30 INFO - PID 13748 | RSS: Main: 144961536 04:16:30 INFO - PID 13748 | 04:19:32 INFO - PID 13748 | Cycle 1(1): loaded http://localhost:52081/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52081/tests/dromaeo/cssquery-prototype.html) 04:19:32 INFO - PID 13748 | RSS: Main: 142737408 04:19:32 INFO - PID 13748 | 04:22:35 INFO - PID 13748 | Cycle 1(1): loaded http://localhost:52081/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52081/tests/dromaeo/cssquery-yui.html) 04:22:36 INFO - PID 13748 | RSS: Main: 148045824 04:22:36 INFO - PID 13748 | 04:25:38 INFO - PID 13748 | Cycle 1(1): loaded http://localhost:52081/tests/dromaeo/cssquery-yui.html (next: http://localhost:52081/tests/dromaeo/cssquery-dojo.html) 04:25:38 INFO - PID 13748 | RSS: Main: 148811776 04:25:38 INFO - PID 13748 | 04:25:38 INFO - PID 13748 | __start_tp_report 04:25:38 INFO - PID 13748 | _x_x_mozilla_page_load 04:25:38 INFO - PID 13748 | _x_x_mozilla_page_load_details 04:25:38 INFO - PID 13748 | |i|pagename|runs| 04:25:38 INFO - PID 13748 | |0;dojo.html;3891.844326226951;3921.490206273184;3926.901827454315;3946.605339466052;3966.504186976628;6131.110988906606;6247.562670613053;6299.811005669837;6351.587146835464;6423.582467139633;6290.591111577744;6332.525060620449;6395.424411802936;6399.776007839727;6468.126802881609;5660.745266462998;5671.517920976722;5681.11942648888;5701;5733.512651424634;5599.514900892665;5661.2923384576925;5686.914696279559;5706.971465142668;5778.855528611776;5344.198370244456;5730.7707691692285;5736.3403208631125;5766.884662306751;5788.594798364116;5498.917516237219;5575.0522411190195;5589;5620.325560932714;5685.857853553653;5574.526165275956;5631.324241091095;5656.519195868356;5674.943250567483;5675.829725108253;6250.374962503759;6325.304216536178;6363.949948258532;6408.359164083555;6409.583377080521;6776.42400395967;6796.932030679729;6811.114555107853;6879;6898.896516552256;6796.35434633714;6906.102206713145;6930.826729331757;6931.514793964425;6945;6173.938260617426;6267.71795269214;6331.588446750947;6394.232692076934;6465.418112369815;7095.148582170069;7103.822404439827;7162.570245785269;7182.030425892543;7190.9280907191305;6695.866082678319;6764.966175169093;6771.390574848188;6785.558938668971;6853.554518956352;6837.897431538531;6889.448844092461;6916;6937.098177236927;6940.30596940312;6774.898376524356;6790.151231096113;6847.075644787989;6860.656967151623;6862.4853136015;6808.4212841908;6810.080639113657;6894.00036994633;6897.689603967833;6910.689018994158;4904.386951631046;4926.359573255526;4988.351514303117;5020.221865610836;5030.421501527371;4419.204543182258;4506.977465112654;4509.503954564995;4521.841735539306;4537.22867112585;6150.231221097363;6159.84600384985;6175.536834737323;6236.688165591702;6241.407066328691;6686.264510903797;6746.460283177432;6786.287439818748;6790.898136527956;6791.524593278423;6263.279722831932;6284.622922624657;6328.17733694626;6351.301356850743;6388.936110638834;5521.834344969658;5587.3015873015875;5602.271704678447;5603.663780173202;5608.298962629671;7061.71753129869;7070.611116388545;7073.717051317993;7166.605836678928;7184.066071410683;6679.9332006679315;6795.490338224554;6799.93200067993;6800.625965571941;6805.115335006418;6991.860162796817;7006.159260888726;7015.263397343206;7114.57312561248;7150.284971502808;6586.901196482152;6697.497687673346;6702.262751097473;6840.760573379908;6858.3141685831015;6678.23200331968;6830.93169068303;6843.3840954314355;6844.247132815486;6854.691538880663;6609.867802643824;6616.503762217757;6679.699413526306;6698.83252918681;6703.3296670332575;6517.217933847968;6603.603783772989;6620.966895165493;6633.402993730588;6733.091032710718;6454.19322584677;6574.211094668479;6576.112224849584;6586.209654841258;6610.041543976193;6645.900311495235;6704.262531121475;6729.528932974645;6743.29195434492;6769.120014398097;6375.394337537927;6452.322506136788;6454.903176452263;6547.9672601638595;6551.80344589663 04:25:38 INFO - PID 13748 | |1;ext.html;919.1681528216964;958.8322043642363;958.8561715742637;1006.6325791086247;1013.8428543575744;45415;46571.534284657144;46969.53030469694;47045;47090.764546177306;43312.13375732483;47379;48003;48123.27815082776;48190.75904620472;8549.358798090152;8687.56562171892;8690.478571285712;8691.652333906668;8696.826063478758;8147.551884646342;8369.83260334793;8382.706605268817;8395.202455766692;8404.915950840474;8407.075221725605;8418.705345312916;8436.831263374728;8458.577071146448;8467.576621168948;8324.62539185738;8389.622466989;8410.663573457115;8415.537145456998;8416.537090460022;8452;8456.534890581015;8459.280961118251;8474.364422668325;8516;41532.79233603843;41976.58023419757;42022.78988605068;42030.78984605088;42232.57767422348;3709.9072523186865;3721.4417837324618;3742.7380083394173;3748.7938163401004;3753.249350129985;34478.48282275768;34692.132696682784;34844.82577587121;34985;35043.299134017434;5296;5304.814331498417;5356.866078348073;5510.008198524264;5532.225488431623;240040;241925;242186;244070;248541;57188.42811571914;57547.424525754206;57783.42216577865;57825.71087144537;57837;59714.10428843571;59829.70085149634;59925.700371497864;60038.399616003284;60070.098948515806;17403.73894391585;17448.214830332665;17488.737668934977;17564.91217543904;17645.38241161553;14182.361793719101;14349.067310624776;14351.569452916428;14421.206833624252;14472.85527144736;14775;14859.405623775145;14879.404823806926;14968.176750278839;15030.549083527512;5577.553795696334;5581.358143813432;5597.132444471114;5664.631798933138;5694.373618901917;1074.435921141407;1076.822324316479;1081.6754973507916;1085.3921803789904;1090;25880.352991175372;25924.74075259261;26117;26143.346416339362;26165.476690466083;1165.959191428296;1184.5735535207318;1190.2501424102759;1202.537023246039;1202.8316035755001;13618.863811361958;13631.250281234627;13823.101498402571;13853;13954.162750235018;314823;316656;316697;316768;316980;61241;61883.38116618776;62084.68957655183;62185.37814621796;62513.37486625076;1477.4607268346977;1492.298619648785;1515.4998850379347;1529.5411376587201;1536.4929573240804;25564.23307300784;25695.743042570084;25868.741312586633;25919;26096.347591309608;42559;42621.36067959045;42634.78682606567;42665.36001960035;42680.57319426766;256575;257272;258534;259172;260366;38583.2283354337;42695;43103.35344969891;43393.783031085906;44000.55999439965;53073.734631326595;54787.17819232794;54976.450235498734;55069.44930550804;55148.17277740757;39711;39918.60081399265;39972.40041399439;40039;40120.39819402769;39215.64706117642;39318.213635726555;39462.80268598639;39566.802165988986;39740.80129599333;46577.301340479244;46785.532144678116;46990.29514557216;47015;47051;37584;37626.2474750498;38267.808660956514;38472.80763596164;38563;27823.582646260726;28574.285642859093;28693.569596456487;28781;28884.42231155407 04:25:38 INFO - PID 13748 | |2;jquery.html;597.9013462778641;612.2775125352089;618.6659204029827;639.146739103297;657.2441692054135;23126;23229.651555226686;24029.639555406684;24064.518709625798;24099.518009639818;25005;26113.34716632085;26137.86931065342;26141.47717045658;26161.47677046458;6766.966165169168;6946.131733533309;6946.131733533309;6952;6952.374286314231;6491.350864913495;6732.697028633724;6779.457643388517;6782.660866956632;6784.3554862288;6731.225909020452;6731.596104233738;6732.95639175926;6736.360045795666;6736.46108311334;6688.264290927994;6688.398044175999;6708.530402871801;6725.092112564815;6750.662466876661;6279.96860015697;6301.40136687014;6302.18071650687;6309.400606942379;6312.400321969406;25251.873740631363;25424.237272881845;25431.237062887958;27959.30101747452;30149.246268843235;2291.679164916913;2306.850054746436;2315.1086831569796;2315.548468048731;2315.710536182977;20448.897755511127;21022.36932892016;21040.368788936357;21157.68263476034;21207.575848482946;2469.925902222936;2534.4170840706743;2561.5773397389407;2592.3519120219944;2623.121254379766;143254;146423;163837;164346;170643.1467842653;16127.919360403122;16432.342706291853;16497.617988312366;16710.331586736636;17404.216810243568;27632;27637.447251054866;27649.308767280978;27684.861575691993;27735.72264277372;10406.531706073245;10409.583616655249;10455.738606534898;10514.526846291936;10711.357318560911;9359.953200233955;9384.436933783994;9414.576344064397;9421.670241541513;9531.475768832643;9760.707178784647;10109.545070471846;10270.33242839213;10342.259301285165;10476.319039262577;2950.2919299367995;2978.8659510322086;2999.3701322722036;3009.179446434919;3091.1962889648403;1049.8425236214475;1070.3898777696793;1074.1460538871615;1091.0398849012818;1092.5411327242577;20904;20932.686009709865;20988.58022839565;21231.575368492544;21705.131794728033;1113.6603335982604;1117.1453837814079;1129.949152288149;1134.6142311614092;1136.9772604547863;11482.770344593062;11626;11632.476538555786;11686.70783230426;11765.588204412805;615590;625793;641179.3823092658;641277;650902;135103;137487;139629.3018534901;139763;141085;1199.2684462477882;1214.1318956945827;1229.2931564350533;1236.863944966071;1240.4851986425713;18741.812581874008;18904.432867014013;18963.715544267125;18972.335968241045;19156.13797379149;63725.40686482875;64122.679386602766;64563;65068;65993.34006660063;82891.5855420719;83204.58397707973;83237;84174;85727;15460.613484662974;15596.922015390304;15679.921600391925;15759.527214183592;15763.133027683587;23382.883085584464;23555.764442355357;23560.410989725395;23769.286921392384;23868.52262954766;19068.141933612744;19562.413127606196;19721.309754158534;19777.901110494357;19783.50541236423;17117;17143;17573.824261757218;17912.373066943117;18028;19418.8058119417;19477.318293859647;19774.901125494856;19847.503812404804;19866.90066549658;15265.389384424943;15375.461858834888;15376.615584610474;15402.768958465856;15467.5359739208;18546.536336591154;18941.810581894006;19001.144948477075;19130.52173695669;19199.232030718616 04:25:38 INFO - PID 13748 | |3;mootools.html;1411.5906387147727;1468.7062587482492;1489.2256026866023;1509.5094094419317;1526.816781986162;16562.171891405425;17326.133693315347;17350.219240134193;17350.566235844093;17369.56576085597;16856.915715421437;16857;16873.74689379657;17005.234764435598;17022.574435639115;2874.080294305823;2899.9565006524867;2969.198316454556;2969.2576855786056;2970.7474864636533;2858.556923676823;2920.9707902920914;2954.1285320830298;2954.6454425468974;2956.1574951138928;2882.7261410166;2994.8203107813492;2996.2509372656837;2997.310618557733;3004.218903085192;2953.3059730963205;2954.335274563217;2975.642922849272;2977.434287485371;2995.311078451957;2882.351470919047;2885.7691384689383;2917.2706823294175;2929.7509711674534;2930.106317573139;16504.33982640693;16902;16906.239219235165;16916.323347066103;16957.49127526176;8196.83606327876;8496.235338819535;8498.915010849874;8499.320054395695;8502.447340922881;5463.098588732854;5565.2122238564225;5613.129964855413;5687.544996400278;5709.229254050691;2074.844386671006;2093.75921768997;2095.2038225474375;2095.559932414195;2149.0651566568595;30572.08283751491;30778;30800.537991930138;30823.38353232945;30860.53709194364;7554;7630.7329243476215;7681.846363072819;7732.9226707732205;7767.2232776723;20346.593068138554;20423.978801059886;20590.176392944413;20597.58804823895;20628.896855515628;8590.797288379632;8802.955985220033;8832.55837208137;8872.73381798547;8878.200961913459;7742.7677169685;7793.376529877596;7797.142314345418;7835.451518393657;7850.842983140419;5728.369879313273;5976.641401515836;6003.249593800775;6024.83106081819;6058.666773327509;1639.5163426789127;1694.4154266777941;1734.5230061733125;1762.814904435016;1768.0717623247897;940.1444685336311;954.513198268874;963.8578284732614;975.3660120921317;985.4235272365746;8879.822403551892;8898.911010889939;8941.731748047569;9021.864672029793;9141.77145571366;755.8526087412902;760.1106705154982;765.3188662090744;778.9532628042335;847.3475423923544;5480.972595136999;5507.697076660822;5510.393856675762;5544.085225937675;5774.682392468455;24025;24981;25077.37306567314;25168.119115830857;25246;23510.529789404118;24339.756602434103;24821.1312604058;24841.130560430298;24936.750632493804;12362.505499780087;12492.81260781089;12499.312537810325;12515.874841251652;12669.366531673379;8579.571021449028;8584.699535516227;8697.34769892248;8908.242799362128;9062.365634405529;19897.104630291382;20093.49766255797;20512;20608.381748547567;20869.165233390493;20956.056977436358;21096.367108986757;21184.15263389447;21338;21881.234156804436;12514.499420023098;12532.060095493056;12689.746205076031;12707.110502264753;12712.6821829455;15069;15344.923275383551;15372.077675339515;15375.461858834888;15389.38442462289;12130.818037729618;12321;12321.383930803604;12473.812892806434;12609.432575534307;11452.82820757706;11668.70828229301;11843.822342664695;11871.643850684493;12160.148789584644;10072.093511583993;10078.445685487368;10226.028527289896;10334.638287659896;10339.844902326622;7884.645190966306;7932.127465978853;7961.721339753312;7983.441159118806;8113.229243221885;8216.671333146607;8223.917760822314;8250.257476827115;8266.008079030555;8290.29532489721 04:25:38 INFO - PID 13748 | |4;prototype.html;564.2354609504124;564.404553196378;575.8013485347557;592.8428966323924;602.8402473344564;6135.81592552223;6136.354553810494;6191.50467962563;6314.810555683337;6330.905036424446;6162.414570615795;6174.567780255373;6248.812535623939;6253.562250642445;6256.749730010794;5150.407703114133;5166.819161329354;5179.979807874932;5192.636515443921;5207.427183009866;5209.400918894319;5269.288646032794;5272.525472707455;5272.762725677354;5323.920141197885;5156.639035267533;5228.215767634866;5242.868928276805;5263.26314315996;5288.836455979678;5191.896162076756;5198.454162312941;5226.425093239741;5232.372115346145;5234.450382709837;4925.581325587329;4949.628777841676;4969.975150124263;4989.151844186461;4996.1756310208775;6241.906371404432;6246.812595622138;6251.687415629201;6278.591891527036;6366.522510811661;6114.602550834226;6144.670988881459;6161.691915404212;6215.378462153793;6250.34371391002;6719.966400168017;6753.4597232221795;6765.830854228634;6801.897971530431;6834.692438840264;5286.444923283077;5354.41101478837;5422.105352616813;5459;5469.206964990054;6865.965670171667;6870.175578930561;6901.861962760717;7020.719171233196;7063.858722825617;5649;5674.184261036511;5678.574106941914;5685.602007859493;5694.117411801178;5806.506446951972;5820;5829.650220986669;5845.473907348359;5880.0015989127805;6193.721282542297;6200.81397558074;6208.888977747588;6250.624962502265;6261.373862613793;6644.900326495106;6783.152105986752;6785.846406110973;6794.592324460548;6794.898076528856;1969.1138987455702;1992.4819546917906;2008.2067583304804;2025.8075482829108;2056.167252262836;1464.7070585882652;1506.517914267424;1509.5055894664422;1510.3656464285027;1524.4131009561177;6046;6046.697665116727;6090.604110732788;6221.4089661482085;6301.338359472281;6567.244766851778;6637.1703537057865;6690.163729533809;6758.966205169041;6864;6109.297430795426;6112.266528016667;6147.66187859672;6220.502359811204;6302.338254483305;3531.2231309111953;3537;3551.751377403557;3557.839897204632;3576.338377400189;6905.41175529631;6938.132733408324;6955.478339124586;7045.436365090781;7052.153741551047;6711.530192886451;6876.587404755631;6900.447964162855;6977.441804655616;7018.964905175441;6897.3447522485285;6918.654067296515;6937.571849852511;6949.791506254821;7023.719051237996;6606.867862642816;6663.7001334941015;6693.263740988585;6718.93281067183;6730.158730158731;6682.398584127452;6700.932990670226;6705.128333316833;6752.189737231563;6774.491913106438;7057.894131588133;7072.25741297177;7094.290570942864;7110.57336559808;7125.3230943060325;5568.470995255444;5714.228579141763;5726.627769194989;5768.855778605568;5770.596058276048;6560.770373036921;6564.573302735307;6795.592264464148;6816.7614133505085;6817.420519255918;6490.578112422678;6548.214214294315;6568.770093046721;6613;6627;6522.478201743944;6561.1470508833545;6571.80284591463;6617.503687223381;6619.304972977768;6301;6343.777967771106;6413.134226879311;6467.579607325509;6569.57297775643;6554.296822295388;6590;6605.801825945229;6657.933420665731;6691.76578819739;6237.908366036017;6417.807465776034;6454.903176452263;6481.736061467968;6552.213734351908 04:25:38 INFO - PID 13748 | |5;yui.html;952.1811242331598;987.3582171588467;997.9650712225074;1003.097212508742;1018.9082982531573;20468.69296960547;20925.162993480262;20971.580568388625;20991.580168396642;21033.05351259193;19294.51763705908;19430.222791088374;19528.902355488237;20136.19455221793;20300.390988270337;1047.0785708576443;1074.0387353318774;1074.7366895110708;1074.8925107489267;1075.6450371377466;1050.1179009631908;1069.8074346617645;1072.2387105155349;1073.3828048871892;1091.8635170603675;1138.6527109231722;1143.0798207443006;1143.6626195272415;1146.3007565385108;1149.1151813103904;1081.9783604327947;1138.7551676389617;1139.8518192634988;1141.0928311991986;1144.3934714601232;806.9838603227903;809.5021561739546;810.1614828652375;810.4205493072423;811.7605306434616;21673;21808.563828723494;21872.343829685135;21875;21876.124955001782;22567.77432225673;23506.76493235063;23708.762912371;23778.881105594533;23851.403714907094;18298.45104646863;18994.62010759777;19147.61704765897;19184.90407547953;19203.903980480147;3989.5412027616617;4004.039030632627;4008.158286759784;4009;4010.5588385277597;169018;169357.15321423436;172250;172415;173487;18093;18160.636787264182;18267.36064237772;18292.908535457238;18383.448496545127;33365.332693346;33613.15967100842;33679;33937.15157121042;33977;8575.95712021436;8829.69096081634;8844.248238899765;8844.292456603456;8849.646014159362;4370.956290437119;4581.702189357681;4599.517050709695;4630.560096790799;4644.256918893028;6227.221597300338;6319.146915166486;6334.271558770801;6382.297947225801;6834.760783372658;1252.974940501203;1260.3068312428127;1279.8848103670714;1290.0582574720506;1297.7274772297924;736.5543845973216;738.5162718419443;756.3722110648149;759.7492827367066;762.0208032677982;12793.912517436122;12805.103642745105;13047.217166970013;13090.14914030585;13521.18872867631;717.2039036669292;718.7017387784011;721.7618185998608;742.8885667149863;743.18992298395;5857.887001469792;5994.550408719277;6018.969905150446;6063.878722425615;6079.635221886701;151989;154818;157143;157803.18811475404;158609;133178;138781;138946;139498;143634;121412;123081.38459307647;123493;125837;125948.37025814812;11020.787713351685;11077.83383249268;11103.278286911325;11160.660720713566;11403.543858245908;54189.729051354494;54395.72802135964;55536.72231638816;56048.15927761168;56371.43628563662;71084;71805.92291115742;72079.63960180165;72462.27537724766;73149;15982.120983346027;16099.195040247707;16148.838511614733;16344.346226151296;16364.34542618282;19060.714089288394;19064.904675476533;19265.325713599956;19297.807021929602;19320.613587728447;14734.26328683583;14853.851461485247;14912.179830109015;15106.848931510543;15138.848611514184;15711;15905.125218112653;15933.601659958595;16007.519774406786;16075.19624018771;17392.217350219522;17478;17509.649807004043;17614.647707046042;17688.204030818386;12660;12712.872871271169;12723.23660580368;12723.681907952376;12855.742885142432;12602;12657.810132848199;12785.936070319589;12854.871451285368;12907.031972601904 04:25:38 INFO - PID 13748 | __end_tp_report 04:25:38 INFO - PID 13748 | __start_cc_report 04:25:38 INFO - PID 13748 | _x_x_mozilla_cycle_collect,285 04:25:38 INFO - PID 13748 | __end_cc_report 04:25:38 INFO - PID 13748 | __startTimestamp1507029938276__endTimestamp 04:25:38 INFO - PID 13748 | 04:25:38 INFO - PID 13748 | ------- Summary: start ------- 04:25:38 INFO - PID 13748 | Number of tests: 6 04:25:38 INFO - PID 13748 | 04:25:38 INFO - PID 13748 | [#0] dojo.html Cycles:165 Average:6293.23 Median:6574.21 stddev:751.52 (11.4%) stddev-sans-first:729.83 04:25:38 INFO - PID 13748 | Values: 3891.8 3921.5 3926.9 3946.6 3966.5 6131.1 6247.6 6299.8 6351.6 6423.6 6290.6 6332.5 6395.4 6399.8 6468.1 5660.7 5671.5 5681.1 5701.0 5733.5 5599.5 5661.3 5686.9 5707.0 5778.9 5344.2 5730.8 5736.3 5766.9 5788.6 5498.9 5575.1 5589.0 5620.3 5685.9 5574.5 5631.3 5656.5 5674.9 5675.8 6250.4 6325.3 6363.9 6408.4 6409.6 6776.4 6796.9 6811.1 6879.0 6898.9 6796.4 6906.1 6930.8 6931.5 6945.0 6173.9 6267.7 6331.6 6394.2 6465.4 7095.1 7103.8 7162.6 7182.0 7190.9 6695.9 6765.0 6771.4 6785.6 6853.6 6837.9 6889.4 6916.0 6937.1 6940.3 6774.9 6790.2 6847.1 6860.7 6862.5 6808.4 6810.1 6894.0 6897.7 6910.7 4904.4 4926.4 4988.4 5020.2 5030.4 4419.2 4507.0 4509.5 4521.8 4537.2 6150.2 6159.8 6175.5 6236.7 6241.4 6686.3 6746.5 6786.3 6790.9 6791.5 6263.3 6284.6 6328.2 6351.3 6388.9 5521.8 5587.3 5602.3 5603.7 5608.3 7061.7 7070.6 7073.7 7166.6 7184.1 6679.9 6795.5 6799.9 6800.6 6805.1 6991.9 7006.2 7015.3 7114.6 7150.3 6586.9 6697.5 6702.3 6840.8 6858.3 6678.2 6830.9 6843.4 6844.2 6854.7 6609.9 6616.5 6679.7 6698.8 6703.3 6517.2 6603.6 6621.0 6633.4 6733.1 6454.2 6574.2 6576.1 6586.2 6610.0 6645.9 6704.3 6729.5 6743.3 6769.1 6375.4 6452.3 6454.9 6548.0 6551.8 04:25:38 INFO - PID 13748 | 04:25:38 INFO - PID 13748 | [#1] ext.html Cycles:180 Average:46522.94 Median:40005.70 stddev:71537.98 (178.8%) stddev-sans-first:71656.72 04:25:38 INFO - PID 13748 | Values: 919.2 958.8 958.9 1006.6 1013.8 45415.0 46571.5 46969.5 47045.0 47090.8 43312.1 47379.0 48003.0 48123.3 48190.8 8549.4 8687.6 8690.5 8691.7 8696.8 8147.6 8369.8 8382.7 8395.2 8404.9 8407.1 8418.7 8436.8 8458.6 8467.6 8324.6 8389.6 8410.7 8415.5 8416.5 8452.0 8456.5 8459.3 8474.4 8516.0 41532.8 41976.6 42022.8 42030.8 42232.6 3709.9 3721.4 3742.7 3748.8 3753.2 34478.5 34692.1 34844.8 34985.0 35043.3 5296.0 5304.8 5356.9 5510.0 5532.2 240040.0 241925.0 242186.0 244070.0 248541.0 57188.4 57547.4 57783.4 57825.7 57837.0 59714.1 59829.7 59925.7 60038.4 60070.1 17403.7 17448.2 17488.7 17564.9 17645.4 14182.4 14349.1 14351.6 14421.2 14472.9 14775.0 14859.4 14879.4 14968.2 15030.5 5577.6 5581.4 5597.1 5664.6 5694.4 1074.4 1076.8 1081.7 1085.4 1090.0 25880.4 25924.7 26117.0 26143.3 26165.5 1166.0 1184.6 1190.3 1202.5 1202.8 13618.9 13631.3 13823.1 13853.0 13954.2 314823.0 316656.0 316697.0 316768.0 316980.0 61241.0 61883.4 62084.7 62185.4 62513.4 1477.5 1492.3 1515.5 1529.5 1536.5 25564.2 25695.7 25868.7 25919.0 26096.3 42559.0 42621.4 42634.8 42665.4 42680.6 256575.0 257272.0 258534.0 259172.0 260366.0 38583.2 42695.0 43103.4 43393.8 44000.6 53073.7 54787.2 54976.5 55069.4 55148.2 39711.0 39918.6 39972.4 40039.0 40120.4 39215.6 39318.2 39462.8 39566.8 39740.8 46577.3 46785.5 46990.3 47015.0 47051.0 37584.0 37626.2 38267.8 38472.8 38563.0 27823.6 28574.3 28693.6 28781.0 28884.4 04:25:38 INFO - PID 13748 | 04:25:38 INFO - PID 13748 | [#2] jquery.html Cycles:180 Average:41086.95 Median:12716.43 stddev:106411.66 (836.8%) stddev-sans-first:106666.74 04:25:38 INFO - PID 13748 | Values: 597.9 612.3 618.7 639.1 657.2 23126.0 23229.7 24029.6 24064.5 24099.5 25005.0 26113.3 26137.9 26141.5 26161.5 6767.0 6946.1 6946.1 6952.0 6952.4 6491.4 6732.7 6779.5 6782.7 6784.4 6731.2 6731.6 6733.0 6736.4 6736.5 6688.3 6688.4 6708.5 6725.1 6750.7 6280.0 6301.4 6302.2 6309.4 6312.4 25251.9 25424.2 25431.2 27959.3 30149.2 2291.7 2306.9 2315.1 2315.5 2315.7 20448.9 21022.4 21040.4 21157.7 21207.6 2469.9 2534.4 2561.6 2592.4 2623.1 143254.0 146423.0 163837.0 164346.0 170643.1 16127.9 16432.3 16497.6 16710.3 17404.2 27632.0 27637.4 27649.3 27684.9 27735.7 10406.5 10409.6 10455.7 10514.5 10711.4 9360.0 9384.4 9414.6 9421.7 9531.5 9760.7 10109.5 10270.3 10342.3 10476.3 2950.3 2978.9 2999.4 3009.2 3091.2 1049.8 1070.4 1074.1 1091.0 1092.5 20904.0 20932.7 20988.6 21231.6 21705.1 1113.7 1117.1 1129.9 1134.6 1137.0 11482.8 11626.0 11632.5 11686.7 11765.6 615590.0 625793.0 641179.4 641277.0 650902.0 135103.0 137487.0 139629.3 139763.0 141085.0 1199.3 1214.1 1229.3 1236.9 1240.5 18741.8 18904.4 18963.7 18972.3 19156.1 63725.4 64122.7 64563.0 65068.0 65993.3 82891.6 83204.6 83237.0 84174.0 85727.0 15460.6 15596.9 15679.9 15759.5 15763.1 23382.9 23555.8 23560.4 23769.3 23868.5 19068.1 19562.4 19721.3 19777.9 19783.5 17117.0 17143.0 17573.8 17912.4 18028.0 19418.8 19477.3 19774.9 19847.5 19866.9 15265.4 15375.5 15376.6 15402.8 15467.5 18546.5 18941.8 19001.1 19130.5 19199.2 04:25:38 INFO - PID 13748 | 04:25:38 INFO - PID 13748 | [#3] mootools.html Cycles:180 Average:10381.14 Median:2908.61 stddev:7659.38 (263.3%) stddev-sans-first:7651.22 04:25:38 INFO - PID 13748 | Values: 1411.6 1468.7 1489.2 1509.5 1526.8 16562.2 17326.1 17350.2 17350.6 17369.6 16856.9 16857.0 16873.7 17005.2 17022.6 2874.1 2900.0 2969.2 2969.3 2970.7 2858.6 2921.0 2954.1 2954.6 2956.2 2882.7 2994.8 2996.3 2997.3 3004.2 2953.3 2954.3 2975.6 2977.4 2995.3 2882.4 2885.8 2917.3 2929.8 2930.1 16504.3 16902.0 16906.2 16916.3 16957.5 8196.8 8496.2 8498.9 8499.3 8502.4 5463.1 5565.2 5613.1 5687.5 5709.2 2074.8 2093.8 2095.2 2095.6 2149.1 30572.1 30778.0 30800.5 30823.4 30860.5 7554.0 7630.7 7681.8 7732.9 7767.2 20346.6 20424.0 20590.2 20597.6 20628.9 8590.8 8803.0 8832.6 8872.7 8878.2 7742.8 7793.4 7797.1 7835.5 7850.8 5728.4 5976.6 6003.2 6024.8 6058.7 1639.5 1694.4 1734.5 1762.8 1768.1 940.1 954.5 963.9 975.4 985.4 8879.8 8898.9 8941.7 9021.9 9141.8 755.9 760.1 765.3 779.0 847.3 5481.0 5507.7 5510.4 5544.1 5774.7 24025.0 24981.0 25077.4 25168.1 25246.0 23510.5 24339.8 24821.1 24841.1 24936.8 12362.5 12492.8 12499.3 12515.9 12669.4 8579.6 8584.7 8697.3 8908.2 9062.4 19897.1 20093.5 20512.0 20608.4 20869.2 20956.1 21096.4 21184.2 21338.0 21881.2 12514.5 12532.1 12689.7 12707.1 12712.7 15069.0 15344.9 15372.1 15375.5 15389.4 12130.8 12321.0 12321.4 12473.8 12609.4 11452.8 11668.7 11843.8 11871.6 12160.1 10072.1 10078.4 10226.0 10334.6 10339.8 7884.6 7932.1 7961.7 7983.4 8113.2 8216.7 8223.9 8250.3 8266.0 8290.3 04:25:38 INFO - PID 13748 | 04:25:38 INFO - PID 13748 | [#4] prototype.html Cycles:180 Average:5761.14 Median:6251.16 stddev:1525.05 (24.4%) stddev-sans-first:1478.60 04:25:38 INFO - PID 13748 | Values: 564.2 564.4 575.8 592.8 602.8 6135.8 6136.4 6191.5 6314.8 6330.9 6162.4 6174.6 6248.8 6253.6 6256.7 5150.4 5166.8 5180.0 5192.6 5207.4 5209.4 5269.3 5272.5 5272.8 5323.9 5156.6 5228.2 5242.9 5263.3 5288.8 5191.9 5198.5 5226.4 5232.4 5234.5 4925.6 4949.6 4970.0 4989.2 4996.2 6241.9 6246.8 6251.7 6278.6 6366.5 6114.6 6144.7 6161.7 6215.4 6250.3 6720.0 6753.5 6765.8 6801.9 6834.7 5286.4 5354.4 5422.1 5459.0 5469.2 6866.0 6870.2 6901.9 7020.7 7063.9 5649.0 5674.2 5678.6 5685.6 5694.1 5806.5 5820.0 5829.7 5845.5 5880.0 6193.7 6200.8 6208.9 6250.6 6261.4 6644.9 6783.2 6785.8 6794.6 6794.9 1969.1 1992.5 2008.2 2025.8 2056.2 1464.7 1506.5 1509.5 1510.4 1524.4 6046.0 6046.7 6090.6 6221.4 6301.3 6567.2 6637.2 6690.2 6759.0 6864.0 6109.3 6112.3 6147.7 6220.5 6302.3 3531.2 3537.0 3551.8 3557.8 3576.3 6905.4 6938.1 6955.5 7045.4 7052.2 6711.5 6876.6 6900.4 6977.4 7019.0 6897.3 6918.7 6937.6 6949.8 7023.7 6606.9 6663.7 6693.3 6718.9 6730.2 6682.4 6700.9 6705.1 6752.2 6774.5 7057.9 7072.3 7094.3 7110.6 7125.3 5568.5 5714.2 5726.6 5768.9 5770.6 6560.8 6564.6 6795.6 6816.8 6817.4 6490.6 6548.2 6568.8 6613.0 6627.0 6522.5 6561.1 6571.8 6617.5 6619.3 6301.0 6343.8 6413.1 6467.6 6569.6 6554.3 6590.0 6605.8 6657.9 6691.8 6237.9 6417.8 6454.9 6481.7 6552.2 04:25:38 INFO - PID 13748 | 04:25:38 INFO - PID 13748 | [#5] yui.html Cycles:180 Average:29073.87 Median:18295.68 stddev:44879.95 (245.3%) stddev-sans-first:44956.18 04:25:38 INFO - PID 13748 | Values: 952.2 987.4 998.0 1003.1 1018.9 20468.7 20925.2 20971.6 20991.6 21033.1 19294.5 19430.2 19528.9 20136.2 20300.4 1047.1 1074.0 1074.7 1074.9 1075.6 1050.1 1069.8 1072.2 1073.4 1091.9 1138.7 1143.1 1143.7 1146.3 1149.1 1082.0 1138.8 1139.9 1141.1 1144.4 807.0 809.5 810.2 810.4 811.8 21673.0 21808.6 21872.3 21875.0 21876.1 22567.8 23506.8 23708.8 23778.9 23851.4 18298.5 18994.6 19147.6 19184.9 19203.9 3989.5 4004.0 4008.2 4009.0 4010.6 169018.0 169357.2 172250.0 172415.0 173487.0 18093.0 18160.6 18267.4 18292.9 18383.4 33365.3 33613.2 33679.0 33937.2 33977.0 8576.0 8829.7 8844.2 8844.3 8849.6 4371.0 4581.7 4599.5 4630.6 4644.3 6227.2 6319.1 6334.3 6382.3 6834.8 1253.0 1260.3 1279.9 1290.1 1297.7 736.6 738.5 756.4 759.7 762.0 12793.9 12805.1 13047.2 13090.1 13521.2 717.2 718.7 721.8 742.9 743.2 5857.9 5994.6 6019.0 6063.9 6079.6 151989.0 154818.0 157143.0 157803.2 158609.0 133178.0 138781.0 138946.0 139498.0 143634.0 121412.0 123081.4 123493.0 125837.0 125948.4 11020.8 11077.8 11103.3 11160.7 11403.5 54189.7 54395.7 55536.7 56048.2 56371.4 71084.0 71805.9 72079.6 72462.3 73149.0 15982.1 16099.2 16148.8 16344.3 16364.3 19060.7 19064.9 19265.3 19297.8 19320.6 14734.3 14853.9 14912.2 15106.8 15138.8 15711.0 15905.1 15933.6 16007.5 16075.2 17392.2 17478.0 17509.6 17614.6 17688.2 12660.0 12712.9 12723.2 12723.7 12855.7 12602.0 12657.8 12785.9 12854.9 12907.0 04:25:38 INFO - PID 13748 | -------- Summary: end -------- 04:25:38 INFO - PID 13748 | 04:25:38 INFO - TEST-INFO | 13748: exit 0 04:25:38 INFO - TEST-OK | dromaeo_css | took 1109050ms 04:25:38 INFO - TEST-START | kraken 04:25:38 INFO - Initialising browser for kraken test... 04:25:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52081/getInfo.html -profile /tmp/tmpk5nkr0/profile 04:25:38 INFO - TEST-INFO | started process 14159 (/builds/slave/test/build/application/firefox/firefox http://localhost:52081/getInfo.html) 04:25:44 INFO - TEST-INFO | 14159: exit 0 04:25:45 INFO - Browser initialized. 04:25:45 INFO - Running cycle 1/1 for kraken test... 04:25:45 INFO - Using env: {'DISPLAY': ':0', 04:25:45 INFO - 'HOME': '/home/cltbld', 04:25:45 INFO - 'JSGC_DISABLE_POISONING': '1', 04:25:45 INFO - 'LANG': 'en_US.UTF-8', 04:25:45 INFO - 'LANGUAGE': 'en_US:en', 04:25:45 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:25:45 INFO - 'LOGNAME': 'cltbld', 04:25:45 INFO - 'MAIL': '/var/mail/cltbld', 04:25:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:25:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:25:45 INFO - 'MOZ_CRASHREPORTER': '1', 04:25:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:45 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:25:45 INFO - 'MOZ_NO_REMOTE': '1', 04:25:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:25:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:45 INFO - 'NO_EM_RESTART': '1', 04:25:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:45 INFO - 'PWD': '/builds/slave/test', 04:25:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:25:45 INFO - 'RUST_BACKTRACE': 'full', 04:25:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:25:45 INFO - 'SHELL': '/bin/bash', 04:25:45 INFO - 'SHLVL': '1', 04:25:45 INFO - 'TERM': 'linux', 04:25:45 INFO - 'TMOUT': '86400', 04:25:45 INFO - 'USER': 'cltbld', 04:25:45 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898', 04:25:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:45 INFO - '_': '/tools/buildbot/bin/python'} 04:25:45 INFO - TEST-INFO | started process 14435 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpk5nkr0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:25:45 INFO - PID 14435 | 04:25:45 INFO - PID 14435 | (/builds/slave/test/build/application/firefox/firefox:14484): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:25:45 INFO - PID 14435 | 04:25:56 INFO - PID 14435 | RSS: Main: 142356480 04:25:56 INFO - PID 14435 | 04:25:58 INFO - PID 14435 | Cycle 1(1): loaded http://localhost:52081/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52081/tests/kraken/driver.html?testName=audio-beat-detection) 04:25:59 INFO - PID 14435 | RSS: Main: 148328448 04:25:59 INFO - PID 14435 | 04:26:02 INFO - PID 14435 | Cycle 1(1): loaded http://localhost:52081/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52081/tests/kraken/driver.html?testName=audio-dft) 04:26:02 INFO - PID 14435 | RSS: Main: 148541440 04:26:02 INFO - PID 14435 | 04:26:05 INFO - PID 14435 | Cycle 1(1): loaded http://localhost:52081/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52081/tests/kraken/driver.html?testName=audio-fft) 04:26:06 INFO - PID 14435 | RSS: Main: 144928768 04:26:06 INFO - PID 14435 | 04:26:08 INFO - PID 14435 | Cycle 1(1): loaded http://localhost:52081/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52081/tests/kraken/driver.html?testName=audio-oscillator) 04:26:08 INFO - PID 14435 | RSS: Main: 145649664 04:26:08 INFO - PID 14435 | 04:26:10 INFO - PID 14435 | Cycle 1(1): loaded http://localhost:52081/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52081/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:26:11 INFO - PID 14435 | RSS: Main: 145547264 04:26:11 INFO - PID 14435 | 04:26:14 INFO - PID 14435 | Cycle 1(1): loaded http://localhost:52081/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52081/tests/kraken/driver.html?testName=imaging-darkroom) 04:26:15 INFO - PID 14435 | RSS: Main: 145268736 04:26:15 INFO - PID 14435 | 04:26:18 INFO - PID 14435 | Cycle 1(1): loaded http://localhost:52081/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52081/tests/kraken/driver.html?testName=imaging-desaturate) 04:26:19 INFO - PID 14435 | RSS: Main: 147038208 04:26:19 INFO - PID 14435 | 04:26:22 INFO - PID 14435 | Cycle 1(1): loaded http://localhost:52081/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52081/tests/kraken/driver.html?testName=json-parse-financial) 04:26:22 INFO - PID 14435 | RSS: Main: 148295680 04:26:22 INFO - PID 14435 | 04:26:24 INFO - PID 14435 | Cycle 1(1): loaded http://localhost:52081/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52081/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:26:24 INFO - PID 14435 | RSS: Main: 148172800 04:26:24 INFO - PID 14435 | 04:26:26 INFO - PID 14435 | Cycle 1(1): loaded http://localhost:52081/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52081/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:26:27 INFO - PID 14435 | RSS: Main: 148115456 04:26:27 INFO - PID 14435 | 04:26:29 INFO - PID 14435 | Cycle 1(1): loaded http://localhost:52081/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52081/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:26:29 INFO - PID 14435 | RSS: Main: 148209664 04:26:29 INFO - PID 14435 | 04:26:32 INFO - PID 14435 | Cycle 1(1): loaded http://localhost:52081/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52081/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:26:32 INFO - PID 14435 | RSS: Main: 148107264 04:26:32 INFO - PID 14435 | 04:26:35 INFO - PID 14435 | Cycle 1(1): loaded http://localhost:52081/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52081/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:26:36 INFO - PID 14435 | RSS: Main: 149143552 04:26:36 INFO - PID 14435 | 04:26:37 INFO - PID 14435 | Cycle 1(1): loaded http://localhost:52081/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52081/tests/kraken/driver.html?testName=ai-astar) 04:26:38 INFO - PID 14435 | RSS: Main: 150249472 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | __start_tp_report 04:26:38 INFO - PID 14435 | _x_x_mozilla_page_load 04:26:38 INFO - PID 14435 | _x_x_mozilla_page_load_details 04:26:38 INFO - PID 14435 | |i|pagename|runs| 04:26:38 INFO - PID 14435 | |0;ai-astar;107;105;106;105;106;106;106;105;105;105 04:26:38 INFO - PID 14435 | |1;audio-beat-detection;114;111;111;112;117;117;111;113;112;146 04:26:38 INFO - PID 14435 | |2;audio-dft;162;163;163;162;161;162;162;161;164;172 04:26:38 INFO - PID 14435 | |3;audio-fft;74;73;73;74;73;73;76;73;78;73 04:26:38 INFO - PID 14435 | |4;audio-oscillator;78;77;81;77;78;78;77;77;77;76 04:26:38 INFO - PID 14435 | |5;imaging-gaussian-blur;110;114;113;113;114;115;114;113;114;113 04:26:38 INFO - PID 14435 | |6;imaging-darkroom;149;149;154;151;150;150;150;149;150;150 04:26:38 INFO - PID 14435 | |7;imaging-desaturate;93;95;95;94;94;94;94;94;94;93 04:26:38 INFO - PID 14435 | |8;json-parse-financial;44;44;44;44;43;47;44;45;44;43 04:26:38 INFO - PID 14435 | |9;json-stringify-tinderbox;36;36;36;36;36;36;37;35;36;36 04:26:38 INFO - PID 14435 | |10;stanford-crypto-aes;68;66;67;68;72;69;69;72;70;68 04:26:38 INFO - PID 14435 | |11;stanford-crypto-ccm;109;106;101;108;106;105;101;103;106;108 04:26:38 INFO - PID 14435 | |12;stanford-crypto-pbkdf2;156;150;156;155;146;145;146;149;149;150 04:26:38 INFO - PID 14435 | |13;stanford-crypto-sha256-iterative;62;61;60;59;61;69;57;63;62;56 04:26:38 INFO - PID 14435 | __end_tp_report 04:26:38 INFO - PID 14435 | __start_cc_report 04:26:38 INFO - PID 14435 | _x_x_mozilla_cycle_collect,694 04:26:38 INFO - PID 14435 | __end_cc_report 04:26:38 INFO - PID 14435 | __startTimestamp1507029997996__endTimestamp 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | ------- Summary: start ------- 04:26:38 INFO - PID 14435 | Number of tests: 14 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | [#0] ai-astar Cycles:10 Average:105.60 Median:106.00 stddev:0.70 (0.7%) stddev-sans-first:0.53 04:26:38 INFO - PID 14435 | Values: 107.0 105.0 106.0 105.0 106.0 106.0 106.0 105.0 105.0 105.0 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | [#1] audio-beat-detection Cycles:10 Average:116.40 Median:113.50 stddev:10.65 (9.4%) stddev-sans-first:11.26 04:26:38 INFO - PID 14435 | Values: 114.0 111.0 111.0 112.0 117.0 117.0 111.0 113.0 112.0 146.0 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | [#2] audio-dft Cycles:10 Average:163.20 Median:162.50 stddev:3.22 (2.0%) stddev-sans-first:3.39 04:26:38 INFO - PID 14435 | Values: 162.0 163.0 163.0 162.0 161.0 162.0 162.0 161.0 164.0 172.0 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | [#3] audio-fft Cycles:10 Average:74.00 Median:73.50 stddev:1.70 (2.3%) stddev-sans-first:1.80 04:26:38 INFO - PID 14435 | Values: 74.0 73.0 73.0 74.0 73.0 73.0 76.0 73.0 78.0 73.0 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:77.50 stddev:1.35 (1.7%) stddev-sans-first:1.42 04:26:38 INFO - PID 14435 | Values: 78.0 77.0 81.0 77.0 78.0 78.0 77.0 77.0 77.0 76.0 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:114.00 stddev:1.34 (1.2%) stddev-sans-first:0.71 04:26:38 INFO - PID 14435 | Values: 110.0 114.0 113.0 113.0 114.0 115.0 114.0 113.0 114.0 113.0 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | [#6] imaging-darkroom Cycles:10 Average:150.20 Median:150.00 stddev:1.48 (1.0%) stddev-sans-first:1.50 04:26:38 INFO - PID 14435 | Values: 149.0 149.0 154.0 151.0 150.0 150.0 150.0 149.0 150.0 150.0 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 04:26:38 INFO - PID 14435 | Values: 93.0 95.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | [#8] json-parse-financial Cycles:10 Average:44.20 Median:44.00 stddev:1.14 (2.6%) stddev-sans-first:1.20 04:26:38 INFO - PID 14435 | Values: 44.0 44.0 44.0 44.0 43.0 47.0 44.0 45.0 44.0 43.0 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | [#9] json-stringify-tinderbox Cycles:10 Average:36.00 Median:36.00 stddev:0.47 (1.3%) stddev-sans-first:0.50 04:26:38 INFO - PID 14435 | Values: 36.0 36.0 36.0 36.0 36.0 36.0 37.0 35.0 36.0 36.0 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | [#10] stanford-crypto-aes Cycles:10 Average:68.90 Median:69.00 stddev:1.97 (2.9%) stddev-sans-first:2.06 04:26:38 INFO - PID 14435 | Values: 68.0 66.0 67.0 68.0 72.0 69.0 69.0 72.0 70.0 68.0 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | [#11] stanford-crypto-ccm Cycles:10 Average:105.30 Median:106.00 stddev:2.83 (2.7%) stddev-sans-first:2.67 04:26:38 INFO - PID 14435 | Values: 109.0 106.0 101.0 108.0 106.0 105.0 101.0 103.0 106.0 108.0 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.20 Median:150.00 stddev:4.16 (2.8%) stddev-sans-first:3.84 04:26:38 INFO - PID 14435 | Values: 156.0 150.0 156.0 155.0 146.0 145.0 146.0 149.0 149.0 150.0 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:61.50 stddev:3.59 (5.8%) stddev-sans-first:3.79 04:26:38 INFO - PID 14435 | Values: 62.0 61.0 60.0 59.0 61.0 69.0 57.0 63.0 62.0 56.0 04:26:38 INFO - PID 14435 | -------- Summary: end -------- 04:26:38 INFO - PID 14435 | 04:26:38 INFO - PID 14435 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:26:38 INFO - TEST-INFO | 14435: exit 0 04:26:39 INFO - TEST-OK | kraken | took 60116ms 04:26:39 INFO - SUITE-END | took 1169s 04:26:39 INFO - Completed test suite (00:19:29) 04:26:39 INFO - javascript benchmark 04:26:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9203.691697564826, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3891.844326226951, 3921.490206273184, 3926.901827454315, 3946.605339466052, 3966.504186976628, 6131.110988906606, 6247.562670613053, 6299.811005669837, 6351.587146835464, 6423.582467139633, 6290.591111577744, 6332.525060620449, 6395.424411802936, 6399.776007839727, 6468.126802881609, 5660.745266462998, 5671.517920976722, 5681.11942648888, 5701.0, 5733.512651424634, 5599.514900892665, 5661.2923384576925, 5686.914696279559, 5706.971465142668, 5778.855528611776, 5344.198370244456, 5730.7707691692285, 5736.3403208631125, 5766.884662306751, 5788.594798364116, 5498.917516237219, 5575.0522411190195, 5589.0, 5620.325560932714, 5685.857853553653, 5574.526165275956, 5631.324241091095, 5656.519195868356, 5674.943250567483, 5675.829725108253, 6250.374962503759, 6325.304216536178, 6363.949948258532, 6408.359164083555, 6409.583377080521, 6776.42400395967, 6796.932030679729, 6811.114555107853, 6879.0, 6898.896516552256, 6796.35434633714, 6906.102206713145, 6930.826729331757, 6931.514793964425, 6945.0, 6173.938260617426, 6267.71795269214, 6331.588446750947, 6394.232692076934, 6465.418112369815, 7095.148582170069, 7103.822404439827, 7162.570245785269, 7182.030425892543, 7190.9280907191305, 6695.866082678319, 6764.966175169093, 6771.390574848188, 6785.558938668971, 6853.554518956352, 6837.897431538531, 6889.448844092461, 6916.0, 6937.098177236927, 6940.30596940312, 6774.898376524356, 6790.151231096113, 6847.075644787989, 6860.656967151623, 6862.4853136015, 6808.4212841908, 6810.080639113657, 6894.00036994633, 6897.689603967833, 6910.689018994158, 4904.386951631046, 4926.359573255526, 4988.351514303117, 5020.221865610836, 5030.421501527371, 4419.204543182258, 4506.977465112654, 4509.503954564995, 4521.841735539306, 4537.22867112585, 6150.231221097363, 6159.84600384985, 6175.536834737323, 6236.688165591702, 6241.407066328691, 6686.264510903797, 6746.460283177432, 6786.287439818748, 6790.898136527956, 6791.524593278423, 6263.279722831932, 6284.622922624657, 6328.17733694626, 6351.301356850743, 6388.936110638834, 5521.834344969658, 5587.3015873015875, 5602.271704678447, 5603.663780173202, 5608.298962629671, 7061.71753129869, 7070.611116388545, 7073.717051317993, 7166.605836678928, 7184.066071410683, 6679.9332006679315, 6795.490338224554, 6799.93200067993, 6800.625965571941, 6805.115335006418, 6991.860162796817, 7006.159260888726, 7015.263397343206, 7114.57312561248, 7150.284971502808, 6586.901196482152, 6697.497687673346, 6702.262751097473, 6840.760573379908, 6858.3141685831015, 6678.23200331968, 6830.93169068303, 6843.3840954314355, 6844.247132815486, 6854.691538880663, 6609.867802643824, 6616.503762217757, 6679.699413526306, 6698.83252918681, 6703.3296670332575, 6517.217933847968, 6603.603783772989, 6620.966895165493, 6633.402993730588, 6733.091032710718, 6454.19322584677, 6574.211094668479, 6576.112224849584, 6586.209654841258, 6610.041543976193, 6645.900311495235, 6704.262531121475, 6729.528932974645, 6743.29195434492, 6769.120014398097, 6375.394337537927, 6452.322506136788, 6454.903176452263, 6547.9672601638595, 6551.80344589663], "value": 6242.177353547562, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [919.1681528216964, 958.8322043642363, 958.8561715742637, 1006.6325791086247, 1013.8428543575744, 45415.0, 46571.534284657144, 46969.53030469694, 47045.0, 47090.764546177306, 43312.13375732483, 47379.0, 48003.0, 48123.27815082776, 48190.75904620472, 8549.358798090152, 8687.56562171892, 8690.478571285712, 8691.652333906668, 8696.826063478758, 8147.551884646342, 8369.83260334793, 8382.706605268817, 8395.202455766692, 8404.915950840474, 8407.075221725605, 8418.705345312916, 8436.831263374728, 8458.577071146448, 8467.576621168948, 8324.62539185738, 8389.622466989, 8410.663573457115, 8415.537145456998, 8416.537090460022, 8452.0, 8456.534890581015, 8459.280961118251, 8474.364422668325, 8516.0, 41532.79233603843, 41976.58023419757, 42022.78988605068, 42030.78984605088, 42232.57767422348, 3709.9072523186865, 3721.4417837324618, 3742.7380083394173, 3748.7938163401004, 3753.249350129985, 34478.48282275768, 34692.132696682784, 34844.82577587121, 34985.0, 35043.299134017434, 5296.0, 5304.814331498417, 5356.866078348073, 5510.008198524264, 5532.225488431623, 240040.0, 241925.0, 242186.0, 244070.0, 248541.0, 57188.42811571914, 57547.424525754206, 57783.42216577865, 57825.71087144537, 57837.0, 59714.10428843571, 59829.70085149634, 59925.700371497864, 60038.399616003284, 60070.098948515806, 17403.73894391585, 17448.214830332665, 17488.737668934977, 17564.91217543904, 17645.38241161553, 14182.361793719101, 14349.067310624776, 14351.569452916428, 14421.206833624252, 14472.85527144736, 14775.0, 14859.405623775145, 14879.404823806926, 14968.176750278839, 15030.549083527512, 5577.553795696334, 5581.358143813432, 5597.132444471114, 5664.631798933138, 5694.373618901917, 1074.435921141407, 1076.822324316479, 1081.6754973507916, 1085.3921803789904, 1090.0, 25880.352991175372, 25924.74075259261, 26117.0, 26143.346416339362, 26165.476690466083, 1165.959191428296, 1184.5735535207318, 1190.2501424102759, 1202.537023246039, 1202.8316035755001, 13618.863811361958, 13631.250281234627, 13823.101498402571, 13853.0, 13954.162750235018, 314823.0, 316656.0, 316697.0, 316768.0, 316980.0, 61241.0, 61883.38116618776, 62084.68957655183, 62185.37814621796, 62513.37486625076, 1477.4607268346977, 1492.298619648785, 1515.4998850379347, 1529.5411376587201, 1536.4929573240804, 25564.23307300784, 25695.743042570084, 25868.741312586633, 25919.0, 26096.347591309608, 42559.0, 42621.36067959045, 42634.78682606567, 42665.36001960035, 42680.57319426766, 256575.0, 257272.0, 258534.0, 259172.0, 260366.0, 38583.2283354337, 42695.0, 43103.35344969891, 43393.783031085906, 44000.55999439965, 53073.734631326595, 54787.17819232794, 54976.450235498734, 55069.44930550804, 55148.17277740757, 39711.0, 39918.60081399265, 39972.40041399439, 40039.0, 40120.39819402769, 39215.64706117642, 39318.213635726555, 39462.80268598639, 39566.802165988986, 39740.80129599333, 46577.301340479244, 46785.532144678116, 46990.29514557216, 47015.0, 47051.0, 37584.0, 37626.2474750498, 38267.808660956514, 38472.80763596164, 38563.0, 27823.582646260726, 28574.285642859093, 28693.569596456487, 28781.0, 28884.42231155407], "value": 19627.460008585247, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.9013462778641, 612.2775125352089, 618.6659204029827, 639.146739103297, 657.2441692054135, 23126.0, 23229.651555226686, 24029.639555406684, 24064.518709625798, 24099.518009639818, 25005.0, 26113.34716632085, 26137.86931065342, 26141.47717045658, 26161.47677046458, 6766.966165169168, 6946.131733533309, 6946.131733533309, 6952.0, 6952.374286314231, 6491.350864913495, 6732.697028633724, 6779.457643388517, 6782.660866956632, 6784.3554862288, 6731.225909020452, 6731.596104233738, 6732.95639175926, 6736.360045795666, 6736.46108311334, 6688.264290927994, 6688.398044175999, 6708.530402871801, 6725.092112564815, 6750.662466876661, 6279.96860015697, 6301.40136687014, 6302.18071650687, 6309.400606942379, 6312.400321969406, 25251.873740631363, 25424.237272881845, 25431.237062887958, 27959.30101747452, 30149.246268843235, 2291.679164916913, 2306.850054746436, 2315.1086831569796, 2315.548468048731, 2315.710536182977, 20448.897755511127, 21022.36932892016, 21040.368788936357, 21157.68263476034, 21207.575848482946, 2469.925902222936, 2534.4170840706743, 2561.5773397389407, 2592.3519120219944, 2623.121254379766, 143254.0, 146423.0, 163837.0, 164346.0, 170643.1467842653, 16127.919360403122, 16432.342706291853, 16497.617988312366, 16710.331586736636, 17404.216810243568, 27632.0, 27637.447251054866, 27649.308767280978, 27684.861575691993, 27735.72264277372, 10406.531706073245, 10409.583616655249, 10455.738606534898, 10514.526846291936, 10711.357318560911, 9359.953200233955, 9384.436933783994, 9414.576344064397, 9421.670241541513, 9531.475768832643, 9760.707178784647, 10109.545070471846, 10270.33242839213, 10342.259301285165, 10476.319039262577, 2950.2919299367995, 2978.8659510322086, 2999.3701322722036, 3009.179446434919, 3091.1962889648403, 1049.8425236214475, 1070.3898777696793, 1074.1460538871615, 1091.0398849012818, 1092.5411327242577, 20904.0, 20932.686009709865, 20988.58022839565, 21231.575368492544, 21705.131794728033, 1113.6603335982604, 1117.1453837814079, 1129.949152288149, 1134.6142311614092, 1136.9772604547863, 11482.770344593062, 11626.0, 11632.476538555786, 11686.70783230426, 11765.588204412805, 615590.0, 625793.0, 641179.3823092658, 641277.0, 650902.0, 135103.0, 137487.0, 139629.3018534901, 139763.0, 141085.0, 1199.2684462477882, 1214.1318956945827, 1229.2931564350533, 1236.863944966071, 1240.4851986425713, 18741.812581874008, 18904.432867014013, 18963.715544267125, 18972.335968241045, 19156.13797379149, 63725.40686482875, 64122.679386602766, 64563.0, 65068.0, 65993.34006660063, 82891.5855420719, 83204.58397707973, 83237.0, 84174.0, 85727.0, 15460.613484662974, 15596.922015390304, 15679.921600391925, 15759.527214183592, 15763.133027683587, 23382.883085584464, 23555.764442355357, 23560.410989725395, 23769.286921392384, 23868.52262954766, 19068.141933612744, 19562.413127606196, 19721.309754158534, 19777.901110494357, 19783.50541236423, 17117.0, 17143.0, 17573.824261757218, 17912.373066943117, 18028.0, 19418.8058119417, 19477.318293859647, 19774.901125494856, 19847.503812404804, 19866.90066549658, 15265.389384424943, 15375.461858834888, 15376.615584610474, 15402.768958465856, 15467.5359739208, 18546.536336591154, 18941.810581894006, 19001.144948477075, 19130.52173695669, 19199.232030718616], "value": 12876.978928998222, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1411.5906387147727, 1468.7062587482492, 1489.2256026866023, 1509.5094094419317, 1526.816781986162, 16562.171891405425, 17326.133693315347, 17350.219240134193, 17350.566235844093, 17369.56576085597, 16856.915715421437, 16857.0, 16873.74689379657, 17005.234764435598, 17022.574435639115, 2874.080294305823, 2899.9565006524867, 2969.198316454556, 2969.2576855786056, 2970.7474864636533, 2858.556923676823, 2920.9707902920914, 2954.1285320830298, 2954.6454425468974, 2956.1574951138928, 2882.7261410166, 2994.8203107813492, 2996.2509372656837, 2997.310618557733, 3004.218903085192, 2953.3059730963205, 2954.335274563217, 2975.642922849272, 2977.434287485371, 2995.311078451957, 2882.351470919047, 2885.7691384689383, 2917.2706823294175, 2929.7509711674534, 2930.106317573139, 16504.33982640693, 16902.0, 16906.239219235165, 16916.323347066103, 16957.49127526176, 8196.83606327876, 8496.235338819535, 8498.915010849874, 8499.320054395695, 8502.447340922881, 5463.098588732854, 5565.2122238564225, 5613.129964855413, 5687.544996400278, 5709.229254050691, 2074.844386671006, 2093.75921768997, 2095.2038225474375, 2095.559932414195, 2149.0651566568595, 30572.08283751491, 30778.0, 30800.537991930138, 30823.38353232945, 30860.53709194364, 7554.0, 7630.7329243476215, 7681.846363072819, 7732.9226707732205, 7767.2232776723, 20346.593068138554, 20423.978801059886, 20590.176392944413, 20597.58804823895, 20628.896855515628, 8590.797288379632, 8802.955985220033, 8832.55837208137, 8872.73381798547, 8878.200961913459, 7742.7677169685, 7793.376529877596, 7797.142314345418, 7835.451518393657, 7850.842983140419, 5728.369879313273, 5976.641401515836, 6003.249593800775, 6024.83106081819, 6058.666773327509, 1639.5163426789127, 1694.4154266777941, 1734.5230061733125, 1762.814904435016, 1768.0717623247897, 940.1444685336311, 954.513198268874, 963.8578284732614, 975.3660120921317, 985.4235272365746, 8879.822403551892, 8898.911010889939, 8941.731748047569, 9021.864672029793, 9141.77145571366, 755.8526087412902, 760.1106705154982, 765.3188662090744, 778.9532628042335, 847.3475423923544, 5480.972595136999, 5507.697076660822, 5510.393856675762, 5544.085225937675, 5774.682392468455, 24025.0, 24981.0, 25077.37306567314, 25168.119115830857, 25246.0, 23510.529789404118, 24339.756602434103, 24821.1312604058, 24841.130560430298, 24936.750632493804, 12362.505499780087, 12492.81260781089, 12499.312537810325, 12515.874841251652, 12669.366531673379, 8579.571021449028, 8584.699535516227, 8697.34769892248, 8908.242799362128, 9062.365634405529, 19897.104630291382, 20093.49766255797, 20512.0, 20608.381748547567, 20869.165233390493, 20956.056977436358, 21096.367108986757, 21184.15263389447, 21338.0, 21881.234156804436, 12514.499420023098, 12532.060095493056, 12689.746205076031, 12707.110502264753, 12712.6821829455, 15069.0, 15344.923275383551, 15372.077675339515, 15375.461858834888, 15389.38442462289, 12130.818037729618, 12321.0, 12321.383930803604, 12473.812892806434, 12609.432575534307, 11452.82820757706, 11668.70828229301, 11843.822342664695, 11871.643850684493, 12160.148789584644, 10072.093511583993, 10078.445685487368, 10226.028527289896, 10334.638287659896, 10339.844902326622, 7884.645190966306, 7932.127465978853, 7961.721339753312, 7983.441159118806, 8113.229243221885, 8216.671333146607, 8223.917760822314, 8250.257476827115, 8266.008079030555, 8290.29532489721], "value": 7287.613820839236, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.2354609504124, 564.404553196378, 575.8013485347557, 592.8428966323924, 602.8402473344564, 6135.81592552223, 6136.354553810494, 6191.50467962563, 6314.810555683337, 6330.905036424446, 6162.414570615795, 6174.567780255373, 6248.812535623939, 6253.562250642445, 6256.749730010794, 5150.407703114133, 5166.819161329354, 5179.979807874932, 5192.636515443921, 5207.427183009866, 5209.400918894319, 5269.288646032794, 5272.525472707455, 5272.762725677354, 5323.920141197885, 5156.639035267533, 5228.215767634866, 5242.868928276805, 5263.26314315996, 5288.836455979678, 5191.896162076756, 5198.454162312941, 5226.425093239741, 5232.372115346145, 5234.450382709837, 4925.581325587329, 4949.628777841676, 4969.975150124263, 4989.151844186461, 4996.1756310208775, 6241.906371404432, 6246.812595622138, 6251.687415629201, 6278.591891527036, 6366.522510811661, 6114.602550834226, 6144.670988881459, 6161.691915404212, 6215.378462153793, 6250.34371391002, 6719.966400168017, 6753.4597232221795, 6765.830854228634, 6801.897971530431, 6834.692438840264, 5286.444923283077, 5354.41101478837, 5422.105352616813, 5459.0, 5469.206964990054, 6865.965670171667, 6870.175578930561, 6901.861962760717, 7020.719171233196, 7063.858722825617, 5649.0, 5674.184261036511, 5678.574106941914, 5685.602007859493, 5694.117411801178, 5806.506446951972, 5820.0, 5829.650220986669, 5845.473907348359, 5880.0015989127805, 6193.721282542297, 6200.81397558074, 6208.888977747588, 6250.624962502265, 6261.373862613793, 6644.900326495106, 6783.152105986752, 6785.846406110973, 6794.592324460548, 6794.898076528856, 1969.1138987455702, 1992.4819546917906, 2008.2067583304804, 2025.8075482829108, 2056.167252262836, 1464.7070585882652, 1506.517914267424, 1509.5055894664422, 1510.3656464285027, 1524.4131009561177, 6046.0, 6046.697665116727, 6090.604110732788, 6221.4089661482085, 6301.338359472281, 6567.244766851778, 6637.1703537057865, 6690.163729533809, 6758.966205169041, 6864.0, 6109.297430795426, 6112.266528016667, 6147.66187859672, 6220.502359811204, 6302.338254483305, 3531.2231309111953, 3537.0, 3551.751377403557, 3557.839897204632, 3576.338377400189, 6905.41175529631, 6938.132733408324, 6955.478339124586, 7045.436365090781, 7052.153741551047, 6711.530192886451, 6876.587404755631, 6900.447964162855, 6977.441804655616, 7018.964905175441, 6897.3447522485285, 6918.654067296515, 6937.571849852511, 6949.791506254821, 7023.719051237996, 6606.867862642816, 6663.7001334941015, 6693.263740988585, 6718.93281067183, 6730.158730158731, 6682.398584127452, 6700.932990670226, 6705.128333316833, 6752.189737231563, 6774.491913106438, 7057.894131588133, 7072.25741297177, 7094.290570942864, 7110.57336559808, 7125.3230943060325, 5568.470995255444, 5714.228579141763, 5726.627769194989, 5768.855778605568, 5770.596058276048, 6560.770373036921, 6564.573302735307, 6795.592264464148, 6816.7614133505085, 6817.420519255918, 6490.578112422678, 6548.214214294315, 6568.770093046721, 6613.0, 6627.0, 6522.478201743944, 6561.1470508833545, 6571.80284591463, 6617.503687223381, 6619.304972977768, 6301.0, 6343.777967771106, 6413.134226879311, 6467.579607325509, 6569.57297775643, 6554.296822295388, 6590.0, 6605.801825945229, 6657.933420665731, 6691.76578819739, 6237.908366036017, 6417.807465776034, 6454.903176452263, 6481.736061467968, 6552.213734351908], "value": 5335.001939333854, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [952.1811242331598, 987.3582171588467, 997.9650712225074, 1003.097212508742, 1018.9082982531573, 20468.69296960547, 20925.162993480262, 20971.580568388625, 20991.580168396642, 21033.05351259193, 19294.51763705908, 19430.222791088374, 19528.902355488237, 20136.19455221793, 20300.390988270337, 1047.0785708576443, 1074.0387353318774, 1074.7366895110708, 1074.8925107489267, 1075.6450371377466, 1050.1179009631908, 1069.8074346617645, 1072.2387105155349, 1073.3828048871892, 1091.8635170603675, 1138.6527109231722, 1143.0798207443006, 1143.6626195272415, 1146.3007565385108, 1149.1151813103904, 1081.9783604327947, 1138.7551676389617, 1139.8518192634988, 1141.0928311991986, 1144.3934714601232, 806.9838603227903, 809.5021561739546, 810.1614828652375, 810.4205493072423, 811.7605306434616, 21673.0, 21808.563828723494, 21872.343829685135, 21875.0, 21876.124955001782, 22567.77432225673, 23506.76493235063, 23708.762912371, 23778.881105594533, 23851.403714907094, 18298.45104646863, 18994.62010759777, 19147.61704765897, 19184.90407547953, 19203.903980480147, 3989.5412027616617, 4004.039030632627, 4008.158286759784, 4009.0, 4010.5588385277597, 169018.0, 169357.15321423436, 172250.0, 172415.0, 173487.0, 18093.0, 18160.636787264182, 18267.36064237772, 18292.908535457238, 18383.448496545127, 33365.332693346, 33613.15967100842, 33679.0, 33937.15157121042, 33977.0, 8575.95712021436, 8829.69096081634, 8844.248238899765, 8844.292456603456, 8849.646014159362, 4370.956290437119, 4581.702189357681, 4599.517050709695, 4630.560096790799, 4644.256918893028, 6227.221597300338, 6319.146915166486, 6334.271558770801, 6382.297947225801, 6834.760783372658, 1252.974940501203, 1260.3068312428127, 1279.8848103670714, 1290.0582574720506, 1297.7274772297924, 736.5543845973216, 738.5162718419443, 756.3722110648149, 759.7492827367066, 762.0208032677982, 12793.912517436122, 12805.103642745105, 13047.217166970013, 13090.14914030585, 13521.18872867631, 717.2039036669292, 718.7017387784011, 721.7618185998608, 742.8885667149863, 743.18992298395, 5857.887001469792, 5994.550408719277, 6018.969905150446, 6063.878722425615, 6079.635221886701, 151989.0, 154818.0, 157143.0, 157803.18811475404, 158609.0, 133178.0, 138781.0, 138946.0, 139498.0, 143634.0, 121412.0, 123081.38459307647, 123493.0, 125837.0, 125948.37025814812, 11020.787713351685, 11077.83383249268, 11103.278286911325, 11160.660720713566, 11403.543858245908, 54189.729051354494, 54395.72802135964, 55536.72231638816, 56048.15927761168, 56371.43628563662, 71084.0, 71805.92291115742, 72079.63960180165, 72462.27537724766, 73149.0, 15982.120983346027, 16099.195040247707, 16148.838511614733, 16344.346226151296, 16364.34542618282, 19060.714089288394, 19064.904675476533, 19265.325713599956, 19297.807021929602, 19320.613587728447, 14734.26328683583, 14853.851461485247, 14912.179830109015, 15106.848931510543, 15138.848611514184, 15711.0, 15905.125218112653, 15933.601659958595, 16007.519774406786, 16075.19624018771, 17392.217350219522, 17478.0, 17509.649807004043, 17614.647707046042, 17688.204030818386, 12660.0, 12712.872871271169, 12723.23660580368, 12723.681907952376, 12855.742885142432, 12602.0, 12657.810132848199, 12785.936070319589, 12854.871451285368, 12907.031972601904], "value": 9908.5732407581, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1359.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 105.0, 106.0, 105.0, 106.0, 106.0, 106.0, 105.0, 105.0, 105.0], "value": 105.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 111.0, 111.0, 112.0, 117.0, 117.0, 111.0, 113.0, 112.0, 146.0], "value": 116.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 162.0, 161.0, 162.0, 162.0, 161.0, 164.0, 172.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 74.0, 73.0, 73.0, 76.0, 73.0, 78.0, 73.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 81.0, 77.0, 78.0, 78.0, 77.0, 77.0, 77.0, 76.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 113.0, 113.0, 114.0, 115.0, 114.0, 113.0, 114.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 154.0, 151.0, 150.0, 150.0, 150.0, 149.0, 150.0, 150.0], "value": 150.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 44.0, 44.0, 44.0, 43.0, 47.0, 44.0, 45.0, 44.0, 43.0], "value": 44.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 36.0, 36.0, 36.0, 36.0, 36.0, 37.0, 35.0, 36.0, 36.0], "value": 36.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 68.0, 72.0, 69.0, 69.0, 72.0, 70.0, 68.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 106.0, 101.0, 108.0, 106.0, 105.0, 101.0, 103.0, 106.0, 108.0], "value": 105.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 150.0, 156.0, 155.0, 146.0, 145.0, 146.0, 149.0, 149.0, 150.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 60.0, 59.0, 61.0, 69.0, 57.0, 63.0, 62.0, 56.0], "value": 61.0, "unit": "score"}]}]} 04:26:39 INFO - Return code: 0 04:26:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:26:39 INFO - # TBPL SUCCESS # 04:26:39 INFO - Running post-action listener: _package_coverage_data 04:26:39 INFO - Running post-action listener: _resource_record_post_action 04:26:39 INFO - [mozharness: 2017-10-03 11:26:39.297065Z] Finished run-tests step (success) 04:26:39 INFO - Running post-run listener: _resource_record_post_run 04:26:39 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 12288; Write bytes: 401330176; Read time: 1216; Write time: 646044 04:26:39 INFO - TinderboxPrint: CPU usage
13.1% 04:26:39 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 1,216 04:26:39 INFO - TinderboxPrint: I/O write bytes / time
401,330,176 / 646,044 04:26:39 INFO - TinderboxPrint: CPU idle
8,230.0 (86.9%) 04:26:39 INFO - TinderboxPrint: CPU user
1,189.3 (12.6%) 04:26:39 INFO - TinderboxPrint: Swap in / out
0 / 0 04:26:39 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 4096; Write bytes: 75567104; Read time: 388; Write time: 93384 04:26:39 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:26:39 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 8192; Write bytes: 325763072; Read time: 828; Write time: 552660 04:26:39 INFO - Running post-run listener: _upload_blobber_files 04:26:39 INFO - Blob upload gear active. 04:26:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:26:39 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:26:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:26:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:26:40 INFO - (blobuploader) - INFO - Open directory for files ... 04:26:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:26:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:26:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:26:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:26:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:26:41 INFO - (blobuploader) - INFO - Done attempting. 04:26:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:26:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:26:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:26:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:26:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:26:43 INFO - (blobuploader) - INFO - Done attempting. 04:26:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:26:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:26:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:26:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:26:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:26:44 INFO - (blobuploader) - INFO - Done attempting. 04:26:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:26:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:26:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:27:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:27:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:27:12 INFO - (blobuploader) - INFO - Done attempting. 04:27:12 INFO - (blobuploader) - INFO - Iteration through files over. 04:27:12 INFO - Return code: 0 04:27:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:27:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:27:12 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/976db9aea3231da0965768b768496015ed0845fc5ef8332988263f8b537d3d7535204d0591d485d7667201424c9aa9f86e6688faf8f801c555de56f0c5440894", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/180efcfc85d1904f14aa1751b6defcc3c2913586427ce56d09c36a96bd67388c5f025c37da5175ee47264ed7cf7c0da89a3e5e3db8e69a68701800d20f8045dc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab24fc78e0bc964bdded8b6ad9cf033b838ae56b18d549d6a9c304dd88ea0c05b7c6bd8153f5fa071856a78e6e07067d7a664caefaabf6d42535a027bb382849", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a58c60d4435d798bd4f29711d98ac29db9cd56881904c6c4066c7b1d3d0c61c33371ec9f430ff4ad16564619ca4d5809e7ec5d782a63c4d9838d9218db82ab40"} 04:27:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:27:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:27:12 INFO - Contents: 04:27:12 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/976db9aea3231da0965768b768496015ed0845fc5ef8332988263f8b537d3d7535204d0591d485d7667201424c9aa9f86e6688faf8f801c555de56f0c5440894", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/180efcfc85d1904f14aa1751b6defcc3c2913586427ce56d09c36a96bd67388c5f025c37da5175ee47264ed7cf7c0da89a3e5e3db8e69a68701800d20f8045dc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab24fc78e0bc964bdded8b6ad9cf033b838ae56b18d549d6a9c304dd88ea0c05b7c6bd8153f5fa071856a78e6e07067d7a664caefaabf6d42535a027bb382849", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a58c60d4435d798bd4f29711d98ac29db9cd56881904c6c4066c7b1d3d0c61c33371ec9f430ff4ad16564619ca4d5809e7ec5d782a63c4d9838d9218db82ab40"} 04:27:12 INFO - Running post-run listener: copy_logs_to_upload_dir 04:27:12 INFO - Copying logs to upload dir... 04:27:12 INFO - mkdir: /builds/slave/test/build/upload/logs 04:27:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.363004 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-10-03 04:27:12.462544) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 04:27:12.466192) ========= 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=ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/976db9aea3231da0965768b768496015ed0845fc5ef8332988263f8b537d3d7535204d0591d485d7667201424c9aa9f86e6688faf8f801c555de56f0c5440894", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/180efcfc85d1904f14aa1751b6defcc3c2913586427ce56d09c36a96bd67388c5f025c37da5175ee47264ed7cf7c0da89a3e5e3db8e69a68701800d20f8045dc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab24fc78e0bc964bdded8b6ad9cf033b838ae56b18d549d6a9c304dd88ea0c05b7c6bd8153f5fa071856a78e6e07067d7a664caefaabf6d42535a027bb382849", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a58c60d4435d798bd4f29711d98ac29db9cd56881904c6c4066c7b1d3d0c61c33371ec9f430ff4ad16564619ca4d5809e7ec5d782a63c4d9838d9218db82ab40"} build_url:https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011366 build_url: 'https://queue.taskcluster.net/v1/task/LJwLOi3zTVSndmo25vI-Gw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/976db9aea3231da0965768b768496015ed0845fc5ef8332988263f8b537d3d7535204d0591d485d7667201424c9aa9f86e6688faf8f801c555de56f0c5440894", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/180efcfc85d1904f14aa1751b6defcc3c2913586427ce56d09c36a96bd67388c5f025c37da5175ee47264ed7cf7c0da89a3e5e3db8e69a68701800d20f8045dc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab24fc78e0bc964bdded8b6ad9cf033b838ae56b18d549d6a9c304dd88ea0c05b7c6bd8153f5fa071856a78e6e07067d7a664caefaabf6d42535a027bb382849", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a58c60d4435d798bd4f29711d98ac29db9cd56881904c6c4066c7b1d3d0c61c33371ec9f430ff4ad16564619ca4d5809e7ec5d782a63c4d9838d9218db82ab40"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 04:27:12.495013) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 04:27:12.495326) ========= 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=ba164b0a7b0ec7655860e956000001c1-1507027812.211068-784691898 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004547 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 04:27:12.576750) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 04:27:12.577077) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 04:27:12.577426) ========= ========= Total master_lag: 0.19 =========