builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-042 starttime: 1504030290.95 results: success (0) revision: e336d84fc1d2d1fde7387dd5f86fe06fa59abe10 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 11:11:30.953646) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 11:11:30.954081) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 11:11:30.954348) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 11:11:31.022704) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:11:31.023054) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-29 11:11:30-- 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% 78.4M=0s 2017-08-29 11:11:31 (78.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.179632 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:11:31.228141) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:11:31.228501) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.064464 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:11:31.309405) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:11:31.312779) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-29 11:11:31,161 truncating revision to first 12 chars 2017-08-29 11:11:31,161 Setting DEBUG logging. 2017-08-29 11:11:31,161 attempt 1/10 2017-08-29 11:11:31,161 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:11:31,600 unpacking tar archive at: mozilla-central-e336d84fc1d2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.748802 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:11:32.079696) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 11:11:32.080164) ========= script_repo_revision: e336d84fc1d2d1fde7387dd5f86fe06fa59abe10 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 11:11:32.080647) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 11:11:32.080991) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 11:11:32.093506) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-08-29 11:11:32.093906) ========= /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=74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:11:32 INFO - MultiFileLogger online at 20170829 11:11:32 in /builds/slave/test 11:11:32 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:11:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:11:32 INFO - {'append_to_log': False, 11:11:32 INFO - 'base_work_dir': '/builds/slave/test', 11:11:32 INFO - 'blob_upload_branch': 'Firefox', 11:11:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:11:32 INFO - 'branch': 'Firefox', 11:11:32 INFO - 'buildbot_json_path': 'buildprops.json', 11:11:32 INFO - 'code_coverage': False, 11:11:32 INFO - 'config_files': ('talos/linux_config.py',), 11:11:32 INFO - 'default_actions': ('clobber', 11:11:32 INFO - 'read-buildbot-config', 11:11:32 INFO - 'download-and-extract', 11:11:32 INFO - 'populate-webroot', 11:11:32 INFO - 'create-virtualenv', 11:11:32 INFO - 'install', 11:11:32 INFO - 'setup-mitmproxy', 11:11:32 INFO - 'run-tests'), 11:11:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:11:32 INFO - 'disable_ccov_upload': False, 11:11:32 INFO - 'download_minidump_stackwalk': True, 11:11:32 INFO - 'download_symbols': 'ondemand', 11:11:32 INFO - 'enable_stylo': False, 11:11:32 INFO - 'enable_webrender': False, 11:11:32 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:11:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:11:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:11:32 INFO - 'gecko_profile': False, 11:11:32 INFO - 'gecko_profile_interval': 0, 11:11:32 INFO - 'installer_path': 'installer.exe', 11:11:32 INFO - 'log_level': 'info', 11:11:32 INFO - 'log_name': 'talos', 11:11:32 INFO - 'log_to_console': True, 11:11:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:11:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:11:32 INFO - 'opt_config_files': (), 11:11:32 INFO - 'pip_index': False, 11:11:32 INFO - 'suite': 'dromaeojs-e10s', 11:11:32 INFO - 'system_bits': '32', 11:11:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:11:32 INFO - 'title': 'talos-linux64-ix-042', 11:11:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:11:32 INFO - 'use_talos_json': True, 11:11:32 INFO - 'verify': 'False', 11:11:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:11:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:11:32 INFO - 'work_dir': 'build'} 11:11:32 INFO - [mozharness: 2017-08-29 18:11:32.183977Z] Running clobber step. 11:11:32 INFO - Running pre-action listener: _resource_record_pre_action 11:11:32 INFO - Running main action method: clobber 11:11:32 INFO - rmtree: /builds/slave/test/build 11:11:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:11:34 INFO - Running post-action listener: _resource_record_post_action 11:11:34 INFO - [mozharness: 2017-08-29 18:11:34.531385Z] Finished clobber step (success) 11:11:34 INFO - [mozharness: 2017-08-29 18:11:34.531496Z] Running read-buildbot-config step. 11:11:34 INFO - Running pre-action listener: _resource_record_pre_action 11:11:34 INFO - Running main action method: read_buildbot_config 11:11:34 INFO - Using buildbot properties: 11:11:34 INFO - { 11:11:34 INFO - "project": "", 11:11:34 INFO - "product": "firefox", 11:11:34 INFO - "who": "ffxbld@noreply.mozilla.org", 11:11:34 INFO - "installer_path": "public/build/target.tar.bz2", 11:11:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 11:11:34 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:11:34 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 11:11:34 INFO - "stage_platform": "linux64-stylo", 11:11:34 INFO - "basedir": "/builds/slave/test", 11:11:34 INFO - "buildnumber": 62, 11:11:34 INFO - "platform": "ubuntu64_hw_stylo", 11:11:34 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 11:11:34 INFO - "slavebuilddir": "test", 11:11:34 INFO - "taskId": "Ja3esleyQjuabvbBJPxFHQ", 11:11:34 INFO - "branch": "mozilla-central", 11:11:34 INFO - "script_repo_revision": "production", 11:11:34 INFO - "revision": "e336d84fc1d2d1fde7387dd5f86fe06fa59abe10", 11:11:34 INFO - "slavename": "talos-linux64-ix-042", 11:11:34 INFO - "repo_path": "mozilla-central" 11:11:34 INFO - } 11:11:34 INFO - Finding installer, test and symbols from parent task. 11:11:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ja3esleyQjuabvbBJPxFHQ'}, attempt #1 11:11:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ja3esleyQjuabvbBJPxFHQ'}, attempt #1 11:11:35 INFO - Task dependencies: J1Wwa5wdSOSum0rQh28Tpg 11:11:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg'}, attempt #1 11:11:35 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.tar.bz2 11:11:36 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.test_packages.json 11:11:36 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.crashreporter-symbols.zip 11:11:36 INFO - Running post-action listener: _resource_record_post_action 11:11:36 INFO - [mozharness: 2017-08-29 18:11:36.199251Z] Finished read-buildbot-config step (success) 11:11:36 INFO - [mozharness: 2017-08-29 18:11:36.199348Z] Running download-and-extract step. 11:11:36 INFO - Running pre-action listener: _resource_record_pre_action 11:11:36 INFO - Running main action method: download_and_extract 11:11:36 INFO - mkdir: /builds/slave/test/build/tests 11:11:36 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:11:36 INFO - trying https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.test_packages.json 11:11:36 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:11:36 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:11:36 INFO - Downloaded 989 bytes. 11:11:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:11:36 INFO - Using the following test package requirements: 11:11:36 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:11:36 INFO - u'common': [u'target.common.tests.zip'], 11:11:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:11:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:11:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:11:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:11:36 INFO - u'mozbase': [u'target.common.tests.zip'], 11:11:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:11:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:11:36 INFO - u'web-platform': [u'target.common.tests.zip', 11:11:36 INFO - u'target.web-platform.tests.tar.gz'], 11:11:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:11:36 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:11:36 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:11:36 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:11:36 INFO - Fetch https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.common.tests.zip into memory 11:11:37 INFO - Content-Length response header: 54977655 11:11:37 INFO - Bytes received: 54977655 11:11:49 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:11:49 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:11:49 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:11:49 INFO - Fetch https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.common.tests.zip into memory 11:11:50 INFO - Content-Length response header: 54977655 11:11:50 INFO - Bytes received: 54977655 11:12:03 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:03 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:03 INFO - Fetch https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.talos.tests.zip into memory 11:12:04 INFO - Content-Length response header: 14496611 11:12:04 INFO - Bytes received: 14496611 11:12:04 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:04 INFO - trying https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.tar.bz2 11:12:04 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:04 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:05 INFO - Downloaded 61283835 bytes. 11:12:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.tar.bz2 11:12:05 INFO - mkdir: /builds/slave/test/properties 11:12:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:12:05 INFO - Writing to file /builds/slave/test/properties/build_url 11:12:05 INFO - Contents: 11:12:05 INFO - build_url:https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.tar.bz2 11:12:05 INFO - Running post-action listener: _resource_record_post_action 11:12:05 INFO - Running post-action listener: find_tests_for_verification 11:12:05 INFO - Running post-action listener: set_extra_try_arguments 11:12:05 INFO - [mozharness: 2017-08-29 18:12:05.889901Z] Finished download-and-extract step (success) 11:12:05 INFO - [mozharness: 2017-08-29 18:12:05.890011Z] Running populate-webroot step. 11:12:05 INFO - Running pre-action listener: _resource_record_pre_action 11:12:05 INFO - Running main action method: populate_webroot 11:12:05 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:12:05 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 11:12:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:12:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:12:05 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 11:12:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:12:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:12:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:12:05 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:12:05 INFO - 'talos_options': ['--stylo'], 11:12:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:12:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:12:05 INFO - 'tests': ['damp', 'tps']}, 11:12:05 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:12:05 INFO - 'talos_options': ['--stylo'], 11:12:05 INFO - 'tests': ['damp', 'tps']}, 11:12:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:12:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:12:05 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 11:12:05 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:12:05 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:12:05 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:12:05 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:12:05 INFO - 'talos_options': ['--stylo'], 11:12:05 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:12:05 INFO - 'other-e10s': {'tests': ['a11yr', 11:12:05 INFO - 'ts_paint', 11:12:05 INFO - 'tpaint', 11:12:05 INFO - 'sessionrestore', 11:12:05 INFO - 'sessionrestore_many_windows', 11:12:05 INFO - 'sessionrestore_no_auto_restore', 11:12:05 INFO - 'tabpaint']}, 11:12:05 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 11:12:05 INFO - 'tests': ['a11yr', 11:12:05 INFO - 'ts_paint', 11:12:05 INFO - 'tpaint', 11:12:05 INFO - 'sessionrestore', 11:12:05 INFO - 'sessionrestore_many_windows', 11:12:05 INFO - 'sessionrestore_no_auto_restore', 11:12:05 INFO - 'tabpaint']}, 11:12:05 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:12:05 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:12:05 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 11:12:05 INFO - 'tests': ['bloom_basic_singleton']}, 11:12:05 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 11:12:05 INFO - 'tests': ['bloom_basic']}, 11:12:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:12:05 INFO - 'tsvgr_opacity', 11:12:05 INFO - 'tart', 11:12:05 INFO - 'tscrollx', 11:12:05 INFO - 'tsvg_static']}, 11:12:05 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 11:12:05 INFO - 'tests': ['tsvgx', 11:12:05 INFO - 'tsvgr_opacity', 11:12:05 INFO - 'tart', 11:12:05 INFO - 'tscrollx', 11:12:05 INFO - 'tsvg_static']}, 11:12:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:12:05 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:12:05 INFO - 'talos_options': ['--stylo'], 11:12:05 INFO - 'tests': ['tp5o']}, 11:12:05 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:12:05 INFO - 'talos_options': ['--mitmproxy', 11:12:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:12:05 INFO - '--firstNonBlankPaint'], 11:12:05 INFO - 'tests': ['tp6_google', 11:12:05 INFO - 'tp6_youtube', 11:12:05 INFO - 'tp6_amazon', 11:12:05 INFO - 'tp6_facebook']}, 11:12:05 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:12:05 INFO - 'talos_options': ['--stylo', 11:12:05 INFO - '--mitmproxy', 11:12:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:12:05 INFO - '--firstNonBlankPaint'], 11:12:05 INFO - 'tests': ['tp6_google', 11:12:05 INFO - 'tp6_youtube', 11:12:05 INFO - 'tp6_amazon', 11:12:05 INFO - 'tp6_facebook']}, 11:12:05 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:12:05 INFO - 'talos_options': ['--stylo', 11:12:05 INFO - '--stylo-threads=1', 11:12:05 INFO - '--mitmproxy', 11:12:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:12:05 INFO - '--firstNonBlankPaint'], 11:12:05 INFO - 'tests': ['tp6_google', 11:12:05 INFO - 'tp6_youtube', 11:12:05 INFO - 'tp6_amazon', 11:12:05 INFO - 'tp6_facebook']}, 11:12:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:12:05 INFO - 'talos_options': ['--xperf_path', 11:12:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:12:05 INFO - 'tests': ['tp5n']}, 11:12:05 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:12:05 INFO - 'talos_options': ['--stylo', 11:12:05 INFO - '--xperf_path', 11:12:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:12:05 INFO - 'tests': ['tp5n']}}} 11:12:05 INFO - Running post-action listener: _resource_record_post_action 11:12:05 INFO - [mozharness: 2017-08-29 18:12:05.905354Z] Finished populate-webroot step (success) 11:12:05 INFO - [mozharness: 2017-08-29 18:12:05.905458Z] Running create-virtualenv step. 11:12:05 INFO - Running pre-action listener: _resource_record_pre_action 11:12:05 INFO - Running main action method: create_virtualenv 11:12:05 INFO - Creating virtualenv /builds/slave/test/build/venv 11:12:05 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:12:05 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:12:05 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:12:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:12:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:12:06 INFO - Installing distribute.............................................................................................................................................................................................done. 11:12:07 INFO - Installing pip...............done. 11:12:07 INFO - Return code: 0 11:12:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:12:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:12:07 INFO - Reading from file tmpfile_stdout 11:12:07 INFO - Output received: 11:12:07 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:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:12:07 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:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:07 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:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:07 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 0x138a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1555a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f8f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f6130>, '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 0x16f7030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f74c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', '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:07 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:07 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:07 INFO - Using env: {'DISPLAY': ':0', 11:12:07 INFO - 'HOME': '/home/cltbld', 11:12:07 INFO - 'LANG': 'en_US.UTF-8', 11:12:07 INFO - 'LANGUAGE': 'en_US:en', 11:12:07 INFO - 'LOGNAME': 'cltbld', 11:12:07 INFO - 'MAIL': '/var/mail/cltbld', 11:12:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:07 INFO - 'MOZ_NO_REMOTE': '1', 11:12:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:07 INFO - 'NO_EM_RESTART': '1', 11:12:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:07 INFO - 'PWD': '/builds/slave/test', 11:12:07 INFO - 'SHELL': '/bin/bash', 11:12:07 INFO - 'SHLVL': '1', 11:12:07 INFO - 'TERM': 'linux', 11:12:07 INFO - 'TMOUT': '86400', 11:12:07 INFO - 'USER': 'cltbld', 11:12:07 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', 11:12:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:07 INFO - '_': '/tools/buildbot/bin/python'} 11:12:08 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:12:08 INFO - Downloading/unpacking pip>=1.5 11:12:08 INFO - Running setup.py egg_info for package pip 11:12:08 INFO - warning: no files found matching 'pip/cacert.pem' 11:12:08 INFO - warning: no files found matching '*.html' under directory 'docs' 11:12:08 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:12:08 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:12:08 INFO - Installing collected packages: pip 11:12:08 INFO - Found existing installation: pip 1.1 11:12:08 INFO - Uninstalling pip: 11:12:08 INFO - Successfully uninstalled pip 11:12:08 INFO - Running setup.py install for pip 11:12:08 INFO - warning: no files found matching 'pip/cacert.pem' 11:12:08 INFO - warning: no files found matching '*.html' under directory 'docs' 11:12:08 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:12:08 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:12:08 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:12:08 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:12:08 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:12:08 INFO - Successfully installed pip 11:12:08 INFO - Cleaning up... 11:12:08 INFO - Return code: 0 11:12:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:12: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:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12: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:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x138a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1555a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f8f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f6130>, '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 0x16f7030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f74c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', '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:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:12:08 INFO - Using env: {'DISPLAY': ':0', 11:12:08 INFO - 'HOME': '/home/cltbld', 11:12:08 INFO - 'LANG': 'en_US.UTF-8', 11:12:08 INFO - 'LANGUAGE': 'en_US:en', 11:12:08 INFO - 'LOGNAME': 'cltbld', 11:12:08 INFO - 'MAIL': '/var/mail/cltbld', 11:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:08 INFO - 'MOZ_NO_REMOTE': '1', 11:12:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:08 INFO - 'NO_EM_RESTART': '1', 11:12:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:08 INFO - 'PWD': '/builds/slave/test', 11:12:08 INFO - 'SHELL': '/bin/bash', 11:12:08 INFO - 'SHLVL': '1', 11:12:08 INFO - 'TERM': 'linux', 11:12:08 INFO - 'TMOUT': '86400', 11:12:08 INFO - 'USER': 'cltbld', 11:12:08 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', 11:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:08 INFO - '_': '/tools/buildbot/bin/python'} 11:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:08 INFO - Downloading/unpacking psutil>=3.1.1 11:12:08 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:12:08 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:12:08 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:12:08 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:12:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:12:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:12:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:12:10 INFO - Installing collected packages: psutil 11:12:10 INFO - Running setup.py install for psutil 11:12:10 INFO - building 'psutil._psutil_linux' extension 11:12:10 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:12:11 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:12:11 INFO - building 'psutil._psutil_posix' extension 11:12:11 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:12:11 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:12:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:12:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:12:11 INFO - Successfully installed psutil 11:12:11 INFO - Cleaning up... 11:12:11 INFO - Return code: 0 11:12:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:12: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:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12: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:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:11 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 0x138a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1555a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f8f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f6130>, '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 0x16f7030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f74c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', '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:11 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:12:11 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:12:11 INFO - Using env: {'DISPLAY': ':0', 11:12:11 INFO - 'HOME': '/home/cltbld', 11:12:11 INFO - 'LANG': 'en_US.UTF-8', 11:12:11 INFO - 'LANGUAGE': 'en_US:en', 11:12:11 INFO - 'LOGNAME': 'cltbld', 11:12:11 INFO - 'MAIL': '/var/mail/cltbld', 11:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:11 INFO - 'MOZ_NO_REMOTE': '1', 11:12:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:11 INFO - 'NO_EM_RESTART': '1', 11:12:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:11 INFO - 'PWD': '/builds/slave/test', 11:12:11 INFO - 'SHELL': '/bin/bash', 11:12:11 INFO - 'SHLVL': '1', 11:12:11 INFO - 'TERM': 'linux', 11:12:11 INFO - 'TMOUT': '86400', 11:12:11 INFO - 'USER': 'cltbld', 11:12:11 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', 11:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:11 INFO - '_': '/tools/buildbot/bin/python'} 11:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:12:11 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:12:11 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:12:11 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:12:11 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:12:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:12:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:12:12 INFO - Installing collected packages: mozsystemmonitor 11:12:12 INFO - Running setup.py install for mozsystemmonitor 11:12:13 INFO - Successfully installed mozsystemmonitor 11:12:13 INFO - Cleaning up... 11:12:13 INFO - Return code: 0 11:12:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:12:13 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:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:13 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:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:13 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 0x138a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1555a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f8f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f6130>, '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 0x16f7030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f74c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', '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:13 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:12:13 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:12:13 INFO - Using env: {'DISPLAY': ':0', 11:12:13 INFO - 'HOME': '/home/cltbld', 11:12:13 INFO - 'LANG': 'en_US.UTF-8', 11:12:13 INFO - 'LANGUAGE': 'en_US:en', 11:12:13 INFO - 'LOGNAME': 'cltbld', 11:12:13 INFO - 'MAIL': '/var/mail/cltbld', 11:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:13 INFO - 'MOZ_NO_REMOTE': '1', 11:12:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:13 INFO - 'NO_EM_RESTART': '1', 11:12:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:13 INFO - 'PWD': '/builds/slave/test', 11:12:13 INFO - 'SHELL': '/bin/bash', 11:12:13 INFO - 'SHLVL': '1', 11:12:13 INFO - 'TERM': 'linux', 11:12:13 INFO - 'TMOUT': '86400', 11:12:13 INFO - 'USER': 'cltbld', 11:12:13 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', 11:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:13 INFO - '_': '/tools/buildbot/bin/python'} 11:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:13 INFO - Downloading/unpacking jsonschema==2.5.1 11:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:12:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:12: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:12: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:12: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:12: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:12:14 INFO - Downloading functools32-3.2.3-2.tar.gz 11:12:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:12:14 INFO - warning: no files found matching '*.txt' 11:12:14 INFO - no previously-included directories found matching 'build' 11:12:14 INFO - no previously-included directories found matching 'dist' 11:12:14 INFO - no previously-included directories found matching '.git*' 11:12:14 INFO - Installing collected packages: jsonschema, functools32 11:12:14 INFO - Running setup.py install for functools32 11:12:14 INFO - warning: no files found matching '*.txt' 11:12:14 INFO - no previously-included directories found matching 'build' 11:12:14 INFO - no previously-included directories found matching 'dist' 11:12:14 INFO - no previously-included directories found matching '.git*' 11:12:14 INFO - Successfully installed jsonschema functools32 11:12:14 INFO - Cleaning up... 11:12:14 INFO - Return code: 0 11:12:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:12: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:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12: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:12:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:14 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 0x138a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1555a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f8f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f6130>, '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 0x16f7030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f74c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', '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:14 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:12:14 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:12:14 INFO - Using env: {'DISPLAY': ':0', 11:12:14 INFO - 'HOME': '/home/cltbld', 11:12:14 INFO - 'LANG': 'en_US.UTF-8', 11:12:14 INFO - 'LANGUAGE': 'en_US:en', 11:12:14 INFO - 'LOGNAME': 'cltbld', 11:12:14 INFO - 'MAIL': '/var/mail/cltbld', 11:12:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:14 INFO - 'MOZ_NO_REMOTE': '1', 11:12:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:14 INFO - 'NO_EM_RESTART': '1', 11:12:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:14 INFO - 'PWD': '/builds/slave/test', 11:12:14 INFO - 'SHELL': '/bin/bash', 11:12:14 INFO - 'SHLVL': '1', 11:12:14 INFO - 'TERM': 'linux', 11:12:14 INFO - 'TMOUT': '86400', 11:12:14 INFO - 'USER': 'cltbld', 11:12:14 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', 11:12:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:14 INFO - '_': '/tools/buildbot/bin/python'} 11:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:12:15 INFO - Cleaning up... 11:12:15 INFO - Return code: 0 11:12:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:12:15 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:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:15 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:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:15 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 0x138a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1555a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f8f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f6130>, '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 0x16f7030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f74c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', '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:15 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:12:15 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:12:15 INFO - Using env: {'DISPLAY': ':0', 11:12:15 INFO - 'HOME': '/home/cltbld', 11:12:15 INFO - 'LANG': 'en_US.UTF-8', 11:12:15 INFO - 'LANGUAGE': 'en_US:en', 11:12:15 INFO - 'LOGNAME': 'cltbld', 11:12:15 INFO - 'MAIL': '/var/mail/cltbld', 11:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:15 INFO - 'MOZ_NO_REMOTE': '1', 11:12:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:15 INFO - 'NO_EM_RESTART': '1', 11:12:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:15 INFO - 'PWD': '/builds/slave/test', 11:12:15 INFO - 'SHELL': '/bin/bash', 11:12:15 INFO - 'SHLVL': '1', 11:12:15 INFO - 'TERM': 'linux', 11:12:15 INFO - 'TMOUT': '86400', 11:12:15 INFO - 'USER': 'cltbld', 11:12:15 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', 11:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:15 INFO - '_': '/tools/buildbot/bin/python'} 11:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:15 INFO - Downloading/unpacking blobuploader==1.2.4 11:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:16 INFO - Downloading blobuploader-1.2.4.tar.gz 11:12:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:12:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:12:16 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:12:16 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:12:16 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:12:16 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:12:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:12:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:12:16 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:12:16 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:12:16 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:12:16 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:12:16 INFO - Downloading docopt-0.6.1.tar.gz 11:12:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:12:16 INFO - Installing collected packages: blobuploader, requests, docopt 11:12:16 INFO - Running setup.py install for blobuploader 11:12:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:12:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:12:17 INFO - Running setup.py install for requests 11:12:17 INFO - Running setup.py install for docopt 11:12:17 INFO - Successfully installed blobuploader requests docopt 11:12:17 INFO - Cleaning up... 11:12:17 INFO - Return code: 0 11:12:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:17 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 0x138a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1555a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f8f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f6130>, '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 0x16f7030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f74c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', '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:17 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:12:17 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:12:17 INFO - Using env: {'DISPLAY': ':0', 11:12:17 INFO - 'HOME': '/home/cltbld', 11:12:17 INFO - 'LANG': 'en_US.UTF-8', 11:12:17 INFO - 'LANGUAGE': 'en_US:en', 11:12:17 INFO - 'LOGNAME': 'cltbld', 11:12:17 INFO - 'MAIL': '/var/mail/cltbld', 11:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:17 INFO - 'MOZ_NO_REMOTE': '1', 11:12:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:17 INFO - 'NO_EM_RESTART': '1', 11:12:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:17 INFO - 'PWD': '/builds/slave/test', 11:12:17 INFO - 'SHELL': '/bin/bash', 11:12:17 INFO - 'SHLVL': '1', 11:12:17 INFO - 'TERM': 'linux', 11:12:17 INFO - 'TMOUT': '86400', 11:12:17 INFO - 'USER': 'cltbld', 11:12:17 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', 11:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:17 INFO - '_': '/tools/buildbot/bin/python'} 11:12:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:12:17 INFO - Running setup.py (path:/tmp/pip-4WdW96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:12:17 INFO - Running setup.py (path:/tmp/pip-JlSEwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:12:17 INFO - Running setup.py (path:/tmp/pip-kRx6_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:12:17 INFO - Running setup.py (path:/tmp/pip-HGEydt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:12:17 INFO - Running setup.py (path:/tmp/pip-X4ggju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:12:18 INFO - Running setup.py (path:/tmp/pip-JedtUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:12:18 INFO - Running setup.py (path:/tmp/pip-CGrLE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:12:18 INFO - Running setup.py (path:/tmp/pip-bNJHUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:12:18 INFO - Running setup.py (path:/tmp/pip-MjByVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:12:18 INFO - Running setup.py (path:/tmp/pip-ljcgpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:12:18 INFO - Running setup.py (path:/tmp/pip-XPrngV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:12:18 INFO - Running setup.py (path:/tmp/pip-Kx43QR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:12:18 INFO - Running setup.py (path:/tmp/pip-vItP1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:12:18 INFO - Running setup.py (path:/tmp/pip-fw86bq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:18 INFO - Running setup.py (path:/tmp/pip-mo7jW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:12:18 INFO - Running setup.py (path:/tmp/pip-ZTbXH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:12:18 INFO - Running setup.py (path:/tmp/pip-9ZKm91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:12:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:12:18 INFO - Running setup.py install for manifestparser 11:12:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Running setup.py install for mozcrash 11:12:19 INFO - Running setup.py install for mozdebug 11:12:19 INFO - Running setup.py install for mozdevice 11:12:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Running setup.py install for mozfile 11:12:19 INFO - Running setup.py install for mozhttpd 11:12:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Running setup.py install for mozinfo 11:12:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Running setup.py install for mozInstall 11:12:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Running setup.py install for mozleak 11:12:19 INFO - Running setup.py install for mozlog 11:12:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Running setup.py install for moznetwork 11:12:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:12:20 INFO - Running setup.py install for mozprocess 11:12:20 INFO - Running setup.py install for mozprofile 11:12:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:12:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:12:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:12:20 INFO - Running setup.py install for mozrunner 11:12:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:12:20 INFO - Running setup.py install for mozscreenshot 11:12:20 INFO - Running setup.py install for moztest 11:12:20 INFO - Running setup.py install for mozversion 11:12:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:12:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:12:20 INFO - Cleaning up... 11:12:20 INFO - Return code: 0 11:12:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:20 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:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:20 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:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:20 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 0x138a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1555a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f8f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f6130>, '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 0x16f7030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f74c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', '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:20 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:12:20 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:12:20 INFO - Using env: {'DISPLAY': ':0', 11:12:20 INFO - 'HOME': '/home/cltbld', 11:12:20 INFO - 'LANG': 'en_US.UTF-8', 11:12:20 INFO - 'LANGUAGE': 'en_US:en', 11:12:20 INFO - 'LOGNAME': 'cltbld', 11:12:20 INFO - 'MAIL': '/var/mail/cltbld', 11:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:20 INFO - 'MOZ_NO_REMOTE': '1', 11:12:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:20 INFO - 'NO_EM_RESTART': '1', 11:12:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:20 INFO - 'PWD': '/builds/slave/test', 11:12:20 INFO - 'SHELL': '/bin/bash', 11:12:20 INFO - 'SHLVL': '1', 11:12:20 INFO - 'TERM': 'linux', 11:12:20 INFO - 'TMOUT': '86400', 11:12:20 INFO - 'USER': 'cltbld', 11:12:20 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', 11:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:20 INFO - '_': '/tools/buildbot/bin/python'} 11:12:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:12:20 INFO - Running setup.py (path:/tmp/pip-1cE_FX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:12:20 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:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:12:20 INFO - Running setup.py (path:/tmp/pip-0s1y6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:12:21 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:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:12:21 INFO - Running setup.py (path:/tmp/pip-dWGSDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:12:21 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:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:12:21 INFO - Running setup.py (path:/tmp/pip-ypZxvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:12:21 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:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:12:21 INFO - Running setup.py (path:/tmp/pip-VtlG_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:12:21 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:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:12:21 INFO - Running setup.py (path:/tmp/pip-EFduRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:12:21 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:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:12:21 INFO - Running setup.py (path:/tmp/pip-rtKiei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:12:21 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:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:12:21 INFO - Running setup.py (path:/tmp/pip-4dj86z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:12:21 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:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:12:21 INFO - Running setup.py (path:/tmp/pip-Dbhs9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:12:21 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:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:12:21 INFO - Running setup.py (path:/tmp/pip-7YRPaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:12:21 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:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:12:21 INFO - Running setup.py (path:/tmp/pip-FfwgiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:12:21 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:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:12:21 INFO - Running setup.py (path:/tmp/pip-UqYUOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:12:21 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:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:12:21 INFO - Running setup.py (path:/tmp/pip-3YKVL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:12:21 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:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:12:21 INFO - Running setup.py (path:/tmp/pip-48pMmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:12:21 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:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:21 INFO - Running setup.py (path:/tmp/pip-EDckMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:21 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:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:12:21 INFO - Running setup.py (path:/tmp/pip-K0Acgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:12:22 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:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:12:22 INFO - Running setup.py (path:/tmp/pip-wZABUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:12:22 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:12:22 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:12:22 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:12:22 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:12:22 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:12:22 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:12:22 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:12:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:12:22 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:12:22 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:12:22 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:12:22 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:12:23 INFO - Downloading blessings-1.6.tar.gz 11:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:12:23 INFO - Installing collected packages: blessings 11:12:23 INFO - Running setup.py install for blessings 11:12:23 INFO - Successfully installed blessings 11:12:23 INFO - Cleaning up... 11:12:23 INFO - Return code: 0 11:12:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:12:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:12:23 INFO - Reading from file tmpfile_stdout 11:12:23 INFO - Current package versions: 11:12:23 INFO - argparse == 1.2.1 11:12:23 INFO - blessings == 1.6 11:12:23 INFO - blobuploader == 1.2.4 11:12:23 INFO - docopt == 0.6.1 11:12:23 INFO - functools32 == 3.2.3-2 11:12:23 INFO - jsonschema == 2.5.1 11:12:23 INFO - manifestparser == 1.1 11:12:23 INFO - mozInstall == 1.13 11:12:23 INFO - mozcrash == 1.0 11:12:23 INFO - mozdebug == 0.1 11:12:23 INFO - mozdevice == 0.51 11:12:23 INFO - mozfile == 1.2 11:12:23 INFO - mozhttpd == 0.7 11:12:23 INFO - mozinfo == 0.10 11:12:23 INFO - mozleak == 0.1 11:12:23 INFO - mozlog == 3.5 11:12:23 INFO - moznetwork == 0.27 11:12:23 INFO - mozprocess == 0.25 11:12:23 INFO - mozprofile == 0.28 11:12:23 INFO - mozrunner == 6.13 11:12:23 INFO - mozscreenshot == 0.1 11:12:23 INFO - mozsystemmonitor == 0.3 11:12:23 INFO - moztest == 0.8 11:12:23 INFO - mozversion == 1.4 11:12:23 INFO - psutil == 3.1.1 11:12:23 INFO - requests == 1.2.3 11:12:23 INFO - wsgiref == 0.1.2 11:12:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:23 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 0x138a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1555a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f8f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f6130>, '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 0x16f7030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f74c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', '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:23 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:12:23 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:12:23 INFO - Using env: {'DISPLAY': ':0', 11:12:23 INFO - 'HOME': '/home/cltbld', 11:12:23 INFO - 'LANG': 'en_US.UTF-8', 11:12:23 INFO - 'LANGUAGE': 'en_US:en', 11:12:23 INFO - 'LOGNAME': 'cltbld', 11:12:23 INFO - 'MAIL': '/var/mail/cltbld', 11:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:23 INFO - 'MOZ_NO_REMOTE': '1', 11:12:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:23 INFO - 'NO_EM_RESTART': '1', 11:12:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:23 INFO - 'PWD': '/builds/slave/test', 11:12:23 INFO - 'SHELL': '/bin/bash', 11:12:23 INFO - 'SHLVL': '1', 11:12:23 INFO - 'TERM': 'linux', 11:12:23 INFO - 'TMOUT': '86400', 11:12:23 INFO - 'USER': 'cltbld', 11:12:23 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', 11:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:23 INFO - '_': '/tools/buildbot/bin/python'} 11:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:23 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:12:23 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:12:23 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:12:23 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:12:23 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:12:23 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:12:23 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:12:23 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:12:23 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:12:23 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:12:23 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:12:23 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:12:23 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:12:23 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:12:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:12:25 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:12:25 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:12:25 INFO - Installing collected packages: simplejson 11:12:25 INFO - Running setup.py install for simplejson 11:12:25 INFO - building 'simplejson._speedups' extension 11:12:25 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:12:25 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:12:26 INFO - Successfully installed simplejson 11:12:26 INFO - Cleaning up... 11:12:26 INFO - Return code: 0 11:12:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:12:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x138a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1555a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f8f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f6130>, '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 0x16f7030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f74c0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', '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:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:12:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:12:26 INFO - Using env: {'DISPLAY': ':0', 11:12:26 INFO - 'HOME': '/home/cltbld', 11:12:26 INFO - 'LANG': 'en_US.UTF-8', 11:12:26 INFO - 'LANGUAGE': 'en_US:en', 11:12:26 INFO - 'LOGNAME': 'cltbld', 11:12:26 INFO - 'MAIL': '/var/mail/cltbld', 11:12:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:26 INFO - 'MOZ_NO_REMOTE': '1', 11:12:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:26 INFO - 'NO_EM_RESTART': '1', 11:12:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:26 INFO - 'PWD': '/builds/slave/test', 11:12:26 INFO - 'SHELL': '/bin/bash', 11:12:26 INFO - 'SHLVL': '1', 11:12:26 INFO - 'TERM': 'linux', 11:12:26 INFO - 'TMOUT': '86400', 11:12:26 INFO - 'USER': 'cltbld', 11:12:26 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', 11:12:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:26 INFO - '_': '/tools/buildbot/bin/python'} 11:12:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:12:26 INFO - Cleaning up... 11:12:26 INFO - Return code: 0 11:12:26 INFO - Running post-action listener: _resource_record_post_action 11:12:26 INFO - Running post-action listener: _start_resource_monitoring 11:12:26 INFO - Starting resource monitoring. 11:12:26 INFO - [mozharness: 2017-08-29 18:12:26.403059Z] Finished create-virtualenv step (success) 11:12:26 INFO - [mozharness: 2017-08-29 18:12:26.404070Z] Running install step. 11:12:26 INFO - Running pre-action listener: _resource_record_pre_action 11:12:26 INFO - Running main action method: install 11:12:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:12:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:12:26 INFO - Reading from file tmpfile_stdout 11:12:26 INFO - Detecting whether we're running mozinstall >=1.0... 11:12:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:12:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:12:26 INFO - Reading from file tmpfile_stdout 11:12:26 INFO - Output received: 11:12:26 INFO - Usage: mozinstall [options] installer 11:12:26 INFO - Options: 11:12:26 INFO - -h, --help show this help message and exit 11:12:26 INFO - -d DEST, --destination=DEST 11:12:26 INFO - Directory to install application into. [default: 11:12:26 INFO - "/builds/slave/test"] 11:12:26 INFO - --app=APP Application being installed. [default: firefox] 11:12:26 INFO - mkdir: /builds/slave/test/build/application 11:12:26 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:12:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:12:44 INFO - Reading from file tmpfile_stdout 11:12:44 INFO - Output received: 11:12:44 INFO - /builds/slave/test/build/application/firefox/firefox 11:12:44 INFO - Running post-action listener: _resource_record_post_action 11:12:44 INFO - [mozharness: 2017-08-29 18:12:44.453927Z] Finished install step (success) 11:12:44 INFO - [mozharness: 2017-08-29 18:12:44.454208Z] Running setup-mitmproxy step. 11:12:44 INFO - Running pre-action listener: _resource_record_pre_action 11:12:44 INFO - Running main action method: setup_mitmproxy 11:12:44 INFO - Skipping: mitmproxy is not required 11:12:44 INFO - Running post-action listener: _resource_record_post_action 11:12:44 INFO - [mozharness: 2017-08-29 18:12:44.455329Z] Finished setup-mitmproxy step (success) 11:12:44 INFO - [mozharness: 2017-08-29 18:12:44.455546Z] Running run-tests step. 11:12:44 INFO - Running pre-action listener: _resource_record_pre_action 11:12:44 INFO - Running pre-action listener: _set_gcov_prefix 11:12:44 INFO - Running main action method: run_tests 11:12:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:12:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:12:44 INFO - Python 2.7.3 11:12:44 INFO - Return code: 0 11:12:44 INFO - grabbing minidump binary from tooltool 11:12:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:44 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 0x16f6130>, '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 0x16f7030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f74c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:12:44 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:12:44 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:12:44 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:12:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:12:44 INFO - Return code: 0 11:12:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:12:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:12:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:12:44 INFO - ENV: RUST_BACKTRACE is now full 11:12:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:12:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:12:44 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-042', '--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:12:44 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-042 --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:12:44 INFO - Using env: {'DISPLAY': ':0', 11:12:44 INFO - 'HOME': '/home/cltbld', 11:12:44 INFO - 'LANG': 'en_US.UTF-8', 11:12:44 INFO - 'LANGUAGE': 'en_US:en', 11:12:44 INFO - 'LOGNAME': 'cltbld', 11:12:44 INFO - 'MAIL': '/var/mail/cltbld', 11:12:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:12:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:12:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:44 INFO - 'MOZ_NO_REMOTE': '1', 11:12:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:12:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:44 INFO - 'NO_EM_RESTART': '1', 11:12:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:44 INFO - 'PWD': '/builds/slave/test', 11:12:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:12:44 INFO - 'RUST_BACKTRACE': 'full', 11:12:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:12:44 INFO - 'SHELL': '/bin/bash', 11:12:44 INFO - 'SHLVL': '1', 11:12:44 INFO - 'STYLO_FORCE_ENABLED': '1', 11:12:44 INFO - 'TERM': 'linux', 11:12:44 INFO - 'TMOUT': '86400', 11:12:44 INFO - 'USER': 'cltbld', 11:12:44 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', 11:12:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:44 INFO - '_': '/tools/buildbot/bin/python'} 11:12:44 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-042', '--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:12:44 INFO - mozversion application_buildid: 20170829172659 11:12:44 INFO - mozversion application_changeset: e336d84fc1d2d1fde7387dd5f86fe06fa59abe10 11:12:44 INFO - mozversion application_display_name: Nightly 11:12:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:12:44 INFO - mozversion application_name: Firefox 11:12:44 INFO - mozversion application_remotingname: firefox 11:12:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:12:44 INFO - mozversion application_vendor: Mozilla 11:12:44 INFO - mozversion application_version: 57.0a1 11:12:44 INFO - mozversion platform_buildid: 20170829172659 11:12:44 INFO - mozversion platform_changeset: e336d84fc1d2d1fde7387dd5f86fe06fa59abe10 11:12:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:12:44 INFO - mozversion platform_version: 57.0a1 11:12:44 INFO - using testdate: 1504030364 11:12:44 INFO - actual date: 1504030364 11:12:44 INFO - starting webserver on 'localhost:59163' 11:12:44 INFO - SUITE-START | Running 2 tests 11:12:44 INFO - TEST-START | dromaeo_css 11:12:44 INFO - Initialising browser for dromaeo_css test... 11:12:44 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59163/getInfo.html -profile /tmp/tmpNkwkZq/profile 11:12:44 INFO - TEST-INFO | started process 3464 (/builds/slave/test/build/application/firefox/firefox http://localhost:59163/getInfo.html) 11:12:52 INFO - TEST-INFO | 3464: exit 0 11:12:54 INFO - Browser initialized. 11:12:54 INFO - Running cycle 1/1 for dromaeo_css test... 11:12:54 INFO - Using env: {'DISPLAY': ':0', 11:12:54 INFO - 'HOME': '/home/cltbld', 11:12:54 INFO - 'JSGC_DISABLE_POISONING': '1', 11:12:54 INFO - 'LANG': 'en_US.UTF-8', 11:12:54 INFO - 'LANGUAGE': 'en_US:en', 11:12:54 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:12:54 INFO - 'LOGNAME': 'cltbld', 11:12:54 INFO - 'MAIL': '/var/mail/cltbld', 11:12:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:12:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:12:54 INFO - 'MOZ_CRASHREPORTER': '1', 11:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:54 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:12:54 INFO - 'MOZ_NO_REMOTE': '1', 11:12:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:12:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:54 INFO - 'NO_EM_RESTART': '1', 11:12:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:54 INFO - 'PWD': '/builds/slave/test', 11:12:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:12:54 INFO - 'RUST_BACKTRACE': 'full', 11:12:54 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:12:54 INFO - 'SHELL': '/bin/bash', 11:12:54 INFO - 'SHLVL': '1', 11:12:54 INFO - 'STYLO_FORCE_ENABLED': '1', 11:12:54 INFO - 'TERM': 'linux', 11:12:54 INFO - 'TMOUT': '86400', 11:12:54 INFO - 'USER': 'cltbld', 11:12:54 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', 11:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:54 INFO - '_': '/tools/buildbot/bin/python'} 11:12:54 INFO - TEST-INFO | started process 3651 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNkwkZq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:12:55 INFO - PID 3651 | 11:12:55 INFO - PID 3651 | (/builds/slave/test/build/application/firefox/firefox:3702): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:12:55 INFO - PID 3651 | 11:13:05 INFO - PID 3651 | RSS: Main: 173723648 11:13:05 INFO - PID 3651 | 11:16:04 INFO - PID 3651 | Cycle 1(1): loaded http://localhost:59163/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59163/tests/dromaeo/cssquery-ext.html) 11:16:05 INFO - PID 3651 | RSS: Main: 177164288 11:16:05 INFO - PID 3651 | 11:19:06 INFO - PID 3651 | Cycle 1(1): loaded http://localhost:59163/tests/dromaeo/cssquery-ext.html (next: http://localhost:59163/tests/dromaeo/cssquery-jquery.html) 11:19:07 INFO - PID 3651 | RSS: Main: 173363200 11:19:07 INFO - PID 3651 | 11:22:09 INFO - PID 3651 | Cycle 1(1): loaded http://localhost:59163/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59163/tests/dromaeo/cssquery-mootools.html) 11:22:09 INFO - PID 3651 | RSS: Main: 175341568 11:22:09 INFO - PID 3651 | 11:25:11 INFO - PID 3651 | Cycle 1(1): loaded http://localhost:59163/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59163/tests/dromaeo/cssquery-prototype.html) 11:25:12 INFO - PID 3651 | RSS: Main: 174632960 11:25:12 INFO - PID 3651 | 11:28:15 INFO - PID 3651 | Cycle 1(1): loaded http://localhost:59163/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59163/tests/dromaeo/cssquery-yui.html) 11:28:16 INFO - PID 3651 | RSS: Main: 175824896 11:28:16 INFO - PID 3651 | 11:31:18 INFO - PID 3651 | Cycle 1(1): loaded http://localhost:59163/tests/dromaeo/cssquery-yui.html (next: http://localhost:59163/tests/dromaeo/cssquery-dojo.html) 11:31:18 INFO - PID 3651 | RSS: Main: 178167808 11:31:18 INFO - PID 3651 | 11:31:18 INFO - PID 3651 | __start_tp_report 11:31:18 INFO - PID 3651 | _x_x_mozilla_page_load 11:31:18 INFO - PID 3651 | _x_x_mozilla_page_load_details 11:31:18 INFO - PID 3651 | |i|pagename|runs| 11:31:18 INFO - PID 3651 | |0;dojo.html;3307.0574886157433;3318.187044174177;3326.6174389945154;3347.715444187244;3357.1271469417943;6159.168512250845;6234.875302493953;6281.748730050798;6310.842228944279;6312.968435157829;6151.538634602412;6251.968740156293;6256;6283.905741413882;6287.717052732626;5713.857153571163;5752.539796816265;5755.942440575583;5790;5809.593328467009;5764.51001664859;5847.122931560258;5849.385814489481;5898.174255604219;5900.203472531196;5620.241267428926;5686.402927692616;5706.914396284038;5782.942170578324;5793.333766616851;5640.971795141039;5757.395473475309;5788.739506722165;5834.154047663065;5847.9415205848245;5711.885762284773;5720.48515633595;5762.423757624246;5777.740001699934;5791.652500849962;6111.786087486961;6185.474234690057;6196.349383314777;6227.408396202353;6285.968570157165;6630;6702.932970670279;6710.16122984627;6728.226253980806;6740.258571557125;6723.327667233287;6724.226713927912;6810.216825065131;6815.42068924142;6843.555168914055;6259.624422534663;6334.2398912130375;6383.936160638381;6393.552451328455;6395.328490508523;6926.099607051051;7003.474739394565;7014.649267536603;7021.262767409348;7025.262347453444;6563.901541476882;6614.768483103068;6655.86688266232;6704.363085506869;6713.597184168965;6554.246261679872;6697.196336439561;6698;6699.76550820719;6704.530682852251;6638.137042184485;6642.86714265712;6648.900266496006;6729.427998620172;6733.057371967929;6664.8000559983275;6722.394984451423;6752.729890804411;6753.425958793459;6754.3921047106;4880.682755620874;4909.582685471775;4911.607071434277;4980.701157930535;5005.624578156653;4335.869923902288;4347.239233134189;4358.346248062816;4364.890877728082;4374.606285434326;5898.4101589840675;5903.409659034148;5908.734106965197;5914.905742437577;5918.674472904032;6619.966900165468;6678.065070890079;6694;6765.019072234499;6767.796966091025;5747.396523364986;5786.94213057864;5854.385289544536;5901.026330655393;5910.4385083417865;5783.508401785895;5799.971000144972;5801.245838041112;5812.88374232513;5818.389069147764;6547.050677651714;6549.148610680581;6558.573692709959;6651.401373876278;6667.399934005963;6559.147310849559;6686.231083425274;6747.865042699021;6759.662016899234;6766.391024807693;6499.2200935887995;6541.215054193527;6585.3085426029575;6608.339166083353;6616.040674102132;6583.80248592543;6684.997250412477;6699.76550820719;6718.361755633207;6743.932560674527;6682.966585167043;6702.262751097473;6714.328567143247;6810.387065164159;6823.965880170567;6480.773172938924;6535.085088087767;6556.573822701509;6563.803085907431;6573.704183311666;6496.155499785186;6522.445592124723;6570.441512471302;6580.078788969644;6617.66911654425;6338.397852204033;6438.806835794933;6449.645269510034;6536.019597060479;6546.116274302908;6508.219013718384;6553.934460655523;6614.933850661432;6659.766908158192;6678.298778628174;6360.014197799349;6370.522210834113;6439.227292724903;6442.742290308335;6475.935240647534 11:31:18 INFO - PID 3651 | |1;ext.html;808.8867558541801;831.9334453243741;851.3870013590215;878.2841983783217;879.8768172455857;38619.613803861954;39545.60454395455;39917.401238981365;39993.200135997264;40041;35723;36082.27835443289;36094.639053609455;36106.8194659027;36153.81923090387;6832.760853370145;6843;6913.827154321157;6918.515703900729;7437.702491900318;7516;7602.809929751773;7604.961975190116;7622.885656715153;7627.847443051136;7565.3947684185405;7571.583562904038;7626.466147369686;7628.351590114847;7628.809279767995;7543.207963163871;7605.201453847321;7610.086789585258;7621.047369078865;7621.161672216052;7449.962750186269;7514.812129696801;7517.4737768356235;7524.661390237453;7532.945387645734;36430.27139457196;36576.63423365759;36614.8169259152;36652;36672.26655466876;3447.517347571342;3490.685838274542;3493.1441796759955;3497.615262321143;3502.562179727534;30440.238994025105;30483.542746858813;30575.235619109477;30629.846850765603;30661.540076898866;5094.286799848024;5156.252343410185;5159.948400516022;5220.347456567929;5223.921641175385;177104;178871;179983;180144;188520;45730.771346143054;46061;46089.30866037012;46153.846153846425;46215.537844621125;48049.27926081111;48075;48209.5179048212;48213.27680084801;48314.75842620764;15383.153926534149;15687.764683529756;15749.527514174593;15770.05379677223;15918.283677234553;13025.218486890817;13026.348682565835;13045.869541304655;13058.869411305956;13068;13544.255065971269;13578.592642220749;13608.115472494255;13612;13637.522686705917;5623.297087864017;5676.687782171937;5690.658560486384;5714.685692286881;5738;996.6760802739138;1000.1548691355792;1006.5470538257813;1008.969730908074;1010.9191264698807;20505.79494205069;20562.79437205639;20623.4844128895;20796.48008799792;21088.578228435344;1101.9063379612662;1102.2339474065607;1110.1230028277732;1111.288775183883;1115.9163062770324;11127.777444451065;11167.106631469464;11207.71980700489;11277.887221127849;11314.208005439705;226663;227694;228010;228440;228575;49411.50588494141;49625.50374496281;49688.503114968385;49690;49886.50113498891;1430.3491911180388;1431.5633731711719;1445.1184777285848;1446.088963952724;1456.6285597172653;20530.486737831674;20655;20669.483262918548;26401.60397594076;26587;47106;47232.7638361806;47236.52763472459;47306.76346618245;47371.76314118407;199569;199724;199805;200568;201794;28433.715662843108;31522.211944701565;31831.363372731954;32807.83596082004;33597;41764.582354177284;41848.58151418569;42068;42183.36724949067;42224;30720.231994200323;31093.8445307772;31106.844465777525;31349.216269593442;31473.21316967094;31198;31264;31385.8430707845;31651.52522712207;32026.83986580052;34968.82515587406;35007.299854003286;35016;35185.1203719909;36078.45882311715;31748.523772142973;31798.205044874063;31834;32055.839720801247;32382;23198.65202021937;23270.76729232754;23461.53076938486;23734.881325593262;23771.881140594185 11:31:18 INFO - PID 3651 | |2;jquery.html;557.7295011919218;559.1417174636928;570.6490508337374;573.2633565867859;573.5182446744735;21321.147154113834;22007;22061.448463788394;22063.33809985699;22065.889670551624;24968.625470617957;25431.109911153068;25546.361340966487;25567.10515131971;25590.872045639746;6407.807765767035;6534.640594767286;6542.378474044958;6547.738090476375;6559.573627714207;6397.1363865877975;6594.274629790719;6901.447884169254;6957.826054348631;6957.965210173942;6920.204176519689;6925.342092501204;6926.826829329257;6940.167179938415;6942.652867356637;6927.653617319139;6930.41091507225;6930.722771089176;6931.826704332381;6956.643454526369;6723.630200338979;6779.254282029022;6782.491313151533;6792.524523283372;6795.932040679629;29738.70261297381;30497.23756906073;30550.388992220254;30636.38727225465;30729.846350768326;2057.3005178239323;2071.54426026274;2076.377086873947;2086.113401804227;2090.780468050848;19872.403827885188;20383;20530.589388212153;20590.897045514826;20663.17347306106;2356.1282325539487;2371.4901296221396;2428.5264373447185;2438.4025913651267;2438.7073551173794;128614.3569282157;131006.99999999904;131070;131752.34123829415;131766.34116829452;18883.527911802314;20042;21160.78839211619;21277.574448510943;21310.467238319165;40679.7966010168;40857.387139192935;40892;40904.79547602303;40936.38595421071;11311.490982905789;11347.829782553268;11424.531777403987;11511.53953841837;11598.130140239517;8756.343274254456;9030.413023153475;9542;9591.136797688241;9675.419474831391;10250.948745256226;10752.892471075345;11152.156200169067;11600.88399116015;11718.121140914465;3823.8279277432584;4137.462129923151;4172.532574509498;4293.003753254751;4432.401625780479;931.9673811416568;950.5104870991445;954.2032402943481;960.4045491794992;968.3173362779223;18910.905445472683;19818.108185131703;19863.404097877086;20005;20045.899770501346;1024.2727663359099;1028.7993841201044;1034.332855308322;1044.1229367331478;1044.1438020823005;10622.681319560426;10683.83974240387;11010.559577616805;11019.063379612822;11042.944785276022;471040;539004;539640;544095;550960;114066.42966785113;115063.42468287772;116064;116643;123130.38434807768;1186.6380753870153;1229.8339724137127;1232.8335674683983;1236.412703965627;1237.9621849784007;20300.59398812045;20389.286374976804;20662.793372066688;20882.686759698314;20998.68501972502;58391;58426.4157358421;58472.415275846695;58671.119933200185;60571.39428605657;74229;75059;75747.24252757402;76241.23758762563;77471.2252877464;11790.292582445081;14111.929440352733;14282.143071415749;14857.777133343225;14919.552413427615;23126.537469250856;23264.953077111233;23424.18015369454;23473.647895281243;23663.881680591487;16942.57643558921;17685.734713979044;17994.640107198044;18039.819601803814;18223.271069157086;16291.511254662379;16827.7475837865;17007.574810629834;17135;17558.29766809313;18337;19459.902700486407;19509.122089505723;19665.8033419664;19780.30768923081;14453.205073720626;14798.48205312809;14847.554573362817;14874.553763387115;14875.925620371829;18340.54148646241;19314.7102793461;19512.21951121939;19710.21159153618;19734.90132549328 11:31:18 INFO - PID 3651 | |3;mootools.html;1331.9800202996958;1379.3723855645676;1398.9160650360973;1402.193738600305;1403.2352367959468;13980.580582582514;14154;14617.853821461782;14644.04813687111;14725.558233252992;15247.695046099072;15501.612459688513;15513.379464821393;15513.914026018183;15529.92235038826;2705.350715828202;2750.064977907511;2763.5025695374825;2768.2664094015104;2769.7507224349806;2619.332070322065;2818.4926713191617;2827.6465441819773;2831.5752637104392;2836.5603331483653;2709.24141240453;2720.1975417251956;2721.687005994306;2762.129929072346;2764.087851009162;2711.0917842522776;2719.3881376690283;2735.0700761741105;2738.9589156162574;2739.02764518596;2582.845029298248;2600.4149066459895;2602.973970260292;2609.908653197129;2610.6606141201523;14054.227017514033;14374;14376;14392.71214575713;14402.423903043866;7433.516821406645;7676.309132178075;7689.884651730228;7695.499792513525;7696.114946781136;5294.338207724035;5416.512513873731;5498.202760599731;5559.6664200148125;5636.887262254732;1868.831805137544;1870.4014715291112;1888.830005299516;1897.459224121119;1930.5656227348875;24698.876505617536;25354.366140846443;25381.238562843144;25399;25400.49199016009;6806.35339642743;6904.102466679299;6925.757598484029;7055.788326350218;7104.467164962649;19274;19299.903500482407;19323;19323.4202973911;19350.129244184045;7849.568273744999;8001.959990200012;8005.879911801328;8008.079070906921;8022.197780222048;6735.831604209836;6853.725850966005;6862.039314495974;7044.119485064367;7145.714171433085;5369.006733754268;5496.587755918322;5501.642393244426;5518.172274158908;5528.9723551381985;1577.7238983177897;1578.3449868304754;1581.3595493825019;1585.9286332115082;1587.4681981536085;885.5483703311357;907.5416914458157;915.231205787142;923.3121324613072;924.0436148586181;7565.962170189114;7570.5836179010685;7604.5437273763755;7619.276168763959;7655.425843061681;699.1889408286465;703.6868593475956;708.5642329967034;713.9607321597362;730.5361095704266;4469.262571675702;4511.661625378109;4615.676902616851;5516.7517461714315;5623.550115990793;19176.80823191778;20347.69478457824;20713.482162946046;20736.48158796012;20762;19492.8050719491;19546.70679939802;20447;20531.897340513202;20689.68965465519;10785.460726963778;10930.945345273223;10971.725706857393;10973.83539246912;10980.450977451255;8457.365697572584;8504.362172836938;8577.8713319299;8601.22588966971;8617.612207450806;17158.828411715724;17864.19611117477;18075.728864067314;18123.275068997093;18441.26234950587;17589.032603206433;17726.468205953843;18031.278748849898;18216.908915455337;18300.816991830445;11969.341686206993;12291.877081229073;12293.754124917272;12295.385230738606;12296.692582685146;13307.866921330662;13759.931200344334;13811.585652430444;13812.240326782123;13861.51484698071;11010.39442830652;11038.834417483584;11047.392393418439;11103.5558577656;11243.887561124284;10899.618513351994;11209.831852522055;11372.147088968195;11519.424028798694;11548.653540393801;9945.651902183388;10205.081542661195;10222.335548189343;10225.233107517115;10232.386056836614;7475.925240747741;7490.5131166474;7493.175750667313;7511.62441877893;7523.1348394935285;7536.811579710551;7628.847423051397;7646.617669116633;7654.61726913641;7731.7680469586 11:31:18 INFO - PID 3651 | |4;prototype.html;498.142804148942;515.294047155397;519.3093186062537;521.2832355511172;533.2081858440216;6307.590006649571;6320.810375688731;6328.3671632836695;6385.648789316586;6420.454261387776;6272.498200143989;6298.6535740534255;6319.052142178675;6322;6327.576295333548;5196.818111366112;5207.062728708831;5234.188700751371;5238.978399212155;5241.449647786984;4871.7076975381415;4957.727324997124;5145.408278048017;5180.041692286939;5203.843884683465;4960.5039496050285;4972.428170760355;4984.22744474607;4996.500349964993;4997.375328083989;4937.963027764138;4956.107900577895;4967.7019378837385;4981.775820088086;4994.225894986283;4353.455818022747;4428.291473364278;4454.10917816438;4467.82128714851;4470.547072201548;6091.756329746805;6131.417515336036;6143.385661433821;6148.754049838001;6152.692365381713;5546.77812887484;5605.131204663243;5605.495505404533;5611.494965453129;5676.268738034898;6484.805455836333;6490.545661803676;6502.967485162591;6553.574017688835;6570.1458810354825;4814.1334559779225;4838.177509823303;4860.441049279343;4888.0957022950515;4895.804167833282;6600.702968366435;6648.401643852028;6750.266142212412;6753.223379311442;6769.123122672844;4962.379702537183;4967.230079337708;4975.228839529878;4976.402831660224;5018.799248030038;5020.322256495399;5032.6980381177245;5050.898982020412;5054.7978080876355;5071.5689166420525;5544.695041772741;5651.237082993825;5658.688772117573;5660.5471562274915;5729.770809167587;6496.577722448026;6512.511561632897;6566.638834864033;6598.670066496752;6660.600363978177;1736.97394539082;1756.1570446185901;1769.1419661464179;1781.9198136083908;1784.5360206346443;1156.173336064701;1192.1714408486193;1209.6189700244395;1209.7640960012714;1224.8775122487857;5747.913781293284;5761.9135712964335;5772.682502462405;5788.623739456922;5856.472917437451;6674.733010679518;6701.329867013357;6723.798286051427;6745.494087943326;6787.694553745093;5770.913436298459;5771.971140144273;5808.477237048686;5833.79581714638;5875.853103672357;3506.7545271830727;3512.806795626214;3544.9645503544634;3553.431450967833;3554.537910071674;6514.478841692653;6534.183227096613;6544.411003009657;6557.770478033246;6599.769008084694;6845.349691779273;6880.65596720162;6906.274841141608;6928.896066559006;6931.930680693229;6032.819015429544;6457.030605665317;6520.934790652128;6578.5395022347975;6582.967085164543;6487.967560162169;6581.934180658132;6644.933550664625;6660.200775906922;6664.766733164316;6582.17722784652;6585.341465853568;6643.239541521597;6697.263301036979;6747.920332746737;6409.4872410206135;6483.708233129426;6484.773032944013;6539.378759017885;6546.738130474728;4827.227643577151;4923.901521969612;4976.776045078052;4978.2034874419915;4986.576141027908;6445.645489497935;6598.29247700745;6601.768938087144;6800.353966373186;6801.727930882709;6356.269029061717;6467.935320646733;6485.675716214076;6541.280459149394;6562.737490500326;6536.901946470712;6540.901886471612;6548.476121910331;6560.179977502812;6565.03049085263;6386.648734319471;6454.419102280817;6455.580387274812;6464.22429308486;6484.870302594016;6555.311692272434;6590.637514936533;6603.141591593254;6613.933860661525;6614.933850661624;6316.62100273985;6320.273168585672;6352.333005034589;6366.389780190099;6367.395097465734 11:31:18 INFO - PID 3651 | |5;yui.html;809.3323008517972;817.8200795824919;833.5290560833125;864.2308345572442;889.9377043606946;18839.340623078195;19289.517762055955;19836.90081549592;19849.40451786447;19851.801481985174;20297.797022029776;21021.579568408622;21228.575428491422;21239.68140477894;21370.57258854822;924.3067699225564;944.8110377924409;947.6398968392001;950.6150009246267;952.6141912525438;911.2072496927649;947.767796889763;950.1306304731163;950.5532399772096;950.6625148072438;980.6861804222651;993.5727637116037;993.9155171810405;994.8656931314252;994.9154321882648;988.9456079915602;990.3760630802549;990.8216521026212;992.1219720547332;992.2409356842022;733.6551820644289;733.7468573342188;733.8238822682571;734.192388372791;735.1912895814614;18586.44240672782;19004.61990760177;19149.80850191494;19210.519737006685;20186.798132018637;20559.177632894665;20790.272340468164;20825.479363015893;21242.044108015176;21260.893695531424;16483.67032659352;16827.74758378625;16897;16970.91514542432;17039.914800426042;3622.5652921649307;3648.1609229877013;3659.213269147146;3662.212624285791;3663.21240933198;124481.37759311237;125794;127865;129334;129691;15968.920155399148;16014.439494617865;16276.348946042262;16353.100579468006;16459.094749788877;29446.263843403656;29548.261293467407;29650.406991860043;29787.255318617208;29793.851030744707;8233.917660823436;8324.542150181676;8325.708600198965;8349.624266908004;8366.748997530083;4224.281872081753;4294.506131794821;4302.440682711227;4302.913941721148;4306.41863348444;4851.272309153655;4947.2579113133315;4992.775325110379;5006.173981293119;5009.44896061433;893.7477162523855;906.0531744327194;918.8116436130578;924.09438750024;924.6116631014855;658.8352911772057;673.9966300168468;678.2911857109333;679.5786612300307;688.9965550172317;10924.945375273232;11458.484368203452;11527.711807204887;11750.29498230109;12351;684.1152624519417;686.8591938652564;689.0731965506386;707.7381368893542;708.7661071846278;5406.297181366397;5505.394406615269;5529.502344788908;5542.806001789918;5616.326040875039;103059.48470257601;106656.46671766591;108083;108103;111889.44055279672;88998.11001890028;92146.07853921509;92455;96913;97281;81970;83664;84613.15386846176;84624;97819;11887.702807429883;11933.582324618597;12006.459709312927;12587.68530786701;12592.496300147892;38485;39067.41398879076;39109.2178156441;39330;39606.40590391204;67701;69828.60342793014;69970.65014674894;71854.64072679603;72120.63939680268;16728.33388941758;17171.65656686884;18719.81280187235;19000.52498687544;19198.71201932;19806.20775168977;19929.601407972048;19991;20003.899980500588;20110.79889201089;18916.148773304958;19296.807031930064;19372.8062719371;19415.708764368264;19419.3203237886;18860.24559017624;18948.147333369754;19078.809211907705;19096;19168.520786980436;16707.832921671114;16923.83076169272;16933.915330423268;16990.98054116757;17055.658886821944;12517;12920.806187907378;13157.407916643966;13204.339783010619;13224.801627975396;12056;12152.696182595506;12563.057770667054;12642.936785316015;14374 11:31:18 INFO - PID 3651 | __end_tp_report 11:31:18 INFO - PID 3651 | __start_cc_report 11:31:18 INFO - PID 3651 | _x_x_mozilla_cycle_collect,370 11:31:18 INFO - PID 3651 | __end_cc_report 11:31:18 INFO - PID 3651 | __startTimestamp1504031478332__endTimestamp 11:31:18 INFO - PID 3651 | 11:31:18 INFO - PID 3651 | ------- Summary: start ------- 11:31:18 INFO - PID 3651 | Number of tests: 6 11:31:18 INFO - PID 3651 | 11:31:18 INFO - PID 3651 | [#0] dojo.html Cycles:165 Average:6178.80 Median:6475.94 stddev:753.85 (11.6%) stddev-sans-first:721.71 11:31:18 INFO - PID 3651 | Values: 3307.1 3318.2 3326.6 3347.7 3357.1 6159.2 6234.9 6281.7 6310.8 6313.0 6151.5 6252.0 6256.0 6283.9 6287.7 5713.9 5752.5 5755.9 5790.0 5809.6 5764.5 5847.1 5849.4 5898.2 5900.2 5620.2 5686.4 5706.9 5782.9 5793.3 5641.0 5757.4 5788.7 5834.2 5847.9 5711.9 5720.5 5762.4 5777.7 5791.7 6111.8 6185.5 6196.3 6227.4 6286.0 6630.0 6702.9 6710.2 6728.2 6740.3 6723.3 6724.2 6810.2 6815.4 6843.6 6259.6 6334.2 6383.9 6393.6 6395.3 6926.1 7003.5 7014.6 7021.3 7025.3 6563.9 6614.8 6655.9 6704.4 6713.6 6554.2 6697.2 6698.0 6699.8 6704.5 6638.1 6642.9 6648.9 6729.4 6733.1 6664.8 6722.4 6752.7 6753.4 6754.4 4880.7 4909.6 4911.6 4980.7 5005.6 4335.9 4347.2 4358.3 4364.9 4374.6 5898.4 5903.4 5908.7 5914.9 5918.7 6620.0 6678.1 6694.0 6765.0 6767.8 5747.4 5786.9 5854.4 5901.0 5910.4 5783.5 5800.0 5801.2 5812.9 5818.4 6547.1 6549.1 6558.6 6651.4 6667.4 6559.1 6686.2 6747.9 6759.7 6766.4 6499.2 6541.2 6585.3 6608.3 6616.0 6583.8 6685.0 6699.8 6718.4 6743.9 6683.0 6702.3 6714.3 6810.4 6824.0 6480.8 6535.1 6556.6 6563.8 6573.7 6496.2 6522.4 6570.4 6580.1 6617.7 6338.4 6438.8 6449.6 6536.0 6546.1 6508.2 6553.9 6614.9 6659.8 6678.3 6360.0 6370.5 6439.2 6442.7 6475.9 11:31:18 INFO - PID 3651 | 11:31:18 INFO - PID 3651 | [#1] ext.html Cycles:180 Average:36759.29 Median:3491.92 stddev:52858.92 (1513.8%) stddev-sans-first:52938.28 11:31:18 INFO - PID 3651 | Values: 808.9 831.9 851.4 878.3 879.9 38619.6 39545.6 39917.4 39993.2 40041.0 35723.0 36082.3 36094.6 36106.8 36153.8 6832.8 6843.0 6913.8 6918.5 7437.7 7516.0 7602.8 7605.0 7622.9 7627.8 7565.4 7571.6 7626.5 7628.4 7628.8 7543.2 7605.2 7610.1 7621.0 7621.2 7450.0 7514.8 7517.5 7524.7 7532.9 36430.3 36576.6 36614.8 36652.0 36672.3 3447.5 3490.7 3493.1 3497.6 3502.6 30440.2 30483.5 30575.2 30629.8 30661.5 5094.3 5156.3 5159.9 5220.3 5223.9 177104.0 178871.0 179983.0 180144.0 188520.0 45730.8 46061.0 46089.3 46153.8 46215.5 48049.3 48075.0 48209.5 48213.3 48314.8 15383.2 15687.8 15749.5 15770.1 15918.3 13025.2 13026.3 13045.9 13058.9 13068.0 13544.3 13578.6 13608.1 13612.0 13637.5 5623.3 5676.7 5690.7 5714.7 5738.0 996.7 1000.2 1006.5 1009.0 1010.9 20505.8 20562.8 20623.5 20796.5 21088.6 1101.9 1102.2 1110.1 1111.3 1115.9 11127.8 11167.1 11207.7 11277.9 11314.2 226663.0 227694.0 228010.0 228440.0 228575.0 49411.5 49625.5 49688.5 49690.0 49886.5 1430.3 1431.6 1445.1 1446.1 1456.6 20530.5 20655.0 20669.5 26401.6 26587.0 47106.0 47232.8 47236.5 47306.8 47371.8 199569.0 199724.0 199805.0 200568.0 201794.0 28433.7 31522.2 31831.4 32807.8 33597.0 41764.6 41848.6 42068.0 42183.4 42224.0 30720.2 31093.8 31106.8 31349.2 31473.2 31198.0 31264.0 31385.8 31651.5 32026.8 34968.8 35007.3 35016.0 35185.1 36078.5 31748.5 31798.2 31834.0 32055.8 32382.0 23198.7 23270.8 23461.5 23734.9 23771.9 11:31:18 INFO - PID 3651 | 11:31:18 INFO - PID 3651 | [#2] jquery.html Cycles:180 Average:36833.85 Median:21664.07 stddev:88475.74 (408.4%) stddev-sans-first:88682.01 11:31:18 INFO - PID 3651 | Values: 557.7 559.1 570.6 573.3 573.5 21321.1 22007.0 22061.4 22063.3 22065.9 24968.6 25431.1 25546.4 25567.1 25590.9 6407.8 6534.6 6542.4 6547.7 6559.6 6397.1 6594.3 6901.4 6957.8 6958.0 6920.2 6925.3 6926.8 6940.2 6942.7 6927.7 6930.4 6930.7 6931.8 6956.6 6723.6 6779.3 6782.5 6792.5 6795.9 29738.7 30497.2 30550.4 30636.4 30729.8 2057.3 2071.5 2076.4 2086.1 2090.8 19872.4 20383.0 20530.6 20590.9 20663.2 2356.1 2371.5 2428.5 2438.4 2438.7 128614.4 131007.0 131070.0 131752.3 131766.3 18883.5 20042.0 21160.8 21277.6 21310.5 40679.8 40857.4 40892.0 40904.8 40936.4 11311.5 11347.8 11424.5 11511.5 11598.1 8756.3 9030.4 9542.0 9591.1 9675.4 10250.9 10752.9 11152.2 11600.9 11718.1 3823.8 4137.5 4172.5 4293.0 4432.4 932.0 950.5 954.2 960.4 968.3 18910.9 19818.1 19863.4 20005.0 20045.9 1024.3 1028.8 1034.3 1044.1 1044.1 10622.7 10683.8 11010.6 11019.1 11042.9 471040.0 539004.0 539640.0 544095.0 550960.0 114066.4 115063.4 116064.0 116643.0 123130.4 1186.6 1229.8 1232.8 1236.4 1238.0 20300.6 20389.3 20662.8 20882.7 20998.7 58391.0 58426.4 58472.4 58671.1 60571.4 74229.0 75059.0 75747.2 76241.2 77471.2 11790.3 14111.9 14282.1 14857.8 14919.6 23126.5 23265.0 23424.2 23473.6 23663.9 16942.6 17685.7 17994.6 18039.8 18223.3 16291.5 16827.7 17007.6 17135.0 17558.3 18337.0 19459.9 19509.1 19665.8 19780.3 14453.2 14798.5 14847.6 14874.6 14875.9 18340.5 19314.7 19512.2 19710.2 19734.9 11:31:18 INFO - PID 3651 | 11:31:18 INFO - PID 3651 | [#3] mootools.html Cycles:180 Average:9205.14 Median:2601.69 stddev:6438.65 (247.5%) stddev-sans-first:6429.53 11:31:18 INFO - PID 3651 | Values: 1332.0 1379.4 1398.9 1402.2 1403.2 13980.6 14154.0 14617.9 14644.0 14725.6 15247.7 15501.6 15513.4 15513.9 15529.9 2705.4 2750.1 2763.5 2768.3 2769.8 2619.3 2818.5 2827.6 2831.6 2836.6 2709.2 2720.2 2721.7 2762.1 2764.1 2711.1 2719.4 2735.1 2739.0 2739.0 2582.8 2600.4 2603.0 2609.9 2610.7 14054.2 14374.0 14376.0 14392.7 14402.4 7433.5 7676.3 7689.9 7695.5 7696.1 5294.3 5416.5 5498.2 5559.7 5636.9 1868.8 1870.4 1888.8 1897.5 1930.6 24698.9 25354.4 25381.2 25399.0 25400.5 6806.4 6904.1 6925.8 7055.8 7104.5 19274.0 19299.9 19323.0 19323.4 19350.1 7849.6 8002.0 8005.9 8008.1 8022.2 6735.8 6853.7 6862.0 7044.1 7145.7 5369.0 5496.6 5501.6 5518.2 5529.0 1577.7 1578.3 1581.4 1585.9 1587.5 885.5 907.5 915.2 923.3 924.0 7566.0 7570.6 7604.5 7619.3 7655.4 699.2 703.7 708.6 714.0 730.5 4469.3 4511.7 4615.7 5516.8 5623.6 19176.8 20347.7 20713.5 20736.5 20762.0 19492.8 19546.7 20447.0 20531.9 20689.7 10785.5 10930.9 10971.7 10973.8 10980.5 8457.4 8504.4 8577.9 8601.2 8617.6 17158.8 17864.2 18075.7 18123.3 18441.3 17589.0 17726.5 18031.3 18216.9 18300.8 11969.3 12291.9 12293.8 12295.4 12296.7 13307.9 13759.9 13811.6 13812.2 13861.5 11010.4 11038.8 11047.4 11103.6 11243.9 10899.6 11209.8 11372.1 11519.4 11548.7 9945.7 10205.1 10222.3 10225.2 10232.4 7475.9 7490.5 7493.2 7511.6 7523.1 7536.8 7628.8 7646.6 7654.6 7731.8 11:31:18 INFO - PID 3651 | 11:31:18 INFO - PID 3651 | [#4] prototype.html Cycles:180 Average:5510.99 Median:6212.60 stddev:1542.82 (24.8%) stddev-sans-first:1500.57 11:31:18 INFO - PID 3651 | Values: 498.1 515.3 519.3 521.3 533.2 6307.6 6320.8 6328.4 6385.6 6420.5 6272.5 6298.7 6319.1 6322.0 6327.6 5196.8 5207.1 5234.2 5239.0 5241.4 4871.7 4957.7 5145.4 5180.0 5203.8 4960.5 4972.4 4984.2 4996.5 4997.4 4938.0 4956.1 4967.7 4981.8 4994.2 4353.5 4428.3 4454.1 4467.8 4470.5 6091.8 6131.4 6143.4 6148.8 6152.7 5546.8 5605.1 5605.5 5611.5 5676.3 6484.8 6490.5 6503.0 6553.6 6570.1 4814.1 4838.2 4860.4 4888.1 4895.8 6600.7 6648.4 6750.3 6753.2 6769.1 4962.4 4967.2 4975.2 4976.4 5018.8 5020.3 5032.7 5050.9 5054.8 5071.6 5544.7 5651.2 5658.7 5660.5 5729.8 6496.6 6512.5 6566.6 6598.7 6660.6 1737.0 1756.2 1769.1 1781.9 1784.5 1156.2 1192.2 1209.6 1209.8 1224.9 5747.9 5761.9 5772.7 5788.6 5856.5 6674.7 6701.3 6723.8 6745.5 6787.7 5770.9 5772.0 5808.5 5833.8 5875.9 3506.8 3512.8 3545.0 3553.4 3554.5 6514.5 6534.2 6544.4 6557.8 6599.8 6845.3 6880.7 6906.3 6928.9 6931.9 6032.8 6457.0 6520.9 6578.5 6583.0 6488.0 6581.9 6644.9 6660.2 6664.8 6582.2 6585.3 6643.2 6697.3 6747.9 6409.5 6483.7 6484.8 6539.4 6546.7 4827.2 4923.9 4976.8 4978.2 4986.6 6445.6 6598.3 6601.8 6800.4 6801.7 6356.3 6467.9 6485.7 6541.3 6562.7 6536.9 6540.9 6548.5 6560.2 6565.0 6386.6 6454.4 6455.6 6464.2 6484.9 6555.3 6590.6 6603.1 6613.9 6614.9 6316.6 6320.3 6352.3 6366.4 6367.4 11:31:18 INFO - PID 3651 | 11:31:18 INFO - PID 3651 | [#5] yui.html Cycles:180 Average:23481.65 Median:3635.36 stddev:31850.18 (876.1%) stddev-sans-first:31894.03 11:31:18 INFO - PID 3651 | Values: 809.3 817.8 833.5 864.2 889.9 18839.3 19289.5 19836.9 19849.4 19851.8 20297.8 21021.6 21228.6 21239.7 21370.6 924.3 944.8 947.6 950.6 952.6 911.2 947.8 950.1 950.6 950.7 980.7 993.6 993.9 994.9 994.9 988.9 990.4 990.8 992.1 992.2 733.7 733.7 733.8 734.2 735.2 18586.4 19004.6 19149.8 19210.5 20186.8 20559.2 20790.3 20825.5 21242.0 21260.9 16483.7 16827.7 16897.0 16970.9 17039.9 3622.6 3648.2 3659.2 3662.2 3663.2 124481.4 125794.0 127865.0 129334.0 129691.0 15968.9 16014.4 16276.3 16353.1 16459.1 29446.3 29548.3 29650.4 29787.3 29793.9 8233.9 8324.5 8325.7 8349.6 8366.7 4224.3 4294.5 4302.4 4302.9 4306.4 4851.3 4947.3 4992.8 5006.2 5009.4 893.7 906.1 918.8 924.1 924.6 658.8 674.0 678.3 679.6 689.0 10924.9 11458.5 11527.7 11750.3 12351.0 684.1 686.9 689.1 707.7 708.8 5406.3 5505.4 5529.5 5542.8 5616.3 103059.5 106656.5 108083.0 108103.0 111889.4 88998.1 92146.1 92455.0 96913.0 97281.0 81970.0 83664.0 84613.2 84624.0 97819.0 11887.7 11933.6 12006.5 12587.7 12592.5 38485.0 39067.4 39109.2 39330.0 39606.4 67701.0 69828.6 69970.7 71854.6 72120.6 16728.3 17171.7 18719.8 19000.5 19198.7 19806.2 19929.6 19991.0 20003.9 20110.8 18916.1 19296.8 19372.8 19415.7 19419.3 18860.2 18948.1 19078.8 19096.0 19168.5 16707.8 16923.8 16933.9 16991.0 17055.7 12517.0 12920.8 13157.4 13204.3 13224.8 12056.0 12152.7 12563.1 12642.9 14374.0 11:31:18 INFO - PID 3651 | -------- Summary: end -------- 11:31:18 INFO - PID 3651 | 11:31:19 INFO - TEST-INFO | 3651: exit 0 11:31:19 INFO - TEST-OK | dromaeo_css | took 1114136ms 11:31:19 INFO - TEST-START | kraken 11:31:19 INFO - Initialising browser for kraken test... 11:31:19 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59163/getInfo.html -profile /tmp/tmpucm56V/profile 11:31:19 INFO - TEST-INFO | started process 4028 (/builds/slave/test/build/application/firefox/firefox http://localhost:59163/getInfo.html) 11:31:24 INFO - TEST-INFO | 4028: exit 0 11:31:24 INFO - Browser initialized. 11:31:24 INFO - Running cycle 1/1 for kraken test... 11:31:24 INFO - Using env: {'DISPLAY': ':0', 11:31:24 INFO - 'HOME': '/home/cltbld', 11:31:24 INFO - 'JSGC_DISABLE_POISONING': '1', 11:31:24 INFO - 'LANG': 'en_US.UTF-8', 11:31:24 INFO - 'LANGUAGE': 'en_US:en', 11:31:24 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:31:24 INFO - 'LOGNAME': 'cltbld', 11:31:24 INFO - 'MAIL': '/var/mail/cltbld', 11:31:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:31:24 INFO - 'MOZ_CRASHREPORTER': '1', 11:31:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:24 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:31:24 INFO - 'MOZ_NO_REMOTE': '1', 11:31:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:24 INFO - 'NO_EM_RESTART': '1', 11:31:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:24 INFO - 'PWD': '/builds/slave/test', 11:31:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:24 INFO - 'RUST_BACKTRACE': 'full', 11:31:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:31:24 INFO - 'SHELL': '/bin/bash', 11:31:24 INFO - 'SHLVL': '1', 11:31:24 INFO - 'STYLO_FORCE_ENABLED': '1', 11:31:24 INFO - 'TERM': 'linux', 11:31:24 INFO - 'TMOUT': '86400', 11:31:24 INFO - 'USER': 'cltbld', 11:31:24 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894', 11:31:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:24 INFO - '_': '/tools/buildbot/bin/python'} 11:31:24 INFO - TEST-INFO | started process 4212 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpucm56V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:31:25 INFO - PID 4212 | 11:31:25 INFO - PID 4212 | (/builds/slave/test/build/application/firefox/firefox:4262): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:31:25 INFO - PID 4212 | 11:31:35 INFO - PID 4212 | RSS: Main: 175759360 11:31:35 INFO - PID 4212 | 11:31:38 INFO - PID 4212 | Cycle 1(1): loaded http://localhost:59163/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59163/tests/kraken/driver.html?testName=audio-beat-detection) 11:31:39 INFO - PID 4212 | RSS: Main: 181800960 11:31:39 INFO - PID 4212 | 11:31:42 INFO - PID 4212 | Cycle 1(1): loaded http://localhost:59163/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59163/tests/kraken/driver.html?testName=audio-dft) 11:31:42 INFO - PID 4212 | RSS: Main: 185561088 11:31:42 INFO - PID 4212 | 11:31:45 INFO - PID 4212 | Cycle 1(1): loaded http://localhost:59163/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59163/tests/kraken/driver.html?testName=audio-fft) 11:31:46 INFO - PID 4212 | RSS: Main: 177659904 11:31:46 INFO - PID 4212 | 11:31:48 INFO - PID 4212 | Cycle 1(1): loaded http://localhost:59163/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59163/tests/kraken/driver.html?testName=audio-oscillator) 11:31:49 INFO - PID 4212 | RSS: Main: 178102272 11:31:49 INFO - PID 4212 | 11:31:51 INFO - PID 4212 | Cycle 1(1): loaded http://localhost:59163/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59163/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:31:51 INFO - PID 4212 | RSS: Main: 178323456 11:31:51 INFO - PID 4212 | 11:31:54 INFO - PID 4212 | Cycle 1(1): loaded http://localhost:59163/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59163/tests/kraken/driver.html?testName=imaging-darkroom) 11:31:55 INFO - PID 4212 | RSS: Main: 179695616 11:31:55 INFO - PID 4212 | 11:31:59 INFO - PID 4212 | Cycle 1(1): loaded http://localhost:59163/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59163/tests/kraken/driver.html?testName=imaging-desaturate) 11:31:59 INFO - PID 4212 | RSS: Main: 179830784 11:31:59 INFO - PID 4212 | 11:32:02 INFO - PID 4212 | Cycle 1(1): loaded http://localhost:59163/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59163/tests/kraken/driver.html?testName=json-parse-financial) 11:32:03 INFO - PID 4212 | RSS: Main: 179511296 11:32:03 INFO - PID 4212 | 11:32:04 INFO - PID 4212 | Cycle 1(1): loaded http://localhost:59163/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59163/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:32:05 INFO - PID 4212 | RSS: Main: 179646464 11:32:05 INFO - PID 4212 | 11:32:07 INFO - PID 4212 | Cycle 1(1): loaded http://localhost:59163/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59163/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:32:07 INFO - PID 4212 | RSS: Main: 179781632 11:32:07 INFO - PID 4212 | 11:32:09 INFO - PID 4212 | Cycle 1(1): loaded http://localhost:59163/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59163/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:32:10 INFO - PID 4212 | RSS: Main: 176570368 11:32:10 INFO - PID 4212 | 11:32:13 INFO - PID 4212 | Cycle 1(1): loaded http://localhost:59163/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59163/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:32:13 INFO - PID 4212 | RSS: Main: 177201152 11:32:13 INFO - PID 4212 | 11:32:16 INFO - PID 4212 | Cycle 1(1): loaded http://localhost:59163/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59163/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:32:17 INFO - PID 4212 | RSS: Main: 177643520 11:32:17 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | Cycle 1(1): loaded http://localhost:59163/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59163/tests/kraken/driver.html?testName=ai-astar) 11:32:19 INFO - PID 4212 | RSS: Main: 179040256 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | __start_tp_report 11:32:19 INFO - PID 4212 | _x_x_mozilla_page_load 11:32:19 INFO - PID 4212 | _x_x_mozilla_page_load_details 11:32:19 INFO - PID 4212 | |i|pagename|runs| 11:32:19 INFO - PID 4212 | |0;ai-astar;100;106;99;106;115;109;109;98;107;109 11:32:19 INFO - PID 4212 | |1;audio-beat-detection;140;116;138;173;117;139;148;118;166;120 11:32:19 INFO - PID 4212 | |2;audio-dft;161;161;161;162;162;163;167;164;160;164 11:32:19 INFO - PID 4212 | |3;audio-fft;74;73;75;180;76;74;74;73;166;74 11:32:19 INFO - PID 4212 | |4;audio-oscillator;79;79;134;78;79;78;124;79;79;127 11:32:19 INFO - PID 4212 | |5;imaging-gaussian-blur;113;115;115;115;113;114;113;114;114;114 11:32:19 INFO - PID 4212 | |6;imaging-darkroom;149;147;149;148;148;147;147;148;147;148 11:32:19 INFO - PID 4212 | |7;imaging-desaturate;95;94;94;94;94;95;94;94;94;93 11:32:19 INFO - PID 4212 | |8;json-parse-financial;52;52;51;52;52;52;53;52;53;53 11:32:19 INFO - PID 4212 | |9;json-stringify-tinderbox;44;37;38;37;38;37;39;48;39;39 11:32:19 INFO - PID 4212 | |10;stanford-crypto-aes;74;74;74;75;75;78;72;74;74;79 11:32:19 INFO - PID 4212 | |11;stanford-crypto-ccm;121;123;118;137;114;121;119;132;116;170 11:32:19 INFO - PID 4212 | |12;stanford-crypto-pbkdf2;172;174;179;186;173;170;186;166;169;169 11:32:19 INFO - PID 4212 | |13;stanford-crypto-sha256-iterative;65;65;69;66;67;63;64;64;62;66 11:32:19 INFO - PID 4212 | __end_tp_report 11:32:19 INFO - PID 4212 | __start_cc_report 11:32:19 INFO - PID 4212 | _x_x_mozilla_cycle_collect,944 11:32:19 INFO - PID 4212 | __end_cc_report 11:32:19 INFO - PID 4212 | __startTimestamp1504031539478__endTimestamp 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | ------- Summary: start ------- 11:32:19 INFO - PID 4212 | Number of tests: 14 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | [#0] ai-astar Cycles:10 Average:105.80 Median:109.00 stddev:5.35 (4.9%) stddev-sans-first:5.25 11:32:19 INFO - PID 4212 | Values: 100.0 106.0 99.0 106.0 115.0 109.0 109.0 98.0 107.0 109.0 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:139.50 stddev:20.44 (14.7%) stddev-sans-first:21.66 11:32:19 INFO - PID 4212 | Values: 140.0 116.0 138.0 173.0 117.0 139.0 148.0 118.0 166.0 120.0 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.50 stddev:2.07 (1.3%) stddev-sans-first:2.12 11:32:19 INFO - PID 4212 | Values: 161.0 161.0 161.0 162.0 162.0 163.0 167.0 164.0 160.0 164.0 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | [#3] audio-fft Cycles:10 Average:93.90 Median:74.00 stddev:41.83 (56.5%) stddev-sans-first:43.74 11:32:19 INFO - PID 4212 | Values: 74.0 73.0 75.0 180.0 76.0 74.0 74.0 73.0 166.0 74.0 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | [#4] audio-oscillator Cycles:10 Average:93.60 Median:79.00 stddev:24.09 (30.5%) stddev-sans-first:24.97 11:32:19 INFO - PID 4212 | Values: 79.0 79.0 134.0 78.0 79.0 78.0 124.0 79.0 79.0 127.0 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.82 (0.7%) stddev-sans-first:0.78 11:32:19 INFO - PID 4212 | Values: 113.0 115.0 115.0 115.0 113.0 114.0 113.0 114.0 114.0 114.0 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:0.79 (0.5%) stddev-sans-first:0.71 11:32:19 INFO - PID 4212 | Values: 149.0 147.0 149.0 148.0 148.0 147.0 147.0 148.0 147.0 148.0 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 11:32:19 INFO - PID 4212 | Values: 95.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 93.0 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:52.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 11:32:19 INFO - PID 4212 | Values: 52.0 52.0 51.0 52.0 52.0 52.0 53.0 52.0 53.0 53.0 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | [#9] json-stringify-tinderbox Cycles:10 Average:39.60 Median:39.00 stddev:3.60 (9.2%) stddev-sans-first:3.44 11:32:19 INFO - PID 4212 | Values: 44.0 37.0 38.0 37.0 38.0 37.0 39.0 48.0 39.0 39.0 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | [#10] stanford-crypto-aes Cycles:10 Average:74.90 Median:74.50 stddev:2.08 (2.8%) stddev-sans-first:2.18 11:32:19 INFO - PID 4212 | Values: 74.0 74.0 74.0 75.0 75.0 78.0 72.0 74.0 74.0 79.0 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | [#11] stanford-crypto-ccm Cycles:10 Average:127.10 Median:122.00 stddev:16.66 (13.7%) stddev-sans-first:17.52 11:32:19 INFO - PID 4212 | Values: 121.0 123.0 118.0 137.0 114.0 121.0 119.0 132.0 116.0 170.0 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.40 Median:173.50 stddev:7.04 (4.1%) stddev-sans-first:7.42 11:32:19 INFO - PID 4212 | Values: 172.0 174.0 179.0 186.0 173.0 170.0 186.0 166.0 169.0 169.0 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.50 stddev:2.02 (3.1%) stddev-sans-first:2.15 11:32:19 INFO - PID 4212 | Values: 65.0 65.0 69.0 66.0 67.0 63.0 64.0 64.0 62.0 66.0 11:32:19 INFO - PID 4212 | -------- Summary: end -------- 11:32:19 INFO - PID 4212 | 11:32:19 INFO - PID 4212 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:32:20 INFO - TEST-INFO | 4212: exit 0 11:32:20 INFO - TEST-OK | kraken | took 61190ms 11:32:20 INFO - SUITE-END | took 1175s 11:32:20 INFO - Completed test suite (00:19:35) 11:32:20 INFO - javascript benchmark 11:32:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8546.40477793883, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3307.0574886157433, 3318.187044174177, 3326.6174389945154, 3347.715444187244, 3357.1271469417943, 6159.168512250845, 6234.875302493953, 6281.748730050798, 6310.842228944279, 6312.968435157829, 6151.538634602412, 6251.968740156293, 6256.0, 6283.905741413882, 6287.717052732626, 5713.857153571163, 5752.539796816265, 5755.942440575583, 5790.0, 5809.593328467009, 5764.51001664859, 5847.122931560258, 5849.385814489481, 5898.174255604219, 5900.203472531196, 5620.241267428926, 5686.402927692616, 5706.914396284038, 5782.942170578324, 5793.333766616851, 5640.971795141039, 5757.395473475309, 5788.739506722165, 5834.154047663065, 5847.9415205848245, 5711.885762284773, 5720.48515633595, 5762.423757624246, 5777.740001699934, 5791.652500849962, 6111.786087486961, 6185.474234690057, 6196.349383314777, 6227.408396202353, 6285.968570157165, 6630.0, 6702.932970670279, 6710.16122984627, 6728.226253980806, 6740.258571557125, 6723.327667233287, 6724.226713927912, 6810.216825065131, 6815.42068924142, 6843.555168914055, 6259.624422534663, 6334.2398912130375, 6383.936160638381, 6393.552451328455, 6395.328490508523, 6926.099607051051, 7003.474739394565, 7014.649267536603, 7021.262767409348, 7025.262347453444, 6563.901541476882, 6614.768483103068, 6655.86688266232, 6704.363085506869, 6713.597184168965, 6554.246261679872, 6697.196336439561, 6698.0, 6699.76550820719, 6704.530682852251, 6638.137042184485, 6642.86714265712, 6648.900266496006, 6729.427998620172, 6733.057371967929, 6664.8000559983275, 6722.394984451423, 6752.729890804411, 6753.425958793459, 6754.3921047106, 4880.682755620874, 4909.582685471775, 4911.607071434277, 4980.701157930535, 5005.624578156653, 4335.869923902288, 4347.239233134189, 4358.346248062816, 4364.890877728082, 4374.606285434326, 5898.4101589840675, 5903.409659034148, 5908.734106965197, 5914.905742437577, 5918.674472904032, 6619.966900165468, 6678.065070890079, 6694.0, 6765.019072234499, 6767.796966091025, 5747.396523364986, 5786.94213057864, 5854.385289544536, 5901.026330655393, 5910.4385083417865, 5783.508401785895, 5799.971000144972, 5801.245838041112, 5812.88374232513, 5818.389069147764, 6547.050677651714, 6549.148610680581, 6558.573692709959, 6651.401373876278, 6667.399934005963, 6559.147310849559, 6686.231083425274, 6747.865042699021, 6759.662016899234, 6766.391024807693, 6499.2200935887995, 6541.215054193527, 6585.3085426029575, 6608.339166083353, 6616.040674102132, 6583.80248592543, 6684.997250412477, 6699.76550820719, 6718.361755633207, 6743.932560674527, 6682.966585167043, 6702.262751097473, 6714.328567143247, 6810.387065164159, 6823.965880170567, 6480.773172938924, 6535.085088087767, 6556.573822701509, 6563.803085907431, 6573.704183311666, 6496.155499785186, 6522.445592124723, 6570.441512471302, 6580.078788969644, 6617.66911654425, 6338.397852204033, 6438.806835794933, 6449.645269510034, 6536.019597060479, 6546.116274302908, 6508.219013718384, 6553.934460655523, 6614.933850661432, 6659.766908158192, 6678.298778628174, 6360.014197799349, 6370.522210834113, 6439.227292724903, 6442.742290308335, 6475.935240647534], "value": 6121.8762934198885, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [808.8867558541801, 831.9334453243741, 851.3870013590215, 878.2841983783217, 879.8768172455857, 38619.613803861954, 39545.60454395455, 39917.401238981365, 39993.200135997264, 40041.0, 35723.0, 36082.27835443289, 36094.639053609455, 36106.8194659027, 36153.81923090387, 6832.760853370145, 6843.0, 6913.827154321157, 6918.515703900729, 7437.702491900318, 7516.0, 7602.809929751773, 7604.961975190116, 7622.885656715153, 7627.847443051136, 7565.3947684185405, 7571.583562904038, 7626.466147369686, 7628.351590114847, 7628.809279767995, 7543.207963163871, 7605.201453847321, 7610.086789585258, 7621.047369078865, 7621.161672216052, 7449.962750186269, 7514.812129696801, 7517.4737768356235, 7524.661390237453, 7532.945387645734, 36430.27139457196, 36576.63423365759, 36614.8169259152, 36652.0, 36672.26655466876, 3447.517347571342, 3490.685838274542, 3493.1441796759955, 3497.615262321143, 3502.562179727534, 30440.238994025105, 30483.542746858813, 30575.235619109477, 30629.846850765603, 30661.540076898866, 5094.286799848024, 5156.252343410185, 5159.948400516022, 5220.347456567929, 5223.921641175385, 177104.0, 178871.0, 179983.0, 180144.0, 188520.0, 45730.771346143054, 46061.0, 46089.30866037012, 46153.846153846425, 46215.537844621125, 48049.27926081111, 48075.0, 48209.5179048212, 48213.27680084801, 48314.75842620764, 15383.153926534149, 15687.764683529756, 15749.527514174593, 15770.05379677223, 15918.283677234553, 13025.218486890817, 13026.348682565835, 13045.869541304655, 13058.869411305956, 13068.0, 13544.255065971269, 13578.592642220749, 13608.115472494255, 13612.0, 13637.522686705917, 5623.297087864017, 5676.687782171937, 5690.658560486384, 5714.685692286881, 5738.0, 996.6760802739138, 1000.1548691355792, 1006.5470538257813, 1008.969730908074, 1010.9191264698807, 20505.79494205069, 20562.79437205639, 20623.4844128895, 20796.48008799792, 21088.578228435344, 1101.9063379612662, 1102.2339474065607, 1110.1230028277732, 1111.288775183883, 1115.9163062770324, 11127.777444451065, 11167.106631469464, 11207.71980700489, 11277.887221127849, 11314.208005439705, 226663.0, 227694.0, 228010.0, 228440.0, 228575.0, 49411.50588494141, 49625.50374496281, 49688.503114968385, 49690.0, 49886.50113498891, 1430.3491911180388, 1431.5633731711719, 1445.1184777285848, 1446.088963952724, 1456.6285597172653, 20530.486737831674, 20655.0, 20669.483262918548, 26401.60397594076, 26587.0, 47106.0, 47232.7638361806, 47236.52763472459, 47306.76346618245, 47371.76314118407, 199569.0, 199724.0, 199805.0, 200568.0, 201794.0, 28433.715662843108, 31522.211944701565, 31831.363372731954, 32807.83596082004, 33597.0, 41764.582354177284, 41848.58151418569, 42068.0, 42183.36724949067, 42224.0, 30720.231994200323, 31093.8445307772, 31106.844465777525, 31349.216269593442, 31473.21316967094, 31198.0, 31264.0, 31385.8430707845, 31651.52522712207, 32026.83986580052, 34968.82515587406, 35007.299854003286, 35016.0, 35185.1203719909, 36078.45882311715, 31748.523772142973, 31798.205044874063, 31834.0, 32055.839720801247, 32382.0, 23198.65202021937, 23270.76729232754, 23461.53076938486, 23734.881325593262, 23771.881140594185], "value": 16741.665157359796, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.7295011919218, 559.1417174636928, 570.6490508337374, 573.2633565867859, 573.5182446744735, 21321.147154113834, 22007.0, 22061.448463788394, 22063.33809985699, 22065.889670551624, 24968.625470617957, 25431.109911153068, 25546.361340966487, 25567.10515131971, 25590.872045639746, 6407.807765767035, 6534.640594767286, 6542.378474044958, 6547.738090476375, 6559.573627714207, 6397.1363865877975, 6594.274629790719, 6901.447884169254, 6957.826054348631, 6957.965210173942, 6920.204176519689, 6925.342092501204, 6926.826829329257, 6940.167179938415, 6942.652867356637, 6927.653617319139, 6930.41091507225, 6930.722771089176, 6931.826704332381, 6956.643454526369, 6723.630200338979, 6779.254282029022, 6782.491313151533, 6792.524523283372, 6795.932040679629, 29738.70261297381, 30497.23756906073, 30550.388992220254, 30636.38727225465, 30729.846350768326, 2057.3005178239323, 2071.54426026274, 2076.377086873947, 2086.113401804227, 2090.780468050848, 19872.403827885188, 20383.0, 20530.589388212153, 20590.897045514826, 20663.17347306106, 2356.1282325539487, 2371.4901296221396, 2428.5264373447185, 2438.4025913651267, 2438.7073551173794, 128614.3569282157, 131006.99999999904, 131070.0, 131752.34123829415, 131766.34116829452, 18883.527911802314, 20042.0, 21160.78839211619, 21277.574448510943, 21310.467238319165, 40679.7966010168, 40857.387139192935, 40892.0, 40904.79547602303, 40936.38595421071, 11311.490982905789, 11347.829782553268, 11424.531777403987, 11511.53953841837, 11598.130140239517, 8756.343274254456, 9030.413023153475, 9542.0, 9591.136797688241, 9675.419474831391, 10250.948745256226, 10752.892471075345, 11152.156200169067, 11600.88399116015, 11718.121140914465, 3823.8279277432584, 4137.462129923151, 4172.532574509498, 4293.003753254751, 4432.401625780479, 931.9673811416568, 950.5104870991445, 954.2032402943481, 960.4045491794992, 968.3173362779223, 18910.905445472683, 19818.108185131703, 19863.404097877086, 20005.0, 20045.899770501346, 1024.2727663359099, 1028.7993841201044, 1034.332855308322, 1044.1229367331478, 1044.1438020823005, 10622.681319560426, 10683.83974240387, 11010.559577616805, 11019.063379612822, 11042.944785276022, 471040.0, 539004.0, 539640.0, 544095.0, 550960.0, 114066.42966785113, 115063.42468287772, 116064.0, 116643.0, 123130.38434807768, 1186.6380753870153, 1229.8339724137127, 1232.8335674683983, 1236.412703965627, 1237.9621849784007, 20300.59398812045, 20389.286374976804, 20662.793372066688, 20882.686759698314, 20998.68501972502, 58391.0, 58426.4157358421, 58472.415275846695, 58671.119933200185, 60571.39428605657, 74229.0, 75059.0, 75747.24252757402, 76241.23758762563, 77471.2252877464, 11790.292582445081, 14111.929440352733, 14282.143071415749, 14857.777133343225, 14919.552413427615, 23126.537469250856, 23264.953077111233, 23424.18015369454, 23473.647895281243, 23663.881680591487, 16942.57643558921, 17685.734713979044, 17994.640107198044, 18039.819601803814, 18223.271069157086, 16291.511254662379, 16827.7475837865, 17007.574810629834, 17135.0, 17558.29766809313, 18337.0, 19459.902700486407, 19509.122089505723, 19665.8033419664, 19780.30768923081, 14453.205073720626, 14798.48205312809, 14847.554573362817, 14874.553763387115, 14875.925620371829, 18340.54148646241, 19314.7102793461, 19512.21951121939, 19710.21159153618, 19734.90132549328], "value": 12743.109398758548, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.9800202996958, 1379.3723855645676, 1398.9160650360973, 1402.193738600305, 1403.2352367959468, 13980.580582582514, 14154.0, 14617.853821461782, 14644.04813687111, 14725.558233252992, 15247.695046099072, 15501.612459688513, 15513.379464821393, 15513.914026018183, 15529.92235038826, 2705.350715828202, 2750.064977907511, 2763.5025695374825, 2768.2664094015104, 2769.7507224349806, 2619.332070322065, 2818.4926713191617, 2827.6465441819773, 2831.5752637104392, 2836.5603331483653, 2709.24141240453, 2720.1975417251956, 2721.687005994306, 2762.129929072346, 2764.087851009162, 2711.0917842522776, 2719.3881376690283, 2735.0700761741105, 2738.9589156162574, 2739.02764518596, 2582.845029298248, 2600.4149066459895, 2602.973970260292, 2609.908653197129, 2610.6606141201523, 14054.227017514033, 14374.0, 14376.0, 14392.71214575713, 14402.423903043866, 7433.516821406645, 7676.309132178075, 7689.884651730228, 7695.499792513525, 7696.114946781136, 5294.338207724035, 5416.512513873731, 5498.202760599731, 5559.6664200148125, 5636.887262254732, 1868.831805137544, 1870.4014715291112, 1888.830005299516, 1897.459224121119, 1930.5656227348875, 24698.876505617536, 25354.366140846443, 25381.238562843144, 25399.0, 25400.49199016009, 6806.35339642743, 6904.102466679299, 6925.757598484029, 7055.788326350218, 7104.467164962649, 19274.0, 19299.903500482407, 19323.0, 19323.4202973911, 19350.129244184045, 7849.568273744999, 8001.959990200012, 8005.879911801328, 8008.079070906921, 8022.197780222048, 6735.831604209836, 6853.725850966005, 6862.039314495974, 7044.119485064367, 7145.714171433085, 5369.006733754268, 5496.587755918322, 5501.642393244426, 5518.172274158908, 5528.9723551381985, 1577.7238983177897, 1578.3449868304754, 1581.3595493825019, 1585.9286332115082, 1587.4681981536085, 885.5483703311357, 907.5416914458157, 915.231205787142, 923.3121324613072, 924.0436148586181, 7565.962170189114, 7570.5836179010685, 7604.5437273763755, 7619.276168763959, 7655.425843061681, 699.1889408286465, 703.6868593475956, 708.5642329967034, 713.9607321597362, 730.5361095704266, 4469.262571675702, 4511.661625378109, 4615.676902616851, 5516.7517461714315, 5623.550115990793, 19176.80823191778, 20347.69478457824, 20713.482162946046, 20736.48158796012, 20762.0, 19492.8050719491, 19546.70679939802, 20447.0, 20531.897340513202, 20689.68965465519, 10785.460726963778, 10930.945345273223, 10971.725706857393, 10973.83539246912, 10980.450977451255, 8457.365697572584, 8504.362172836938, 8577.8713319299, 8601.22588966971, 8617.612207450806, 17158.828411715724, 17864.19611117477, 18075.728864067314, 18123.275068997093, 18441.26234950587, 17589.032603206433, 17726.468205953843, 18031.278748849898, 18216.908915455337, 18300.816991830445, 11969.341686206993, 12291.877081229073, 12293.754124917272, 12295.385230738606, 12296.692582685146, 13307.866921330662, 13759.931200344334, 13811.585652430444, 13812.240326782123, 13861.51484698071, 11010.39442830652, 11038.834417483584, 11047.392393418439, 11103.5558577656, 11243.887561124284, 10899.618513351994, 11209.831852522055, 11372.147088968195, 11519.424028798694, 11548.653540393801, 9945.651902183388, 10205.081542661195, 10222.335548189343, 10225.233107517115, 10232.386056836614, 7475.925240747741, 7490.5131166474, 7493.175750667313, 7511.62441877893, 7523.1348394935285, 7536.811579710551, 7628.847423051397, 7646.617669116633, 7654.61726913641, 7731.7680469586], "value": 6596.768659331169, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.142804148942, 515.294047155397, 519.3093186062537, 521.2832355511172, 533.2081858440216, 6307.590006649571, 6320.810375688731, 6328.3671632836695, 6385.648789316586, 6420.454261387776, 6272.498200143989, 6298.6535740534255, 6319.052142178675, 6322.0, 6327.576295333548, 5196.818111366112, 5207.062728708831, 5234.188700751371, 5238.978399212155, 5241.449647786984, 4871.7076975381415, 4957.727324997124, 5145.408278048017, 5180.041692286939, 5203.843884683465, 4960.5039496050285, 4972.428170760355, 4984.22744474607, 4996.500349964993, 4997.375328083989, 4937.963027764138, 4956.107900577895, 4967.7019378837385, 4981.775820088086, 4994.225894986283, 4353.455818022747, 4428.291473364278, 4454.10917816438, 4467.82128714851, 4470.547072201548, 6091.756329746805, 6131.417515336036, 6143.385661433821, 6148.754049838001, 6152.692365381713, 5546.77812887484, 5605.131204663243, 5605.495505404533, 5611.494965453129, 5676.268738034898, 6484.805455836333, 6490.545661803676, 6502.967485162591, 6553.574017688835, 6570.1458810354825, 4814.1334559779225, 4838.177509823303, 4860.441049279343, 4888.0957022950515, 4895.804167833282, 6600.702968366435, 6648.401643852028, 6750.266142212412, 6753.223379311442, 6769.123122672844, 4962.379702537183, 4967.230079337708, 4975.228839529878, 4976.402831660224, 5018.799248030038, 5020.322256495399, 5032.6980381177245, 5050.898982020412, 5054.7978080876355, 5071.5689166420525, 5544.695041772741, 5651.237082993825, 5658.688772117573, 5660.5471562274915, 5729.770809167587, 6496.577722448026, 6512.511561632897, 6566.638834864033, 6598.670066496752, 6660.600363978177, 1736.97394539082, 1756.1570446185901, 1769.1419661464179, 1781.9198136083908, 1784.5360206346443, 1156.173336064701, 1192.1714408486193, 1209.6189700244395, 1209.7640960012714, 1224.8775122487857, 5747.913781293284, 5761.9135712964335, 5772.682502462405, 5788.623739456922, 5856.472917437451, 6674.733010679518, 6701.329867013357, 6723.798286051427, 6745.494087943326, 6787.694553745093, 5770.913436298459, 5771.971140144273, 5808.477237048686, 5833.79581714638, 5875.853103672357, 3506.7545271830727, 3512.806795626214, 3544.9645503544634, 3553.431450967833, 3554.537910071674, 6514.478841692653, 6534.183227096613, 6544.411003009657, 6557.770478033246, 6599.769008084694, 6845.349691779273, 6880.65596720162, 6906.274841141608, 6928.896066559006, 6931.930680693229, 6032.819015429544, 6457.030605665317, 6520.934790652128, 6578.5395022347975, 6582.967085164543, 6487.967560162169, 6581.934180658132, 6644.933550664625, 6660.200775906922, 6664.766733164316, 6582.17722784652, 6585.341465853568, 6643.239541521597, 6697.263301036979, 6747.920332746737, 6409.4872410206135, 6483.708233129426, 6484.773032944013, 6539.378759017885, 6546.738130474728, 4827.227643577151, 4923.901521969612, 4976.776045078052, 4978.2034874419915, 4986.576141027908, 6445.645489497935, 6598.29247700745, 6601.768938087144, 6800.353966373186, 6801.727930882709, 6356.269029061717, 6467.935320646733, 6485.675716214076, 6541.280459149394, 6562.737490500326, 6536.901946470712, 6540.901886471612, 6548.476121910331, 6560.179977502812, 6565.03049085263, 6386.648734319471, 6454.419102280817, 6455.580387274812, 6464.22429308486, 6484.870302594016, 6555.311692272434, 6590.637514936533, 6603.141591593254, 6613.933860661525, 6614.933850661624, 6316.62100273985, 6320.273168585672, 6352.333005034589, 6366.389780190099, 6367.395097465734], "value": 5056.089502807109, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [809.3323008517972, 817.8200795824919, 833.5290560833125, 864.2308345572442, 889.9377043606946, 18839.340623078195, 19289.517762055955, 19836.90081549592, 19849.40451786447, 19851.801481985174, 20297.797022029776, 21021.579568408622, 21228.575428491422, 21239.68140477894, 21370.57258854822, 924.3067699225564, 944.8110377924409, 947.6398968392001, 950.6150009246267, 952.6141912525438, 911.2072496927649, 947.767796889763, 950.1306304731163, 950.5532399772096, 950.6625148072438, 980.6861804222651, 993.5727637116037, 993.9155171810405, 994.8656931314252, 994.9154321882648, 988.9456079915602, 990.3760630802549, 990.8216521026212, 992.1219720547332, 992.2409356842022, 733.6551820644289, 733.7468573342188, 733.8238822682571, 734.192388372791, 735.1912895814614, 18586.44240672782, 19004.61990760177, 19149.80850191494, 19210.519737006685, 20186.798132018637, 20559.177632894665, 20790.272340468164, 20825.479363015893, 21242.044108015176, 21260.893695531424, 16483.67032659352, 16827.74758378625, 16897.0, 16970.91514542432, 17039.914800426042, 3622.5652921649307, 3648.1609229877013, 3659.213269147146, 3662.212624285791, 3663.21240933198, 124481.37759311237, 125794.0, 127865.0, 129334.0, 129691.0, 15968.920155399148, 16014.439494617865, 16276.348946042262, 16353.100579468006, 16459.094749788877, 29446.263843403656, 29548.261293467407, 29650.406991860043, 29787.255318617208, 29793.851030744707, 8233.917660823436, 8324.542150181676, 8325.708600198965, 8349.624266908004, 8366.748997530083, 4224.281872081753, 4294.506131794821, 4302.440682711227, 4302.913941721148, 4306.41863348444, 4851.272309153655, 4947.2579113133315, 4992.775325110379, 5006.173981293119, 5009.44896061433, 893.7477162523855, 906.0531744327194, 918.8116436130578, 924.09438750024, 924.6116631014855, 658.8352911772057, 673.9966300168468, 678.2911857109333, 679.5786612300307, 688.9965550172317, 10924.945375273232, 11458.484368203452, 11527.711807204887, 11750.29498230109, 12351.0, 684.1152624519417, 686.8591938652564, 689.0731965506386, 707.7381368893542, 708.7661071846278, 5406.297181366397, 5505.394406615269, 5529.502344788908, 5542.806001789918, 5616.326040875039, 103059.48470257601, 106656.46671766591, 108083.0, 108103.0, 111889.44055279672, 88998.11001890028, 92146.07853921509, 92455.0, 96913.0, 97281.0, 81970.0, 83664.0, 84613.15386846176, 84624.0, 97819.0, 11887.702807429883, 11933.582324618597, 12006.459709312927, 12587.68530786701, 12592.496300147892, 38485.0, 39067.41398879076, 39109.2178156441, 39330.0, 39606.40590391204, 67701.0, 69828.60342793014, 69970.65014674894, 71854.64072679603, 72120.63939680268, 16728.33388941758, 17171.65656686884, 18719.81280187235, 19000.52498687544, 19198.71201932, 19806.20775168977, 19929.601407972048, 19991.0, 20003.899980500588, 20110.79889201089, 18916.148773304958, 19296.807031930064, 19372.8062719371, 19415.708764368264, 19419.3203237886, 18860.24559017624, 18948.147333369754, 19078.809211907705, 19096.0, 19168.520786980436, 16707.832921671114, 16923.83076169272, 16933.915330423268, 16990.98054116757, 17055.658886821944, 12517.0, 12920.806187907378, 13157.407916643966, 13204.339783010619, 13224.801627975396, 12056.0, 12152.696182595506, 12563.057770667054, 12642.936785316015, 14374.0], "value": 8944.782526780284, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1482.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 106.0, 99.0, 106.0, 115.0, 109.0, 109.0, 98.0, 107.0, 109.0], "value": 105.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 116.0, 138.0, 173.0, 117.0, 139.0, 148.0, 118.0, 166.0, 120.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 162.0, 162.0, 163.0, 167.0, 164.0, 160.0, 164.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 75.0, 180.0, 76.0, 74.0, 74.0, 73.0, 166.0, 74.0], "value": 93.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 134.0, 78.0, 79.0, 78.0, 124.0, 79.0, 79.0, 127.0], "value": 93.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 115.0, 115.0, 113.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 149.0, 148.0, 148.0, 147.0, 147.0, 148.0, 147.0, 148.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 93.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 51.0, 52.0, 52.0, 52.0, 53.0, 52.0, 53.0, 53.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 37.0, 38.0, 37.0, 38.0, 37.0, 39.0, 48.0, 39.0, 39.0], "value": 39.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 75.0, 75.0, 78.0, 72.0, 74.0, 74.0, 79.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 123.0, 118.0, 137.0, 114.0, 121.0, 119.0, 132.0, 116.0, 170.0], "value": 127.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 179.0, 186.0, 173.0, 170.0, 186.0, 166.0, 169.0, 169.0], "value": 174.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 69.0, 66.0, 67.0, 63.0, 64.0, 64.0, 62.0, 66.0], "value": 65.1, "unit": "score"}]}]} 11:32:20 INFO - Return code: 0 11:32:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:32:20 INFO - # TBPL SUCCESS # 11:32:20 INFO - Running post-action listener: _package_coverage_data 11:32:20 INFO - Running post-action listener: _resource_record_post_action 11:32:20 INFO - [mozharness: 2017-08-29 18:32:20.742127Z] Finished run-tests step (success) 11:32:20 INFO - Running post-run listener: _resource_record_post_run 11:32:20 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 7258112; Write bytes: 438415360; Read time: 2368; Write time: 354820 11:32:20 INFO - TinderboxPrint: CPU usage
13.1% 11:32:20 INFO - TinderboxPrint: I/O read bytes / time
7,258,112 / 2,368 11:32:20 INFO - TinderboxPrint: I/O write bytes / time
438,415,360 / 354,820 11:32:20 INFO - TinderboxPrint: CPU idle
8,271.3 (86.9%) 11:32:20 INFO - TinderboxPrint: CPU user
1,193.0 (12.5%) 11:32:20 INFO - TinderboxPrint: Swap in / out
0 / 0 11:32:20 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 114614272; Read time: 44; Write time: 98348 11:32:20 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:32:20 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 5017600; Write bytes: 323801088; Read time: 2164; Write time: 256472 11:32:21 INFO - Running post-run listener: _upload_blobber_files 11:32:21 INFO - Blob upload gear active. 11:32:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:32:21 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:32:21 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:32:21 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:32:21 INFO - (blobuploader) - INFO - Open directory for files ... 11:32:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:32:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:32:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:32:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:22 INFO - (blobuploader) - INFO - Done attempting. 11:32:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:32:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:32:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:32:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:23 INFO - (blobuploader) - INFO - Done attempting. 11:32:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:32:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:32:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:32:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:24 INFO - (blobuploader) - INFO - Done attempting. 11:32:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:32:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:32:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:32:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:31 INFO - (blobuploader) - INFO - Done attempting. 11:32:31 INFO - (blobuploader) - INFO - Iteration through files over. 11:32:31 INFO - Return code: 0 11:32:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:32:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:32:31 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23e18d22fa9f17a92a729cf55c319ae53263ee60eb2b2f891e723dc3eae3f45b6d9a9411ee744505da54f635151a3f88aee4e1d7bf19f0d096f649851885f530", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53553c1a181e2ff281c1d5a44f8cd1efec777fba1919066e8d3a4010eaaaad7355c57db011639330aeba4c223834b21e0a9138bceca39800a97db45a9d4524df", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb4d4ffed794d0983be8896852d15ae8ab7df5053b2c1e7039894da943e8afb4577ef8de5bb2fac3c090fb0e31a75ea4d609254e9a19b39b98e2da453ad7d72a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea7ae2126e6c243d1dfca15b174cfac7eff971f9c81b118773a1fdeddadb42695df35e00a249df06822d8922d9e04d579c12872f7955cb656615ae255eb35e69"} 11:32:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:32:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:32:31 INFO - Contents: 11:32:31 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23e18d22fa9f17a92a729cf55c319ae53263ee60eb2b2f891e723dc3eae3f45b6d9a9411ee744505da54f635151a3f88aee4e1d7bf19f0d096f649851885f530", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53553c1a181e2ff281c1d5a44f8cd1efec777fba1919066e8d3a4010eaaaad7355c57db011639330aeba4c223834b21e0a9138bceca39800a97db45a9d4524df", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb4d4ffed794d0983be8896852d15ae8ab7df5053b2c1e7039894da943e8afb4577ef8de5bb2fac3c090fb0e31a75ea4d609254e9a19b39b98e2da453ad7d72a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea7ae2126e6c243d1dfca15b174cfac7eff971f9c81b118773a1fdeddadb42695df35e00a249df06822d8922d9e04d579c12872f7955cb656615ae255eb35e69"} 11:32:31 INFO - Running post-run listener: copy_logs_to_upload_dir 11:32:31 INFO - Copying logs to upload dir... 11:32:31 INFO - mkdir: /builds/slave/test/build/upload/logs 11:32:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.645967 ========= master_lag: -0.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-08-29 11:32:31.400909) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 11:32:31.405656) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894 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/23e18d22fa9f17a92a729cf55c319ae53263ee60eb2b2f891e723dc3eae3f45b6d9a9411ee744505da54f635151a3f88aee4e1d7bf19f0d096f649851885f530", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53553c1a181e2ff281c1d5a44f8cd1efec777fba1919066e8d3a4010eaaaad7355c57db011639330aeba4c223834b21e0a9138bceca39800a97db45a9d4524df", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb4d4ffed794d0983be8896852d15ae8ab7df5053b2c1e7039894da943e8afb4577ef8de5bb2fac3c090fb0e31a75ea4d609254e9a19b39b98e2da453ad7d72a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea7ae2126e6c243d1dfca15b174cfac7eff971f9c81b118773a1fdeddadb42695df35e00a249df06822d8922d9e04d579c12872f7955cb656615ae255eb35e69"} build_url:https://queue.taskcluster.net/v1/task/J1Wwa5wdSOSum0rQh28Tpg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011519 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/23e18d22fa9f17a92a729cf55c319ae53263ee60eb2b2f891e723dc3eae3f45b6d9a9411ee744505da54f635151a3f88aee4e1d7bf19f0d096f649851885f530", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53553c1a181e2ff281c1d5a44f8cd1efec777fba1919066e8d3a4010eaaaad7355c57db011639330aeba4c223834b21e0a9138bceca39800a97db45a9d4524df", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb4d4ffed794d0983be8896852d15ae8ab7df5053b2c1e7039894da943e8afb4577ef8de5bb2fac3c090fb0e31a75ea4d609254e9a19b39b98e2da453ad7d72a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea7ae2126e6c243d1dfca15b174cfac7eff971f9c81b118773a1fdeddadb42695df35e00a249df06822d8922d9e04d579c12872f7955cb656615ae255eb35e69"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 11:32:31.432154) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:32:31.434550) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1504030289.520193-856188894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004409 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 11:32:31.517044) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 11:32:31.518393) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 11:32:31.518671) ========= ========= Total master_lag: -0.19 =========