builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-018 starttime: 1505730025.99 results: success (0) revision: 4ef69bbd6816d95ade430640785971f587e2d5bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-18 03:20:25.989758) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-18 03:20:25.990132) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-18 03:20:25.990455) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-18 03:20:26.008479) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-18 03:20:26.008749) ========= 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=62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-18 03:20:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2017-09-18 03:20:26 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114042 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-18 03:20:26.139256) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-18 03:20:26.139538) ========= 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=62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020433 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-18 03:20:26.224897) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-18 03:20:26.225271) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4ef69bbd6816d95ade430640785971f587e2d5bc --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 4ef69bbd6816d95ade430640785971f587e2d5bc --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=62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-18 03:20:26,208 truncating revision to first 12 chars 2017-09-18 03:20:26,208 Setting DEBUG logging. 2017-09-18 03:20:26,208 attempt 1/10 2017-09-18 03:20:26,208 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4ef69bbd6816?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-18 03:20:26,584 attempt 1/10 2017-09-18 03:20:27,171 current task status: no status available at this point. state: STARTED 2017-09-18 03:20:27,172 sleeping for 10.00s (attempt 1/10) 2017-09-18 03:20:37,182 attempt 2/10 2017-09-18 03:20:37,507 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-18 03:20:37,695 unpacking tar archive at: mozilla-central-4ef69bbd6816/testing/mozharness/ program finished with exit code 0 elapsedTime=11.796133 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-18 03:20:38.047052) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-18 03:20:38.047340) ========= script_repo_revision: 4ef69bbd6816d95ade430640785971f587e2d5bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-18 03:20:38.047681) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-18 03:20:38.047941) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-18 03:20:38.066042) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-18 03:20:38.066302) ========= /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=62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:20:38 INFO - MultiFileLogger online at 20170918 03:20:38 in /builds/slave/test 03:20:38 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:20:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:20:38 INFO - {'append_to_log': False, 03:20:38 INFO - 'base_work_dir': '/builds/slave/test', 03:20:38 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:20:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:20:38 INFO - 'branch': 'Firefox-Non-PGO', 03:20:38 INFO - 'buildbot_json_path': 'buildprops.json', 03:20:38 INFO - 'code_coverage': False, 03:20:38 INFO - 'config_files': ('talos/linux_config.py',), 03:20:38 INFO - 'default_actions': ('clobber', 03:20:38 INFO - 'read-buildbot-config', 03:20:38 INFO - 'download-and-extract', 03:20:38 INFO - 'populate-webroot', 03:20:38 INFO - 'create-virtualenv', 03:20:38 INFO - 'install', 03:20:38 INFO - 'setup-mitmproxy', 03:20:38 INFO - 'run-tests'), 03:20:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:20:38 INFO - 'disable_ccov_upload': False, 03:20:38 INFO - 'disable_stylo': False, 03:20:38 INFO - 'download_minidump_stackwalk': True, 03:20:38 INFO - 'download_symbols': 'ondemand', 03:20:38 INFO - 'enable_stylo': False, 03:20:38 INFO - 'enable_webrender': False, 03:20:38 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:20:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:20:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:20:38 INFO - 'gecko_profile': False, 03:20:38 INFO - 'gecko_profile_interval': 0, 03:20:38 INFO - 'installer_path': 'installer.exe', 03:20:38 INFO - 'log_level': 'info', 03:20:38 INFO - 'log_name': 'talos', 03:20:38 INFO - 'log_to_console': True, 03:20:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:20:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:20:38 INFO - 'opt_config_files': (), 03:20:38 INFO - 'pip_index': False, 03:20:38 INFO - 'suite': 'dromaeojs-e10s', 03:20:38 INFO - 'system_bits': '32', 03:20:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:20:38 INFO - 'title': 'talos-linux64-ix-018', 03:20:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:20:38 INFO - 'use_talos_json': True, 03:20:38 INFO - 'verify': 'False', 03:20:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:20:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:20:38 INFO - 'work_dir': 'build'} 03:20:38 INFO - [mozharness: 2017-09-18 10:20:38.189688Z] Running clobber step. 03:20:38 INFO - Running pre-action listener: _resource_record_pre_action 03:20:38 INFO - Running main action method: clobber 03:20:38 INFO - rmtree: /builds/slave/test/build 03:20:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:20:39 INFO - Running post-action listener: _resource_record_post_action 03:20:39 INFO - [mozharness: 2017-09-18 10:20:39.180085Z] Finished clobber step (success) 03:20:39 INFO - [mozharness: 2017-09-18 10:20:39.180182Z] Running read-buildbot-config step. 03:20:39 INFO - Running pre-action listener: _resource_record_pre_action 03:20:39 INFO - Running main action method: read_buildbot_config 03:20:39 INFO - Using buildbot properties: 03:20:39 INFO - { 03:20:39 INFO - "project": "", 03:20:39 INFO - "product": "firefox", 03:20:39 INFO - "who": "archaeopteryx@coole-files.de", 03:20:39 INFO - "installer_path": "public/build/target.tar.bz2", 03:20:39 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:20:39 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:20:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:20:39 INFO - "stage_platform": "linux64", 03:20:39 INFO - "basedir": "/builds/slave/test", 03:20:39 INFO - "buildnumber": 843, 03:20:39 INFO - "platform": "ubuntu64_hw", 03:20:39 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:20:39 INFO - "slavebuilddir": "test", 03:20:39 INFO - "taskId": "RH47JIbsSzaT2zgiU_-ncQ", 03:20:39 INFO - "branch": "mozilla-central", 03:20:39 INFO - "script_repo_revision": "production", 03:20:39 INFO - "revision": "4ef69bbd6816d95ade430640785971f587e2d5bc", 03:20:39 INFO - "slavename": "talos-linux64-ix-018", 03:20:39 INFO - "repo_path": "mozilla-central" 03:20:39 INFO - } 03:20:39 INFO - Finding installer, test and symbols from parent task. 03:20:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RH47JIbsSzaT2zgiU_-ncQ'}, attempt #1 03:20:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RH47JIbsSzaT2zgiU_-ncQ'}, attempt #1 03:20:40 INFO - Task dependencies: WN7zCNJvSaqvgRuYr4ZXkw 03:20:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw'}, attempt #1 03:20:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.tar.bz2 03:20:40 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.test_packages.json 03:20:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.crashreporter-symbols.zip 03:20:40 INFO - Running post-action listener: _resource_record_post_action 03:20:40 INFO - [mozharness: 2017-09-18 10:20:40.603547Z] Finished read-buildbot-config step (success) 03:20:40 INFO - [mozharness: 2017-09-18 10:20:40.603767Z] Running download-and-extract step. 03:20:40 INFO - Running pre-action listener: _resource_record_pre_action 03:20:40 INFO - Running main action method: download_and_extract 03:20:40 INFO - mkdir: /builds/slave/test/build/tests 03:20: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:20:40 INFO - trying https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.test_packages.json 03:20:40 INFO - Downloading https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:20:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:20:41 INFO - Downloaded 989 bytes. 03:20:41 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:20:41 INFO - Using the following test package requirements: 03:20:41 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:20:41 INFO - u'common': [u'target.common.tests.zip'], 03:20:41 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:20:41 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:20:41 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:20:41 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:20:41 INFO - u'mozbase': [u'target.common.tests.zip'], 03:20:41 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:20:41 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:20:41 INFO - u'web-platform': [u'target.common.tests.zip', 03:20:41 INFO - u'target.web-platform.tests.tar.gz'], 03:20:41 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:20:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:20:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.common.tests.zip 03:20:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:20:41 INFO - Fetch https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.common.tests.zip into memory 03:20:42 INFO - Content-Length response header: 54731157 03:20:42 INFO - Bytes received: 54731157 03:20:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.talos.tests.zip 03:20:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:20:53 INFO - Fetch https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.talos.tests.zip into memory 03:20:53 INFO - Content-Length response header: 14508748 03:20:53 INFO - Bytes received: 14508748 03:20: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:20:54 INFO - trying https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.tar.bz2 03:20:54 INFO - Downloading https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:20:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:20:55 INFO - Downloaded 61444184 bytes. 03:20:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.tar.bz2 03:20:55 INFO - mkdir: /builds/slave/test/properties 03:20:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:20:55 INFO - Writing to file /builds/slave/test/properties/build_url 03:20:55 INFO - Contents: 03:20:55 INFO - build_url:https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.tar.bz2 03:20:55 INFO - Running post-action listener: _resource_record_post_action 03:20:55 INFO - Running post-action listener: find_tests_for_verification 03:20:55 INFO - Running post-action listener: set_extra_try_arguments 03:20:55 INFO - [mozharness: 2017-09-18 10:20:55.305581Z] Finished download-and-extract step (success) 03:20:55 INFO - [mozharness: 2017-09-18 10:20:55.305679Z] Running populate-webroot step. 03:20:55 INFO - Running pre-action listener: _resource_record_pre_action 03:20:55 INFO - Running main action method: populate_webroot 03:20:55 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:20:55 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:20:55 INFO - 'tests': ['tresize', 03:20:55 INFO - 'tcanvasmark']}, 03:20:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:20:55 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:20:55 INFO - 'tests': ['dromaeo_css', 03:20:55 INFO - 'kraken']}, 03:20:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:20:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:20:55 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:20:55 INFO - 'talos_options': ['--disable-stylo'], 03:20:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:20:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:20:55 INFO - 'tests': ['damp', 'tps']}, 03:20:55 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:20:55 INFO - 'talos_options': ['--disable-stylo'], 03:20:55 INFO - 'tests': ['damp', 'tps']}, 03:20:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:20:55 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:20:55 INFO - 'tests': ['dromaeo_dom']}, 03:20:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:20:55 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:20:55 INFO - 'tests': ['basic_compositor_video', 03:20:55 INFO - 'glvideo']}, 03:20:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:20:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:20:55 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:20:55 INFO - 'talos_options': ['--disable-stylo'], 03:20:55 INFO - 'tests': ['ts_paint_webext', 03:20:55 INFO - 'tp5o_webext']}, 03:20:55 INFO - 'other-e10s': {'tests': ['a11yr', 03:20:55 INFO - 'ts_paint', 03:20:55 INFO - 'tpaint', 03:20:55 INFO - 'sessionrestore', 03:20:55 INFO - 'sessionrestore_many_windows', 03:20:55 INFO - 'sessionrestore_no_auto_restore', 03:20:55 INFO - 'tabpaint']}, 03:20:55 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:20:55 INFO - 'tests': ['a11yr', 03:20:55 INFO - 'ts_paint', 03:20:55 INFO - 'tpaint', 03:20:55 INFO - 'sessionrestore', 03:20:55 INFO - 'sessionrestore_many_windows', 03:20:55 INFO - 'sessionrestore_no_auto_restore', 03:20:55 INFO - 'tabpaint']}, 03:20:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:20:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 03:20:55 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:20:55 INFO - 'tests': ['perf_reftest_singletons']}, 03:20:55 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:20:55 INFO - 'tests': ['bloom_basic']}, 03:20:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:20:55 INFO - 'tsvgr_opacity', 03:20:55 INFO - 'tart', 03:20:55 INFO - 'tscrollx', 03:20:55 INFO - 'tsvg_static']}, 03:20:55 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:20:55 INFO - 'tests': ['tsvgx', 03:20:55 INFO - 'tsvgr_opacity', 03:20:55 INFO - 'tart', 03:20:55 INFO - 'tscrollx', 03:20:55 INFO - 'tsvg_static']}, 03:20:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:20:55 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:20:55 INFO - 'talos_options': ['--disable-stylo'], 03:20:55 INFO - 'tests': ['tp5o']}, 03:20:55 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:20:55 INFO - 'talos_options': ['--mitmproxy', 03:20:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:20:55 INFO - '--firstNonBlankPaint'], 03:20:55 INFO - 'tests': ['tp6_google', 03:20:55 INFO - 'tp6_youtube', 03:20:55 INFO - 'tp6_amazon', 03:20:55 INFO - 'tp6_facebook']}, 03:20:55 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:20:55 INFO - 'talos_options': ['--disable-stylo', 03:20:55 INFO - '--mitmproxy', 03:20:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:20:55 INFO - '--firstNonBlankPaint'], 03:20:55 INFO - 'tests': ['tp6_google', 03:20:55 INFO - 'tp6_youtube', 03:20:55 INFO - 'tp6_amazon', 03:20:55 INFO - 'tp6_facebook']}, 03:20:55 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:20:55 INFO - 'talos_options': ['--stylo-threads=1', 03:20:55 INFO - '--mitmproxy', 03:20:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:20:55 INFO - '--firstNonBlankPaint'], 03:20:55 INFO - 'tests': ['tp6_google', 03:20:55 INFO - 'tp6_youtube', 03:20:55 INFO - 'tp6_amazon', 03:20:55 INFO - 'tp6_facebook']}, 03:20:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:20:55 INFO - 'talos_options': ['--xperf_path', 03:20:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:20:55 INFO - 'tests': ['tp5n']}, 03:20:55 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:20:55 INFO - 'talos_options': ['--disable-stylo', 03:20:55 INFO - '--xperf_path', 03:20:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:20:55 INFO - 'tests': ['tp5n']}}} 03:20:55 INFO - Running post-action listener: _resource_record_post_action 03:20:55 INFO - [mozharness: 2017-09-18 10:20:55.320755Z] Finished populate-webroot step (success) 03:20:55 INFO - [mozharness: 2017-09-18 10:20:55.320845Z] Running create-virtualenv step. 03:20:55 INFO - Running pre-action listener: _resource_record_pre_action 03:20:55 INFO - Running main action method: create_virtualenv 03:20:55 INFO - Creating virtualenv /builds/slave/test/build/venv 03:20:55 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:20:55 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:20:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:20:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:20:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:20:56 INFO - Installing distribute.............................................................................................................................................................................................done. 03:20:57 INFO - Installing pip...............done. 03:20:57 INFO - Return code: 0 03:20:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:20:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:20:57 INFO - Reading from file tmpfile_stdout 03:20:57 INFO - Output received: 03:20:57 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:20:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:20:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:20:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x176c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1935a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adc090>, '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 0x1adcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ad6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', '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:20:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:20:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:20:57 INFO - Using env: {'DISPLAY': ':0', 03:20:57 INFO - 'HOME': '/home/cltbld', 03:20:57 INFO - 'LANG': 'en_US.UTF-8', 03:20:57 INFO - 'LANGUAGE': 'en_US:en', 03:20:57 INFO - 'LOGNAME': 'cltbld', 03:20:57 INFO - 'MAIL': '/var/mail/cltbld', 03:20:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:57 INFO - 'MOZ_NO_REMOTE': '1', 03:20:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:20:57 INFO - 'NO_EM_RESTART': '1', 03:20:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:20:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:57 INFO - 'PWD': '/builds/slave/test', 03:20:57 INFO - 'SHELL': '/bin/bash', 03:20:57 INFO - 'SHLVL': '1', 03:20:57 INFO - 'TERM': 'linux', 03:20:57 INFO - 'TMOUT': '86400', 03:20:57 INFO - 'USER': 'cltbld', 03:20:57 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', 03:20:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:57 INFO - '_': '/tools/buildbot/bin/python'} 03:20:58 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:20:58 INFO - Downloading/unpacking pip>=1.5 03:20:58 INFO - Running setup.py egg_info for package pip 03:20:58 INFO - warning: no files found matching 'pip/cacert.pem' 03:20:58 INFO - warning: no files found matching '*.html' under directory 'docs' 03:20:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:20:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:20:58 INFO - Installing collected packages: pip 03:20:58 INFO - Found existing installation: pip 1.1 03:20:58 INFO - Uninstalling pip: 03:20:58 INFO - Successfully uninstalled pip 03:20:58 INFO - Running setup.py install for pip 03:20:58 INFO - warning: no files found matching 'pip/cacert.pem' 03:20:58 INFO - warning: no files found matching '*.html' under directory 'docs' 03:20:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:20:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:20:58 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:20:58 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:20:58 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:20:58 INFO - Successfully installed pip 03:20:58 INFO - Cleaning up... 03:20:58 INFO - Return code: 0 03:20:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:20:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:58 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 0x176c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1935a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adc090>, '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 0x1adcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ad6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', '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:20:58 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:20:58 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:20:58 INFO - Using env: {'DISPLAY': ':0', 03:20:58 INFO - 'HOME': '/home/cltbld', 03:20:58 INFO - 'LANG': 'en_US.UTF-8', 03:20:58 INFO - 'LANGUAGE': 'en_US:en', 03:20:58 INFO - 'LOGNAME': 'cltbld', 03:20:58 INFO - 'MAIL': '/var/mail/cltbld', 03:20:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:58 INFO - 'MOZ_NO_REMOTE': '1', 03:20:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:20:58 INFO - 'NO_EM_RESTART': '1', 03:20:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:20:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:58 INFO - 'PWD': '/builds/slave/test', 03:20:58 INFO - 'SHELL': '/bin/bash', 03:20:58 INFO - 'SHLVL': '1', 03:20:58 INFO - 'TERM': 'linux', 03:20:58 INFO - 'TMOUT': '86400', 03:20:58 INFO - 'USER': 'cltbld', 03:20:58 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', 03:20:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:58 INFO - '_': '/tools/buildbot/bin/python'} 03:20:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:58 INFO - Downloading/unpacking psutil>=3.1.1 03:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:20:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:20:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:20:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:20:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:20:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:20:59 INFO - Installing collected packages: psutil 03:20:59 INFO - Running setup.py install for psutil 03:21:00 INFO - building 'psutil._psutil_linux' extension 03:21:00 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:21:00 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:21:00 INFO - building 'psutil._psutil_posix' extension 03:21:00 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:21:00 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:21:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:00 INFO - Successfully installed psutil 03:21:00 INFO - Cleaning up... 03:21:00 INFO - Return code: 0 03:21:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:00 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 0x176c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1935a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adc090>, '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 0x1adcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ad6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', '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:21:00 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:21:00 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:21:00 INFO - Using env: {'DISPLAY': ':0', 03:21:00 INFO - 'HOME': '/home/cltbld', 03:21:00 INFO - 'LANG': 'en_US.UTF-8', 03:21:00 INFO - 'LANGUAGE': 'en_US:en', 03:21:00 INFO - 'LOGNAME': 'cltbld', 03:21:00 INFO - 'MAIL': '/var/mail/cltbld', 03:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:00 INFO - 'MOZ_NO_REMOTE': '1', 03:21:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:00 INFO - 'NO_EM_RESTART': '1', 03:21:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:00 INFO - 'PWD': '/builds/slave/test', 03:21:00 INFO - 'SHELL': '/bin/bash', 03:21:00 INFO - 'SHLVL': '1', 03:21:00 INFO - 'TERM': 'linux', 03:21:00 INFO - 'TMOUT': '86400', 03:21:00 INFO - 'USER': 'cltbld', 03:21:00 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', 03:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:00 INFO - '_': '/tools/buildbot/bin/python'} 03:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:21:00 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:21:00 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:21:00 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:21:00 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:21:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:21:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:21:01 INFO - Installing collected packages: mozsystemmonitor 03:21:01 INFO - Running setup.py install for mozsystemmonitor 03:21:02 INFO - Successfully installed mozsystemmonitor 03:21:02 INFO - Cleaning up... 03:21:03 INFO - Return code: 0 03:21:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:03 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 0x176c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1935a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adc090>, '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 0x1adcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ad6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', '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:21:03 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:21:03 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:21:03 INFO - Using env: {'DISPLAY': ':0', 03:21:03 INFO - 'HOME': '/home/cltbld', 03:21:03 INFO - 'LANG': 'en_US.UTF-8', 03:21:03 INFO - 'LANGUAGE': 'en_US:en', 03:21:03 INFO - 'LOGNAME': 'cltbld', 03:21:03 INFO - 'MAIL': '/var/mail/cltbld', 03:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:03 INFO - 'MOZ_NO_REMOTE': '1', 03:21:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:03 INFO - 'NO_EM_RESTART': '1', 03:21:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:03 INFO - 'PWD': '/builds/slave/test', 03:21:03 INFO - 'SHELL': '/bin/bash', 03:21:03 INFO - 'SHLVL': '1', 03:21:03 INFO - 'TERM': 'linux', 03:21:03 INFO - 'TMOUT': '86400', 03:21:03 INFO - 'USER': 'cltbld', 03:21:03 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', 03:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:03 INFO - '_': '/tools/buildbot/bin/python'} 03:21:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:03 INFO - Downloading/unpacking jsonschema==2.5.1 03:21: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:21: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:21: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:21: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:21:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:21:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:21:05 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:21:05 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:21:05 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:21:05 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:21:05 INFO - Downloading functools32-3.2.3-2.tar.gz 03:21:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:21:05 INFO - warning: no files found matching '*.txt' 03:21:05 INFO - no previously-included directories found matching 'build' 03:21:05 INFO - no previously-included directories found matching 'dist' 03:21:05 INFO - no previously-included directories found matching '.git*' 03:21:05 INFO - Installing collected packages: jsonschema, functools32 03:21:05 INFO - Running setup.py install for functools32 03:21:05 INFO - warning: no files found matching '*.txt' 03:21:05 INFO - no previously-included directories found matching 'build' 03:21:05 INFO - no previously-included directories found matching 'dist' 03:21:05 INFO - no previously-included directories found matching '.git*' 03:21:05 INFO - Successfully installed jsonschema functools32 03:21:05 INFO - Cleaning up... 03:21:06 INFO - Return code: 0 03:21:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:21:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:06 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 0x176c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1935a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adc090>, '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 0x1adcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ad6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', '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:21:06 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:21:06 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:21:06 INFO - Using env: {'DISPLAY': ':0', 03:21:06 INFO - 'HOME': '/home/cltbld', 03:21:06 INFO - 'LANG': 'en_US.UTF-8', 03:21:06 INFO - 'LANGUAGE': 'en_US:en', 03:21:06 INFO - 'LOGNAME': 'cltbld', 03:21:06 INFO - 'MAIL': '/var/mail/cltbld', 03:21:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:06 INFO - 'MOZ_NO_REMOTE': '1', 03:21:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:06 INFO - 'NO_EM_RESTART': '1', 03:21:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:06 INFO - 'PWD': '/builds/slave/test', 03:21:06 INFO - 'SHELL': '/bin/bash', 03:21:06 INFO - 'SHLVL': '1', 03:21:06 INFO - 'TERM': 'linux', 03:21:06 INFO - 'TMOUT': '86400', 03:21:06 INFO - 'USER': 'cltbld', 03:21:06 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', 03:21:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:06 INFO - '_': '/tools/buildbot/bin/python'} 03:21:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:21:06 INFO - Cleaning up... 03:21:06 INFO - Return code: 0 03:21:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:21:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:06 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 0x176c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1935a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adc090>, '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 0x1adcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ad6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', '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:21:06 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:21:06 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:21:06 INFO - Using env: {'DISPLAY': ':0', 03:21:06 INFO - 'HOME': '/home/cltbld', 03:21:06 INFO - 'LANG': 'en_US.UTF-8', 03:21:06 INFO - 'LANGUAGE': 'en_US:en', 03:21:06 INFO - 'LOGNAME': 'cltbld', 03:21:06 INFO - 'MAIL': '/var/mail/cltbld', 03:21:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:06 INFO - 'MOZ_NO_REMOTE': '1', 03:21:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:06 INFO - 'NO_EM_RESTART': '1', 03:21:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:06 INFO - 'PWD': '/builds/slave/test', 03:21:06 INFO - 'SHELL': '/bin/bash', 03:21:06 INFO - 'SHLVL': '1', 03:21:06 INFO - 'TERM': 'linux', 03:21:06 INFO - 'TMOUT': '86400', 03:21:06 INFO - 'USER': 'cltbld', 03:21:06 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', 03:21:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:06 INFO - '_': '/tools/buildbot/bin/python'} 03:21:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:06 INFO - Downloading/unpacking blobuploader==1.2.4 03:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:07 INFO - Downloading blobuploader-1.2.4.tar.gz 03:21:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:21:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:21:07 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:21:07 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:21:07 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:21:07 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:21:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:21:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:21:07 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:21:07 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:21:07 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:21:07 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:21:08 INFO - Downloading docopt-0.6.1.tar.gz 03:21:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:21:08 INFO - Installing collected packages: blobuploader, requests, docopt 03:21:08 INFO - Running setup.py install for blobuploader 03:21:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:21:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:21:08 INFO - Running setup.py install for requests 03:21:08 INFO - Running setup.py install for docopt 03:21:08 INFO - Successfully installed blobuploader requests docopt 03:21:08 INFO - Cleaning up... 03:21:08 INFO - Return code: 0 03:21:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:08 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 0x176c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1935a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adc090>, '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 0x1adcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ad6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', '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:21:08 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:21:08 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:21:08 INFO - Using env: {'DISPLAY': ':0', 03:21:08 INFO - 'HOME': '/home/cltbld', 03:21:08 INFO - 'LANG': 'en_US.UTF-8', 03:21:08 INFO - 'LANGUAGE': 'en_US:en', 03:21:08 INFO - 'LOGNAME': 'cltbld', 03:21:08 INFO - 'MAIL': '/var/mail/cltbld', 03:21:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:08 INFO - 'MOZ_NO_REMOTE': '1', 03:21:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:08 INFO - 'NO_EM_RESTART': '1', 03:21:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:08 INFO - 'PWD': '/builds/slave/test', 03:21:08 INFO - 'SHELL': '/bin/bash', 03:21:08 INFO - 'SHLVL': '1', 03:21:08 INFO - 'TERM': 'linux', 03:21:08 INFO - 'TMOUT': '86400', 03:21:08 INFO - 'USER': 'cltbld', 03:21:08 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', 03:21:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:08 INFO - '_': '/tools/buildbot/bin/python'} 03:21:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:08 INFO - Running setup.py (path:/tmp/pip-eYuYsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:09 INFO - Running setup.py (path:/tmp/pip-mDOlAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:09 INFO - Running setup.py (path:/tmp/pip-vAf7bh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:21:09 INFO - Running setup.py (path:/tmp/pip-oinbPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:21:09 INFO - Running setup.py (path:/tmp/pip-Iq9GQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:21:09 INFO - Running setup.py (path:/tmp/pip-RRAifJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:21:09 INFO - Running setup.py (path:/tmp/pip-qZNAwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:21:09 INFO - Running setup.py (path:/tmp/pip-sDvrnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:21:09 INFO - Running setup.py (path:/tmp/pip-HgZswO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:21:09 INFO - Running setup.py (path:/tmp/pip-rCSwXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:21:09 INFO - Running setup.py (path:/tmp/pip-RfXrQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:21:09 INFO - Running setup.py (path:/tmp/pip-04DZKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:21:09 INFO - Running setup.py (path:/tmp/pip-s_2Tu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:21:09 INFO - Running setup.py (path:/tmp/pip-nrnE8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:09 INFO - Running setup.py (path:/tmp/pip-o02g4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:21:10 INFO - Running setup.py (path:/tmp/pip-VVTgFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:21:10 INFO - Running setup.py (path:/tmp/pip-i7cbUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:21:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:21:10 INFO - Running setup.py install for manifestparser 03:21:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:21:10 INFO - Running setup.py install for mozcrash 03:21:10 INFO - Running setup.py install for mozdebug 03:21:10 INFO - Running setup.py install for mozdevice 03:21:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:21:10 INFO - Running setup.py install for mozfile 03:21:10 INFO - Running setup.py install for mozhttpd 03:21:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:21:10 INFO - Running setup.py install for mozinfo 03:21:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:21:10 INFO - Running setup.py install for mozInstall 03:21:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:21:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:21:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:21:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:21:10 INFO - Running setup.py install for mozleak 03:21:11 INFO - Running setup.py install for mozlog 03:21:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:21:11 INFO - Running setup.py install for moznetwork 03:21:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:21:11 INFO - Running setup.py install for mozprocess 03:21:11 INFO - Running setup.py install for mozprofile 03:21:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:21:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:21:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:21:11 INFO - Running setup.py install for mozrunner 03:21:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:21:11 INFO - Running setup.py install for mozscreenshot 03:21:11 INFO - Running setup.py install for moztest 03:21:11 INFO - Running setup.py install for mozversion 03:21:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:21:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:21:11 INFO - Cleaning up... 03:21:11 INFO - Return code: 0 03:21:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:11 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:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:11 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:21:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:11 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 0x176c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1935a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adc090>, '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 0x1adcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ad6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', '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:21:11 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:21:11 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:21:11 INFO - Using env: {'DISPLAY': ':0', 03:21:11 INFO - 'HOME': '/home/cltbld', 03:21:11 INFO - 'LANG': 'en_US.UTF-8', 03:21:11 INFO - 'LANGUAGE': 'en_US:en', 03:21:11 INFO - 'LOGNAME': 'cltbld', 03:21:11 INFO - 'MAIL': '/var/mail/cltbld', 03:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:11 INFO - 'MOZ_NO_REMOTE': '1', 03:21:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:11 INFO - 'NO_EM_RESTART': '1', 03:21:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:11 INFO - 'PWD': '/builds/slave/test', 03:21:11 INFO - 'SHELL': '/bin/bash', 03:21:11 INFO - 'SHLVL': '1', 03:21:11 INFO - 'TERM': 'linux', 03:21:11 INFO - 'TMOUT': '86400', 03:21:11 INFO - 'USER': 'cltbld', 03:21:11 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', 03:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:11 INFO - '_': '/tools/buildbot/bin/python'} 03:21:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:12 INFO - Running setup.py (path:/tmp/pip-tpqZK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:12 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:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:12 INFO - Running setup.py (path:/tmp/pip-e_SgD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:12 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:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:12 INFO - Running setup.py (path:/tmp/pip-WitnsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:12 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:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:21:12 INFO - Running setup.py (path:/tmp/pip-16nzwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:21:12 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:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:21:12 INFO - Running setup.py (path:/tmp/pip-i7lsLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:21:12 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:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:21:12 INFO - Running setup.py (path:/tmp/pip-6euq8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:21:12 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:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:21:12 INFO - Running setup.py (path:/tmp/pip-SYCDEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:21:12 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:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:21:12 INFO - Running setup.py (path:/tmp/pip-MFjik5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:21:12 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:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:21:12 INFO - Running setup.py (path:/tmp/pip-Cr3v3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:21:12 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:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:21:12 INFO - Running setup.py (path:/tmp/pip-iR8bBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:21:12 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:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:21:12 INFO - Running setup.py (path:/tmp/pip-BBiofr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:21:12 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:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:21:12 INFO - Running setup.py (path:/tmp/pip-3Md3LJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:21:13 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:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:21:13 INFO - Running setup.py (path:/tmp/pip-7DxsMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:21:13 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:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:21:13 INFO - Running setup.py (path:/tmp/pip-ioynrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:21:13 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:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:13 INFO - Running setup.py (path:/tmp/pip-onhWK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:13 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:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:21:13 INFO - Running setup.py (path:/tmp/pip-04FgbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:21:13 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:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:21:13 INFO - Running setup.py (path:/tmp/pip-8XUD22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:21:13 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:21:13 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:21:13 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:21:13 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:21:13 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:21:13 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:21:13 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:21:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:21:13 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:21:13 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:21:13 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:21:13 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:21:14 INFO - Downloading blessings-1.6.tar.gz 03:21:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:21:14 INFO - Installing collected packages: blessings 03:21:14 INFO - Running setup.py install for blessings 03:21:14 INFO - Successfully installed blessings 03:21:14 INFO - Cleaning up... 03:21:14 INFO - Return code: 0 03:21:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:21:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:21:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:21:14 INFO - Reading from file tmpfile_stdout 03:21:14 INFO - Current package versions: 03:21:14 INFO - argparse == 1.2.1 03:21:14 INFO - blessings == 1.6 03:21:14 INFO - blobuploader == 1.2.4 03:21:14 INFO - docopt == 0.6.1 03:21:14 INFO - functools32 == 3.2.3-2 03:21:14 INFO - jsonschema == 2.5.1 03:21:14 INFO - manifestparser == 1.1 03:21:14 INFO - mozInstall == 1.13 03:21:14 INFO - mozcrash == 1.0 03:21:14 INFO - mozdebug == 0.1 03:21:14 INFO - mozdevice == 0.51 03:21:14 INFO - mozfile == 1.2 03:21:14 INFO - mozhttpd == 0.7 03:21:14 INFO - mozinfo == 0.10 03:21:14 INFO - mozleak == 0.1 03:21:14 INFO - mozlog == 3.5 03:21:14 INFO - moznetwork == 0.27 03:21:14 INFO - mozprocess == 0.25 03:21:14 INFO - mozprofile == 0.28 03:21:14 INFO - mozrunner == 6.13 03:21:14 INFO - mozscreenshot == 0.1 03:21:14 INFO - mozsystemmonitor == 0.3 03:21:14 INFO - moztest == 0.8 03:21:14 INFO - mozversion == 1.4 03:21:14 INFO - psutil == 3.1.1 03:21:14 INFO - requests == 1.2.3 03:21:14 INFO - wsgiref == 0.1.2 03:21:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:14 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:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:14 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:21:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:14 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 0x176c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1935a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adc090>, '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 0x1adcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ad6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', '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:21:14 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:21:14 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:21:14 INFO - Using env: {'DISPLAY': ':0', 03:21:14 INFO - 'HOME': '/home/cltbld', 03:21:14 INFO - 'LANG': 'en_US.UTF-8', 03:21:14 INFO - 'LANGUAGE': 'en_US:en', 03:21:14 INFO - 'LOGNAME': 'cltbld', 03:21:14 INFO - 'MAIL': '/var/mail/cltbld', 03:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:14 INFO - 'MOZ_NO_REMOTE': '1', 03:21:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:14 INFO - 'NO_EM_RESTART': '1', 03:21:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:14 INFO - 'PWD': '/builds/slave/test', 03:21:14 INFO - 'SHELL': '/bin/bash', 03:21:14 INFO - 'SHLVL': '1', 03:21:14 INFO - 'TERM': 'linux', 03:21:14 INFO - 'TMOUT': '86400', 03:21:14 INFO - 'USER': 'cltbld', 03:21:14 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', 03:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:14 INFO - '_': '/tools/buildbot/bin/python'} 03:21:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:15 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:21:15 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:21:15 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:21:15 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:21:15 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:21:15 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:21:15 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:21:15 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:21:15 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:21:15 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:21:15 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:21:15 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:21:15 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:21:15 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:21:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:21:16 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:21:16 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:21:16 INFO - Installing collected packages: simplejson 03:21:16 INFO - Running setup.py install for simplejson 03:21:16 INFO - building 'simplejson._speedups' extension 03:21:16 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:21:17 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:21:17 INFO - Successfully installed simplejson 03:21:17 INFO - Cleaning up... 03:21:17 INFO - Return code: 0 03:21:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:21:17 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:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:17 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:21:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:17 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 0x176c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1935a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adc090>, '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 0x1adcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ad6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', '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:21:17 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:21:17 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:21:17 INFO - Using env: {'DISPLAY': ':0', 03:21:17 INFO - 'HOME': '/home/cltbld', 03:21:17 INFO - 'LANG': 'en_US.UTF-8', 03:21:17 INFO - 'LANGUAGE': 'en_US:en', 03:21:17 INFO - 'LOGNAME': 'cltbld', 03:21:17 INFO - 'MAIL': '/var/mail/cltbld', 03:21:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:17 INFO - 'MOZ_NO_REMOTE': '1', 03:21:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:17 INFO - 'NO_EM_RESTART': '1', 03:21:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:17 INFO - 'PWD': '/builds/slave/test', 03:21:17 INFO - 'SHELL': '/bin/bash', 03:21:17 INFO - 'SHLVL': '1', 03:21:17 INFO - 'TERM': 'linux', 03:21:17 INFO - 'TMOUT': '86400', 03:21:17 INFO - 'USER': 'cltbld', 03:21:17 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', 03:21:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:17 INFO - '_': '/tools/buildbot/bin/python'} 03:21:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:21:17 INFO - Cleaning up... 03:21:17 INFO - Return code: 0 03:21:17 INFO - Running post-action listener: _resource_record_post_action 03:21:17 INFO - Running post-action listener: _start_resource_monitoring 03:21:17 INFO - Starting resource monitoring. 03:21:17 INFO - [mozharness: 2017-09-18 10:21:17.644989Z] Finished create-virtualenv step (success) 03:21:17 INFO - [mozharness: 2017-09-18 10:21:17.645525Z] Running install step. 03:21:17 INFO - Running pre-action listener: _resource_record_pre_action 03:21:17 INFO - Running main action method: install 03:21:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:21:17 INFO - Reading from file tmpfile_stdout 03:21:17 INFO - Detecting whether we're running mozinstall >=1.0... 03:21:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:21:18 INFO - Reading from file tmpfile_stdout 03:21:18 INFO - Output received: 03:21:18 INFO - Usage: mozinstall [options] installer 03:21:18 INFO - Options: 03:21:18 INFO - -h, --help show this help message and exit 03:21:18 INFO - -d DEST, --destination=DEST 03:21:18 INFO - Directory to install application into. [default: 03:21:18 INFO - "/builds/slave/test"] 03:21:18 INFO - --app=APP Application being installed. [default: firefox] 03:21:18 INFO - mkdir: /builds/slave/test/build/application 03:21:18 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:21:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:21:35 INFO - Reading from file tmpfile_stdout 03:21:35 INFO - Output received: 03:21:35 INFO - /builds/slave/test/build/application/firefox/firefox 03:21:35 INFO - Running post-action listener: _resource_record_post_action 03:21:35 INFO - [mozharness: 2017-09-18 10:21:35.732589Z] Finished install step (success) 03:21:35 INFO - [mozharness: 2017-09-18 10:21:35.732736Z] Running setup-mitmproxy step. 03:21:35 INFO - Running pre-action listener: _resource_record_pre_action 03:21:35 INFO - Running main action method: setup_mitmproxy 03:21:35 INFO - Skipping: mitmproxy is not required 03:21:35 INFO - Running post-action listener: _resource_record_post_action 03:21:35 INFO - [mozharness: 2017-09-18 10:21:35.733484Z] Finished setup-mitmproxy step (success) 03:21:35 INFO - [mozharness: 2017-09-18 10:21:35.733804Z] Running run-tests step. 03:21:35 INFO - Running pre-action listener: _resource_record_pre_action 03:21:35 INFO - Running pre-action listener: _set_gcov_prefix 03:21:35 INFO - Running main action method: run_tests 03:21:35 WARNING - Try message not found. 03:21:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:21:35 INFO - Python 2.7.3 03:21:35 INFO - Return code: 0 03:21:35 INFO - grabbing minidump binary from tooltool 03:21:35 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:21:35 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 0x1adc090>, '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 0x1adcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ad6f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:21:35 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:21:35 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:21:35 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:21:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:21:35 INFO - Return code: 0 03:21:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:21:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:21:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:21:35 INFO - ENV: RUST_BACKTRACE is now full 03:21:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:21:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:21:35 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/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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:21:35 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/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-018 --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:21:35 INFO - Using env: {'DISPLAY': ':0', 03:21:35 INFO - 'HOME': '/home/cltbld', 03:21:35 INFO - 'LANG': 'en_US.UTF-8', 03:21:35 INFO - 'LANGUAGE': 'en_US:en', 03:21:35 INFO - 'LOGNAME': 'cltbld', 03:21:35 INFO - 'MAIL': '/var/mail/cltbld', 03:21:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:21:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:35 INFO - 'MOZ_NO_REMOTE': '1', 03:21:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:21:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:35 INFO - 'NO_EM_RESTART': '1', 03:21:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:35 INFO - 'PWD': '/builds/slave/test', 03:21:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:21:35 INFO - 'RUST_BACKTRACE': 'full', 03:21:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:21:35 INFO - 'SHELL': '/bin/bash', 03:21:35 INFO - 'SHLVL': '1', 03:21:35 INFO - 'TERM': 'linux', 03:21:35 INFO - 'TMOUT': '86400', 03:21:35 INFO - 'USER': 'cltbld', 03:21:35 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', 03:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:35 INFO - '_': '/tools/buildbot/bin/python'} 03:21:35 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/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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:21:36 INFO - mozversion application_buildid: 20170918093138 03:21:36 INFO - mozversion application_changeset: 4ef69bbd6816d95ade430640785971f587e2d5bc 03:21:36 INFO - mozversion application_display_name: Nightly 03:21:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:21:36 INFO - mozversion application_name: Firefox 03:21:36 INFO - mozversion application_remotingname: firefox 03:21:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:21:36 INFO - mozversion application_vendor: Mozilla 03:21:36 INFO - mozversion application_version: 57.0a1 03:21:36 INFO - mozversion platform_buildid: 20170918093138 03:21:36 INFO - mozversion platform_changeset: 4ef69bbd6816d95ade430640785971f587e2d5bc 03:21:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:21:36 INFO - mozversion platform_version: 57.0a1 03:21:36 INFO - using testdate: 1505730096 03:21:36 INFO - actual date: 1505730096 03:21:36 INFO - starting webserver on 'localhost:44089' 03:21:36 INFO - SUITE-START | Running 2 tests 03:21:36 INFO - TEST-START | dromaeo_css 03:21:36 INFO - Initialising browser for dromaeo_css test... 03:21:36 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44089/getInfo.html -profile /tmp/tmpeqZP3v/profile 03:21:36 INFO - TEST-INFO | started process 25610 (/builds/slave/test/build/application/firefox/firefox http://localhost:44089/getInfo.html) 03:21:41 INFO - TEST-INFO | 25610: exit 0 03:21:41 INFO - Browser initialized. 03:21:41 INFO - Running cycle 1/1 for dromaeo_css test... 03:21:41 INFO - Using env: {'DISPLAY': ':0', 03:21:41 INFO - 'HOME': '/home/cltbld', 03:21:41 INFO - 'JSGC_DISABLE_POISONING': '1', 03:21:41 INFO - 'LANG': 'en_US.UTF-8', 03:21:41 INFO - 'LANGUAGE': 'en_US:en', 03:21:41 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:21:41 INFO - 'LOGNAME': 'cltbld', 03:21:41 INFO - 'MAIL': '/var/mail/cltbld', 03:21:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:21:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:21:41 INFO - 'MOZ_CRASHREPORTER': '1', 03:21:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:41 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:21:41 INFO - 'MOZ_NO_REMOTE': '1', 03:21:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:21:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:41 INFO - 'NO_EM_RESTART': '1', 03:21:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:41 INFO - 'PWD': '/builds/slave/test', 03:21:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:21:41 INFO - 'RUST_BACKTRACE': 'full', 03:21:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:21:41 INFO - 'SHELL': '/bin/bash', 03:21:41 INFO - 'SHLVL': '1', 03:21:41 INFO - 'TERM': 'linux', 03:21:41 INFO - 'TMOUT': '86400', 03:21:41 INFO - 'USER': 'cltbld', 03:21:41 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', 03:21:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:41 INFO - '_': '/tools/buildbot/bin/python'} 03:21:41 INFO - TEST-INFO | started process 25846 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeqZP3v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:21:42 INFO - PID 25846 | 03:21:42 INFO - PID 25846 | (/builds/slave/test/build/application/firefox/firefox:25895): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:21:42 INFO - PID 25846 | 03:21:53 INFO - PID 25846 | RSS: Main: 154861568 03:21:53 INFO - PID 25846 | 03:24:49 INFO - PID 25846 | Cycle 1(1): loaded http://localhost:44089/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44089/tests/dromaeo/cssquery-ext.html) 03:24:50 INFO - PID 25846 | RSS: Main: 154980352 03:24:50 INFO - PID 25846 | 03:27:52 INFO - PID 25846 | Cycle 1(1): loaded http://localhost:44089/tests/dromaeo/cssquery-ext.html (next: http://localhost:44089/tests/dromaeo/cssquery-jquery.html) 03:27:53 INFO - PID 25846 | RSS: Main: 155717632 03:27:53 INFO - PID 25846 | 03:30:54 INFO - PID 25846 | Cycle 1(1): loaded http://localhost:44089/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44089/tests/dromaeo/cssquery-mootools.html) 03:30:55 INFO - PID 25846 | RSS: Main: 157442048 03:30:55 INFO - PID 25846 | 03:33:56 INFO - PID 25846 | Cycle 1(1): loaded http://localhost:44089/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44089/tests/dromaeo/cssquery-prototype.html) 03:33:57 INFO - PID 25846 | RSS: Main: 155496448 03:33:57 INFO - PID 25846 | 03:37:00 INFO - PID 25846 | Cycle 1(1): loaded http://localhost:44089/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44089/tests/dromaeo/cssquery-yui.html) 03:37:01 INFO - PID 25846 | RSS: Main: 157003776 03:37:01 INFO - PID 25846 | 03:40:03 INFO - PID 25846 | Cycle 1(1): loaded http://localhost:44089/tests/dromaeo/cssquery-yui.html (next: http://localhost:44089/tests/dromaeo/cssquery-dojo.html) 03:40:03 INFO - PID 25846 | RSS: Main: 159330304 03:40:03 INFO - PID 25846 | 03:40:03 INFO - PID 25846 | __start_tp_report 03:40:03 INFO - PID 25846 | _x_x_mozilla_page_load 03:40:03 INFO - PID 25846 | _x_x_mozilla_page_load_details 03:40:03 INFO - PID 25846 | |i|pagename|runs| 03:40:03 INFO - PID 25846 | |0;dojo.html;3089.382123575285;3143.6856314368565;3143.732782713469;3163.8101713897167;3188.9521657175146;6097.817065488031;6252.124702541648;6279.968600156999;6314.589551679143;6353.491720662348;6182.598131121474;6211.192544969159;6241.594296370733;6285.9685701571425;6295.181626388563;5751.10857817038;5758.827235182951;5763.337216220126;5797.797077102302;5861.384554621767;5835.824925252249;5872.236609240793;5876.823695289148;5920.171176035358;5923.733431995549;19.910344888165362;5662.6036177467595;5796.275465566804;5878.5003274721485;5889.676067816268;5679.829605111853;5680.289963754531;5747.540196784289;5824.679642619654;5841.7371218295275;5730.455606717355;5744.167095771089;5806.970965145189;5815.796447124372;5816.156657284712;6194.5354098442795;6243.56295059346;6312.431881130675;6323.20959880015;6337.429631333156;6661.16735408074;6674.16572928384;6761.0872532208505;6768.984652302145;6781.186257649064;6698.397144256992;6710.228323742784;6813.829654258634;6815;6819.965900170517;6134.785282515135;6234.937650623527;6244.781432649836;6300.653464059475;6300.905486417708;6921.8961715574305;6938.618375989268;6947.305269473113;6948.895766563505;6952.687129079203;6575.145231119924;6657.567258128206;6660.933390666031;6661.034150048215;6672.899906501407;6666.300038495985;6697.832554186184;6760.121184246016;6768.492363072789;6773.8983915241315;6443.001334793114;6638.17022872141;6669.499787515956;6696.598204107671;6774.72901083961;6694.799156025327;6750.223724271674;6773.525853190229;6775;6778.017187507784;5231.058409486329;5305.549028332635;5309.840704778863;5331.760070796823;5336.546393556591;4593.678442508993;4662.067586482649;4669.836555720602;4712.175369310357;4714.669973101919;5779.855503612359;5781.075027995501;5847.06446968483;5850.64896106235;5854.8536286592325;6571.0471981562405;6695.598264104169;6719.563228390237;6804.489663275274;6812.591244525245;5786.218860453869;5786.884262314814;5799;5820.941790582125;5849.795257165979;5758.078707406795;5758.337791154071;5837.591368604242;5849.532037436995;5855.531557475391;6352.333005034498;6554.279029306773;6555.639439830759;6643.236027856762;6643.7674681385915;6651.43462805667;6704.094947182068;6807.9319206807295;6808.148981377328;6812.386885180357;6571.605703657605;6578.440832529097;6582.0785090086465;6587.440067594116;6595.868082638416;6600.976848588476;6606.4384527315715;6694.698738556679;6696.631685257357;6700.664966751546;6763.357481039294;6770.255271920183;6844.623545705034;6856.554323968926;6868.313168683092;6310.7791227306825;6432.581882177644;6440.033994900802;6524.9021264682015;6639.834004149935;6444.677766111769;6465.838354041187;6466.482681385383;6497.902531462127;6526.118973938457;6494.0258961156205;6510.609363438209;6511.120998665119;6517.73929042833;6537.771178008747;6492.350764923659;6586.604803711793;6656.068150459037;6667.166604174478;6682.198136223684;6183.3816618337805;6342.90485642725;6430.099786030053;6436.324185960407;6450.774222902176 03:40:03 INFO - PID 25846 | |1;ext.html;855.119227195988;883.5891310540597;884.4207044385926;884.9601767920443;886.2732559301376;39241.80379098104;43425;43493.56506434939;43544.34683479743;43644.908877278;39071.41392879109;39327.803360983155;39408.80295598518;39471.40792888109;39516;7047.43620510358;7121.4302855771675;7696.191899850519;7920.48516846406;7938.483998540077;7642.732504362349;7730.304272615464;7731.0336207974005;7732.381409487256;7733;7672.65473053714;7697;7698.461107722434;7706.7302644407455;7726.343260822837;7581.2418758124295;7640.923590764077;7676.846463070735;7694.692212311501;7727.227277272228;7761.728339508147;7796.220377962215;7802.414818888549;7806.531608103475;7817.843643127162;39492.60507394918;39520.802395987834;39661.80169099136;39711;39765.80117099425;3577.0520811984843;3605.3330133925047;3607.116256517144;3608.2963822054708;3617.385044542434;32471.51292730611;32491.187720306945;32531.837340813145;32541.837290813393;32562;5215.765290561933;5237.790488380499;5284.894302113975;5307.150855863043;5314.0700377433795;190610;192145;192287;192335;192348;43941.560584393745;44560.3315950261;50169.24746128811;50183.49816501861;51103.48896511061;53491.465085349424;53734.46265537295;53919.19121213185;53996.73001634967;54048;16159.757603635955;16414;16415.343386264656;16542.751858722128;16549.50351489433;13500.594982150551;13534.323283835769;13557.457701691821;13575.932120339336;13578.592642220749;13851.515196968057;13897;13920.443182272798;14107.647308817363;14129.152250864981;5522.861928451821;5567.276254086943;5570.192322113352;5590.776368945197;5602.411746766531;1018.6791160784329;1022.1771473963531;1028.6451174344838;1043.2957753516346;1051.164324362134;23426;23601.52796944086;23604.527909441716;23639.7636023641;23773.643395349085;1133.8015847226868;1134.9943250283695;1142.9828552571719;1143.8341440491083;1148.5463242019355;12489.875101248872;12528.874711252953;12625.684357891127;12667;12800.6799830005;243389;243721;244173;245448;246001;53829.461705383226;54211.18683219755;54261.72869135708;54368.728156358964;54373;1429.4925301517812;1459.5256541623803;1464.3337281536876;1471.1687896338535;1476.1143314011508;22447.551048978603;23152.18967336135;23257;23369.649455258525;23392.64911026302;38000.23999520049;38016.42975355427;38049.61950380571;38104;38122.61877381302;208121;213685;214370;215565;215808;37687.2462550742;38230.2353952925;38353.23293534169;38537.80731096439;38706.61293387143;45425.091498170514;45936.31095533503;46608.30087548622;46682.76658616685;47079;33918.83040584781;34359.14102147366;34416.48375274423;34426.311473769885;34744.47883281804;33797.155071123416;34267.65732342745;34725.30549389048;34744.652553474145;34939.82530087333;38936.610633893295;38989.610103898594;39102.6089739099;39239.215215696095;39293;32763.508547371333;32901;33308;33353.16617084592;33658.66341336555;23503.647445288676;24059.759402406453;24182.63726044146;24225.394365140306;24741.87629061843 03:40:03 INFO - PID 25846 | |2;jquery.html;549.1351121982875;555.2864569028799;560.8317504748577;571.6627189957924;590.7223604905694;22417.3274801756;23128.42178945527;23304.533909321803;23329.416764580896;23384.76615233847;23360.299191024253;24539.50920981584;24900;25165.99336026557;25548.74451255487;6280.371962803717;6377.553571250014;6416.13382193405;6418.390252925965;6420.839479013039;6291.213598300213;6395.744170233185;6401.423871851533;6404;6669.23303820062;6649.900251496231;6655.0682904393425;6672.266050734415;6672.76645317414;6675.866482670343;6641.037049627801;6647.50143739219;6649.700763465656;6651.866962660768;6669.833254168636;6156.599821011665;6170.413810687977;6173.351798061184;6191.071339299141;6199.101130336121;27004.729952700614;27854.88580456779;27986.720132798615;28251.57622635662;28376.858115709496;2021.686638571009;2069.865458745177;2075.543380456297;2075.6263872502946;2080.3446914221972;18723.438296851116;19396.80603193978;19406.805931940642;19449.31927382549;19506;2326.674265602817;2366.9408264793346;2392.5932591459496;2408.5664580375524;2410.6745589345387;112601.43699281533;117968;118495;132076;134545.32727336301;14623.915187868182;14760.645609544996;14767.778483322758;14868.776968345483;14972.925135374253;22735.431614209447;22735.545289094458;22799.202027928943;22874.31377058691;22886.542269154525;10283.588656453807;10287.27989040775;10377.740556486147;10424.583016679248;10426.791464170827;9517.476538790295;9518.524073796283;9558.66544670947;9650.034996500293;9714.75713107178;8707.042225355206;8907.465552066897;8936.24041956441;9111.954440227757;9234.261259099256;2736.945261094807;2738.315421144714;2741.8629068546493;2795.3850152966315;2799.90200342987;951.4338968313842;961.5864678921973;968.1093394077325;982.7297493189287;983.8229118758549;18686.813131868777;19137.13882875274;19169.849809011503;19283.614327713367;19460.31888883882;1047.2773786087575;1055.1242468750918;1056.2553399853084;1056.9312994655322;1057.962971296001;10522.842157367646;10537.63118290856;10591.576336946437;10619.681409557727;10636.521356538975;460944;465175;476059;480092;492512;112722.43638781754;114796.42601786938;115072;115477.4226128864;116008.41995789966;1158.2992289664633;1171.0455978377595;1189.0725234317247;1198.8441502604605;1200.4177973682756;16808.88777930346;16970.67087198888;17047.91476042612;17119.54483868885;17181.31274748996;57277.713611431675;57720.71139644275;58979.705101475934;59258;59640.403595963486;69971.65014174896;73190.63404682942;73318.26681733114;73917.6304118476;74517.25482745103;13754.93122534381;13987.370568324246;14097.929510352793;14115.647108822362;14120.717585648435;21999.34001979943;22136.225232116798;22272.88863555672;22298.77701222967;22624.208152714575;17942.730859036863;18001.459956201335;18011.459656210336;18167.727484088013;18194.272229110687;15807.525774226791;16280.511584652479;16423.589410264838;16430.096344701156;16726.58183545421;18480.075996200405;18832.7175092371;18900.716489252947;18923.243070277036;18923.43229703111;13904.72190556163;13916.234607096301;13929.23389213603;13979.161250325013;13991.720165596835;17000.82999170042;17890.28438862431;17970.550736231176;17977.550561236072;18077.36729214471 03:40:03 INFO - PID 25846 | |3;mootools.html;1302.70037891285;1353.9932300338496;1366.883814875736;1370.0751992405123;1371.9039667223285;14971.326290316934;15646.295916683748;15679.92160039201;15690.921545392257;15697.607559811011;15398.230088495586;15440.61398465039;15461.690766184669;15565.766513502307;15577.532674019769;2711.30861630284;2745.835249885008;2779.902703405381;2780.6107144999714;2786.122371452986;2644.907428240012;2764.4885696146175;2775.736305051017;2777.3334399744117;2780.2771279467383;2643.6034594810835;2729.0584748261817;2741.163944996775;2744.0258708158617;2854.300696329392;2795.5247607906604;2824.449232399683;2824.7740180785486;2825.759810416117;2826.1380279014893;2696.4337489127306;2723.509768241716;2724.809263351566;2729.426820367725;2737.096758069852;14886.925565372212;14928.32822522989;15098.24508774568;15142.924285378502;15152.621184470367;7989.800254993614;8272.04871439786;8285.171482851727;8286.047104582989;8298.626561804673;5152.175651895716;5436.565074794007;5530.419305972855;5575.553955683536;5578.163275508666;1928.4503916383762;1983.871048381865;1986.2055177928798;2028.1177687705747;2032.3293313206755;26415.603765943528;26474.470510589872;26559.46881062368;26596.86701566499;26622;6174.845628859224;6445.355464453517;6784.10365018064;6866.416354609814;7194.136703595497;17198.484045478657;17225.65548689019;17226;17241.310347586208;17252.65494690099;8065.919340806634;8105.1084380718075;8156.5513896735065;8199.057108432487;8202.26179643823;7334.706611735577;7400.296971787671;7439.702411903463;7456.918746781795;7537.849243015109;5275.076861549213;5381.892362152813;5401.864953376118;5414.917016596696;5563.443655634404;1527.9083255004734;1537.8462153784756;1538.6461113943633;1580.083551540104;1581.4860170444651;876.5179151466668;894.6689724801865;899.7480705402497;902.5351943748974;903.4895284164426;7694.345980591601;7721.7683469496005;7728.459007869507;7743.419243556756;7768.922310776933;713.3615414204362;720.8846584546448;729.4237552333703;735.0481126940518;743.9851202976018;4706.388169537938;4720.433547974265;4782.139214941344;4859.7327147006545;5286.550643195372;18283.63432731338;21328;22005.11979520833;22018.559628807656;22086;20943.37169884906;21219.575608487743;21789.78210217909;21886.671699924515;21929.671054934188;11224.94387528057;11232.887671123184;11455.369954652408;11486.483108260149;11498.25261358009;8562.657493700182;8742.256908162877;8796.91203087961;8798.208161265517;8980.640774368952;18257.360992365204;18649.72025419647;18820.52948676294;18934.14796334196;19138.7129193065;18971.241150354384;19158.61682766365;19194.616107678048;19209.51976200606;19263.22947082101;11001.724956876142;11247.831282530933;11253.324800511667;11260.718482037686;11274.210805243554;13954.930225348808;13960.650983725489;14016.159030458206;14087.718245634826;14121.717565648423;11377.829332560184;11492.597759078391;11538.13463990221;11617.419129043683;11883.584074557695;10983.450827458755;11106.333619982826;11129.721756956142;11381.203315767816;11466.77066458683;9495.240380769385;9521.0478952107;9597.760055998657;9603.855942161013;9606.27952903549;7654.8086297843;7721.6911323548675;7737.961310193638;7765.7670269892;7824.765257042298;7456.589887556236;7469.439792015729;7514.323710866049;7575.469717119749;7598.506097103671 03:40:03 INFO - PID 25846 | |4;prototype.html;501.53607912680775;503.59460634189463;525.6136739496469;527.7360125063478;531.5056996992797;6057.666828324441;6108.450239478446;6179.474744646711;6228.128062071308;6276.592021518598;6108.969455152718;6164.845878853031;6195.721192546335;6238.59449135807;6312.6212427254395;5215.348081489814;5238.895222095556;5238.973805130969;5241.50205730455;5247.160454327299;4900.142475066867;4973.701577905319;5260.868478288055;5264.947350526484;5268.525832675058;4993.800247990076;5002.9499705002845;5011.649184557083;5021.020900924321;5074.314967479399;4972.229304457779;4993.475685053054;5003.974980125113;5006.949930500685;5033.723145227011;4504.864854054384;4505.481869585007;4510.481294651125;4512.774361281923;4520.909581808378;6133.975787989953;6154.415330543591;6232.345603711636;6238.5009199264405;6245.437910588069;5723.313202415737;5725.427457254283;5736.3403208631125;5746.109353050284;5796.13058041293;6439.678016099223;6494.93505064948;6530.379613936669;6570.211574611029;6577.539572229945;4885.972321486579;4923.556879880828;4937.308776771255;4955;4961.751912404401;6845.863082738318;6895.827604309932;6926.510800177935;6950.478714096463;6955.739384699942;4981.277714731416;5011.7494125294315;5020.472850350734;5031.572316353077;5042.949570504248;5093.567046801063;5101.69389836611;5102.438731739506;5124.693203233226;5125.256837758504;5686.175504551817;5702.543796496353;5735.680793417538;5760.769569217268;5778;6430.807075787734;6589.505787065989;6624.27133015368;6674.466042716571;6708.664566771739;1612.6049117965938;1636.141025961356;1686.8313168683032;1724.430937790526;1737.1748419500889;1181.2026881854713;1195.5038658956435;1216.5741990303322;1222.6882145053132;1223.920445171061;5641.182028605829;5649.350324712711;5704;5730.856728581819;5786.392428794916;6495.610263384212;6628.867422651617;6750.401095578238;6769.796906092825;6895.724171033103;5793.826185214366;5803.564732645069;5809.5352371811;5834.883302334015;5856.795012174553;3505.9123521912156;3604.891853244407;3616.764910280823;3616.782993020427;3638.399664055401;6518.348165183444;6538.248101468297;6551.279359270477;6585.83535411609;6637.1703537057865;6939.091749768854;6942.965285173643;6944.930550694529;6945.826354341081;7011.579305241702;6566.9014964776525;6581.572197807127;6614.239362473377;6622.1722284714415;6622.8344291393105;6727.192736871411;6777.526202935795;6791.049253104668;6791.2190098139345;6853.143357080365;6732.764353247613;6819.420349270367;6821;6829.760958366433;6839.213490448463;6560.4582923014;6564.113844631104;6623.979686195757;6634.800955971328;6721.4040488338505;4914.803407863788;4931.211006238985;4946.406431228275;4995.750212489434;5041.949580504148;6334.873302534015;6755.2231493378895;6762.729490820312;6806.829829254309;6944.722211111499;6105.603135796159;6436.8390790230615;6489.286178520265;6563.9343606565235;6638.867222655424;6565.409113179837;6590;6608.8347791305605;6613.503987201076;6651.301613330724;6353.491720662429;6427.935720642734;6461.095446637569;6483.870322593616;6521.282658907421;6559.4752419805345;6567.343265673395;6689.665516724242;6697.665116744046;6727.327267273234;6384.872302554016;6391.296957334596;6439.259485159079;6458.967705161444;6462.090435286772 03:40:03 INFO - PID 25846 | |5;yui.html;890.9242714369274;891.7681402835262;905.5427009360272;910.886139232596;918.4122161816439;18337.266509339628;19091.71362429563;19101.522461938457;19103;19197.520061998457;18199.45401637953;18409.355672551465;18432.98618575978;18457.63084738308;18465.261389544437;936.1901954809092;951.8239125761721;952.4333021851987;955.173774684896;955.7945041816043;966.9323147379686;969.2246203037577;972.1639390124487;974.0892265731528;974.6539978307705;1000;1023.7696518283401;1039.45428649959;1040.0587468341164;1040.2302296300759;1012.448215722433;1021.1473419694564;1023.6928921323611;1028.958841646337;1031.1853635627845;714.9034880291147;728.0535304104643;735.547638202507;736.1975446763054;753.1940823319051;18692.158852851655;18742.250309987583;18897;18909.14908829106;18936.62126757457;21277.468063298387;21391.465213369636;22080;22150.446238843997;22316.21893233729;16018.35926562936;16143.757843632355;16294.918525407416;16298.755518667229;16458.75311870323;3762.4168253920684;3795.1840354323954;3795.981020094882;3796.354619714655;3798.088458769903;104786.47606761994;142873.2856335722;143099.28450357786;144106.27946860302;146106;16716.916415417843;17045.659086818196;17061.914690426718;17075.40236091731;17139.22873470697;32014.83992580022;32863;32939.67060329414;32982.67017329844;33053.17367065843;8767.605457754416;8884.955575222082;8913.55432228399;9012.0086790453;9043.547822608843;4335;4391.451068616423;4429.933550996738;4438.312061630452;4465.352523884018;5273.235380869829;5305.151175811851;5387.111126664135;5406.107992181324;5407.324084489439;1085.137315833914;1089.836524521328;1100.785346857355;1102.7518808268076;1105.0883021507289;678.6030172349231;696.24109720405;696.923338432772;702.4134847402477;704.8695991241636;12361.443735031946;12495.375231238402;12723.745525089447;12790.808137877939;13162.341882906008;671.7648822911942;676.5129107042922;682.2324884504932;690.5649440852332;691.4779341597063;5452.890942181134;5491.533219676372;5525.834224973257;5589.385167631557;5611.523020543218;122978;124367.3781631086;124386;126644.36677816552;126817.36591317168;111625;114629;116278;117033.4148329253;119772.40113799549;97005.0299496996;97078.5146074265;99731.50134249282;100771.49614251882;100911;10253.692389228336;10290.794184116425;10296.691099267035;10415.895841041493;10673.786524269626;44100;44222.336664949406;45212.77393613011;45913.31130032986;46001.53998460106;57495;57808.71095644495;58107;58238.708806455696;58389.70805145947;15799.68400632004;16093.275802588678;16141.273642685872;16266.186690665656;16289.51131466058;17753;17853.642927141125;17889.821101788817;17934.91032544829;18077.909610451865;14702.926485367505;14817.40730370773;14973.251337433303;15143.545693629208;15150.696986060437;15993.600159996093;16340.591485212964;16362.509124726277;16363.836361636708;16385.91807040957;15627.218639067774;15656.4520241791;15692.76460853111;15818;15904.522864313627;10751.731206719895;10820.134389248684;11082.16883733707;11325.490352933974;11387.487563059516;9621.326507144433;9628.951855240679;9632.614695412105;9839;9950.950245248727 03:40:03 INFO - PID 25846 | __end_tp_report 03:40:03 INFO - PID 25846 | __start_cc_report 03:40:03 INFO - PID 25846 | _x_x_mozilla_cycle_collect,287 03:40:03 INFO - PID 25846 | __end_cc_report 03:40:03 INFO - PID 25846 | __startTimestamp1505731203347__endTimestamp 03:40:03 INFO - PID 25846 | 03:40:03 INFO - PID 25846 | ------- Summary: start ------- 03:40:03 INFO - PID 25846 | Number of tests: 6 03:40:03 INFO - PID 25846 | 03:40:03 INFO - PID 25846 | [#0] dojo.html Cycles:165 Average:6157.90 Median:6440.03 stddev:880.67 (13.7%) stddev-sans-first:849.84 03:40:03 INFO - PID 25846 | Values: 3089.4 3143.7 3143.7 3163.8 3189.0 6097.8 6252.1 6280.0 6314.6 6353.5 6182.6 6211.2 6241.6 6286.0 6295.2 5751.1 5758.8 5763.3 5797.8 5861.4 5835.8 5872.2 5876.8 5920.2 5923.7 19.9 5662.6 5796.3 5878.5 5889.7 5679.8 5680.3 5747.5 5824.7 5841.7 5730.5 5744.2 5807.0 5815.8 5816.2 6194.5 6243.6 6312.4 6323.2 6337.4 6661.2 6674.2 6761.1 6769.0 6781.2 6698.4 6710.2 6813.8 6815.0 6820.0 6134.8 6234.9 6244.8 6300.7 6300.9 6921.9 6938.6 6947.3 6948.9 6952.7 6575.1 6657.6 6660.9 6661.0 6672.9 6666.3 6697.8 6760.1 6768.5 6773.9 6443.0 6638.2 6669.5 6696.6 6774.7 6694.8 6750.2 6773.5 6775.0 6778.0 5231.1 5305.5 5309.8 5331.8 5336.5 4593.7 4662.1 4669.8 4712.2 4714.7 5779.9 5781.1 5847.1 5850.6 5854.9 6571.0 6695.6 6719.6 6804.5 6812.6 5786.2 5786.9 5799.0 5820.9 5849.8 5758.1 5758.3 5837.6 5849.5 5855.5 6352.3 6554.3 6555.6 6643.2 6643.8 6651.4 6704.1 6807.9 6808.1 6812.4 6571.6 6578.4 6582.1 6587.4 6595.9 6601.0 6606.4 6694.7 6696.6 6700.7 6763.4 6770.3 6844.6 6856.6 6868.3 6310.8 6432.6 6440.0 6524.9 6639.8 6444.7 6465.8 6466.5 6497.9 6526.1 6494.0 6510.6 6511.1 6517.7 6537.8 6492.4 6586.6 6656.1 6667.2 6682.2 6183.4 6342.9 6430.1 6436.3 6450.8 03:40:03 INFO - PID 25846 | 03:40:03 INFO - PID 25846 | [#1] ext.html Cycles:180 Average:39136.86 Median:37843.74 stddev:56473.34 (149.2%) stddev-sans-first:56558.60 03:40:03 INFO - PID 25846 | Values: 855.1 883.6 884.4 885.0 886.3 39241.8 43425.0 43493.6 43544.3 43644.9 39071.4 39327.8 39408.8 39471.4 39516.0 7047.4 7121.4 7696.2 7920.5 7938.5 7642.7 7730.3 7731.0 7732.4 7733.0 7672.7 7697.0 7698.5 7706.7 7726.3 7581.2 7640.9 7676.8 7694.7 7727.2 7761.7 7796.2 7802.4 7806.5 7817.8 39492.6 39520.8 39661.8 39711.0 39765.8 3577.1 3605.3 3607.1 3608.3 3617.4 32471.5 32491.2 32531.8 32541.8 32562.0 5215.8 5237.8 5284.9 5307.2 5314.1 190610.0 192145.0 192287.0 192335.0 192348.0 43941.6 44560.3 50169.2 50183.5 51103.5 53491.5 53734.5 53919.2 53996.7 54048.0 16159.8 16414.0 16415.3 16542.8 16549.5 13500.6 13534.3 13557.5 13575.9 13578.6 13851.5 13897.0 13920.4 14107.6 14129.2 5522.9 5567.3 5570.2 5590.8 5602.4 1018.7 1022.2 1028.6 1043.3 1051.2 23426.0 23601.5 23604.5 23639.8 23773.6 1133.8 1135.0 1143.0 1143.8 1148.5 12489.9 12528.9 12625.7 12667.0 12800.7 243389.0 243721.0 244173.0 245448.0 246001.0 53829.5 54211.2 54261.7 54368.7 54373.0 1429.5 1459.5 1464.3 1471.2 1476.1 22447.6 23152.2 23257.0 23369.6 23392.6 38000.2 38016.4 38049.6 38104.0 38122.6 208121.0 213685.0 214370.0 215565.0 215808.0 37687.2 38230.2 38353.2 38537.8 38706.6 45425.1 45936.3 46608.3 46682.8 47079.0 33918.8 34359.1 34416.5 34426.3 34744.5 33797.2 34267.7 34725.3 34744.7 34939.8 38936.6 38989.6 39102.6 39239.2 39293.0 32763.5 32901.0 33308.0 33353.2 33658.7 23503.6 24059.8 24182.6 24225.4 24741.9 03:40:03 INFO - PID 25846 | 03:40:03 INFO - PID 25846 | [#2] jquery.html Cycles:180 Average:33813.22 Median:22679.82 stddev:79838.29 (352.0%) stddev-sans-first:80023.20 03:40:03 INFO - PID 25846 | Values: 549.1 555.3 560.8 571.7 590.7 22417.3 23128.4 23304.5 23329.4 23384.8 23360.3 24539.5 24900.0 25166.0 25548.7 6280.4 6377.6 6416.1 6418.4 6420.8 6291.2 6395.7 6401.4 6404.0 6669.2 6649.9 6655.1 6672.3 6672.8 6675.9 6641.0 6647.5 6649.7 6651.9 6669.8 6156.6 6170.4 6173.4 6191.1 6199.1 27004.7 27854.9 27986.7 28251.6 28376.9 2021.7 2069.9 2075.5 2075.6 2080.3 18723.4 19396.8 19406.8 19449.3 19506.0 2326.7 2366.9 2392.6 2408.6 2410.7 112601.4 117968.0 118495.0 132076.0 134545.3 14623.9 14760.6 14767.8 14868.8 14972.9 22735.4 22735.5 22799.2 22874.3 22886.5 10283.6 10287.3 10377.7 10424.6 10426.8 9517.5 9518.5 9558.7 9650.0 9714.8 8707.0 8907.5 8936.2 9112.0 9234.3 2736.9 2738.3 2741.9 2795.4 2799.9 951.4 961.6 968.1 982.7 983.8 18686.8 19137.1 19169.8 19283.6 19460.3 1047.3 1055.1 1056.3 1056.9 1058.0 10522.8 10537.6 10591.6 10619.7 10636.5 460944.0 465175.0 476059.0 480092.0 492512.0 112722.4 114796.4 115072.0 115477.4 116008.4 1158.3 1171.0 1189.1 1198.8 1200.4 16808.9 16970.7 17047.9 17119.5 17181.3 57277.7 57720.7 58979.7 59258.0 59640.4 69971.7 73190.6 73318.3 73917.6 74517.3 13754.9 13987.4 14097.9 14115.6 14120.7 21999.3 22136.2 22272.9 22298.8 22624.2 17942.7 18001.5 18011.5 18167.7 18194.3 15807.5 16280.5 16423.6 16430.1 16726.6 18480.1 18832.7 18900.7 18923.2 18923.4 13904.7 13916.2 13929.2 13979.2 13991.7 17000.8 17890.3 17970.6 17977.6 18077.4 03:40:03 INFO - PID 25846 | 03:40:03 INFO - PID 25846 | [#3] mootools.html Cycles:180 Average:9381.73 Median:2726.93 stddev:6673.38 (244.7%) stddev-sans-first:6664.49 03:40:03 INFO - PID 25846 | Values: 1302.7 1354.0 1366.9 1370.1 1371.9 14971.3 15646.3 15679.9 15690.9 15697.6 15398.2 15440.6 15461.7 15565.8 15577.5 2711.3 2745.8 2779.9 2780.6 2786.1 2644.9 2764.5 2775.7 2777.3 2780.3 2643.6 2729.1 2741.2 2744.0 2854.3 2795.5 2824.4 2824.8 2825.8 2826.1 2696.4 2723.5 2724.8 2729.4 2737.1 14886.9 14928.3 15098.2 15142.9 15152.6 7989.8 8272.0 8285.2 8286.0 8298.6 5152.2 5436.6 5530.4 5575.6 5578.2 1928.5 1983.9 1986.2 2028.1 2032.3 26415.6 26474.5 26559.5 26596.9 26622.0 6174.8 6445.4 6784.1 6866.4 7194.1 17198.5 17225.7 17226.0 17241.3 17252.7 8065.9 8105.1 8156.6 8199.1 8202.3 7334.7 7400.3 7439.7 7456.9 7537.8 5275.1 5381.9 5401.9 5414.9 5563.4 1527.9 1537.8 1538.6 1580.1 1581.5 876.5 894.7 899.7 902.5 903.5 7694.3 7721.8 7728.5 7743.4 7768.9 713.4 720.9 729.4 735.0 744.0 4706.4 4720.4 4782.1 4859.7 5286.6 18283.6 21328.0 22005.1 22018.6 22086.0 20943.4 21219.6 21789.8 21886.7 21929.7 11224.9 11232.9 11455.4 11486.5 11498.3 8562.7 8742.3 8796.9 8798.2 8980.6 18257.4 18649.7 18820.5 18934.1 19138.7 18971.2 19158.6 19194.6 19209.5 19263.2 11001.7 11247.8 11253.3 11260.7 11274.2 13954.9 13960.7 14016.2 14087.7 14121.7 11377.8 11492.6 11538.1 11617.4 11883.6 10983.5 11106.3 11129.7 11381.2 11466.8 9495.2 9521.0 9597.8 9603.9 9606.3 7654.8 7721.7 7738.0 7765.8 7824.8 7456.6 7469.4 7514.3 7575.5 7598.5 03:40:03 INFO - PID 25846 | 03:40:03 INFO - PID 25846 | [#4] prototype.html Cycles:180 Average:5552.74 Median:6159.63 stddev:1562.55 (25.4%) stddev-sans-first:1520.25 03:40:03 INFO - PID 25846 | Values: 501.5 503.6 525.6 527.7 531.5 6057.7 6108.5 6179.5 6228.1 6276.6 6109.0 6164.8 6195.7 6238.6 6312.6 5215.3 5238.9 5239.0 5241.5 5247.2 4900.1 4973.7 5260.9 5264.9 5268.5 4993.8 5002.9 5011.6 5021.0 5074.3 4972.2 4993.5 5004.0 5006.9 5033.7 4504.9 4505.5 4510.5 4512.8 4520.9 6134.0 6154.4 6232.3 6238.5 6245.4 5723.3 5725.4 5736.3 5746.1 5796.1 6439.7 6494.9 6530.4 6570.2 6577.5 4886.0 4923.6 4937.3 4955.0 4961.8 6845.9 6895.8 6926.5 6950.5 6955.7 4981.3 5011.7 5020.5 5031.6 5042.9 5093.6 5101.7 5102.4 5124.7 5125.3 5686.2 5702.5 5735.7 5760.8 5778.0 6430.8 6589.5 6624.3 6674.5 6708.7 1612.6 1636.1 1686.8 1724.4 1737.2 1181.2 1195.5 1216.6 1222.7 1223.9 5641.2 5649.4 5704.0 5730.9 5786.4 6495.6 6628.9 6750.4 6769.8 6895.7 5793.8 5803.6 5809.5 5834.9 5856.8 3505.9 3604.9 3616.8 3616.8 3638.4 6518.3 6538.2 6551.3 6585.8 6637.2 6939.1 6943.0 6944.9 6945.8 7011.6 6566.9 6581.6 6614.2 6622.2 6622.8 6727.2 6777.5 6791.0 6791.2 6853.1 6732.8 6819.4 6821.0 6829.8 6839.2 6560.5 6564.1 6624.0 6634.8 6721.4 4914.8 4931.2 4946.4 4995.8 5041.9 6334.9 6755.2 6762.7 6806.8 6944.7 6105.6 6436.8 6489.3 6563.9 6638.9 6565.4 6590.0 6608.8 6613.5 6651.3 6353.5 6427.9 6461.1 6483.9 6521.3 6559.5 6567.3 6689.7 6697.7 6727.3 6384.9 6391.3 6439.3 6459.0 6462.1 03:40:03 INFO - PID 25846 | 03:40:03 INFO - PID 25846 | [#5] yui.html Cycles:180 Average:24565.73 Median:18903.07 stddev:36042.07 (190.7%) stddev-sans-first:36099.34 03:40:03 INFO - PID 25846 | Values: 890.9 891.8 905.5 910.9 918.4 18337.3 19091.7 19101.5 19103.0 19197.5 18199.5 18409.4 18433.0 18457.6 18465.3 936.2 951.8 952.4 955.2 955.8 966.9 969.2 972.2 974.1 974.7 1000.0 1023.8 1039.5 1040.1 1040.2 1012.4 1021.1 1023.7 1029.0 1031.2 714.9 728.1 735.5 736.2 753.2 18692.2 18742.3 18897.0 18909.1 18936.6 21277.5 21391.5 22080.0 22150.4 22316.2 16018.4 16143.8 16294.9 16298.8 16458.8 3762.4 3795.2 3796.0 3796.4 3798.1 104786.5 142873.3 143099.3 144106.3 146106.0 16716.9 17045.7 17061.9 17075.4 17139.2 32014.8 32863.0 32939.7 32982.7 33053.2 8767.6 8885.0 8913.6 9012.0 9043.5 4335.0 4391.5 4429.9 4438.3 4465.4 5273.2 5305.2 5387.1 5406.1 5407.3 1085.1 1089.8 1100.8 1102.8 1105.1 678.6 696.2 696.9 702.4 704.9 12361.4 12495.4 12723.7 12790.8 13162.3 671.8 676.5 682.2 690.6 691.5 5452.9 5491.5 5525.8 5589.4 5611.5 122978.0 124367.4 124386.0 126644.4 126817.4 111625.0 114629.0 116278.0 117033.4 119772.4 97005.0 97078.5 99731.5 100771.5 100911.0 10253.7 10290.8 10296.7 10415.9 10673.8 44100.0 44222.3 45212.8 45913.3 46001.5 57495.0 57808.7 58107.0 58238.7 58389.7 15799.7 16093.3 16141.3 16266.2 16289.5 17753.0 17853.6 17889.8 17934.9 18077.9 14702.9 14817.4 14973.3 15143.5 15150.7 15993.6 16340.6 16362.5 16363.8 16385.9 15627.2 15656.5 15692.8 15818.0 15904.5 10751.7 10820.1 11082.2 11325.5 11387.5 9621.3 9629.0 9632.6 9839.0 9951.0 03:40:03 INFO - PID 25846 | -------- Summary: end -------- 03:40:03 INFO - PID 25846 | 03:40:03 INFO - TEST-INFO | 25846: exit 0 03:40:04 INFO - TEST-OK | dromaeo_css | took 1107908ms 03:40:04 INFO - TEST-START | kraken 03:40:04 INFO - Initialising browser for kraken test... 03:40:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44089/getInfo.html -profile /tmp/tmpGpSy3i/profile 03:40:04 INFO - TEST-INFO | started process 26218 (/builds/slave/test/build/application/firefox/firefox http://localhost:44089/getInfo.html) 03:40:08 INFO - TEST-INFO | 26218: exit 0 03:40:09 INFO - Browser initialized. 03:40:09 INFO - Running cycle 1/1 for kraken test... 03:40:09 INFO - Using env: {'DISPLAY': ':0', 03:40:09 INFO - 'HOME': '/home/cltbld', 03:40:09 INFO - 'JSGC_DISABLE_POISONING': '1', 03:40:09 INFO - 'LANG': 'en_US.UTF-8', 03:40:09 INFO - 'LANGUAGE': 'en_US:en', 03:40:09 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:40:09 INFO - 'LOGNAME': 'cltbld', 03:40:09 INFO - 'MAIL': '/var/mail/cltbld', 03:40:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:40:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:40:09 INFO - 'MOZ_CRASHREPORTER': '1', 03:40:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:09 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:40:09 INFO - 'MOZ_NO_REMOTE': '1', 03:40:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:40:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:09 INFO - 'NO_EM_RESTART': '1', 03:40:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:09 INFO - 'PWD': '/builds/slave/test', 03:40:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:40:09 INFO - 'RUST_BACKTRACE': 'full', 03:40:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:40:09 INFO - 'SHELL': '/bin/bash', 03:40:09 INFO - 'SHLVL': '1', 03:40:09 INFO - 'TERM': 'linux', 03:40:09 INFO - 'TMOUT': '86400', 03:40:09 INFO - 'USER': 'cltbld', 03:40:09 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391', 03:40:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:09 INFO - '_': '/tools/buildbot/bin/python'} 03:40:09 INFO - TEST-INFO | started process 26448 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGpSy3i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:40:09 INFO - PID 26448 | 03:40:09 INFO - PID 26448 | (/builds/slave/test/build/application/firefox/firefox:26498): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:40:09 INFO - PID 26448 | 03:40:20 INFO - PID 26448 | RSS: Main: 156827648 03:40:20 INFO - PID 26448 | 03:40:22 INFO - PID 26448 | Cycle 1(1): loaded http://localhost:44089/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44089/tests/kraken/driver.html?testName=audio-beat-detection) 03:40:23 INFO - PID 26448 | RSS: Main: 161910784 03:40:23 INFO - PID 26448 | 03:40:26 INFO - PID 26448 | Cycle 1(1): loaded http://localhost:44089/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44089/tests/kraken/driver.html?testName=audio-dft) 03:40:26 INFO - PID 26448 | RSS: Main: 162643968 03:40:26 INFO - PID 26448 | 03:40:29 INFO - PID 26448 | Cycle 1(1): loaded http://localhost:44089/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44089/tests/kraken/driver.html?testName=audio-fft) 03:40:30 INFO - PID 26448 | RSS: Main: 158695424 03:40:30 INFO - PID 26448 | 03:40:32 INFO - PID 26448 | Cycle 1(1): loaded http://localhost:44089/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44089/tests/kraken/driver.html?testName=audio-oscillator) 03:40:33 INFO - PID 26448 | RSS: Main: 159162368 03:40:33 INFO - PID 26448 | 03:40:35 INFO - PID 26448 | Cycle 1(1): loaded http://localhost:44089/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44089/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:40:35 INFO - PID 26448 | RSS: Main: 159649792 03:40:35 INFO - PID 26448 | 03:40:39 INFO - PID 26448 | Cycle 1(1): loaded http://localhost:44089/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44089/tests/kraken/driver.html?testName=imaging-darkroom) 03:40:39 INFO - PID 26448 | RSS: Main: 161869824 03:40:39 INFO - PID 26448 | 03:40:43 INFO - PID 26448 | Cycle 1(1): loaded http://localhost:44089/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44089/tests/kraken/driver.html?testName=imaging-desaturate) 03:40:44 INFO - PID 26448 | RSS: Main: 161882112 03:40:44 INFO - PID 26448 | 03:40:47 INFO - PID 26448 | Cycle 1(1): loaded http://localhost:44089/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44089/tests/kraken/driver.html?testName=json-parse-financial) 03:40:47 INFO - PID 26448 | RSS: Main: 162041856 03:40:47 INFO - PID 26448 | 03:40:49 INFO - PID 26448 | Cycle 1(1): loaded http://localhost:44089/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44089/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:40:49 INFO - PID 26448 | RSS: Main: 161792000 03:40:49 INFO - PID 26448 | 03:40:51 INFO - PID 26448 | Cycle 1(1): loaded http://localhost:44089/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44089/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:40:52 INFO - PID 26448 | RSS: Main: 162045952 03:40:52 INFO - PID 26448 | 03:40:54 INFO - PID 26448 | Cycle 1(1): loaded http://localhost:44089/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44089/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:40:54 INFO - PID 26448 | RSS: Main: 158330880 03:40:54 INFO - PID 26448 | 03:40:57 INFO - PID 26448 | Cycle 1(1): loaded http://localhost:44089/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44089/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:40:58 INFO - PID 26448 | RSS: Main: 159223808 03:40:58 INFO - PID 26448 | 03:41:01 INFO - PID 26448 | Cycle 1(1): loaded http://localhost:44089/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44089/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:41:01 INFO - PID 26448 | RSS: Main: 159453184 03:41:01 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | Cycle 1(1): loaded http://localhost:44089/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44089/tests/kraken/driver.html?testName=ai-astar) 03:41:03 INFO - PID 26448 | RSS: Main: 160641024 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | __start_tp_report 03:41:03 INFO - PID 26448 | _x_x_mozilla_page_load 03:41:03 INFO - PID 26448 | _x_x_mozilla_page_load_details 03:41:03 INFO - PID 26448 | |i|pagename|runs| 03:41:03 INFO - PID 26448 | |0;ai-astar;108;108;108;107;106;111;107;106;107;107 03:41:03 INFO - PID 26448 | |1;audio-beat-detection;120;117;117;122;120;116;139;116;150;119 03:41:03 INFO - PID 26448 | |2;audio-dft;163;163;161;162;163;161;162;163;166;169 03:41:03 INFO - PID 26448 | |3;audio-fft;73;75;76;73;73;75;76;77;76;75 03:41:03 INFO - PID 26448 | |4;audio-oscillator;79;79;79;78;79;77;79;79;80;78 03:41:03 INFO - PID 26448 | |5;imaging-gaussian-blur;113;113;110;114;113;111;113;114;114;114 03:41:03 INFO - PID 26448 | |6;imaging-darkroom;147;147;148;147;148;148;148;148;150;148 03:41:03 INFO - PID 26448 | |7;imaging-desaturate;98;103;93;99;94;94;95;94;94;94 03:41:03 INFO - PID 26448 | |8;json-parse-financial;56;57;53;55;53;53;55;54;53;53 03:41:03 INFO - PID 26448 | |9;json-stringify-tinderbox;37;38;38;38;38;38;38;38;38;37 03:41:03 INFO - PID 26448 | |10;stanford-crypto-aes;74;76;76;74;74;76;73;76;73;74 03:41:03 INFO - PID 26448 | |11;stanford-crypto-ccm;110;110;111;128;113;113;117;115;116;122 03:41:03 INFO - PID 26448 | |12;stanford-crypto-pbkdf2;188;197;168;168;169;172;177;167;173;168 03:41:03 INFO - PID 26448 | |13;stanford-crypto-sha256-iterative;69;68;65;68;65;65;66;66;67;69 03:41:03 INFO - PID 26448 | __end_tp_report 03:41:03 INFO - PID 26448 | __start_cc_report 03:41:03 INFO - PID 26448 | _x_x_mozilla_cycle_collect,710 03:41:03 INFO - PID 26448 | __end_cc_report 03:41:03 INFO - PID 26448 | __startTimestamp1505731263692__endTimestamp 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | ------- Summary: start ------- 03:41:03 INFO - PID 26448 | Number of tests: 14 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | [#0] ai-astar Cycles:10 Average:107.50 Median:107.50 stddev:1.43 (1.3%) stddev-sans-first:1.51 03:41:03 INFO - PID 26448 | Values: 108.0 108.0 108.0 107.0 106.0 111.0 107.0 106.0 107.0 107.0 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | [#1] audio-beat-detection Cycles:10 Average:123.60 Median:120.00 stddev:11.48 (9.6%) stddev-sans-first:12.10 03:41:03 INFO - PID 26448 | Values: 120.0 117.0 117.0 122.0 120.0 116.0 139.0 116.0 150.0 119.0 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | [#2] audio-dft Cycles:10 Average:163.30 Median:163.00 stddev:2.45 (1.5%) stddev-sans-first:2.60 03:41:03 INFO - PID 26448 | Values: 163.0 163.0 161.0 162.0 163.0 161.0 162.0 163.0 166.0 169.0 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | [#3] audio-fft Cycles:10 Average:74.90 Median:75.50 stddev:1.45 (1.9%) stddev-sans-first:1.36 03:41:03 INFO - PID 26448 | Values: 73.0 75.0 76.0 73.0 73.0 75.0 76.0 77.0 76.0 75.0 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | [#4] audio-oscillator Cycles:10 Average:78.70 Median:79.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 03:41:03 INFO - PID 26448 | Values: 79.0 79.0 79.0 78.0 79.0 77.0 79.0 79.0 80.0 78.0 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.50 stddev:1.37 (1.2%) stddev-sans-first:1.45 03:41:03 INFO - PID 26448 | Values: 113.0 113.0 110.0 114.0 113.0 111.0 113.0 114.0 114.0 114.0 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:0.88 (0.6%) stddev-sans-first:0.87 03:41:03 INFO - PID 26448 | Values: 147.0 147.0 148.0 147.0 148.0 148.0 148.0 148.0 150.0 148.0 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:94.00 stddev:3.19 (3.4%) stddev-sans-first:3.28 03:41:03 INFO - PID 26448 | Values: 98.0 103.0 93.0 99.0 94.0 94.0 95.0 94.0 94.0 94.0 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.50 stddev:1.48 (2.7%) stddev-sans-first:1.41 03:41:03 INFO - PID 26448 | Values: 56.0 57.0 53.0 55.0 53.0 53.0 55.0 54.0 53.0 53.0 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | [#9] json-stringify-tinderbox Cycles:10 Average:37.80 Median:38.00 stddev:0.42 (1.1%) stddev-sans-first:0.33 03:41:03 INFO - PID 26448 | Values: 37.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 37.0 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:75.00 stddev:1.26 (1.7%) stddev-sans-first:1.32 03:41:03 INFO - PID 26448 | Values: 74.0 76.0 76.0 74.0 74.0 76.0 73.0 76.0 73.0 74.0 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | [#11] stanford-crypto-ccm Cycles:10 Average:115.50 Median:115.50 stddev:5.72 (5.0%) stddev-sans-first:5.71 03:41:03 INFO - PID 26448 | Values: 110.0 110.0 111.0 128.0 113.0 113.0 117.0 115.0 116.0 122.0 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:172.50 stddev:10.09 (5.8%) stddev-sans-first:9.48 03:41:03 INFO - PID 26448 | Values: 188.0 197.0 168.0 168.0 169.0 172.0 177.0 167.0 173.0 168.0 03:41:03 INFO - PID 26448 | 03:41:03 INFO - PID 26448 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.50 stddev:1.62 (2.4%) stddev-sans-first:1.51 03:41:03 INFO - PID 26448 | Values: 69.0 68.0 65.0 68.0 65.0 65.0 66.0 66.0 67.0 69.0 03:41:03 INFO - PID 26448 | -------- Summary: end -------- 03:41:03 INFO - PID 26448 | 03:41:04 INFO - PID 26448 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:41:04 INFO - TEST-INFO | 26448: exit 0 03:41:04 INFO - TEST-OK | kraken | took 60311ms 03:41:04 INFO - SUITE-END | took 1168s 03:41:04 INFO - Completed test suite (00:19:28) 03:41:04 INFO - javascript benchmark 03:41:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8527.218538950181, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3089.382123575285, 3143.6856314368565, 3143.732782713469, 3163.8101713897167, 3188.9521657175146, 6097.817065488031, 6252.124702541648, 6279.968600156999, 6314.589551679143, 6353.491720662348, 6182.598131121474, 6211.192544969159, 6241.594296370733, 6285.9685701571425, 6295.181626388563, 5751.10857817038, 5758.827235182951, 5763.337216220126, 5797.797077102302, 5861.384554621767, 5835.824925252249, 5872.236609240793, 5876.823695289148, 5920.171176035358, 5923.733431995549, 19.910344888165362, 5662.6036177467595, 5796.275465566804, 5878.5003274721485, 5889.676067816268, 5679.829605111853, 5680.289963754531, 5747.540196784289, 5824.679642619654, 5841.7371218295275, 5730.455606717355, 5744.167095771089, 5806.970965145189, 5815.796447124372, 5816.156657284712, 6194.5354098442795, 6243.56295059346, 6312.431881130675, 6323.20959880015, 6337.429631333156, 6661.16735408074, 6674.16572928384, 6761.0872532208505, 6768.984652302145, 6781.186257649064, 6698.397144256992, 6710.228323742784, 6813.829654258634, 6815.0, 6819.965900170517, 6134.785282515135, 6234.937650623527, 6244.781432649836, 6300.653464059475, 6300.905486417708, 6921.8961715574305, 6938.618375989268, 6947.305269473113, 6948.895766563505, 6952.687129079203, 6575.145231119924, 6657.567258128206, 6660.933390666031, 6661.034150048215, 6672.899906501407, 6666.300038495985, 6697.832554186184, 6760.121184246016, 6768.492363072789, 6773.8983915241315, 6443.001334793114, 6638.17022872141, 6669.499787515956, 6696.598204107671, 6774.72901083961, 6694.799156025327, 6750.223724271674, 6773.525853190229, 6775.0, 6778.017187507784, 5231.058409486329, 5305.549028332635, 5309.840704778863, 5331.760070796823, 5336.546393556591, 4593.678442508993, 4662.067586482649, 4669.836555720602, 4712.175369310357, 4714.669973101919, 5779.855503612359, 5781.075027995501, 5847.06446968483, 5850.64896106235, 5854.8536286592325, 6571.0471981562405, 6695.598264104169, 6719.563228390237, 6804.489663275274, 6812.591244525245, 5786.218860453869, 5786.884262314814, 5799.0, 5820.941790582125, 5849.795257165979, 5758.078707406795, 5758.337791154071, 5837.591368604242, 5849.532037436995, 5855.531557475391, 6352.333005034498, 6554.279029306773, 6555.639439830759, 6643.236027856762, 6643.7674681385915, 6651.43462805667, 6704.094947182068, 6807.9319206807295, 6808.148981377328, 6812.386885180357, 6571.605703657605, 6578.440832529097, 6582.0785090086465, 6587.440067594116, 6595.868082638416, 6600.976848588476, 6606.4384527315715, 6694.698738556679, 6696.631685257357, 6700.664966751546, 6763.357481039294, 6770.255271920183, 6844.623545705034, 6856.554323968926, 6868.313168683092, 6310.7791227306825, 6432.581882177644, 6440.033994900802, 6524.9021264682015, 6639.834004149935, 6444.677766111769, 6465.838354041187, 6466.482681385383, 6497.902531462127, 6526.118973938457, 6494.0258961156205, 6510.609363438209, 6511.120998665119, 6517.73929042833, 6537.771178008747, 6492.350764923659, 6586.604803711793, 6656.068150459037, 6667.166604174478, 6682.198136223684, 6183.3816618337805, 6342.90485642725, 6430.099786030053, 6436.324185960407, 6450.774222902176], "value": 6095.577941417416, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [855.119227195988, 883.5891310540597, 884.4207044385926, 884.9601767920443, 886.2732559301376, 39241.80379098104, 43425.0, 43493.56506434939, 43544.34683479743, 43644.908877278, 39071.41392879109, 39327.803360983155, 39408.80295598518, 39471.40792888109, 39516.0, 7047.43620510358, 7121.4302855771675, 7696.191899850519, 7920.48516846406, 7938.483998540077, 7642.732504362349, 7730.304272615464, 7731.0336207974005, 7732.381409487256, 7733.0, 7672.65473053714, 7697.0, 7698.461107722434, 7706.7302644407455, 7726.343260822837, 7581.2418758124295, 7640.923590764077, 7676.846463070735, 7694.692212311501, 7727.227277272228, 7761.728339508147, 7796.220377962215, 7802.414818888549, 7806.531608103475, 7817.843643127162, 39492.60507394918, 39520.802395987834, 39661.80169099136, 39711.0, 39765.80117099425, 3577.0520811984843, 3605.3330133925047, 3607.116256517144, 3608.2963822054708, 3617.385044542434, 32471.51292730611, 32491.187720306945, 32531.837340813145, 32541.837290813393, 32562.0, 5215.765290561933, 5237.790488380499, 5284.894302113975, 5307.150855863043, 5314.0700377433795, 190610.0, 192145.0, 192287.0, 192335.0, 192348.0, 43941.560584393745, 44560.3315950261, 50169.24746128811, 50183.49816501861, 51103.48896511061, 53491.465085349424, 53734.46265537295, 53919.19121213185, 53996.73001634967, 54048.0, 16159.757603635955, 16414.0, 16415.343386264656, 16542.751858722128, 16549.50351489433, 13500.594982150551, 13534.323283835769, 13557.457701691821, 13575.932120339336, 13578.592642220749, 13851.515196968057, 13897.0, 13920.443182272798, 14107.647308817363, 14129.152250864981, 5522.861928451821, 5567.276254086943, 5570.192322113352, 5590.776368945197, 5602.411746766531, 1018.6791160784329, 1022.1771473963531, 1028.6451174344838, 1043.2957753516346, 1051.164324362134, 23426.0, 23601.52796944086, 23604.527909441716, 23639.7636023641, 23773.643395349085, 1133.8015847226868, 1134.9943250283695, 1142.9828552571719, 1143.8341440491083, 1148.5463242019355, 12489.875101248872, 12528.874711252953, 12625.684357891127, 12667.0, 12800.6799830005, 243389.0, 243721.0, 244173.0, 245448.0, 246001.0, 53829.461705383226, 54211.18683219755, 54261.72869135708, 54368.728156358964, 54373.0, 1429.4925301517812, 1459.5256541623803, 1464.3337281536876, 1471.1687896338535, 1476.1143314011508, 22447.551048978603, 23152.18967336135, 23257.0, 23369.649455258525, 23392.64911026302, 38000.23999520049, 38016.42975355427, 38049.61950380571, 38104.0, 38122.61877381302, 208121.0, 213685.0, 214370.0, 215565.0, 215808.0, 37687.2462550742, 38230.2353952925, 38353.23293534169, 38537.80731096439, 38706.61293387143, 45425.091498170514, 45936.31095533503, 46608.30087548622, 46682.76658616685, 47079.0, 33918.83040584781, 34359.14102147366, 34416.48375274423, 34426.311473769885, 34744.47883281804, 33797.155071123416, 34267.65732342745, 34725.30549389048, 34744.652553474145, 34939.82530087333, 38936.610633893295, 38989.610103898594, 39102.6089739099, 39239.215215696095, 39293.0, 32763.508547371333, 32901.0, 33308.0, 33353.16617084592, 33658.66341336555, 23503.647445288676, 24059.759402406453, 24182.63726044146, 24225.394365140306, 24741.87629061843], "value": 17609.65391296522, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.1351121982875, 555.2864569028799, 560.8317504748577, 571.6627189957924, 590.7223604905694, 22417.3274801756, 23128.42178945527, 23304.533909321803, 23329.416764580896, 23384.76615233847, 23360.299191024253, 24539.50920981584, 24900.0, 25165.99336026557, 25548.74451255487, 6280.371962803717, 6377.553571250014, 6416.13382193405, 6418.390252925965, 6420.839479013039, 6291.213598300213, 6395.744170233185, 6401.423871851533, 6404.0, 6669.23303820062, 6649.900251496231, 6655.0682904393425, 6672.266050734415, 6672.76645317414, 6675.866482670343, 6641.037049627801, 6647.50143739219, 6649.700763465656, 6651.866962660768, 6669.833254168636, 6156.599821011665, 6170.413810687977, 6173.351798061184, 6191.071339299141, 6199.101130336121, 27004.729952700614, 27854.88580456779, 27986.720132798615, 28251.57622635662, 28376.858115709496, 2021.686638571009, 2069.865458745177, 2075.543380456297, 2075.6263872502946, 2080.3446914221972, 18723.438296851116, 19396.80603193978, 19406.805931940642, 19449.31927382549, 19506.0, 2326.674265602817, 2366.9408264793346, 2392.5932591459496, 2408.5664580375524, 2410.6745589345387, 112601.43699281533, 117968.0, 118495.0, 132076.0, 134545.32727336301, 14623.915187868182, 14760.645609544996, 14767.778483322758, 14868.776968345483, 14972.925135374253, 22735.431614209447, 22735.545289094458, 22799.202027928943, 22874.31377058691, 22886.542269154525, 10283.588656453807, 10287.27989040775, 10377.740556486147, 10424.583016679248, 10426.791464170827, 9517.476538790295, 9518.524073796283, 9558.66544670947, 9650.034996500293, 9714.75713107178, 8707.042225355206, 8907.465552066897, 8936.24041956441, 9111.954440227757, 9234.261259099256, 2736.945261094807, 2738.315421144714, 2741.8629068546493, 2795.3850152966315, 2799.90200342987, 951.4338968313842, 961.5864678921973, 968.1093394077325, 982.7297493189287, 983.8229118758549, 18686.813131868777, 19137.13882875274, 19169.849809011503, 19283.614327713367, 19460.31888883882, 1047.2773786087575, 1055.1242468750918, 1056.2553399853084, 1056.9312994655322, 1057.962971296001, 10522.842157367646, 10537.63118290856, 10591.576336946437, 10619.681409557727, 10636.521356538975, 460944.0, 465175.0, 476059.0, 480092.0, 492512.0, 112722.43638781754, 114796.42601786938, 115072.0, 115477.4226128864, 116008.41995789966, 1158.2992289664633, 1171.0455978377595, 1189.0725234317247, 1198.8441502604605, 1200.4177973682756, 16808.88777930346, 16970.67087198888, 17047.91476042612, 17119.54483868885, 17181.31274748996, 57277.713611431675, 57720.71139644275, 58979.705101475934, 59258.0, 59640.403595963486, 69971.65014174896, 73190.63404682942, 73318.26681733114, 73917.6304118476, 74517.25482745103, 13754.93122534381, 13987.370568324246, 14097.929510352793, 14115.647108822362, 14120.717585648435, 21999.34001979943, 22136.225232116798, 22272.88863555672, 22298.77701222967, 22624.208152714575, 17942.730859036863, 18001.459956201335, 18011.459656210336, 18167.727484088013, 18194.272229110687, 15807.525774226791, 16280.511584652479, 16423.589410264838, 16430.096344701156, 16726.58183545421, 18480.075996200405, 18832.7175092371, 18900.716489252947, 18923.243070277036, 18923.43229703111, 13904.72190556163, 13916.234607096301, 13929.23389213603, 13979.161250325013, 13991.720165596835, 17000.82999170042, 17890.28438862431, 17970.550736231176, 17977.550561236072, 18077.36729214471], "value": 11821.691858424909, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1302.70037891285, 1353.9932300338496, 1366.883814875736, 1370.0751992405123, 1371.9039667223285, 14971.326290316934, 15646.295916683748, 15679.92160039201, 15690.921545392257, 15697.607559811011, 15398.230088495586, 15440.61398465039, 15461.690766184669, 15565.766513502307, 15577.532674019769, 2711.30861630284, 2745.835249885008, 2779.902703405381, 2780.6107144999714, 2786.122371452986, 2644.907428240012, 2764.4885696146175, 2775.736305051017, 2777.3334399744117, 2780.2771279467383, 2643.6034594810835, 2729.0584748261817, 2741.163944996775, 2744.0258708158617, 2854.300696329392, 2795.5247607906604, 2824.449232399683, 2824.7740180785486, 2825.759810416117, 2826.1380279014893, 2696.4337489127306, 2723.509768241716, 2724.809263351566, 2729.426820367725, 2737.096758069852, 14886.925565372212, 14928.32822522989, 15098.24508774568, 15142.924285378502, 15152.621184470367, 7989.800254993614, 8272.04871439786, 8285.171482851727, 8286.047104582989, 8298.626561804673, 5152.175651895716, 5436.565074794007, 5530.419305972855, 5575.553955683536, 5578.163275508666, 1928.4503916383762, 1983.871048381865, 1986.2055177928798, 2028.1177687705747, 2032.3293313206755, 26415.603765943528, 26474.470510589872, 26559.46881062368, 26596.86701566499, 26622.0, 6174.845628859224, 6445.355464453517, 6784.10365018064, 6866.416354609814, 7194.136703595497, 17198.484045478657, 17225.65548689019, 17226.0, 17241.310347586208, 17252.65494690099, 8065.919340806634, 8105.1084380718075, 8156.5513896735065, 8199.057108432487, 8202.26179643823, 7334.706611735577, 7400.296971787671, 7439.702411903463, 7456.918746781795, 7537.849243015109, 5275.076861549213, 5381.892362152813, 5401.864953376118, 5414.917016596696, 5563.443655634404, 1527.9083255004734, 1537.8462153784756, 1538.6461113943633, 1580.083551540104, 1581.4860170444651, 876.5179151466668, 894.6689724801865, 899.7480705402497, 902.5351943748974, 903.4895284164426, 7694.345980591601, 7721.7683469496005, 7728.459007869507, 7743.419243556756, 7768.922310776933, 713.3615414204362, 720.8846584546448, 729.4237552333703, 735.0481126940518, 743.9851202976018, 4706.388169537938, 4720.433547974265, 4782.139214941344, 4859.7327147006545, 5286.550643195372, 18283.63432731338, 21328.0, 22005.11979520833, 22018.559628807656, 22086.0, 20943.37169884906, 21219.575608487743, 21789.78210217909, 21886.671699924515, 21929.671054934188, 11224.94387528057, 11232.887671123184, 11455.369954652408, 11486.483108260149, 11498.25261358009, 8562.657493700182, 8742.256908162877, 8796.91203087961, 8798.208161265517, 8980.640774368952, 18257.360992365204, 18649.72025419647, 18820.52948676294, 18934.14796334196, 19138.7129193065, 18971.241150354384, 19158.61682766365, 19194.616107678048, 19209.51976200606, 19263.22947082101, 11001.724956876142, 11247.831282530933, 11253.324800511667, 11260.718482037686, 11274.210805243554, 13954.930225348808, 13960.650983725489, 14016.159030458206, 14087.718245634826, 14121.717565648423, 11377.829332560184, 11492.597759078391, 11538.13463990221, 11617.419129043683, 11883.584074557695, 10983.450827458755, 11106.333619982826, 11129.721756956142, 11381.203315767816, 11466.77066458683, 9495.240380769385, 9521.0478952107, 9597.760055998657, 9603.855942161013, 9606.27952903549, 7654.8086297843, 7721.6911323548675, 7737.961310193638, 7765.7670269892, 7824.765257042298, 7456.589887556236, 7469.439792015729, 7514.323710866049, 7575.469717119749, 7598.506097103671], "value": 6682.630125935635, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.53607912680775, 503.59460634189463, 525.6136739496469, 527.7360125063478, 531.5056996992797, 6057.666828324441, 6108.450239478446, 6179.474744646711, 6228.128062071308, 6276.592021518598, 6108.969455152718, 6164.845878853031, 6195.721192546335, 6238.59449135807, 6312.6212427254395, 5215.348081489814, 5238.895222095556, 5238.973805130969, 5241.50205730455, 5247.160454327299, 4900.142475066867, 4973.701577905319, 5260.868478288055, 5264.947350526484, 5268.525832675058, 4993.800247990076, 5002.9499705002845, 5011.649184557083, 5021.020900924321, 5074.314967479399, 4972.229304457779, 4993.475685053054, 5003.974980125113, 5006.949930500685, 5033.723145227011, 4504.864854054384, 4505.481869585007, 4510.481294651125, 4512.774361281923, 4520.909581808378, 6133.975787989953, 6154.415330543591, 6232.345603711636, 6238.5009199264405, 6245.437910588069, 5723.313202415737, 5725.427457254283, 5736.3403208631125, 5746.109353050284, 5796.13058041293, 6439.678016099223, 6494.93505064948, 6530.379613936669, 6570.211574611029, 6577.539572229945, 4885.972321486579, 4923.556879880828, 4937.308776771255, 4955.0, 4961.751912404401, 6845.863082738318, 6895.827604309932, 6926.510800177935, 6950.478714096463, 6955.739384699942, 4981.277714731416, 5011.7494125294315, 5020.472850350734, 5031.572316353077, 5042.949570504248, 5093.567046801063, 5101.69389836611, 5102.438731739506, 5124.693203233226, 5125.256837758504, 5686.175504551817, 5702.543796496353, 5735.680793417538, 5760.769569217268, 5778.0, 6430.807075787734, 6589.505787065989, 6624.27133015368, 6674.466042716571, 6708.664566771739, 1612.6049117965938, 1636.141025961356, 1686.8313168683032, 1724.430937790526, 1737.1748419500889, 1181.2026881854713, 1195.5038658956435, 1216.5741990303322, 1222.6882145053132, 1223.920445171061, 5641.182028605829, 5649.350324712711, 5704.0, 5730.856728581819, 5786.392428794916, 6495.610263384212, 6628.867422651617, 6750.401095578238, 6769.796906092825, 6895.724171033103, 5793.826185214366, 5803.564732645069, 5809.5352371811, 5834.883302334015, 5856.795012174553, 3505.9123521912156, 3604.891853244407, 3616.764910280823, 3616.782993020427, 3638.399664055401, 6518.348165183444, 6538.248101468297, 6551.279359270477, 6585.83535411609, 6637.1703537057865, 6939.091749768854, 6942.965285173643, 6944.930550694529, 6945.826354341081, 7011.579305241702, 6566.9014964776525, 6581.572197807127, 6614.239362473377, 6622.1722284714415, 6622.8344291393105, 6727.192736871411, 6777.526202935795, 6791.049253104668, 6791.2190098139345, 6853.143357080365, 6732.764353247613, 6819.420349270367, 6821.0, 6829.760958366433, 6839.213490448463, 6560.4582923014, 6564.113844631104, 6623.979686195757, 6634.800955971328, 6721.4040488338505, 4914.803407863788, 4931.211006238985, 4946.406431228275, 4995.750212489434, 5041.949580504148, 6334.873302534015, 6755.2231493378895, 6762.729490820312, 6806.829829254309, 6944.722211111499, 6105.603135796159, 6436.8390790230615, 6489.286178520265, 6563.9343606565235, 6638.867222655424, 6565.409113179837, 6590.0, 6608.8347791305605, 6613.503987201076, 6651.301613330724, 6353.491720662429, 6427.935720642734, 6461.095446637569, 6483.870322593616, 6521.282658907421, 6559.4752419805345, 6567.343265673395, 6689.665516724242, 6697.665116744046, 6727.327267273234, 6384.872302554016, 6391.296957334596, 6439.259485159079, 6458.967705161444, 6462.090435286772], "value": 5088.333334491554, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [890.9242714369274, 891.7681402835262, 905.5427009360272, 910.886139232596, 918.4122161816439, 18337.266509339628, 19091.71362429563, 19101.522461938457, 19103.0, 19197.520061998457, 18199.45401637953, 18409.355672551465, 18432.98618575978, 18457.63084738308, 18465.261389544437, 936.1901954809092, 951.8239125761721, 952.4333021851987, 955.173774684896, 955.7945041816043, 966.9323147379686, 969.2246203037577, 972.1639390124487, 974.0892265731528, 974.6539978307705, 1000.0, 1023.7696518283401, 1039.45428649959, 1040.0587468341164, 1040.2302296300759, 1012.448215722433, 1021.1473419694564, 1023.6928921323611, 1028.958841646337, 1031.1853635627845, 714.9034880291147, 728.0535304104643, 735.547638202507, 736.1975446763054, 753.1940823319051, 18692.158852851655, 18742.250309987583, 18897.0, 18909.14908829106, 18936.62126757457, 21277.468063298387, 21391.465213369636, 22080.0, 22150.446238843997, 22316.21893233729, 16018.35926562936, 16143.757843632355, 16294.918525407416, 16298.755518667229, 16458.75311870323, 3762.4168253920684, 3795.1840354323954, 3795.981020094882, 3796.354619714655, 3798.088458769903, 104786.47606761994, 142873.2856335722, 143099.28450357786, 144106.27946860302, 146106.0, 16716.916415417843, 17045.659086818196, 17061.914690426718, 17075.40236091731, 17139.22873470697, 32014.83992580022, 32863.0, 32939.67060329414, 32982.67017329844, 33053.17367065843, 8767.605457754416, 8884.955575222082, 8913.55432228399, 9012.0086790453, 9043.547822608843, 4335.0, 4391.451068616423, 4429.933550996738, 4438.312061630452, 4465.352523884018, 5273.235380869829, 5305.151175811851, 5387.111126664135, 5406.107992181324, 5407.324084489439, 1085.137315833914, 1089.836524521328, 1100.785346857355, 1102.7518808268076, 1105.0883021507289, 678.6030172349231, 696.24109720405, 696.923338432772, 702.4134847402477, 704.8695991241636, 12361.443735031946, 12495.375231238402, 12723.745525089447, 12790.808137877939, 13162.341882906008, 671.7648822911942, 676.5129107042922, 682.2324884504932, 690.5649440852332, 691.4779341597063, 5452.890942181134, 5491.533219676372, 5525.834224973257, 5589.385167631557, 5611.523020543218, 122978.0, 124367.3781631086, 124386.0, 126644.36677816552, 126817.36591317168, 111625.0, 114629.0, 116278.0, 117033.4148329253, 119772.40113799549, 97005.0299496996, 97078.5146074265, 99731.50134249282, 100771.49614251882, 100911.0, 10253.692389228336, 10290.794184116425, 10296.691099267035, 10415.895841041493, 10673.786524269626, 44100.0, 44222.336664949406, 45212.77393613011, 45913.31130032986, 46001.53998460106, 57495.0, 57808.71095644495, 58107.0, 58238.708806455696, 58389.70805145947, 15799.68400632004, 16093.275802588678, 16141.273642685872, 16266.186690665656, 16289.51131466058, 17753.0, 17853.642927141125, 17889.821101788817, 17934.91032544829, 18077.909610451865, 14702.926485367505, 14817.40730370773, 14973.251337433303, 15143.545693629208, 15150.696986060437, 15993.600159996093, 16340.591485212964, 16362.509124726277, 16363.836361636708, 16385.91807040957, 15627.218639067774, 15656.4520241791, 15692.76460853111, 15818.0, 15904.522864313627, 10751.731206719895, 10820.134389248684, 11082.16883733707, 11325.490352933974, 11387.487563059516, 9621.326507144433, 9628.951855240679, 9632.614695412105, 9839.0, 9950.950245248727], "value": 8909.429471503388, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1428.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 108.0, 108.0, 107.0, 106.0, 111.0, 107.0, 106.0, 107.0, 107.0], "value": 107.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 117.0, 117.0, 122.0, 120.0, 116.0, 139.0, 116.0, 150.0, 119.0], "value": 123.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 161.0, 162.0, 163.0, 161.0, 162.0, 163.0, 166.0, 169.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 76.0, 73.0, 73.0, 75.0, 76.0, 77.0, 76.0, 75.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 78.0, 79.0, 77.0, 79.0, 79.0, 80.0, 78.0], "value": 78.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 110.0, 114.0, 113.0, 111.0, 113.0, 114.0, 114.0, 114.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 147.0, 148.0, 148.0, 148.0, 148.0, 150.0, 148.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 103.0, 93.0, 99.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 53.0, 55.0, 53.0, 53.0, 55.0, 54.0, 53.0, 53.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 37.0], "value": 37.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 74.0, 74.0, 76.0, 73.0, 76.0, 73.0, 74.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 111.0, 128.0, 113.0, 113.0, 117.0, 115.0, 116.0, 122.0], "value": 115.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 197.0, 168.0, 168.0, 169.0, 172.0, 177.0, 167.0, 173.0, 168.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 65.0, 68.0, 65.0, 65.0, 66.0, 66.0, 67.0, 69.0], "value": 66.8, "unit": "score"}]}]} 03:41:04 INFO - Return code: 0 03:41:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:41:04 INFO - # TBPL SUCCESS # 03:41:04 INFO - Running post-action listener: _package_coverage_data 03:41:04 INFO - Running post-action listener: _resource_record_post_action 03:41:04 INFO - [mozharness: 2017-09-18 10:41:04.459087Z] Finished run-tests step (success) 03:41:04 INFO - Running post-run listener: _resource_record_post_run 03:41:04 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 147456; Write bytes: 332345344; Read time: 44; Write time: 303856 03:41:04 INFO - TinderboxPrint: CPU usage
13.1% 03:41:04 INFO - TinderboxPrint: I/O read bytes / time
147,456 / 44 03:41:04 INFO - TinderboxPrint: I/O write bytes / time
332,345,344 / 303,856 03:41:04 INFO - TinderboxPrint: CPU idle
8,216.8 (86.9%) 03:41:04 INFO - TinderboxPrint: CPU user
1,190.8 (12.6%) 03:41:04 INFO - TinderboxPrint: Swap in / out
0 / 0 03:41:04 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 9932800; Read time: 0; Write time: 700 03:41:04 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:41:04 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 143360; Write bytes: 322027520; Read time: 28; Write time: 303120 03:41:05 INFO - Running post-run listener: _upload_blobber_files 03:41:05 INFO - Blob upload gear active. 03:41:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:41:05 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:41:05 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:41:05 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:41:05 INFO - (blobuploader) - INFO - Open directory for files ... 03:41:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:41:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:41:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:41:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:41:06 INFO - (blobuploader) - INFO - Done attempting. 03:41:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:41:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:41:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:41:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:41:07 INFO - (blobuploader) - INFO - Done attempting. 03:41:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:41:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:41:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:41:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:41:14 INFO - (blobuploader) - INFO - Done attempting. 03:41:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:41:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:41:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:41:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:41:15 INFO - (blobuploader) - INFO - Done attempting. 03:41:15 INFO - (blobuploader) - INFO - Iteration through files over. 03:41:15 INFO - Return code: 0 03:41:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:41:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:41:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8b0114a5e9e46b52caadc649844810fa90a8e1a2c7006d1c583f33a6ac128480c158be6b6b2eea7802031830c4006573b2dc28cdfbfeb3042f8e7ec2f6cb0027", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a95226a374afc44475f5ca8ae1e9ec9456f00e4338f5d0383f6d47457cca499908b5fdbef299277ebc66b7089c8854f32fdf64f78ace30a6b8d1e76d7c801bc5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/afdc1e3b46e8f069ac20a0a34c90882d1d4c9c26b66073d59f334ab8c520143c1067591f2b49c81329db54a30f971c7fdeb72ae63a49461271fdf530aa84e913", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8b6d0543cd6c12dcf9f08e110cdb50469ed0d5674796df5c5a85f9f5f6c7116bf4d238f7027861d73b0d4e52fa62a9763e9e00973f354079f37d3118920efc02"} 03:41:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:41:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:41:15 INFO - Contents: 03:41:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8b0114a5e9e46b52caadc649844810fa90a8e1a2c7006d1c583f33a6ac128480c158be6b6b2eea7802031830c4006573b2dc28cdfbfeb3042f8e7ec2f6cb0027", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a95226a374afc44475f5ca8ae1e9ec9456f00e4338f5d0383f6d47457cca499908b5fdbef299277ebc66b7089c8854f32fdf64f78ace30a6b8d1e76d7c801bc5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/afdc1e3b46e8f069ac20a0a34c90882d1d4c9c26b66073d59f334ab8c520143c1067591f2b49c81329db54a30f971c7fdeb72ae63a49461271fdf530aa84e913", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8b6d0543cd6c12dcf9f08e110cdb50469ed0d5674796df5c5a85f9f5f6c7116bf4d238f7027861d73b0d4e52fa62a9763e9e00973f354079f37d3118920efc02"} 03:41:15 INFO - Running post-run listener: copy_logs_to_upload_dir 03:41:15 INFO - Copying logs to upload dir... 03:41:15 INFO - mkdir: /builds/slave/test/build/upload/logs 03:41:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.313506 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-18 03:41:15.411673) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-18 03:41:15.416679) ========= 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=62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8b0114a5e9e46b52caadc649844810fa90a8e1a2c7006d1c583f33a6ac128480c158be6b6b2eea7802031830c4006573b2dc28cdfbfeb3042f8e7ec2f6cb0027", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a95226a374afc44475f5ca8ae1e9ec9456f00e4338f5d0383f6d47457cca499908b5fdbef299277ebc66b7089c8854f32fdf64f78ace30a6b8d1e76d7c801bc5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/afdc1e3b46e8f069ac20a0a34c90882d1d4c9c26b66073d59f334ab8c520143c1067591f2b49c81329db54a30f971c7fdeb72ae63a49461271fdf530aa84e913", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8b6d0543cd6c12dcf9f08e110cdb50469ed0d5674796df5c5a85f9f5f6c7116bf4d238f7027861d73b0d4e52fa62a9763e9e00973f354079f37d3118920efc02"} build_url:https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011303 build_url: 'https://queue.taskcluster.net/v1/task/WN7zCNJvSaqvgRuYr4ZXkw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8b0114a5e9e46b52caadc649844810fa90a8e1a2c7006d1c583f33a6ac128480c158be6b6b2eea7802031830c4006573b2dc28cdfbfeb3042f8e7ec2f6cb0027", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a95226a374afc44475f5ca8ae1e9ec9456f00e4338f5d0383f6d47457cca499908b5fdbef299277ebc66b7089c8854f32fdf64f78ace30a6b8d1e76d7c801bc5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/afdc1e3b46e8f069ac20a0a34c90882d1d4c9c26b66073d59f334ab8c520143c1067591f2b49c81329db54a30f971c7fdeb72ae63a49461271fdf530aa84e913", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8b6d0543cd6c12dcf9f08e110cdb50469ed0d5674796df5c5a85f9f5f6c7116bf4d238f7027861d73b0d4e52fa62a9763e9e00973f354079f37d3118920efc02"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-18 03:41:15.452951) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-18 03:41:15.453348) ========= 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=62c568580acbfdaac3eec7be000001dc-1505727620.678743-848443391 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004546 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-18 03:41:15.533041) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-18 03:41:15.536402) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-18 03:41:15.536768) ========= ========= Total master_lag: 0.24 =========