builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-021 starttime: 1504030345.36 results: success (0) revision: e336d84fc1d2d1fde7387dd5f86fe06fa59abe10 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 11:12:25.358180) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 11:12:25.358659) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 11:12:25.358946) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 11:12:25.375835) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:12:25.376103) ========= 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=df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-29 11:12: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% 225M=0s 2017-08-29 11:12:25 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131496 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:12:25.526237) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:12:25.526623) ========= 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=df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014442 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:12:25.576182) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:12:25.576523) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e336d84fc1d2d1fde7387dd5f86fe06fa59abe10 --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 e336d84fc1d2d1fde7387dd5f86fe06fa59abe10 --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=df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-29 11:12:25,818 truncating revision to first 12 chars 2017-08-29 11:12:25,818 Setting DEBUG logging. 2017-08-29 11:12:25,818 attempt 1/10 2017-08-29 11:12:25,818 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e336d84fc1d2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-29 11:12:26,107 unpacking tar archive at: mozilla-central-e336d84fc1d2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.598190 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:12:26.193043) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 11:12:26.196373) ========= script_repo_revision: e336d84fc1d2d1fde7387dd5f86fe06fa59abe10 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 11:12:26.196761) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 11:12:26.197019) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 11:12:26.214625) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-08-29 11:12:26.214975) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:12:26 INFO - MultiFileLogger online at 20170829 11:12:26 in /builds/slave/test 11:12:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:12:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:12:26 INFO - {'append_to_log': False, 11:12:26 INFO - 'base_work_dir': '/builds/slave/test', 11:12:26 INFO - 'blob_upload_branch': 'Firefox', 11:12:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:12:26 INFO - 'branch': 'Firefox', 11:12:26 INFO - 'buildbot_json_path': 'buildprops.json', 11:12:26 INFO - 'code_coverage': False, 11:12:26 INFO - 'config_files': ('talos/linux_config.py',), 11:12:26 INFO - 'default_actions': ('clobber', 11:12:26 INFO - 'read-buildbot-config', 11:12:26 INFO - 'download-and-extract', 11:12:26 INFO - 'populate-webroot', 11:12:26 INFO - 'create-virtualenv', 11:12:26 INFO - 'install', 11:12:26 INFO - 'setup-mitmproxy', 11:12:26 INFO - 'run-tests'), 11:12:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:12:26 INFO - 'disable_ccov_upload': False, 11:12:26 INFO - 'download_minidump_stackwalk': True, 11:12:26 INFO - 'download_symbols': 'ondemand', 11:12:26 INFO - 'enable_stylo': False, 11:12:26 INFO - 'enable_webrender': False, 11:12:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:12:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:12:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:12:26 INFO - 'gecko_profile': False, 11:12:26 INFO - 'gecko_profile_interval': 0, 11:12:26 INFO - 'installer_path': 'installer.exe', 11:12:26 INFO - 'log_level': 'info', 11:12:26 INFO - 'log_name': 'talos', 11:12:26 INFO - 'log_to_console': True, 11:12:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:12:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:12:26 INFO - 'opt_config_files': (), 11:12:26 INFO - 'pip_index': False, 11:12:26 INFO - 'suite': 'dromaeojs-e10s', 11:12:26 INFO - 'system_bits': '32', 11:12:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:12:26 INFO - 'title': 'talos-linux64-ix-021', 11:12:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:12:26 INFO - 'use_talos_json': True, 11:12:26 INFO - 'verify': 'False', 11:12:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:12:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:12:26 INFO - 'work_dir': 'build'} 11:12:26 INFO - [mozharness: 2017-08-29 18:12:26.595513Z] Running clobber step. 11:12:26 INFO - Running pre-action listener: _resource_record_pre_action 11:12:26 INFO - Running main action method: clobber 11:12:26 INFO - rmtree: /builds/slave/test/build 11:12:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:12:27 INFO - Running post-action listener: _resource_record_post_action 11:12:27 INFO - [mozharness: 2017-08-29 18:12:27.618644Z] Finished clobber step (success) 11:12:27 INFO - [mozharness: 2017-08-29 18:12:27.618741Z] Running read-buildbot-config step. 11:12:27 INFO - Running pre-action listener: _resource_record_pre_action 11:12:27 INFO - Running main action method: read_buildbot_config 11:12:27 INFO - Using buildbot properties: 11:12:27 INFO - { 11:12:27 INFO - "project": "", 11:12:27 INFO - "product": "firefox", 11:12:27 INFO - "who": "ffxbld@noreply.mozilla.org", 11:12:27 INFO - "installer_path": "public/build/target.tar.bz2", 11:12:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 11:12:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:12:27 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 11:12:27 INFO - "stage_platform": "linux64-qr", 11:12:27 INFO - "basedir": "/builds/slave/test", 11:12:27 INFO - "buildnumber": 22, 11:12:27 INFO - "platform": "ubuntu64_hw_qr", 11:12:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:12:27 INFO - "slavebuilddir": "test", 11:12:27 INFO - "taskId": "HhuKuhbhSYKH-LwXO6WDZg", 11:12:27 INFO - "branch": "mozilla-central", 11:12:27 INFO - "script_repo_revision": "production", 11:12:27 INFO - "revision": "e336d84fc1d2d1fde7387dd5f86fe06fa59abe10", 11:12:27 INFO - "slavename": "talos-linux64-ix-021", 11:12:27 INFO - "repo_path": "mozilla-central" 11:12:27 INFO - } 11:12:27 INFO - Finding installer, test and symbols from parent task. 11:12:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HhuKuhbhSYKH-LwXO6WDZg'}, attempt #1 11:12:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HhuKuhbhSYKH-LwXO6WDZg'}, attempt #1 11:12:28 INFO - Task dependencies: J1Wwa5wdSOSum0rQh28Tpg 11:12:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg'}, attempt #1 11:12:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.tar.bz2 11:12:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.test_packages.json 11:12:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.crashreporter-symbols.zip 11:12:29 INFO - Running post-action listener: _resource_record_post_action 11:12:29 INFO - [mozharness: 2017-08-29 18:12:29.026334Z] Finished read-buildbot-config step (success) 11:12:29 INFO - [mozharness: 2017-08-29 18:12:29.026550Z] Running download-and-extract step. 11:12:29 INFO - Running pre-action listener: _resource_record_pre_action 11:12:29 INFO - Running main action method: download_and_extract 11:12:29 INFO - mkdir: /builds/slave/test/build/tests 11:12:29 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')]} 11:12:29 INFO - trying https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.test_packages.json 11:12:29 INFO - Downloading https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:12:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:12:29 INFO - Downloaded 989 bytes. 11:12:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:12:29 INFO - Using the following test package requirements: 11:12:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:12:29 INFO - u'common': [u'target.common.tests.zip'], 11:12:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:12:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:12:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:12:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:12:29 INFO - u'mozbase': [u'target.common.tests.zip'], 11:12:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:12:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:12:29 INFO - u'web-platform': [u'target.common.tests.zip', 11:12:29 INFO - u'target.web-platform.tests.tar.gz'], 11:12:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:12:29 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:12:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.common.tests.zip 11:12:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:12:29 INFO - Fetch https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.common.tests.zip into memory 11:12:31 INFO - Content-Length response header: 54977655 11:12:31 INFO - Bytes received: 54977655 11:12:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:12:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.common.tests.zip 11:12:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:12:42 INFO - Fetch https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.common.tests.zip into memory 11:12:43 INFO - Content-Length response header: 54977655 11:12:43 INFO - Bytes received: 54977655 11:12:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.talos.tests.zip 11:12:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:12:55 INFO - Fetch https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.talos.tests.zip into memory 11:12:56 INFO - Content-Length response header: 14496611 11:12:56 INFO - Bytes received: 14496611 11:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:56 INFO - trying https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.tar.bz2 11:12:56 INFO - Downloading https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:12:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:12:58 INFO - Downloaded 61283835 bytes. 11:12:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.tar.bz2 11:12:58 INFO - mkdir: /builds/slave/test/properties 11:12:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:12:58 INFO - Writing to file /builds/slave/test/properties/build_url 11:12:58 INFO - Contents: 11:12:58 INFO - build_url:https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.tar.bz2 11:12:58 INFO - Running post-action listener: _resource_record_post_action 11:12:58 INFO - Running post-action listener: find_tests_for_verification 11:12:58 INFO - Running post-action listener: set_extra_try_arguments 11:12:58 INFO - [mozharness: 2017-08-29 18:12:58.014457Z] Finished download-and-extract step (success) 11:12:58 INFO - [mozharness: 2017-08-29 18:12:58.014554Z] Running populate-webroot step. 11:12:58 INFO - Running pre-action listener: _resource_record_pre_action 11:12:58 INFO - Running main action method: populate_webroot 11:12:58 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:12:58 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 11:12:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:12:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:12:58 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 11:12:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:12:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:12:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:12:58 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:12:58 INFO - 'talos_options': ['--stylo'], 11:12:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:12:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:12:58 INFO - 'tests': ['damp', 'tps']}, 11:12:58 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:12:58 INFO - 'talos_options': ['--stylo'], 11:12:58 INFO - 'tests': ['damp', 'tps']}, 11:12:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:12:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:12:58 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 11:12:58 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:12:58 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:12:58 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:12:58 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:12:58 INFO - 'talos_options': ['--stylo'], 11:12:58 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:12:58 INFO - 'other-e10s': {'tests': ['a11yr', 11:12:58 INFO - 'ts_paint', 11:12:58 INFO - 'tpaint', 11:12:58 INFO - 'sessionrestore', 11:12:58 INFO - 'sessionrestore_many_windows', 11:12:58 INFO - 'sessionrestore_no_auto_restore', 11:12:58 INFO - 'tabpaint']}, 11:12:58 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 11:12:58 INFO - 'tests': ['a11yr', 11:12:58 INFO - 'ts_paint', 11:12:58 INFO - 'tpaint', 11:12:58 INFO - 'sessionrestore', 11:12:58 INFO - 'sessionrestore_many_windows', 11:12:58 INFO - 'sessionrestore_no_auto_restore', 11:12:58 INFO - 'tabpaint']}, 11:12:58 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:12:58 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:12:58 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 11:12:58 INFO - 'tests': ['bloom_basic_singleton']}, 11:12:58 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 11:12:58 INFO - 'tests': ['bloom_basic']}, 11:12:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:12:58 INFO - 'tsvgr_opacity', 11:12:58 INFO - 'tart', 11:12:58 INFO - 'tscrollx', 11:12:58 INFO - 'tsvg_static']}, 11:12:58 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 11:12:58 INFO - 'tests': ['tsvgx', 11:12:58 INFO - 'tsvgr_opacity', 11:12:58 INFO - 'tart', 11:12:58 INFO - 'tscrollx', 11:12:58 INFO - 'tsvg_static']}, 11:12:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:12:58 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:12:58 INFO - 'talos_options': ['--stylo'], 11:12:58 INFO - 'tests': ['tp5o']}, 11:12:58 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:12:58 INFO - 'talos_options': ['--mitmproxy', 11:12:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:12:58 INFO - '--firstNonBlankPaint'], 11:12:58 INFO - 'tests': ['tp6_google', 11:12:58 INFO - 'tp6_youtube', 11:12:58 INFO - 'tp6_amazon', 11:12:58 INFO - 'tp6_facebook']}, 11:12:58 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:12:58 INFO - 'talos_options': ['--stylo', 11:12:58 INFO - '--mitmproxy', 11:12:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:12:58 INFO - '--firstNonBlankPaint'], 11:12:58 INFO - 'tests': ['tp6_google', 11:12:58 INFO - 'tp6_youtube', 11:12:58 INFO - 'tp6_amazon', 11:12:58 INFO - 'tp6_facebook']}, 11:12:58 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:12:58 INFO - 'talos_options': ['--stylo', 11:12:58 INFO - '--stylo-threads=1', 11:12:58 INFO - '--mitmproxy', 11:12:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:12:58 INFO - '--firstNonBlankPaint'], 11:12:58 INFO - 'tests': ['tp6_google', 11:12:58 INFO - 'tp6_youtube', 11:12:58 INFO - 'tp6_amazon', 11:12:58 INFO - 'tp6_facebook']}, 11:12:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:12:58 INFO - 'talos_options': ['--xperf_path', 11:12:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:12:58 INFO - 'tests': ['tp5n']}, 11:12:58 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:12:58 INFO - 'talos_options': ['--stylo', 11:12:58 INFO - '--xperf_path', 11:12:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:12:58 INFO - 'tests': ['tp5n']}}} 11:12:58 INFO - Running post-action listener: _resource_record_post_action 11:12:58 INFO - [mozharness: 2017-08-29 18:12:58.028707Z] Finished populate-webroot step (success) 11:12:58 INFO - [mozharness: 2017-08-29 18:12:58.028795Z] Running create-virtualenv step. 11:12:58 INFO - Running pre-action listener: _resource_record_pre_action 11:12:58 INFO - Running main action method: create_virtualenv 11:12:58 INFO - Creating virtualenv /builds/slave/test/build/venv 11:12:58 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:12:58 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:12:58 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:12:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:12:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:12:58 INFO - Installing distribute.............................................................................................................................................................................................done. 11:12:58 INFO - Installing pip...............done. 11:12:58 INFO - Return code: 0 11:12:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:12:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:12:59 INFO - Reading from file tmpfile_stdout 11:12:59 INFO - Output received: 11:12:59 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:12:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:12:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:59 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 0x22a41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2612f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2610130>, '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 0x2611030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26114c0>, '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': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', '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 11:12:59 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 11:12:59 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 11:12:59 INFO - Using env: {'DISPLAY': ':0', 11:12:59 INFO - 'HOME': '/home/cltbld', 11:12:59 INFO - 'LANG': 'en_US.UTF-8', 11:12:59 INFO - 'LANGUAGE': 'en_US:en', 11:12:59 INFO - 'LOGNAME': 'cltbld', 11:12:59 INFO - 'MAIL': '/var/mail/cltbld', 11:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:59 INFO - 'MOZ_NO_REMOTE': '1', 11:12:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:59 INFO - 'NO_EM_RESTART': '1', 11:12:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:59 INFO - 'PWD': '/builds/slave/test', 11:12:59 INFO - 'SHELL': '/bin/bash', 11:12:59 INFO - 'SHLVL': '1', 11:12:59 INFO - 'TERM': 'linux', 11:12:59 INFO - 'TMOUT': '86400', 11:12:59 INFO - 'USER': 'cltbld', 11:12:59 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', 11:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:59 INFO - '_': '/tools/buildbot/bin/python'} 11:13:00 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:13:00 INFO - Downloading/unpacking pip>=1.5 11:13:00 INFO - Running setup.py egg_info for package pip 11:13:00 INFO - warning: no files found matching 'pip/cacert.pem' 11:13:00 INFO - warning: no files found matching '*.html' under directory 'docs' 11:13:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:13:00 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:13:00 INFO - Installing collected packages: pip 11:13:00 INFO - Found existing installation: pip 1.1 11:13:00 INFO - Uninstalling pip: 11:13:00 INFO - Successfully uninstalled pip 11:13:00 INFO - Running setup.py install for pip 11:13:00 INFO - warning: no files found matching 'pip/cacert.pem' 11:13:00 INFO - warning: no files found matching '*.html' under directory 'docs' 11:13:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:13:00 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:13:00 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:13:00 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:13:00 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:13:00 INFO - Successfully installed pip 11:13:00 INFO - Cleaning up... 11:13:00 INFO - Return code: 0 11:13:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:13: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')]} 11:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:13: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')]} 11:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:13: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', '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 0x22a41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2612f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2610130>, '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 0x2611030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26114c0>, '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': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', '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 11:13: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:13: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 psutil>=3.1.1 11:13:00 INFO - Using env: {'DISPLAY': ':0', 11:13:00 INFO - 'HOME': '/home/cltbld', 11:13:00 INFO - 'LANG': 'en_US.UTF-8', 11:13:00 INFO - 'LANGUAGE': 'en_US:en', 11:13:00 INFO - 'LOGNAME': 'cltbld', 11:13:00 INFO - 'MAIL': '/var/mail/cltbld', 11:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:00 INFO - 'MOZ_NO_REMOTE': '1', 11:13:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:13:00 INFO - 'NO_EM_RESTART': '1', 11:13:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:00 INFO - 'PWD': '/builds/slave/test', 11:13:00 INFO - 'SHELL': '/bin/bash', 11:13:00 INFO - 'SHLVL': '1', 11:13:00 INFO - 'TERM': 'linux', 11:13:00 INFO - 'TMOUT': '86400', 11:13:00 INFO - 'USER': 'cltbld', 11:13:00 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', 11:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:00 INFO - '_': '/tools/buildbot/bin/python'} 11:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:13:00 INFO - Downloading/unpacking psutil>=3.1.1 11:13: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 11:13: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 11:13: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 11:13: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 11:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:13:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:13:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:13:01 INFO - Installing collected packages: psutil 11:13:01 INFO - Running setup.py install for psutil 11:13:01 INFO - building 'psutil._psutil_linux' extension 11:13:01 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 11:13:01 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 11:13:01 INFO - building 'psutil._psutil_posix' extension 11:13:01 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 11:13:02 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 11:13:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:13:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:13:02 INFO - Successfully installed psutil 11:13:02 INFO - Cleaning up... 11:13:02 INFO - Return code: 0 11:13:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:13:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:13:02 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 0x22a41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2612f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2610130>, '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 0x2611030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26114c0>, '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': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', '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 11:13:02 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 11:13:02 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 11:13:02 INFO - Using env: {'DISPLAY': ':0', 11:13:02 INFO - 'HOME': '/home/cltbld', 11:13:02 INFO - 'LANG': 'en_US.UTF-8', 11:13:02 INFO - 'LANGUAGE': 'en_US:en', 11:13:02 INFO - 'LOGNAME': 'cltbld', 11:13:02 INFO - 'MAIL': '/var/mail/cltbld', 11:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:02 INFO - 'MOZ_NO_REMOTE': '1', 11:13:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:13:02 INFO - 'NO_EM_RESTART': '1', 11:13:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:02 INFO - 'PWD': '/builds/slave/test', 11:13:02 INFO - 'SHELL': '/bin/bash', 11:13:02 INFO - 'SHLVL': '1', 11:13:02 INFO - 'TERM': 'linux', 11:13:02 INFO - 'TMOUT': '86400', 11:13:02 INFO - 'USER': 'cltbld', 11:13:02 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', 11:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:02 INFO - '_': '/tools/buildbot/bin/python'} 11:13:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:13:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:13:02 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 11:13:02 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 11:13:02 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 11:13:02 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 11:13:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:13:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:13:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:13:03 INFO - Installing collected packages: mozsystemmonitor 11:13:03 INFO - Running setup.py install for mozsystemmonitor 11:13:03 INFO - Successfully installed mozsystemmonitor 11:13:03 INFO - Cleaning up... 11:13:03 INFO - Return code: 0 11:13:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:13: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')]} 11:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:13: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')]} 11:13:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:13: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 0x22a41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2612f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2610130>, '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 0x2611030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26114c0>, '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': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', '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 11:13: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 11:13: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 11:13:03 INFO - Using env: {'DISPLAY': ':0', 11:13:03 INFO - 'HOME': '/home/cltbld', 11:13:03 INFO - 'LANG': 'en_US.UTF-8', 11:13:03 INFO - 'LANGUAGE': 'en_US:en', 11:13:03 INFO - 'LOGNAME': 'cltbld', 11:13:03 INFO - 'MAIL': '/var/mail/cltbld', 11:13:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:03 INFO - 'MOZ_NO_REMOTE': '1', 11:13:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:13:03 INFO - 'NO_EM_RESTART': '1', 11:13:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:13:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:03 INFO - 'PWD': '/builds/slave/test', 11:13:03 INFO - 'SHELL': '/bin/bash', 11:13:03 INFO - 'SHLVL': '1', 11:13:03 INFO - 'TERM': 'linux', 11:13:03 INFO - 'TMOUT': '86400', 11:13:03 INFO - 'USER': 'cltbld', 11:13:03 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', 11:13:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:03 INFO - '_': '/tools/buildbot/bin/python'} 11:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:13:03 INFO - Downloading/unpacking jsonschema==2.5.1 11:13: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 11:13: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 11:13: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 11:13: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 11:13:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:13:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:13:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:13:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:13:05 INFO - Downloading functools32-3.2.3-2.tar.gz 11:13:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:13:05 INFO - warning: no files found matching '*.txt' 11:13:05 INFO - no previously-included directories found matching 'build' 11:13:05 INFO - no previously-included directories found matching 'dist' 11:13:05 INFO - no previously-included directories found matching '.git*' 11:13:05 INFO - Installing collected packages: jsonschema, functools32 11:13:05 INFO - Running setup.py install for functools32 11:13:05 INFO - warning: no files found matching '*.txt' 11:13:05 INFO - no previously-included directories found matching 'build' 11:13:05 INFO - no previously-included directories found matching 'dist' 11:13:05 INFO - no previously-included directories found matching '.git*' 11:13:05 INFO - Successfully installed jsonschema functools32 11:13:05 INFO - Cleaning up... 11:13:05 INFO - Return code: 0 11:13:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:13:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22a41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2612f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2610130>, '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 0x2611030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26114c0>, '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': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', '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 11:13:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:13:05 INFO - Using env: {'DISPLAY': ':0', 11:13:05 INFO - 'HOME': '/home/cltbld', 11:13:05 INFO - 'LANG': 'en_US.UTF-8', 11:13:05 INFO - 'LANGUAGE': 'en_US:en', 11:13:05 INFO - 'LOGNAME': 'cltbld', 11:13:05 INFO - 'MAIL': '/var/mail/cltbld', 11:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:05 INFO - 'MOZ_NO_REMOTE': '1', 11:13:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:13:05 INFO - 'NO_EM_RESTART': '1', 11:13:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:05 INFO - 'PWD': '/builds/slave/test', 11:13:05 INFO - 'SHELL': '/bin/bash', 11:13:05 INFO - 'SHLVL': '1', 11:13:05 INFO - 'TERM': 'linux', 11:13:05 INFO - 'TMOUT': '86400', 11:13:05 INFO - 'USER': 'cltbld', 11:13:05 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', 11:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:05 INFO - '_': '/tools/buildbot/bin/python'} 11:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:13:05 INFO - Cleaning up... 11:13:05 INFO - Return code: 0 11:13:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:13:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22a41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2612f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2610130>, '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 0x2611030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26114c0>, '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': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', '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 11:13:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:13:05 INFO - Using env: {'DISPLAY': ':0', 11:13:05 INFO - 'HOME': '/home/cltbld', 11:13:05 INFO - 'LANG': 'en_US.UTF-8', 11:13:05 INFO - 'LANGUAGE': 'en_US:en', 11:13:05 INFO - 'LOGNAME': 'cltbld', 11:13:05 INFO - 'MAIL': '/var/mail/cltbld', 11:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:05 INFO - 'MOZ_NO_REMOTE': '1', 11:13:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:13:05 INFO - 'NO_EM_RESTART': '1', 11:13:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:05 INFO - 'PWD': '/builds/slave/test', 11:13:05 INFO - 'SHELL': '/bin/bash', 11:13:05 INFO - 'SHLVL': '1', 11:13:05 INFO - 'TERM': 'linux', 11:13:05 INFO - 'TMOUT': '86400', 11:13:05 INFO - 'USER': 'cltbld', 11:13:05 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', 11:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:05 INFO - '_': '/tools/buildbot/bin/python'} 11:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:13:05 INFO - Downloading/unpacking blobuploader==1.2.4 11:13: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 11:13: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 11:13: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 11:13: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 11:13:06 INFO - Downloading blobuploader-1.2.4.tar.gz 11:13:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:13:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:13: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 11:13: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 11:13: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 11:13: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 11:13:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:13:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:13: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 11:13: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 11:13: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 11:13: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 11:13:07 INFO - Downloading docopt-0.6.1.tar.gz 11:13:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:13:07 INFO - Installing collected packages: blobuploader, requests, docopt 11:13:07 INFO - Running setup.py install for blobuploader 11:13:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:13:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:13:07 INFO - Running setup.py install for requests 11:13:07 INFO - Running setup.py install for docopt 11:13:07 INFO - Successfully installed blobuploader requests docopt 11:13:07 INFO - Cleaning up... 11:13:08 INFO - Return code: 0 11:13:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:13: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')]} 11:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:13: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')]} 11:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:13: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 0x22a41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2612f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2610130>, '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 0x2611030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26114c0>, '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': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', '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 11:13: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 11:13: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 11:13:08 INFO - Using env: {'DISPLAY': ':0', 11:13:08 INFO - 'HOME': '/home/cltbld', 11:13:08 INFO - 'LANG': 'en_US.UTF-8', 11:13:08 INFO - 'LANGUAGE': 'en_US:en', 11:13:08 INFO - 'LOGNAME': 'cltbld', 11:13:08 INFO - 'MAIL': '/var/mail/cltbld', 11:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:08 INFO - 'MOZ_NO_REMOTE': '1', 11:13:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:13:08 INFO - 'NO_EM_RESTART': '1', 11:13:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:08 INFO - 'PWD': '/builds/slave/test', 11:13:08 INFO - 'SHELL': '/bin/bash', 11:13:08 INFO - 'SHLVL': '1', 11:13:08 INFO - 'TERM': 'linux', 11:13:08 INFO - 'TMOUT': '86400', 11:13:08 INFO - 'USER': 'cltbld', 11:13:08 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', 11:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:08 INFO - '_': '/tools/buildbot/bin/python'} 11:13:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:13:08 INFO - Running setup.py (path:/tmp/pip-CVZB5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:13:08 INFO - Running setup.py (path:/tmp/pip-jJJkwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:13:08 INFO - Running setup.py (path:/tmp/pip-N1ixXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:13:08 INFO - Running setup.py (path:/tmp/pip-uUpQip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:13:08 INFO - Running setup.py (path:/tmp/pip-Khn5F3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:13:08 INFO - Running setup.py (path:/tmp/pip-69tZ8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:13:08 INFO - Running setup.py (path:/tmp/pip-C7FAit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:13:08 INFO - Running setup.py (path:/tmp/pip-ricOHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:13:08 INFO - Running setup.py (path:/tmp/pip-RPiS4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:13:08 INFO - Running setup.py (path:/tmp/pip-yuL42h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:13:08 INFO - Running setup.py (path:/tmp/pip-9qVOUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:13:09 INFO - Running setup.py (path:/tmp/pip-Wcuyw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:13:09 INFO - Running setup.py (path:/tmp/pip-e1BCiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:13:09 INFO - Running setup.py (path:/tmp/pip-OTrDCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:13:09 INFO - Running setup.py (path:/tmp/pip-UFqbkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:13:09 INFO - Running setup.py (path:/tmp/pip-GDBTR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:13:09 INFO - Running setup.py (path:/tmp/pip-J3Ftfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:13:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:13:09 INFO - Running setup.py install for manifestparser 11:13:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:13:09 INFO - Running setup.py install for mozcrash 11:13:09 INFO - Running setup.py install for mozdebug 11:13:09 INFO - Running setup.py install for mozdevice 11:13:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:13:09 INFO - Running setup.py install for mozfile 11:13:09 INFO - Running setup.py install for mozhttpd 11:13:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:13:10 INFO - Running setup.py install for mozinfo 11:13:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:13:10 INFO - Running setup.py install for mozInstall 11:13:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:13:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:13:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:13:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:13:10 INFO - Running setup.py install for mozleak 11:13:10 INFO - Running setup.py install for mozlog 11:13:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:13:10 INFO - Running setup.py install for moznetwork 11:13:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:13:10 INFO - Running setup.py install for mozprocess 11:13:10 INFO - Running setup.py install for mozprofile 11:13:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:13:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:13:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:13:10 INFO - Running setup.py install for mozrunner 11:13:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:13:10 INFO - Running setup.py install for mozscreenshot 11:13:11 INFO - Running setup.py install for moztest 11:13:11 INFO - Running setup.py install for mozversion 11:13:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:13:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:13:11 INFO - Cleaning up... 11:13:11 INFO - Return code: 0 11:13:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:13: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')]} 11:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:13: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')]} 11:13:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:13: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 0x22a41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2612f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2610130>, '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 0x2611030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26114c0>, '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': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', '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 11:13: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 11:13: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 11:13:11 INFO - Using env: {'DISPLAY': ':0', 11:13:11 INFO - 'HOME': '/home/cltbld', 11:13:11 INFO - 'LANG': 'en_US.UTF-8', 11:13:11 INFO - 'LANGUAGE': 'en_US:en', 11:13:11 INFO - 'LOGNAME': 'cltbld', 11:13:11 INFO - 'MAIL': '/var/mail/cltbld', 11:13:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:11 INFO - 'MOZ_NO_REMOTE': '1', 11:13:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:13:11 INFO - 'NO_EM_RESTART': '1', 11:13:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:13:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:11 INFO - 'PWD': '/builds/slave/test', 11:13:11 INFO - 'SHELL': '/bin/bash', 11:13:11 INFO - 'SHLVL': '1', 11:13:11 INFO - 'TERM': 'linux', 11:13:11 INFO - 'TMOUT': '86400', 11:13:11 INFO - 'USER': 'cltbld', 11:13:11 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', 11:13:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:11 INFO - '_': '/tools/buildbot/bin/python'} 11:13:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:13:11 INFO - Running setup.py (path:/tmp/pip-mDFVMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:13:11 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)) 11:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:13:11 INFO - Running setup.py (path:/tmp/pip-BbReuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:13:11 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)) 11:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:13:11 INFO - Running setup.py (path:/tmp/pip-CrzRt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:13:11 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)) 11:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:13:11 INFO - Running setup.py (path:/tmp/pip-B7NQev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:13:11 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)) 11:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:13:11 INFO - Running setup.py (path:/tmp/pip-K2TDNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:13:11 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)) 11:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:13:11 INFO - Running setup.py (path:/tmp/pip-V2hYyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:13:11 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)) 11:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:13:11 INFO - Running setup.py (path:/tmp/pip-4mbr0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:13:11 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)) 11:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:13:11 INFO - Running setup.py (path:/tmp/pip-GMwz87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:13: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)) 11:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:13:12 INFO - Running setup.py (path:/tmp/pip-74pIm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:13: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)) 11:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:13:12 INFO - Running setup.py (path:/tmp/pip-8D4O9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:13: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)) 11:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:13:12 INFO - Running setup.py (path:/tmp/pip-65ybQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:13: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)) 11:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:13:12 INFO - Running setup.py (path:/tmp/pip-p5Z0XC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:13:12 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)) 11:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:13:12 INFO - Running setup.py (path:/tmp/pip-L9WOoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:13:12 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)) 11:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:13:12 INFO - Running setup.py (path:/tmp/pip-4S5WqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:13:12 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)) 11:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:13:12 INFO - Running setup.py (path:/tmp/pip-6b7uGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:13:12 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)) 11:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:13:12 INFO - Running setup.py (path:/tmp/pip-GQWOCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:13:12 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)) 11:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:13:12 INFO - Running setup.py (path:/tmp/pip-PZa8lW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:13:12 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)) 11:13:12 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)) 11:13:12 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)) 11:13:12 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)) 11:13:12 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)) 11:13:12 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)) 11:13:12 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)) 11:13:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:13:12 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 11:13:12 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 11:13:12 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 11:13:12 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 11:13:13 INFO - Downloading blessings-1.6.tar.gz 11:13:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:13:13 INFO - Installing collected packages: blessings 11:13:13 INFO - Running setup.py install for blessings 11:13:13 INFO - Successfully installed blessings 11:13:13 INFO - Cleaning up... 11:13:13 INFO - Return code: 0 11:13:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:13:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:13:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:13:14 INFO - Reading from file tmpfile_stdout 11:13:14 INFO - Current package versions: 11:13:14 INFO - argparse == 1.2.1 11:13:14 INFO - blessings == 1.6 11:13:14 INFO - blobuploader == 1.2.4 11:13:14 INFO - docopt == 0.6.1 11:13:14 INFO - functools32 == 3.2.3-2 11:13:14 INFO - jsonschema == 2.5.1 11:13:14 INFO - manifestparser == 1.1 11:13:14 INFO - mozInstall == 1.13 11:13:14 INFO - mozcrash == 1.0 11:13:14 INFO - mozdebug == 0.1 11:13:14 INFO - mozdevice == 0.51 11:13:14 INFO - mozfile == 1.2 11:13:14 INFO - mozhttpd == 0.7 11:13:14 INFO - mozinfo == 0.10 11:13:14 INFO - mozleak == 0.1 11:13:14 INFO - mozlog == 3.5 11:13:14 INFO - moznetwork == 0.27 11:13:14 INFO - mozprocess == 0.25 11:13:14 INFO - mozprofile == 0.28 11:13:14 INFO - mozrunner == 6.13 11:13:14 INFO - mozscreenshot == 0.1 11:13:14 INFO - mozsystemmonitor == 0.3 11:13:14 INFO - moztest == 0.8 11:13:14 INFO - mozversion == 1.4 11:13:14 INFO - psutil == 3.1.1 11:13:14 INFO - requests == 1.2.3 11:13:14 INFO - wsgiref == 0.1.2 11:13:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:13: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')]} 11:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:13: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')]} 11:13:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:13: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 0x22a41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2612f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2610130>, '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 0x2611030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26114c0>, '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': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', '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 11:13: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 11:13: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 11:13:14 INFO - Using env: {'DISPLAY': ':0', 11:13:14 INFO - 'HOME': '/home/cltbld', 11:13:14 INFO - 'LANG': 'en_US.UTF-8', 11:13:14 INFO - 'LANGUAGE': 'en_US:en', 11:13:14 INFO - 'LOGNAME': 'cltbld', 11:13:14 INFO - 'MAIL': '/var/mail/cltbld', 11:13:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:14 INFO - 'MOZ_NO_REMOTE': '1', 11:13:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:13:14 INFO - 'NO_EM_RESTART': '1', 11:13:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:13:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:14 INFO - 'PWD': '/builds/slave/test', 11:13:14 INFO - 'SHELL': '/bin/bash', 11:13:14 INFO - 'SHLVL': '1', 11:13:14 INFO - 'TERM': 'linux', 11:13:14 INFO - 'TMOUT': '86400', 11:13:14 INFO - 'USER': 'cltbld', 11:13:14 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', 11:13:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:14 INFO - '_': '/tools/buildbot/bin/python'} 11:13:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:13:14 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)) 11:13:14 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)) 11:13:14 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)) 11:13:14 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)) 11:13:14 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)) 11:13:14 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)) 11:13:14 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)) 11:13:14 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)) 11:13:14 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)) 11:13:14 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:13:14 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 11:13:14 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 11:13:14 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 11:13:14 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 11:13:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:13:15 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)) 11:13:15 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)) 11:13:15 INFO - Installing collected packages: simplejson 11:13:15 INFO - Running setup.py install for simplejson 11:13:15 INFO - building 'simplejson._speedups' extension 11:13:15 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 11:13:16 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 11:13:16 INFO - Successfully installed simplejson 11:13:16 INFO - Cleaning up... 11:13:16 INFO - Return code: 0 11:13:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:13:16 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')]} 11:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:13:16 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')]} 11:13:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:13:16 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 0x22a41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2612f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2610130>, '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 0x2611030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26114c0>, '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': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', '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 11:13:16 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 11:13:16 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 11:13:16 INFO - Using env: {'DISPLAY': ':0', 11:13:16 INFO - 'HOME': '/home/cltbld', 11:13:16 INFO - 'LANG': 'en_US.UTF-8', 11:13:16 INFO - 'LANGUAGE': 'en_US:en', 11:13:16 INFO - 'LOGNAME': 'cltbld', 11:13:16 INFO - 'MAIL': '/var/mail/cltbld', 11:13:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:16 INFO - 'MOZ_NO_REMOTE': '1', 11:13:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:13:16 INFO - 'NO_EM_RESTART': '1', 11:13:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:13:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:16 INFO - 'PWD': '/builds/slave/test', 11:13:16 INFO - 'SHELL': '/bin/bash', 11:13:16 INFO - 'SHLVL': '1', 11:13:16 INFO - 'TERM': 'linux', 11:13:16 INFO - 'TMOUT': '86400', 11:13:16 INFO - 'USER': 'cltbld', 11:13:16 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', 11:13:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:16 INFO - '_': '/tools/buildbot/bin/python'} 11:13:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:13:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:13:16 INFO - Cleaning up... 11:13:16 INFO - Return code: 0 11:13:16 INFO - Running post-action listener: _resource_record_post_action 11:13:16 INFO - Running post-action listener: _start_resource_monitoring 11:13:16 INFO - Starting resource monitoring. 11:13:16 INFO - [mozharness: 2017-08-29 18:13:16.888573Z] Finished create-virtualenv step (success) 11:13:16 INFO - [mozharness: 2017-08-29 18:13:16.889095Z] Running install step. 11:13:16 INFO - Running pre-action listener: _resource_record_pre_action 11:13:16 INFO - Running main action method: install 11:13:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:13:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:13:17 INFO - Reading from file tmpfile_stdout 11:13:17 INFO - Detecting whether we're running mozinstall >=1.0... 11:13:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:13:17 INFO - Reading from file tmpfile_stdout 11:13:17 INFO - Output received: 11:13:17 INFO - Usage: mozinstall [options] installer 11:13:17 INFO - Options: 11:13:17 INFO - -h, --help show this help message and exit 11:13:17 INFO - -d DEST, --destination=DEST 11:13:17 INFO - Directory to install application into. [default: 11:13:17 INFO - "/builds/slave/test"] 11:13:17 INFO - --app=APP Application being installed. [default: firefox] 11:13:17 INFO - mkdir: /builds/slave/test/build/application 11:13:17 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'] 11:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:13:35 INFO - Reading from file tmpfile_stdout 11:13:35 INFO - Output received: 11:13:35 INFO - /builds/slave/test/build/application/firefox/firefox 11:13:35 INFO - Running post-action listener: _resource_record_post_action 11:13:35 INFO - [mozharness: 2017-08-29 18:13:35.205077Z] Finished install step (success) 11:13:35 INFO - [mozharness: 2017-08-29 18:13:35.205224Z] Running setup-mitmproxy step. 11:13:35 INFO - Running pre-action listener: _resource_record_pre_action 11:13:35 INFO - Running main action method: setup_mitmproxy 11:13:35 INFO - Skipping: mitmproxy is not required 11:13:35 INFO - Running post-action listener: _resource_record_post_action 11:13:35 INFO - [mozharness: 2017-08-29 18:13:35.205741Z] Finished setup-mitmproxy step (success) 11:13:35 INFO - [mozharness: 2017-08-29 18:13:35.205840Z] Running run-tests step. 11:13:35 INFO - Running pre-action listener: _resource_record_pre_action 11:13:35 INFO - Running pre-action listener: _set_gcov_prefix 11:13:35 INFO - Running main action method: run_tests 11:13:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:13:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:13:35 INFO - Python 2.7.3 11:13:35 INFO - Return code: 0 11:13:35 INFO - grabbing minidump binary from tooltool 11:13: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')]} 11:13: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 0x2610130>, '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 0x2611030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26114c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:13: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 11:13: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 11:13: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 11:13:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:13:35 INFO - Return code: 0 11:13:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:13:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:13:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:13:35 INFO - ENV: RUST_BACKTRACE is now full 11:13:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:13:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:13:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 11:13:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 11:13:35 INFO - Using env: {'DISPLAY': ':0', 11:13:35 INFO - 'HOME': '/home/cltbld', 11:13:35 INFO - 'LANG': 'en_US.UTF-8', 11:13:35 INFO - 'LANGUAGE': 'en_US:en', 11:13:35 INFO - 'LOGNAME': 'cltbld', 11:13:35 INFO - 'MAIL': '/var/mail/cltbld', 11:13:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:13:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:13:35 INFO - 'MOZ_ACCELERATED': '1', 11:13:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:35 INFO - 'MOZ_NO_REMOTE': '1', 11:13:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:13:35 INFO - 'MOZ_WEBRENDER': '1', 11:13:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:13:35 INFO - 'NO_EM_RESTART': '1', 11:13:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:13:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:35 INFO - 'PWD': '/builds/slave/test', 11:13:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:13:35 INFO - 'RUST_BACKTRACE': 'full', 11:13:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:13:35 INFO - 'SHELL': '/bin/bash', 11:13:35 INFO - 'SHLVL': '1', 11:13:35 INFO - 'TERM': 'linux', 11:13:35 INFO - 'TMOUT': '86400', 11:13:35 INFO - 'USER': 'cltbld', 11:13:35 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', 11:13:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:35 INFO - '_': '/tools/buildbot/bin/python'} 11:13:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 11:13:35 INFO - mozversion application_buildid: 20170829172659 11:13:35 INFO - mozversion application_changeset: e336d84fc1d2d1fde7387dd5f86fe06fa59abe10 11:13:35 INFO - mozversion application_display_name: Nightly 11:13:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:13:35 INFO - mozversion application_name: Firefox 11:13:35 INFO - mozversion application_remotingname: firefox 11:13:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:13:35 INFO - mozversion application_vendor: Mozilla 11:13:35 INFO - mozversion application_version: 57.0a1 11:13:35 INFO - mozversion platform_buildid: 20170829172659 11:13:35 INFO - mozversion platform_changeset: e336d84fc1d2d1fde7387dd5f86fe06fa59abe10 11:13:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:13:35 INFO - mozversion platform_version: 57.0a1 11:13:35 INFO - using testdate: 1504030415 11:13:35 INFO - actual date: 1504030415 11:13:35 INFO - starting webserver on 'localhost:43079' 11:13:35 INFO - SUITE-START | Running 2 tests 11:13:35 INFO - TEST-START | dromaeo_css 11:13:35 INFO - Initialising browser for dromaeo_css test... 11:13:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43079/getInfo.html -profile /tmp/tmpsBDZzI/profile 11:13:35 INFO - TEST-INFO | started process 21923 (/builds/slave/test/build/application/firefox/firefox http://localhost:43079/getInfo.html) 11:13:44 INFO - TEST-INFO | 21923: exit 0 11:13:44 INFO - Browser initialized. 11:13:44 INFO - Running cycle 1/1 for dromaeo_css test... 11:13:44 INFO - Using env: {'DISPLAY': ':0', 11:13:44 INFO - 'HOME': '/home/cltbld', 11:13:44 INFO - 'JSGC_DISABLE_POISONING': '1', 11:13:44 INFO - 'LANG': 'en_US.UTF-8', 11:13:44 INFO - 'LANGUAGE': 'en_US:en', 11:13:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:13:44 INFO - 'LOGNAME': 'cltbld', 11:13:44 INFO - 'MAIL': '/var/mail/cltbld', 11:13:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:13:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:13:44 INFO - 'MOZ_ACCELERATED': '1', 11:13:44 INFO - 'MOZ_CRASHREPORTER': '1', 11:13:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:13:44 INFO - 'MOZ_NO_REMOTE': '1', 11:13:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:13:44 INFO - 'MOZ_WEBRENDER': '1', 11:13:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:13:44 INFO - 'NO_EM_RESTART': '1', 11:13:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:13:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:44 INFO - 'PWD': '/builds/slave/test', 11:13:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:13:44 INFO - 'RUST_BACKTRACE': 'full', 11:13:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:13:44 INFO - 'SHELL': '/bin/bash', 11:13:44 INFO - 'SHLVL': '1', 11:13:44 INFO - 'TERM': 'linux', 11:13:44 INFO - 'TMOUT': '86400', 11:13:44 INFO - 'USER': 'cltbld', 11:13:44 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', 11:13:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:44 INFO - '_': '/tools/buildbot/bin/python'} 11:13:44 INFO - TEST-INFO | started process 22107 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsBDZzI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:13:45 INFO - PID 22107 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:13:45 INFO - PID 22107 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:13:45 INFO - PID 22107 | 11:13:45 INFO - PID 22107 | (/builds/slave/test/build/application/firefox/firefox:22166): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:13:45 INFO - PID 22107 | 11:13:56 INFO - PID 22107 | RSS: Main: 201760768 11:13:56 INFO - PID 22107 | 11:13:59 INFO - PID 22107 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:16:55 INFO - PID 22107 | Cycle 1(1): loaded http://localhost:43079/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43079/tests/dromaeo/cssquery-ext.html) 11:16:56 INFO - PID 22107 | RSS: Main: 214671360 11:16:56 INFO - PID 22107 | 11:19:59 INFO - PID 22107 | Cycle 1(1): loaded http://localhost:43079/tests/dromaeo/cssquery-ext.html (next: http://localhost:43079/tests/dromaeo/cssquery-jquery.html) 11:19:59 INFO - PID 22107 | RSS: Main: 215265280 11:19:59 INFO - PID 22107 | 11:23:01 INFO - PID 22107 | Cycle 1(1): loaded http://localhost:43079/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43079/tests/dromaeo/cssquery-mootools.html) 11:23:02 INFO - PID 22107 | RSS: Main: 214863872 11:23:02 INFO - PID 22107 | 11:26:04 INFO - PID 22107 | Cycle 1(1): loaded http://localhost:43079/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43079/tests/dromaeo/cssquery-prototype.html) 11:26:05 INFO - PID 22107 | RSS: Main: 213180416 11:26:05 INFO - PID 22107 | 11:29:09 INFO - PID 22107 | Cycle 1(1): loaded http://localhost:43079/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43079/tests/dromaeo/cssquery-yui.html) 11:29:09 INFO - PID 22107 | RSS: Main: 215314432 11:29:09 INFO - PID 22107 | 11:32:11 INFO - PID 22107 | Cycle 1(1): loaded http://localhost:43079/tests/dromaeo/cssquery-yui.html (next: http://localhost:43079/tests/dromaeo/cssquery-dojo.html) 11:32:11 INFO - PID 22107 | RSS: Main: 219987968 11:32:11 INFO - PID 22107 | 11:32:11 INFO - PID 22107 | __start_tp_report 11:32:11 INFO - PID 22107 | _x_x_mozilla_page_load 11:32:11 INFO - PID 22107 | _x_x_mozilla_page_load_details 11:32:11 INFO - PID 22107 | |i|pagename|runs| 11:32:11 INFO - PID 22107 | |0;dojo.html;3271.6401195868466;3279.376918385505;3293.505974103884;3338.9332213355733;3351.8659253629858;6207.658578778165;6249.406306400891;6253.9687301563545;6268.373162683735;6363.045543168527;6250.312465628788;6327.7785277515295;6352.555321127523;6371.808845734636;6375.9479685851775;5688.658680479184;5769.5672824538115;5823.184754134425;5829.387914269004;5847.912281315784;5574.358948720888;5607.57943154263;5617.4382561743705;5696.11710184922;5715.057015592422;5673.517750991171;5701.828945131652;5708.686022268774;5723.313202415716;5769;5729.111987641881;5785.074388097926;5786.363500014995;5787.884242315172;5790.073588225863;5713.657180569137;5715.971420142872;5717.514011309044;5736.942630573724;5755.366909639936;5992.730327135289;6093.086037094427;6108.267007959029;6135.785247516315;6270.404311590437;6589.308122647101;6599.670016499156;6601.801945941629;6614.636195009272;6628.005799130169;6623.370779775962;6667.599944003376;6672.799816005528;6704.161979752531;6705.195376554844;6173.104899789595;6205.410486003822;6220.1602783624085;6223.3776622337855;6229.0967809667345;6829.214640316328;6841.452683785285;6862.794116176523;6863.794086177422;6866.690998905061;5792.189093526909;6527.086207930894;6550.672466376757;6615.636140012345;6648.401643852077;6695.765648202387;6701.530892837454;6769.492288078315;6774;6809.421199198025;6481;6592.769253076215;6611.471082313404;6649.767258145942;6724.49566282521;6643.069970204176;6754.0881980931945;6762.8647427052165;6765.188177418643;6792.184937807396;4877.487863774323;4919.458859525449;4985.376827896513;4987.975060124749;5008.699478031257;4283.057727299989;4343.1747967886;4381.036172042146;4402.449693788277;4414.735115893057;5714.857128571736;5788.044972579477;5814.563907706939;5828.912566311508;5829.795957141479;6657.300983396771;6669.933300667029;6680.131582894192;6736.7642132525125;6738.787018336747;5794.391588883191;5847.970760146172;5865.530757539386;5866.032104702762;5898.380670029585;5686.488216060575;5736.42635736423;5763.740631671585;5808.273965754281;5833.562482813806;6538.574992625464;6557.868842623217;6627.635480048547;6628.370304821035;6649.46804255668;6496.74013039492;6596.340365963365;6600.570962887397;6611.471082313499;6615.338466153346;6458.54790164684;6463.127477790438;6468.611883287018;6486.772962946275;6497.122888410004;6532.640704761284;6543.5419520633095;6545.083688283739;6545.705443254969;6551.312112228147;6008.973131017776;6628.270890201978;6645.102911106938;6679.4656427484715;6689.69896354675;6467.385598368148;6473.061406096184;6488.124103246001;6572.572782769104;6572.967135164485;6448.645324507197;6462.4183823456115;6510.44661203803;6556.704948277244;6578.835529111619;6252.718627661857;6434.64609446485;6435.871282574229;6439.227292724903;6448.097266382616;5578.581606379619;6367.3314301997625;6424.518161138027;6460.289368169591;6545.574537655038;6290.339514351111;6308.053791931247;6332.398422150072;6393.520485963479;6445.355464453517 11:32:11 INFO - PID 22107 | |1;ext.html;839.4879123734522;868.1058509734974;1108.1467270201945;1115.4478533126103;1181.858177018758;34379.82810085952;37949.051273718134;37995;38078.23843523128;38204.61795382045;35892.6669766465;36153;36218.637813621855;36246.456303155406;36286;7072.186698529671;7153.35619794221;7158.570485770844;7206;7207.747728829492;7017.964910175442;7115.324044215791;7130.8573828523395;7135.85728285434;7137.678804453786;7117.964410177942;7169.46229032822;7198.8560228795395;7398.297161769623;7682.423818213629;7532.133804612457;7601.12587052485;7634.12207596128;7646.273604007611;7652.655630496641;7501.437392195608;7587.582682952436;7602.429817763635;7607.847843043108;7610.048743907012;35682;35990.280194396226;36136.63863361359;36629.26741465156;36727.26545469076;3419.675130862564;3466.2374277658873;3473.0796338970194;3476.356873978297;3478.4608385700258;29978.85010574955;29982.25044373908;29983.400331993238;30045.098647040624;30085.398292034257;5116.974415127937;5133.178691409394;5137.023965446553;5141.742912854343;5175.249588809639;177765;177771;178729;179014;179663;39671.40492892609;40089;45020.3246951296;45137.77431112823;45165.322520162226;41552;41632.58367416348;41665.79167104145;47753.28370074452;49195;15705;15822.920885395499;15861.286242119133;15940.202989850693;16005.599860003593;13064.673383165496;13131.409086591126;13139;13139.014573906805;13143.737125257632;13527.797083043763;13608.93195534016;13630.250336231602;13650;13720;5676.858078547987;5721.170430287585;5721.485066344049;5794.565407594448;5836.387179346192;971.4365667912593;993.5082134343546;996.531630133836;1011.438651548383;1019.7552587378975;22281.777182228296;22330.441738956655;22411.775882241294;22473.21343752961;22917.770822291564;1071.7802850415646;1075.854759607443;1100.4827730966433;1108.318384193715;1113.3820729495046;11814.88185118155;11833.230839995373;11996.220245684002;12000.759984800256;12027.15809893299;228114;231034;231790;232058;232252;47352;47732;47925.28112078321;48060.759696201996;48277.5172248273;1396.5112210726368;1425.4796999095256;1443.9061461005;1444.415011920174;1444.4799872046055;21653;21730.044296013435;21906.123755050256;21931.342059738232;22279;35556.28887422186;35701.82149089238;35754.28491430209;35759.46360804538;35762.28475430529;189331;189580;189684;189747;190791;29991;32047.679523205403;32869.68521259123;33120.50319245165;33358.83320583381;39953.40069898896;40064.3990340151;40602;41311;41331.7933410343;30089.849550752107;30369.696303036686;30400;30432.543511847784;30638.540421893245;29474.705252947195;29747.107586772432;29838;30008.399832003674;30424.391512170074;33847.66152338544;33918.491222632176;33987.32025359529;34121.48817767785;35838.64161358457;30829.38341233118;30838.229044274074;31596.526052108777;31793.205169870937;31843.84078079595;23467.530649386576;23565.882170589037;23650.52698945977;23664.52670946606;24082.759172408754 11:32:11 INFO - PID 22107 | |2;jquery.html;554.2268535393125;555.469526602095;559.1110134885531;564.1622191046296;569.2599620493359;21178.152873885047;21694.240701575447;21797.34607961762;21805;22386.552268954612;24548.140815071478;25183.118590849324;25194.496110077787;25279.62080568793;25317.62023569648;6413.230412350513;6562.081308616797;6594.670266486681;6597.67011649418;6601.471882249409;6569.93430065698;6583.901241481358;6616.404523592876;6639.435647969928;6641.734330626769;6130.080487926802;6379.904301435482;6406.93593064068;6412;6419.679016049179;6435;6470.838229044264;6473.029045643168;6475;6479.546431749779;5976.581639285252;5989.2812862456785;5990.580659353847;5996.13056106862;5997.190379298782;27645.723542764517;28030.719692803217;28160.155195344174;28667.426651466852;28678.426431471464;2040.2247146084542;2075.6678931370907;2076.6365885970044;2077.1587507059667;2083.5103750618746;18405.907970460197;18850.434486965274;18862;18900.905495472573;18921.7161742574;2302.125192426867;2341.3444235613883;2363.976360236393;2364.798992085675;2391.629297458897;125193;128466.7153328464;130064;132791.33604332013;133371;13730.725385492235;13836.584902452943;14138.575842724735;14412.207328596818;14810.629734256514;20241.797582024286;22424.88787556052;22477.662835057487;22539.661905071436;22717;8798.736037918872;9635.470049147365;9845.310828242098;10116.494175291205;10136.493175341204;9244.168024877794;9282.443053416817;9295.860562091573;9325.813483730288;9466.148046675695;8698;8931.285497160341;9054.909450905407;9106.499142547224;9129.406588571721;2539.5555777738823;2625.317417471433;2746.6978632350424;2785.7353551412584;2841.289677580605;937.6436953957452;955.6655170690203;973.99026009739;976.5166242709904;981.6318880419842;19351.4194574163;19432.70850937237;19992.000399979945;20006.49983750418;20248.898755506423;1001.1790331927895;1023.5394072667181;1024.2676486312323;1038.4911393417171;1043.9634612788516;11195.328280303207;11253.268537545197;11348.602798901999;11361.261518001304;11436.771264574661;456218;459201;459774;463820;479031;104042;104356.47821760843;104766.95233047572;106238;110572;1115.3475216998147;1155.2779512804498;1169.7543515861594;1173.6948393417772;1178.2753606531921;17434.73847892308;17468.21393037291;17581.29674812993;17680.11599419998;17753.467395978143;50798.49201507938;51862.740686296325;52610.73694631655;53057.46942530525;54021.72989135029;70207;72045;72071;72454.27545724476;73001;14231.64420889486;14632.414703411745;14690.338934747748;14858.479953201586;14990.550283491513;23099.88450057739;23320;23391;23504.059837606987;23648.054077837383;18247;18765.6246875059;18923.337683181024;18966.336178233694;18971.43085707431;15587.454439094578;16215.189240538162;16790.24443900052;17278;17425.651486970444;18822.24711011544;19293.517662058;19664.901675492103;19759.901200493907;19772.60454790925;14249.358778854768;14378;14450.927745361207;14552.781708274597;14709.117452952856;18490.16794244289;18547.0726463679;18575;18691.532711682317;18755.531111722317 11:32:11 INFO - PID 22107 | |3;mootools.html;1316.6445059833845;1372.9519466818663;1376.2980879751324;1378;1381.9723605527884;14039.298035098243;14740.705185896275;14743.189124598142;14756.557303280892;14777.408903643869;13876.236806975612;14458.493952711657;15334.923325383357;15355.846441535581;15356.46252381167;2615.738426157388;2654.960175597358;2676.4647070585866;2686.758191762751;2690.2870739254113;2530.7342729013467;2669.8798554065015;2670.4258584404347;2671.9599206011926;2673.6925253595796;2573.279481745105;2602.2843717977516;2769.819961702493;2780.0964686476873;2781.1795520321452;2702.297402675299;2709.4581083783223;2719.9592006119924;2731.945361092777;2740.0135951057605;2509.121807367425;2532.9493410131695;2545.300042488312;2546.376137846221;2556.948861022769;14052.437902483889;14135.71728565423;14275.21486318252;14435;14455.060421072596;7727.96136019322;7933.206679332021;7965.601719914039;8027.879581806277;8030.996125484315;5156.8968620627375;5206.531412172884;5234.895302093975;5351.866203344908;5502;1739.8086210516833;1745.9476215713548;1765.538110864397;1805.1876655505075;1805.9796215138406;24289;24768;24781.25656230316;24940.12709555157;25017.874910625513;6538.1827271591055;6684.598924064571;6712.496562757813;6870.965645171842;7109.395701365442;18534.16596253172;18558.44324670262;18559;18559.628807423775;18567.071646417626;7871.134175240719;7902.841943161105;7907.802304942423;7908.604569771488;7942.126366099725;7008.26413226614;7112.110986126734;7226.6386680665755;7237.493375463772;7250.9637451812405;4734.431868175841;4902.607791376681;4913.410390753061;4936.753162341868;4944.851654450372;1296.5591698822445;1299.5503475947717;1329.7805862032656;1344.2405041151717;1366.2007725480705;875.1117615620151;885.8139790643908;897.7261935109855;910.6812615584493;912.3886995712888;8410.957945210235;8456.154384561494;8574.399792014623;8589.871151932726;8717.651293948298;972;976.8534719792088;981.6220755009373;989.9158571521357;994.7910938702953;5349.251104845324;5448.210009548593;5496.835094947158;5545.195946587803;5575.916361254584;20023;20533.89733051355;20601.58796824055;20668;20804.583908322053;19374;20060.89969550143;20240.696389554167;20372.490687732927;20417.795822041888;10545.683629491128;11084.833727494093;11191.832122518168;11205.103591712643;11210.327380357205;8601.440906341068;8650.567471626267;8676.956615216883;8749.8687519686;8824.911750882666;16300.184990750178;16972.321107155574;17887.105644717973;18026.549336266173;18206.72689909679;16511.83488165103;16893.577660558094;16901.32394704198;17327.826721733127;17482.562935926195;11468.770624587629;11780.05759539252;11821.527138914691;11839.76320473603;11844.644660660195;11941.940290298493;12243.632691019284;12319.876801231872;12448.502059917502;12630.936845315715;10761.30051546647;10912.836307455236;11193.552257909592;11272.267302625303;11435.656930292105;10586.576536938435;10664.733381665521;10687.30532515384;10743.570257189625;10809.405482698527;9881.703548893545;9962.402255864672;9996.900030999888;10010.949945250228;10013.048760367754;7216.603086830275;7267.927320726725;7329.340359367682;7387;7461.403087753075;7431.29402706742;7460.776176714708;7523.247675232433;7540.585267810323;7562.546247225184 11:32:11 INFO - PID 22107 | |4;prototype.html;487.5164068021521;500.7815377876009;519.5999080707854;520.3677531798863;524.355043296745;6285.5914365566205;6310.8106756797315;6358;6358.745650173987;6378.425941665249;6048.939510604903;6125.488549694626;6223.719932603032;6230.127782110497;6241.282252540959;4975.179095449246;5030.440563425332;5045.924311135336;5074.517920797518;5086.313347698069;4748.548887855648;4929.753512324387;4968.70187788732;5029.421616514092;5064.417591976933;4832.395950506187;4843.4187897452175;4856.732879691615;4930.778114984834;4947.901041979158;4918.655694101414;4951.480094590071;4989.675671081368;5025.246213068032;5032.84901452957;4373.650107991353;4422.756748378838;4436.112777444524;4439;4472.731636101845;6053.486628342915;6064.211652485193;6097.512199024068;6118.908216376758;6202.162708034402;5692.231548740909;5706.914396284059;5713.200151978726;5719.342275638313;5722.226109905942;6491;6536.182977127859;6554.672266386661;6582.979638156045;6609.801705948829;4788.162071637449;4796.208625576775;4814.761136071708;4837.153498137847;4853.878653033667;6780.593164410102;6870.076378613996;6907.861842763167;6929.480288978297;6935.491930245381;4985.501449855058;5017.297578338962;5020.221865610836;5021;5034.647574669812;5014.498550144956;5029.7736601852275;5056.216286475602;5078.720670363092;5098.515641014098;5611.915821262684;5687.601867869293;5713.685747283939;5727.083666613322;5741.971290143606;6540.803775886731;6581.243157037002;6581.769638062645;6635.052143974328;6675.933240667531;1748.2744660965557;1748.370586588827;1774.1218097042047;1779.421687951421;1790.274938649849;1111.1000089927186;1162.610525473959;1194.5102507971865;1226.7301193737364;1234.6357824441811;5774.971125144431;5777.653340799566;5822.7088645567555;5823.388544202798;5827.184194212814;6510.869782604322;6523.510736694767;6528.759535688204;6561.639109849004;6600.5049621278595;5640.492355687926;5667.348254950651;5710.086386178192;5748.281464816898;5756.798512052058;3500.1949551603275;3544.379733546619;3548.5450965104183;3554.289142171576;3580.337637537064;6532;6583.83540411484;6593.14289142418;6633.6019838809825;6678.666066696645;6839.2818754031105;6858;6955.46979664473;6978.371946524736;6981.232064472904;6511.804645860537;6534.248561415498;6566.4418524425955;6571.5399922005;6622.403983641495;6487.837804054936;6599.60402375859;6642.83392915181;6648.867022659423;6730.93269067303;6520.706568204537;6629.502787290876;6630.7679231226675;6653.866922661617;6717.798466046026;6422.233885681475;6427.871442571216;6483.481321494175;6489.7404103835315;6544.083828263952;4935.766058485378;4937;4968.751562421937;4974.806046548874;4995.450500444876;6596.670166491752;6628.5691430056895;6661.867482528079;6671.33286671329;6698.56459330142;6457.6771161442675;6469.935300646934;6491.415772580491;6520.804375868732;6550.443212327005;6355.713992870239;6429.22849258092;6454.838629034311;6469.320721324193;6509.349065093453;5783.421657834183;6376.904346434714;6404.391582799627;6457;6468.935310646833;6465.77369792055;6494;6502.414782669583;6510.493460017832;6628.171478565179;6235.563510554218;6243.531735119975;6243.906341404791;6295.842603934938;6317.810465686037 11:32:11 INFO - PID 22107 | |5;yui.html;838.5807096451774;841.2975165736609;855.1192271959882;861.8104017116232;867.301822033263;19231.807681923176;20066.598668026632;20073.397798066046;20076.89961550192;20101.296454624055;19690.409287721355;20181.394558163243;20366.592668146666;20400.489987750276;20422.183112675513;928.2852203803068;945.9574319155655;951.1487218939045;954.6372378496162;954.6944977607169;860.4407135362002;947.3747326764337;955.1308309438413;961.6345788600357;966.5940305071887;987.4667679453087;1002.8395456726926;1003.1072345612411;1004.1615251265166;1004.3572113847143;996.7408473796829;998.6305067125138;999.0159692702682;1000.4047591682937;1000.4897502273857;733.7761982595306;735.4263674334028;739.268124556685;740.6555951482533;740.7481456304828;19176.328828490936;19778.406647800588;19887.20451181951;20125;20149.597008059758;18216.544586385313;18395.908020459945;18955.71566426491;18998.905005474884;19041;16766.32934682611;17013.65972680552;17161.65676686459;17214.31142754288;17263.395781147727;3650.1787097903025;3685.318216129998;3704.111013356802;3710.5732840723394;3710.7402481826284;126547.36726316402;128664.35667821695;128980.71019289781;131714;132298;15358.923205383902;15436.45972390961;15819.446319378767;15898.125603091941;16129.354825807071;29332.853335733613;29597.112086637437;29921.251968700522;29965.850170749007;30348.69651303503;8554.957225213833;8557.058723540405;8587.184217499327;8606.31149508038;8624.870626940601;3913.72603917723;4008.659263962596;4009.258287216874;4034.919301613951;4035.9596404035583;5039.874003149951;5199.584033277329;5270.60470464709;5289.047971365114;5305.416404195613;935.2050756856753;937.6858752317913;954.9686338754129;956.220680145679;961.0725649748026;652.5758257132807;669.3039239191284;671.5131529640971;673.5520878615764;684.165318311659;11383.203175777782;11431.397625994205;11504.137189710806;11803.937645611935;12623.747525049448;661.4939571228015;664.1930054983219;665.710415969055;681.7170874087199;692.6813665713828;5369.57043436532;5476.123820188751;5988.221531200916;6239.344868788802;6248.781292654826;105469.47265263778;106779;108334.45832770786;108425.4578727117;108516;96588.51705741427;97740.51129744305;98530;98882;99882;80258;80604.59697701591;81827.9999999988;82431;82628;12420.75158496807;12449.502019919102;12601.684957876127;12821.871781282067;12976.545820896223;37435.6256437443;37723;38665.22669546537;38710.64512742041;38940.610593893696;48854.26718599295;49809.25286120784;50061.74969125131;51420.74289628528;51505.742471287405;16599.91700041492;16895;17007.234674439926;17013.57466063358;17095;20157.395278141677;20562.794372056687;20941.476463088544;20955.790442095386;21288;18087.728684069487;18636;18846.717299240798;18919.71620425667;19077.809221907602;18500.352487663407;19188.71216931775;19272.518187044876;19363.12865921065;19527;18062;18441.63116737631;18852.905735471235;19085.427437176906;19281.51796205106;12936.805947910601;13248.138870973356;13548.661283467993;13634;13925.582232533041;14256.144631322153;14256.786148207993;14384.928075359556;14530.927345363205;14564.927175364055 11:32:11 INFO - PID 22107 | __end_tp_report 11:32:11 INFO - PID 22107 | __start_cc_report 11:32:11 INFO - PID 22107 | _x_x_mozilla_cycle_collect,358 11:32:11 INFO - PID 22107 | __end_cc_report 11:32:11 INFO - PID 22107 | __startTimestamp1504031531873__endTimestamp 11:32:11 INFO - PID 22107 | 11:32:11 INFO - PID 22107 | ------- Summary: start ------- 11:32:11 INFO - PID 22107 | Number of tests: 6 11:32:11 INFO - PID 22107 | 11:32:11 INFO - PID 22107 | [#0] dojo.html Cycles:165 Average:6114.67 Median:6375.95 stddev:736.27 (11.5%) stddev-sans-first:703.94 11:32:11 INFO - PID 22107 | Values: 3271.6 3279.4 3293.5 3338.9 3351.9 6207.7 6249.4 6254.0 6268.4 6363.0 6250.3 6327.8 6352.6 6371.8 6375.9 5688.7 5769.6 5823.2 5829.4 5847.9 5574.4 5607.6 5617.4 5696.1 5715.1 5673.5 5701.8 5708.7 5723.3 5769.0 5729.1 5785.1 5786.4 5787.9 5790.1 5713.7 5716.0 5717.5 5736.9 5755.4 5992.7 6093.1 6108.3 6135.8 6270.4 6589.3 6599.7 6601.8 6614.6 6628.0 6623.4 6667.6 6672.8 6704.2 6705.2 6173.1 6205.4 6220.2 6223.4 6229.1 6829.2 6841.5 6862.8 6863.8 6866.7 5792.2 6527.1 6550.7 6615.6 6648.4 6695.8 6701.5 6769.5 6774.0 6809.4 6481.0 6592.8 6611.5 6649.8 6724.5 6643.1 6754.1 6762.9 6765.2 6792.2 4877.5 4919.5 4985.4 4988.0 5008.7 4283.1 4343.2 4381.0 4402.4 4414.7 5714.9 5788.0 5814.6 5828.9 5829.8 6657.3 6669.9 6680.1 6736.8 6738.8 5794.4 5848.0 5865.5 5866.0 5898.4 5686.5 5736.4 5763.7 5808.3 5833.6 6538.6 6557.9 6627.6 6628.4 6649.5 6496.7 6596.3 6600.6 6611.5 6615.3 6458.5 6463.1 6468.6 6486.8 6497.1 6532.6 6543.5 6545.1 6545.7 6551.3 6009.0 6628.3 6645.1 6679.5 6689.7 6467.4 6473.1 6488.1 6572.6 6573.0 6448.6 6462.4 6510.4 6556.7 6578.8 6252.7 6434.6 6435.9 6439.2 6448.1 5578.6 6367.3 6424.5 6460.3 6545.6 6290.3 6308.1 6332.4 6393.5 6445.4 11:32:11 INFO - PID 22107 | 11:32:11 INFO - PID 22107 | [#1] ext.html Cycles:180 Average:35760.58 Median:33883.08 stddev:52031.74 (153.6%) stddev-sans-first:52111.64 11:32:11 INFO - PID 22107 | Values: 839.5 868.1 1108.1 1115.4 1181.9 34379.8 37949.1 37995.0 38078.2 38204.6 35892.7 36153.0 36218.6 36246.5 36286.0 7072.2 7153.4 7158.6 7206.0 7207.7 7018.0 7115.3 7130.9 7135.9 7137.7 7118.0 7169.5 7198.9 7398.3 7682.4 7532.1 7601.1 7634.1 7646.3 7652.7 7501.4 7587.6 7602.4 7607.8 7610.0 35682.0 35990.3 36136.6 36629.3 36727.3 3419.7 3466.2 3473.1 3476.4 3478.5 29978.9 29982.3 29983.4 30045.1 30085.4 5117.0 5133.2 5137.0 5141.7 5175.2 177765.0 177771.0 178729.0 179014.0 179663.0 39671.4 40089.0 45020.3 45137.8 45165.3 41552.0 41632.6 41665.8 47753.3 49195.0 15705.0 15822.9 15861.3 15940.2 16005.6 13064.7 13131.4 13139.0 13139.0 13143.7 13527.8 13608.9 13630.3 13650.0 13720.0 5676.9 5721.2 5721.5 5794.6 5836.4 971.4 993.5 996.5 1011.4 1019.8 22281.8 22330.4 22411.8 22473.2 22917.8 1071.8 1075.9 1100.5 1108.3 1113.4 11814.9 11833.2 11996.2 12000.8 12027.2 228114.0 231034.0 231790.0 232058.0 232252.0 47352.0 47732.0 47925.3 48060.8 48277.5 1396.5 1425.5 1443.9 1444.4 1444.5 21653.0 21730.0 21906.1 21931.3 22279.0 35556.3 35701.8 35754.3 35759.5 35762.3 189331.0 189580.0 189684.0 189747.0 190791.0 29991.0 32047.7 32869.7 33120.5 33358.8 39953.4 40064.4 40602.0 41311.0 41331.8 30089.8 30369.7 30400.0 30432.5 30638.5 29474.7 29747.1 29838.0 30008.4 30424.4 33847.7 33918.5 33987.3 34121.5 35838.6 30829.4 30838.2 31596.5 31793.2 31843.8 23467.5 23565.9 23650.5 23664.5 24082.8 11:32:11 INFO - PID 22107 | 11:32:11 INFO - PID 22107 | [#2] jquery.html Cycles:180 Average:33326.63 Median:22628.33 stddev:77977.46 (344.6%) stddev-sans-first:78157.38 11:32:11 INFO - PID 22107 | Values: 554.2 555.5 559.1 564.2 569.3 21178.2 21694.2 21797.3 21805.0 22386.6 24548.1 25183.1 25194.5 25279.6 25317.6 6413.2 6562.1 6594.7 6597.7 6601.5 6569.9 6583.9 6616.4 6639.4 6641.7 6130.1 6379.9 6406.9 6412.0 6419.7 6435.0 6470.8 6473.0 6475.0 6479.5 5976.6 5989.3 5990.6 5996.1 5997.2 27645.7 28030.7 28160.2 28667.4 28678.4 2040.2 2075.7 2076.6 2077.2 2083.5 18405.9 18850.4 18862.0 18900.9 18921.7 2302.1 2341.3 2364.0 2364.8 2391.6 125193.0 128466.7 130064.0 132791.3 133371.0 13730.7 13836.6 14138.6 14412.2 14810.6 20241.8 22424.9 22477.7 22539.7 22717.0 8798.7 9635.5 9845.3 10116.5 10136.5 9244.2 9282.4 9295.9 9325.8 9466.1 8698.0 8931.3 9054.9 9106.5 9129.4 2539.6 2625.3 2746.7 2785.7 2841.3 937.6 955.7 974.0 976.5 981.6 19351.4 19432.7 19992.0 20006.5 20248.9 1001.2 1023.5 1024.3 1038.5 1044.0 11195.3 11253.3 11348.6 11361.3 11436.8 456218.0 459201.0 459774.0 463820.0 479031.0 104042.0 104356.5 104767.0 106238.0 110572.0 1115.3 1155.3 1169.8 1173.7 1178.3 17434.7 17468.2 17581.3 17680.1 17753.5 50798.5 51862.7 52610.7 53057.5 54021.7 70207.0 72045.0 72071.0 72454.3 73001.0 14231.6 14632.4 14690.3 14858.5 14990.6 23099.9 23320.0 23391.0 23504.1 23648.1 18247.0 18765.6 18923.3 18966.3 18971.4 15587.5 16215.2 16790.2 17278.0 17425.7 18822.2 19293.5 19664.9 19759.9 19772.6 14249.4 14378.0 14450.9 14552.8 14709.1 18490.2 18547.1 18575.0 18691.5 18755.5 11:32:11 INFO - PID 22107 | 11:32:11 INFO - PID 22107 | [#3] mootools.html Cycles:180 Average:9062.50 Median:2545.84 stddev:6293.58 (247.2%) stddev-sans-first:6284.32 11:32:11 INFO - PID 22107 | Values: 1316.6 1373.0 1376.3 1378.0 1382.0 14039.3 14740.7 14743.2 14756.6 14777.4 13876.2 14458.5 15334.9 15355.8 15356.5 2615.7 2655.0 2676.5 2686.8 2690.3 2530.7 2669.9 2670.4 2672.0 2673.7 2573.3 2602.3 2769.8 2780.1 2781.2 2702.3 2709.5 2720.0 2731.9 2740.0 2509.1 2532.9 2545.3 2546.4 2556.9 14052.4 14135.7 14275.2 14435.0 14455.1 7728.0 7933.2 7965.6 8027.9 8031.0 5156.9 5206.5 5234.9 5351.9 5502.0 1739.8 1745.9 1765.5 1805.2 1806.0 24289.0 24768.0 24781.3 24940.1 25017.9 6538.2 6684.6 6712.5 6871.0 7109.4 18534.2 18558.4 18559.0 18559.6 18567.1 7871.1 7902.8 7907.8 7908.6 7942.1 7008.3 7112.1 7226.6 7237.5 7251.0 4734.4 4902.6 4913.4 4936.8 4944.9 1296.6 1299.6 1329.8 1344.2 1366.2 875.1 885.8 897.7 910.7 912.4 8411.0 8456.2 8574.4 8589.9 8717.7 972.0 976.9 981.6 989.9 994.8 5349.3 5448.2 5496.8 5545.2 5575.9 20023.0 20533.9 20601.6 20668.0 20804.6 19374.0 20060.9 20240.7 20372.5 20417.8 10545.7 11084.8 11191.8 11205.1 11210.3 8601.4 8650.6 8677.0 8749.9 8824.9 16300.2 16972.3 17887.1 18026.5 18206.7 16511.8 16893.6 16901.3 17327.8 17482.6 11468.8 11780.1 11821.5 11839.8 11844.6 11941.9 12243.6 12319.9 12448.5 12630.9 10761.3 10912.8 11193.6 11272.3 11435.7 10586.6 10664.7 10687.3 10743.6 10809.4 9881.7 9962.4 9996.9 10010.9 10013.0 7216.6 7267.9 7329.3 7387.0 7461.4 7431.3 7460.8 7523.2 7540.6 7562.5 11:32:11 INFO - PID 22107 | 11:32:11 INFO - PID 22107 | [#4] prototype.html Cycles:180 Average:5493.45 Median:6122.20 stddev:1543.65 (25.2%) stddev-sans-first:1501.56 11:32:11 INFO - PID 22107 | Values: 487.5 500.8 519.6 520.4 524.4 6285.6 6310.8 6358.0 6358.7 6378.4 6048.9 6125.5 6223.7 6230.1 6241.3 4975.2 5030.4 5045.9 5074.5 5086.3 4748.5 4929.8 4968.7 5029.4 5064.4 4832.4 4843.4 4856.7 4930.8 4947.9 4918.7 4951.5 4989.7 5025.2 5032.8 4373.7 4422.8 4436.1 4439.0 4472.7 6053.5 6064.2 6097.5 6118.9 6202.2 5692.2 5706.9 5713.2 5719.3 5722.2 6491.0 6536.2 6554.7 6583.0 6609.8 4788.2 4796.2 4814.8 4837.2 4853.9 6780.6 6870.1 6907.9 6929.5 6935.5 4985.5 5017.3 5020.2 5021.0 5034.6 5014.5 5029.8 5056.2 5078.7 5098.5 5611.9 5687.6 5713.7 5727.1 5742.0 6540.8 6581.2 6581.8 6635.1 6675.9 1748.3 1748.4 1774.1 1779.4 1790.3 1111.1 1162.6 1194.5 1226.7 1234.6 5775.0 5777.7 5822.7 5823.4 5827.2 6510.9 6523.5 6528.8 6561.6 6600.5 5640.5 5667.3 5710.1 5748.3 5756.8 3500.2 3544.4 3548.5 3554.3 3580.3 6532.0 6583.8 6593.1 6633.6 6678.7 6839.3 6858.0 6955.5 6978.4 6981.2 6511.8 6534.2 6566.4 6571.5 6622.4 6487.8 6599.6 6642.8 6648.9 6730.9 6520.7 6629.5 6630.8 6653.9 6717.8 6422.2 6427.9 6483.5 6489.7 6544.1 4935.8 4937.0 4968.8 4974.8 4995.5 6596.7 6628.6 6661.9 6671.3 6698.6 6457.7 6469.9 6491.4 6520.8 6550.4 6355.7 6429.2 6454.8 6469.3 6509.3 5783.4 6376.9 6404.4 6457.0 6468.9 6465.8 6494.0 6502.4 6510.5 6628.2 6235.6 6243.5 6243.9 6295.8 6317.8 11:32:11 INFO - PID 22107 | 11:32:11 INFO - PID 22107 | [#5] yui.html Cycles:180 Average:23018.97 Median:25310.43 stddev:31374.96 (124.0%) stddev-sans-first:31418.77 11:32:11 INFO - PID 22107 | Values: 838.6 841.3 855.1 861.8 867.3 19231.8 20066.6 20073.4 20076.9 20101.3 19690.4 20181.4 20366.6 20400.5 20422.2 928.3 946.0 951.1 954.6 954.7 860.4 947.4 955.1 961.6 966.6 987.5 1002.8 1003.1 1004.2 1004.4 996.7 998.6 999.0 1000.4 1000.5 733.8 735.4 739.3 740.7 740.7 19176.3 19778.4 19887.2 20125.0 20149.6 18216.5 18395.9 18955.7 18998.9 19041.0 16766.3 17013.7 17161.7 17214.3 17263.4 3650.2 3685.3 3704.1 3710.6 3710.7 126547.4 128664.4 128980.7 131714.0 132298.0 15358.9 15436.5 15819.4 15898.1 16129.4 29332.9 29597.1 29921.3 29965.9 30348.7 8555.0 8557.1 8587.2 8606.3 8624.9 3913.7 4008.7 4009.3 4034.9 4036.0 5039.9 5199.6 5270.6 5289.0 5305.4 935.2 937.7 955.0 956.2 961.1 652.6 669.3 671.5 673.6 684.2 11383.2 11431.4 11504.1 11803.9 12623.7 661.5 664.2 665.7 681.7 692.7 5369.6 5476.1 5988.2 6239.3 6248.8 105469.5 106779.0 108334.5 108425.5 108516.0 96588.5 97740.5 98530.0 98882.0 99882.0 80258.0 80604.6 81828.0 82431.0 82628.0 12420.8 12449.5 12601.7 12821.9 12976.5 37435.6 37723.0 38665.2 38710.6 38940.6 48854.3 49809.3 50061.7 51420.7 51505.7 16599.9 16895.0 17007.2 17013.6 17095.0 20157.4 20562.8 20941.5 20955.8 21288.0 18087.7 18636.0 18846.7 18919.7 19077.8 18500.4 19188.7 19272.5 19363.1 19527.0 18062.0 18441.6 18852.9 19085.4 19281.5 12936.8 13248.1 13548.7 13634.0 13925.6 14256.1 14256.8 14384.9 14530.9 14564.9 11:32:11 INFO - PID 22107 | -------- Summary: end -------- 11:32:11 INFO - PID 22107 | 11:32:12 INFO - TEST-INFO | 22107: exit 0 11:32:12 INFO - TEST-OK | dromaeo_css | took 1117307ms 11:32:12 INFO - TEST-START | kraken 11:32:12 INFO - Initialising browser for kraken test... 11:32:12 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43079/getInfo.html -profile /tmp/tmpyw4_KX/profile 11:32:12 INFO - TEST-INFO | started process 22483 (/builds/slave/test/build/application/firefox/firefox http://localhost:43079/getInfo.html) 11:32:19 INFO - TEST-INFO | 22483: exit 0 11:32:19 INFO - Browser initialized. 11:32:19 INFO - Running cycle 1/1 for kraken test... 11:32:19 INFO - Using env: {'DISPLAY': ':0', 11:32:19 INFO - 'HOME': '/home/cltbld', 11:32:19 INFO - 'JSGC_DISABLE_POISONING': '1', 11:32:19 INFO - 'LANG': 'en_US.UTF-8', 11:32:19 INFO - 'LANGUAGE': 'en_US:en', 11:32:19 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:32:19 INFO - 'LOGNAME': 'cltbld', 11:32:19 INFO - 'MAIL': '/var/mail/cltbld', 11:32:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:32:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:32:19 INFO - 'MOZ_ACCELERATED': '1', 11:32:19 INFO - 'MOZ_CRASHREPORTER': '1', 11:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:19 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:32:19 INFO - 'MOZ_NO_REMOTE': '1', 11:32:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:32:19 INFO - 'MOZ_WEBRENDER': '1', 11:32:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:32:19 INFO - 'NO_EM_RESTART': '1', 11:32:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:19 INFO - 'PWD': '/builds/slave/test', 11:32:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:32:19 INFO - 'RUST_BACKTRACE': 'full', 11:32:19 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:32:19 INFO - 'SHELL': '/bin/bash', 11:32:19 INFO - 'SHLVL': '1', 11:32:19 INFO - 'TERM': 'linux', 11:32:19 INFO - 'TMOUT': '86400', 11:32:19 INFO - 'USER': 'cltbld', 11:32:19 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512', 11:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:19 INFO - '_': '/tools/buildbot/bin/python'} 11:32:19 INFO - TEST-INFO | started process 22667 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyw4_KX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:32:20 INFO - PID 22667 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:32:20 INFO - PID 22667 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:32:21 INFO - PID 22667 | 11:32:21 INFO - PID 22667 | (/builds/slave/test/build/application/firefox/firefox:22728): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:32:21 INFO - PID 22667 | 11:32:31 INFO - PID 22667 | RSS: Main: 201113600 11:32:31 INFO - PID 22667 | 11:32:34 INFO - PID 22667 | Cycle 1(1): loaded http://localhost:43079/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43079/tests/kraken/driver.html?testName=audio-beat-detection) 11:32:35 INFO - PID 22667 | RSS: Main: 207343616 11:32:35 INFO - PID 22667 | 11:32:37 INFO - PID 22667 | Cycle 1(1): loaded http://localhost:43079/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43079/tests/kraken/driver.html?testName=audio-dft) 11:32:38 INFO - PID 22667 | RSS: Main: 207175680 11:32:38 INFO - PID 22667 | 11:32:41 INFO - PID 22667 | Cycle 1(1): loaded http://localhost:43079/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43079/tests/kraken/driver.html?testName=audio-fft) 11:32:42 INFO - PID 22667 | RSS: Main: 201273344 11:32:42 INFO - PID 22667 | 11:32:44 INFO - PID 22667 | Cycle 1(1): loaded http://localhost:43079/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43079/tests/kraken/driver.html?testName=audio-oscillator) 11:32:44 INFO - PID 22667 | RSS: Main: 202825728 11:32:44 INFO - PID 22667 | 11:32:47 INFO - PID 22667 | Cycle 1(1): loaded http://localhost:43079/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43079/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:32:47 INFO - PID 22667 | RSS: Main: 202633216 11:32:47 INFO - PID 22667 | 11:32:50 INFO - PID 22667 | Cycle 1(1): loaded http://localhost:43079/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43079/tests/kraken/driver.html?testName=imaging-darkroom) 11:32:51 INFO - PID 22667 | RSS: Main: 206299136 11:32:51 INFO - PID 22667 | 11:32:55 INFO - PID 22667 | Cycle 1(1): loaded http://localhost:43079/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43079/tests/kraken/driver.html?testName=imaging-desaturate) 11:32:55 INFO - PID 22667 | RSS: Main: 205438976 11:32:55 INFO - PID 22667 | 11:32:58 INFO - PID 22667 | Cycle 1(1): loaded http://localhost:43079/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43079/tests/kraken/driver.html?testName=json-parse-financial) 11:32:59 INFO - PID 22667 | RSS: Main: 206909440 11:32:59 INFO - PID 22667 | 11:33:00 INFO - PID 22667 | Cycle 1(1): loaded http://localhost:43079/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43079/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:33:01 INFO - PID 22667 | RSS: Main: 206708736 11:33:01 INFO - PID 22667 | 11:33:03 INFO - PID 22667 | Cycle 1(1): loaded http://localhost:43079/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43079/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:33:03 INFO - PID 22667 | RSS: Main: 206467072 11:33:03 INFO - PID 22667 | 11:33:06 INFO - PID 22667 | Cycle 1(1): loaded http://localhost:43079/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43079/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:33:06 INFO - PID 22667 | RSS: Main: 205815808 11:33:06 INFO - PID 22667 | 11:33:09 INFO - PID 22667 | Cycle 1(1): loaded http://localhost:43079/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43079/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:33:10 INFO - PID 22667 | RSS: Main: 206499840 11:33:10 INFO - PID 22667 | 11:33:13 INFO - PID 22667 | Cycle 1(1): loaded http://localhost:43079/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43079/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:33:13 INFO - PID 22667 | RSS: Main: 205811712 11:33:13 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | Cycle 1(1): loaded http://localhost:43079/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43079/tests/kraken/driver.html?testName=ai-astar) 11:33:15 INFO - PID 22667 | RSS: Main: 208740352 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | __start_tp_report 11:33:15 INFO - PID 22667 | _x_x_mozilla_page_load 11:33:15 INFO - PID 22667 | _x_x_mozilla_page_load_details 11:33:15 INFO - PID 22667 | |i|pagename|runs| 11:33:15 INFO - PID 22667 | |0;ai-astar;105;111;111;108;113;110;107;99;114;108 11:33:15 INFO - PID 22667 | |1;audio-beat-detection;147;126;143;176;122;154;144;117;176;119 11:33:15 INFO - PID 22667 | |2;audio-dft;172;167;164;169;164;166;176;162;164;174 11:33:15 INFO - PID 22667 | |3;audio-fft;76;75;74;179;75;77;74;88;98;75 11:33:15 INFO - PID 22667 | |4;audio-oscillator;80;85;135;81;80;78;136;79;88;130 11:33:15 INFO - PID 22667 | |5;imaging-gaussian-blur;122;118;124;116;114;116;114;117;118;114 11:33:15 INFO - PID 22667 | |6;imaging-darkroom;150;150;157;151;153;150;151;149;147;148 11:33:15 INFO - PID 22667 | |7;imaging-desaturate;96;94;98;102;97;102;95;97;97;96 11:33:15 INFO - PID 22667 | |8;json-parse-financial;53;52;58;52;52;57;54;51;60;52 11:33:15 INFO - PID 22667 | |9;json-stringify-tinderbox;43;38;41;48;43;50;38;38;38;41 11:33:15 INFO - PID 22667 | |10;stanford-crypto-aes;77;84;77;79;81;86;84;80;76;87 11:33:15 INFO - PID 22667 | |11;stanford-crypto-ccm;125;135;121;128;122;119;120;130;116;149 11:33:15 INFO - PID 22667 | |12;stanford-crypto-pbkdf2;182;168;172;179;173;171;171;171;185;175 11:33:15 INFO - PID 22667 | |13;stanford-crypto-sha256-iterative;63;75;67;65;66;69;65;68;65;70 11:33:15 INFO - PID 22667 | __end_tp_report 11:33:15 INFO - PID 22667 | __start_cc_report 11:33:15 INFO - PID 22667 | _x_x_mozilla_cycle_collect,977 11:33:15 INFO - PID 22667 | __end_cc_report 11:33:15 INFO - PID 22667 | __startTimestamp1504031595833__endTimestamp 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | ------- Summary: start ------- 11:33:15 INFO - PID 22667 | Number of tests: 14 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | [#0] ai-astar Cycles:10 Average:108.60 Median:111.00 stddev:4.35 (3.9%) stddev-sans-first:4.42 11:33:15 INFO - PID 22667 | Values: 105.0 111.0 111.0 108.0 113.0 110.0 107.0 99.0 114.0 108.0 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | [#1] audio-beat-detection Cycles:10 Average:142.40 Median:145.50 stddev:21.84 (15.0%) stddev-sans-first:23.11 11:33:15 INFO - PID 22667 | Values: 147.0 126.0 143.0 176.0 122.0 154.0 144.0 117.0 176.0 119.0 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | [#2] audio-dft Cycles:10 Average:167.80 Median:168.00 stddev:4.78 (2.8%) stddev-sans-first:4.82 11:33:15 INFO - PID 22667 | Values: 172.0 167.0 164.0 169.0 164.0 166.0 176.0 162.0 164.0 174.0 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | [#3] audio-fft Cycles:10 Average:89.10 Median:75.50 stddev:32.55 (43.1%) stddev-sans-first:34.17 11:33:15 INFO - PID 22667 | Values: 76.0 75.0 74.0 179.0 75.0 77.0 74.0 88.0 98.0 75.0 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:80.00 stddev:25.38 (31.7%) stddev-sans-first:26.15 11:33:15 INFO - PID 22667 | Values: 80.0 85.0 135.0 81.0 80.0 78.0 136.0 79.0 88.0 130.0 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.50 stddev:3.40 (2.9%) stddev-sans-first:3.15 11:33:15 INFO - PID 22667 | Values: 122.0 118.0 124.0 116.0 114.0 116.0 114.0 117.0 118.0 114.0 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | [#6] imaging-darkroom Cycles:10 Average:150.60 Median:150.50 stddev:2.80 (1.9%) stddev-sans-first:2.96 11:33:15 INFO - PID 22667 | Values: 150.0 150.0 157.0 151.0 153.0 150.0 151.0 149.0 147.0 148.0 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | [#7] imaging-desaturate Cycles:10 Average:97.40 Median:96.50 stddev:2.67 (2.8%) stddev-sans-first:2.79 11:33:15 INFO - PID 22667 | Values: 96.0 94.0 98.0 102.0 97.0 102.0 95.0 97.0 97.0 96.0 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:53.50 stddev:3.11 (5.8%) stddev-sans-first:3.27 11:33:15 INFO - PID 22667 | Values: 53.0 52.0 58.0 52.0 52.0 57.0 54.0 51.0 60.0 52.0 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | [#9] json-stringify-tinderbox Cycles:10 Average:41.80 Median:42.00 stddev:4.32 (10.3%) stddev-sans-first:4.56 11:33:15 INFO - PID 22667 | Values: 43.0 38.0 41.0 48.0 43.0 50.0 38.0 38.0 38.0 41.0 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:82.50 stddev:3.96 (4.8%) stddev-sans-first:3.91 11:33:15 INFO - PID 22667 | Values: 77.0 84.0 77.0 79.0 81.0 86.0 84.0 80.0 76.0 87.0 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:126.50 stddev:9.74 (7.7%) stddev-sans-first:10.32 11:33:15 INFO - PID 22667 | Values: 125.0 135.0 121.0 128.0 122.0 119.0 120.0 130.0 116.0 149.0 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:174.00 stddev:5.52 (3.2%) stddev-sans-first:5.18 11:33:15 INFO - PID 22667 | Values: 182.0 168.0 172.0 179.0 173.0 171.0 171.0 171.0 185.0 175.0 11:33:15 INFO - PID 22667 | 11:33:15 INFO - PID 22667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:3.43 (5.1%) stddev-sans-first:3.27 11:33:15 INFO - PID 22667 | Values: 63.0 75.0 67.0 65.0 66.0 69.0 65.0 68.0 65.0 70.0 11:33:15 INFO - PID 22667 | -------- Summary: end -------- 11:33:15 INFO - PID 22667 | 11:33:16 INFO - PID 22667 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:33:16 INFO - TEST-INFO | 22667: exit 0 11:33:16 INFO - TEST-OK | kraken | took 63909ms 11:33:16 INFO - SUITE-END | took 1181s 11:33:16 INFO - Completed test suite (00:19:41) 11:33:16 INFO - javascript benchmark 11:33:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8377.208448325613, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3271.6401195868466, 3279.376918385505, 3293.505974103884, 3338.9332213355733, 3351.8659253629858, 6207.658578778165, 6249.406306400891, 6253.9687301563545, 6268.373162683735, 6363.045543168527, 6250.312465628788, 6327.7785277515295, 6352.555321127523, 6371.808845734636, 6375.9479685851775, 5688.658680479184, 5769.5672824538115, 5823.184754134425, 5829.387914269004, 5847.912281315784, 5574.358948720888, 5607.57943154263, 5617.4382561743705, 5696.11710184922, 5715.057015592422, 5673.517750991171, 5701.828945131652, 5708.686022268774, 5723.313202415716, 5769.0, 5729.111987641881, 5785.074388097926, 5786.363500014995, 5787.884242315172, 5790.073588225863, 5713.657180569137, 5715.971420142872, 5717.514011309044, 5736.942630573724, 5755.366909639936, 5992.730327135289, 6093.086037094427, 6108.267007959029, 6135.785247516315, 6270.404311590437, 6589.308122647101, 6599.670016499156, 6601.801945941629, 6614.636195009272, 6628.005799130169, 6623.370779775962, 6667.599944003376, 6672.799816005528, 6704.161979752531, 6705.195376554844, 6173.104899789595, 6205.410486003822, 6220.1602783624085, 6223.3776622337855, 6229.0967809667345, 6829.214640316328, 6841.452683785285, 6862.794116176523, 6863.794086177422, 6866.690998905061, 5792.189093526909, 6527.086207930894, 6550.672466376757, 6615.636140012345, 6648.401643852077, 6695.765648202387, 6701.530892837454, 6769.492288078315, 6774.0, 6809.421199198025, 6481.0, 6592.769253076215, 6611.471082313404, 6649.767258145942, 6724.49566282521, 6643.069970204176, 6754.0881980931945, 6762.8647427052165, 6765.188177418643, 6792.184937807396, 4877.487863774323, 4919.458859525449, 4985.376827896513, 4987.975060124749, 5008.699478031257, 4283.057727299989, 4343.1747967886, 4381.036172042146, 4402.449693788277, 4414.735115893057, 5714.857128571736, 5788.044972579477, 5814.563907706939, 5828.912566311508, 5829.795957141479, 6657.300983396771, 6669.933300667029, 6680.131582894192, 6736.7642132525125, 6738.787018336747, 5794.391588883191, 5847.970760146172, 5865.530757539386, 5866.032104702762, 5898.380670029585, 5686.488216060575, 5736.42635736423, 5763.740631671585, 5808.273965754281, 5833.562482813806, 6538.574992625464, 6557.868842623217, 6627.635480048547, 6628.370304821035, 6649.46804255668, 6496.74013039492, 6596.340365963365, 6600.570962887397, 6611.471082313499, 6615.338466153346, 6458.54790164684, 6463.127477790438, 6468.611883287018, 6486.772962946275, 6497.122888410004, 6532.640704761284, 6543.5419520633095, 6545.083688283739, 6545.705443254969, 6551.312112228147, 6008.973131017776, 6628.270890201978, 6645.102911106938, 6679.4656427484715, 6689.69896354675, 6467.385598368148, 6473.061406096184, 6488.124103246001, 6572.572782769104, 6572.967135164485, 6448.645324507197, 6462.4183823456115, 6510.44661203803, 6556.704948277244, 6578.835529111619, 6252.718627661857, 6434.64609446485, 6435.871282574229, 6439.227292724903, 6448.097266382616, 5578.581606379619, 6367.3314301997625, 6424.518161138027, 6460.289368169591, 6545.574537655038, 6290.339514351111, 6308.053791931247, 6332.398422150072, 6393.520485963479, 6445.355464453517], "value": 6060.567464699874, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [839.4879123734522, 868.1058509734974, 1108.1467270201945, 1115.4478533126103, 1181.858177018758, 34379.82810085952, 37949.051273718134, 37995.0, 38078.23843523128, 38204.61795382045, 35892.6669766465, 36153.0, 36218.637813621855, 36246.456303155406, 36286.0, 7072.186698529671, 7153.35619794221, 7158.570485770844, 7206.0, 7207.747728829492, 7017.964910175442, 7115.324044215791, 7130.8573828523395, 7135.85728285434, 7137.678804453786, 7117.964410177942, 7169.46229032822, 7198.8560228795395, 7398.297161769623, 7682.423818213629, 7532.133804612457, 7601.12587052485, 7634.12207596128, 7646.273604007611, 7652.655630496641, 7501.437392195608, 7587.582682952436, 7602.429817763635, 7607.847843043108, 7610.048743907012, 35682.0, 35990.280194396226, 36136.63863361359, 36629.26741465156, 36727.26545469076, 3419.675130862564, 3466.2374277658873, 3473.0796338970194, 3476.356873978297, 3478.4608385700258, 29978.85010574955, 29982.25044373908, 29983.400331993238, 30045.098647040624, 30085.398292034257, 5116.974415127937, 5133.178691409394, 5137.023965446553, 5141.742912854343, 5175.249588809639, 177765.0, 177771.0, 178729.0, 179014.0, 179663.0, 39671.40492892609, 40089.0, 45020.3246951296, 45137.77431112823, 45165.322520162226, 41552.0, 41632.58367416348, 41665.79167104145, 47753.28370074452, 49195.0, 15705.0, 15822.920885395499, 15861.286242119133, 15940.202989850693, 16005.599860003593, 13064.673383165496, 13131.409086591126, 13139.0, 13139.014573906805, 13143.737125257632, 13527.797083043763, 13608.93195534016, 13630.250336231602, 13650.0, 13720.0, 5676.858078547987, 5721.170430287585, 5721.485066344049, 5794.565407594448, 5836.387179346192, 971.4365667912593, 993.5082134343546, 996.531630133836, 1011.438651548383, 1019.7552587378975, 22281.777182228296, 22330.441738956655, 22411.775882241294, 22473.21343752961, 22917.770822291564, 1071.7802850415646, 1075.854759607443, 1100.4827730966433, 1108.318384193715, 1113.3820729495046, 11814.88185118155, 11833.230839995373, 11996.220245684002, 12000.759984800256, 12027.15809893299, 228114.0, 231034.0, 231790.0, 232058.0, 232252.0, 47352.0, 47732.0, 47925.28112078321, 48060.759696201996, 48277.5172248273, 1396.5112210726368, 1425.4796999095256, 1443.9061461005, 1444.415011920174, 1444.4799872046055, 21653.0, 21730.044296013435, 21906.123755050256, 21931.342059738232, 22279.0, 35556.28887422186, 35701.82149089238, 35754.28491430209, 35759.46360804538, 35762.28475430529, 189331.0, 189580.0, 189684.0, 189747.0, 190791.0, 29991.0, 32047.679523205403, 32869.68521259123, 33120.50319245165, 33358.83320583381, 39953.40069898896, 40064.3990340151, 40602.0, 41311.0, 41331.7933410343, 30089.849550752107, 30369.696303036686, 30400.0, 30432.543511847784, 30638.540421893245, 29474.705252947195, 29747.107586772432, 29838.0, 30008.399832003674, 30424.391512170074, 33847.66152338544, 33918.491222632176, 33987.32025359529, 34121.48817767785, 35838.64161358457, 30829.38341233118, 30838.229044274074, 31596.526052108777, 31793.205169870937, 31843.84078079595, 23467.530649386576, 23565.882170589037, 23650.52698945977, 23664.52670946606, 24082.759172408754], "value": 16497.227719207596, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.2268535393125, 555.469526602095, 559.1110134885531, 564.1622191046296, 569.2599620493359, 21178.152873885047, 21694.240701575447, 21797.34607961762, 21805.0, 22386.552268954612, 24548.140815071478, 25183.118590849324, 25194.496110077787, 25279.62080568793, 25317.62023569648, 6413.230412350513, 6562.081308616797, 6594.670266486681, 6597.67011649418, 6601.471882249409, 6569.93430065698, 6583.901241481358, 6616.404523592876, 6639.435647969928, 6641.734330626769, 6130.080487926802, 6379.904301435482, 6406.93593064068, 6412.0, 6419.679016049179, 6435.0, 6470.838229044264, 6473.029045643168, 6475.0, 6479.546431749779, 5976.581639285252, 5989.2812862456785, 5990.580659353847, 5996.13056106862, 5997.190379298782, 27645.723542764517, 28030.719692803217, 28160.155195344174, 28667.426651466852, 28678.426431471464, 2040.2247146084542, 2075.6678931370907, 2076.6365885970044, 2077.1587507059667, 2083.5103750618746, 18405.907970460197, 18850.434486965274, 18862.0, 18900.905495472573, 18921.7161742574, 2302.125192426867, 2341.3444235613883, 2363.976360236393, 2364.798992085675, 2391.629297458897, 125193.0, 128466.7153328464, 130064.0, 132791.33604332013, 133371.0, 13730.725385492235, 13836.584902452943, 14138.575842724735, 14412.207328596818, 14810.629734256514, 20241.797582024286, 22424.88787556052, 22477.662835057487, 22539.661905071436, 22717.0, 8798.736037918872, 9635.470049147365, 9845.310828242098, 10116.494175291205, 10136.493175341204, 9244.168024877794, 9282.443053416817, 9295.860562091573, 9325.813483730288, 9466.148046675695, 8698.0, 8931.285497160341, 9054.909450905407, 9106.499142547224, 9129.406588571721, 2539.5555777738823, 2625.317417471433, 2746.6978632350424, 2785.7353551412584, 2841.289677580605, 937.6436953957452, 955.6655170690203, 973.99026009739, 976.5166242709904, 981.6318880419842, 19351.4194574163, 19432.70850937237, 19992.000399979945, 20006.49983750418, 20248.898755506423, 1001.1790331927895, 1023.5394072667181, 1024.2676486312323, 1038.4911393417171, 1043.9634612788516, 11195.328280303207, 11253.268537545197, 11348.602798901999, 11361.261518001304, 11436.771264574661, 456218.0, 459201.0, 459774.0, 463820.0, 479031.0, 104042.0, 104356.47821760843, 104766.95233047572, 106238.0, 110572.0, 1115.3475216998147, 1155.2779512804498, 1169.7543515861594, 1173.6948393417772, 1178.2753606531921, 17434.73847892308, 17468.21393037291, 17581.29674812993, 17680.11599419998, 17753.467395978143, 50798.49201507938, 51862.740686296325, 52610.73694631655, 53057.46942530525, 54021.72989135029, 70207.0, 72045.0, 72071.0, 72454.27545724476, 73001.0, 14231.64420889486, 14632.414703411745, 14690.338934747748, 14858.479953201586, 14990.550283491513, 23099.88450057739, 23320.0, 23391.0, 23504.059837606987, 23648.054077837383, 18247.0, 18765.6246875059, 18923.337683181024, 18966.336178233694, 18971.43085707431, 15587.454439094578, 16215.189240538162, 16790.24443900052, 17278.0, 17425.651486970444, 18822.24711011544, 19293.517662058, 19664.901675492103, 19759.901200493907, 19772.60454790925, 14249.358778854768, 14378.0, 14450.927745361207, 14552.781708274597, 14709.117452952856, 18490.16794244289, 18547.0726463679, 18575.0, 18691.532711682317, 18755.531111722317], "value": 11775.309931196272, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.6445059833845, 1372.9519466818663, 1376.2980879751324, 1378.0, 1381.9723605527884, 14039.298035098243, 14740.705185896275, 14743.189124598142, 14756.557303280892, 14777.408903643869, 13876.236806975612, 14458.493952711657, 15334.923325383357, 15355.846441535581, 15356.46252381167, 2615.738426157388, 2654.960175597358, 2676.4647070585866, 2686.758191762751, 2690.2870739254113, 2530.7342729013467, 2669.8798554065015, 2670.4258584404347, 2671.9599206011926, 2673.6925253595796, 2573.279481745105, 2602.2843717977516, 2769.819961702493, 2780.0964686476873, 2781.1795520321452, 2702.297402675299, 2709.4581083783223, 2719.9592006119924, 2731.945361092777, 2740.0135951057605, 2509.121807367425, 2532.9493410131695, 2545.300042488312, 2546.376137846221, 2556.948861022769, 14052.437902483889, 14135.71728565423, 14275.21486318252, 14435.0, 14455.060421072596, 7727.96136019322, 7933.206679332021, 7965.601719914039, 8027.879581806277, 8030.996125484315, 5156.8968620627375, 5206.531412172884, 5234.895302093975, 5351.866203344908, 5502.0, 1739.8086210516833, 1745.9476215713548, 1765.538110864397, 1805.1876655505075, 1805.9796215138406, 24289.0, 24768.0, 24781.25656230316, 24940.12709555157, 25017.874910625513, 6538.1827271591055, 6684.598924064571, 6712.496562757813, 6870.965645171842, 7109.395701365442, 18534.16596253172, 18558.44324670262, 18559.0, 18559.628807423775, 18567.071646417626, 7871.134175240719, 7902.841943161105, 7907.802304942423, 7908.604569771488, 7942.126366099725, 7008.26413226614, 7112.110986126734, 7226.6386680665755, 7237.493375463772, 7250.9637451812405, 4734.431868175841, 4902.607791376681, 4913.410390753061, 4936.753162341868, 4944.851654450372, 1296.5591698822445, 1299.5503475947717, 1329.7805862032656, 1344.2405041151717, 1366.2007725480705, 875.1117615620151, 885.8139790643908, 897.7261935109855, 910.6812615584493, 912.3886995712888, 8410.957945210235, 8456.154384561494, 8574.399792014623, 8589.871151932726, 8717.651293948298, 972.0, 976.8534719792088, 981.6220755009373, 989.9158571521357, 994.7910938702953, 5349.251104845324, 5448.210009548593, 5496.835094947158, 5545.195946587803, 5575.916361254584, 20023.0, 20533.89733051355, 20601.58796824055, 20668.0, 20804.583908322053, 19374.0, 20060.89969550143, 20240.696389554167, 20372.490687732927, 20417.795822041888, 10545.683629491128, 11084.833727494093, 11191.832122518168, 11205.103591712643, 11210.327380357205, 8601.440906341068, 8650.567471626267, 8676.956615216883, 8749.8687519686, 8824.911750882666, 16300.184990750178, 16972.321107155574, 17887.105644717973, 18026.549336266173, 18206.72689909679, 16511.83488165103, 16893.577660558094, 16901.32394704198, 17327.826721733127, 17482.562935926195, 11468.770624587629, 11780.05759539252, 11821.527138914691, 11839.76320473603, 11844.644660660195, 11941.940290298493, 12243.632691019284, 12319.876801231872, 12448.502059917502, 12630.936845315715, 10761.30051546647, 10912.836307455236, 11193.552257909592, 11272.267302625303, 11435.656930292105, 10586.576536938435, 10664.733381665521, 10687.30532515384, 10743.570257189625, 10809.405482698527, 9881.703548893545, 9962.402255864672, 9996.900030999888, 10010.949945250228, 10013.048760367754, 7216.603086830275, 7267.927320726725, 7329.340359367682, 7387.0, 7461.403087753075, 7431.29402706742, 7460.776176714708, 7523.247675232433, 7540.585267810323, 7562.546247225184], "value": 6531.473937949879, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.5164068021521, 500.7815377876009, 519.5999080707854, 520.3677531798863, 524.355043296745, 6285.5914365566205, 6310.8106756797315, 6358.0, 6358.745650173987, 6378.425941665249, 6048.939510604903, 6125.488549694626, 6223.719932603032, 6230.127782110497, 6241.282252540959, 4975.179095449246, 5030.440563425332, 5045.924311135336, 5074.517920797518, 5086.313347698069, 4748.548887855648, 4929.753512324387, 4968.70187788732, 5029.421616514092, 5064.417591976933, 4832.395950506187, 4843.4187897452175, 4856.732879691615, 4930.778114984834, 4947.901041979158, 4918.655694101414, 4951.480094590071, 4989.675671081368, 5025.246213068032, 5032.84901452957, 4373.650107991353, 4422.756748378838, 4436.112777444524, 4439.0, 4472.731636101845, 6053.486628342915, 6064.211652485193, 6097.512199024068, 6118.908216376758, 6202.162708034402, 5692.231548740909, 5706.914396284059, 5713.200151978726, 5719.342275638313, 5722.226109905942, 6491.0, 6536.182977127859, 6554.672266386661, 6582.979638156045, 6609.801705948829, 4788.162071637449, 4796.208625576775, 4814.761136071708, 4837.153498137847, 4853.878653033667, 6780.593164410102, 6870.076378613996, 6907.861842763167, 6929.480288978297, 6935.491930245381, 4985.501449855058, 5017.297578338962, 5020.221865610836, 5021.0, 5034.647574669812, 5014.498550144956, 5029.7736601852275, 5056.216286475602, 5078.720670363092, 5098.515641014098, 5611.915821262684, 5687.601867869293, 5713.685747283939, 5727.083666613322, 5741.971290143606, 6540.803775886731, 6581.243157037002, 6581.769638062645, 6635.052143974328, 6675.933240667531, 1748.2744660965557, 1748.370586588827, 1774.1218097042047, 1779.421687951421, 1790.274938649849, 1111.1000089927186, 1162.610525473959, 1194.5102507971865, 1226.7301193737364, 1234.6357824441811, 5774.971125144431, 5777.653340799566, 5822.7088645567555, 5823.388544202798, 5827.184194212814, 6510.869782604322, 6523.510736694767, 6528.759535688204, 6561.639109849004, 6600.5049621278595, 5640.492355687926, 5667.348254950651, 5710.086386178192, 5748.281464816898, 5756.798512052058, 3500.1949551603275, 3544.379733546619, 3548.5450965104183, 3554.289142171576, 3580.337637537064, 6532.0, 6583.83540411484, 6593.14289142418, 6633.6019838809825, 6678.666066696645, 6839.2818754031105, 6858.0, 6955.46979664473, 6978.371946524736, 6981.232064472904, 6511.804645860537, 6534.248561415498, 6566.4418524425955, 6571.5399922005, 6622.403983641495, 6487.837804054936, 6599.60402375859, 6642.83392915181, 6648.867022659423, 6730.93269067303, 6520.706568204537, 6629.502787290876, 6630.7679231226675, 6653.866922661617, 6717.798466046026, 6422.233885681475, 6427.871442571216, 6483.481321494175, 6489.7404103835315, 6544.083828263952, 4935.766058485378, 4937.0, 4968.751562421937, 4974.806046548874, 4995.450500444876, 6596.670166491752, 6628.5691430056895, 6661.867482528079, 6671.33286671329, 6698.56459330142, 6457.6771161442675, 6469.935300646934, 6491.415772580491, 6520.804375868732, 6550.443212327005, 6355.713992870239, 6429.22849258092, 6454.838629034311, 6469.320721324193, 6509.349065093453, 5783.421657834183, 6376.904346434714, 6404.391582799627, 6457.0, 6468.935310646833, 6465.77369792055, 6494.0, 6502.414782669583, 6510.493460017832, 6628.171478565179, 6235.563510554218, 6243.531735119975, 6243.906341404791, 6295.842603934938, 6317.810465686037], "value": 5037.3482174667715, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [838.5807096451774, 841.2975165736609, 855.1192271959882, 861.8104017116232, 867.301822033263, 19231.807681923176, 20066.598668026632, 20073.397798066046, 20076.89961550192, 20101.296454624055, 19690.409287721355, 20181.394558163243, 20366.592668146666, 20400.489987750276, 20422.183112675513, 928.2852203803068, 945.9574319155655, 951.1487218939045, 954.6372378496162, 954.6944977607169, 860.4407135362002, 947.3747326764337, 955.1308309438413, 961.6345788600357, 966.5940305071887, 987.4667679453087, 1002.8395456726926, 1003.1072345612411, 1004.1615251265166, 1004.3572113847143, 996.7408473796829, 998.6305067125138, 999.0159692702682, 1000.4047591682937, 1000.4897502273857, 733.7761982595306, 735.4263674334028, 739.268124556685, 740.6555951482533, 740.7481456304828, 19176.328828490936, 19778.406647800588, 19887.20451181951, 20125.0, 20149.597008059758, 18216.544586385313, 18395.908020459945, 18955.71566426491, 18998.905005474884, 19041.0, 16766.32934682611, 17013.65972680552, 17161.65676686459, 17214.31142754288, 17263.395781147727, 3650.1787097903025, 3685.318216129998, 3704.111013356802, 3710.5732840723394, 3710.7402481826284, 126547.36726316402, 128664.35667821695, 128980.71019289781, 131714.0, 132298.0, 15358.923205383902, 15436.45972390961, 15819.446319378767, 15898.125603091941, 16129.354825807071, 29332.853335733613, 29597.112086637437, 29921.251968700522, 29965.850170749007, 30348.69651303503, 8554.957225213833, 8557.058723540405, 8587.184217499327, 8606.31149508038, 8624.870626940601, 3913.72603917723, 4008.659263962596, 4009.258287216874, 4034.919301613951, 4035.9596404035583, 5039.874003149951, 5199.584033277329, 5270.60470464709, 5289.047971365114, 5305.416404195613, 935.2050756856753, 937.6858752317913, 954.9686338754129, 956.220680145679, 961.0725649748026, 652.5758257132807, 669.3039239191284, 671.5131529640971, 673.5520878615764, 684.165318311659, 11383.203175777782, 11431.397625994205, 11504.137189710806, 11803.937645611935, 12623.747525049448, 661.4939571228015, 664.1930054983219, 665.710415969055, 681.7170874087199, 692.6813665713828, 5369.57043436532, 5476.123820188751, 5988.221531200916, 6239.344868788802, 6248.781292654826, 105469.47265263778, 106779.0, 108334.45832770786, 108425.4578727117, 108516.0, 96588.51705741427, 97740.51129744305, 98530.0, 98882.0, 99882.0, 80258.0, 80604.59697701591, 81827.9999999988, 82431.0, 82628.0, 12420.75158496807, 12449.502019919102, 12601.684957876127, 12821.871781282067, 12976.545820896223, 37435.6256437443, 37723.0, 38665.22669546537, 38710.64512742041, 38940.610593893696, 48854.26718599295, 49809.25286120784, 50061.74969125131, 51420.74289628528, 51505.742471287405, 16599.91700041492, 16895.0, 17007.234674439926, 17013.57466063358, 17095.0, 20157.395278141677, 20562.794372056687, 20941.476463088544, 20955.790442095386, 21288.0, 18087.728684069487, 18636.0, 18846.717299240798, 18919.71620425667, 19077.809221907602, 18500.352487663407, 19188.71216931775, 19272.518187044876, 19363.12865921065, 19527.0, 18062.0, 18441.63116737631, 18852.905735471235, 19085.427437176906, 19281.51796205106, 12936.805947910601, 13248.138870973356, 13548.661283467993, 13634.0, 13925.582232533041, 14256.144631322153, 14256.786148207993, 14384.928075359556, 14530.927345363205, 14564.927175364055], "value": 8921.974226732389, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1515.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 111.0, 111.0, 108.0, 113.0, 110.0, 107.0, 99.0, 114.0, 108.0], "value": 108.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 126.0, 143.0, 176.0, 122.0, 154.0, 144.0, 117.0, 176.0, 119.0], "value": 142.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 167.0, 164.0, 169.0, 164.0, 166.0, 176.0, 162.0, 164.0, 174.0], "value": 167.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 74.0, 179.0, 75.0, 77.0, 74.0, 88.0, 98.0, 75.0], "value": 89.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 85.0, 135.0, 81.0, 80.0, 78.0, 136.0, 79.0, 88.0, 130.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 118.0, 124.0, 116.0, 114.0, 116.0, 114.0, 117.0, 118.0, 114.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 157.0, 151.0, 153.0, 150.0, 151.0, 149.0, 147.0, 148.0], "value": 150.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 98.0, 102.0, 97.0, 102.0, 95.0, 97.0, 97.0, 96.0], "value": 97.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 58.0, 52.0, 52.0, 57.0, 54.0, 51.0, 60.0, 52.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 38.0, 41.0, 48.0, 43.0, 50.0, 38.0, 38.0, 38.0, 41.0], "value": 41.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 84.0, 77.0, 79.0, 81.0, 86.0, 84.0, 80.0, 76.0, 87.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 135.0, 121.0, 128.0, 122.0, 119.0, 120.0, 130.0, 116.0, 149.0], "value": 126.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 168.0, 172.0, 179.0, 173.0, 171.0, 171.0, 171.0, 185.0, 175.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 75.0, 67.0, 65.0, 66.0, 69.0, 65.0, 68.0, 65.0, 70.0], "value": 67.3, "unit": "score"}]}]} 11:33:17 INFO - Return code: 0 11:33:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:33:17 INFO - # TBPL SUCCESS # 11:33:17 INFO - Running post-action listener: _package_coverage_data 11:33:17 INFO - Running post-action listener: _resource_record_post_action 11:33:17 INFO - [mozharness: 2017-08-29 18:33:17.088289Z] Finished run-tests step (success) 11:33:17 INFO - Running post-run listener: _resource_record_post_run 11:33:17 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 3186688; Write bytes: 440471552; Read time: 3928; Write time: 615744 11:33:17 INFO - TinderboxPrint: CPU usage
13.3% 11:33:17 INFO - TinderboxPrint: I/O read bytes / time
3,186,688 / 3,928 11:33:17 INFO - TinderboxPrint: I/O write bytes / time
440,471,552 / 615,744 11:33:17 INFO - TinderboxPrint: CPU idle
8,288.9 (86.7%) 11:33:17 INFO - TinderboxPrint: CPU user
1,203.6 (12.6%) 11:33:17 INFO - TinderboxPrint: Swap in / out
0 / 0 11:33:17 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 105029632; Read time: 0; Write time: 136028 11:33:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:33:17 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 3182592; Write bytes: 335437824; Read time: 3904; Write time: 479628 11:33:17 INFO - Running post-run listener: _upload_blobber_files 11:33:17 INFO - Blob upload gear active. 11:33:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:33:17 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 11:33:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:33:18 INFO - (blobuploader) - INFO - Open directory for files ... 11:33:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:33:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:33:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:33:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:33:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:33:19 INFO - (blobuploader) - INFO - Done attempting. 11:33:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:33:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:33:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:33:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:33:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:33:25 INFO - (blobuploader) - INFO - Done attempting. 11:33:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:33:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:33:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:33:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:33:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:33:26 INFO - (blobuploader) - INFO - Done attempting. 11:33:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:33:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:33:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:33:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:33:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:33:27 INFO - (blobuploader) - INFO - Done attempting. 11:33:27 INFO - (blobuploader) - INFO - Iteration through files over. 11:33:27 INFO - Return code: 0 11:33:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:33:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:33:27 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66425f69e410976d621eb2664e8d043adfa2b6cc9053cda4509639411c104deedbc9e46cd58c551ed2470acd7a2d15387afeea517e4ce547aa553c890c134b6a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/469ddd87e2b5394bea5d021e17e490a2c40548b1d40737e1f362ea5b527dda140ea4646445afd91e6a5f23cebd8a4acc929779fb6383fcbcbff562f93e5f0c9b", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e09e1933f754637e25bc312a5ccd04d6cd42ef07469fc9144dafd62e8d800f1961b223d744c3463ea7849b358834a464b5773ca21a3f2fa33707787280057e5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5a7937b2463ac0ab68f73d7b4fdc217964f2f6ac129c678b6e4ff8d396712b92024ab3371320e718bcffe22ce4d53ee71d22e625d853f2260f9316e5ca826c5"} 11:33:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:33:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:33:27 INFO - Contents: 11:33:27 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66425f69e410976d621eb2664e8d043adfa2b6cc9053cda4509639411c104deedbc9e46cd58c551ed2470acd7a2d15387afeea517e4ce547aa553c890c134b6a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/469ddd87e2b5394bea5d021e17e490a2c40548b1d40737e1f362ea5b527dda140ea4646445afd91e6a5f23cebd8a4acc929779fb6383fcbcbff562f93e5f0c9b", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e09e1933f754637e25bc312a5ccd04d6cd42ef07469fc9144dafd62e8d800f1961b223d744c3463ea7849b358834a464b5773ca21a3f2fa33707787280057e5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5a7937b2463ac0ab68f73d7b4fdc217964f2f6ac129c678b6e4ff8d396712b92024ab3371320e718bcffe22ce4d53ee71d22e625d853f2260f9316e5ca826c5"} 11:33:27 INFO - Running post-run listener: copy_logs_to_upload_dir 11:33:27 INFO - Copying logs to upload dir... 11:33:27 INFO - mkdir: /builds/slave/test/build/upload/logs 11:33:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.355463 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-08-29 11:33:27.756443) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 11:33:27.761066) ========= 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=df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66425f69e410976d621eb2664e8d043adfa2b6cc9053cda4509639411c104deedbc9e46cd58c551ed2470acd7a2d15387afeea517e4ce547aa553c890c134b6a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/469ddd87e2b5394bea5d021e17e490a2c40548b1d40737e1f362ea5b527dda140ea4646445afd91e6a5f23cebd8a4acc929779fb6383fcbcbff562f93e5f0c9b", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e09e1933f754637e25bc312a5ccd04d6cd42ef07469fc9144dafd62e8d800f1961b223d744c3463ea7849b358834a464b5773ca21a3f2fa33707787280057e5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5a7937b2463ac0ab68f73d7b4fdc217964f2f6ac129c678b6e4ff8d396712b92024ab3371320e718bcffe22ce4d53ee71d22e625d853f2260f9316e5ca826c5"} build_url:https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011315 build_url: 'https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66425f69e410976d621eb2664e8d043adfa2b6cc9053cda4509639411c104deedbc9e46cd58c551ed2470acd7a2d15387afeea517e4ce547aa553c890c134b6a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/469ddd87e2b5394bea5d021e17e490a2c40548b1d40737e1f362ea5b527dda140ea4646445afd91e6a5f23cebd8a4acc929779fb6383fcbcbff562f93e5f0c9b", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e09e1933f754637e25bc312a5ccd04d6cd42ef07469fc9144dafd62e8d800f1961b223d744c3463ea7849b358834a464b5773ca21a3f2fa33707787280057e5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5a7937b2463ac0ab68f73d7b4fdc217964f2f6ac129c678b6e4ff8d396712b92024ab3371320e718bcffe22ce4d53ee71d22e625d853f2260f9316e5ca826c5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 11:33:27.790509) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:33:27.790905) ========= 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=df17445c3590ff7897617a0e00000225-1504027515.411871-1875128512 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:33:27.874646) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 11:33:27.875032) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 11:33:27.875428) ========= ========= Total master_lag: 0.36 =========