builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-020 starttime: 1504186344.02 results: success (0) revision: fb22415719a9d971a2646fa2d1b74e134ca00c3d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-31 06:32:24.017998) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-31 06:32:24.018430) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-31 06:32:24.018701) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-31 06:32:24.035617) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 06:32:24.035869) ========= 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=af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-31 06:32:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 109M=0s 2017-08-31 06:32:24 (109 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145768 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 06:32:24.196437) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 06:32:24.196728) ========= 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=af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015378 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 06:32:24.248491) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-31 06:32:24.248797) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fb22415719a9d971a2646fa2d1b74e134ca00c3d --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 fb22415719a9d971a2646fa2d1b74e134ca00c3d --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=af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-31 06:32:24,286 truncating revision to first 12 chars 2017-08-31 06:32:24,286 Setting DEBUG logging. 2017-08-31 06:32:24,286 attempt 1/10 2017-08-31 06:32:24,286 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fb22415719a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-31 06:32:24,972 unpacking tar archive at: mozilla-central-fb22415719a9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.161603 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-31 06:32:25.427968) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-31 06:32:25.429449) ========= script_repo_revision: fb22415719a9d971a2646fa2d1b74e134ca00c3d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-31 06:32:25.429873) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-31 06:32:25.433195) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-31 06:32:25.445328) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-08-31 06:32:25.445708) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:32:25 INFO - MultiFileLogger online at 20170831 06:32:25 in /builds/slave/test 06:32:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 06:32:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:32:25 INFO - {'append_to_log': False, 06:32:25 INFO - 'base_work_dir': '/builds/slave/test', 06:32:25 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 06:32:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:32:25 INFO - 'branch': 'Firefox-Non-PGO', 06:32:25 INFO - 'buildbot_json_path': 'buildprops.json', 06:32:25 INFO - 'code_coverage': False, 06:32:25 INFO - 'config_files': ('talos/linux_config.py',), 06:32:25 INFO - 'default_actions': ('clobber', 06:32:25 INFO - 'read-buildbot-config', 06:32:25 INFO - 'download-and-extract', 06:32:25 INFO - 'populate-webroot', 06:32:25 INFO - 'create-virtualenv', 06:32:25 INFO - 'install', 06:32:25 INFO - 'setup-mitmproxy', 06:32:25 INFO - 'run-tests'), 06:32:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:32:25 INFO - 'disable_ccov_upload': False, 06:32:25 INFO - 'disable_stylo': False, 06:32:25 INFO - 'download_minidump_stackwalk': True, 06:32:25 INFO - 'download_symbols': 'ondemand', 06:32:25 INFO - 'enable_stylo': False, 06:32:25 INFO - 'enable_webrender': False, 06:32:25 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 06:32:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:32:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:32:25 INFO - 'gecko_profile': False, 06:32:25 INFO - 'gecko_profile_interval': 0, 06:32:25 INFO - 'installer_path': 'installer.exe', 06:32:25 INFO - 'log_level': 'info', 06:32:25 INFO - 'log_name': 'talos', 06:32:25 INFO - 'log_to_console': True, 06:32:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:32:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:32:25 INFO - 'opt_config_files': (), 06:32:25 INFO - 'pip_index': False, 06:32:25 INFO - 'suite': 'dromaeojs-e10s', 06:32:25 INFO - 'system_bits': '32', 06:32:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:32:25 INFO - 'title': 'talos-linux64-ix-020', 06:32:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:32:25 INFO - 'use_talos_json': True, 06:32:25 INFO - 'verify': 'False', 06:32:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:32:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:32:25 INFO - 'work_dir': 'build'} 06:32:25 INFO - [mozharness: 2017-08-31 13:32:25.625292Z] Running clobber step. 06:32:25 INFO - Running pre-action listener: _resource_record_pre_action 06:32:25 INFO - Running main action method: clobber 06:32:25 INFO - rmtree: /builds/slave/test/build 06:32:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:32:27 INFO - Running post-action listener: _resource_record_post_action 06:32:27 INFO - [mozharness: 2017-08-31 13:32:27.234325Z] Finished clobber step (success) 06:32:27 INFO - [mozharness: 2017-08-31 13:32:27.234422Z] Running read-buildbot-config step. 06:32:27 INFO - Running pre-action listener: _resource_record_pre_action 06:32:27 INFO - Running main action method: read_buildbot_config 06:32:27 INFO - Using buildbot properties: 06:32:27 INFO - { 06:32:27 INFO - "project": "", 06:32:27 INFO - "product": "firefox", 06:32:27 INFO - "who": "archaeopteryx@coole-files.de", 06:32:27 INFO - "installer_path": "public/build/target.tar.bz2", 06:32:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 06:32:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 06:32:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 06:32:27 INFO - "stage_platform": "linux64", 06:32:27 INFO - "basedir": "/builds/slave/test", 06:32:27 INFO - "buildnumber": 802, 06:32:27 INFO - "platform": "ubuntu64_hw", 06:32:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 06:32:27 INFO - "slavebuilddir": "test", 06:32:27 INFO - "taskId": "BAJ7vSPMSlG7uI5sCRJz9w", 06:32:27 INFO - "branch": "mozilla-central", 06:32:27 INFO - "script_repo_revision": "production", 06:32:27 INFO - "revision": "fb22415719a9d971a2646fa2d1b74e134ca00c3d", 06:32:27 INFO - "slavename": "talos-linux64-ix-020", 06:32:27 INFO - "repo_path": "mozilla-central" 06:32:27 INFO - } 06:32:27 INFO - Finding installer, test and symbols from parent task. 06:32:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BAJ7vSPMSlG7uI5sCRJz9w'}, attempt #1 06:32:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BAJ7vSPMSlG7uI5sCRJz9w'}, attempt #1 06:32:28 INFO - Task dependencies: RI4nAJyITZ2MQzniRVqbBQ 06:32:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ'}, attempt #1 06:32:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2 06:32:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.test_packages.json 06:32:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.crashreporter-symbols.zip 06:32:28 INFO - Running post-action listener: _resource_record_post_action 06:32:28 INFO - [mozharness: 2017-08-31 13:32:28.771621Z] Finished read-buildbot-config step (success) 06:32:28 INFO - [mozharness: 2017-08-31 13:32:28.771839Z] Running download-and-extract step. 06:32:28 INFO - Running pre-action listener: _resource_record_pre_action 06:32:28 INFO - Running main action method: download_and_extract 06:32:28 INFO - mkdir: /builds/slave/test/build/tests 06:32:28 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')]} 06:32:28 INFO - trying https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.test_packages.json 06:32:28 INFO - Downloading https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 06:32:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 06:32:29 INFO - Downloaded 989 bytes. 06:32:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 06:32:29 INFO - Using the following test package requirements: 06:32:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 06:32:29 INFO - u'common': [u'target.common.tests.zip'], 06:32:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 06:32:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 06:32:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 06:32:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 06:32:29 INFO - u'mozbase': [u'target.common.tests.zip'], 06:32:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 06:32:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 06:32:29 INFO - u'web-platform': [u'target.common.tests.zip', 06:32:29 INFO - u'target.web-platform.tests.tar.gz'], 06:32:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 06:32:29 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 06:32:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.common.tests.zip 06:32:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:32:29 INFO - Fetch https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.common.tests.zip into memory 06:32:30 INFO - Content-Length response header: 54932979 06:32:30 INFO - Bytes received: 54932979 06:32:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 06:32:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.common.tests.zip 06:32:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:32:41 INFO - Fetch https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.common.tests.zip into memory 06:32:42 INFO - Content-Length response header: 54932979 06:32:42 INFO - Bytes received: 54932979 06:32:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.talos.tests.zip 06:32:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 06:32:54 INFO - Fetch https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.talos.tests.zip into memory 06:32:55 INFO - Content-Length response header: 14496650 06:32:55 INFO - Bytes received: 14496650 06:32:55 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')]} 06:32:55 INFO - trying https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2 06:32:55 INFO - Downloading https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 06:32:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 06:32:56 INFO - Downloaded 62671715 bytes. 06:32:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2 06:32:56 INFO - mkdir: /builds/slave/test/properties 06:32:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:32:56 INFO - Writing to file /builds/slave/test/properties/build_url 06:32:56 INFO - Contents: 06:32:56 INFO - build_url:https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2 06:32:56 INFO - Running post-action listener: _resource_record_post_action 06:32:56 INFO - Running post-action listener: find_tests_for_verification 06:32:56 INFO - Running post-action listener: set_extra_try_arguments 06:32:56 INFO - [mozharness: 2017-08-31 13:32:56.818871Z] Finished download-and-extract step (success) 06:32:56 INFO - [mozharness: 2017-08-31 13:32:56.818966Z] Running populate-webroot step. 06:32:56 INFO - Running pre-action listener: _resource_record_pre_action 06:32:56 INFO - Running main action method: populate_webroot 06:32:56 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:32:56 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 06:32:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:32:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:32:56 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 06:32:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:32:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:32:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:32:56 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 06:32:56 INFO - 'talos_options': ['--enable-stylo'], 06:32:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:32:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:32:56 INFO - 'tests': ['damp', 'tps']}, 06:32:56 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 06:32:56 INFO - 'talos_options': ['--enable-stylo'], 06:32:56 INFO - 'tests': ['damp', 'tps']}, 06:32:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:32:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 06:32:56 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 06:32:56 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 06:32:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 06:32:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 06:32:56 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 06:32:56 INFO - 'talos_options': ['--enable-stylo'], 06:32:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 06:32:56 INFO - 'other-e10s': {'tests': ['a11yr', 06:32:56 INFO - 'ts_paint', 06:32:56 INFO - 'tpaint', 06:32:56 INFO - 'sessionrestore', 06:32:56 INFO - 'sessionrestore_many_windows', 06:32:56 INFO - 'sessionrestore_no_auto_restore', 06:32:56 INFO - 'tabpaint']}, 06:32:56 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 06:32:56 INFO - 'tests': ['a11yr', 06:32:56 INFO - 'ts_paint', 06:32:56 INFO - 'tpaint', 06:32:56 INFO - 'sessionrestore', 06:32:56 INFO - 'sessionrestore_many_windows', 06:32:56 INFO - 'sessionrestore_no_auto_restore', 06:32:56 INFO - 'tabpaint']}, 06:32:56 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 06:32:56 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 06:32:56 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 06:32:56 INFO - 'tests': ['bloom_basic_singleton']}, 06:32:56 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 06:32:56 INFO - 'tests': ['bloom_basic']}, 06:32:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:32:56 INFO - 'tsvgr_opacity', 06:32:56 INFO - 'tart', 06:32:56 INFO - 'tscrollx', 06:32:56 INFO - 'tsvg_static']}, 06:32:56 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 06:32:56 INFO - 'tests': ['tsvgx', 06:32:56 INFO - 'tsvgr_opacity', 06:32:56 INFO - 'tart', 06:32:56 INFO - 'tscrollx', 06:32:56 INFO - 'tsvg_static']}, 06:32:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:32:56 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 06:32:56 INFO - 'talos_options': ['--enable-stylo'], 06:32:56 INFO - 'tests': ['tp5o']}, 06:32:56 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 06:32:56 INFO - 'talos_options': ['--mitmproxy', 06:32:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 06:32:56 INFO - '--firstNonBlankPaint'], 06:32:56 INFO - 'tests': ['tp6_google', 06:32:56 INFO - 'tp6_youtube', 06:32:56 INFO - 'tp6_amazon', 06:32:56 INFO - 'tp6_facebook']}, 06:32:56 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 06:32:56 INFO - 'talos_options': ['--enable-stylo', 06:32:56 INFO - '--mitmproxy', 06:32:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 06:32:56 INFO - '--firstNonBlankPaint'], 06:32:56 INFO - 'tests': ['tp6_google', 06:32:56 INFO - 'tp6_youtube', 06:32:56 INFO - 'tp6_amazon', 06:32:56 INFO - 'tp6_facebook']}, 06:32:56 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 06:32:56 INFO - 'talos_options': ['--enable-stylo', 06:32:56 INFO - '--stylo-threads=1', 06:32:56 INFO - '--mitmproxy', 06:32:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 06:32:56 INFO - '--firstNonBlankPaint'], 06:32:56 INFO - 'tests': ['tp6_google', 06:32:56 INFO - 'tp6_youtube', 06:32:56 INFO - 'tp6_amazon', 06:32:56 INFO - 'tp6_facebook']}, 06:32:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:32:56 INFO - 'talos_options': ['--xperf_path', 06:32:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:32:56 INFO - 'tests': ['tp5n']}, 06:32:56 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 06:32:56 INFO - 'talos_options': ['--enable-stylo', 06:32:56 INFO - '--xperf_path', 06:32:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:32:56 INFO - 'tests': ['tp5n']}}} 06:32:56 INFO - Running post-action listener: _resource_record_post_action 06:32:56 INFO - [mozharness: 2017-08-31 13:32:56.833328Z] Finished populate-webroot step (success) 06:32:56 INFO - [mozharness: 2017-08-31 13:32:56.833416Z] Running create-virtualenv step. 06:32:56 INFO - Running pre-action listener: _resource_record_pre_action 06:32:56 INFO - Running main action method: create_virtualenv 06:32:56 INFO - Creating virtualenv /builds/slave/test/build/venv 06:32:56 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:32:56 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 06:32:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 06:32:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:32:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:32:57 INFO - Installing distribute.............................................................................................................................................................................................done. 06:32:57 INFO - Installing pip...............done. 06:32:57 INFO - Return code: 0 06:32:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:32:57 INFO - Reading from file tmpfile_stdout 06:32:57 INFO - Output received: 06:32:57 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 06:32:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:32:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:32:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1824a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19cbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8580>, '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 0x19c9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c9910>, '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': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', '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 06:32:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:32:57 INFO - Using env: {'DISPLAY': ':0', 06:32:57 INFO - 'HOME': '/home/cltbld', 06:32:57 INFO - 'LANG': 'en_US.UTF-8', 06:32:57 INFO - 'LANGUAGE': 'en_US:en', 06:32:57 INFO - 'LOGNAME': 'cltbld', 06:32:57 INFO - 'MAIL': '/var/mail/cltbld', 06:32:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:32:57 INFO - 'MOZ_NO_REMOTE': '1', 06:32:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:32:57 INFO - 'NO_EM_RESTART': '1', 06:32:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:32:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:32:57 INFO - 'PWD': '/builds/slave/test', 06:32:57 INFO - 'SHELL': '/bin/bash', 06:32:57 INFO - 'SHLVL': '1', 06:32:57 INFO - 'TERM': 'linux', 06:32:57 INFO - 'TMOUT': '86400', 06:32:57 INFO - 'USER': 'cltbld', 06:32:57 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', 06:32:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:32:57 INFO - '_': '/tools/buildbot/bin/python'} 06:32:59 INFO - Ignoring indexes: http://pypi.python.org/simple/ 06:32:59 INFO - Downloading/unpacking pip>=1.5 06:32:59 INFO - Running setup.py egg_info for package pip 06:32:59 INFO - warning: no files found matching 'pip/cacert.pem' 06:32:59 INFO - warning: no files found matching '*.html' under directory 'docs' 06:32:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 06:32:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 06:32:59 INFO - Installing collected packages: pip 06:32:59 INFO - Found existing installation: pip 1.1 06:32:59 INFO - Uninstalling pip: 06:32:59 INFO - Successfully uninstalled pip 06:32:59 INFO - Running setup.py install for pip 06:32:59 INFO - warning: no files found matching 'pip/cacert.pem' 06:32:59 INFO - warning: no files found matching '*.html' under directory 'docs' 06:32:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 06:32:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 06:32:59 INFO - Installing pip script to /builds/slave/test/build/venv/bin 06:32:59 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 06:32:59 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 06:32:59 INFO - Successfully installed pip 06:32:59 INFO - Cleaning up... 06:32:59 INFO - Return code: 0 06:32:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:32:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x165c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1824a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19cbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8580>, '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 0x19c9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c9910>, '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': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', '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 06:32:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:32:59 INFO - Using env: {'DISPLAY': ':0', 06:32:59 INFO - 'HOME': '/home/cltbld', 06:32:59 INFO - 'LANG': 'en_US.UTF-8', 06:32:59 INFO - 'LANGUAGE': 'en_US:en', 06:32:59 INFO - 'LOGNAME': 'cltbld', 06:32:59 INFO - 'MAIL': '/var/mail/cltbld', 06:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:32:59 INFO - 'MOZ_NO_REMOTE': '1', 06:32:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:32:59 INFO - 'NO_EM_RESTART': '1', 06:32:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:32:59 INFO - 'PWD': '/builds/slave/test', 06:32:59 INFO - 'SHELL': '/bin/bash', 06:32:59 INFO - 'SHLVL': '1', 06:32:59 INFO - 'TERM': 'linux', 06:32:59 INFO - 'TMOUT': '86400', 06:32:59 INFO - 'USER': 'cltbld', 06:32:59 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', 06:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:32:59 INFO - '_': '/tools/buildbot/bin/python'} 06:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:32:59 INFO - Downloading/unpacking psutil>=3.1.1 06:32:59 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 06:32:59 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 06:32:59 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 06:32:59 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 06:33:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:33:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:33:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:33:00 INFO - Installing collected packages: psutil 06:33:00 INFO - Running setup.py install for psutil 06:33:00 INFO - building 'psutil._psutil_linux' extension 06:33:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 06:33:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 06:33:00 INFO - building 'psutil._psutil_posix' extension 06:33:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 06:33:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 06:33:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:33:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:33:00 INFO - Successfully installed psutil 06:33:00 INFO - Cleaning up... 06:33:00 INFO - Return code: 0 06:33:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:33:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1824a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19cbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8580>, '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 0x19c9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c9910>, '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': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', '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 06:33:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:33:00 INFO - Using env: {'DISPLAY': ':0', 06:33:00 INFO - 'HOME': '/home/cltbld', 06:33:00 INFO - 'LANG': 'en_US.UTF-8', 06:33:00 INFO - 'LANGUAGE': 'en_US:en', 06:33:00 INFO - 'LOGNAME': 'cltbld', 06:33:00 INFO - 'MAIL': '/var/mail/cltbld', 06:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:33:00 INFO - 'MOZ_NO_REMOTE': '1', 06:33:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:33:00 INFO - 'NO_EM_RESTART': '1', 06:33:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:33:00 INFO - 'PWD': '/builds/slave/test', 06:33:00 INFO - 'SHELL': '/bin/bash', 06:33:00 INFO - 'SHLVL': '1', 06:33:00 INFO - 'TERM': 'linux', 06:33:00 INFO - 'TMOUT': '86400', 06:33:00 INFO - 'USER': 'cltbld', 06:33:00 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', 06:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:33:00 INFO - '_': '/tools/buildbot/bin/python'} 06:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:33:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:33:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:33:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:33:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:33:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:33:02 INFO - Installing collected packages: mozsystemmonitor 06:33:02 INFO - Running setup.py install for mozsystemmonitor 06:33:02 INFO - Successfully installed mozsystemmonitor 06:33:02 INFO - Cleaning up... 06:33:02 INFO - Return code: 0 06:33:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:33:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x165c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1824a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19cbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8580>, '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 0x19c9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c9910>, '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': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', '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 06:33:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:33:02 INFO - Using env: {'DISPLAY': ':0', 06:33:02 INFO - 'HOME': '/home/cltbld', 06:33:02 INFO - 'LANG': 'en_US.UTF-8', 06:33:02 INFO - 'LANGUAGE': 'en_US:en', 06:33:02 INFO - 'LOGNAME': 'cltbld', 06:33:02 INFO - 'MAIL': '/var/mail/cltbld', 06:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:33:02 INFO - 'MOZ_NO_REMOTE': '1', 06:33:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:33:02 INFO - 'NO_EM_RESTART': '1', 06:33:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:33:02 INFO - 'PWD': '/builds/slave/test', 06:33:02 INFO - 'SHELL': '/bin/bash', 06:33:02 INFO - 'SHLVL': '1', 06:33:02 INFO - 'TERM': 'linux', 06:33:02 INFO - 'TMOUT': '86400', 06:33:02 INFO - 'USER': 'cltbld', 06:33:02 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', 06:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:33:02 INFO - '_': '/tools/buildbot/bin/python'} 06:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:33:02 INFO - Downloading/unpacking jsonschema==2.5.1 06:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:33:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:33:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:33:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:33:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:33:03 INFO - Downloading functools32-3.2.3-2.tar.gz 06:33:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:33:03 INFO - warning: no files found matching '*.txt' 06:33:03 INFO - no previously-included directories found matching 'build' 06:33:03 INFO - no previously-included directories found matching 'dist' 06:33:03 INFO - no previously-included directories found matching '.git*' 06:33:03 INFO - Installing collected packages: jsonschema, functools32 06:33:03 INFO - Running setup.py install for functools32 06:33:04 INFO - warning: no files found matching '*.txt' 06:33:04 INFO - no previously-included directories found matching 'build' 06:33:04 INFO - no previously-included directories found matching 'dist' 06:33:04 INFO - no previously-included directories found matching '.git*' 06:33:04 INFO - Successfully installed jsonschema functools32 06:33:04 INFO - Cleaning up... 06:33:04 INFO - Return code: 0 06:33:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:33: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')]} 06:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:33: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')]} 06:33:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:33:04 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 0x165c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1824a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19cbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8580>, '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 0x19c9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c9910>, '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': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', '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 06:33:04 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 06:33:04 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 06:33:04 INFO - Using env: {'DISPLAY': ':0', 06:33:04 INFO - 'HOME': '/home/cltbld', 06:33:04 INFO - 'LANG': 'en_US.UTF-8', 06:33:04 INFO - 'LANGUAGE': 'en_US:en', 06:33:04 INFO - 'LOGNAME': 'cltbld', 06:33:04 INFO - 'MAIL': '/var/mail/cltbld', 06:33:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:33:04 INFO - 'MOZ_NO_REMOTE': '1', 06:33:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:33:04 INFO - 'NO_EM_RESTART': '1', 06:33:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:33:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:33:04 INFO - 'PWD': '/builds/slave/test', 06:33:04 INFO - 'SHELL': '/bin/bash', 06:33:04 INFO - 'SHLVL': '1', 06:33:04 INFO - 'TERM': 'linux', 06:33:04 INFO - 'TMOUT': '86400', 06:33:04 INFO - 'USER': 'cltbld', 06:33:04 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', 06:33:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:33:04 INFO - '_': '/tools/buildbot/bin/python'} 06:33:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:33:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:33:04 INFO - Cleaning up... 06:33:04 INFO - Return code: 0 06:33:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:33: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')]} 06:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:33: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')]} 06:33:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:33:04 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 0x165c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1824a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19cbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8580>, '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 0x19c9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c9910>, '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': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', '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 06:33:04 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 06:33:04 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 06:33:04 INFO - Using env: {'DISPLAY': ':0', 06:33:04 INFO - 'HOME': '/home/cltbld', 06:33:04 INFO - 'LANG': 'en_US.UTF-8', 06:33:04 INFO - 'LANGUAGE': 'en_US:en', 06:33:04 INFO - 'LOGNAME': 'cltbld', 06:33:04 INFO - 'MAIL': '/var/mail/cltbld', 06:33:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:33:04 INFO - 'MOZ_NO_REMOTE': '1', 06:33:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:33:04 INFO - 'NO_EM_RESTART': '1', 06:33:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:33:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:33:04 INFO - 'PWD': '/builds/slave/test', 06:33:04 INFO - 'SHELL': '/bin/bash', 06:33:04 INFO - 'SHLVL': '1', 06:33:04 INFO - 'TERM': 'linux', 06:33:04 INFO - 'TMOUT': '86400', 06:33:04 INFO - 'USER': 'cltbld', 06:33:04 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', 06:33:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:33:04 INFO - '_': '/tools/buildbot/bin/python'} 06:33:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:33:04 INFO - Downloading/unpacking blobuploader==1.2.4 06:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:33:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:33:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:33:05 INFO - Downloading blobuploader-1.2.4.tar.gz 06:33:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:33:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:33:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:33:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:33:06 INFO - Downloading docopt-0.6.1.tar.gz 06:33:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:33:06 INFO - Installing collected packages: blobuploader, requests, docopt 06:33:06 INFO - Running setup.py install for blobuploader 06:33:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:33:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:33:06 INFO - Running setup.py install for requests 06:33:06 INFO - Running setup.py install for docopt 06:33:06 INFO - Successfully installed blobuploader requests docopt 06:33:06 INFO - Cleaning up... 06:33:06 INFO - Return code: 0 06:33:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:33:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:33:06 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 0x165c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1824a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19cbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8580>, '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 0x19c9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c9910>, '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': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', '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 06:33:06 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 06:33:06 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 06:33:06 INFO - Using env: {'DISPLAY': ':0', 06:33:06 INFO - 'HOME': '/home/cltbld', 06:33:06 INFO - 'LANG': 'en_US.UTF-8', 06:33:06 INFO - 'LANGUAGE': 'en_US:en', 06:33:06 INFO - 'LOGNAME': 'cltbld', 06:33:06 INFO - 'MAIL': '/var/mail/cltbld', 06:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:33:06 INFO - 'MOZ_NO_REMOTE': '1', 06:33:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:33:06 INFO - 'NO_EM_RESTART': '1', 06:33:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:33:06 INFO - 'PWD': '/builds/slave/test', 06:33:06 INFO - 'SHELL': '/bin/bash', 06:33:06 INFO - 'SHLVL': '1', 06:33:06 INFO - 'TERM': 'linux', 06:33:06 INFO - 'TMOUT': '86400', 06:33:06 INFO - 'USER': 'cltbld', 06:33:06 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', 06:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:33:06 INFO - '_': '/tools/buildbot/bin/python'} 06:33:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:33:07 INFO - Running setup.py (path:/tmp/pip-tPGp5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:33:07 INFO - Running setup.py (path:/tmp/pip-Tkzb9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:33:07 INFO - Running setup.py (path:/tmp/pip-f1Z4p6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:33:07 INFO - Running setup.py (path:/tmp/pip-GdJV8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:33:07 INFO - Running setup.py (path:/tmp/pip-HBAoZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:33:07 INFO - Running setup.py (path:/tmp/pip-vOYnjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:33:07 INFO - Running setup.py (path:/tmp/pip-GQV_KO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:33:07 INFO - Running setup.py (path:/tmp/pip-BmT0By-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:33:07 INFO - Running setup.py (path:/tmp/pip-CG1ZDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:33:07 INFO - Running setup.py (path:/tmp/pip-ftjmFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:33:07 INFO - Running setup.py (path:/tmp/pip-Ob3wnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:33:07 INFO - Running setup.py (path:/tmp/pip-hogoxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:33:07 INFO - Running setup.py (path:/tmp/pip-l7QpD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:33:07 INFO - Running setup.py (path:/tmp/pip-CMfR1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:33:08 INFO - Running setup.py (path:/tmp/pip-mtQ4Nb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:33:08 INFO - Running setup.py (path:/tmp/pip-hFyWhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:33:08 INFO - Running setup.py (path:/tmp/pip-ixPI_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:33:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:33:08 INFO - Running setup.py install for manifestparser 06:33:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:33:08 INFO - Running setup.py install for mozcrash 06:33:08 INFO - Running setup.py install for mozdebug 06:33:08 INFO - Running setup.py install for mozdevice 06:33:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:33:08 INFO - Running setup.py install for mozfile 06:33:08 INFO - Running setup.py install for mozhttpd 06:33:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:33:08 INFO - Running setup.py install for mozinfo 06:33:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:33:08 INFO - Running setup.py install for mozInstall 06:33:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:33:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:33:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:33:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:33:09 INFO - Running setup.py install for mozleak 06:33:09 INFO - Running setup.py install for mozlog 06:33:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:33:09 INFO - Running setup.py install for moznetwork 06:33:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:33:09 INFO - Running setup.py install for mozprocess 06:33:09 INFO - Running setup.py install for mozprofile 06:33:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:33:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:33:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:33:09 INFO - Running setup.py install for mozrunner 06:33:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:33:09 INFO - Running setup.py install for mozscreenshot 06:33:09 INFO - Running setup.py install for moztest 06:33:09 INFO - Running setup.py install for mozversion 06:33:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:33:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:33:09 INFO - Cleaning up... 06:33:10 INFO - Return code: 0 06:33:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:33:10 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')]} 06:33:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:33:10 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')]} 06:33:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:33:10 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 0x165c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1824a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19cbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8580>, '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 0x19c9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c9910>, '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': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', '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 06:33:10 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 06:33:10 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 06:33:10 INFO - Using env: {'DISPLAY': ':0', 06:33:10 INFO - 'HOME': '/home/cltbld', 06:33:10 INFO - 'LANG': 'en_US.UTF-8', 06:33:10 INFO - 'LANGUAGE': 'en_US:en', 06:33:10 INFO - 'LOGNAME': 'cltbld', 06:33:10 INFO - 'MAIL': '/var/mail/cltbld', 06:33:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:33:10 INFO - 'MOZ_NO_REMOTE': '1', 06:33:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:33:10 INFO - 'NO_EM_RESTART': '1', 06:33:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:33:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:33:10 INFO - 'PWD': '/builds/slave/test', 06:33:10 INFO - 'SHELL': '/bin/bash', 06:33:10 INFO - 'SHLVL': '1', 06:33:10 INFO - 'TERM': 'linux', 06:33:10 INFO - 'TMOUT': '86400', 06:33:10 INFO - 'USER': 'cltbld', 06:33:10 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', 06:33:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:33:10 INFO - '_': '/tools/buildbot/bin/python'} 06:33:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:33:10 INFO - Running setup.py (path:/tmp/pip-OUiYYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:33:10 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)) 06:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:33:10 INFO - Running setup.py (path:/tmp/pip-hmUo8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:33:10 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)) 06:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:33:10 INFO - Running setup.py (path:/tmp/pip-0y0mK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:33:10 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)) 06:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:33:10 INFO - Running setup.py (path:/tmp/pip-Sr6XOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:33:10 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)) 06:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:33:10 INFO - Running setup.py (path:/tmp/pip-IPsDyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:33:10 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)) 06:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:33:10 INFO - Running setup.py (path:/tmp/pip-AdzwcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:33:10 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)) 06:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:33:10 INFO - Running setup.py (path:/tmp/pip-YDB60C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:33:10 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)) 06:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:33:10 INFO - Running setup.py (path:/tmp/pip-6_13Sa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:33:10 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)) 06:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:33:10 INFO - Running setup.py (path:/tmp/pip-pH0Ev6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:33:10 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)) 06:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:33:10 INFO - Running setup.py (path:/tmp/pip-VEMf5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:33:10 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)) 06:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:33:10 INFO - Running setup.py (path:/tmp/pip-A3Te6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:33:10 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)) 06:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:33:11 INFO - Running setup.py (path:/tmp/pip-Tc9vSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:33:11 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)) 06:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:33:11 INFO - Running setup.py (path:/tmp/pip-MjAaTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:33:11 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)) 06:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:33:11 INFO - Running setup.py (path:/tmp/pip-xKZ3oA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:33:11 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)) 06:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:33:11 INFO - Running setup.py (path:/tmp/pip-Vbcgg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:33:11 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)) 06:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:33:11 INFO - Running setup.py (path:/tmp/pip-QlV4dZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:33:11 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)) 06:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:33:11 INFO - Running setup.py (path:/tmp/pip-eQ5CKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:33:11 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)) 06:33:11 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)) 06:33:11 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)) 06:33:11 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)) 06:33:11 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)) 06:33:11 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)) 06:33:11 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)) 06:33:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:33: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 06:33: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 06:33: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 06:33: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 06:33:12 INFO - Downloading blessings-1.6.tar.gz 06:33:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:33:12 INFO - Installing collected packages: blessings 06:33:12 INFO - Running setup.py install for blessings 06:33:12 INFO - Successfully installed blessings 06:33:12 INFO - Cleaning up... 06:33:12 INFO - Return code: 0 06:33:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:33:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:33:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:33:12 INFO - Reading from file tmpfile_stdout 06:33:12 INFO - Current package versions: 06:33:12 INFO - argparse == 1.2.1 06:33:12 INFO - blessings == 1.6 06:33:12 INFO - blobuploader == 1.2.4 06:33:12 INFO - docopt == 0.6.1 06:33:12 INFO - functools32 == 3.2.3-2 06:33:12 INFO - jsonschema == 2.5.1 06:33:12 INFO - manifestparser == 1.1 06:33:12 INFO - mozInstall == 1.13 06:33:12 INFO - mozcrash == 1.0 06:33:12 INFO - mozdebug == 0.1 06:33:12 INFO - mozdevice == 0.51 06:33:12 INFO - mozfile == 1.2 06:33:12 INFO - mozhttpd == 0.7 06:33:12 INFO - mozinfo == 0.10 06:33:12 INFO - mozleak == 0.1 06:33:12 INFO - mozlog == 3.5 06:33:12 INFO - moznetwork == 0.27 06:33:12 INFO - mozprocess == 0.25 06:33:12 INFO - mozprofile == 0.28 06:33:12 INFO - mozrunner == 6.13 06:33:12 INFO - mozscreenshot == 0.1 06:33:12 INFO - mozsystemmonitor == 0.3 06:33:12 INFO - moztest == 0.8 06:33:12 INFO - mozversion == 1.4 06:33:12 INFO - psutil == 3.1.1 06:33:12 INFO - requests == 1.2.3 06:33:12 INFO - wsgiref == 0.1.2 06:33:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:33:12 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')]} 06:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:33:12 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')]} 06:33:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:33:12 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 0x165c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1824a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19cbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8580>, '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 0x19c9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c9910>, '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': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', '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 06:33:12 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 06:33:12 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 06:33:12 INFO - Using env: {'DISPLAY': ':0', 06:33:12 INFO - 'HOME': '/home/cltbld', 06:33:12 INFO - 'LANG': 'en_US.UTF-8', 06:33:12 INFO - 'LANGUAGE': 'en_US:en', 06:33:12 INFO - 'LOGNAME': 'cltbld', 06:33:12 INFO - 'MAIL': '/var/mail/cltbld', 06:33:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:33:12 INFO - 'MOZ_NO_REMOTE': '1', 06:33:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:33:12 INFO - 'NO_EM_RESTART': '1', 06:33:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:33:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:33:12 INFO - 'PWD': '/builds/slave/test', 06:33:12 INFO - 'SHELL': '/bin/bash', 06:33:12 INFO - 'SHLVL': '1', 06:33:12 INFO - 'TERM': 'linux', 06:33:12 INFO - 'TMOUT': '86400', 06:33:12 INFO - 'USER': 'cltbld', 06:33:12 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', 06:33:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:33:12 INFO - '_': '/tools/buildbot/bin/python'} 06:33:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:33:13 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)) 06:33:13 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)) 06:33:13 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)) 06:33:13 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)) 06:33:13 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)) 06:33:13 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)) 06:33:13 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)) 06:33:13 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)) 06:33:13 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)) 06:33:13 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:33: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 06:33: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 06:33: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 06:33: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 06:33:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 06:33:14 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)) 06:33:14 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)) 06:33:14 INFO - Installing collected packages: simplejson 06:33:14 INFO - Running setup.py install for simplejson 06:33:14 INFO - building 'simplejson._speedups' extension 06:33:14 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 06:33:15 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 06:33:15 INFO - Successfully installed simplejson 06:33:15 INFO - Cleaning up... 06:33:15 INFO - Return code: 0 06:33:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:33: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')]} 06:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:33: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')]} 06:33:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:33: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1824a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19cbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8580>, '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 0x19c9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c9910>, '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': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', '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 06:33: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', 'jsonschema'] in /builds/slave/test/build 06:33: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 jsonschema 06:33:15 INFO - Using env: {'DISPLAY': ':0', 06:33:15 INFO - 'HOME': '/home/cltbld', 06:33:15 INFO - 'LANG': 'en_US.UTF-8', 06:33:15 INFO - 'LANGUAGE': 'en_US:en', 06:33:15 INFO - 'LOGNAME': 'cltbld', 06:33:15 INFO - 'MAIL': '/var/mail/cltbld', 06:33:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:33:15 INFO - 'MOZ_NO_REMOTE': '1', 06:33:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:33:15 INFO - 'NO_EM_RESTART': '1', 06:33:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:33:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:33:15 INFO - 'PWD': '/builds/slave/test', 06:33:15 INFO - 'SHELL': '/bin/bash', 06:33:15 INFO - 'SHLVL': '1', 06:33:15 INFO - 'TERM': 'linux', 06:33:15 INFO - 'TMOUT': '86400', 06:33:15 INFO - 'USER': 'cltbld', 06:33:15 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', 06:33:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:33:15 INFO - '_': '/tools/buildbot/bin/python'} 06:33:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:33:15 INFO - Cleaning up... 06:33:15 INFO - Return code: 0 06:33:15 INFO - Running post-action listener: _resource_record_post_action 06:33:15 INFO - Running post-action listener: _start_resource_monitoring 06:33:15 INFO - Starting resource monitoring. 06:33:15 INFO - [mozharness: 2017-08-31 13:33:15.674672Z] Finished create-virtualenv step (success) 06:33:15 INFO - [mozharness: 2017-08-31 13:33:15.675186Z] Running install step. 06:33:15 INFO - Running pre-action listener: _resource_record_pre_action 06:33:15 INFO - Running main action method: install 06:33:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:33:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:33:15 INFO - Reading from file tmpfile_stdout 06:33:15 INFO - Detecting whether we're running mozinstall >=1.0... 06:33:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:33:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:33:16 INFO - Reading from file tmpfile_stdout 06:33:16 INFO - Output received: 06:33:16 INFO - Usage: mozinstall [options] installer 06:33:16 INFO - Options: 06:33:16 INFO - -h, --help show this help message and exit 06:33:16 INFO - -d DEST, --destination=DEST 06:33:16 INFO - Directory to install application into. [default: 06:33:16 INFO - "/builds/slave/test"] 06:33:16 INFO - --app=APP Application being installed. [default: firefox] 06:33:16 INFO - mkdir: /builds/slave/test/build/application 06:33:16 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'] 06:33:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 06:33:32 INFO - Reading from file tmpfile_stdout 06:33:32 INFO - Output received: 06:33:32 INFO - /builds/slave/test/build/application/firefox/firefox 06:33:32 INFO - Running post-action listener: _resource_record_post_action 06:33:32 INFO - [mozharness: 2017-08-31 13:33:32.585887Z] Finished install step (success) 06:33:32 INFO - [mozharness: 2017-08-31 13:33:32.586037Z] Running setup-mitmproxy step. 06:33:32 INFO - Running pre-action listener: _resource_record_pre_action 06:33:32 INFO - Running main action method: setup_mitmproxy 06:33:32 INFO - Skipping: mitmproxy is not required 06:33:32 INFO - Running post-action listener: _resource_record_post_action 06:33:32 INFO - [mozharness: 2017-08-31 13:33:32.586564Z] Finished setup-mitmproxy step (success) 06:33:32 INFO - [mozharness: 2017-08-31 13:33:32.586665Z] Running run-tests step. 06:33:32 INFO - Running pre-action listener: _resource_record_pre_action 06:33:32 INFO - Running pre-action listener: _set_gcov_prefix 06:33:32 INFO - Running main action method: run_tests 06:33:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:33:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:33:32 INFO - Python 2.7.3 06:33:32 INFO - Return code: 0 06:33:32 INFO - grabbing minidump binary from tooltool 06:33:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:33:32 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 0x19c8580>, '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 0x19c9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c9910>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:33:32 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 06:33:32 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 06:33:32 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 06:33:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:33:32 INFO - Return code: 0 06:33:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:33:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:33:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:33:32 INFO - ENV: RUST_BACKTRACE is now full 06:33:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:33:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:33:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 06:33:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-020 --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 06:33:32 INFO - Using env: {'DISPLAY': ':0', 06:33:32 INFO - 'HOME': '/home/cltbld', 06:33:32 INFO - 'LANG': 'en_US.UTF-8', 06:33:32 INFO - 'LANGUAGE': 'en_US:en', 06:33:32 INFO - 'LOGNAME': 'cltbld', 06:33:32 INFO - 'MAIL': '/var/mail/cltbld', 06:33:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:33:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:33:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:33:32 INFO - 'MOZ_NO_REMOTE': '1', 06:33:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:33:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:33:32 INFO - 'NO_EM_RESTART': '1', 06:33:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:33:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:33:32 INFO - 'PWD': '/builds/slave/test', 06:33:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:33:32 INFO - 'RUST_BACKTRACE': 'full', 06:33:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:33:32 INFO - 'SHELL': '/bin/bash', 06:33:32 INFO - 'SHLVL': '1', 06:33:32 INFO - 'TERM': 'linux', 06:33:32 INFO - 'TMOUT': '86400', 06:33:32 INFO - 'USER': 'cltbld', 06:33:32 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', 06:33:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:33:32 INFO - '_': '/tools/buildbot/bin/python'} 06:33:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 06:33:32 INFO - mozversion application_buildid: 20170831123711 06:33:32 INFO - mozversion application_changeset: fb22415719a9d971a2646fa2d1b74e134ca00c3d 06:33:32 INFO - mozversion application_display_name: Nightly 06:33:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:33:32 INFO - mozversion application_name: Firefox 06:33:32 INFO - mozversion application_remotingname: firefox 06:33:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:33:32 INFO - mozversion application_vendor: Mozilla 06:33:32 INFO - mozversion application_version: 57.0a1 06:33:32 INFO - mozversion platform_buildid: 20170831123711 06:33:32 INFO - mozversion platform_changeset: fb22415719a9d971a2646fa2d1b74e134ca00c3d 06:33:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:33:32 INFO - mozversion platform_version: 57.0a1 06:33:32 INFO - using testdate: 1504186412 06:33:32 INFO - actual date: 1504186412 06:33:32 INFO - starting webserver on 'localhost:47359' 06:33:32 INFO - SUITE-START | Running 2 tests 06:33:32 INFO - TEST-START | dromaeo_css 06:33:32 INFO - Initialising browser for dromaeo_css test... 06:33:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47359/getInfo.html -profile /tmp/tmpqG3k6M/profile 06:33:32 INFO - TEST-INFO | started process 4333 (/builds/slave/test/build/application/firefox/firefox http://localhost:47359/getInfo.html) 06:33:39 INFO - TEST-INFO | 4333: exit 0 06:33:39 INFO - Browser initialized. 06:33:39 INFO - Running cycle 1/1 for dromaeo_css test... 06:33:39 INFO - Using env: {'DISPLAY': ':0', 06:33:39 INFO - 'HOME': '/home/cltbld', 06:33:39 INFO - 'JSGC_DISABLE_POISONING': '1', 06:33:39 INFO - 'LANG': 'en_US.UTF-8', 06:33:39 INFO - 'LANGUAGE': 'en_US:en', 06:33:39 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 06:33:39 INFO - 'LOGNAME': 'cltbld', 06:33:39 INFO - 'MAIL': '/var/mail/cltbld', 06:33:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:33:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:33:39 INFO - 'MOZ_CRASHREPORTER': '1', 06:33:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:33:39 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 06:33:39 INFO - 'MOZ_NO_REMOTE': '1', 06:33:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:33:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:33:39 INFO - 'NO_EM_RESTART': '1', 06:33:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:33:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:33:39 INFO - 'PWD': '/builds/slave/test', 06:33:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:33:39 INFO - 'RUST_BACKTRACE': 'full', 06:33:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:33:39 INFO - 'SHELL': '/bin/bash', 06:33:39 INFO - 'SHLVL': '1', 06:33:39 INFO - 'TERM': 'linux', 06:33:39 INFO - 'TMOUT': '86400', 06:33:39 INFO - 'USER': 'cltbld', 06:33:39 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', 06:33:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:33:39 INFO - '_': '/tools/buildbot/bin/python'} 06:33:39 INFO - TEST-INFO | started process 4517 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqG3k6M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 06:33:40 INFO - PID 4517 | 06:33:40 INFO - PID 4517 | (/builds/slave/test/build/application/firefox/firefox:4568): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 06:33:40 INFO - PID 4517 | 06:33:50 INFO - PID 4517 | RSS: Main: 168579072 06:33:50 INFO - PID 4517 | 06:36:50 INFO - PID 4517 | Cycle 1(1): loaded http://localhost:47359/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47359/tests/dromaeo/cssquery-ext.html) 06:36:51 INFO - PID 4517 | RSS: Main: 171909120 06:36:51 INFO - PID 4517 | 06:39:52 INFO - PID 4517 | Cycle 1(1): loaded http://localhost:47359/tests/dromaeo/cssquery-ext.html (next: http://localhost:47359/tests/dromaeo/cssquery-jquery.html) 06:39:53 INFO - PID 4517 | RSS: Main: 168124416 06:39:53 INFO - PID 4517 | 06:42:55 INFO - PID 4517 | Cycle 1(1): loaded http://localhost:47359/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47359/tests/dromaeo/cssquery-mootools.html) 06:42:55 INFO - PID 4517 | RSS: Main: 170455040 06:42:55 INFO - PID 4517 | 06:45:57 INFO - PID 4517 | Cycle 1(1): loaded http://localhost:47359/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47359/tests/dromaeo/cssquery-prototype.html) 06:45:58 INFO - PID 4517 | RSS: Main: 169082880 06:45:58 INFO - PID 4517 | 06:49:01 INFO - PID 4517 | Cycle 1(1): loaded http://localhost:47359/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47359/tests/dromaeo/cssquery-yui.html) 06:49:02 INFO - PID 4517 | RSS: Main: 170827776 06:49:02 INFO - PID 4517 | 06:52:04 INFO - PID 4517 | Cycle 1(1): loaded http://localhost:47359/tests/dromaeo/cssquery-yui.html (next: http://localhost:47359/tests/dromaeo/cssquery-dojo.html) 06:52:04 INFO - PID 4517 | RSS: Main: 171327488 06:52:04 INFO - PID 4517 | 06:52:04 INFO - PID 4517 | __start_tp_report 06:52:04 INFO - PID 4517 | _x_x_mozilla_page_load 06:52:04 INFO - PID 4517 | _x_x_mozilla_page_load_details 06:52:04 INFO - PID 4517 | |i|pagename|runs| 06:52:04 INFO - PID 4517 | |0;dojo.html;3286.293446908914;3314.585676790401;3316.286998295366;3339.1985923378397;3340.5156252343395;6287.08837218603;6293.496520278372;6326.936730632692;6326.968365158174;6359.55483116182;6199.566030377875;6305.085762564426;6305.905411418821;6322.020086886529;6348.777792777254;5790.131480277961;5812.593118481687;5815.040518314473;5818.476337129658;5843.79546715865;5835.562332825034;5839.978003849331;5879.941200588003;5909.261342332208;5931.228940237763;5861.296644402678;5872.7944521941745;5891.5286777057945;5929.792457263997;5939.910901336461;5531.557475401958;5734.225879506255;5832.212651292064;5914.822555323347;5915.112733090006;5557.888842223173;5743.684097374643;5777.913331300034;5785.045467497858;5839.036558967765;6271.310155882849;6284.34014428483;6321.87356252877;6354.650494222862;6385.2656944451055;6703.597784132919;6719.0593316935665;6722.462203023746;6731.427828634572;6802.387785099315;6768.187817461887;6852.417544508723;6919.169699636025;6927.930720692779;6929.618870962095;6320.5891617044745;6348.9365106348805;6349.841253968688;6429.774957876499;6436.774712885027;6800.353966373186;6963.303669633097;6989.790306290819;7085.256048114977;7087.787366379017;6555.475561955032;6556.541042127005;6561.573497722633;6568.86862262752;6578.04618330349;6741.460683145337;6751.0886030386255;6818.113645226089;6822.795316140524;6882.414994725505;6843.794686159423;6845.555038922554;6852.760153394607;6856.211535673461;6858.936864785866;6794.9660251699415;6797.6601169941305;6818.386345228953;6905.723771049102;6910.550814197061;5208.557272631793;5264.263003179558;5271.156614941591;5372.489613486712;5375.220593013991;4664.533546645376;4671.11248862715;4675.5324467553655;4677.158111539956;4736.052789442126;5888.705564721747;5889.146073819272;5959.4934430573885;5960.344362120163;5979.701014949235;6589.143411356493;6785.728570857209;6845.589264644137;6850.862982740317;6864.313568643096;5805.071188609802;5822.097574875901;5895.55783316253;5895.646261224255;5911.586188966731;5796.217510636095;5871.3541510433815;5879.617824841372;5886.499647530008;5892.7642894283745;6572.14562106923;6590.242122155918;6646.767363142363;6690.598564086073;6706.329367063255;6690.6320152390135;6783.728650853911;6826.146731658543;6904.896426553505;6934.930650693428;6517.8696426072165;6526.738930442729;6528.738850445929;6533.836654083686;6540.247871494839;6528;6610.70251838678;6618.0403841443685;6619.635920024252;6622.900656490253;6737.898931515933;6813.250542440229;6868.210155832143;6881.346272104142;6897.7585784497305;6526.184226971629;6598.934010659832;6618.63597502142;6646.567973081735;6653.168353955755;6520.641364724986;6531.444827189552;6619.139511863427;6629.767958121635;6653.168353955755;6369.0764839099;6447.484201263794;6489.870202595827;6498.447631951148;6567.540272181092;6515.28331883502;6576.342365763577;6693.1298931138645;6727.192736871411;6745.527813053235;6174.25908890936;6290.622562646256;6320.652364120017;6487.416132548094;6539.574927629689 06:52:04 INFO - PID 4517 | |1;ext.html;801.5391150088697;839.9622017009236;865.7272959017905;870.7344260000701;1117.8882111788828;29123.999999999975;29638.259043523896;38760.22479550411;39134;39349.40975885364;39462;39760;39789.80105099478;39910;39929.20141597174;7515.661795219228;7599.658015389293;7673.577953212571;7684.578353004684;7723.922760772404;7536.4724469287175;7652.655630496641;7653.579053152074;7657.272559106876;7664.156942736295;7654.0815102187535;7711.498752581092;7720.806979825521;7721.189275126115;7724.382049436032;7629.2752188542;7660.387169026464;7660.61696915152;7662.1954694756805;7672.194419585975;7548.5848278344665;7584.772456826249;7586.620668966585;7603.163651998276;7607.315341619281;35730.46404303938;35926.64073359259;35932.82033589842;35970.64029359725;35990;3478.3217272631846;3510.069831494656;3513.402721537345;3518.6305437928904;3522.9119272018147;29963.700362996526;29988.250293742614;30004.54993175104;30054.098377048722;30092.548611770842;5149.124648809711;5153.381594208681;5157.613179011589;5196.844094677166;5198.428172900978;192042;193211;193405;193664;193902;45366.77316613396;45435.31847022297;45538;45560.08879822385;46572;48458.757706211945;48762.756186218845;48910;48972.26541601879;49025;12300.138990270596;14790.852091479162;14885;14902;14949;10001.749956251006;11890.702732431759;12056.156069075081;12058.638240852788;12064.517419303129;13825.79261311081;13841.792373114411;13846.515371961934;13887.652808679662;13903.86096139046;5633.971830140823;5735.655860648374;5755.510781583528;5765.625234359837;5768.846230753866;989.5744829723288;1012.448215722433;1014.3204053284318;1021.279997601689;1023.2070145637191;22788.658170127463;27972.44055118927;28598.85700571484;28827.711722882923;29402.852985734935;1072.051234657334;1075.7902209069157;1080.5407701726735;1082.6643740440331;1084.766775143348;11839.348835814113;12247.571335003233;12278.201916875374;12294;12337;229415;231776;231879;232310;232433;47813;47999.28001079986;48015.27977080346;48100;48366.27450588244;1425.2588653899913;1429.5568373804153;1430.6208854653526;1436.4469679173385;1448.6450819549277;22193.334199974026;22385;22432.32703018912;22470;22552;36284.27431451409;36362.45462817577;36416.81791591131;36611.81694091512;36623.63376366202;207364;207925;208291;208534;208930;31421;34562.82718586492;36145;36168;36253.45619815652;42452.57547424486;43502.12995740131;43565.56434435615;43750.78124609357;43998.560014399445;32243.677563225006;32633.673663263064;32816.1795955103;32830;33120.834395827864;32281.51577726289;32353.838230808695;32748.672513274563;33147.834260829506;33228.83385583057;35898.64101358953;36951.26097477982;36991;37038;37179;31558.684413156494;32051.198720032186;32136.678633214306;32268.838655806572;32682.34635307328;23783.762162378156;24224.636630450204;24700.752992469847;24733.505329893662;24768.133115340876 06:52:04 INFO - PID 4517 | |2;jquery.html;563.369026690107;573.3979321712204;576.6280748916946;579.9942000579993;582.3739480058938;21532.354029379123;22092.116315347368;22317.10731570739;22356.10575576977;22526.774732252714;25845.095421660244;26249.950001999896;26258.212253632373;26274.6058809118;26369.736302636968;6544.672766361687;6696.129503164581;6707.094542236809;6709.966450167743;6730.427913627347;6336.683165841712;6586;6606.207255129367;6629.668516574152;6633.436157926582;6565.0480680301325;6573.211214654224;6573.901391479131;6575.605463672171;6578.901316480256;6546.214454265519;6549.181352330958;6557.344265573428;6560.93439065608;6562.179727534059;6092.878142437126;6095.664738439384;6099;6099;6112.908306375408;28686.139415817557;29029;29100;29168.124956251348;29541;2007.9498012549802;2025.3316405586122;2028.29009846554;2030.7867673894177;2041.9591608167902;19054.61890762191;19722.013899304977;19722.90138549298;19743.308984185485;19768.11043503046;2297.276357947241;2317.5364927014666;2354.540864531417;2356.6111591587537;2361.97254194426;136389;139473;139536;139677;139871.30064349613;14333.354999025069;14982.250887455584;15035.54893353201;15377.308021139075;15469.5359139226;23487.76512234856;23543.88228058883;23566.29301120969;23583.410414739425;23592.17427390033;9847.606095756233;10010.149137323264;10042.748931276776;10045.648402305884;10170.338927969806;7647.847043059219;9125.36122471434;9209;9310.86033709495;9322.906770932339;7979.9601001994615;9051.230645395084;9059.411138275991;9148.542572871329;9158.81682366349;2682.6780786305767;2708.390612112259;2710.471458065698;2719.279390961397;2747.615333853262;948.3503799897093;978.6721448314752;993.9602415903283;997.8952210017989;1005.3766664667953;19390.03049847502;20201.59596808085;20504.794952050586;23886.661852949303;24902.5019499609;1041.2555023158477;1070.3363914373138;1075.0754351257913;1080.8108580998294;1085.3976043295888;11575.479103440366;13239.602811915658;13852.238126903118;14194.361253743606;14291.356888940023;500600;517722.46832595085;541064;541724;543042;112648;116166;116256.41871790586;116496;117784.41107794407;1231.9260844349367;1250.7748605251143;1268.3784945376883;1270.7394984028251;1276.9808452873215;21728.456788580414;21898.45253868666;21973.450663732896;22251.666225006313;22330;59475.40524594699;59554.10668839884;60528.39471605227;60799.39200608114;63561.36438635555;70723.64638176776;71883.28116718766;72009.63995179991;72550;72566;14695.191764453059;15052.473163439226;15175.013624114397;15178.316975735897;15187.088774673555;23758.168464103674;23760.762392375855;23920.521589567765;24054.27837164818;24152.516949660556;16680.916595416944;18391.54021149482;18901.621967560848;19255.903720481307;19327.130279137753;15568.37726490973;16387.098709571088;16538.421155259988;16860.915695421936;17156.571085722957;18177.727334089734;18512.537186570444;19182.616347673247;19454.22183112716;19506.60986780228;14463.553644635453;14710.852891471377;14716.705665886408;14717.852821472077;14784.926075369554;17005.829941700424;17032.48902532926;17060.23228954675;17118.058506782247;17598.912005440405 06:52:04 INFO - PID 4517 | |3;mootools.html;1338.2639548248465;1373.2927542315708;1389.0832050846443;1406.8171137752097;1407.373718695181;15170.84829151708;15285.388584456636;15851.841481585196;15859.444919427822;15895.28471218795;16002;16290.592735181626;16324;16325.18374081297;16340.937839040454;2770.016644091354;2836.0640988473856;2836.2342167614734;2837.092130518235;2843.829370237782;2769.819961702493;2878.625778648783;2880.2223399682075;2881.193265885545;2884.8124871883365;2776.194903477989;2778.8610569471443;2806.873690683914;2818.9295267618268;2825.3501694610254;2709.173702020883;2713.91858244252;2733.3030077330254;2738.1511731363144;2739.123480486245;2674.064077572834;2697.689765676933;2701.7568418842397;2710.1192112563494;2712.34903623131;15162.469313573973;15224.847751522453;15334.309956052004;15339.693206135815;15346.155961422117;8079.798005049862;8265.545395003272;8276.627551760184;8321.292690121347;8325.791855203608;5338.839834804962;5340.118880384732;5419.214213938996;5477.068898287261;5502.8349149525575;1763.2241813601975;1765.5850875044355;1797.2451570340488;1823.188681036939;1857.359211072178;26802.195934122006;27022.054228101922;27024.59463108055;27092.864535677392;27103;6647.534672572873;6683.832904177434;6828.2147553031045;6887.276835932255;7033.085698859116;17391.65216695659;17396.913015435093;17408.47774566765;17429.912850435667;17466.650666986843;8066.596670166468;8068.314193293519;8179.79550511242;8435.156484351515;8446.113158118433;6083.726232319556;6291.056341548713;6432.9678351607945;7571.621418929141;7572.886406703904;4838.927416088762;5234.241035049897;5389.595780316492;5391.29913111293;5409.134538473825;1487.1894817324585;1497.9400823967137;1510.8791296696238;1511.6220944763809;1523.7028779387913;866.67066514724;880.4453194487432;890.6882591093195;893.4415990006246;908.3006085314271;7851.254130857559;7853.960730196312;7870;7899;7942.285194332422;723.3778950102908;731.5500966905315;733.7028503456108;748.0611832150616;758.0335072782246;4714.245720684674;4834.419869615669;4836.298736683161;4855.198892182821;4946.851594452172;21247.256346027814;21291.78708212929;22109.226177083725;22111;22115.668264976037;22072.779272207074;22116.778832211792;22180;22243.666345004836;22304.219352322914;10919.672409827717;11479.483423245974;11723.4138293085;11809.822852657217;11811.409429528489;9139.177474027369;9169.40398874071;9264.305177111879;9286;9337.346385752931;18831.15259813339;18859.811401886356;19124.33064842724;19306.806931931063;19563.902180489007;18410.815891841445;19303.613927721086;19697.310594129136;20031;20127.496812579804;11717.414129293331;11958.940205298917;11961;11964.042876570027;11965.641030769091;13274.203547787163;13277.468901244229;13321.134126282148;13353.66615834612;13520.797188042385;10361.844572331573;11236.494357753758;11237.606683766357;11330.830037549607;11457.770844583229;10869.29349592274;11006.559737610405;11182.217244793112;11269.887301126884;11315.490802913724;9567.808643827224;9578.47318397467;9647;9672.08115229052;9801.16690081351;7232.602206878461;7285.7085716572865;7299.671514781741;7318.158411782713;7389.224131466333;7578.280063393969;7591.696332146873;7610.581418022062;7685.654145563575;7723.150453450228 06:52:04 INFO - PID 4517 | |4;prototype.html;500.61330115579847;501.3157040639527;525.947405259474;527.0250037430751;531.9468053194681;6270.153529273547;6359.6184228946295;6391.64845933474;6398.840028999277;6451.870962580757;6030.849228769283;6036.275646922364;6105.725242364093;6123.173371594833;6195.941658213866;5028.145215313387;5059.266406371074;5065.468125846788;5065.7467126643705;5094.6688465249645;5003.1994880819075;5037.4710655381205;5053.090443720129;5059.342285502879;5077.619178561604;4857.125717370872;5007.974960125194;5008.774605142759;5019.64862459628;5027.874303142414;5025.824096156617;5029.170186919154;5030.924536131979;5031.295618613397;5035.395752509722;4533.954660453386;4537.818487260505;4547.1360441516335;4550.817967281305;4574.61115805157;6175.7529698811995;6195.969020154916;6213.813585592439;6227.906581401283;6244.750209991595;5770.653760774367;5775.364709881909;5782.149712092132;5786.1610066540115;5788.5369170466265;6594.769183078617;6601.174853143357;6664.366885145903;6676.732930682767;6683.6658167091455;4893.510648935114;4898.779554920037;4903.131667974204;4907.080287229129;4942.481039490837;7001.404880585156;7037.290643102442;7040.190378106532;7048.964755176191;7077.221505634376;5036.370453693288;5043.445221025684;5055.620828437808;5101.591872650179;5115.411727651293;5095.8980820383385;5108.157154069611;5129.717865517432;5134.204198349262;5138.2806407103035;5732.082866741302;5741.91387129185;5764.682942438122;5772.451617096369;5799.449052340021;6639.302873198245;6667.799966001027;6717.56335838169;6750.426213771786;6767.052612634235;1670.3485640599934;1687.3548290416745;1727.6199236168022;1731.991340043317;1887.7545919030713;1414.151509094535;1462.2980969134876;1470.3678916402998;1471.3820195517908;1474.3660226102663;5678.223981052636;5802.33273173582;5836.358000619928;5856.502197313191;5886.970565147147;6450.645214513247;6532.8366790830605;6672.399484046362;6717.227518835396;6747.359000894907;5727.3699893011735;5730.713464326767;5764.625299355528;5797.36229014808;5854.775185429509;3494.9126271842897;3555.1112221944513;3562.750607457453;3565.35823551758;3587.1390866191928;6507.34926507355;6550.868982620321;6567.770128045496;6594.802155935329;6596.505262105361;6817.931820681829;6849.965750171217;6879.346462086094;6899.275576064541;6956.991236270713;6546;6546.3453654635105;6548.1814773153355;6569.441597464173;6633.535652504375;6721.865562688621;6779.694913728993;6807.727690892309;6835.829104272433;6853.828654283683;6680.5991640501725;6729.394354508117;6755.560888542229;6778.423833974168;6799.830004249736;6458.741650333934;6522.706478208397;6539.70571324282;6592.406683398325;6602.40578347951;4956.702597844141;4964.25536169563;4967.254911763264;5012.649114561946;5027.572656324107;6797.25230224665;6821.28376520478;6827.590344579341;6859.451243900576;6888.173419189729;6518.771842985473;6520.510961677798;6576.44100251484;6663.067170596023;6668.032930285481;6323.588966717149;6499;6563.671816409065;6573.934260657332;6603.669816509251;6221.688915554295;6332.335104813928;6366.472046708849;6430.64631445275;6470.209162799174;6535.411812936859;6593.340665933369;6593.703283352164;6635.8009259722285;6647.434968027772;6303.621782693053;6344.873102537831;6366.236051673829;6403.470629101385;6418.775342863164 06:52:04 INFO - PID 4517 | |5;yui.html;906.859436787298;920.0799200799197;936.5457752989803;937.8593211018347;939.8073394954033;18673.346432874852;19841.20635174595;19868.60262794745;19889.502762430966;20979.16083356668;20149.496262593406;21443.892780536076;21463;21572.568548629017;21580.352589422342;938.8028514012066;963.802420503795;965.1120968708805;965.2615748952057;965.4834663455053;969.849673300636;1011.3881101933334;1017.3285631483222;1017.3488967061087;1020.2603112743276;1061.198794909842;1074.7796701676177;1074.940878251696;1075.1452595186845;1075.1613741281774;1054.5254635414094;1061.5541472581497;1063.558623171383;1063.6702622187108;1064.7391389109641;778.7079845058104;780.2899361580934;780.4302858912969;780.6252998560666;780.7189411811743;17865.01742404167;18266.452006439697;18410.44768656929;18425.170867311004;18880.24479020851;20263.29078482246;20593;21009.89495052515;21030.579388412298;21430.14279428821;16644.6671066578;16648.08435536045;16796;16824.83175168245;17043.91478042614;3836.9040773980596;3868.3617203161443;3868.613138686137;3875.302445559798;3877.418387241908;127296.36351818181;127411.08883366664;130419;131144.34427827896;131486;16223.51329460118;16295.755563666555;16630.916845415937;16844.831551684572;16849.494515164566;30111.849440752656;30604.84697576498;30678.53982190269;30721.231969200948;31200.37599248002;7772;8091.676332946734;8190.467619604826;8517.872231916526;9271.490068046187;4240.194363071007;4339.218940590724;4347.5217726050105;4356.6950313477755;4357.128574285156;5134.640575159778;5196.76614552346;5228.686278823283;5256.080185967517;5279.894402111936;988.4118949225343;1013.7060252526685;1014.497823577334;1015.8882522922472;1027.41950797799;623.4949690750507;628.6825153297647;636.0491065856575;642.2870613618879;673.5049738442241;11534.365609891536;11765.764684706259;11983.161178717406;12082.637520874388;12466.252024878355;688.8036909480774;702.3994484715597;705.8552996635677;718.1812733483832;720.2257573108928;5438.809641662523;5444.40111587725;5455.236266922634;5500.257465242221;5581.2186293918885;107846.46076769567;109230;109231.45384273028;109234.45382773035;109418;96073.5196324028;100239;100239.49880250552;100889;103489;84422.57788711016;86324;86346.13653863507;86747.56626216829;88648;9953.751156221153;10028.949855250678;10072.697819065439;11978.58074967372;12603.243805371707;39763.80118099507;41293.793531032155;42180.57819421766;42868.571314286455;43252.351214732436;67379;69783;70146;71808.64095679489;72368;16233.918830406245;16250.837491624932;16253.618442432526;16347.673046539241;16942.40701575439;20416.591668166257;20652.483687907923;20898.68651970252;21095.57808843845;21139.154433822474;17946.282148713904;17954.102294884942;18516.351927682466;18630.53423664419;18859.811401885807;15719.528414147593;16844.57888552796;17164.22760975784;17184.914075429544;18169.636607268047;17549.912250438665;17615.295388184328;17671.823281767018;18834.434966951012;18930.905345473184;12212.75574488523;12349.814752778535;12638.873611263769;12720.6819829505;13123.146995445266;13029.934850326066;13239.3380330985;13278.09920235818;13279.535216267384;13444.798328025283 06:52:04 INFO - PID 4517 | __end_tp_report 06:52:04 INFO - PID 4517 | __start_cc_report 06:52:04 INFO - PID 4517 | _x_x_mozilla_cycle_collect,366 06:52:04 INFO - PID 4517 | __end_cc_report 06:52:04 INFO - PID 4517 | __startTimestamp1504187524442__endTimestamp 06:52:04 INFO - PID 4517 | 06:52:04 INFO - PID 4517 | ------- Summary: start ------- 06:52:04 INFO - PID 4517 | Number of tests: 6 06:52:04 INFO - PID 4517 | 06:52:04 INFO - PID 4517 | [#0] dojo.html Cycles:165 Average:6241.94 Median:6498.45 stddev:730.60 (11.2%) stddev-sans-first:695.08 06:52:04 INFO - PID 4517 | Values: 3286.3 3314.6 3316.3 3339.2 3340.5 6287.1 6293.5 6326.9 6327.0 6359.6 6199.6 6305.1 6305.9 6322.0 6348.8 5790.1 5812.6 5815.0 5818.5 5843.8 5835.6 5840.0 5879.9 5909.3 5931.2 5861.3 5872.8 5891.5 5929.8 5939.9 5531.6 5734.2 5832.2 5914.8 5915.1 5557.9 5743.7 5777.9 5785.0 5839.0 6271.3 6284.3 6321.9 6354.7 6385.3 6703.6 6719.1 6722.5 6731.4 6802.4 6768.2 6852.4 6919.2 6927.9 6929.6 6320.6 6348.9 6349.8 6429.8 6436.8 6800.4 6963.3 6989.8 7085.3 7087.8 6555.5 6556.5 6561.6 6568.9 6578.0 6741.5 6751.1 6818.1 6822.8 6882.4 6843.8 6845.6 6852.8 6856.2 6858.9 6795.0 6797.7 6818.4 6905.7 6910.6 5208.6 5264.3 5271.2 5372.5 5375.2 4664.5 4671.1 4675.5 4677.2 4736.1 5888.7 5889.1 5959.5 5960.3 5979.7 6589.1 6785.7 6845.6 6850.9 6864.3 5805.1 5822.1 5895.6 5895.6 5911.6 5796.2 5871.4 5879.6 5886.5 5892.8 6572.1 6590.2 6646.8 6690.6 6706.3 6690.6 6783.7 6826.1 6904.9 6934.9 6517.9 6526.7 6528.7 6533.8 6540.2 6528.0 6610.7 6618.0 6619.6 6622.9 6737.9 6813.3 6868.2 6881.3 6897.8 6526.2 6598.9 6618.6 6646.6 6653.2 6520.6 6531.4 6619.1 6629.8 6653.2 6369.1 6447.5 6489.9 6498.4 6567.5 6515.3 6576.3 6693.1 6727.2 6745.5 6174.3 6290.6 6320.7 6487.4 6539.6 06:52:04 INFO - PID 4517 | 06:52:04 INFO - PID 4517 | [#1] ext.html Cycles:180 Average:37478.28 Median:3511.74 stddev:54786.45 (1560.1%) stddev-sans-first:54870.93 06:52:04 INFO - PID 4517 | Values: 801.5 840.0 865.7 870.7 1117.9 29124.0 29638.3 38760.2 39134.0 39349.4 39462.0 39760.0 39789.8 39910.0 39929.2 7515.7 7599.7 7673.6 7684.6 7723.9 7536.5 7652.7 7653.6 7657.3 7664.2 7654.1 7711.5 7720.8 7721.2 7724.4 7629.3 7660.4 7660.6 7662.2 7672.2 7548.6 7584.8 7586.6 7603.2 7607.3 35730.5 35926.6 35932.8 35970.6 35990.0 3478.3 3510.1 3513.4 3518.6 3522.9 29963.7 29988.3 30004.5 30054.1 30092.5 5149.1 5153.4 5157.6 5196.8 5198.4 192042.0 193211.0 193405.0 193664.0 193902.0 45366.8 45435.3 45538.0 45560.1 46572.0 48458.8 48762.8 48910.0 48972.3 49025.0 12300.1 14790.9 14885.0 14902.0 14949.0 10001.7 11890.7 12056.2 12058.6 12064.5 13825.8 13841.8 13846.5 13887.7 13903.9 5634.0 5735.7 5755.5 5765.6 5768.8 989.6 1012.4 1014.3 1021.3 1023.2 22788.7 27972.4 28598.9 28827.7 29402.9 1072.1 1075.8 1080.5 1082.7 1084.8 11839.3 12247.6 12278.2 12294.0 12337.0 229415.0 231776.0 231879.0 232310.0 232433.0 47813.0 47999.3 48015.3 48100.0 48366.3 1425.3 1429.6 1430.6 1436.4 1448.6 22193.3 22385.0 22432.3 22470.0 22552.0 36284.3 36362.5 36416.8 36611.8 36623.6 207364.0 207925.0 208291.0 208534.0 208930.0 31421.0 34562.8 36145.0 36168.0 36253.5 42452.6 43502.1 43565.6 43750.8 43998.6 32243.7 32633.7 32816.2 32830.0 33120.8 32281.5 32353.8 32748.7 33147.8 33228.8 35898.6 36951.3 36991.0 37038.0 37179.0 31558.7 32051.2 32136.7 32268.8 32682.3 23783.8 24224.6 24700.8 24733.5 24768.1 06:52:04 INFO - PID 4517 | 06:52:04 INFO - PID 4517 | [#2] jquery.html Cycles:180 Average:36261.06 Median:12921.15 stddev:88650.70 (686.1%) stddev-sans-first:88858.87 06:52:04 INFO - PID 4517 | Values: 563.4 573.4 576.6 580.0 582.4 21532.4 22092.1 22317.1 22356.1 22526.8 25845.1 26250.0 26258.2 26274.6 26369.7 6544.7 6696.1 6707.1 6710.0 6730.4 6336.7 6586.0 6606.2 6629.7 6633.4 6565.0 6573.2 6573.9 6575.6 6578.9 6546.2 6549.2 6557.3 6560.9 6562.2 6092.9 6095.7 6099.0 6099.0 6112.9 28686.1 29029.0 29100.0 29168.1 29541.0 2007.9 2025.3 2028.3 2030.8 2042.0 19054.6 19722.0 19722.9 19743.3 19768.1 2297.3 2317.5 2354.5 2356.6 2362.0 136389.0 139473.0 139536.0 139677.0 139871.3 14333.4 14982.3 15035.5 15377.3 15469.5 23487.8 23543.9 23566.3 23583.4 23592.2 9847.6 10010.1 10042.7 10045.6 10170.3 7647.8 9125.4 9209.0 9310.9 9322.9 7980.0 9051.2 9059.4 9148.5 9158.8 2682.7 2708.4 2710.5 2719.3 2747.6 948.4 978.7 994.0 997.9 1005.4 19390.0 20201.6 20504.8 23886.7 24902.5 1041.3 1070.3 1075.1 1080.8 1085.4 11575.5 13239.6 13852.2 14194.4 14291.4 500600.0 517722.5 541064.0 541724.0 543042.0 112648.0 116166.0 116256.4 116496.0 117784.4 1231.9 1250.8 1268.4 1270.7 1277.0 21728.5 21898.5 21973.5 22251.7 22330.0 59475.4 59554.1 60528.4 60799.4 63561.4 70723.6 71883.3 72009.6 72550.0 72566.0 14695.2 15052.5 15175.0 15178.3 15187.1 23758.2 23760.8 23920.5 24054.3 24152.5 16680.9 18391.5 18901.6 19255.9 19327.1 15568.4 16387.1 16538.4 16860.9 17156.6 18177.7 18512.5 19182.6 19454.2 19506.6 14463.6 14710.9 14716.7 14717.9 14784.9 17005.8 17032.5 17060.2 17118.1 17598.9 06:52:04 INFO - PID 4517 | 06:52:04 INFO - PID 4517 | [#3] mootools.html Cycles:180 Average:9466.81 Median:2713.13 stddev:6835.94 (252.0%) stddev-sans-first:6827.84 06:52:04 INFO - PID 4517 | Values: 1338.3 1373.3 1389.1 1406.8 1407.4 15170.8 15285.4 15851.8 15859.4 15895.3 16002.0 16290.6 16324.0 16325.2 16340.9 2770.0 2836.1 2836.2 2837.1 2843.8 2769.8 2878.6 2880.2 2881.2 2884.8 2776.2 2778.9 2806.9 2818.9 2825.4 2709.2 2713.9 2733.3 2738.2 2739.1 2674.1 2697.7 2701.8 2710.1 2712.3 15162.5 15224.8 15334.3 15339.7 15346.2 8079.8 8265.5 8276.6 8321.3 8325.8 5338.8 5340.1 5419.2 5477.1 5502.8 1763.2 1765.6 1797.2 1823.2 1857.4 26802.2 27022.1 27024.6 27092.9 27103.0 6647.5 6683.8 6828.2 6887.3 7033.1 17391.7 17396.9 17408.5 17429.9 17466.7 8066.6 8068.3 8179.8 8435.2 8446.1 6083.7 6291.1 6433.0 7571.6 7572.9 4838.9 5234.2 5389.6 5391.3 5409.1 1487.2 1497.9 1510.9 1511.6 1523.7 866.7 880.4 890.7 893.4 908.3 7851.3 7854.0 7870.0 7899.0 7942.3 723.4 731.6 733.7 748.1 758.0 4714.2 4834.4 4836.3 4855.2 4946.9 21247.3 21291.8 22109.2 22111.0 22115.7 22072.8 22116.8 22180.0 22243.7 22304.2 10919.7 11479.5 11723.4 11809.8 11811.4 9139.2 9169.4 9264.3 9286.0 9337.3 18831.2 18859.8 19124.3 19306.8 19563.9 18410.8 19303.6 19697.3 20031.0 20127.5 11717.4 11958.9 11961.0 11964.0 11965.6 13274.2 13277.5 13321.1 13353.7 13520.8 10361.8 11236.5 11237.6 11330.8 11457.8 10869.3 11006.6 11182.2 11269.9 11315.5 9567.8 9578.5 9647.0 9672.1 9801.2 7232.6 7285.7 7299.7 7318.2 7389.2 7578.3 7591.7 7610.6 7685.7 7723.2 06:52:04 INFO - PID 4517 | 06:52:04 INFO - PID 4517 | [#4] prototype.html Cycles:180 Average:5562.19 Median:6195.96 stddev:1544.02 (24.9%) stddev-sans-first:1500.89 06:52:04 INFO - PID 4517 | Values: 500.6 501.3 525.9 527.0 531.9 6270.2 6359.6 6391.6 6398.8 6451.9 6030.8 6036.3 6105.7 6123.2 6195.9 5028.1 5059.3 5065.5 5065.7 5094.7 5003.2 5037.5 5053.1 5059.3 5077.6 4857.1 5008.0 5008.8 5019.6 5027.9 5025.8 5029.2 5030.9 5031.3 5035.4 4534.0 4537.8 4547.1 4550.8 4574.6 6175.8 6196.0 6213.8 6227.9 6244.8 5770.7 5775.4 5782.1 5786.2 5788.5 6594.8 6601.2 6664.4 6676.7 6683.7 4893.5 4898.8 4903.1 4907.1 4942.5 7001.4 7037.3 7040.2 7049.0 7077.2 5036.4 5043.4 5055.6 5101.6 5115.4 5095.9 5108.2 5129.7 5134.2 5138.3 5732.1 5741.9 5764.7 5772.5 5799.4 6639.3 6667.8 6717.6 6750.4 6767.1 1670.3 1687.4 1727.6 1732.0 1887.8 1414.2 1462.3 1470.4 1471.4 1474.4 5678.2 5802.3 5836.4 5856.5 5887.0 6450.6 6532.8 6672.4 6717.2 6747.4 5727.4 5730.7 5764.6 5797.4 5854.8 3494.9 3555.1 3562.8 3565.4 3587.1 6507.3 6550.9 6567.8 6594.8 6596.5 6817.9 6850.0 6879.3 6899.3 6957.0 6546.0 6546.3 6548.2 6569.4 6633.5 6721.9 6779.7 6807.7 6835.8 6853.8 6680.6 6729.4 6755.6 6778.4 6799.8 6458.7 6522.7 6539.7 6592.4 6602.4 4956.7 4964.3 4967.3 5012.6 5027.6 6797.3 6821.3 6827.6 6859.5 6888.2 6518.8 6520.5 6576.4 6663.1 6668.0 6323.6 6499.0 6563.7 6573.9 6603.7 6221.7 6332.3 6366.5 6430.6 6470.2 6535.4 6593.3 6593.7 6635.8 6647.4 6303.6 6344.9 6366.2 6403.5 6418.8 06:52:04 INFO - PID 4517 | 06:52:04 INFO - PID 4517 | [#5] yui.html Cycles:180 Average:23797.31 Median:20206.39 stddev:32601.24 (161.3%) stddev-sans-first:32647.38 06:52:04 INFO - PID 4517 | Values: 906.9 920.1 936.5 937.9 939.8 18673.3 19841.2 19868.6 19889.5 20979.2 20149.5 21443.9 21463.0 21572.6 21580.4 938.8 963.8 965.1 965.3 965.5 969.8 1011.4 1017.3 1017.3 1020.3 1061.2 1074.8 1074.9 1075.1 1075.2 1054.5 1061.6 1063.6 1063.7 1064.7 778.7 780.3 780.4 780.6 780.7 17865.0 18266.5 18410.4 18425.2 18880.2 20263.3 20593.0 21009.9 21030.6 21430.1 16644.7 16648.1 16796.0 16824.8 17043.9 3836.9 3868.4 3868.6 3875.3 3877.4 127296.4 127411.1 130419.0 131144.3 131486.0 16223.5 16295.8 16630.9 16844.8 16849.5 30111.8 30604.8 30678.5 30721.2 31200.4 7772.0 8091.7 8190.5 8517.9 9271.5 4240.2 4339.2 4347.5 4356.7 4357.1 5134.6 5196.8 5228.7 5256.1 5279.9 988.4 1013.7 1014.5 1015.9 1027.4 623.5 628.7 636.0 642.3 673.5 11534.4 11765.8 11983.2 12082.6 12466.3 688.8 702.4 705.9 718.2 720.2 5438.8 5444.4 5455.2 5500.3 5581.2 107846.5 109230.0 109231.5 109234.5 109418.0 96073.5 100239.0 100239.5 100889.0 103489.0 84422.6 86324.0 86346.1 86747.6 88648.0 9953.8 10028.9 10072.7 11978.6 12603.2 39763.8 41293.8 42180.6 42868.6 43252.4 67379.0 69783.0 70146.0 71808.6 72368.0 16233.9 16250.8 16253.6 16347.7 16942.4 20416.6 20652.5 20898.7 21095.6 21139.2 17946.3 17954.1 18516.4 18630.5 18859.8 15719.5 16844.6 17164.2 17184.9 18169.6 17549.9 17615.3 17671.8 18834.4 18930.9 12212.8 12349.8 12638.9 12720.7 13123.1 13029.9 13239.3 13278.1 13279.5 13444.8 06:52:04 INFO - PID 4517 | -------- Summary: end -------- 06:52:04 INFO - PID 4517 | 06:52:05 INFO - TEST-INFO | 4517: exit 0 06:52:05 INFO - TEST-OK | dromaeo_css | took 1112253ms 06:52:05 INFO - TEST-START | kraken 06:52:05 INFO - Initialising browser for kraken test... 06:52:05 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47359/getInfo.html -profile /tmp/tmp9F2hmp/profile 06:52:05 INFO - TEST-INFO | started process 4891 (/builds/slave/test/build/application/firefox/firefox http://localhost:47359/getInfo.html) 06:52:10 INFO - TEST-INFO | 4891: exit 0 06:52:10 INFO - Browser initialized. 06:52:10 INFO - Running cycle 1/1 for kraken test... 06:52:10 INFO - Using env: {'DISPLAY': ':0', 06:52:10 INFO - 'HOME': '/home/cltbld', 06:52:10 INFO - 'JSGC_DISABLE_POISONING': '1', 06:52:10 INFO - 'LANG': 'en_US.UTF-8', 06:52:10 INFO - 'LANGUAGE': 'en_US:en', 06:52:10 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 06:52:10 INFO - 'LOGNAME': 'cltbld', 06:52:10 INFO - 'MAIL': '/var/mail/cltbld', 06:52:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:52:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:52:10 INFO - 'MOZ_CRASHREPORTER': '1', 06:52:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:52:10 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 06:52:10 INFO - 'MOZ_NO_REMOTE': '1', 06:52:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:52:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:52:10 INFO - 'NO_EM_RESTART': '1', 06:52:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:52:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:52:10 INFO - 'PWD': '/builds/slave/test', 06:52:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:52:10 INFO - 'RUST_BACKTRACE': 'full', 06:52:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:52:10 INFO - 'SHELL': '/bin/bash', 06:52:10 INFO - 'SHLVL': '1', 06:52:10 INFO - 'TERM': 'linux', 06:52:10 INFO - 'TMOUT': '86400', 06:52:10 INFO - 'USER': 'cltbld', 06:52:10 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723', 06:52:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:52:10 INFO - '_': '/tools/buildbot/bin/python'} 06:52:10 INFO - TEST-INFO | started process 5077 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9F2hmp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 06:52:11 INFO - PID 5077 | 06:52:11 INFO - PID 5077 | (/builds/slave/test/build/application/firefox/firefox:5128): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 06:52:11 INFO - PID 5077 | 06:52:22 INFO - PID 5077 | RSS: Main: 170340352 06:52:22 INFO - PID 5077 | 06:52:24 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47359/tests/kraken/driver.html?testName=audio-beat-detection) 06:52:25 INFO - PID 5077 | RSS: Main: 176300032 06:52:25 INFO - PID 5077 | 06:52:28 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47359/tests/kraken/driver.html?testName=audio-dft) 06:52:29 INFO - PID 5077 | RSS: Main: 176648192 06:52:29 INFO - PID 5077 | 06:52:32 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47359/tests/kraken/driver.html?testName=audio-fft) 06:52:32 INFO - PID 5077 | RSS: Main: 169852928 06:52:32 INFO - PID 5077 | 06:52:34 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47359/tests/kraken/driver.html?testName=audio-oscillator) 06:52:35 INFO - PID 5077 | RSS: Main: 170553344 06:52:35 INFO - PID 5077 | 06:52:37 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47359/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:52:38 INFO - PID 5077 | RSS: Main: 170647552 06:52:38 INFO - PID 5077 | 06:52:41 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47359/tests/kraken/driver.html?testName=imaging-darkroom) 06:52:41 INFO - PID 5077 | RSS: Main: 172949504 06:52:41 INFO - PID 5077 | 06:52:45 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47359/tests/kraken/driver.html?testName=imaging-desaturate) 06:52:46 INFO - PID 5077 | RSS: Main: 173461504 06:52:46 INFO - PID 5077 | 06:52:48 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47359/tests/kraken/driver.html?testName=json-parse-financial) 06:52:49 INFO - PID 5077 | RSS: Main: 173481984 06:52:49 INFO - PID 5077 | 06:52:51 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47359/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:52:51 INFO - PID 5077 | RSS: Main: 172609536 06:52:51 INFO - PID 5077 | 06:52:53 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:52:54 INFO - PID 5077 | RSS: Main: 173105152 06:52:54 INFO - PID 5077 | 06:52:56 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:52:56 INFO - PID 5077 | RSS: Main: 170450944 06:52:56 INFO - PID 5077 | 06:52:59 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:53:00 INFO - PID 5077 | RSS: Main: 170323968 06:53:00 INFO - PID 5077 | 06:53:03 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:53:03 INFO - PID 5077 | RSS: Main: 170844160 06:53:03 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47359/tests/kraken/driver.html?testName=ai-astar) 06:53:05 INFO - PID 5077 | RSS: Main: 172441600 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | __start_tp_report 06:53:05 INFO - PID 5077 | _x_x_mozilla_page_load 06:53:05 INFO - PID 5077 | _x_x_mozilla_page_load_details 06:53:05 INFO - PID 5077 | |i|pagename|runs| 06:53:05 INFO - PID 5077 | |0;ai-astar;98;107;99;109;108;118;107;98;106;111 06:53:05 INFO - PID 5077 | |1;audio-beat-detection;154;120;144;171;118;150;151;120;173;116 06:53:05 INFO - PID 5077 | |2;audio-dft;164;162;164;163;162;164;162;165;172;163 06:53:05 INFO - PID 5077 | |3;audio-fft;76;75;75;171;75;74;73;73;153;76 06:53:05 INFO - PID 5077 | |4;audio-oscillator;79;79;136;78;80;80;138;79;79;125 06:53:05 INFO - PID 5077 | |5;imaging-gaussian-blur;113;114;114;117;117;114;113;114;114;114 06:53:05 INFO - PID 5077 | |6;imaging-darkroom;150;151;148;148;151;150;148;148;150;148 06:53:05 INFO - PID 5077 | |7;imaging-desaturate;94;93;94;95;93;94;94;94;100;94 06:53:05 INFO - PID 5077 | |8;json-parse-financial;53;52;55;53;52;52;52;52;52;53 06:53:05 INFO - PID 5077 | |9;json-stringify-tinderbox;37;39;38;38;38;38;37;38;37;38 06:53:05 INFO - PID 5077 | |10;stanford-crypto-aes;74;76;75;75;74;76;79;74;73;74 06:53:05 INFO - PID 5077 | |11;stanford-crypto-ccm;124;118;123;126;120;125;119;118;125;161 06:53:05 INFO - PID 5077 | |12;stanford-crypto-pbkdf2;179;166;169;165;167;172;170;172;171;168 06:53:05 INFO - PID 5077 | |13;stanford-crypto-sha256-iterative;65;68;65;68;65;64;64;66;65;66 06:53:05 INFO - PID 5077 | __end_tp_report 06:53:05 INFO - PID 5077 | __start_cc_report 06:53:05 INFO - PID 5077 | _x_x_mozilla_cycle_collect,970 06:53:05 INFO - PID 5077 | __end_cc_report 06:53:05 INFO - PID 5077 | __startTimestamp1504187585700__endTimestamp 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | ------- Summary: start ------- 06:53:05 INFO - PID 5077 | Number of tests: 14 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | [#0] ai-astar Cycles:10 Average:106.10 Median:114.50 stddev:6.33 (5.5%) stddev-sans-first:6.00 06:53:05 INFO - PID 5077 | Values: 98.0 107.0 99.0 109.0 108.0 118.0 107.0 98.0 106.0 111.0 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:150.50 stddev:21.89 (14.5%) stddev-sans-first:22.77 06:53:05 INFO - PID 5077 | Values: 154.0 120.0 144.0 171.0 118.0 150.0 151.0 120.0 173.0 116.0 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:2.96 (1.8%) stddev-sans-first:3.14 06:53:05 INFO - PID 5077 | Values: 164.0 162.0 164.0 163.0 162.0 164.0 162.0 165.0 172.0 163.0 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | [#3] audio-fft Cycles:10 Average:92.10 Median:75.00 stddev:37.10 (49.5%) stddev-sans-first:38.89 06:53:05 INFO - PID 5077 | Values: 76.0 75.0 75.0 171.0 75.0 74.0 73.0 73.0 153.0 76.0 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:79.00 stddev:26.23 (33.2%) stddev-sans-first:27.15 06:53:05 INFO - PID 5077 | Values: 79.0 79.0 136.0 78.0 80.0 80.0 138.0 79.0 79.0 125.0 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:1.43 (1.3%) stddev-sans-first:1.42 06:53:05 INFO - PID 5077 | Values: 113.0 114.0 114.0 117.0 117.0 114.0 113.0 114.0 114.0 114.0 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:150.00 stddev:1.32 (0.9%) stddev-sans-first:1.36 06:53:05 INFO - PID 5077 | Values: 150.0 151.0 148.0 148.0 151.0 150.0 148.0 148.0 150.0 148.0 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.00 stddev:2.01 (2.1%) stddev-sans-first:2.13 06:53:05 INFO - PID 5077 | Values: 94.0 93.0 94.0 95.0 93.0 94.0 94.0 94.0 100.0 94.0 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:52.50 stddev:0.97 (1.8%) stddev-sans-first:1.01 06:53:05 INFO - PID 5077 | Values: 53.0 52.0 55.0 53.0 52.0 52.0 52.0 52.0 52.0 53.0 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | [#9] json-stringify-tinderbox Cycles:10 Average:37.80 Median:38.00 stddev:0.63 (1.7%) stddev-sans-first:0.60 06:53:05 INFO - PID 5077 | Values: 37.0 39.0 38.0 38.0 38.0 38.0 37.0 38.0 37.0 38.0 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:75.00 stddev:1.70 (2.3%) stddev-sans-first:1.76 06:53:05 INFO - PID 5077 | Values: 74.0 76.0 75.0 75.0 74.0 76.0 79.0 74.0 73.0 74.0 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | [#11] stanford-crypto-ccm Cycles:10 Average:125.90 Median:124.50 stddev:12.71 (10.2%) stddev-sans-first:13.46 06:53:05 INFO - PID 5077 | Values: 124.0 118.0 123.0 126.0 120.0 125.0 119.0 118.0 125.0 161.0 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.90 Median:170.50 stddev:4.01 (2.4%) stddev-sans-first:2.57 06:53:05 INFO - PID 5077 | Values: 179.0 166.0 169.0 165.0 167.0 172.0 170.0 172.0 171.0 168.0 06:53:05 INFO - PID 5077 | 06:53:05 INFO - PID 5077 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:65.50 stddev:1.43 (2.2%) stddev-sans-first:1.50 06:53:05 INFO - PID 5077 | Values: 65.0 68.0 65.0 68.0 65.0 64.0 64.0 66.0 65.0 66.0 06:53:05 INFO - PID 5077 | -------- Summary: end -------- 06:53:05 INFO - PID 5077 | 06:53:06 INFO - PID 5077 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 06:53:06 INFO - TEST-INFO | 5077: exit 0 06:53:06 INFO - TEST-OK | kraken | took 61219ms 06:53:06 INFO - SUITE-END | took 1173s 06:53:06 INFO - Completed test suite (00:19:33) 06:53:06 INFO - javascript benchmark 06:53:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8577.30280713241, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3286.293446908914, 3314.585676790401, 3316.286998295366, 3339.1985923378397, 3340.5156252343395, 6287.08837218603, 6293.496520278372, 6326.936730632692, 6326.968365158174, 6359.55483116182, 6199.566030377875, 6305.085762564426, 6305.905411418821, 6322.020086886529, 6348.777792777254, 5790.131480277961, 5812.593118481687, 5815.040518314473, 5818.476337129658, 5843.79546715865, 5835.562332825034, 5839.978003849331, 5879.941200588003, 5909.261342332208, 5931.228940237763, 5861.296644402678, 5872.7944521941745, 5891.5286777057945, 5929.792457263997, 5939.910901336461, 5531.557475401958, 5734.225879506255, 5832.212651292064, 5914.822555323347, 5915.112733090006, 5557.888842223173, 5743.684097374643, 5777.913331300034, 5785.045467497858, 5839.036558967765, 6271.310155882849, 6284.34014428483, 6321.87356252877, 6354.650494222862, 6385.2656944451055, 6703.597784132919, 6719.0593316935665, 6722.462203023746, 6731.427828634572, 6802.387785099315, 6768.187817461887, 6852.417544508723, 6919.169699636025, 6927.930720692779, 6929.618870962095, 6320.5891617044745, 6348.9365106348805, 6349.841253968688, 6429.774957876499, 6436.774712885027, 6800.353966373186, 6963.303669633097, 6989.790306290819, 7085.256048114977, 7087.787366379017, 6555.475561955032, 6556.541042127005, 6561.573497722633, 6568.86862262752, 6578.04618330349, 6741.460683145337, 6751.0886030386255, 6818.113645226089, 6822.795316140524, 6882.414994725505, 6843.794686159423, 6845.555038922554, 6852.760153394607, 6856.211535673461, 6858.936864785866, 6794.9660251699415, 6797.6601169941305, 6818.386345228953, 6905.723771049102, 6910.550814197061, 5208.557272631793, 5264.263003179558, 5271.156614941591, 5372.489613486712, 5375.220593013991, 4664.533546645376, 4671.11248862715, 4675.5324467553655, 4677.158111539956, 4736.052789442126, 5888.705564721747, 5889.146073819272, 5959.4934430573885, 5960.344362120163, 5979.701014949235, 6589.143411356493, 6785.728570857209, 6845.589264644137, 6850.862982740317, 6864.313568643096, 5805.071188609802, 5822.097574875901, 5895.55783316253, 5895.646261224255, 5911.586188966731, 5796.217510636095, 5871.3541510433815, 5879.617824841372, 5886.499647530008, 5892.7642894283745, 6572.14562106923, 6590.242122155918, 6646.767363142363, 6690.598564086073, 6706.329367063255, 6690.6320152390135, 6783.728650853911, 6826.146731658543, 6904.896426553505, 6934.930650693428, 6517.8696426072165, 6526.738930442729, 6528.738850445929, 6533.836654083686, 6540.247871494839, 6528.0, 6610.70251838678, 6618.0403841443685, 6619.635920024252, 6622.900656490253, 6737.898931515933, 6813.250542440229, 6868.210155832143, 6881.346272104142, 6897.7585784497305, 6526.184226971629, 6598.934010659832, 6618.63597502142, 6646.567973081735, 6653.168353955755, 6520.641364724986, 6531.444827189552, 6619.139511863427, 6629.767958121635, 6653.168353955755, 6369.0764839099, 6447.484201263794, 6489.870202595827, 6498.447631951148, 6567.540272181092, 6515.28331883502, 6576.342365763577, 6693.1298931138645, 6727.192736871411, 6745.527813053235, 6174.25908890936, 6290.622562646256, 6320.652364120017, 6487.416132548094, 6539.574927629689], "value": 6188.812023077995, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [801.5391150088697, 839.9622017009236, 865.7272959017905, 870.7344260000701, 1117.8882111788828, 29123.999999999975, 29638.259043523896, 38760.22479550411, 39134.0, 39349.40975885364, 39462.0, 39760.0, 39789.80105099478, 39910.0, 39929.20141597174, 7515.661795219228, 7599.658015389293, 7673.577953212571, 7684.578353004684, 7723.922760772404, 7536.4724469287175, 7652.655630496641, 7653.579053152074, 7657.272559106876, 7664.156942736295, 7654.0815102187535, 7711.498752581092, 7720.806979825521, 7721.189275126115, 7724.382049436032, 7629.2752188542, 7660.387169026464, 7660.61696915152, 7662.1954694756805, 7672.194419585975, 7548.5848278344665, 7584.772456826249, 7586.620668966585, 7603.163651998276, 7607.315341619281, 35730.46404303938, 35926.64073359259, 35932.82033589842, 35970.64029359725, 35990.0, 3478.3217272631846, 3510.069831494656, 3513.402721537345, 3518.6305437928904, 3522.9119272018147, 29963.700362996526, 29988.250293742614, 30004.54993175104, 30054.098377048722, 30092.548611770842, 5149.124648809711, 5153.381594208681, 5157.613179011589, 5196.844094677166, 5198.428172900978, 192042.0, 193211.0, 193405.0, 193664.0, 193902.0, 45366.77316613396, 45435.31847022297, 45538.0, 45560.08879822385, 46572.0, 48458.757706211945, 48762.756186218845, 48910.0, 48972.26541601879, 49025.0, 12300.138990270596, 14790.852091479162, 14885.0, 14902.0, 14949.0, 10001.749956251006, 11890.702732431759, 12056.156069075081, 12058.638240852788, 12064.517419303129, 13825.79261311081, 13841.792373114411, 13846.515371961934, 13887.652808679662, 13903.86096139046, 5633.971830140823, 5735.655860648374, 5755.510781583528, 5765.625234359837, 5768.846230753866, 989.5744829723288, 1012.448215722433, 1014.3204053284318, 1021.279997601689, 1023.2070145637191, 22788.658170127463, 27972.44055118927, 28598.85700571484, 28827.711722882923, 29402.852985734935, 1072.051234657334, 1075.7902209069157, 1080.5407701726735, 1082.6643740440331, 1084.766775143348, 11839.348835814113, 12247.571335003233, 12278.201916875374, 12294.0, 12337.0, 229415.0, 231776.0, 231879.0, 232310.0, 232433.0, 47813.0, 47999.28001079986, 48015.27977080346, 48100.0, 48366.27450588244, 1425.2588653899913, 1429.5568373804153, 1430.6208854653526, 1436.4469679173385, 1448.6450819549277, 22193.334199974026, 22385.0, 22432.32703018912, 22470.0, 22552.0, 36284.27431451409, 36362.45462817577, 36416.81791591131, 36611.81694091512, 36623.63376366202, 207364.0, 207925.0, 208291.0, 208534.0, 208930.0, 31421.0, 34562.82718586492, 36145.0, 36168.0, 36253.45619815652, 42452.57547424486, 43502.12995740131, 43565.56434435615, 43750.78124609357, 43998.560014399445, 32243.677563225006, 32633.673663263064, 32816.1795955103, 32830.0, 33120.834395827864, 32281.51577726289, 32353.838230808695, 32748.672513274563, 33147.834260829506, 33228.83385583057, 35898.64101358953, 36951.26097477982, 36991.0, 37038.0, 37179.0, 31558.684413156494, 32051.198720032186, 32136.678633214306, 32268.838655806572, 32682.34635307328, 23783.762162378156, 24224.636630450204, 24700.752992469847, 24733.505329893662, 24768.133115340876], "value": 16935.743160881488, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.369026690107, 573.3979321712204, 576.6280748916946, 579.9942000579993, 582.3739480058938, 21532.354029379123, 22092.116315347368, 22317.10731570739, 22356.10575576977, 22526.774732252714, 25845.095421660244, 26249.950001999896, 26258.212253632373, 26274.6058809118, 26369.736302636968, 6544.672766361687, 6696.129503164581, 6707.094542236809, 6709.966450167743, 6730.427913627347, 6336.683165841712, 6586.0, 6606.207255129367, 6629.668516574152, 6633.436157926582, 6565.0480680301325, 6573.211214654224, 6573.901391479131, 6575.605463672171, 6578.901316480256, 6546.214454265519, 6549.181352330958, 6557.344265573428, 6560.93439065608, 6562.179727534059, 6092.878142437126, 6095.664738439384, 6099.0, 6099.0, 6112.908306375408, 28686.139415817557, 29029.0, 29100.0, 29168.124956251348, 29541.0, 2007.9498012549802, 2025.3316405586122, 2028.29009846554, 2030.7867673894177, 2041.9591608167902, 19054.61890762191, 19722.013899304977, 19722.90138549298, 19743.308984185485, 19768.11043503046, 2297.276357947241, 2317.5364927014666, 2354.540864531417, 2356.6111591587537, 2361.97254194426, 136389.0, 139473.0, 139536.0, 139677.0, 139871.30064349613, 14333.354999025069, 14982.250887455584, 15035.54893353201, 15377.308021139075, 15469.5359139226, 23487.76512234856, 23543.88228058883, 23566.29301120969, 23583.410414739425, 23592.17427390033, 9847.606095756233, 10010.149137323264, 10042.748931276776, 10045.648402305884, 10170.338927969806, 7647.847043059219, 9125.36122471434, 9209.0, 9310.86033709495, 9322.906770932339, 7979.9601001994615, 9051.230645395084, 9059.411138275991, 9148.542572871329, 9158.81682366349, 2682.6780786305767, 2708.390612112259, 2710.471458065698, 2719.279390961397, 2747.615333853262, 948.3503799897093, 978.6721448314752, 993.9602415903283, 997.8952210017989, 1005.3766664667953, 19390.03049847502, 20201.59596808085, 20504.794952050586, 23886.661852949303, 24902.5019499609, 1041.2555023158477, 1070.3363914373138, 1075.0754351257913, 1080.8108580998294, 1085.3976043295888, 11575.479103440366, 13239.602811915658, 13852.238126903118, 14194.361253743606, 14291.356888940023, 500600.0, 517722.46832595085, 541064.0, 541724.0, 543042.0, 112648.0, 116166.0, 116256.41871790586, 116496.0, 117784.41107794407, 1231.9260844349367, 1250.7748605251143, 1268.3784945376883, 1270.7394984028251, 1276.9808452873215, 21728.456788580414, 21898.45253868666, 21973.450663732896, 22251.666225006313, 22330.0, 59475.40524594699, 59554.10668839884, 60528.39471605227, 60799.39200608114, 63561.36438635555, 70723.64638176776, 71883.28116718766, 72009.63995179991, 72550.0, 72566.0, 14695.191764453059, 15052.473163439226, 15175.013624114397, 15178.316975735897, 15187.088774673555, 23758.168464103674, 23760.762392375855, 23920.521589567765, 24054.27837164818, 24152.516949660556, 16680.916595416944, 18391.54021149482, 18901.621967560848, 19255.903720481307, 19327.130279137753, 15568.37726490973, 16387.098709571088, 16538.421155259988, 16860.915695421936, 17156.571085722957, 18177.727334089734, 18512.537186570444, 19182.616347673247, 19454.22183112716, 19506.60986780228, 14463.553644635453, 14710.852891471377, 14716.705665886408, 14717.852821472077, 14784.926075369554, 17005.829941700424, 17032.48902532926, 17060.23228954675, 17118.058506782247, 17598.912005440405], "value": 12213.410979855205, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.2639548248465, 1373.2927542315708, 1389.0832050846443, 1406.8171137752097, 1407.373718695181, 15170.84829151708, 15285.388584456636, 15851.841481585196, 15859.444919427822, 15895.28471218795, 16002.0, 16290.592735181626, 16324.0, 16325.18374081297, 16340.937839040454, 2770.016644091354, 2836.0640988473856, 2836.2342167614734, 2837.092130518235, 2843.829370237782, 2769.819961702493, 2878.625778648783, 2880.2223399682075, 2881.193265885545, 2884.8124871883365, 2776.194903477989, 2778.8610569471443, 2806.873690683914, 2818.9295267618268, 2825.3501694610254, 2709.173702020883, 2713.91858244252, 2733.3030077330254, 2738.1511731363144, 2739.123480486245, 2674.064077572834, 2697.689765676933, 2701.7568418842397, 2710.1192112563494, 2712.34903623131, 15162.469313573973, 15224.847751522453, 15334.309956052004, 15339.693206135815, 15346.155961422117, 8079.798005049862, 8265.545395003272, 8276.627551760184, 8321.292690121347, 8325.791855203608, 5338.839834804962, 5340.118880384732, 5419.214213938996, 5477.068898287261, 5502.8349149525575, 1763.2241813601975, 1765.5850875044355, 1797.2451570340488, 1823.188681036939, 1857.359211072178, 26802.195934122006, 27022.054228101922, 27024.59463108055, 27092.864535677392, 27103.0, 6647.534672572873, 6683.832904177434, 6828.2147553031045, 6887.276835932255, 7033.085698859116, 17391.65216695659, 17396.913015435093, 17408.47774566765, 17429.912850435667, 17466.650666986843, 8066.596670166468, 8068.314193293519, 8179.79550511242, 8435.156484351515, 8446.113158118433, 6083.726232319556, 6291.056341548713, 6432.9678351607945, 7571.621418929141, 7572.886406703904, 4838.927416088762, 5234.241035049897, 5389.595780316492, 5391.29913111293, 5409.134538473825, 1487.1894817324585, 1497.9400823967137, 1510.8791296696238, 1511.6220944763809, 1523.7028779387913, 866.67066514724, 880.4453194487432, 890.6882591093195, 893.4415990006246, 908.3006085314271, 7851.254130857559, 7853.960730196312, 7870.0, 7899.0, 7942.285194332422, 723.3778950102908, 731.5500966905315, 733.7028503456108, 748.0611832150616, 758.0335072782246, 4714.245720684674, 4834.419869615669, 4836.298736683161, 4855.198892182821, 4946.851594452172, 21247.256346027814, 21291.78708212929, 22109.226177083725, 22111.0, 22115.668264976037, 22072.779272207074, 22116.778832211792, 22180.0, 22243.666345004836, 22304.219352322914, 10919.672409827717, 11479.483423245974, 11723.4138293085, 11809.822852657217, 11811.409429528489, 9139.177474027369, 9169.40398874071, 9264.305177111879, 9286.0, 9337.346385752931, 18831.15259813339, 18859.811401886356, 19124.33064842724, 19306.806931931063, 19563.902180489007, 18410.815891841445, 19303.613927721086, 19697.310594129136, 20031.0, 20127.496812579804, 11717.414129293331, 11958.940205298917, 11961.0, 11964.042876570027, 11965.641030769091, 13274.203547787163, 13277.468901244229, 13321.134126282148, 13353.66615834612, 13520.797188042385, 10361.844572331573, 11236.494357753758, 11237.606683766357, 11330.830037549607, 11457.770844583229, 10869.29349592274, 11006.559737610405, 11182.217244793112, 11269.887301126884, 11315.490802913724, 9567.808643827224, 9578.47318397467, 9647.0, 9672.08115229052, 9801.16690081351, 7232.602206878461, 7285.7085716572865, 7299.671514781741, 7318.158411782713, 7389.224131466333, 7578.280063393969, 7591.696332146873, 7610.581418022062, 7685.654145563575, 7723.150453450228], "value": 6691.390872155948, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.61330115579847, 501.3157040639527, 525.947405259474, 527.0250037430751, 531.9468053194681, 6270.153529273547, 6359.6184228946295, 6391.64845933474, 6398.840028999277, 6451.870962580757, 6030.849228769283, 6036.275646922364, 6105.725242364093, 6123.173371594833, 6195.941658213866, 5028.145215313387, 5059.266406371074, 5065.468125846788, 5065.7467126643705, 5094.6688465249645, 5003.1994880819075, 5037.4710655381205, 5053.090443720129, 5059.342285502879, 5077.619178561604, 4857.125717370872, 5007.974960125194, 5008.774605142759, 5019.64862459628, 5027.874303142414, 5025.824096156617, 5029.170186919154, 5030.924536131979, 5031.295618613397, 5035.395752509722, 4533.954660453386, 4537.818487260505, 4547.1360441516335, 4550.817967281305, 4574.61115805157, 6175.7529698811995, 6195.969020154916, 6213.813585592439, 6227.906581401283, 6244.750209991595, 5770.653760774367, 5775.364709881909, 5782.149712092132, 5786.1610066540115, 5788.5369170466265, 6594.769183078617, 6601.174853143357, 6664.366885145903, 6676.732930682767, 6683.6658167091455, 4893.510648935114, 4898.779554920037, 4903.131667974204, 4907.080287229129, 4942.481039490837, 7001.404880585156, 7037.290643102442, 7040.190378106532, 7048.964755176191, 7077.221505634376, 5036.370453693288, 5043.445221025684, 5055.620828437808, 5101.591872650179, 5115.411727651293, 5095.8980820383385, 5108.157154069611, 5129.717865517432, 5134.204198349262, 5138.2806407103035, 5732.082866741302, 5741.91387129185, 5764.682942438122, 5772.451617096369, 5799.449052340021, 6639.302873198245, 6667.799966001027, 6717.56335838169, 6750.426213771786, 6767.052612634235, 1670.3485640599934, 1687.3548290416745, 1727.6199236168022, 1731.991340043317, 1887.7545919030713, 1414.151509094535, 1462.2980969134876, 1470.3678916402998, 1471.3820195517908, 1474.3660226102663, 5678.223981052636, 5802.33273173582, 5836.358000619928, 5856.502197313191, 5886.970565147147, 6450.645214513247, 6532.8366790830605, 6672.399484046362, 6717.227518835396, 6747.359000894907, 5727.3699893011735, 5730.713464326767, 5764.625299355528, 5797.36229014808, 5854.775185429509, 3494.9126271842897, 3555.1112221944513, 3562.750607457453, 3565.35823551758, 3587.1390866191928, 6507.34926507355, 6550.868982620321, 6567.770128045496, 6594.802155935329, 6596.505262105361, 6817.931820681829, 6849.965750171217, 6879.346462086094, 6899.275576064541, 6956.991236270713, 6546.0, 6546.3453654635105, 6548.1814773153355, 6569.441597464173, 6633.535652504375, 6721.865562688621, 6779.694913728993, 6807.727690892309, 6835.829104272433, 6853.828654283683, 6680.5991640501725, 6729.394354508117, 6755.560888542229, 6778.423833974168, 6799.830004249736, 6458.741650333934, 6522.706478208397, 6539.70571324282, 6592.406683398325, 6602.40578347951, 4956.702597844141, 4964.25536169563, 4967.254911763264, 5012.649114561946, 5027.572656324107, 6797.25230224665, 6821.28376520478, 6827.590344579341, 6859.451243900576, 6888.173419189729, 6518.771842985473, 6520.510961677798, 6576.44100251484, 6663.067170596023, 6668.032930285481, 6323.588966717149, 6499.0, 6563.671816409065, 6573.934260657332, 6603.669816509251, 6221.688915554295, 6332.335104813928, 6366.472046708849, 6430.64631445275, 6470.209162799174, 6535.411812936859, 6593.340665933369, 6593.703283352164, 6635.8009259722285, 6647.434968027772, 6303.621782693053, 6344.873102537831, 6366.236051673829, 6403.470629101385, 6418.775342863164], "value": 5119.208036194606, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [906.859436787298, 920.0799200799197, 936.5457752989803, 937.8593211018347, 939.8073394954033, 18673.346432874852, 19841.20635174595, 19868.60262794745, 19889.502762430966, 20979.16083356668, 20149.496262593406, 21443.892780536076, 21463.0, 21572.568548629017, 21580.352589422342, 938.8028514012066, 963.802420503795, 965.1120968708805, 965.2615748952057, 965.4834663455053, 969.849673300636, 1011.3881101933334, 1017.3285631483222, 1017.3488967061087, 1020.2603112743276, 1061.198794909842, 1074.7796701676177, 1074.940878251696, 1075.1452595186845, 1075.1613741281774, 1054.5254635414094, 1061.5541472581497, 1063.558623171383, 1063.6702622187108, 1064.7391389109641, 778.7079845058104, 780.2899361580934, 780.4302858912969, 780.6252998560666, 780.7189411811743, 17865.01742404167, 18266.452006439697, 18410.44768656929, 18425.170867311004, 18880.24479020851, 20263.29078482246, 20593.0, 21009.89495052515, 21030.579388412298, 21430.14279428821, 16644.6671066578, 16648.08435536045, 16796.0, 16824.83175168245, 17043.91478042614, 3836.9040773980596, 3868.3617203161443, 3868.613138686137, 3875.302445559798, 3877.418387241908, 127296.36351818181, 127411.08883366664, 130419.0, 131144.34427827896, 131486.0, 16223.51329460118, 16295.755563666555, 16630.916845415937, 16844.831551684572, 16849.494515164566, 30111.849440752656, 30604.84697576498, 30678.53982190269, 30721.231969200948, 31200.37599248002, 7772.0, 8091.676332946734, 8190.467619604826, 8517.872231916526, 9271.490068046187, 4240.194363071007, 4339.218940590724, 4347.5217726050105, 4356.6950313477755, 4357.128574285156, 5134.640575159778, 5196.76614552346, 5228.686278823283, 5256.080185967517, 5279.894402111936, 988.4118949225343, 1013.7060252526685, 1014.497823577334, 1015.8882522922472, 1027.41950797799, 623.4949690750507, 628.6825153297647, 636.0491065856575, 642.2870613618879, 673.5049738442241, 11534.365609891536, 11765.764684706259, 11983.161178717406, 12082.637520874388, 12466.252024878355, 688.8036909480774, 702.3994484715597, 705.8552996635677, 718.1812733483832, 720.2257573108928, 5438.809641662523, 5444.40111587725, 5455.236266922634, 5500.257465242221, 5581.2186293918885, 107846.46076769567, 109230.0, 109231.45384273028, 109234.45382773035, 109418.0, 96073.5196324028, 100239.0, 100239.49880250552, 100889.0, 103489.0, 84422.57788711016, 86324.0, 86346.13653863507, 86747.56626216829, 88648.0, 9953.751156221153, 10028.949855250678, 10072.697819065439, 11978.58074967372, 12603.243805371707, 39763.80118099507, 41293.793531032155, 42180.57819421766, 42868.571314286455, 43252.351214732436, 67379.0, 69783.0, 70146.0, 71808.64095679489, 72368.0, 16233.918830406245, 16250.837491624932, 16253.618442432526, 16347.673046539241, 16942.40701575439, 20416.591668166257, 20652.483687907923, 20898.68651970252, 21095.57808843845, 21139.154433822474, 17946.282148713904, 17954.102294884942, 18516.351927682466, 18630.53423664419, 18859.811401885807, 15719.528414147593, 16844.57888552796, 17164.22760975784, 17184.914075429544, 18169.636607268047, 17549.912250438665, 17615.295388184328, 17671.823281767018, 18834.434966951012, 18930.905345473184, 12212.75574488523, 12349.814752778535, 12638.873611263769, 12720.6819829505, 13123.146995445266, 13029.934850326066, 13239.3380330985, 13278.09920235818, 13279.535216267384, 13444.798328025283], "value": 9080.538717642736, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1484.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 99.0, 109.0, 108.0, 118.0, 107.0, 98.0, 106.0, 111.0], "value": 106.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 120.0, 144.0, 171.0, 118.0, 150.0, 151.0, 120.0, 173.0, 116.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 164.0, 163.0, 162.0, 164.0, 162.0, 165.0, 172.0, 163.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 171.0, 75.0, 74.0, 73.0, 73.0, 153.0, 76.0], "value": 92.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 136.0, 78.0, 80.0, 80.0, 138.0, 79.0, 79.0, 125.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 117.0, 117.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 148.0, 148.0, 151.0, 150.0, 148.0, 148.0, 150.0, 148.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 95.0, 93.0, 94.0, 94.0, 94.0, 100.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 55.0, 53.0, 52.0, 52.0, 52.0, 52.0, 52.0, 53.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 39.0, 38.0, 38.0, 38.0, 38.0, 37.0, 38.0, 37.0, 38.0], "value": 37.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 75.0, 74.0, 76.0, 79.0, 74.0, 73.0, 74.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 118.0, 123.0, 126.0, 120.0, 125.0, 119.0, 118.0, 125.0, 161.0], "value": 125.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 166.0, 169.0, 165.0, 167.0, 172.0, 170.0, 172.0, 171.0, 168.0], "value": 169.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 65.0, 68.0, 65.0, 64.0, 64.0, 66.0, 65.0, 66.0], "value": 65.6, "unit": "score"}]}]} 06:53:06 INFO - Return code: 0 06:53:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:53:06 INFO - # TBPL SUCCESS # 06:53:06 INFO - Running post-action listener: _package_coverage_data 06:53:06 INFO - Running post-action listener: _resource_record_post_action 06:53:06 INFO - [mozharness: 2017-08-31 13:53:06.963868Z] Finished run-tests step (success) 06:53:06 INFO - Running post-run listener: _resource_record_post_run 06:53:07 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 391032832; Read time: 64; Write time: 573560 06:53:07 INFO - TinderboxPrint: CPU usage
13.1% 06:53:07 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 64 06:53:07 INFO - TinderboxPrint: I/O write bytes / time
391,032,832 / 573,560 06:53:07 INFO - TinderboxPrint: CPU idle
8,249.9 (86.9%) 06:53:07 INFO - TinderboxPrint: CPU user
1,192.7 (12.6%) 06:53:07 INFO - TinderboxPrint: Swap in / out
0 / 0 06:53:07 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 74305536; Read time: 0; Write time: 105004 06:53:07 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 06:53:07 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283844608; Read time: 64; Write time: 322648 06:53:07 INFO - Running post-run listener: _upload_blobber_files 06:53:07 INFO - Blob upload gear active. 06:53:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:53:07 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 06:53:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:53:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:53:08 INFO - (blobuploader) - INFO - Open directory for files ... 06:53:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:53:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:53:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:53:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:53:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:53:11 INFO - (blobuploader) - INFO - Done attempting. 06:53:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:53:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:53:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:53:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:53:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:53:12 INFO - (blobuploader) - INFO - Done attempting. 06:53:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:53:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:53:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:53:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:53:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:53:13 INFO - (blobuploader) - INFO - Done attempting. 06:53:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:53:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:53:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:53:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:53:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:53:14 INFO - (blobuploader) - INFO - Done attempting. 06:53:14 INFO - (blobuploader) - INFO - Iteration through files over. 06:53:14 INFO - Return code: 0 06:53:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:53:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:53:14 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e7f1cd614f0f6d7be4afd14c424365f12219e0e71d32f263399f8f9669c8e2ae7c69da0d2013b3ba2811ea6fffdb429520888a7210aa4a6cf982b8ff05d4ea2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c251b9644b28604bc22a8aaab8c333755fb34313ddbba118f840c84e85b7ab2e6a1d606337de3369c48dcb0017317ad939f4d8cf30ca94ae35165a081b804984", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7b1f8b71d4ae0ba9a1440d15d186b3ed387c6ba587eff0991ca073297c3030cb70ee3530f2b05567cfd9bc99ebf23f23db72cdb69ec2dc3d4f207c1e9f4b6e3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ce6136dbca2b68311de3d4bdf9fe8a9554857ae66066a510e83d7e94883891d2e65827c261c713453905688a01c4588a2bdd649fd46616b78ed4afb5de69252"} 06:53:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:53:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:53:14 INFO - Contents: 06:53:14 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e7f1cd614f0f6d7be4afd14c424365f12219e0e71d32f263399f8f9669c8e2ae7c69da0d2013b3ba2811ea6fffdb429520888a7210aa4a6cf982b8ff05d4ea2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c251b9644b28604bc22a8aaab8c333755fb34313ddbba118f840c84e85b7ab2e6a1d606337de3369c48dcb0017317ad939f4d8cf30ca94ae35165a081b804984", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7b1f8b71d4ae0ba9a1440d15d186b3ed387c6ba587eff0991ca073297c3030cb70ee3530f2b05567cfd9bc99ebf23f23db72cdb69ec2dc3d4f207c1e9f4b6e3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ce6136dbca2b68311de3d4bdf9fe8a9554857ae66066a510e83d7e94883891d2e65827c261c713453905688a01c4588a2bdd649fd46616b78ed4afb5de69252"} 06:53:14 INFO - Running post-run listener: copy_logs_to_upload_dir 06:53:14 INFO - Copying logs to upload dir... 06:53:14 INFO - mkdir: /builds/slave/test/build/upload/logs 06:53:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.364541 ========= master_lag: -0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-08-31 06:53:14.732703) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-31 06:53:14.737432) ========= 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=af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e7f1cd614f0f6d7be4afd14c424365f12219e0e71d32f263399f8f9669c8e2ae7c69da0d2013b3ba2811ea6fffdb429520888a7210aa4a6cf982b8ff05d4ea2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c251b9644b28604bc22a8aaab8c333755fb34313ddbba118f840c84e85b7ab2e6a1d606337de3369c48dcb0017317ad939f4d8cf30ca94ae35165a081b804984", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7b1f8b71d4ae0ba9a1440d15d186b3ed387c6ba587eff0991ca073297c3030cb70ee3530f2b05567cfd9bc99ebf23f23db72cdb69ec2dc3d4f207c1e9f4b6e3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ce6136dbca2b68311de3d4bdf9fe8a9554857ae66066a510e83d7e94883891d2e65827c261c713453905688a01c4588a2bdd649fd46616b78ed4afb5de69252"} build_url:https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011372 build_url: 'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e7f1cd614f0f6d7be4afd14c424365f12219e0e71d32f263399f8f9669c8e2ae7c69da0d2013b3ba2811ea6fffdb429520888a7210aa4a6cf982b8ff05d4ea2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c251b9644b28604bc22a8aaab8c333755fb34313ddbba118f840c84e85b7ab2e6a1d606337de3369c48dcb0017317ad939f4d8cf30ca94ae35165a081b804984", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7b1f8b71d4ae0ba9a1440d15d186b3ed387c6ba587eff0991ca073297c3030cb70ee3530f2b05567cfd9bc99ebf23f23db72cdb69ec2dc3d4f207c1e9f4b6e3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ce6136dbca2b68311de3d4bdf9fe8a9554857ae66066a510e83d7e94883891d2e65827c261c713453905688a01c4588a2bdd649fd46616b78ed4afb5de69252"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-31 06:53:14.763626) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 06:53:14.763921) ========= 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=af724c981e2a0f424dbabf99000001e7-1504186343.40537-290706723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004518 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 06:53:14.848654) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-31 06:53:14.848950) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-31 06:53:14.852256) ========= ========= Total master_lag: 0.09 =========