builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-054 starttime: 1505644149.18 results: success (0) revision: faa897d7948b7e2439573f39c34366c138913663 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:09.176481) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:09.176975) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:09.177262) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:09.387812) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:09.388143) ========= 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=cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-17 03:29:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2017-09-17 03:29:09 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137033 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:09.604213) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:09.604653) ========= 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=cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021122 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:09.703614) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-17 03:29:09.704058) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev faa897d7948b7e2439573f39c34366c138913663 --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 faa897d7948b7e2439573f39c34366c138913663 --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=cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-17 03:29:09,970 truncating revision to first 12 chars 2017-09-17 03:29:09,970 Setting DEBUG logging. 2017-09-17 03:29:09,970 attempt 1/10 2017-09-17 03:29:09,970 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/faa897d7948b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-17 03:29:10,675 sleeping for 9.00s (attempt 1/10) 2017-09-17 03:29:19,684 attempt 2/10 2017-09-17 03:29:19,684 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/faa897d7948b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-17 03:29:20,988 unpacking tar archive at: mozilla-central-faa897d7948b/testing/mozharness/ program finished with exit code 0 elapsedTime=11.335080 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-17 03:29:21.082965) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:21.083262) ========= script_repo_revision: faa897d7948b7e2439573f39c34366c138913663 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:21.083625) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:21.086970) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:21.100714) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-17 03:29:21.101095) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:29:21 INFO - MultiFileLogger online at 20170917 03:29:21 in /builds/slave/test 03:29:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 03:29:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:29:21 INFO - {'append_to_log': False, 03:29:21 INFO - 'base_work_dir': '/builds/slave/test', 03:29:21 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:29:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:29:21 INFO - 'branch': 'Firefox-Non-PGO', 03:29:21 INFO - 'buildbot_json_path': 'buildprops.json', 03:29:21 INFO - 'code_coverage': False, 03:29:21 INFO - 'config_files': ('talos/linux_config.py',), 03:29:21 INFO - 'default_actions': ('clobber', 03:29:21 INFO - 'read-buildbot-config', 03:29:21 INFO - 'download-and-extract', 03:29:21 INFO - 'populate-webroot', 03:29:21 INFO - 'create-virtualenv', 03:29:21 INFO - 'install', 03:29:21 INFO - 'setup-mitmproxy', 03:29:21 INFO - 'run-tests'), 03:29:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:29:21 INFO - 'disable_ccov_upload': False, 03:29:21 INFO - 'disable_stylo': False, 03:29:21 INFO - 'download_minidump_stackwalk': True, 03:29:21 INFO - 'download_symbols': 'ondemand', 03:29:21 INFO - 'enable_stylo': False, 03:29:21 INFO - 'enable_webrender': False, 03:29:21 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:29:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:29:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:29:21 INFO - 'gecko_profile': False, 03:29:21 INFO - 'gecko_profile_interval': 0, 03:29:21 INFO - 'installer_path': 'installer.exe', 03:29:21 INFO - 'log_level': 'info', 03:29:21 INFO - 'log_name': 'talos', 03:29:21 INFO - 'log_to_console': True, 03:29:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:29:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:29:21 INFO - 'opt_config_files': (), 03:29:21 INFO - 'pip_index': False, 03:29:21 INFO - 'suite': 'dromaeojs-e10s', 03:29:21 INFO - 'system_bits': '32', 03:29:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:29:21 INFO - 'title': 'talos-linux64-ix-054', 03:29:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:29:21 INFO - 'use_talos_json': True, 03:29:21 INFO - 'verify': 'False', 03:29:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:29:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:29:21 INFO - 'work_dir': 'build'} 03:29:21 INFO - [mozharness: 2017-09-17 10:29:21.493112Z] Running clobber step. 03:29:21 INFO - Running pre-action listener: _resource_record_pre_action 03:29:21 INFO - Running main action method: clobber 03:29:21 INFO - rmtree: /builds/slave/test/build 03:29:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:29:22 INFO - Running post-action listener: _resource_record_post_action 03:29:22 INFO - [mozharness: 2017-09-17 10:29:22.973587Z] Finished clobber step (success) 03:29:22 INFO - [mozharness: 2017-09-17 10:29:22.973685Z] Running read-buildbot-config step. 03:29:22 INFO - Running pre-action listener: _resource_record_pre_action 03:29:22 INFO - Running main action method: read_buildbot_config 03:29:22 INFO - Using buildbot properties: 03:29:22 INFO - { 03:29:22 INFO - "project": "", 03:29:22 INFO - "product": "firefox", 03:29:22 INFO - "who": "archaeopteryx@coole-files.de", 03:29:22 INFO - "installer_path": "public/build/target.tar.bz2", 03:29:22 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:29:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:29:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:29:22 INFO - "stage_platform": "linux64", 03:29:22 INFO - "basedir": "/builds/slave/test", 03:29:22 INFO - "buildnumber": 839, 03:29:22 INFO - "platform": "ubuntu64_hw", 03:29:22 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:29:22 INFO - "slavebuilddir": "test", 03:29:22 INFO - "taskId": "Oi5dh-MYSR2_TGAX1QvJsQ", 03:29:22 INFO - "branch": "mozilla-central", 03:29:22 INFO - "script_repo_revision": "production", 03:29:22 INFO - "revision": "faa897d7948b7e2439573f39c34366c138913663", 03:29:22 INFO - "slavename": "talos-linux64-ix-054", 03:29:22 INFO - "repo_path": "mozilla-central" 03:29:22 INFO - } 03:29:22 INFO - Finding installer, test and symbols from parent task. 03:29:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oi5dh-MYSR2_TGAX1QvJsQ'}, attempt #1 03:29:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oi5dh-MYSR2_TGAX1QvJsQ'}, attempt #1 03:29:24 INFO - Task dependencies: OSv4zTzPSF2AmqFHfdlHSQ 03:29:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ'}, attempt #1 03:29:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2 03:29:24 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.test_packages.json 03:29:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.crashreporter-symbols.zip 03:29:24 INFO - Running post-action listener: _resource_record_post_action 03:29:24 INFO - [mozharness: 2017-09-17 10:29:24.466948Z] Finished read-buildbot-config step (success) 03:29:24 INFO - [mozharness: 2017-09-17 10:29:24.467164Z] Running download-and-extract step. 03:29:24 INFO - Running pre-action listener: _resource_record_pre_action 03:29:24 INFO - Running main action method: download_and_extract 03:29:24 INFO - mkdir: /builds/slave/test/build/tests 03:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:24 INFO - trying https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.test_packages.json 03:29:24 INFO - Downloading https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:29:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:29:25 INFO - Downloaded 989 bytes. 03:29:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:29:25 INFO - Using the following test package requirements: 03:29:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:29:25 INFO - u'common': [u'target.common.tests.zip'], 03:29:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:29:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:29:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:29:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:29:25 INFO - u'mozbase': [u'target.common.tests.zip'], 03:29:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:29:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:29:25 INFO - u'web-platform': [u'target.common.tests.zip', 03:29:25 INFO - u'target.web-platform.tests.tar.gz'], 03:29:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:29:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:29:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.common.tests.zip 03:29:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:29:25 INFO - Fetch https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.common.tests.zip into memory 03:29:27 INFO - Content-Length response header: 54731186 03:29:27 INFO - Bytes received: 54731186 03:29:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.talos.tests.zip 03:29:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:29:38 INFO - Fetch https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.talos.tests.zip into memory 03:29:39 INFO - Content-Length response header: 14508748 03:29:39 INFO - Bytes received: 14508748 03:29:40 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')]} 03:29:40 INFO - trying https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2 03:29:40 INFO - Downloading https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:29:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:29:43 INFO - Downloaded 61513324 bytes. 03:29:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2 03:29:43 INFO - mkdir: /builds/slave/test/properties 03:29:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:29:43 INFO - Writing to file /builds/slave/test/properties/build_url 03:29:43 INFO - Contents: 03:29:43 INFO - build_url:https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2 03:29:43 INFO - Running post-action listener: _resource_record_post_action 03:29:43 INFO - Running post-action listener: find_tests_for_verification 03:29:43 INFO - Running post-action listener: set_extra_try_arguments 03:29:43 INFO - [mozharness: 2017-09-17 10:29:43.531915Z] Finished download-and-extract step (success) 03:29:43 INFO - [mozharness: 2017-09-17 10:29:43.532016Z] Running populate-webroot step. 03:29:43 INFO - Running pre-action listener: _resource_record_pre_action 03:29:43 INFO - Running main action method: populate_webroot 03:29:43 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:29:43 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['tresize', 03:29:43 INFO - 'tcanvasmark']}, 03:29:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:29:43 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['dromaeo_css', 03:29:43 INFO - 'kraken']}, 03:29:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:43 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'tests': ['damp', 'tps']}, 03:29:43 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['damp', 'tps']}, 03:29:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:29:43 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['dromaeo_dom']}, 03:29:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:29:43 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['basic_compositor_video', 03:29:43 INFO - 'glvideo']}, 03:29:43 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:29:43 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['ts_paint_webext', 03:29:43 INFO - 'tp5o_webext']}, 03:29:43 INFO - 'other-e10s': {'tests': ['a11yr', 03:29:43 INFO - 'ts_paint', 03:29:43 INFO - 'tpaint', 03:29:43 INFO - 'sessionrestore', 03:29:43 INFO - 'sessionrestore_many_windows', 03:29:43 INFO - 'sessionrestore_no_auto_restore', 03:29:43 INFO - 'tabpaint']}, 03:29:43 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['a11yr', 03:29:43 INFO - 'ts_paint', 03:29:43 INFO - 'tpaint', 03:29:43 INFO - 'sessionrestore', 03:29:43 INFO - 'sessionrestore_many_windows', 03:29:43 INFO - 'sessionrestore_no_auto_restore', 03:29:43 INFO - 'tabpaint']}, 03:29:43 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:29:43 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 03:29:43 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['perf_reftest_singletons']}, 03:29:43 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['bloom_basic']}, 03:29:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:29:43 INFO - 'tsvgr_opacity', 03:29:43 INFO - 'tart', 03:29:43 INFO - 'tscrollx', 03:29:43 INFO - 'tsvg_static']}, 03:29:43 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['tsvgx', 03:29:43 INFO - 'tsvgr_opacity', 03:29:43 INFO - 'tart', 03:29:43 INFO - 'tscrollx', 03:29:43 INFO - 'tsvg_static']}, 03:29:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:29:43 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['tp5o']}, 03:29:43 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:29:43 INFO - 'talos_options': ['--mitmproxy', 03:29:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:29:43 INFO - '--firstNonBlankPaint'], 03:29:43 INFO - 'tests': ['tp6_google', 03:29:43 INFO - 'tp6_youtube', 03:29:43 INFO - 'tp6_amazon', 03:29:43 INFO - 'tp6_facebook']}, 03:29:43 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo', 03:29:43 INFO - '--mitmproxy', 03:29:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:29:43 INFO - '--firstNonBlankPaint'], 03:29:43 INFO - 'tests': ['tp6_google', 03:29:43 INFO - 'tp6_youtube', 03:29:43 INFO - 'tp6_amazon', 03:29:43 INFO - 'tp6_facebook']}, 03:29:43 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:29:43 INFO - 'talos_options': ['--stylo-threads=1', 03:29:43 INFO - '--mitmproxy', 03:29:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:29:43 INFO - '--firstNonBlankPaint'], 03:29:43 INFO - 'tests': ['tp6_google', 03:29:43 INFO - 'tp6_youtube', 03:29:43 INFO - 'tp6_amazon', 03:29:43 INFO - 'tp6_facebook']}, 03:29:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--xperf_path', 03:29:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:43 INFO - 'tests': ['tp5n']}, 03:29:43 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo', 03:29:43 INFO - '--xperf_path', 03:29:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:43 INFO - 'tests': ['tp5n']}}} 03:29:43 INFO - Running post-action listener: _resource_record_post_action 03:29:43 INFO - [mozharness: 2017-09-17 10:29:43.554866Z] Finished populate-webroot step (success) 03:29:43 INFO - [mozharness: 2017-09-17 10:29:43.554956Z] Running create-virtualenv step. 03:29:43 INFO - Running pre-action listener: _resource_record_pre_action 03:29:43 INFO - Running main action method: create_virtualenv 03:29:43 INFO - Creating virtualenv /builds/slave/test/build/venv 03:29:43 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:29:43 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:29:43 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:29:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:29:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:29:44 INFO - Installing distribute.............................................................................................................................................................................................done. 03:29:44 INFO - Installing pip...............done. 03:29:44 INFO - Return code: 0 03:29:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:29:44 INFO - Reading from file tmpfile_stdout 03:29:44 INFO - Output received: 03:29:44 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:29:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:29: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')]} 03:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29: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')]} 03:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:44 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 0x13b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1722a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726090>, '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 0x1726d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1720f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', '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 03:29:44 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 03:29:44 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 03:29:44 INFO - Using env: {'DISPLAY': ':0', 03:29:44 INFO - 'HOME': '/home/cltbld', 03:29:44 INFO - 'LANG': 'en_US.UTF-8', 03:29:44 INFO - 'LANGUAGE': 'en_US:en', 03:29:44 INFO - 'LOGNAME': 'cltbld', 03:29:44 INFO - 'MAIL': '/var/mail/cltbld', 03:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:44 INFO - 'MOZ_NO_REMOTE': '1', 03:29:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:44 INFO - 'NO_EM_RESTART': '1', 03:29:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:44 INFO - 'PWD': '/builds/slave/test', 03:29:44 INFO - 'SHELL': '/bin/bash', 03:29:44 INFO - 'SHLVL': '1', 03:29:44 INFO - 'TERM': 'linux', 03:29:44 INFO - 'TMOUT': '86400', 03:29:44 INFO - 'USER': 'cltbld', 03:29:44 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', 03:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:44 INFO - '_': '/tools/buildbot/bin/python'} 03:29:46 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:29:46 INFO - Downloading/unpacking pip>=1.5 03:29:46 INFO - Running setup.py egg_info for package pip 03:29:46 INFO - warning: no files found matching 'pip/cacert.pem' 03:29:46 INFO - warning: no files found matching '*.html' under directory 'docs' 03:29:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:29:46 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:29:46 INFO - Installing collected packages: pip 03:29:46 INFO - Found existing installation: pip 1.1 03:29:46 INFO - Uninstalling pip: 03:29:46 INFO - Successfully uninstalled pip 03:29:46 INFO - Running setup.py install for pip 03:29:46 INFO - warning: no files found matching 'pip/cacert.pem' 03:29:46 INFO - warning: no files found matching '*.html' under directory 'docs' 03:29:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:29:46 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:29:46 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:29:46 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:29:46 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:29:46 INFO - Successfully installed pip 03:29:46 INFO - Cleaning up... 03:29:46 INFO - Return code: 0 03:29:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:29:46 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')]} 03:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:46 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')]} 03:29:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:46 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 0x13b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1722a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726090>, '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 0x1726d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1720f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', '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 03:29:46 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 03:29:46 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 03:29:46 INFO - Using env: {'DISPLAY': ':0', 03:29:46 INFO - 'HOME': '/home/cltbld', 03:29:46 INFO - 'LANG': 'en_US.UTF-8', 03:29:46 INFO - 'LANGUAGE': 'en_US:en', 03:29:46 INFO - 'LOGNAME': 'cltbld', 03:29:46 INFO - 'MAIL': '/var/mail/cltbld', 03:29:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:46 INFO - 'MOZ_NO_REMOTE': '1', 03:29:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:46 INFO - 'NO_EM_RESTART': '1', 03:29:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:46 INFO - 'PWD': '/builds/slave/test', 03:29:46 INFO - 'SHELL': '/bin/bash', 03:29:46 INFO - 'SHLVL': '1', 03:29:46 INFO - 'TERM': 'linux', 03:29:46 INFO - 'TMOUT': '86400', 03:29:46 INFO - 'USER': 'cltbld', 03:29:46 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', 03:29:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:46 INFO - '_': '/tools/buildbot/bin/python'} 03:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:46 INFO - Downloading/unpacking psutil>=3.1.1 03:29: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 03:29: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 03:29: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 03:29: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 03:29:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:29:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:29:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:29:47 INFO - Installing collected packages: psutil 03:29:47 INFO - Running setup.py install for psutil 03:29:47 INFO - building 'psutil._psutil_linux' extension 03:29:47 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 03:29:47 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 03:29:47 INFO - building 'psutil._psutil_posix' extension 03:29:47 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 03:29:48 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 03:29:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:29:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:29:50 INFO - Successfully installed psutil 03:29:50 INFO - Cleaning up... 03:29:50 INFO - Return code: 0 03:29:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:29: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')]} 03:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29: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')]} 03:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1722a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726090>, '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 0x1726d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1720f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', '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 03:29: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:29: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 mozsystemmonitor==0.3 03:29:50 INFO - Using env: {'DISPLAY': ':0', 03:29:50 INFO - 'HOME': '/home/cltbld', 03:29:50 INFO - 'LANG': 'en_US.UTF-8', 03:29:50 INFO - 'LANGUAGE': 'en_US:en', 03:29:50 INFO - 'LOGNAME': 'cltbld', 03:29:50 INFO - 'MAIL': '/var/mail/cltbld', 03:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:50 INFO - 'MOZ_NO_REMOTE': '1', 03:29:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:50 INFO - 'NO_EM_RESTART': '1', 03:29:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:50 INFO - 'PWD': '/builds/slave/test', 03:29:50 INFO - 'SHELL': '/bin/bash', 03:29:50 INFO - 'SHLVL': '1', 03:29:50 INFO - 'TERM': 'linux', 03:29:50 INFO - 'TMOUT': '86400', 03:29:50 INFO - 'USER': 'cltbld', 03:29:50 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', 03:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:50 INFO - '_': '/tools/buildbot/bin/python'} 03:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:29:50 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 03:29:50 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 03:29:50 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 03:29:50 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 03:29:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:29:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:29:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:29:52 INFO - Installing collected packages: mozsystemmonitor 03:29:52 INFO - Running setup.py install for mozsystemmonitor 03:29:52 INFO - Successfully installed mozsystemmonitor 03:29:52 INFO - Cleaning up... 03:29:52 INFO - Return code: 0 03:29:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:29:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1722a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726090>, '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 0x1726d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1720f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', '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 03:29:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:29:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:29:52 INFO - Using env: {'DISPLAY': ':0', 03:29:52 INFO - 'HOME': '/home/cltbld', 03:29:52 INFO - 'LANG': 'en_US.UTF-8', 03:29:52 INFO - 'LANGUAGE': 'en_US:en', 03:29:52 INFO - 'LOGNAME': 'cltbld', 03:29:52 INFO - 'MAIL': '/var/mail/cltbld', 03:29:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:52 INFO - 'MOZ_NO_REMOTE': '1', 03:29:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:52 INFO - 'NO_EM_RESTART': '1', 03:29:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:52 INFO - 'PWD': '/builds/slave/test', 03:29:52 INFO - 'SHELL': '/bin/bash', 03:29:52 INFO - 'SHLVL': '1', 03:29:52 INFO - 'TERM': 'linux', 03:29:52 INFO - 'TMOUT': '86400', 03:29:52 INFO - 'USER': 'cltbld', 03:29:52 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', 03:29:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:52 INFO - '_': '/tools/buildbot/bin/python'} 03:29:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:52 INFO - Downloading/unpacking jsonschema==2.5.1 03:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:29:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:29:53 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 03:29:53 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 03:29:53 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 03:29:53 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 03:29:53 INFO - Downloading functools32-3.2.3-2.tar.gz 03:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:29:53 INFO - warning: no files found matching '*.txt' 03:29:53 INFO - no previously-included directories found matching 'build' 03:29:53 INFO - no previously-included directories found matching 'dist' 03:29:53 INFO - no previously-included directories found matching '.git*' 03:29:53 INFO - Installing collected packages: jsonschema, functools32 03:29:53 INFO - Running setup.py install for functools32 03:29:53 INFO - warning: no files found matching '*.txt' 03:29:53 INFO - no previously-included directories found matching 'build' 03:29:53 INFO - no previously-included directories found matching 'dist' 03:29:53 INFO - no previously-included directories found matching '.git*' 03:29:53 INFO - Successfully installed jsonschema functools32 03:29:53 INFO - Cleaning up... 03:29:53 INFO - Return code: 0 03:29:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1722a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726090>, '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 0x1726d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1720f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', '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 03:29:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:29:53 INFO - Using env: {'DISPLAY': ':0', 03:29:53 INFO - 'HOME': '/home/cltbld', 03:29:53 INFO - 'LANG': 'en_US.UTF-8', 03:29:53 INFO - 'LANGUAGE': 'en_US:en', 03:29:53 INFO - 'LOGNAME': 'cltbld', 03:29:53 INFO - 'MAIL': '/var/mail/cltbld', 03:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:53 INFO - 'MOZ_NO_REMOTE': '1', 03:29:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:53 INFO - 'NO_EM_RESTART': '1', 03:29:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:53 INFO - 'PWD': '/builds/slave/test', 03:29:53 INFO - 'SHELL': '/bin/bash', 03:29:53 INFO - 'SHLVL': '1', 03:29:53 INFO - 'TERM': 'linux', 03:29:53 INFO - 'TMOUT': '86400', 03:29:53 INFO - 'USER': 'cltbld', 03:29:53 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', 03:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:53 INFO - '_': '/tools/buildbot/bin/python'} 03:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:29:54 INFO - Cleaning up... 03:29:54 INFO - Return code: 0 03:29:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1722a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726090>, '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 0x1726d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1720f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', '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 03:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:29:54 INFO - Using env: {'DISPLAY': ':0', 03:29:54 INFO - 'HOME': '/home/cltbld', 03:29:54 INFO - 'LANG': 'en_US.UTF-8', 03:29:54 INFO - 'LANGUAGE': 'en_US:en', 03:29:54 INFO - 'LOGNAME': 'cltbld', 03:29:54 INFO - 'MAIL': '/var/mail/cltbld', 03:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:54 INFO - 'MOZ_NO_REMOTE': '1', 03:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:54 INFO - 'NO_EM_RESTART': '1', 03:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:54 INFO - 'PWD': '/builds/slave/test', 03:29:54 INFO - 'SHELL': '/bin/bash', 03:29:54 INFO - 'SHLVL': '1', 03:29:54 INFO - 'TERM': 'linux', 03:29:54 INFO - 'TMOUT': '86400', 03:29:54 INFO - 'USER': 'cltbld', 03:29:54 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', 03:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:54 INFO - '_': '/tools/buildbot/bin/python'} 03:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:54 INFO - Downloading/unpacking blobuploader==1.2.4 03:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:55 INFO - Downloading blobuploader-1.2.4.tar.gz 03:29:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:29:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:29:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:56 INFO - Downloading docopt-0.6.1.tar.gz 03:29:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:29:56 INFO - Installing collected packages: blobuploader, requests, docopt 03:29:56 INFO - Running setup.py install for blobuploader 03:29:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:29:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:29:56 INFO - Running setup.py install for requests 03:29:56 INFO - Running setup.py install for docopt 03:29:56 INFO - Successfully installed blobuploader requests docopt 03:29:56 INFO - Cleaning up... 03:29:56 INFO - Return code: 0 03:29:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:29:56 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')]} 03:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:56 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')]} 03:29:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:56 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 0x13b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1722a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726090>, '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 0x1726d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1720f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', '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 03:29:56 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 03:29:56 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 03:29:56 INFO - Using env: {'DISPLAY': ':0', 03:29:56 INFO - 'HOME': '/home/cltbld', 03:29:56 INFO - 'LANG': 'en_US.UTF-8', 03:29:56 INFO - 'LANGUAGE': 'en_US:en', 03:29:56 INFO - 'LOGNAME': 'cltbld', 03:29:56 INFO - 'MAIL': '/var/mail/cltbld', 03:29:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:56 INFO - 'MOZ_NO_REMOTE': '1', 03:29:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:56 INFO - 'NO_EM_RESTART': '1', 03:29:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:56 INFO - 'PWD': '/builds/slave/test', 03:29:56 INFO - 'SHELL': '/bin/bash', 03:29:56 INFO - 'SHLVL': '1', 03:29:56 INFO - 'TERM': 'linux', 03:29:56 INFO - 'TMOUT': '86400', 03:29:56 INFO - 'USER': 'cltbld', 03:29:56 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', 03:29:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:56 INFO - '_': '/tools/buildbot/bin/python'} 03:29:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:29:56 INFO - Running setup.py (path:/tmp/pip-8YF4Aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:29:56 INFO - Running setup.py (path:/tmp/pip-DJ4Umm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:29:57 INFO - Running setup.py (path:/tmp/pip-S9IhHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:29:57 INFO - Running setup.py (path:/tmp/pip-9onitk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:29:57 INFO - Running setup.py (path:/tmp/pip-NJQEN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:29:57 INFO - Running setup.py (path:/tmp/pip-NdE4eb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:29:57 INFO - Running setup.py (path:/tmp/pip-a4xLpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:29:57 INFO - Running setup.py (path:/tmp/pip-asRALd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:29:57 INFO - Running setup.py (path:/tmp/pip-YdIr0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:29:57 INFO - Running setup.py (path:/tmp/pip-VCTfXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:29:57 INFO - Running setup.py (path:/tmp/pip-7JO61L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:29:57 INFO - Running setup.py (path:/tmp/pip-jknk2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:29:57 INFO - Running setup.py (path:/tmp/pip-yYCIUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:29:57 INFO - Running setup.py (path:/tmp/pip-uPZe0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:57 INFO - Running setup.py (path:/tmp/pip-0Mf6Mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:29:57 INFO - Running setup.py (path:/tmp/pip-XYdYCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:29:58 INFO - Running setup.py (path:/tmp/pip-p41xSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:29:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:29:58 INFO - Running setup.py install for manifestparser 03:29:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Running setup.py install for mozcrash 03:29:58 INFO - Running setup.py install for mozdebug 03:29:58 INFO - Running setup.py install for mozdevice 03:29:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Running setup.py install for mozfile 03:29:58 INFO - Running setup.py install for mozhttpd 03:29:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Running setup.py install for mozinfo 03:29:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Running setup.py install for mozInstall 03:29:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Running setup.py install for mozleak 03:29:58 INFO - Running setup.py install for mozlog 03:29:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:29:59 INFO - Running setup.py install for moznetwork 03:29:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:29:59 INFO - Running setup.py install for mozprocess 03:29:59 INFO - Running setup.py install for mozprofile 03:29:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:29:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:29:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:29:59 INFO - Running setup.py install for mozrunner 03:29:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:29:59 INFO - Running setup.py install for mozscreenshot 03:29:59 INFO - Running setup.py install for moztest 03:29:59 INFO - Running setup.py install for mozversion 03:29:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:29:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:29:59 INFO - Cleaning up... 03:29:59 INFO - Return code: 0 03:29:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:59 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 0x13b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1722a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726090>, '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 0x1726d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1720f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', '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 03:29:59 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 03:29:59 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 03:29:59 INFO - Using env: {'DISPLAY': ':0', 03:29:59 INFO - 'HOME': '/home/cltbld', 03:29:59 INFO - 'LANG': 'en_US.UTF-8', 03:29:59 INFO - 'LANGUAGE': 'en_US:en', 03:29:59 INFO - 'LOGNAME': 'cltbld', 03:29:59 INFO - 'MAIL': '/var/mail/cltbld', 03:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:59 INFO - 'MOZ_NO_REMOTE': '1', 03:29:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:59 INFO - 'NO_EM_RESTART': '1', 03:29:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:59 INFO - 'PWD': '/builds/slave/test', 03:29:59 INFO - 'SHELL': '/bin/bash', 03:29:59 INFO - 'SHLVL': '1', 03:29:59 INFO - 'TERM': 'linux', 03:29:59 INFO - 'TMOUT': '86400', 03:29:59 INFO - 'USER': 'cltbld', 03:29:59 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', 03:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:59 INFO - '_': '/tools/buildbot/bin/python'} 03:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:30:00 INFO - Running setup.py (path:/tmp/pip-Wq2ih_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:30:00 INFO - Running setup.py (path:/tmp/pip-pvHzwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:30:00 INFO - Running setup.py (path:/tmp/pip-BPM4hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:30:00 INFO - Running setup.py (path:/tmp/pip-qUFKPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:30:00 INFO - Running setup.py (path:/tmp/pip-vhrx14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:30:00 INFO - Running setup.py (path:/tmp/pip-uLRARe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:30:00 INFO - Running setup.py (path:/tmp/pip-VMEzvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:30:00 INFO - Running setup.py (path:/tmp/pip-BdF5PR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:30:00 INFO - Running setup.py (path:/tmp/pip-yZqBaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:30:00 INFO - Running setup.py (path:/tmp/pip-D_dQ8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:30:00 INFO - Running setup.py (path:/tmp/pip-3igZAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:30:00 INFO - Running setup.py (path:/tmp/pip-DzInqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:30:00 INFO - Running setup.py (path:/tmp/pip-mVo9C0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:30:01 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)) 03:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:30:01 INFO - Running setup.py (path:/tmp/pip-JqdvyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:30:01 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)) 03:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:01 INFO - Running setup.py (path:/tmp/pip-eLwuMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:01 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)) 03:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:30:01 INFO - Running setup.py (path:/tmp/pip-zpP93t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:30:01 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)) 03:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:30:01 INFO - Running setup.py (path:/tmp/pip-n7iSBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:30:01 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)) 03:30:01 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)) 03:30:01 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)) 03:30:01 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)) 03:30:01 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)) 03:30:01 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)) 03:30:01 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)) 03:30:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:30:01 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 03:30:01 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 03:30:01 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 03:30:01 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 03:30:02 INFO - Downloading blessings-1.6.tar.gz 03:30:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:30:02 INFO - Installing collected packages: blessings 03:30:02 INFO - Running setup.py install for blessings 03:30:02 INFO - Successfully installed blessings 03:30:02 INFO - Cleaning up... 03:30:02 INFO - Return code: 0 03:30:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:30:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:30:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:30:02 INFO - Reading from file tmpfile_stdout 03:30:02 INFO - Current package versions: 03:30:02 INFO - argparse == 1.2.1 03:30:02 INFO - blessings == 1.6 03:30:02 INFO - blobuploader == 1.2.4 03:30:02 INFO - docopt == 0.6.1 03:30:02 INFO - functools32 == 3.2.3-2 03:30:02 INFO - jsonschema == 2.5.1 03:30:02 INFO - manifestparser == 1.1 03:30:02 INFO - mozInstall == 1.13 03:30:02 INFO - mozcrash == 1.0 03:30:02 INFO - mozdebug == 0.1 03:30:02 INFO - mozdevice == 0.51 03:30:02 INFO - mozfile == 1.2 03:30:02 INFO - mozhttpd == 0.7 03:30:02 INFO - mozinfo == 0.10 03:30:02 INFO - mozleak == 0.1 03:30:02 INFO - mozlog == 3.5 03:30:02 INFO - moznetwork == 0.27 03:30:02 INFO - mozprocess == 0.25 03:30:02 INFO - mozprofile == 0.28 03:30:02 INFO - mozrunner == 6.13 03:30:02 INFO - mozscreenshot == 0.1 03:30:02 INFO - mozsystemmonitor == 0.3 03:30:02 INFO - moztest == 0.8 03:30:02 INFO - mozversion == 1.4 03:30:02 INFO - psutil == 3.1.1 03:30:02 INFO - requests == 1.2.3 03:30:02 INFO - wsgiref == 0.1.2 03:30:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:30:02 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')]} 03:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:02 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')]} 03:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:02 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 0x13b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1722a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726090>, '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 0x1726d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1720f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', '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 03:30:02 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 03:30:02 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 03:30:02 INFO - Using env: {'DISPLAY': ':0', 03:30:02 INFO - 'HOME': '/home/cltbld', 03:30:02 INFO - 'LANG': 'en_US.UTF-8', 03:30:02 INFO - 'LANGUAGE': 'en_US:en', 03:30:02 INFO - 'LOGNAME': 'cltbld', 03:30:02 INFO - 'MAIL': '/var/mail/cltbld', 03:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:02 INFO - 'MOZ_NO_REMOTE': '1', 03:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:02 INFO - 'NO_EM_RESTART': '1', 03:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:02 INFO - 'PWD': '/builds/slave/test', 03:30:02 INFO - 'SHELL': '/bin/bash', 03:30:02 INFO - 'SHLVL': '1', 03:30:02 INFO - 'TERM': 'linux', 03:30:02 INFO - 'TMOUT': '86400', 03:30:02 INFO - 'USER': 'cltbld', 03:30:02 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', 03:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:02 INFO - '_': '/tools/buildbot/bin/python'} 03:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:03 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)) 03:30:03 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)) 03:30:03 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)) 03:30:03 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)) 03:30:03 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)) 03:30:03 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)) 03:30:03 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)) 03:30:03 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)) 03:30:03 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)) 03:30:03 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:30:04 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)) 03:30:04 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)) 03:30:04 INFO - Installing collected packages: simplejson 03:30:04 INFO - Running setup.py install for simplejson 03:30:04 INFO - building 'simplejson._speedups' extension 03:30:04 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 03:30:05 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 03:30:05 INFO - Successfully installed simplejson 03:30:05 INFO - Cleaning up... 03:30:05 INFO - Return code: 0 03:30:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:30:05 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')]} 03:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:05 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')]} 03:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:05 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 0x13b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1722a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726090>, '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 0x1726d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1720f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', '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 03:30:05 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 03:30:05 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 03:30:05 INFO - Using env: {'DISPLAY': ':0', 03:30:05 INFO - 'HOME': '/home/cltbld', 03:30:05 INFO - 'LANG': 'en_US.UTF-8', 03:30:05 INFO - 'LANGUAGE': 'en_US:en', 03:30:05 INFO - 'LOGNAME': 'cltbld', 03:30:05 INFO - 'MAIL': '/var/mail/cltbld', 03:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:05 INFO - 'MOZ_NO_REMOTE': '1', 03:30:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:05 INFO - 'NO_EM_RESTART': '1', 03:30:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:05 INFO - 'PWD': '/builds/slave/test', 03:30:05 INFO - 'SHELL': '/bin/bash', 03:30:05 INFO - 'SHLVL': '1', 03:30:05 INFO - 'TERM': 'linux', 03:30:05 INFO - 'TMOUT': '86400', 03:30:05 INFO - 'USER': 'cltbld', 03:30:05 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', 03:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:05 INFO - '_': '/tools/buildbot/bin/python'} 03:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:30:05 INFO - Cleaning up... 03:30:05 INFO - Return code: 0 03:30:05 INFO - Running post-action listener: _resource_record_post_action 03:30:05 INFO - Running post-action listener: _start_resource_monitoring 03:30:05 INFO - Starting resource monitoring. 03:30:05 INFO - [mozharness: 2017-09-17 10:30:05.628863Z] Finished create-virtualenv step (success) 03:30:05 INFO - [mozharness: 2017-09-17 10:30:05.629403Z] Running install step. 03:30:05 INFO - Running pre-action listener: _resource_record_pre_action 03:30:05 INFO - Running main action method: install 03:30:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:30:05 INFO - Reading from file tmpfile_stdout 03:30:05 INFO - Detecting whether we're running mozinstall >=1.0... 03:30:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:30:05 INFO - Reading from file tmpfile_stdout 03:30:05 INFO - Output received: 03:30:05 INFO - Usage: mozinstall [options] installer 03:30:05 INFO - Options: 03:30:05 INFO - -h, --help show this help message and exit 03:30:05 INFO - -d DEST, --destination=DEST 03:30:05 INFO - Directory to install application into. [default: 03:30:05 INFO - "/builds/slave/test"] 03:30:05 INFO - --app=APP Application being installed. [default: firefox] 03:30:05 INFO - mkdir: /builds/slave/test/build/application 03:30:05 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'] 03:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:30:23 INFO - Reading from file tmpfile_stdout 03:30:23 INFO - Output received: 03:30:23 INFO - /builds/slave/test/build/application/firefox/firefox 03:30:23 INFO - Running post-action listener: _resource_record_post_action 03:30:23 INFO - [mozharness: 2017-09-17 10:30:23.781142Z] Finished install step (success) 03:30:23 INFO - [mozharness: 2017-09-17 10:30:23.781458Z] Running setup-mitmproxy step. 03:30:23 INFO - Running pre-action listener: _resource_record_pre_action 03:30:23 INFO - Running main action method: setup_mitmproxy 03:30:23 INFO - Skipping: mitmproxy is not required 03:30:23 INFO - Running post-action listener: _resource_record_post_action 03:30:23 INFO - [mozharness: 2017-09-17 10:30:23.782621Z] Finished setup-mitmproxy step (success) 03:30:23 INFO - [mozharness: 2017-09-17 10:30:23.782842Z] Running run-tests step. 03:30:23 INFO - Running pre-action listener: _resource_record_pre_action 03:30:23 INFO - Running pre-action listener: _set_gcov_prefix 03:30:23 INFO - Running main action method: run_tests 03:30:23 WARNING - Try message not found. 03:30:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:30:23 INFO - Python 2.7.3 03:30:23 INFO - Return code: 0 03:30:23 INFO - grabbing minidump binary from tooltool 03:30:23 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')]} 03:30:23 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 0x1726090>, '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 0x1726d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1720f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:30:23 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 03:30:23 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 03:30:23 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 03:30:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:30:23 INFO - Return code: 0 03:30:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:30:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:30:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:30:23 INFO - ENV: RUST_BACKTRACE is now full 03:30:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:30:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:30:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 03:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-054 --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 03:30:23 INFO - Using env: {'DISPLAY': ':0', 03:30:23 INFO - 'HOME': '/home/cltbld', 03:30:23 INFO - 'LANG': 'en_US.UTF-8', 03:30:23 INFO - 'LANGUAGE': 'en_US:en', 03:30:23 INFO - 'LOGNAME': 'cltbld', 03:30:23 INFO - 'MAIL': '/var/mail/cltbld', 03:30:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:30:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:30:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:23 INFO - 'MOZ_NO_REMOTE': '1', 03:30:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:30:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:23 INFO - 'NO_EM_RESTART': '1', 03:30:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:23 INFO - 'PWD': '/builds/slave/test', 03:30:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:30:23 INFO - 'RUST_BACKTRACE': 'full', 03:30:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:30:23 INFO - 'SHELL': '/bin/bash', 03:30:23 INFO - 'SHLVL': '1', 03:30:23 INFO - 'TERM': 'linux', 03:30:23 INFO - 'TMOUT': '86400', 03:30:23 INFO - 'USER': 'cltbld', 03:30:23 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', 03:30:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:23 INFO - '_': '/tools/buildbot/bin/python'} 03:30:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 03:30:24 INFO - mozversion application_buildid: 20170917094019 03:30:24 INFO - mozversion application_changeset: faa897d7948b7e2439573f39c34366c138913663 03:30:24 INFO - mozversion application_display_name: Nightly 03:30:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:30:24 INFO - mozversion application_name: Firefox 03:30:24 INFO - mozversion application_remotingname: firefox 03:30:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:30:24 INFO - mozversion application_vendor: Mozilla 03:30:24 INFO - mozversion application_version: 57.0a1 03:30:24 INFO - mozversion platform_buildid: 20170917094019 03:30:24 INFO - mozversion platform_changeset: faa897d7948b7e2439573f39c34366c138913663 03:30:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:30:24 INFO - mozversion platform_version: 57.0a1 03:30:24 INFO - using testdate: 1505644224 03:30:24 INFO - actual date: 1505644224 03:30:24 INFO - starting webserver on 'localhost:57847' 03:30:24 INFO - SUITE-START | Running 2 tests 03:30:24 INFO - TEST-START | dromaeo_css 03:30:24 INFO - Initialising browser for dromaeo_css test... 03:30:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57847/getInfo.html -profile /tmp/tmpVdDNdS/profile 03:30:24 INFO - TEST-INFO | started process 23033 (/builds/slave/test/build/application/firefox/firefox http://localhost:57847/getInfo.html) 03:30:29 INFO - TEST-INFO | 23033: exit 0 03:30:29 INFO - Browser initialized. 03:30:29 INFO - Running cycle 1/1 for dromaeo_css test... 03:30:29 INFO - Using env: {'DISPLAY': ':0', 03:30:29 INFO - 'HOME': '/home/cltbld', 03:30:29 INFO - 'JSGC_DISABLE_POISONING': '1', 03:30:29 INFO - 'LANG': 'en_US.UTF-8', 03:30:29 INFO - 'LANGUAGE': 'en_US:en', 03:30:29 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:30:29 INFO - 'LOGNAME': 'cltbld', 03:30:29 INFO - 'MAIL': '/var/mail/cltbld', 03:30:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:30:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:30:29 INFO - 'MOZ_CRASHREPORTER': '1', 03:30:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:30:29 INFO - 'MOZ_NO_REMOTE': '1', 03:30:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:30:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:29 INFO - 'NO_EM_RESTART': '1', 03:30:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:29 INFO - 'PWD': '/builds/slave/test', 03:30:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:30:29 INFO - 'RUST_BACKTRACE': 'full', 03:30:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:30:29 INFO - 'SHELL': '/bin/bash', 03:30:29 INFO - 'SHLVL': '1', 03:30:29 INFO - 'TERM': 'linux', 03:30:29 INFO - 'TMOUT': '86400', 03:30:29 INFO - 'USER': 'cltbld', 03:30:29 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', 03:30:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:29 INFO - '_': '/tools/buildbot/bin/python'} 03:30:29 INFO - TEST-INFO | started process 23268 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVdDNdS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:30:30 INFO - PID 23268 | 03:30:30 INFO - PID 23268 | (/builds/slave/test/build/application/firefox/firefox:23317): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:30:30 INFO - PID 23268 | 03:30:41 INFO - PID 23268 | RSS: Main: 154759168 03:30:41 INFO - PID 23268 | 03:33:37 INFO - PID 23268 | Cycle 1(1): loaded http://localhost:57847/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57847/tests/dromaeo/cssquery-ext.html) 03:33:38 INFO - PID 23268 | RSS: Main: 155447296 03:33:38 INFO - PID 23268 | 03:36:40 INFO - PID 23268 | Cycle 1(1): loaded http://localhost:57847/tests/dromaeo/cssquery-ext.html (next: http://localhost:57847/tests/dromaeo/cssquery-jquery.html) 03:36:41 INFO - PID 23268 | RSS: Main: 154099712 03:36:41 INFO - PID 23268 | 03:39:43 INFO - PID 23268 | Cycle 1(1): loaded http://localhost:57847/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57847/tests/dromaeo/cssquery-mootools.html) 03:39:43 INFO - PID 23268 | RSS: Main: 158117888 03:39:43 INFO - PID 23268 | 03:42:45 INFO - PID 23268 | Cycle 1(1): loaded http://localhost:57847/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57847/tests/dromaeo/cssquery-prototype.html) 03:42:46 INFO - PID 23268 | RSS: Main: 156917760 03:42:46 INFO - PID 23268 | 03:45:49 INFO - PID 23268 | Cycle 1(1): loaded http://localhost:57847/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57847/tests/dromaeo/cssquery-yui.html) 03:45:50 INFO - PID 23268 | RSS: Main: 157286400 03:45:50 INFO - PID 23268 | 03:48:51 INFO - PID 23268 | Cycle 1(1): loaded http://localhost:57847/tests/dromaeo/cssquery-yui.html (next: http://localhost:57847/tests/dromaeo/cssquery-dojo.html) 03:48:51 INFO - PID 23268 | RSS: Main: 159576064 03:48:51 INFO - PID 23268 | 03:48:51 INFO - PID 23268 | __start_tp_report 03:48:51 INFO - PID 23268 | _x_x_mozilla_page_load 03:48:51 INFO - PID 23268 | _x_x_mozilla_page_load_details 03:48:51 INFO - PID 23268 | |i|pagename|runs| 03:48:51 INFO - PID 23268 | |0;dojo.html;3144.1039303798416;3146.5280207968804;3172.2228054126745;3183.013265887574;3188.4101441233374;6074.149619053336;6236.906446403307;6249.218847644044;6272.56092073555;6390.904136437957;6175.444210021097;6197.938020619792;6218.160548325986;6232.750689972396;6265.624062556239;5731.14032895067;5775.393583673716;5796.82609521715;5801.53587712982;5883.499902508292;5833.941660583382;5871.266091738533;5881.558883083764;5891.941080589204;5937.4656280934705;5806.622569532967;5824.097264923944;5875;5924.911126333109;5937.6140550864275;502.3589431405375;5352.598555108343;5680.914786278167;5696.943030569724;5845.85385365365;5671.971640141773;5691.146328050784;5753.137029445575;5760.423957604248;5776.711164441761;6180.258368995705;6191;6234.906476402857;6283.7800676976085;6329.05064240363;6687.063811066455;6720.361565651256;6729.663516824139;6779.32206779323;6811.829704257433;6657.434118099919;6813.182418109858;6877.65611719412;6883.967404889257;6887.758928437481;6189.907151392733;6190.783322583733;6197.4732147767045;6227.657478838707;6318.336574659686;6920.788861949139;6972.337627925441;6990.685419156151;7002.7899163025195;7044.471664625174;6606.306337834554;6608.570442921195;6611.140551728245;6700.061991321219;6701.798946031627;6553.377429144223;6737.6968036438475;6739.359760822714;6791.626460544718;6835.350641689031;6611.801645950629;6629.071929929813;6721.193456785119;6730.562513436709;6739.090222819955;6747.291534388916;6758.222804377461;6772.5936443813525;6791.981202819517;6817.863642727118;5275.60432967529;5284.603654725911;5347.197920311906;5358.062339090644;5368.436314187033;4460.063386688832;4606.239970404846;4635.2120139576355;4669.34629151919;4670.486246512881;5701.857453563611;5832.358440571534;5861.150133230658;5891.617044892068;5893.911591326134;6758.357955994271;6781.593104413751;6783.59298442095;6786.049953006583;6803.523753337219;5791.594588378857;5791.62354446968;5799.217105690762;5869.706514674334;5890.558208134407;5650.85872853182;5743.712814359349;5745.827625171251;5751.338596061423;5831.562632802472;6563.310852360529;6646.534742567974;6649.966750166218;6656.534042617067;6660.433863121689;6704.195496540447;6825.453963682893;6842.213145488233;6849.897251541331;6852.211995620368;6617.139771829824;6633.270340262664;6690.498212633975;6707.664616769044;6714.664266786739;6481.967590162019;6580.21037475506;6592.307807680125;6645.966770166118;6734.427573656294;6675.465962723068;6697;6720.899186512109;6778.288279730557;6799.694013769491;6504.674766261763;6506.544541882023;6564.442022427956;6590.934090659032;6603.075569420189;6508.8047358579315;6521.836954075996;6583.703733331916;6593.538452308484;6597.868042639217;6295.433410993032;6448.548185974757;6449.226092868886;6530.216374035146;6534.248561415498;6454.644994525158;6499.122618446639;6510.674466276572;6520.543561950618;6524.934750652433;6324.083007963727;6394.968025159845;6445.097686324012;6445.548811583331;6456.12842266288 03:48:51 INFO - PID 23268 | |1;ext.html;671.4930227678104;693.6462404173869;693.691307368221;693.8438851258468;699.9020137180793;37318.81340593297;40467.19065618686;40526;40607;40656.79671601642;37065.44401833968;37338.253234935284;37366.43950340747;37501.437478437845;37517.06207344818;6628.6022838629715;7657.885131723001;7827.765167044998;7843;7863.370930325589;7582.317591416771;7638.121616014174;7670.386369090487;7679.462437629368;7707.730229441971;7688.038995125609;7695.384369250447;7731.0336207974005;7767.184445633212;7773.766786996399;7701.845963080735;7706.7687969361;7708.537487750753;7720.305172534443;7736.34241089508;7687.385009199251;7756.961215193888;7758.418118641068;7758.495697779683;7771.961140194319;38112.80943595292;38219;38241.61758382408;38264.42603360924;38272.617273827185;3493.5633045869267;3535.611082780892;3541.628129046439;3550.7869527828157;3557.5019497270405;31456.685433145834;31568.684313156806;31626.683733162605;31695.366092678247;31715.36569268625;4980.7509624518625;5173.844784656466;5248.081585722483;5254.290670759437;5273.762680679379;184953;185666.07166964214;185828.0708596462;186209;186526;41070;42443;47528;47559;47669.76165119222;50556.494435055916;50649.74675126601;50820.23769643458;50852.745736271085;51007.48992510027;15986.840131598536;16006.199690015452;16078.598035049217;16154.757678634594;16159.757603635955;13214;13231.73536529264;13236.669083272995;13243.602691919257;13372.33138343079;13936.790948135786;13986.580402587735;14061.859381406259;14143.29283535819;14234.14595124296;5828.59199856006;5925.140854576062;5970.522358211333;5973.253343332083;5989.580729348903;1003.7340104872214;1020.5815615597569;1027.9743006424899;1030.6547306652205;1031.3193292426963;22858.771412285998;23028.194013209457;23078.192263270706;23117.190898318477;23124.768752312597;1089.2593036735095;1103.2938919091787;1111.149970272742;1111.4887151910211;1112.9777404451866;12257.203281786655;12303.077269204845;12303.540641163281;12309;12371;218559;220474;221213;222168;222363;52826.471735282925;53133.734331328094;53275.733621331645;53324.20013699798;53380.19929701058;1413.6536548545466;1456.4174330267977;1462.7367073926796;1465.2893346726828;1465.4358072142088;22601.773982260624;22885.31344059681;22919.770802291765;22957.426064348525;23214.76785232126;36780.44829327509;36807.26385472329;36812.26375472529;36878;36885.81557092197;191321;191588;191956;192072;192195;33143.83428082844;33517.83241083877;35144.47283290804;35160;35256.823715881255;43284;43929.78035109804;44074.55925440833;44215;44245.55754442414;32253;32527.349453010334;32718.83640581782;32840.50739238961;33678.32643347168;31912.68087319097;32340.676593234708;32667;32739.672603273662;32903.83548082244;35545.64454355527;36099;36203.45694814527;36493.45259821158;36570.085747856516;31735;31853.840730796197;32310.192245193117;32376.190595235308;32677.673223267462;23492;23737.762622373557;23745.406364841016;23799.76200238045;24152.396190095387 03:48:51 INFO - PID 23268 | |2;jquery.html;559.7173427419156;563.1974436428089;573.8737477754893;580.2775544447164;591.2934043817637;22230.777692223073;23077.653835192486;23193.76806231937;23249.070037198533;23330.300090997254;24804.379890502747;25245.36886577832;25621;26180.738192618115;26231.868840655818;6631.834204144899;6816.182058153028;6828;6861.245263021064;6863.4852386071;6662.1339225900565;6796.184457865038;6801.93198068018;6804.965975170142;6805.081314022593;6355.555111142222;6629.767958121467;6645.60126392418;6783.084283621697;6802.523823332369;6494.480441564687;6540.313267106981;6545.37818907203;6546.574472659262;6549.672516374162;5926.911096333559;6030.366811484775;6058.666773327465;6068.726907289182;6070.271567411939;28603.14190574286;29863.552046719316;29915;29918.252043698863;29927.102186934426;2034.6032523657893;2077.2833372486466;2078.18950609261;2080.251109600543;2083.499960009602;19335.12991915367;19663.213471461124;19940.601187976303;19971.600567988706;19998.70001949972;2430.149447693311;2475.059477398577;2494.052260141153;2500.1874390823054;2536.1250368622796;121779;124475;131956;135541;136283.31858340744;20263.568371795478;20878.37364879056;21090.47273818136;21462.892685536473;22275.888620556794;42072.36891446631;42237.788811056365;42292.36561451581;42418;42442.36336454956;11518.193726439069;11574.88425115755;11697.824532632018;11723.824142637697;11975.820362694567;9656.903430965742;9741.512924353754;9790.118889299998;9846.359986600848;9851.75370615726;10820.945895270474;11049.723756905982;11212.159088068427;11469.655910322703;11627.418629068512;3462.9653703463146;3614.2410093880053;4107.486564179478;4147.212029714345;4200.222958752641;944.5324564340696;966.3235734985398;966.4539535162611;968.3995922528078;971.2472833554052;19498.902505487666;19542.70685939712;19809.504762381057;19916.800831991495;20187.293444729657;1029.953652085658;1034.5499707627207;1038.600138946511;1043.2801367056707;1050.5784167494;10849.294795838245;10885.401302928256;10924.344539327665;11123.777524449466;11147.21969462146;414619;415064;415745;420650;426280;104397.47801261097;105614.47192763987;106514;108406.45796770966;110538.44730776295;1018.6027449294781;1145.1297014269048;1162.7093226693328;1186.3297237061033;1194.2714943884223;20462.283820066226;20637.58724825525;21197.470063248544;21288.148474060865;21338.679919800907;55753.442465576445;56361.71819140878;56870.43129568651;58172.12741808792;58404;72260;72472.63763681147;72520;73321.6333918327;73631.26368736244;20081.79918200799;20736.48158796042;20939;20943.37169884906;20948.581028379653;22887.88556057209;23029.19397821135;23210.53578928446;23334.649980250648;23894.52210955806;18072.096395179924;18343.35798247055;18583.07084645735;18777.061146942873;18842.623147537248;16424.83575164233;16609.916950415172;16835.747463787808;17017.23422445968;17639.911800440917;19062.23751049998;19087.618247635248;19095.713564296824;19370.2251909922;19400.12699428501;20191.394258172277;20243.59512809765;20309.187632494533;20334.983250837104;20370.79629203689;17185.31258749636;17260.91369543144;17264.568385789953;17412.21645026002;17469.65060698804 03:48:51 INFO - PID 23268 | |3;mootools.html;1347.0570600579595;1386.521650030739;1412.399730114701;1416.8016477693122;1418.7517184492717;14974.700505989887;15598.220088995547;15599.688006239869;15604.063756174637;15624.453144139943;15154.318055687492;15270.465533706323;15303.081815091102;15317.540473785777;15323.233838308095;2638;2686.408990022192;2696.6224728538023;2708.241692326142;2717.578775289833;2695.5199321881737;2710.5798601216743;2724.4551089782026;2728.2770065932446;2732.508148533263;2629.434671545617;2675.3579141006117;2730.795190360721;2736.192823117175;2741.9451610967767;2746.6292050573115;2747.4779791839587;2767.8339299642084;2780.805343625947;2781.3463835998523;2696.9056083037194;2704.9045136719465;2705.91882243531;2721.7414345637135;2723.360010397555;14844.10935343872;15026.398944042225;15079.170645614488;15104.39582416702;15109.622259443491;8138.430309878311;8394.412391132562;8405.915940840574;8442.915570844274;8458.02732685743;5508.118701007819;5724.685142317171;5759.510441612468;5799.3910639382675;5850.882982340329;1947.4255094747084;1963.3520938090396;1963.4207908666835;2014.446027342478;2021.080408414168;26968.730312696818;27496;27512.9999999998;27559.173224803086;27588.17235482939;7259.382952448996;7410.592417417094;7419.29516695913;7579.848403032019;7600.3539699126195;20229.898850505655;20323.593528129353;20333.288334908208;20339.491512712302;20356.796432035786;8231.794205144919;8232.794180145544;8384.664613415516;8486;8497.447665901696;7522.736704215326;7536.472446928662;7542.057242844645;7546.811329716691;7612.467127301035;5138.794448222104;5289.550388216968;5292.603054770908;5308.654937429132;5357.758900849393;1583.691180219846;1593.219322531974;1610.452446168285;1611.887167898236;1619.1094897806395;828.329053466694;854.7051267312767;862.7498025572513;877.635781150828;891.5675897189858;6979.616121113388;7009.7897063088185;7046.401055910203;7061.6469176542;7105.467089968273;714.720650036429;715.8282012317111;718.1597530888872;723.0997408226687;723.3742812467178;4338.8718933077;4648;4874.95125048745;5162.432132465426;5214.556762675139;20084.19663213455;21286.893565532075;21377.67933480999;21874.45313867166;22084;21557.24549640755;21600.13599456004;21605.891970540048;21675.67486487704;21927.342179734635;10997.725056873482;11244.49399777012;11441.199116061796;11482.77034459323;11542.422878856192;7573.962130189314;7680.615969201629;7705.845883082419;7716.344110750649;7935.0874649413745;18294;18618.906905465385;18841.340553080576;18939.337123201174;19148.80851191526;18698.813011869708;19077.71383429222;19263.22947082157;19287.517812054248;19293.710594340817;10979.451027448755;11157.944210278896;11175.329480231212;11198.776024479628;11245.71885702864;13842.30788460593;13894.583162505141;14076.859231407965;14090.929545352208;14175.929120354333;10938.890611094106;11236.775264494501;11411.885881141414;11485.712857178303;11655.941720291345;11020.669379918616;11179.944100279447;11293.153013523854;11312.377819220023;11414.771704566028;10187.13409360183;10247.48762561854;10278.33190842593;10365.118964888068;10398.428086455318;6580.934190658032;6585.6377899216;6655.301193374819;6716.597004179765;6762;6766.18805743314;6804.6257455840405;6869.7595584154315;6897.62063086515;6903.378695917391 03:48:51 INFO - PID 23268 | |4;prototype.html;494.9269982677555;499.6466642115635;529.5657560800144;530.4456842599484;531.183446933781;6148.63108213507;6294.716737746801;6320.367963203677;6338.429541341278;6362.586431881936;6173.320934697191;6215.7513699451965;6221;6245;6273.874522509547;5171.4311425743235;5200.7399630018535;5216.26972223889;5217.47825217479;5259.421463638997;4779.498152693969;4893.975530122344;5112.36095488064;5206;5218.112920803454;4909.288153217782;4940.382452193476;4953.777080031407;4955.801767929296;4961.3798275215595;4927.113119638464;4949.257611358289;4960.776765045582;4989.426215985171;4990.75046247688;4300.22595932732;4389.868303950887;4406.713563618355;4418.602325790662;4451.243288640906;5882.411758824083;5932.279638904713;5939.910901336483;5960.033189047604;5975.163477113208;5626.324841019104;5668.4614961579;5677.971610141964;5683.857903552403;5685.943140568624;6409.166808314936;6422.389872962061;6433.774817881352;6443.710033048477;6443.83890402739;4583.564561366665;4818.156822556039;4830.275458681191;4834.419869615634;4859.979404325096;6531.346865313478;6565.54041217115;6792.388685018323;6816.023353227558;6850.21222559412;4918.360613120343;4970.545470897691;4985.925211121836;4992.675476094042;4997.675151115166;5013.974930125399;5037.974810125926;5039.697618142924;5079.746012699424;5084.720340381241;5617.494425501724;5657.264555607827;5680.77276908919;5684.34630017545;5697.547125482975;6540.411362977355;6560.720659471453;6561.8359541010905;6567.179102612174;6626.7017984190825;1713.922873470697;1717.9656406872043;1722.811260230437;1740.4691569071301;1758.2703178181168;1160.3444054109402;1186.2763714134371;1213.7633161533415;1214.921030133056;1229.3668760588305;5722;5744.4829965303325;5750.568707346882;5758.596898217168;5759;6629.436497897636;6631.370019848203;6689.130413046273;6724.125863637696;6755.392014718699;5723.198752174699;5730.34101078373;5744.626599271034;5765.509931655773;5783;3517.648235176462;3557.83989720458;3565.447355659877;3589.892303230907;3601.7118630509494;6534.4445722113205;6552.641049222276;6558.868822623521;6568.441682457044;6591.604503729695;6783.050372947791;6790.558613690095;6818.2159051708695;6874.65626718662;6884.931150688429;5975.685349223077;6451.387258110286;6464.870702585922;6529.7388104475285;6532.444742196966;6530.310374638314;6610.710911372428;6654.13496245485;6674.866502669823;6727.2263689674355;6581.473482121514;6617.338266173344;6646.268910419753;6672.332766723289;6689.1972963244725;6325.936740632719;6395.136656551306;6400.551961362846;6423.96788016057;6431.935680643133;5302.310699609026;5449.264349312792;5469.3983661797965;5474.671519708831;5492.670439773627;6396.840078998062;6523.575967562093;6561.704723287368;6720.076766606542;6805.557638753465;6395.744170233324;6443.226812782496;6460.418562329413;6488.767134244478;6490.480761538971;6500.382463666133;6510.283868774336;6518.641474718936;6537.934620653733;6540.215174179129;6275.646632512808;6487.18910136233;6490.870182596227;6496.447801936888;6508.837279068061;6527.2167339919515;6528;6557.180352455943;6564.573302735307;6597.637129957899;6219.284782250099;6248.812535623939;6276.309605943434;6312.053192021233;6390.968045159744 03:48:51 INFO - PID 23268 | |5;yui.html;913.5249670171506;922.3451349541825;937.4469063252689;938.6855403439847;954.5704433005145;19073.71389429158;19705.31031413901;19705.60588788227;19749.11128999195;19771.802281977176;17755.91122044391;18014;18020.279188832432;18118.456446306598;18169;938.0713094036888;939.6053657463882;940.3699521320732;942.180303136269;949.1789601994294;967.4485543240356;978.1343510992751;980.2990861534001;981.9116279534841;982.1798798003675;973.1144658360893;1016.2632091733508;1018.7198520406911;1021.4943602916567;1021.8365061590147;1016.7051555049027;1019.4087429290994;1019.4596863662234;1019.9337043092175;1020.9183265338792;715.4026387966055;736.4660621049696;739.3826155160448;740.0749063670412;741.5402450480736;19410.70883936742;19670.508237294038;19680.114394852266;19691.507712307164;19730.90134549318;20592.691109633368;20780.168793248253;21490.14039438436;21512.247071352584;21575.67636485454;16352.427665031786;16506.504804855875;16610.335586576522;16688.83311166885;16754.497365079067;3779.905502362463;3784.8675296364772;3801.6958643308467;3805.6194380562;3808.428735689641;103306;104756;141596;142457;144042;16206;16428.260728267258;16630.168491575372;16643;16709.164541773105;30861;31003.224919377197;31006;31025.069247922598;31063.378732425226;8781.07798681142;8904.19862212404;8979.551022448983;9038.86441703375;9049.411788233876;4214.662826973835;4315.029118448387;4315.870523884289;4317.460317460317;4321.935170972438;5179.533841954242;5226.320578324793;5266.368035835724;5276.894462110813;5299.205119232146;1093.2620481175175;1110.0342701849281;1127.785720713062;1131.0216662586968;1133.110508251019;660.2802944790202;676.6954870308381;685.674304705271;693.8681650486492;698.0471656189367;11545.191836571359;12406.441710123067;12617.93691031539;13102.344882755824;13214.537491187955;697.6616341074575;697.7383481194552;698.249381914444;714.2821464428321;717.2612209424301;5575.665460072409;5598.608097433141;5746.88506229873;5841.824745257564;5844.211031510692;119240.40379798046;120264;122076;122197;122824.38587807004;107542;107638;107668.92331076745;107972;108708.4564577172;88000.5599971996;88279.55860220658;89325;93573.0642693578;93997.53001235086;10747.731306717395;10915.235933484897;10963.56145754161;10991.945040274748;11198.712148103072;41916.79041604773;42876.57123428726;43376;43738;43885.78057109694;55643.44356556382;55971.72014140066;56916;57820.71089644525;58148.4185158143;15417.152056636993;15591.29839157218;15785.921070394574;15799.36802527886;15965.760513592073;16251.3499460025;16334.836651633332;16345.26446309895;16544.917275413547;16616.418425355056;13385.531506397228;14311.212883291519;14363.138211706913;14438.566842994727;14540.781888271473;14734.336954837272;14861.331240094005;15076.698466030837;15089.924550377178;15091.169985650455;15492.457763978207;15509.22453877279;15631.921840390725;15756.842431575538;15768.60578485547;11935.403229838646;11988.280703157838;12000.39998000079;12042.458089385824;12431.31627760482;11755.647330580096;11800.645980620595;12126.878731212575;12312;12341.691457713629 03:48:51 INFO - PID 23268 | __end_tp_report 03:48:51 INFO - PID 23268 | __start_cc_report 03:48:51 INFO - PID 23268 | _x_x_mozilla_cycle_collect,291 03:48:51 INFO - PID 23268 | __end_cc_report 03:48:51 INFO - PID 23268 | __startTimestamp1505645331895__endTimestamp 03:48:51 INFO - PID 23268 | 03:48:51 INFO - PID 23268 | ------- Summary: start ------- 03:48:51 INFO - PID 23268 | Number of tests: 6 03:48:51 INFO - PID 23268 | 03:48:51 INFO - PID 23268 | [#0] dojo.html Cycles:165 Average:6165.72 Median:6454.64 stddev:869.29 (13.5%) stddev-sans-first:839.02 03:48:51 INFO - PID 23268 | Values: 3144.1 3146.5 3172.2 3183.0 3188.4 6074.1 6236.9 6249.2 6272.6 6390.9 6175.4 6197.9 6218.2 6232.8 6265.6 5731.1 5775.4 5796.8 5801.5 5883.5 5833.9 5871.3 5881.6 5891.9 5937.5 5806.6 5824.1 5875.0 5924.9 5937.6 502.4 5352.6 5680.9 5696.9 5845.9 5672.0 5691.1 5753.1 5760.4 5776.7 6180.3 6191.0 6234.9 6283.8 6329.1 6687.1 6720.4 6729.7 6779.3 6811.8 6657.4 6813.2 6877.7 6884.0 6887.8 6189.9 6190.8 6197.5 6227.7 6318.3 6920.8 6972.3 6990.7 7002.8 7044.5 6606.3 6608.6 6611.1 6700.1 6701.8 6553.4 6737.7 6739.4 6791.6 6835.4 6611.8 6629.1 6721.2 6730.6 6739.1 6747.3 6758.2 6772.6 6792.0 6817.9 5275.6 5284.6 5347.2 5358.1 5368.4 4460.1 4606.2 4635.2 4669.3 4670.5 5701.9 5832.4 5861.2 5891.6 5893.9 6758.4 6781.6 6783.6 6786.0 6803.5 5791.6 5791.6 5799.2 5869.7 5890.6 5650.9 5743.7 5745.8 5751.3 5831.6 6563.3 6646.5 6650.0 6656.5 6660.4 6704.2 6825.5 6842.2 6849.9 6852.2 6617.1 6633.3 6690.5 6707.7 6714.7 6482.0 6580.2 6592.3 6646.0 6734.4 6675.5 6697.0 6720.9 6778.3 6799.7 6504.7 6506.5 6564.4 6590.9 6603.1 6508.8 6521.8 6583.7 6593.5 6597.9 6295.4 6448.5 6449.2 6530.2 6534.2 6454.6 6499.1 6510.7 6520.5 6524.9 6324.1 6395.0 6445.1 6445.5 6456.1 03:48:51 INFO - PID 23268 | 03:48:51 INFO - PID 23268 | [#1] ext.html Cycles:180 Average:36851.83 Median:19396.22 stddev:51761.15 (266.9%) stddev-sans-first:51835.06 03:48:51 INFO - PID 23268 | Values: 671.5 693.6 693.7 693.8 699.9 37318.8 40467.2 40526.0 40607.0 40656.8 37065.4 37338.3 37366.4 37501.4 37517.1 6628.6 7657.9 7827.8 7843.0 7863.4 7582.3 7638.1 7670.4 7679.5 7707.7 7688.0 7695.4 7731.0 7767.2 7773.8 7701.8 7706.8 7708.5 7720.3 7736.3 7687.4 7757.0 7758.4 7758.5 7772.0 38112.8 38219.0 38241.6 38264.4 38272.6 3493.6 3535.6 3541.6 3550.8 3557.5 31456.7 31568.7 31626.7 31695.4 31715.4 4980.8 5173.8 5248.1 5254.3 5273.8 184953.0 185666.1 185828.1 186209.0 186526.0 41070.0 42443.0 47528.0 47559.0 47669.8 50556.5 50649.7 50820.2 50852.7 51007.5 15986.8 16006.2 16078.6 16154.8 16159.8 13214.0 13231.7 13236.7 13243.6 13372.3 13936.8 13986.6 14061.9 14143.3 14234.1 5828.6 5925.1 5970.5 5973.3 5989.6 1003.7 1020.6 1028.0 1030.7 1031.3 22858.8 23028.2 23078.2 23117.2 23124.8 1089.3 1103.3 1111.1 1111.5 1113.0 12257.2 12303.1 12303.5 12309.0 12371.0 218559.0 220474.0 221213.0 222168.0 222363.0 52826.5 53133.7 53275.7 53324.2 53380.2 1413.7 1456.4 1462.7 1465.3 1465.4 22601.8 22885.3 22919.8 22957.4 23214.8 36780.4 36807.3 36812.3 36878.0 36885.8 191321.0 191588.0 191956.0 192072.0 192195.0 33143.8 33517.8 35144.5 35160.0 35256.8 43284.0 43929.8 44074.6 44215.0 44245.6 32253.0 32527.3 32718.8 32840.5 33678.3 31912.7 32340.7 32667.0 32739.7 32903.8 35545.6 36099.0 36203.5 36493.5 36570.1 31735.0 31853.8 32310.2 32376.2 32677.7 23492.0 23737.8 23745.4 23799.8 24152.4 03:48:51 INFO - PID 23268 | 03:48:51 INFO - PID 23268 | [#2] jquery.html Cycles:180 Average:33687.62 Median:22581.89 stddev:70946.43 (314.2%) stddev-sans-first:71101.86 03:48:51 INFO - PID 23268 | Values: 559.7 563.2 573.9 580.3 591.3 22230.8 23077.7 23193.8 23249.1 23330.3 24804.4 25245.4 25621.0 26180.7 26231.9 6631.8 6816.2 6828.0 6861.2 6863.5 6662.1 6796.2 6801.9 6805.0 6805.1 6355.6 6629.8 6645.6 6783.1 6802.5 6494.5 6540.3 6545.4 6546.6 6549.7 5926.9 6030.4 6058.7 6068.7 6070.3 28603.1 29863.6 29915.0 29918.3 29927.1 2034.6 2077.3 2078.2 2080.3 2083.5 19335.1 19663.2 19940.6 19971.6 19998.7 2430.1 2475.1 2494.1 2500.2 2536.1 121779.0 124475.0 131956.0 135541.0 136283.3 20263.6 20878.4 21090.5 21462.9 22275.9 42072.4 42237.8 42292.4 42418.0 42442.4 11518.2 11574.9 11697.8 11723.8 11975.8 9656.9 9741.5 9790.1 9846.4 9851.8 10820.9 11049.7 11212.2 11469.7 11627.4 3463.0 3614.2 4107.5 4147.2 4200.2 944.5 966.3 966.5 968.4 971.2 19498.9 19542.7 19809.5 19916.8 20187.3 1030.0 1034.5 1038.6 1043.3 1050.6 10849.3 10885.4 10924.3 11123.8 11147.2 414619.0 415064.0 415745.0 420650.0 426280.0 104397.5 105614.5 106514.0 108406.5 110538.4 1018.6 1145.1 1162.7 1186.3 1194.3 20462.3 20637.6 21197.5 21288.1 21338.7 55753.4 56361.7 56870.4 58172.1 58404.0 72260.0 72472.6 72520.0 73321.6 73631.3 20081.8 20736.5 20939.0 20943.4 20948.6 22887.9 23029.2 23210.5 23334.6 23894.5 18072.1 18343.4 18583.1 18777.1 18842.6 16424.8 16609.9 16835.7 17017.2 17639.9 19062.2 19087.6 19095.7 19370.2 19400.1 20191.4 20243.6 20309.2 20335.0 20370.8 17185.3 17260.9 17264.6 17412.2 17469.7 03:48:51 INFO - PID 23268 | 03:48:51 INFO - PID 23268 | [#3] mootools.html Cycles:180 Average:9440.16 Median:2696.76 stddev:6879.60 (255.1%) stddev-sans-first:6872.02 03:48:51 INFO - PID 23268 | Values: 1347.1 1386.5 1412.4 1416.8 1418.8 14974.7 15598.2 15599.7 15604.1 15624.5 15154.3 15270.5 15303.1 15317.5 15323.2 2638.0 2686.4 2696.6 2708.2 2717.6 2695.5 2710.6 2724.5 2728.3 2732.5 2629.4 2675.4 2730.8 2736.2 2741.9 2746.6 2747.5 2767.8 2780.8 2781.3 2696.9 2704.9 2705.9 2721.7 2723.4 14844.1 15026.4 15079.2 15104.4 15109.6 8138.4 8394.4 8405.9 8442.9 8458.0 5508.1 5724.7 5759.5 5799.4 5850.9 1947.4 1963.4 1963.4 2014.4 2021.1 26968.7 27496.0 27513.0 27559.2 27588.2 7259.4 7410.6 7419.3 7579.8 7600.4 20229.9 20323.6 20333.3 20339.5 20356.8 8231.8 8232.8 8384.7 8486.0 8497.4 7522.7 7536.5 7542.1 7546.8 7612.5 5138.8 5289.6 5292.6 5308.7 5357.8 1583.7 1593.2 1610.5 1611.9 1619.1 828.3 854.7 862.7 877.6 891.6 6979.6 7009.8 7046.4 7061.6 7105.5 714.7 715.8 718.2 723.1 723.4 4338.9 4648.0 4875.0 5162.4 5214.6 20084.2 21286.9 21377.7 21874.5 22084.0 21557.2 21600.1 21605.9 21675.7 21927.3 10997.7 11244.5 11441.2 11482.8 11542.4 7574.0 7680.6 7705.8 7716.3 7935.1 18294.0 18618.9 18841.3 18939.3 19148.8 18698.8 19077.7 19263.2 19287.5 19293.7 10979.5 11157.9 11175.3 11198.8 11245.7 13842.3 13894.6 14076.9 14090.9 14175.9 10938.9 11236.8 11411.9 11485.7 11655.9 11020.7 11179.9 11293.2 11312.4 11414.8 10187.1 10247.5 10278.3 10365.1 10398.4 6580.9 6585.6 6655.3 6716.6 6762.0 6766.2 6804.6 6869.8 6897.6 6903.4 03:48:51 INFO - PID 23268 | 03:48:51 INFO - PID 23268 | [#4] prototype.html Cycles:180 Average:5495.55 Median:6062.16 stddev:1530.47 (25.2%) stddev-sans-first:1488.02 03:48:51 INFO - PID 23268 | Values: 494.9 499.6 529.6 530.4 531.2 6148.6 6294.7 6320.4 6338.4 6362.6 6173.3 6215.8 6221.0 6245.0 6273.9 5171.4 5200.7 5216.3 5217.5 5259.4 4779.5 4894.0 5112.4 5206.0 5218.1 4909.3 4940.4 4953.8 4955.8 4961.4 4927.1 4949.3 4960.8 4989.4 4990.8 4300.2 4389.9 4406.7 4418.6 4451.2 5882.4 5932.3 5939.9 5960.0 5975.2 5626.3 5668.5 5678.0 5683.9 5685.9 6409.2 6422.4 6433.8 6443.7 6443.8 4583.6 4818.2 4830.3 4834.4 4860.0 6531.3 6565.5 6792.4 6816.0 6850.2 4918.4 4970.5 4985.9 4992.7 4997.7 5014.0 5038.0 5039.7 5079.7 5084.7 5617.5 5657.3 5680.8 5684.3 5697.5 6540.4 6560.7 6561.8 6567.2 6626.7 1713.9 1718.0 1722.8 1740.5 1758.3 1160.3 1186.3 1213.8 1214.9 1229.4 5722.0 5744.5 5750.6 5758.6 5759.0 6629.4 6631.4 6689.1 6724.1 6755.4 5723.2 5730.3 5744.6 5765.5 5783.0 3517.6 3557.8 3565.4 3589.9 3601.7 6534.4 6552.6 6558.9 6568.4 6591.6 6783.1 6790.6 6818.2 6874.7 6884.9 5975.7 6451.4 6464.9 6529.7 6532.4 6530.3 6610.7 6654.1 6674.9 6727.2 6581.5 6617.3 6646.3 6672.3 6689.2 6325.9 6395.1 6400.6 6424.0 6431.9 5302.3 5449.3 5469.4 5474.7 5492.7 6396.8 6523.6 6561.7 6720.1 6805.6 6395.7 6443.2 6460.4 6488.8 6490.5 6500.4 6510.3 6518.6 6537.9 6540.2 6275.6 6487.2 6490.9 6496.4 6508.8 6527.2 6528.0 6557.2 6564.6 6597.6 6219.3 6248.8 6276.3 6312.1 6391.0 03:48:51 INFO - PID 23268 | 03:48:51 INFO - PID 23268 | [#5] yui.html Cycles:180 Average:23623.90 Median:19540.61 stddev:33896.99 (173.5%) stddev-sans-first:33949.18 03:48:51 INFO - PID 23268 | Values: 913.5 922.3 937.4 938.7 954.6 19073.7 19705.3 19705.6 19749.1 19771.8 17755.9 18014.0 18020.3 18118.5 18169.0 938.1 939.6 940.4 942.2 949.2 967.4 978.1 980.3 981.9 982.2 973.1 1016.3 1018.7 1021.5 1021.8 1016.7 1019.4 1019.5 1019.9 1020.9 715.4 736.5 739.4 740.1 741.5 19410.7 19670.5 19680.1 19691.5 19730.9 20592.7 20780.2 21490.1 21512.2 21575.7 16352.4 16506.5 16610.3 16688.8 16754.5 3779.9 3784.9 3801.7 3805.6 3808.4 103306.0 104756.0 141596.0 142457.0 144042.0 16206.0 16428.3 16630.2 16643.0 16709.2 30861.0 31003.2 31006.0 31025.1 31063.4 8781.1 8904.2 8979.6 9038.9 9049.4 4214.7 4315.0 4315.9 4317.5 4321.9 5179.5 5226.3 5266.4 5276.9 5299.2 1093.3 1110.0 1127.8 1131.0 1133.1 660.3 676.7 685.7 693.9 698.0 11545.2 12406.4 12617.9 13102.3 13214.5 697.7 697.7 698.2 714.3 717.3 5575.7 5598.6 5746.9 5841.8 5844.2 119240.4 120264.0 122076.0 122197.0 122824.4 107542.0 107638.0 107668.9 107972.0 108708.5 88000.6 88279.6 89325.0 93573.1 93997.5 10747.7 10915.2 10963.6 10991.9 11198.7 41916.8 42876.6 43376.0 43738.0 43885.8 55643.4 55971.7 56916.0 57820.7 58148.4 15417.2 15591.3 15785.9 15799.4 15965.8 16251.3 16334.8 16345.3 16544.9 16616.4 13385.5 14311.2 14363.1 14438.6 14540.8 14734.3 14861.3 15076.7 15089.9 15091.2 15492.5 15509.2 15631.9 15756.8 15768.6 11935.4 11988.3 12000.4 12042.5 12431.3 11755.6 11800.6 12126.9 12312.0 12341.7 03:48:51 INFO - PID 23268 | -------- Summary: end -------- 03:48:51 INFO - PID 23268 | 03:48:52 INFO - TEST-INFO | 23268: exit 0 03:48:52 INFO - TEST-OK | dromaeo_css | took 1108434ms 03:48:52 INFO - TEST-START | kraken 03:48:52 INFO - Initialising browser for kraken test... 03:48:52 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57847/getInfo.html -profile /tmp/tmpZgAnCF/profile 03:48:52 INFO - TEST-INFO | started process 23640 (/builds/slave/test/build/application/firefox/firefox http://localhost:57847/getInfo.html) 03:48:57 INFO - TEST-INFO | 23640: exit 0 03:48:58 INFO - Browser initialized. 03:48:58 INFO - Running cycle 1/1 for kraken test... 03:48:58 INFO - Using env: {'DISPLAY': ':0', 03:48:58 INFO - 'HOME': '/home/cltbld', 03:48:58 INFO - 'JSGC_DISABLE_POISONING': '1', 03:48:58 INFO - 'LANG': 'en_US.UTF-8', 03:48:58 INFO - 'LANGUAGE': 'en_US:en', 03:48:58 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:48:58 INFO - 'LOGNAME': 'cltbld', 03:48:58 INFO - 'MAIL': '/var/mail/cltbld', 03:48:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:48:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:48:58 INFO - 'MOZ_CRASHREPORTER': '1', 03:48:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:58 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:48:58 INFO - 'MOZ_NO_REMOTE': '1', 03:48:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:48:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:58 INFO - 'NO_EM_RESTART': '1', 03:48:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:58 INFO - 'PWD': '/builds/slave/test', 03:48:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:48:58 INFO - 'RUST_BACKTRACE': 'full', 03:48:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:48:58 INFO - 'SHELL': '/bin/bash', 03:48:58 INFO - 'SHLVL': '1', 03:48:58 INFO - 'TERM': 'linux', 03:48:58 INFO - 'TMOUT': '86400', 03:48:58 INFO - 'USER': 'cltbld', 03:48:58 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935', 03:48:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:58 INFO - '_': '/tools/buildbot/bin/python'} 03:48:58 INFO - TEST-INFO | started process 23873 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZgAnCF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:48:58 INFO - PID 23873 | 03:48:58 INFO - PID 23873 | (/builds/slave/test/build/application/firefox/firefox:23922): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:48:58 INFO - PID 23873 | 03:49:09 INFO - PID 23873 | RSS: Main: 155078656 03:49:09 INFO - PID 23873 | 03:49:11 INFO - PID 23873 | Cycle 1(1): loaded http://localhost:57847/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57847/tests/kraken/driver.html?testName=audio-beat-detection) 03:49:12 INFO - PID 23873 | RSS: Main: 160477184 03:49:12 INFO - PID 23873 | 03:49:15 INFO - PID 23873 | Cycle 1(1): loaded http://localhost:57847/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57847/tests/kraken/driver.html?testName=audio-dft) 03:49:16 INFO - PID 23873 | RSS: Main: 160837632 03:49:16 INFO - PID 23873 | 03:49:19 INFO - PID 23873 | Cycle 1(1): loaded http://localhost:57847/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57847/tests/kraken/driver.html?testName=audio-fft) 03:49:19 INFO - PID 23873 | RSS: Main: 157908992 03:49:19 INFO - PID 23873 | 03:49:21 INFO - PID 23873 | Cycle 1(1): loaded http://localhost:57847/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57847/tests/kraken/driver.html?testName=audio-oscillator) 03:49:22 INFO - PID 23873 | RSS: Main: 158113792 03:49:22 INFO - PID 23873 | 03:49:24 INFO - PID 23873 | Cycle 1(1): loaded http://localhost:57847/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57847/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:49:25 INFO - PID 23873 | RSS: Main: 158392320 03:49:25 INFO - PID 23873 | 03:49:28 INFO - PID 23873 | Cycle 1(1): loaded http://localhost:57847/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57847/tests/kraken/driver.html?testName=imaging-darkroom) 03:49:28 INFO - PID 23873 | RSS: Main: 160452608 03:49:28 INFO - PID 23873 | 03:49:32 INFO - PID 23873 | Cycle 1(1): loaded http://localhost:57847/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57847/tests/kraken/driver.html?testName=imaging-desaturate) 03:49:33 INFO - PID 23873 | RSS: Main: 160018432 03:49:33 INFO - PID 23873 | 03:49:36 INFO - PID 23873 | Cycle 1(1): loaded http://localhost:57847/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57847/tests/kraken/driver.html?testName=json-parse-financial) 03:49:36 INFO - PID 23873 | RSS: Main: 160681984 03:49:36 INFO - PID 23873 | 03:49:38 INFO - PID 23873 | Cycle 1(1): loaded http://localhost:57847/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57847/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:49:38 INFO - PID 23873 | RSS: Main: 160550912 03:49:38 INFO - PID 23873 | 03:49:40 INFO - PID 23873 | Cycle 1(1): loaded http://localhost:57847/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57847/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:49:41 INFO - PID 23873 | RSS: Main: 160690176 03:49:41 INFO - PID 23873 | 03:49:43 INFO - PID 23873 | Cycle 1(1): loaded http://localhost:57847/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57847/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:49:44 INFO - PID 23873 | RSS: Main: 157560832 03:49:44 INFO - PID 23873 | 03:49:46 INFO - PID 23873 | Cycle 1(1): loaded http://localhost:57847/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57847/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:49:47 INFO - PID 23873 | RSS: Main: 157810688 03:49:47 INFO - PID 23873 | 03:49:50 INFO - PID 23873 | Cycle 1(1): loaded http://localhost:57847/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57847/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:49:50 INFO - PID 23873 | RSS: Main: 158109696 03:49:50 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | Cycle 1(1): loaded http://localhost:57847/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57847/tests/kraken/driver.html?testName=ai-astar) 03:49:52 INFO - PID 23873 | RSS: Main: 158998528 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | __start_tp_report 03:49:52 INFO - PID 23873 | _x_x_mozilla_page_load 03:49:52 INFO - PID 23873 | _x_x_mozilla_page_load_details 03:49:52 INFO - PID 23873 | |i|pagename|runs| 03:49:52 INFO - PID 23873 | |0;ai-astar;110;109;108;107;106;109;107;107;108;107 03:49:52 INFO - PID 23873 | |1;audio-beat-detection;115;117;119;117;115;118;119;122;118;119 03:49:52 INFO - PID 23873 | |2;audio-dft;163;163;163;164;164;162;163;162;167;168 03:49:52 INFO - PID 23873 | |3;audio-fft;75;74;74;75;74;74;74;84;74;73 03:49:52 INFO - PID 23873 | |4;audio-oscillator;80;80;81;79;79;79;80;80;77;78 03:49:52 INFO - PID 23873 | |5;imaging-gaussian-blur;109;114;113;113;113;114;114;114;114;114 03:49:52 INFO - PID 23873 | |6;imaging-darkroom;151;147;148;148;149;148;159;150;149;148 03:49:52 INFO - PID 23873 | |7;imaging-desaturate;97;94;99;94;94;95;95;94;94;94 03:49:52 INFO - PID 23873 | |8;json-parse-financial;58;54;54;56;55;62;57;57;57;53 03:49:52 INFO - PID 23873 | |9;json-stringify-tinderbox;38;37;38;37;38;37;37;38;37;37 03:49:52 INFO - PID 23873 | |10;stanford-crypto-aes;77;78;73;76;74;74;76;75;74;75 03:49:52 INFO - PID 23873 | |11;stanford-crypto-ccm;111;113;109;111;118;122;130;116;111;113 03:49:52 INFO - PID 23873 | |12;stanford-crypto-pbkdf2;169;174;179;169;169;166;174;168;172;170 03:49:52 INFO - PID 23873 | |13;stanford-crypto-sha256-iterative;64;67;67;65;63;67;66;65;63;65 03:49:52 INFO - PID 23873 | __end_tp_report 03:49:52 INFO - PID 23873 | __start_cc_report 03:49:52 INFO - PID 23873 | _x_x_mozilla_cycle_collect,711 03:49:52 INFO - PID 23873 | __end_cc_report 03:49:52 INFO - PID 23873 | __startTimestamp1505645392799__endTimestamp 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | ------- Summary: start ------- 03:49:52 INFO - PID 23873 | Number of tests: 14 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | [#0] ai-astar Cycles:10 Average:107.80 Median:108.00 stddev:1.23 (1.1%) stddev-sans-first:1.01 03:49:52 INFO - PID 23873 | Values: 110.0 109.0 108.0 107.0 106.0 109.0 107.0 107.0 108.0 107.0 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | [#1] audio-beat-detection Cycles:10 Average:117.90 Median:118.50 stddev:2.08 (1.8%) stddev-sans-first:1.92 03:49:52 INFO - PID 23873 | Values: 115.0 117.0 119.0 117.0 115.0 118.0 119.0 122.0 118.0 119.0 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | [#2] audio-dft Cycles:10 Average:163.90 Median:163.50 stddev:2.02 (1.2%) stddev-sans-first:2.12 03:49:52 INFO - PID 23873 | Values: 163.0 163.0 163.0 164.0 164.0 162.0 163.0 162.0 167.0 168.0 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | [#3] audio-fft Cycles:10 Average:75.10 Median:74.00 stddev:3.18 (4.3%) stddev-sans-first:3.37 03:49:52 INFO - PID 23873 | Values: 75.0 74.0 74.0 75.0 74.0 74.0 74.0 84.0 74.0 73.0 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | [#4] audio-oscillator Cycles:10 Average:79.30 Median:80.00 stddev:1.16 (1.4%) stddev-sans-first:1.20 03:49:52 INFO - PID 23873 | Values: 80.0 80.0 81.0 79.0 79.0 79.0 80.0 80.0 77.0 78.0 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | [#5] imaging-gaussian-blur Cycles:10 Average:113.20 Median:114.00 stddev:1.55 (1.4%) stddev-sans-first:0.50 03:49:52 INFO - PID 23873 | Values: 109.0 114.0 113.0 113.0 113.0 114.0 114.0 114.0 114.0 114.0 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:149.00 stddev:3.47 (2.3%) stddev-sans-first:3.64 03:49:52 INFO - PID 23873 | Values: 151.0 147.0 148.0 148.0 149.0 148.0 159.0 150.0 149.0 148.0 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:94.50 stddev:1.70 (1.8%) stddev-sans-first:1.64 03:49:52 INFO - PID 23873 | Values: 97.0 94.0 99.0 94.0 94.0 95.0 95.0 94.0 94.0 94.0 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | [#8] json-parse-financial Cycles:10 Average:56.30 Median:57.00 stddev:2.58 (4.5%) stddev-sans-first:2.67 03:49:52 INFO - PID 23873 | Values: 58.0 54.0 54.0 56.0 55.0 62.0 57.0 57.0 57.0 53.0 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | [#9] json-stringify-tinderbox Cycles:10 Average:37.40 Median:37.50 stddev:0.52 (1.4%) stddev-sans-first:0.50 03:49:52 INFO - PID 23873 | Values: 38.0 37.0 38.0 37.0 38.0 37.0 37.0 38.0 37.0 37.0 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | [#10] stanford-crypto-aes Cycles:10 Average:75.20 Median:75.50 stddev:1.55 (2.1%) stddev-sans-first:1.50 03:49:52 INFO - PID 23873 | Values: 77.0 78.0 73.0 76.0 74.0 74.0 76.0 75.0 74.0 75.0 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | [#11] stanford-crypto-ccm Cycles:10 Average:115.40 Median:114.50 stddev:6.45 (5.6%) stddev-sans-first:6.64 03:49:52 INFO - PID 23873 | Values: 111.0 113.0 109.0 111.0 118.0 122.0 130.0 116.0 111.0 113.0 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.00 Median:171.00 stddev:3.80 (2.2%) stddev-sans-first:3.96 03:49:52 INFO - PID 23873 | Values: 169.0 174.0 179.0 169.0 169.0 166.0 174.0 168.0 172.0 170.0 03:49:52 INFO - PID 23873 | 03:49:52 INFO - PID 23873 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.50 stddev:1.55 (2.4%) stddev-sans-first:1.58 03:49:52 INFO - PID 23873 | Values: 64.0 67.0 67.0 65.0 63.0 67.0 66.0 65.0 63.0 65.0 03:49:52 INFO - PID 23873 | -------- Summary: end -------- 03:49:52 INFO - PID 23873 | 03:49:53 INFO - PID 23873 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:49:53 INFO - TEST-INFO | 23873: exit 0 03:49:53 INFO - TEST-OK | kraken | took 60829ms 03:49:53 INFO - SUITE-END | took 1169s 03:49:53 INFO - Completed test suite (00:19:29) 03:49:53 INFO - javascript benchmark 03:49:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8562.721331636918, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3144.1039303798416, 3146.5280207968804, 3172.2228054126745, 3183.013265887574, 3188.4101441233374, 6074.149619053336, 6236.906446403307, 6249.218847644044, 6272.56092073555, 6390.904136437957, 6175.444210021097, 6197.938020619792, 6218.160548325986, 6232.750689972396, 6265.624062556239, 5731.14032895067, 5775.393583673716, 5796.82609521715, 5801.53587712982, 5883.499902508292, 5833.941660583382, 5871.266091738533, 5881.558883083764, 5891.941080589204, 5937.4656280934705, 5806.622569532967, 5824.097264923944, 5875.0, 5924.911126333109, 5937.6140550864275, 502.3589431405375, 5352.598555108343, 5680.914786278167, 5696.943030569724, 5845.85385365365, 5671.971640141773, 5691.146328050784, 5753.137029445575, 5760.423957604248, 5776.711164441761, 6180.258368995705, 6191.0, 6234.906476402857, 6283.7800676976085, 6329.05064240363, 6687.063811066455, 6720.361565651256, 6729.663516824139, 6779.32206779323, 6811.829704257433, 6657.434118099919, 6813.182418109858, 6877.65611719412, 6883.967404889257, 6887.758928437481, 6189.907151392733, 6190.783322583733, 6197.4732147767045, 6227.657478838707, 6318.336574659686, 6920.788861949139, 6972.337627925441, 6990.685419156151, 7002.7899163025195, 7044.471664625174, 6606.306337834554, 6608.570442921195, 6611.140551728245, 6700.061991321219, 6701.798946031627, 6553.377429144223, 6737.6968036438475, 6739.359760822714, 6791.626460544718, 6835.350641689031, 6611.801645950629, 6629.071929929813, 6721.193456785119, 6730.562513436709, 6739.090222819955, 6747.291534388916, 6758.222804377461, 6772.5936443813525, 6791.981202819517, 6817.863642727118, 5275.60432967529, 5284.603654725911, 5347.197920311906, 5358.062339090644, 5368.436314187033, 4460.063386688832, 4606.239970404846, 4635.2120139576355, 4669.34629151919, 4670.486246512881, 5701.857453563611, 5832.358440571534, 5861.150133230658, 5891.617044892068, 5893.911591326134, 6758.357955994271, 6781.593104413751, 6783.59298442095, 6786.049953006583, 6803.523753337219, 5791.594588378857, 5791.62354446968, 5799.217105690762, 5869.706514674334, 5890.558208134407, 5650.85872853182, 5743.712814359349, 5745.827625171251, 5751.338596061423, 5831.562632802472, 6563.310852360529, 6646.534742567974, 6649.966750166218, 6656.534042617067, 6660.433863121689, 6704.195496540447, 6825.453963682893, 6842.213145488233, 6849.897251541331, 6852.211995620368, 6617.139771829824, 6633.270340262664, 6690.498212633975, 6707.664616769044, 6714.664266786739, 6481.967590162019, 6580.21037475506, 6592.307807680125, 6645.966770166118, 6734.427573656294, 6675.465962723068, 6697.0, 6720.899186512109, 6778.288279730557, 6799.694013769491, 6504.674766261763, 6506.544541882023, 6564.442022427956, 6590.934090659032, 6603.075569420189, 6508.8047358579315, 6521.836954075996, 6583.703733331916, 6593.538452308484, 6597.868042639217, 6295.433410993032, 6448.548185974757, 6449.226092868886, 6530.216374035146, 6534.248561415498, 6454.644994525158, 6499.122618446639, 6510.674466276572, 6520.543561950618, 6524.934750652433, 6324.083007963727, 6394.968025159845, 6445.097686324012, 6445.548811583331, 6456.12842266288], "value": 6102.332363957163, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.4930227678104, 693.6462404173869, 693.691307368221, 693.8438851258468, 699.9020137180793, 37318.81340593297, 40467.19065618686, 40526.0, 40607.0, 40656.79671601642, 37065.44401833968, 37338.253234935284, 37366.43950340747, 37501.437478437845, 37517.06207344818, 6628.6022838629715, 7657.885131723001, 7827.765167044998, 7843.0, 7863.370930325589, 7582.317591416771, 7638.121616014174, 7670.386369090487, 7679.462437629368, 7707.730229441971, 7688.038995125609, 7695.384369250447, 7731.0336207974005, 7767.184445633212, 7773.766786996399, 7701.845963080735, 7706.7687969361, 7708.537487750753, 7720.305172534443, 7736.34241089508, 7687.385009199251, 7756.961215193888, 7758.418118641068, 7758.495697779683, 7771.961140194319, 38112.80943595292, 38219.0, 38241.61758382408, 38264.42603360924, 38272.617273827185, 3493.5633045869267, 3535.611082780892, 3541.628129046439, 3550.7869527828157, 3557.5019497270405, 31456.685433145834, 31568.684313156806, 31626.683733162605, 31695.366092678247, 31715.36569268625, 4980.7509624518625, 5173.844784656466, 5248.081585722483, 5254.290670759437, 5273.762680679379, 184953.0, 185666.07166964214, 185828.0708596462, 186209.0, 186526.0, 41070.0, 42443.0, 47528.0, 47559.0, 47669.76165119222, 50556.494435055916, 50649.74675126601, 50820.23769643458, 50852.745736271085, 51007.48992510027, 15986.840131598536, 16006.199690015452, 16078.598035049217, 16154.757678634594, 16159.757603635955, 13214.0, 13231.73536529264, 13236.669083272995, 13243.602691919257, 13372.33138343079, 13936.790948135786, 13986.580402587735, 14061.859381406259, 14143.29283535819, 14234.14595124296, 5828.59199856006, 5925.140854576062, 5970.522358211333, 5973.253343332083, 5989.580729348903, 1003.7340104872214, 1020.5815615597569, 1027.9743006424899, 1030.6547306652205, 1031.3193292426963, 22858.771412285998, 23028.194013209457, 23078.192263270706, 23117.190898318477, 23124.768752312597, 1089.2593036735095, 1103.2938919091787, 1111.149970272742, 1111.4887151910211, 1112.9777404451866, 12257.203281786655, 12303.077269204845, 12303.540641163281, 12309.0, 12371.0, 218559.0, 220474.0, 221213.0, 222168.0, 222363.0, 52826.471735282925, 53133.734331328094, 53275.733621331645, 53324.20013699798, 53380.19929701058, 1413.6536548545466, 1456.4174330267977, 1462.7367073926796, 1465.2893346726828, 1465.4358072142088, 22601.773982260624, 22885.31344059681, 22919.770802291765, 22957.426064348525, 23214.76785232126, 36780.44829327509, 36807.26385472329, 36812.26375472529, 36878.0, 36885.81557092197, 191321.0, 191588.0, 191956.0, 192072.0, 192195.0, 33143.83428082844, 33517.83241083877, 35144.47283290804, 35160.0, 35256.823715881255, 43284.0, 43929.78035109804, 44074.55925440833, 44215.0, 44245.55754442414, 32253.0, 32527.349453010334, 32718.83640581782, 32840.50739238961, 33678.32643347168, 31912.68087319097, 32340.676593234708, 32667.0, 32739.672603273662, 32903.83548082244, 35545.64454355527, 36099.0, 36203.45694814527, 36493.45259821158, 36570.085747856516, 31735.0, 31853.840730796197, 32310.192245193117, 32376.190595235308, 32677.673223267462, 23492.0, 23737.762622373557, 23745.406364841016, 23799.76200238045, 24152.396190095387], "value": 16968.14065090158, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.7173427419156, 563.1974436428089, 573.8737477754893, 580.2775544447164, 591.2934043817637, 22230.777692223073, 23077.653835192486, 23193.76806231937, 23249.070037198533, 23330.300090997254, 24804.379890502747, 25245.36886577832, 25621.0, 26180.738192618115, 26231.868840655818, 6631.834204144899, 6816.182058153028, 6828.0, 6861.245263021064, 6863.4852386071, 6662.1339225900565, 6796.184457865038, 6801.93198068018, 6804.965975170142, 6805.081314022593, 6355.555111142222, 6629.767958121467, 6645.60126392418, 6783.084283621697, 6802.523823332369, 6494.480441564687, 6540.313267106981, 6545.37818907203, 6546.574472659262, 6549.672516374162, 5926.911096333559, 6030.366811484775, 6058.666773327465, 6068.726907289182, 6070.271567411939, 28603.14190574286, 29863.552046719316, 29915.0, 29918.252043698863, 29927.102186934426, 2034.6032523657893, 2077.2833372486466, 2078.18950609261, 2080.251109600543, 2083.499960009602, 19335.12991915367, 19663.213471461124, 19940.601187976303, 19971.600567988706, 19998.70001949972, 2430.149447693311, 2475.059477398577, 2494.052260141153, 2500.1874390823054, 2536.1250368622796, 121779.0, 124475.0, 131956.0, 135541.0, 136283.31858340744, 20263.568371795478, 20878.37364879056, 21090.47273818136, 21462.892685536473, 22275.888620556794, 42072.36891446631, 42237.788811056365, 42292.36561451581, 42418.0, 42442.36336454956, 11518.193726439069, 11574.88425115755, 11697.824532632018, 11723.824142637697, 11975.820362694567, 9656.903430965742, 9741.512924353754, 9790.118889299998, 9846.359986600848, 9851.75370615726, 10820.945895270474, 11049.723756905982, 11212.159088068427, 11469.655910322703, 11627.418629068512, 3462.9653703463146, 3614.2410093880053, 4107.486564179478, 4147.212029714345, 4200.222958752641, 944.5324564340696, 966.3235734985398, 966.4539535162611, 968.3995922528078, 971.2472833554052, 19498.902505487666, 19542.70685939712, 19809.504762381057, 19916.800831991495, 20187.293444729657, 1029.953652085658, 1034.5499707627207, 1038.600138946511, 1043.2801367056707, 1050.5784167494, 10849.294795838245, 10885.401302928256, 10924.344539327665, 11123.777524449466, 11147.21969462146, 414619.0, 415064.0, 415745.0, 420650.0, 426280.0, 104397.47801261097, 105614.47192763987, 106514.0, 108406.45796770966, 110538.44730776295, 1018.6027449294781, 1145.1297014269048, 1162.7093226693328, 1186.3297237061033, 1194.2714943884223, 20462.283820066226, 20637.58724825525, 21197.470063248544, 21288.148474060865, 21338.679919800907, 55753.442465576445, 56361.71819140878, 56870.43129568651, 58172.12741808792, 58404.0, 72260.0, 72472.63763681147, 72520.0, 73321.6333918327, 73631.26368736244, 20081.79918200799, 20736.48158796042, 20939.0, 20943.37169884906, 20948.581028379653, 22887.88556057209, 23029.19397821135, 23210.53578928446, 23334.649980250648, 23894.52210955806, 18072.096395179924, 18343.35798247055, 18583.07084645735, 18777.061146942873, 18842.623147537248, 16424.83575164233, 16609.916950415172, 16835.747463787808, 17017.23422445968, 17639.911800440917, 19062.23751049998, 19087.618247635248, 19095.713564296824, 19370.2251909922, 19400.12699428501, 20191.394258172277, 20243.59512809765, 20309.187632494533, 20334.983250837104, 20370.79629203689, 17185.31258749636, 17260.91369543144, 17264.568385789953, 17412.21645026002, 17469.65060698804], "value": 12797.138572483434, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.0570600579595, 1386.521650030739, 1412.399730114701, 1416.8016477693122, 1418.7517184492717, 14974.700505989887, 15598.220088995547, 15599.688006239869, 15604.063756174637, 15624.453144139943, 15154.318055687492, 15270.465533706323, 15303.081815091102, 15317.540473785777, 15323.233838308095, 2638.0, 2686.408990022192, 2696.6224728538023, 2708.241692326142, 2717.578775289833, 2695.5199321881737, 2710.5798601216743, 2724.4551089782026, 2728.2770065932446, 2732.508148533263, 2629.434671545617, 2675.3579141006117, 2730.795190360721, 2736.192823117175, 2741.9451610967767, 2746.6292050573115, 2747.4779791839587, 2767.8339299642084, 2780.805343625947, 2781.3463835998523, 2696.9056083037194, 2704.9045136719465, 2705.91882243531, 2721.7414345637135, 2723.360010397555, 14844.10935343872, 15026.398944042225, 15079.170645614488, 15104.39582416702, 15109.622259443491, 8138.430309878311, 8394.412391132562, 8405.915940840574, 8442.915570844274, 8458.02732685743, 5508.118701007819, 5724.685142317171, 5759.510441612468, 5799.3910639382675, 5850.882982340329, 1947.4255094747084, 1963.3520938090396, 1963.4207908666835, 2014.446027342478, 2021.080408414168, 26968.730312696818, 27496.0, 27512.9999999998, 27559.173224803086, 27588.17235482939, 7259.382952448996, 7410.592417417094, 7419.29516695913, 7579.848403032019, 7600.3539699126195, 20229.898850505655, 20323.593528129353, 20333.288334908208, 20339.491512712302, 20356.796432035786, 8231.794205144919, 8232.794180145544, 8384.664613415516, 8486.0, 8497.447665901696, 7522.736704215326, 7536.472446928662, 7542.057242844645, 7546.811329716691, 7612.467127301035, 5138.794448222104, 5289.550388216968, 5292.603054770908, 5308.654937429132, 5357.758900849393, 1583.691180219846, 1593.219322531974, 1610.452446168285, 1611.887167898236, 1619.1094897806395, 828.329053466694, 854.7051267312767, 862.7498025572513, 877.635781150828, 891.5675897189858, 6979.616121113388, 7009.7897063088185, 7046.401055910203, 7061.6469176542, 7105.467089968273, 714.720650036429, 715.8282012317111, 718.1597530888872, 723.0997408226687, 723.3742812467178, 4338.8718933077, 4648.0, 4874.95125048745, 5162.432132465426, 5214.556762675139, 20084.19663213455, 21286.893565532075, 21377.67933480999, 21874.45313867166, 22084.0, 21557.24549640755, 21600.13599456004, 21605.891970540048, 21675.67486487704, 21927.342179734635, 10997.725056873482, 11244.49399777012, 11441.199116061796, 11482.77034459323, 11542.422878856192, 7573.962130189314, 7680.615969201629, 7705.845883082419, 7716.344110750649, 7935.0874649413745, 18294.0, 18618.906905465385, 18841.340553080576, 18939.337123201174, 19148.80851191526, 18698.813011869708, 19077.71383429222, 19263.22947082157, 19287.517812054248, 19293.710594340817, 10979.451027448755, 11157.944210278896, 11175.329480231212, 11198.776024479628, 11245.71885702864, 13842.30788460593, 13894.583162505141, 14076.859231407965, 14090.929545352208, 14175.929120354333, 10938.890611094106, 11236.775264494501, 11411.885881141414, 11485.712857178303, 11655.941720291345, 11020.669379918616, 11179.944100279447, 11293.153013523854, 11312.377819220023, 11414.771704566028, 10187.13409360183, 10247.48762561854, 10278.33190842593, 10365.118964888068, 10398.428086455318, 6580.934190658032, 6585.6377899216, 6655.301193374819, 6716.597004179765, 6762.0, 6766.18805743314, 6804.6257455840405, 6869.7595584154315, 6897.62063086515, 6903.378695917391], "value": 6662.927139442169, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.9269982677555, 499.6466642115635, 529.5657560800144, 530.4456842599484, 531.183446933781, 6148.63108213507, 6294.716737746801, 6320.367963203677, 6338.429541341278, 6362.586431881936, 6173.320934697191, 6215.7513699451965, 6221.0, 6245.0, 6273.874522509547, 5171.4311425743235, 5200.7399630018535, 5216.26972223889, 5217.47825217479, 5259.421463638997, 4779.498152693969, 4893.975530122344, 5112.36095488064, 5206.0, 5218.112920803454, 4909.288153217782, 4940.382452193476, 4953.777080031407, 4955.801767929296, 4961.3798275215595, 4927.113119638464, 4949.257611358289, 4960.776765045582, 4989.426215985171, 4990.75046247688, 4300.22595932732, 4389.868303950887, 4406.713563618355, 4418.602325790662, 4451.243288640906, 5882.411758824083, 5932.279638904713, 5939.910901336483, 5960.033189047604, 5975.163477113208, 5626.324841019104, 5668.4614961579, 5677.971610141964, 5683.857903552403, 5685.943140568624, 6409.166808314936, 6422.389872962061, 6433.774817881352, 6443.710033048477, 6443.83890402739, 4583.564561366665, 4818.156822556039, 4830.275458681191, 4834.419869615634, 4859.979404325096, 6531.346865313478, 6565.54041217115, 6792.388685018323, 6816.023353227558, 6850.21222559412, 4918.360613120343, 4970.545470897691, 4985.925211121836, 4992.675476094042, 4997.675151115166, 5013.974930125399, 5037.974810125926, 5039.697618142924, 5079.746012699424, 5084.720340381241, 5617.494425501724, 5657.264555607827, 5680.77276908919, 5684.34630017545, 5697.547125482975, 6540.411362977355, 6560.720659471453, 6561.8359541010905, 6567.179102612174, 6626.7017984190825, 1713.922873470697, 1717.9656406872043, 1722.811260230437, 1740.4691569071301, 1758.2703178181168, 1160.3444054109402, 1186.2763714134371, 1213.7633161533415, 1214.921030133056, 1229.3668760588305, 5722.0, 5744.4829965303325, 5750.568707346882, 5758.596898217168, 5759.0, 6629.436497897636, 6631.370019848203, 6689.130413046273, 6724.125863637696, 6755.392014718699, 5723.198752174699, 5730.34101078373, 5744.626599271034, 5765.509931655773, 5783.0, 3517.648235176462, 3557.83989720458, 3565.447355659877, 3589.892303230907, 3601.7118630509494, 6534.4445722113205, 6552.641049222276, 6558.868822623521, 6568.441682457044, 6591.604503729695, 6783.050372947791, 6790.558613690095, 6818.2159051708695, 6874.65626718662, 6884.931150688429, 5975.685349223077, 6451.387258110286, 6464.870702585922, 6529.7388104475285, 6532.444742196966, 6530.310374638314, 6610.710911372428, 6654.13496245485, 6674.866502669823, 6727.2263689674355, 6581.473482121514, 6617.338266173344, 6646.268910419753, 6672.332766723289, 6689.1972963244725, 6325.936740632719, 6395.136656551306, 6400.551961362846, 6423.96788016057, 6431.935680643133, 5302.310699609026, 5449.264349312792, 5469.3983661797965, 5474.671519708831, 5492.670439773627, 6396.840078998062, 6523.575967562093, 6561.704723287368, 6720.076766606542, 6805.557638753465, 6395.744170233324, 6443.226812782496, 6460.418562329413, 6488.767134244478, 6490.480761538971, 6500.382463666133, 6510.283868774336, 6518.641474718936, 6537.934620653733, 6540.215174179129, 6275.646632512808, 6487.18910136233, 6490.870182596227, 6496.447801936888, 6508.837279068061, 6527.2167339919515, 6528.0, 6557.180352455943, 6564.573302735307, 6597.637129957899, 6219.284782250099, 6248.812535623939, 6276.309605943434, 6312.053192021233, 6390.968045159744], "value": 5043.592921947393, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [913.5249670171506, 922.3451349541825, 937.4469063252689, 938.6855403439847, 954.5704433005145, 19073.71389429158, 19705.31031413901, 19705.60588788227, 19749.11128999195, 19771.802281977176, 17755.91122044391, 18014.0, 18020.279188832432, 18118.456446306598, 18169.0, 938.0713094036888, 939.6053657463882, 940.3699521320732, 942.180303136269, 949.1789601994294, 967.4485543240356, 978.1343510992751, 980.2990861534001, 981.9116279534841, 982.1798798003675, 973.1144658360893, 1016.2632091733508, 1018.7198520406911, 1021.4943602916567, 1021.8365061590147, 1016.7051555049027, 1019.4087429290994, 1019.4596863662234, 1019.9337043092175, 1020.9183265338792, 715.4026387966055, 736.4660621049696, 739.3826155160448, 740.0749063670412, 741.5402450480736, 19410.70883936742, 19670.508237294038, 19680.114394852266, 19691.507712307164, 19730.90134549318, 20592.691109633368, 20780.168793248253, 21490.14039438436, 21512.247071352584, 21575.67636485454, 16352.427665031786, 16506.504804855875, 16610.335586576522, 16688.83311166885, 16754.497365079067, 3779.905502362463, 3784.8675296364772, 3801.6958643308467, 3805.6194380562, 3808.428735689641, 103306.0, 104756.0, 141596.0, 142457.0, 144042.0, 16206.0, 16428.260728267258, 16630.168491575372, 16643.0, 16709.164541773105, 30861.0, 31003.224919377197, 31006.0, 31025.069247922598, 31063.378732425226, 8781.07798681142, 8904.19862212404, 8979.551022448983, 9038.86441703375, 9049.411788233876, 4214.662826973835, 4315.029118448387, 4315.870523884289, 4317.460317460317, 4321.935170972438, 5179.533841954242, 5226.320578324793, 5266.368035835724, 5276.894462110813, 5299.205119232146, 1093.2620481175175, 1110.0342701849281, 1127.785720713062, 1131.0216662586968, 1133.110508251019, 660.2802944790202, 676.6954870308381, 685.674304705271, 693.8681650486492, 698.0471656189367, 11545.191836571359, 12406.441710123067, 12617.93691031539, 13102.344882755824, 13214.537491187955, 697.6616341074575, 697.7383481194552, 698.249381914444, 714.2821464428321, 717.2612209424301, 5575.665460072409, 5598.608097433141, 5746.88506229873, 5841.824745257564, 5844.211031510692, 119240.40379798046, 120264.0, 122076.0, 122197.0, 122824.38587807004, 107542.0, 107638.0, 107668.92331076745, 107972.0, 108708.4564577172, 88000.5599971996, 88279.55860220658, 89325.0, 93573.0642693578, 93997.53001235086, 10747.731306717395, 10915.235933484897, 10963.56145754161, 10991.945040274748, 11198.712148103072, 41916.79041604773, 42876.57123428726, 43376.0, 43738.0, 43885.78057109694, 55643.44356556382, 55971.72014140066, 56916.0, 57820.71089644525, 58148.4185158143, 15417.152056636993, 15591.29839157218, 15785.921070394574, 15799.36802527886, 15965.760513592073, 16251.3499460025, 16334.836651633332, 16345.26446309895, 16544.917275413547, 16616.418425355056, 13385.531506397228, 14311.212883291519, 14363.138211706913, 14438.566842994727, 14540.781888271473, 14734.336954837272, 14861.331240094005, 15076.698466030837, 15089.924550377178, 15091.169985650455, 15492.457763978207, 15509.22453877279, 15631.921840390725, 15756.842431575538, 15768.60578485547, 11935.403229838646, 11988.280703157838, 12000.39998000079, 12042.458089385824, 12431.31627760482, 11755.647330580096, 11800.645980620595, 12126.878731212575, 12312.0, 12341.691457713629], "value": 8851.075625416826, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1422.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 109.0, 108.0, 107.0, 106.0, 109.0, 107.0, 107.0, 108.0, 107.0], "value": 107.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 119.0, 117.0, 115.0, 118.0, 119.0, 122.0, 118.0, 119.0], "value": 117.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 164.0, 164.0, 162.0, 163.0, 162.0, 167.0, 168.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 75.0, 74.0, 74.0, 74.0, 84.0, 74.0, 73.0], "value": 75.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 81.0, 79.0, 79.0, 79.0, 80.0, 80.0, 77.0, 78.0], "value": 79.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 114.0, 113.0, 113.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 147.0, 148.0, 148.0, 149.0, 148.0, 159.0, 150.0, 149.0, 148.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 94.0, 99.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 54.0, 54.0, 56.0, 55.0, 62.0, 57.0, 57.0, 57.0, 53.0], "value": 56.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 38.0, 37.0, 38.0, 37.0, 37.0, 38.0, 37.0, 37.0], "value": 37.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 73.0, 76.0, 74.0, 74.0, 76.0, 75.0, 74.0, 75.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 109.0, 111.0, 118.0, 122.0, 130.0, 116.0, 111.0, 113.0], "value": 115.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 174.0, 179.0, 169.0, 169.0, 166.0, 174.0, 168.0, 172.0, 170.0], "value": 171.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 67.0, 65.0, 63.0, 67.0, 66.0, 65.0, 63.0, 65.0], "value": 65.2, "unit": "score"}]}]} 03:49:53 INFO - Return code: 0 03:49:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:49:53 INFO - # TBPL SUCCESS # 03:49:53 INFO - Running post-action listener: _package_coverage_data 03:49:53 INFO - Running post-action listener: _resource_record_post_action 03:49:53 INFO - [mozharness: 2017-09-17 10:49:53.560788Z] Finished run-tests step (success) 03:49:53 INFO - Running post-run listener: _resource_record_post_run 03:49:53 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 339750912; Read time: 12; Write time: 209036 03:49:53 INFO - TinderboxPrint: CPU usage
13.1% 03:49:53 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 03:49:53 INFO - TinderboxPrint: I/O write bytes / time
339,750,912 / 209,036 03:49:53 INFO - TinderboxPrint: CPU idle
8,222.8 (86.9%) 03:49:53 INFO - TinderboxPrint: CPU user
1,191.3 (12.6%) 03:49:53 INFO - TinderboxPrint: Swap in / out
0 / 0 03:49:53 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13164544; Read time: 0; Write time: 2416 03:49:53 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:49:53 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 0; Write bytes: 326586368; Read time: 0; Write time: 206620 03:49:54 INFO - Running post-run listener: _upload_blobber_files 03:49:54 INFO - Blob upload gear active. 03:49:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:49:54 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 03:49:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:49:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:49:54 INFO - (blobuploader) - INFO - Open directory for files ... 03:49:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:49:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:50:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:01 INFO - (blobuploader) - INFO - Done attempting. 03:50:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:50:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:50:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:50:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:02 INFO - (blobuploader) - INFO - Done attempting. 03:50:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:50:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:50:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:50:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:03 INFO - (blobuploader) - INFO - Done attempting. 03:50:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:50:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:50:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:50:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:04 INFO - (blobuploader) - INFO - Done attempting. 03:50:04 INFO - (blobuploader) - INFO - Iteration through files over. 03:50:04 INFO - Return code: 0 03:50:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:50:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:50:04 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4cdee245949871a9b79418d134ba291dfb5822bc448fc749eb0db0ea7b6b422799c863bfdc6e0189d94a0a1ac2fa69258f6b6df84df9674a5a675b7cdc312fe0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e5c40fd4b8eb2cd945551c4b02f2dd28137392df80c96070940dd918bf86fee427d009a555ae40aec1edfdb0a62f823766e5f91b4ba49679c4d9fb45603dd4cf", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d9386adcda66d0d67cceaa29dc11c05f820fcd9616c1c65eee629f2fe38ddf8f83b1053a44f0b08032646b5095c9c319fde9f84f0780025c7623931713fb3949", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/855595c7328b8b99b62acad1066508bab53bbc2352d3c926d4b03b6f7f09c71049cd93656e20dce45d554c70ece611f668f0c12f0a30d7369e27e41a65560eb6"} 03:50:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:50:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:50:04 INFO - Contents: 03:50:04 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4cdee245949871a9b79418d134ba291dfb5822bc448fc749eb0db0ea7b6b422799c863bfdc6e0189d94a0a1ac2fa69258f6b6df84df9674a5a675b7cdc312fe0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e5c40fd4b8eb2cd945551c4b02f2dd28137392df80c96070940dd918bf86fee427d009a555ae40aec1edfdb0a62f823766e5f91b4ba49679c4d9fb45603dd4cf", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d9386adcda66d0d67cceaa29dc11c05f820fcd9616c1c65eee629f2fe38ddf8f83b1053a44f0b08032646b5095c9c319fde9f84f0780025c7623931713fb3949", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/855595c7328b8b99b62acad1066508bab53bbc2352d3c926d4b03b6f7f09c71049cd93656e20dce45d554c70ece611f668f0c12f0a30d7369e27e41a65560eb6"} 03:50:04 INFO - Running post-run listener: copy_logs_to_upload_dir 03:50:04 INFO - Copying logs to upload dir... 03:50:04 INFO - mkdir: /builds/slave/test/build/upload/logs 03:50:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.276772 ========= master_lag: 0.23 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-17 03:50:04.610953) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-17 03:50:04.619328) ========= 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=cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935 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-Non-PGO/sha512/4cdee245949871a9b79418d134ba291dfb5822bc448fc749eb0db0ea7b6b422799c863bfdc6e0189d94a0a1ac2fa69258f6b6df84df9674a5a675b7cdc312fe0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e5c40fd4b8eb2cd945551c4b02f2dd28137392df80c96070940dd918bf86fee427d009a555ae40aec1edfdb0a62f823766e5f91b4ba49679c4d9fb45603dd4cf", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d9386adcda66d0d67cceaa29dc11c05f820fcd9616c1c65eee629f2fe38ddf8f83b1053a44f0b08032646b5095c9c319fde9f84f0780025c7623931713fb3949", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/855595c7328b8b99b62acad1066508bab53bbc2352d3c926d4b03b6f7f09c71049cd93656e20dce45d554c70ece611f668f0c12f0a30d7369e27e41a65560eb6"} build_url:https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011386 build_url: 'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4cdee245949871a9b79418d134ba291dfb5822bc448fc749eb0db0ea7b6b422799c863bfdc6e0189d94a0a1ac2fa69258f6b6df84df9674a5a675b7cdc312fe0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e5c40fd4b8eb2cd945551c4b02f2dd28137392df80c96070940dd918bf86fee427d009a555ae40aec1edfdb0a62f823766e5f91b4ba49679c4d9fb45603dd4cf", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d9386adcda66d0d67cceaa29dc11c05f820fcd9616c1c65eee629f2fe38ddf8f83b1053a44f0b08032646b5095c9c319fde9f84f0780025c7623931713fb3949", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/855595c7328b8b99b62acad1066508bab53bbc2352d3c926d4b03b6f7f09c71049cd93656e20dce45d554c70ece611f668f0c12f0a30d7369e27e41a65560eb6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-17 03:50:04.647632) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 03:50:04.650902) ========= 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=cbe409c6de289db6ac707de3000001d4-1505634345.549514-723908935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004520 ========= master_lag: 0.09 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 03:50:04.747998) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-17 03:50:04.748380) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-17 03:50:04.748760) ========= ========= Total master_lag: 0.54 =========