builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-062 starttime: 1504686622.9 results: success (0) revision: b235fb79d6e017b9f47309cb06eb701c06b7e8d2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:22.897418) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:22.897832) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:22.898106) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:22.915447) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:22.915705) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-06 01:30:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 32.7M=0s 2017-09-06 01:30:23 (32.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121259 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:23.050087) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:23.050412) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020735 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:23.099939) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-06 01:30:23.100245) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b235fb79d6e017b9f47309cb06eb701c06b7e8d2 --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 b235fb79d6e017b9f47309cb06eb701c06b7e8d2 --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=a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-06 01:30:23,151 truncating revision to first 12 chars 2017-09-06 01:30:23,151 Setting DEBUG logging. 2017-09-06 01:30:23,151 attempt 1/10 2017-09-06 01:30:23,151 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b235fb79d6e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-06 01:30:24,405 unpacking tar archive at: mozilla-central-b235fb79d6e0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.544875 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-06 01:30:24.661326) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:24.661640) ========= script_repo_revision: b235fb79d6e017b9f47309cb06eb701c06b7e8d2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:24.662033) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:24.662297) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:24.674864) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-06 01:30:24.675268) ========= /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=a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:30:24 INFO - MultiFileLogger online at 20170906 01:30:24 in /builds/slave/test 01:30:24 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 01:30:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:30:24 INFO - {'append_to_log': False, 01:30:24 INFO - 'base_work_dir': '/builds/slave/test', 01:30:24 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 01:30:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:30:24 INFO - 'branch': 'Firefox-Non-PGO', 01:30:24 INFO - 'buildbot_json_path': 'buildprops.json', 01:30:24 INFO - 'code_coverage': False, 01:30:24 INFO - 'config_files': ('talos/linux_config.py',), 01:30:24 INFO - 'default_actions': ('clobber', 01:30:24 INFO - 'read-buildbot-config', 01:30:24 INFO - 'download-and-extract', 01:30:24 INFO - 'populate-webroot', 01:30:24 INFO - 'create-virtualenv', 01:30:24 INFO - 'install', 01:30:24 INFO - 'setup-mitmproxy', 01:30:24 INFO - 'run-tests'), 01:30:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:30:24 INFO - 'disable_ccov_upload': False, 01:30:24 INFO - 'disable_stylo': False, 01:30:24 INFO - 'download_minidump_stackwalk': True, 01:30:24 INFO - 'download_symbols': 'ondemand', 01:30:24 INFO - 'enable_stylo': False, 01:30:24 INFO - 'enable_webrender': False, 01:30:24 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 01:30:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:30:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:30:24 INFO - 'gecko_profile': False, 01:30:24 INFO - 'gecko_profile_interval': 0, 01:30:24 INFO - 'installer_path': 'installer.exe', 01:30:24 INFO - 'log_level': 'info', 01:30:24 INFO - 'log_name': 'talos', 01:30:24 INFO - 'log_to_console': True, 01:30:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:30:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:30:24 INFO - 'opt_config_files': (), 01:30:24 INFO - 'pip_index': False, 01:30:24 INFO - 'suite': 'dromaeojs-e10s', 01:30:24 INFO - 'system_bits': '32', 01:30:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:30:24 INFO - 'title': 'talos-linux64-ix-062', 01:30:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:30:24 INFO - 'use_talos_json': True, 01:30:24 INFO - 'verify': 'False', 01:30:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:30:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:30:24 INFO - 'work_dir': 'build'} 01:30:24 INFO - [mozharness: 2017-09-06 08:30:24.872369Z] Running clobber step. 01:30:24 INFO - Running pre-action listener: _resource_record_pre_action 01:30:24 INFO - Running main action method: clobber 01:30:24 INFO - rmtree: /builds/slave/test/build 01:30:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:30:25 INFO - Running post-action listener: _resource_record_post_action 01:30:25 INFO - [mozharness: 2017-09-06 08:30:25.884450Z] Finished clobber step (success) 01:30:25 INFO - [mozharness: 2017-09-06 08:30:25.884548Z] Running read-buildbot-config step. 01:30:25 INFO - Running pre-action listener: _resource_record_pre_action 01:30:25 INFO - Running main action method: read_buildbot_config 01:30:25 INFO - Using buildbot properties: 01:30:25 INFO - { 01:30:25 INFO - "project": "", 01:30:25 INFO - "product": "firefox", 01:30:25 INFO - "who": "archaeopteryx@coole-files.de", 01:30:25 INFO - "installer_path": "public/build/target.tar.bz2", 01:30:25 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 01:30:25 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 01:30:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 01:30:25 INFO - "stage_platform": "linux64", 01:30:25 INFO - "basedir": "/builds/slave/test", 01:30:25 INFO - "buildnumber": 1117, 01:30:25 INFO - "platform": "ubuntu64_hw", 01:30:25 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:30:25 INFO - "slavebuilddir": "test", 01:30:25 INFO - "taskId": "eCoEbOThS-W8PVkJG5O6sA", 01:30:25 INFO - "branch": "mozilla-central", 01:30:25 INFO - "script_repo_revision": "production", 01:30:25 INFO - "revision": "b235fb79d6e017b9f47309cb06eb701c06b7e8d2", 01:30:25 INFO - "slavename": "talos-linux64-ix-062", 01:30:25 INFO - "repo_path": "mozilla-central" 01:30:25 INFO - } 01:30:25 INFO - Finding installer, test and symbols from parent task. 01:30:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eCoEbOThS-W8PVkJG5O6sA'}, attempt #1 01:30:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eCoEbOThS-W8PVkJG5O6sA'}, attempt #1 01:30:26 INFO - Task dependencies: K7fP2uF7RUix-OKaOebu0g 01:30:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g'}, attempt #1 01:30:27 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2 01:30:27 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.test_packages.json 01:30:27 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.crashreporter-symbols.zip 01:30:27 INFO - Running post-action listener: _resource_record_post_action 01:30:27 INFO - [mozharness: 2017-09-06 08:30:27.283338Z] Finished read-buildbot-config step (success) 01:30:27 INFO - [mozharness: 2017-09-06 08:30:27.283556Z] Running download-and-extract step. 01:30:27 INFO - Running pre-action listener: _resource_record_pre_action 01:30:27 INFO - Running main action method: download_and_extract 01:30:27 INFO - mkdir: /builds/slave/test/build/tests 01:30:27 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')]} 01:30:27 INFO - trying https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.test_packages.json 01:30:27 INFO - Downloading https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 01:30:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 01:30:27 INFO - Downloaded 989 bytes. 01:30:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 01:30:27 INFO - Using the following test package requirements: 01:30:27 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 01:30:27 INFO - u'common': [u'target.common.tests.zip'], 01:30:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 01:30:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 01:30:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 01:30:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 01:30:27 INFO - u'mozbase': [u'target.common.tests.zip'], 01:30:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 01:30:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 01:30:27 INFO - u'web-platform': [u'target.common.tests.zip', 01:30:27 INFO - u'target.web-platform.tests.tar.gz'], 01:30:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 01:30:27 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 01:30:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.common.tests.zip 01:30:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:30:27 INFO - Fetch https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.common.tests.zip into memory 01:30:28 INFO - Content-Length response header: 55019854 01:30:28 INFO - Bytes received: 55019854 01:30:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 01:30:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.common.tests.zip 01:30:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:30:39 INFO - Fetch https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.common.tests.zip into memory 01:30:40 INFO - Content-Length response header: 55019854 01:30:40 INFO - Bytes received: 55019854 01:30:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.talos.tests.zip 01:30:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 01:30:52 INFO - Fetch https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.talos.tests.zip into memory 01:30:53 INFO - Content-Length response header: 14496654 01:30:53 INFO - Bytes received: 14496654 01:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:53 INFO - trying https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2 01:30:53 INFO - Downloading https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 01:30:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 01:30:54 INFO - Downloaded 62756371 bytes. 01:30:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2 01:30:54 INFO - mkdir: /builds/slave/test/properties 01:30:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:30:54 INFO - Writing to file /builds/slave/test/properties/build_url 01:30:54 INFO - Contents: 01:30:54 INFO - build_url:https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2 01:30:54 INFO - Running post-action listener: _resource_record_post_action 01:30:54 INFO - Running post-action listener: find_tests_for_verification 01:30:54 INFO - Running post-action listener: set_extra_try_arguments 01:30:54 INFO - [mozharness: 2017-09-06 08:30:54.990212Z] Finished download-and-extract step (success) 01:30:54 INFO - [mozharness: 2017-09-06 08:30:54.990314Z] Running populate-webroot step. 01:30:54 INFO - Running pre-action listener: _resource_record_pre_action 01:30:54 INFO - Running main action method: populate_webroot 01:30:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:30:54 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:30:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:30:54 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:30:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:30:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:30:54 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 01:30:54 INFO - 'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:30:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:30:54 INFO - 'tests': ['damp', 'tps']}, 01:30:54 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 01:30:54 INFO - 'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['damp', 'tps']}, 01:30:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:30:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 01:30:54 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 01:30:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 01:30:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 01:30:54 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 01:30:54 INFO - 'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 01:30:54 INFO - 'other-e10s': {'tests': ['a11yr', 01:30:54 INFO - 'ts_paint', 01:30:54 INFO - 'tpaint', 01:30:54 INFO - 'sessionrestore', 01:30:54 INFO - 'sessionrestore_many_windows', 01:30:54 INFO - 'sessionrestore_no_auto_restore', 01:30:54 INFO - 'tabpaint']}, 01:30:54 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['a11yr', 01:30:54 INFO - 'ts_paint', 01:30:54 INFO - 'tpaint', 01:30:55 INFO - 'sessionrestore', 01:30:55 INFO - 'sessionrestore_many_windows', 01:30:55 INFO - 'sessionrestore_no_auto_restore', 01:30:55 INFO - 'tabpaint']}, 01:30:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 01:30:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 01:30:55 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:30:55 INFO - 'tests': ['bloom_basic_singleton']}, 01:30:55 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:30:55 INFO - 'tests': ['bloom_basic']}, 01:30:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:30:55 INFO - 'tsvgr_opacity', 01:30:55 INFO - 'tart', 01:30:55 INFO - 'tscrollx', 01:30:55 INFO - 'tsvg_static']}, 01:30:55 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:30:55 INFO - 'tests': ['tsvgx', 01:30:55 INFO - 'tsvgr_opacity', 01:30:55 INFO - 'tart', 01:30:55 INFO - 'tscrollx', 01:30:55 INFO - 'tsvg_static']}, 01:30:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:30:55 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 01:30:55 INFO - 'talos_options': ['--enable-stylo'], 01:30:55 INFO - 'tests': ['tp5o']}, 01:30:55 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 01:30:55 INFO - 'talos_options': ['--mitmproxy', 01:30:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 01:30:55 INFO - '--firstNonBlankPaint'], 01:30:55 INFO - 'tests': ['tp6_google', 01:30:55 INFO - 'tp6_youtube', 01:30:55 INFO - 'tp6_amazon', 01:30:55 INFO - 'tp6_facebook']}, 01:30:55 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 01:30:55 INFO - 'talos_options': ['--enable-stylo', 01:30:55 INFO - '--mitmproxy', 01:30:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 01:30:55 INFO - '--firstNonBlankPaint'], 01:30:55 INFO - 'tests': ['tp6_google', 01:30:55 INFO - 'tp6_youtube', 01:30:55 INFO - 'tp6_amazon', 01:30:55 INFO - 'tp6_facebook']}, 01:30:55 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 01:30:55 INFO - 'talos_options': ['--enable-stylo', 01:30:55 INFO - '--stylo-threads=1', 01:30:55 INFO - '--mitmproxy', 01:30:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 01:30:55 INFO - '--firstNonBlankPaint'], 01:30:55 INFO - 'tests': ['tp6_google', 01:30:55 INFO - 'tp6_youtube', 01:30:55 INFO - 'tp6_amazon', 01:30:55 INFO - 'tp6_facebook']}, 01:30:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:30:55 INFO - 'talos_options': ['--xperf_path', 01:30:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:30:55 INFO - 'tests': ['tp5n']}, 01:30:55 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 01:30:55 INFO - 'talos_options': ['--enable-stylo', 01:30:55 INFO - '--xperf_path', 01:30:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:30:55 INFO - 'tests': ['tp5n']}}} 01:30:55 INFO - Running post-action listener: _resource_record_post_action 01:30:55 INFO - [mozharness: 2017-09-06 08:30:55.004651Z] Finished populate-webroot step (success) 01:30:55 INFO - [mozharness: 2017-09-06 08:30:55.004741Z] Running create-virtualenv step. 01:30:55 INFO - Running pre-action listener: _resource_record_pre_action 01:30:55 INFO - Running main action method: create_virtualenv 01:30:55 INFO - Creating virtualenv /builds/slave/test/build/venv 01:30:55 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:30:55 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 01:30:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 01:30:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:30:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:30:55 INFO - Installing distribute.............................................................................................................................................................................................done. 01:30:55 INFO - Installing pip...............done. 01:30:55 INFO - Return code: 0 01:30:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:30:55 INFO - Reading from file tmpfile_stdout 01:30:55 INFO - Output received: 01:30:55 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 01:30:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:30: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')]} 01:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30: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')]} 01:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:55 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 0x254d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bd090>, '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 0x28bdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', '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 01:30:55 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 01:30:55 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 01:30:55 INFO - Using env: {'DISPLAY': ':0', 01:30:55 INFO - 'HOME': '/home/cltbld', 01:30:55 INFO - 'LANG': 'en_US.UTF-8', 01:30:55 INFO - 'LANGUAGE': 'en_US:en', 01:30:55 INFO - 'LOGNAME': 'cltbld', 01:30:55 INFO - 'MAIL': '/var/mail/cltbld', 01:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:55 INFO - 'MOZ_NO_REMOTE': '1', 01:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:30:55 INFO - 'NO_EM_RESTART': '1', 01:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:55 INFO - 'PWD': '/builds/slave/test', 01:30:56 INFO - 'SHELL': '/bin/bash', 01:30:56 INFO - 'SHLVL': '1', 01:30:56 INFO - 'TERM': 'linux', 01:30:56 INFO - 'TMOUT': '86400', 01:30:56 INFO - 'USER': 'cltbld', 01:30:56 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', 01:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:56 INFO - '_': '/tools/buildbot/bin/python'} 01:30:57 INFO - Ignoring indexes: http://pypi.python.org/simple/ 01:30:57 INFO - Downloading/unpacking pip>=1.5 01:30:57 INFO - Running setup.py egg_info for package pip 01:30:57 INFO - warning: no files found matching 'pip/cacert.pem' 01:30:57 INFO - warning: no files found matching '*.html' under directory 'docs' 01:30:57 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 01:30:57 INFO - no previously-included directories found matching 'docs/_build/_sources' 01:30:57 INFO - Installing collected packages: pip 01:30:57 INFO - Found existing installation: pip 1.1 01:30:57 INFO - Uninstalling pip: 01:30:57 INFO - Successfully uninstalled pip 01:30:57 INFO - Running setup.py install for pip 01:30:57 INFO - warning: no files found matching 'pip/cacert.pem' 01:30:57 INFO - warning: no files found matching '*.html' under directory 'docs' 01:30:57 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 01:30:57 INFO - no previously-included directories found matching 'docs/_build/_sources' 01:30:57 INFO - Installing pip script to /builds/slave/test/build/venv/bin 01:30:57 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 01:30:57 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 01:30:57 INFO - Successfully installed pip 01:30:57 INFO - Cleaning up... 01:30:57 INFO - Return code: 0 01:30:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:30: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')]} 01:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30: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')]} 01:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30: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', '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 0x254d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bd090>, '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 0x28bdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', '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 01:30: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', 'psutil>=3.1.1'] in /builds/slave/test/build 01:30: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 psutil>=3.1.1 01:30:57 INFO - Using env: {'DISPLAY': ':0', 01:30:57 INFO - 'HOME': '/home/cltbld', 01:30:57 INFO - 'LANG': 'en_US.UTF-8', 01:30:57 INFO - 'LANGUAGE': 'en_US:en', 01:30:57 INFO - 'LOGNAME': 'cltbld', 01:30:57 INFO - 'MAIL': '/var/mail/cltbld', 01:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:57 INFO - 'MOZ_NO_REMOTE': '1', 01:30:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:30:57 INFO - 'NO_EM_RESTART': '1', 01:30:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:57 INFO - 'PWD': '/builds/slave/test', 01:30:57 INFO - 'SHELL': '/bin/bash', 01:30:57 INFO - 'SHLVL': '1', 01:30:57 INFO - 'TERM': 'linux', 01:30:57 INFO - 'TMOUT': '86400', 01:30:57 INFO - 'USER': 'cltbld', 01:30:57 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', 01:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:57 INFO - '_': '/tools/buildbot/bin/python'} 01:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:57 INFO - Downloading/unpacking psutil>=3.1.1 01:30:57 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 01:30:57 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 01:30:57 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 01:30:57 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 01:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:30:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:30:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:30:58 INFO - Installing collected packages: psutil 01:30:58 INFO - Running setup.py install for psutil 01:30:58 INFO - building 'psutil._psutil_linux' extension 01:30:58 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 01:30:58 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 01:30:58 INFO - building 'psutil._psutil_posix' extension 01:30:58 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 01:30:59 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 01:30:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:30:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:30:59 INFO - Successfully installed psutil 01:30:59 INFO - Cleaning up... 01:30:59 INFO - Return code: 0 01:30:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:30: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')]} 01:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30: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')]} 01:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bd090>, '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 0x28bdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', '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 01:30: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:30: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 mozsystemmonitor==0.3 01:30:59 INFO - Using env: {'DISPLAY': ':0', 01:30:59 INFO - 'HOME': '/home/cltbld', 01:30:59 INFO - 'LANG': 'en_US.UTF-8', 01:30:59 INFO - 'LANGUAGE': 'en_US:en', 01:30:59 INFO - 'LOGNAME': 'cltbld', 01:30:59 INFO - 'MAIL': '/var/mail/cltbld', 01:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:59 INFO - 'MOZ_NO_REMOTE': '1', 01:30:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:30:59 INFO - 'NO_EM_RESTART': '1', 01:30:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:59 INFO - 'PWD': '/builds/slave/test', 01:30:59 INFO - 'SHELL': '/bin/bash', 01:30:59 INFO - 'SHLVL': '1', 01:30:59 INFO - 'TERM': 'linux', 01:30:59 INFO - 'TMOUT': '86400', 01:30:59 INFO - 'USER': 'cltbld', 01:30:59 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', 01:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:59 INFO - '_': '/tools/buildbot/bin/python'} 01:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:30: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 01:30: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 01:30: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 01:30: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 01:31:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:31:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:31:00 INFO - Installing collected packages: mozsystemmonitor 01:31:00 INFO - Running setup.py install for mozsystemmonitor 01:31:00 INFO - Successfully installed mozsystemmonitor 01:31:00 INFO - Cleaning up... 01:31:00 INFO - Return code: 0 01:31:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:31: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')]} 01:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31: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')]} 01:31:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31: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', '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 0x254d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bd090>, '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 0x28bdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', '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 01:31: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:31: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 jsonschema==2.5.1 01:31:00 INFO - Using env: {'DISPLAY': ':0', 01:31:00 INFO - 'HOME': '/home/cltbld', 01:31:00 INFO - 'LANG': 'en_US.UTF-8', 01:31:00 INFO - 'LANGUAGE': 'en_US:en', 01:31:00 INFO - 'LOGNAME': 'cltbld', 01:31:00 INFO - 'MAIL': '/var/mail/cltbld', 01:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:00 INFO - 'MOZ_NO_REMOTE': '1', 01:31:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:00 INFO - 'NO_EM_RESTART': '1', 01:31:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:00 INFO - 'PWD': '/builds/slave/test', 01:31:00 INFO - 'SHELL': '/bin/bash', 01:31:00 INFO - 'SHLVL': '1', 01:31:00 INFO - 'TERM': 'linux', 01:31:00 INFO - 'TMOUT': '86400', 01:31:00 INFO - 'USER': 'cltbld', 01:31:00 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', 01:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:00 INFO - '_': '/tools/buildbot/bin/python'} 01:31:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:00 INFO - Downloading/unpacking jsonschema==2.5.1 01:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:31:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:31: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 01:31: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 01:31: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 01:31: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 01:31:02 INFO - Downloading functools32-3.2.3-2.tar.gz 01:31:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:31:02 INFO - warning: no files found matching '*.txt' 01:31:02 INFO - no previously-included directories found matching 'build' 01:31:02 INFO - no previously-included directories found matching 'dist' 01:31:02 INFO - no previously-included directories found matching '.git*' 01:31:02 INFO - Installing collected packages: jsonschema, functools32 01:31:02 INFO - Running setup.py install for functools32 01:31:02 INFO - warning: no files found matching '*.txt' 01:31:02 INFO - no previously-included directories found matching 'build' 01:31:02 INFO - no previously-included directories found matching 'dist' 01:31:02 INFO - no previously-included directories found matching '.git*' 01:31:02 INFO - Successfully installed jsonschema functools32 01:31:02 INFO - Cleaning up... 01:31:02 INFO - Return code: 0 01:31:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:31: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')]} 01:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31: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')]} 01:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31: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', '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 0x254d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bd090>, '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 0x28bdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', '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 01:31: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:31: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 functools32==3.2.3-2 01:31:02 INFO - Using env: {'DISPLAY': ':0', 01:31:02 INFO - 'HOME': '/home/cltbld', 01:31:02 INFO - 'LANG': 'en_US.UTF-8', 01:31:02 INFO - 'LANGUAGE': 'en_US:en', 01:31:02 INFO - 'LOGNAME': 'cltbld', 01:31:02 INFO - 'MAIL': '/var/mail/cltbld', 01:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:02 INFO - 'MOZ_NO_REMOTE': '1', 01:31:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:02 INFO - 'NO_EM_RESTART': '1', 01:31:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:02 INFO - 'PWD': '/builds/slave/test', 01:31:02 INFO - 'SHELL': '/bin/bash', 01:31:02 INFO - 'SHLVL': '1', 01:31:02 INFO - 'TERM': 'linux', 01:31:02 INFO - 'TMOUT': '86400', 01:31:02 INFO - 'USER': 'cltbld', 01:31:02 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', 01:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:02 INFO - '_': '/tools/buildbot/bin/python'} 01:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:31:02 INFO - Cleaning up... 01:31:02 INFO - Return code: 0 01:31:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:31: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')]} 01:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31: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')]} 01:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31: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', '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 0x254d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bd090>, '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 0x28bdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', '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 01:31: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:31: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 blobuploader==1.2.4 01:31:02 INFO - Using env: {'DISPLAY': ':0', 01:31:02 INFO - 'HOME': '/home/cltbld', 01:31:02 INFO - 'LANG': 'en_US.UTF-8', 01:31:02 INFO - 'LANGUAGE': 'en_US:en', 01:31:02 INFO - 'LOGNAME': 'cltbld', 01:31:02 INFO - 'MAIL': '/var/mail/cltbld', 01:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:02 INFO - 'MOZ_NO_REMOTE': '1', 01:31:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:02 INFO - 'NO_EM_RESTART': '1', 01:31:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:02 INFO - 'PWD': '/builds/slave/test', 01:31:02 INFO - 'SHELL': '/bin/bash', 01:31:02 INFO - 'SHLVL': '1', 01:31:02 INFO - 'TERM': 'linux', 01:31:02 INFO - 'TMOUT': '86400', 01:31:02 INFO - 'USER': 'cltbld', 01:31:02 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', 01:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:02 INFO - '_': '/tools/buildbot/bin/python'} 01:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:02 INFO - Downloading/unpacking blobuploader==1.2.4 01:31: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 01:31: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 01:31: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 01:31: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 01:31:03 INFO - Downloading blobuploader-1.2.4.tar.gz 01:31:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:31:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:31: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 01:31: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 01:31: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 01:31: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 01:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:31:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:31: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 01:31: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 01:31: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 01:31: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 01:31:04 INFO - Downloading docopt-0.6.1.tar.gz 01:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:31:04 INFO - Installing collected packages: blobuploader, requests, docopt 01:31:04 INFO - Running setup.py install for blobuploader 01:31:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:31:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:31:04 INFO - Running setup.py install for requests 01:31:04 INFO - Running setup.py install for docopt 01:31:04 INFO - Successfully installed blobuploader requests docopt 01:31:04 INFO - Cleaning up... 01:31:05 INFO - Return code: 0 01:31:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:05 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 0x254d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bd090>, '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 0x28bdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', '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 01:31:05 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 01:31:05 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 01:31:05 INFO - Using env: {'DISPLAY': ':0', 01:31:05 INFO - 'HOME': '/home/cltbld', 01:31:05 INFO - 'LANG': 'en_US.UTF-8', 01:31:05 INFO - 'LANGUAGE': 'en_US:en', 01:31:05 INFO - 'LOGNAME': 'cltbld', 01:31:05 INFO - 'MAIL': '/var/mail/cltbld', 01:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:05 INFO - 'MOZ_NO_REMOTE': '1', 01:31:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:05 INFO - 'NO_EM_RESTART': '1', 01:31:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:05 INFO - 'PWD': '/builds/slave/test', 01:31:05 INFO - 'SHELL': '/bin/bash', 01:31:05 INFO - 'SHLVL': '1', 01:31:05 INFO - 'TERM': 'linux', 01:31:05 INFO - 'TMOUT': '86400', 01:31:05 INFO - 'USER': 'cltbld', 01:31:05 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', 01:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:05 INFO - '_': '/tools/buildbot/bin/python'} 01:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:31:05 INFO - Running setup.py (path:/tmp/pip-EkMuXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:31:05 INFO - Running setup.py (path:/tmp/pip-NNoVUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:31:05 INFO - Running setup.py (path:/tmp/pip-RCWfjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:31:05 INFO - Running setup.py (path:/tmp/pip-WAswUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:31:05 INFO - Running setup.py (path:/tmp/pip-I7d8iL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:31:05 INFO - Running setup.py (path:/tmp/pip-xBu58O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:31:05 INFO - Running setup.py (path:/tmp/pip-cxnsVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:31:05 INFO - Running setup.py (path:/tmp/pip-FPvjna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:31:05 INFO - Running setup.py (path:/tmp/pip-_jYDqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:31:05 INFO - Running setup.py (path:/tmp/pip-4CY2eP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:31:05 INFO - Running setup.py (path:/tmp/pip-iQYRGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:31:06 INFO - Running setup.py (path:/tmp/pip-2iEwkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:31:06 INFO - Running setup.py (path:/tmp/pip-QdhqiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:31:06 INFO - Running setup.py (path:/tmp/pip-NU6QJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:31:06 INFO - Running setup.py (path:/tmp/pip-SCi64s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:31:06 INFO - Running setup.py (path:/tmp/pip-P419ux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:31:06 INFO - Running setup.py (path:/tmp/pip-zacn_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:31:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:31:06 INFO - Running setup.py install for manifestparser 01:31:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:31:06 INFO - Running setup.py install for mozcrash 01:31:06 INFO - Running setup.py install for mozdebug 01:31:06 INFO - Running setup.py install for mozdevice 01:31:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:31:06 INFO - Running setup.py install for mozfile 01:31:06 INFO - Running setup.py install for mozhttpd 01:31:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Running setup.py install for mozinfo 01:31:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Running setup.py install for mozInstall 01:31:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Running setup.py install for mozleak 01:31:07 INFO - Running setup.py install for mozlog 01:31:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Running setup.py install for moznetwork 01:31:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Running setup.py install for mozprocess 01:31:07 INFO - Running setup.py install for mozprofile 01:31:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Running setup.py install for mozrunner 01:31:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Running setup.py install for mozscreenshot 01:31:07 INFO - Running setup.py install for moztest 01:31:08 INFO - Running setup.py install for mozversion 01:31:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:31:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:31:08 INFO - Cleaning up... 01:31:08 INFO - Return code: 0 01:31:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:08 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 0x254d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bd090>, '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 0x28bdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', '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 01:31:08 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 01:31:08 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 01:31:08 INFO - Using env: {'DISPLAY': ':0', 01:31:08 INFO - 'HOME': '/home/cltbld', 01:31:08 INFO - 'LANG': 'en_US.UTF-8', 01:31:08 INFO - 'LANGUAGE': 'en_US:en', 01:31:08 INFO - 'LOGNAME': 'cltbld', 01:31:08 INFO - 'MAIL': '/var/mail/cltbld', 01:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:08 INFO - 'MOZ_NO_REMOTE': '1', 01:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:08 INFO - 'NO_EM_RESTART': '1', 01:31:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:08 INFO - 'PWD': '/builds/slave/test', 01:31:08 INFO - 'SHELL': '/bin/bash', 01:31:08 INFO - 'SHLVL': '1', 01:31:08 INFO - 'TERM': 'linux', 01:31:08 INFO - 'TMOUT': '86400', 01:31:08 INFO - 'USER': 'cltbld', 01:31:08 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', 01:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:08 INFO - '_': '/tools/buildbot/bin/python'} 01:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:31:08 INFO - Running setup.py (path:/tmp/pip-2xonVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:31:08 INFO - Running setup.py (path:/tmp/pip-7ePski-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:31:08 INFO - Running setup.py (path:/tmp/pip-aXNrJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:31:08 INFO - Running setup.py (path:/tmp/pip-7eJfX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:31:08 INFO - Running setup.py (path:/tmp/pip-snJX7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:31:08 INFO - Running setup.py (path:/tmp/pip-8d21zN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:31:08 INFO - Running setup.py (path:/tmp/pip-i2IOQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:31:08 INFO - Running setup.py (path:/tmp/pip-dh_y2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:31:08 INFO - Running setup.py (path:/tmp/pip-U1zIwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:31:09 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)) 01:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:31:09 INFO - Running setup.py (path:/tmp/pip-jqeJoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:31:09 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)) 01:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:31:09 INFO - Running setup.py (path:/tmp/pip-Wa27qi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:31:09 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)) 01:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:31:09 INFO - Running setup.py (path:/tmp/pip-ZEwz2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:31:09 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)) 01:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:31:09 INFO - Running setup.py (path:/tmp/pip-Y4lrde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:31:09 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)) 01:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:31:09 INFO - Running setup.py (path:/tmp/pip-4UXxv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:31:09 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)) 01:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:31:09 INFO - Running setup.py (path:/tmp/pip-pbSGsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:31:09 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)) 01:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:31:09 INFO - Running setup.py (path:/tmp/pip-wmxxMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:31:09 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)) 01:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:31:09 INFO - Running setup.py (path:/tmp/pip-Rkr2ku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:31:09 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)) 01:31:09 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)) 01:31:09 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)) 01:31:09 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)) 01:31:09 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)) 01:31:09 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)) 01:31:09 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)) 01:31:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:31:09 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 01:31:09 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 01:31:09 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 01:31:09 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 01:31:10 INFO - Downloading blessings-1.6.tar.gz 01:31:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:31:10 INFO - Installing collected packages: blessings 01:31:10 INFO - Running setup.py install for blessings 01:31:10 INFO - Successfully installed blessings 01:31:10 INFO - Cleaning up... 01:31:10 INFO - Return code: 0 01:31:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:31:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:31:11 INFO - Reading from file tmpfile_stdout 01:31:11 INFO - Current package versions: 01:31:11 INFO - argparse == 1.2.1 01:31:11 INFO - blessings == 1.6 01:31:11 INFO - blobuploader == 1.2.4 01:31:11 INFO - docopt == 0.6.1 01:31:11 INFO - functools32 == 3.2.3-2 01:31:11 INFO - jsonschema == 2.5.1 01:31:11 INFO - manifestparser == 1.1 01:31:11 INFO - mozInstall == 1.13 01:31:11 INFO - mozcrash == 1.0 01:31:11 INFO - mozdebug == 0.1 01:31:11 INFO - mozdevice == 0.51 01:31:11 INFO - mozfile == 1.2 01:31:11 INFO - mozhttpd == 0.7 01:31:11 INFO - mozinfo == 0.10 01:31:11 INFO - mozleak == 0.1 01:31:11 INFO - mozlog == 3.5 01:31:11 INFO - moznetwork == 0.27 01:31:11 INFO - mozprocess == 0.25 01:31:11 INFO - mozprofile == 0.28 01:31:11 INFO - mozrunner == 6.13 01:31:11 INFO - mozscreenshot == 0.1 01:31:11 INFO - mozsystemmonitor == 0.3 01:31:11 INFO - moztest == 0.8 01:31:11 INFO - mozversion == 1.4 01:31:11 INFO - psutil == 3.1.1 01:31:11 INFO - requests == 1.2.3 01:31:11 INFO - wsgiref == 0.1.2 01:31:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:11 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 0x254d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bd090>, '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 0x28bdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', '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 01:31:11 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 01:31:11 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 01:31:11 INFO - Using env: {'DISPLAY': ':0', 01:31:11 INFO - 'HOME': '/home/cltbld', 01:31:11 INFO - 'LANG': 'en_US.UTF-8', 01:31:11 INFO - 'LANGUAGE': 'en_US:en', 01:31:11 INFO - 'LOGNAME': 'cltbld', 01:31:11 INFO - 'MAIL': '/var/mail/cltbld', 01:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:11 INFO - 'MOZ_NO_REMOTE': '1', 01:31:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:11 INFO - 'NO_EM_RESTART': '1', 01:31:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:11 INFO - 'PWD': '/builds/slave/test', 01:31:11 INFO - 'SHELL': '/bin/bash', 01:31:11 INFO - 'SHLVL': '1', 01:31:11 INFO - 'TERM': 'linux', 01:31:11 INFO - 'TMOUT': '86400', 01:31:11 INFO - 'USER': 'cltbld', 01:31:11 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', 01:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:11 INFO - '_': '/tools/buildbot/bin/python'} 01:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:11 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)) 01:31:11 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)) 01:31:11 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)) 01:31:11 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)) 01:31:11 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)) 01:31:11 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)) 01:31:11 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)) 01:31:11 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)) 01:31:11 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)) 01:31:11 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 01:31: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 01:31: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 01:31: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 01:31: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 01:31:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 01:31:12 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)) 01:31:12 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)) 01:31:12 INFO - Installing collected packages: simplejson 01:31:12 INFO - Running setup.py install for simplejson 01:31:12 INFO - building 'simplejson._speedups' extension 01:31:12 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 01:31:13 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 01:31:13 INFO - Successfully installed simplejson 01:31:13 INFO - Cleaning up... 01:31:13 INFO - Return code: 0 01:31:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bd090>, '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 0x28bdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', '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 01:31:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:31:13 INFO - Using env: {'DISPLAY': ':0', 01:31:13 INFO - 'HOME': '/home/cltbld', 01:31:13 INFO - 'LANG': 'en_US.UTF-8', 01:31:13 INFO - 'LANGUAGE': 'en_US:en', 01:31:13 INFO - 'LOGNAME': 'cltbld', 01:31:13 INFO - 'MAIL': '/var/mail/cltbld', 01:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:13 INFO - 'MOZ_NO_REMOTE': '1', 01:31:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:13 INFO - 'NO_EM_RESTART': '1', 01:31:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:13 INFO - 'PWD': '/builds/slave/test', 01:31:13 INFO - 'SHELL': '/bin/bash', 01:31:13 INFO - 'SHLVL': '1', 01:31:13 INFO - 'TERM': 'linux', 01:31:13 INFO - 'TMOUT': '86400', 01:31:13 INFO - 'USER': 'cltbld', 01:31:13 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', 01:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:13 INFO - '_': '/tools/buildbot/bin/python'} 01:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:31:13 INFO - Cleaning up... 01:31:13 INFO - Return code: 0 01:31:13 INFO - Running post-action listener: _resource_record_post_action 01:31:13 INFO - Running post-action listener: _start_resource_monitoring 01:31:13 INFO - Starting resource monitoring. 01:31:13 INFO - [mozharness: 2017-09-06 08:31:13.861756Z] Finished create-virtualenv step (success) 01:31:13 INFO - [mozharness: 2017-09-06 08:31:13.862252Z] Running install step. 01:31:13 INFO - Running pre-action listener: _resource_record_pre_action 01:31:13 INFO - Running main action method: install 01:31:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:31:14 INFO - Reading from file tmpfile_stdout 01:31:14 INFO - Detecting whether we're running mozinstall >=1.0... 01:31:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:31:14 INFO - Reading from file tmpfile_stdout 01:31:14 INFO - Output received: 01:31:14 INFO - Usage: mozinstall [options] installer 01:31:14 INFO - Options: 01:31:14 INFO - -h, --help show this help message and exit 01:31:14 INFO - -d DEST, --destination=DEST 01:31:14 INFO - Directory to install application into. [default: 01:31:14 INFO - "/builds/slave/test"] 01:31:14 INFO - --app=APP Application being installed. [default: firefox] 01:31:14 INFO - mkdir: /builds/slave/test/build/application 01:31:14 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'] 01:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 01:31:30 INFO - Reading from file tmpfile_stdout 01:31:30 INFO - Output received: 01:31:30 INFO - /builds/slave/test/build/application/firefox/firefox 01:31:30 INFO - Running post-action listener: _resource_record_post_action 01:31:30 INFO - [mozharness: 2017-09-06 08:31:30.801622Z] Finished install step (success) 01:31:30 INFO - [mozharness: 2017-09-06 08:31:30.801884Z] Running setup-mitmproxy step. 01:31:30 INFO - Running pre-action listener: _resource_record_pre_action 01:31:30 INFO - Running main action method: setup_mitmproxy 01:31:30 INFO - Skipping: mitmproxy is not required 01:31:30 INFO - Running post-action listener: _resource_record_post_action 01:31:30 INFO - [mozharness: 2017-09-06 08:31:30.803004Z] Finished setup-mitmproxy step (success) 01:31:30 INFO - [mozharness: 2017-09-06 08:31:30.803223Z] Running run-tests step. 01:31:30 INFO - Running pre-action listener: _resource_record_pre_action 01:31:30 INFO - Running pre-action listener: _set_gcov_prefix 01:31:30 INFO - Running main action method: run_tests 01:31:30 WARNING - Try message not found. 01:31:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:31:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:31:30 INFO - Python 2.7.3 01:31:30 INFO - Return code: 0 01:31:30 INFO - grabbing minidump binary from tooltool 01:31:30 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')]} 01:31:30 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 0x28bd090>, '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 0x28bdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:31:30 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 01:31:30 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 01:31:30 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 01:31:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:31:30 INFO - Return code: 0 01:31:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:31:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:31:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:31:30 INFO - ENV: RUST_BACKTRACE is now full 01:31:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:31:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:31:30 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/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 01:31:30 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/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 01:31:30 INFO - Using env: {'DISPLAY': ':0', 01:31:30 INFO - 'HOME': '/home/cltbld', 01:31:30 INFO - 'LANG': 'en_US.UTF-8', 01:31:30 INFO - 'LANGUAGE': 'en_US:en', 01:31:30 INFO - 'LOGNAME': 'cltbld', 01:31:30 INFO - 'MAIL': '/var/mail/cltbld', 01:31:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:31:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:30 INFO - 'MOZ_NO_REMOTE': '1', 01:31:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:31:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:30 INFO - 'NO_EM_RESTART': '1', 01:31:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:30 INFO - 'PWD': '/builds/slave/test', 01:31:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:31:30 INFO - 'RUST_BACKTRACE': 'full', 01:31:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 01:31:30 INFO - 'SHELL': '/bin/bash', 01:31:30 INFO - 'SHLVL': '1', 01:31:30 INFO - 'TERM': 'linux', 01:31:30 INFO - 'TMOUT': '86400', 01:31:30 INFO - 'USER': 'cltbld', 01:31:30 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', 01:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:30 INFO - '_': '/tools/buildbot/bin/python'} 01:31:30 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/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 01:31:31 INFO - mozversion application_buildid: 20170906073832 01:31:31 INFO - mozversion application_changeset: b235fb79d6e017b9f47309cb06eb701c06b7e8d2 01:31:31 INFO - mozversion application_display_name: Nightly 01:31:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:31:31 INFO - mozversion application_name: Firefox 01:31:31 INFO - mozversion application_remotingname: firefox 01:31:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 01:31:31 INFO - mozversion application_vendor: Mozilla 01:31:31 INFO - mozversion application_version: 57.0a1 01:31:31 INFO - mozversion platform_buildid: 20170906073832 01:31:31 INFO - mozversion platform_changeset: b235fb79d6e017b9f47309cb06eb701c06b7e8d2 01:31:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 01:31:31 INFO - mozversion platform_version: 57.0a1 01:31:31 INFO - using testdate: 1504686691 01:31:31 INFO - actual date: 1504686691 01:31:31 INFO - starting webserver on 'localhost:45020' 01:31:31 INFO - SUITE-START | Running 2 tests 01:31:31 INFO - TEST-START | dromaeo_css 01:31:31 INFO - Initialising browser for dromaeo_css test... 01:31:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45020/getInfo.html -profile /tmp/tmpEbOFya/profile 01:31:31 INFO - TEST-INFO | started process 24692 (/builds/slave/test/build/application/firefox/firefox http://localhost:45020/getInfo.html) 01:31:36 INFO - TEST-INFO | 24692: exit 0 01:31:36 INFO - Browser initialized. 01:31:36 INFO - Running cycle 1/1 for dromaeo_css test... 01:31:36 INFO - Using env: {'DISPLAY': ':0', 01:31:36 INFO - 'HOME': '/home/cltbld', 01:31:36 INFO - 'JSGC_DISABLE_POISONING': '1', 01:31:36 INFO - 'LANG': 'en_US.UTF-8', 01:31:36 INFO - 'LANGUAGE': 'en_US:en', 01:31:36 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 01:31:36 INFO - 'LOGNAME': 'cltbld', 01:31:36 INFO - 'MAIL': '/var/mail/cltbld', 01:31:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:31:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:31:36 INFO - 'MOZ_CRASHREPORTER': '1', 01:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:36 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 01:31:36 INFO - 'MOZ_NO_REMOTE': '1', 01:31:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:31:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:36 INFO - 'NO_EM_RESTART': '1', 01:31:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:36 INFO - 'PWD': '/builds/slave/test', 01:31:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:31:36 INFO - 'RUST_BACKTRACE': 'full', 01:31:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 01:31:36 INFO - 'SHELL': '/bin/bash', 01:31:36 INFO - 'SHLVL': '1', 01:31:36 INFO - 'TERM': 'linux', 01:31:36 INFO - 'TMOUT': '86400', 01:31:36 INFO - 'USER': 'cltbld', 01:31:36 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', 01:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:36 INFO - '_': '/tools/buildbot/bin/python'} 01:31:36 INFO - TEST-INFO | started process 24874 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEbOFya/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 01:31:37 INFO - PID 24874 | 01:31:37 INFO - PID 24874 | (/builds/slave/test/build/application/firefox/firefox:24927): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 01:31:37 INFO - PID 24874 | 01:31:47 INFO - PID 24874 | RSS: Main: 170029056 01:31:47 INFO - PID 24874 | 01:34:44 INFO - PID 24874 | Cycle 1(1): loaded http://localhost:45020/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45020/tests/dromaeo/cssquery-ext.html) 01:34:45 INFO - PID 24874 | RSS: Main: 173039616 01:34:45 INFO - PID 24874 | 01:37:47 INFO - PID 24874 | Cycle 1(1): loaded http://localhost:45020/tests/dromaeo/cssquery-ext.html (next: http://localhost:45020/tests/dromaeo/cssquery-jquery.html) 01:37:47 INFO - PID 24874 | RSS: Main: 169529344 01:37:47 INFO - PID 24874 | 01:40:49 INFO - PID 24874 | Cycle 1(1): loaded http://localhost:45020/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45020/tests/dromaeo/cssquery-mootools.html) 01:40:50 INFO - PID 24874 | RSS: Main: 171843584 01:40:50 INFO - PID 24874 | 01:43:51 INFO - PID 24874 | Cycle 1(1): loaded http://localhost:45020/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45020/tests/dromaeo/cssquery-prototype.html) 01:43:52 INFO - PID 24874 | RSS: Main: 170283008 01:43:52 INFO - PID 24874 | 01:46:56 INFO - PID 24874 | Cycle 1(1): loaded http://localhost:45020/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45020/tests/dromaeo/cssquery-yui.html) 01:46:56 INFO - PID 24874 | RSS: Main: 171593728 01:46:56 INFO - PID 24874 | 01:49:58 INFO - PID 24874 | Cycle 1(1): loaded http://localhost:45020/tests/dromaeo/cssquery-yui.html (next: http://localhost:45020/tests/dromaeo/cssquery-dojo.html) 01:49:58 INFO - PID 24874 | RSS: Main: 174411776 01:49:58 INFO - PID 24874 | 01:49:58 INFO - PID 24874 | __start_tp_report 01:49:58 INFO - PID 24874 | _x_x_mozilla_page_load 01:49:58 INFO - PID 24874 | _x_x_mozilla_page_load_details 01:49:58 INFO - PID 24874 | |i|pagename|runs| 01:49:58 INFO - PID 24874 | |0;dojo.html;3251.561039259701;3282.8522716477764;3299.91750206245;3302.653221411752;3347.2803347280346;6290.905636415452;6335.556511044218;6345.873082538346;6354.777582784609;6361.300256971729;6261.060840873871;6347.36526347365;6351.364863513646;6367.554271201017;6384.521160912938;5824.796132135376;5844.649321040752;5846;5857.560682948774;5864.912026319608;5864.530837532987;5871.442212989759;5876.70616469177;5889.676067816268;5909.527237820964;5863.79476718315;5932.2584676915385;5953.970230148865;5991.221141251653;6049.274087109531;5800.796972105998;5876.97061514694;5882.411758824105;5957.821265362046;5981.58128930975;5696.088625819891;5725.856853578653;5739.196512488255;5827.912581311284;5866.14940833581;6276.372362763733;6400.199975003125;6413.486921046305;6437.098806167093;6442.098106265126;6791.320867913169;6810.863782724367;6880.58716477013;6881.518293719441;6906.792796216121;6855.5886646801355;6886.139232595926;6936.306369363023;6946.895796563055;6947.617881016542;6410.358964103599;6442.194725659293;6496.5127615428555;6499.610023398611;6501.447376972963;7064.964675176694;7065.0815393998455;7078.469114816409;7190.712371505186;7191.3887319578425;6583.835404114935;6652.634105124168;6748.763793267212;6770.1537307836525;6870.587764734132;6843.213030501556;6843.828904277433;6877.58734475923;6887.2079710832895;6891.173059232824;6680.832979175462;6688.598684078971;6700.731970721214;6780.186377634617;6789.932100679029;6777.593344399352;6786.524943254023;6821.69302381394;6839.521233513607;6902.930970690229;4977.726225057584;5033.546980771748;5050.696958182447;5052.141136006888;5068.493150684976;4392.934105988413;4432.512423633398;4443.644508439317;4454.888627784331;4497.415336006298;5864.944310024237;6010.909836352371;6028.879422411527;6035.517158627299;6073.423024812636;6764.255931847493;6768;6776.220734615484;6780.898286525706;6783.559068660521;5875.559333050039;5896.705164741746;5899.351071382144;5911.497522710618;5929.851753706192;5905.557083218776;5917.437843404869;5941.405859414111;5950.31571369298;5961.463468287875;6468.256150542654;6552.3447655234095;6640.402363787282;6660;6731.259561448237;6705.430038446787;6843.384095431236;6929.93070069313;6934.549254298556;6944.375006249462;6491.578047426902;6563.113979612882;6640.867182656417;6648.767293144717;6655.966720166368;6790.72837086511;6833.897491537531;6836.453083753188;6852.349026842442;6853.520253582202;6942.166939967236;6945.236024037251;6967.372936435745;7006.404455621126;7036.577805331696;6451.838704032437;6557.770478033246;6630.336966303331;6678.599284042973;6744.5616034957575;6620.2386725527185;6621.437177839937;6633.038209459698;6639.2696803352555;6702.698378573074;6573.276939536551;6607;6642.236142843635;6644.036614690939;6647.235567909753;6694.765683201064;6701.262861085374;6708.631025293461;6729.596224226562;6821.386075253251;6345.619262844244;6454.774082907075;6482.675866206765;6487.156669632917;6589.374009469093 01:49:58 INFO - PID 24874 | |1;ext.html;738.3207449146785;774.5933384972889;788.1015642167926;797.6809276289483;1106.8007758603449;30682;31219.68780312196;31238.68761312389;40122.799386003026;41183.58816411835;42419.363709544385;42748.78625606876;42859.57140428595;42901;42923.78538107313;7292.635368231594;7701.730439434635;7875.645595948167;7876.448648594601;7891.644875980566;7737.845243095163;7847.1368149503505;7849.8037549061155;7852.450328476981;7853.096893857194;7770.456068075208;7832.099308579529;7851.254130857588;7905.525668459911;7912.881306780403;7807.609619519001;7815.491993020464;7830.530168189898;7862.567558784264;7870.685172593061;7681.731139410093;7722.42081843864;7728.613569321511;7750;7826.647800848976;37674.43488347677;37790.24419511622;37828.81085594554;37911;37915;3486.267883744417;3509.385857474957;3513.1041584395816;3514.209302906851;3516.841742121585;31660.525092123637;32105.51841722376;32120.518192227137;32155.678443215504;32172;5221.451747566489;5235;5236.7119808410525;5238.926020165857;5265.710385928773;185700.07149964297;185746;185854.07072964683;185928;186402;48107.75946120247;48253;48324;48352.5164748355;48425.757871210415;50711.746441267554;50944.23583646248;50951.49048509468;50991;51277.7436112817;13981.930090349686;14222.644433889029;14243.501477448239;14264.144151350953;14295.428182872776;10565.366078035342;10569.260151789302;11299.49152288149;11397.601083962187;11403.63156421218;14339.354729037219;14361.64095897611;14382.208978506073;14506.782398264035;14568.05307654999;5571.247881536022;5758.819442014377;5833.79581714638;5877.735501902424;5952.285725712942;990.4700984973005;991.9355241909253;1016.6645007147623;1023.8157131716214;1027.4759872464947;22304.219352322914;22329;22350.888245558992;22644.547109057727;23082.422939426313;1096.5668560918552;1103.0844399148687;1111.8721347045193;1114.403793970222;1119.1997721629075;11756.294622322688;12007.459664315127;12035.856593623592;12046.33745144008;12079.335636540074;226497;226863;226959;229035;229629;51910.48089519132;51931.48068519342;52080.21879671808;52344;53065;1414.8585141485976;1435.4904009076836;1451.151076620189;1451.267110109409;1452.2811208451883;22413.327600172022;22511.662325064808;22566.323010309716;22573.32280031602;22832.543349132593;37193.070173245884;37334.62665373312;37340.25319493649;37542.24915501729;37613.81193094017;194239;194789;194947;195380;195869;30923.226919327197;31157.376852463276;31371.529427059068;33365.33269334551;33607.83196084004;42433.15133697371;43034.56965430306;43036.35445468258;43097.569024309356;43230.567694323916;30495.237619059702;30691.232719181306;30798.84600576983;30966.53550196704;30982.380352392374;30808.69191308148;30968;31359;31643.84178079095;31882.202944925633;35599.82200089087;35947;37841;37960.240795184494;37998.810005949796;31705.524417134224;31951.041468755975;32047.198820028752;32157.678423215468;33093.50359744654;23442.64836027495;23661.881690592127;23911.402214944766;23925.88037059873;24320 01:49:58 INFO - PID 24874 | |2;jquery.html;560.271646859083;562.9690367029812;567.6423852972626;570.7973669347383;577.3793172339734;21756.238531651394;22576.774232257674;22802.54394912101;22806.88596557017;22851.885740571295;23944.40138996526;26361.209163725118;26502.86748566259;26553.203403897867;26569.202923912315;6747;6830.760923367683;6905.274946130659;6908.240093589701;6909.928961011026;6014;6578.901316480233;6611.074449577039;6741.3258674132685;6798.796036118924;6562.671866406685;6565.901511477332;6566.63883486408;6569.474442044625;6584.637844918528;6574.276829548746;6577.835554111138;6581.045748366484;6594.648097140075;6597.1423714916755;6055.273367195965;6061.878762424771;6062.999605065159;6117.051856962178;6130.724117414727;28018.85990570034;28632.284192895135;28775.85612071926;28957;29315.560266596018;2070.430631576329;2087.676410156413;2089.540301133748;2090.1639344262267;2092.9476763080893;19336.516587085294;19872.105755240904;19883.30408435712;19884.508661850417;19911;2383.7377888432256;2397.9520409591887;2438.829281950264;2441.084593277521;2445.9755402445926;138232;139791;141685;141998;142541.28729356392;14467.855321446861;14470.20413877247;14504.492342768164;14716.632084197767;15510.069395836286;23431.648525272136;23435.531289374117;23460.178893738637;23471.530569388517;23507.647385289234;9200.355975081813;9409.576569054272;9456.479893605918;9459.574319155654;9626.807463850684;8413.074561798197;8451.873221901677;8563.357748168912;8573.657053717907;8717.346199034972;7723.497972631873;8937.374383793072;9125.406848554823;9131.145342281408;9161;2593.4164812916943;2646.625552420679;2713.3894873653667;2734.494118588028;2766.7094955029434;946.6308139825474;960.2174228004154;963.2403164932558;969.8836139663144;976.5312649928075;19118.426447206606;19211.32760353409;19430;19567.902160489106;19747.901260493894;1021.6985989133224;1027.7173777211326;1027.8715160604925;1030.299396410433;1033.8552602635637;10699.518521666543;10702.625408110678;10780.676579702622;10841.891581084088;11057.336559806437;444978;449311;450842;481888;482306;116069;121247;122468.38765806114;122550;124442;1182.8284898689642;1207.3721664734464;1230.2864338683542;1230.4708975140775;1231.852177738677;20542.483237178632;20597;20676.483087922923;20692.68960965617;21015.57968840645;56279.718601406734;56612;57159.714201428724;57476.13785793133;59316.110258345296;70824;71915.28084719086;71935.64032179806;72187.27812721806;72294.63852680914;11433.485493152995;11694.239874408137;12535.498580056696;13846.93076534611;14281.928590356982;22660.206892758673;22693;22734.431639209153;22753.886230569402;22813.657795132753;17983.910080449514;18141.818581814012;18230.908845455688;18244.361447349278;18343.082845857385;16450.341986320414;16552.917235413744;17165.914170429067;17240.65518689644;17447.127643617514;18645.81354186441;18820.341288054853;18845.528861778017;18847.528811779815;19131.61736765285;13997.230152341719;14084.436622534984;14088.15471071739;14115.858841411455;14237.932155088203;18005.2797888083;18090.185941632393;18318.90840545789;18413.171407286973;18513.166907489463 01:49:58 INFO - PID 24874 | |3;mootools.html;1303.6349822049826;1321.6960099177188;1345.5492410042634;1351.4121357209622;1356.138851829089;14716.779248311284;15881.920590397061;15893.68212635748;15893.920530397347;15912.920435397837;15598.454054108108;15638.296276667548;15826.129562874037;15894;15937.282822272997;2646.973530264697;2660.494506043855;2669.7330266973245;2672.1182009936674;2676.1703871799805;2543.173468622705;2590.3265151060673;2616.1628278950743;2617.41108250644;2618.6203000564906;2574.3692795265188;2574.6395504629368;2598.7791037761813;2605.7524535169127;2614.4901744159897;2475.220305603738;2494.2641920633455;2496.188738659943;2496.226169887341;2499.962500562493;2457.619069044301;2478.6405971134263;2491.2650768023477;2491.7134529529153;2501.062101711858;14902.627434314229;14981.250937453193;15046.699066018728;15048.623784405368;15050.548483545512;7860.6069696515515;8178.591070446513;8182.099969003405;8182.345412366996;8196.590170491452;5404.891902161935;5502.202180683778;5504;5627.6904770237625;5629.802956896489;1903.961920761577;1935.4290484307164;1939.4278687787137;1948.1720268885667;1961.1469010980127;26051.609225861626;26284.73715262842;26287.60568591473;26295.21114366553;26343.736562634513;6396.264429590564;6437.485001199892;6571.408573228432;6709.463242940553;6896.965515172392;16247.675046499004;16409.343626254817;16442.75335869963;16553.08958007297;16568.917155414387;7688.961555192188;7841.098273698483;7861.292483676497;7880.290773830383;7900.920990790133;5716.085426331767;6021.397860214031;6179;7190.316919892602;7200.819979500555;5627.409122042127;5787.565932555075;5804.9419505805245;5813.79651712188;5902.527797776168;1650.8761842861834;1656.2629629814858;1683.276191237755;1699.3032856528764;1706.709859323918;893.097971049245;906.9637214511478;917.8485549884327;922.7739203894954;926.5737760630051;7787.688492460352;7787.922120778833;7836.294733473901;7837.412194085467;7917.604119793988;704.1620471638734;708.7094291340526;711.8647456983157;723.7213672736035;726.8800647893144;4812.06164797868;5021.20407237789;5364.3830959440165;5527.50252477279;5635.549156067505;20360.796392036187;20528.794712052986;20873.582528349347;21502.677459838116;21539.353819385444;20678.655887367575;21115.155393784076;21126.366209013755;21433.356999290045;21500.354989350344;11187.4965626547;11270.211085223955;11331.886681133248;11346.886531134747;11562.595309164139;8160.26557609818;8292.585370731318;8408.789780255542;8412.116727743498;8455.8731619027;18182.72725909139;18878.905605471886;18931.43205703831;19033.90483047576;19242.903785481543;18373.632527349644;18904.43286701346;19005.239790408228;19029.429117126507;19150.23399064022;12131.45408456604;12202.267863928193;12266.938665306974;12268.202566833128;12279.079069070034;13704.725905482033;13864.098833575785;13876.444942202199;13878.514252001132;13888.72222555523;11215.49530271156;11251.831222531504;11303.830442543203;11373.886261137282;11425.657230283105;10749.408782517037;10759.30064545802;10870.293430926964;10876.18428617841;10991.230613857273;9925.751856203653;10107.444090575089;10111.747206319607;10114.898851011396;10127.189824814144;7358.638651849425;7568.318851303409;7578.204288549623;7662.0422447194105;7681.116671582616;7632.961835190788;7640.656170472231;7760;7778.183290754389;7861.095973962839 01:49:58 INFO - PID 24874 | |4;prototype.html;498.32974649096974;504.88672477187794;524.4440892653787;530.7611574791345;532.177785321678;6255.311915689276;6328.740698540244;6421.518386121042;6446.452051575622;6471.967640161798;6042.607230530023;6118.877622447549;6186.505079593633;6221.129041934133;6224.906626400608;5042.84871453857;5055.266986287004;5058.367704036996;5065.670731402465;5072.771725272353;4912.803487860481;5006.399232092154;5009.398872135331;5018.899622007558;5027.547520723134;4998.650094493387;5013.949860501385;5019;5022.221555658879;5036.84889453317;4943.258511223327;4962.3548938638105;4983.377077865267;5010.799568017275;5030.170021946375;4415.536368681274;4469.25698977399;4469.284914413678;4493.191225579394;4501.549845015505;6154.799814036265;6206.937930620681;6221.129041934133;6223.875522489525;6228.937710622927;5691;5757.1652110444165;5761.395053519362;5762.855928601776;5767.480926716616;6598.439132673729;6600.108985286973;6603.306652801435;6607.240167380764;6623.403893649546;4899.755012249373;4923.901521969577;4935.7532123393685;4941.530554597343;4960.727160006199;6907.896381554281;6949.930500694979;6974.825629359256;7030.578165310098;7079.29207079288;5006;5013.49865013503;5021.51865199767;5022.573081288058;5044.596432285409;5096.47105868238;5115.18157094863;5134.820281290212;5135.589152867761;5139.331886854685;5718.199452076713;5741.311042674905;5754.309482862;5760.452756988079;5765.88468230633;6546.279909209983;6577.86844263112;6633.867322653617;6669;6675;1638.868890488782;1673.8577220936113;1692.280780668211;1740.7736994190927;1752.772139621841;1177.7232350397649;1225.197495640357;1232.4084439469104;1235;1247.812828075796;5571.582131340166;5722;5736.68448235351;5768.596198266081;5809.3609702932645;6516.837079073061;6600.537962342686;6632.767853125214;6770.966145169243;6825.146856642919;5786.942130578725;5814.883702326015;5875.9118613220835;5928.525717942554;5961.731722072517;3506.457158850149;3547.219611685425;3550.343186510504;3554.9157506960623;3573;6365.4989755634215;6451.1936007999;6479.449246814073;6486.6756662167645;6497.740090396331;6759.459243260528;6826.351496607815;6867.759628412981;6891.655417229119;6910.516263861481;6335.524835637438;6418.19772528434;6440.967795160994;6484.773032943825;6493.155889734304;6613.470922326106;6670.499712521483;6674.766383176566;6715.395614394728;6741.494387920828;6579.276279609335;6598.109255250672;6671.39957403836;6698.866022679422;6702.83242918931;6443.613383197023;6458.644774537445;6490.415862572391;6564.310747371457;6564.770233041821;4864.586510146677;4924.975375123245;4928.67963582366;4930.580900623487;4952.702837829742;6631.900521492278;6734.191896972395;6755.831104222237;6785.59286442815;6827.317268273133;6376.298607153302;6515.543911926309;6534.183227096613;6581.572197807127;6588.9361446925495;6284.3087260402235;6375.521835862236;6497.480201583957;6527;6529.314421985623;6255.811395834777;6334.968325158345;6387.840303992437;6419.550631455753;6436.678166091583;6548.934510655023;6550.901736473861;6568.934310657023;6579.289384759901;6688.966555167193;6289.874202516015;6359.682015899094;6401.207661854688;6410.775622853178;6441.584405847807 01:49:58 INFO - PID 24874 | |5;yui.html;824.6907409721358;829.4359835311985;841.4866931171985;900.842352588297;916.6791622931972;18465.7230141548;19290.710639340403;19296.324628638;19316.71024934627;21536.784632153674;15901.045937243773;16053.197340132976;18094.819051809478;21230.25694100707;21539.138434462642;946.4841661294611;965.1265604627788;970.5341436110671;975.1029053270972;976.3312131190122;898.5327629632587;981.4454833019357;990.7721224118457;992.0476342711006;992.5285489392517;1027.2603725317758;1028.9022542858415;1030.8711411073616;1031.520343040486;1031.6079889641926;1028.6399760083948;1029.5469993202967;1029.6447725534679;1030.206740809576;1032.0866033560317;753.7851712262034;753.8002429356225;755.0674916478146;755.3768141283463;760.9391248700146;19242.23031078769;19702.507437314038;19898.80101198984;19916.50208744793;20173.697394539093;20510.282140125026;20636.17455301786;20788.272410465714;20866.165353385848;21051.473713157295;16487.422940197037;16971.490855274238;16975.575610609834;17121.31514739409;17362.565935851577;3722;3749.887503374903;3752.418375151856;3763.7741735495956;3765.0775559987705;118858;119343.40328298302;119384;120710.77512287028;122156;15605.921970390076;15847.762283565755;16003.27985240667;16044.358225670843;16169.353225870833;28920.27699307492;29833.25416864596;30051.849740751157;30138.794448222263;30139;8808.559572021373;8900.332475064397;8904.688335908211;8909.109089091038;8932.821343573221;4337.9132417351475;4425.114977004612;4459.487158976694;4475.239209334421;4482.574155455227;5154.484551544815;5228;5236.266922630834;5262.736863156827;5290.444503327172;930.9813803724023;952.8951815300311;967.052288757008;973.1387721866199;973.8052389521982;685.4619123987745;700.831800367908;703.085988215318;707.8265824872939;715.9105111861018;11701;11813.900697922294;12083.395830208454;12877.935610322076;12924.22454652705;683.1494788987742;706.9328413800681;711.5659447736876;716.6309350684402;730.3536370312312;5170.560502357342;5354.678719276856;5428.348598168245;5702.800401985911;5759.65442073477;105485;110313.44843275732;113193.4340328293;115008;117742;96430.51784741032;96487;98803.50598246962;98880;99562.5021874886;85538.57230713931;87184.56407717921;87684.12315876887;88642;89181.55409222913;11671.88328116708;11707.180497365103;11961.700957476132;12872.292023938773;13051.869481305066;40844.387334189414;41395;41457.58542414537;42085.78957105195;42999.57000429956;53902.73048634888;54008.18987715109;54432;54445;55466;17829.73255401196;18210.904146295426;18220.45338639843;18296;18496.630067398844;20333.288334908208;20663.17347306091;20682.379528614165;20759.58480830405;21009.57980840344;18449;18543.721844172618;18579.44261672152;18629.06854657289;18685.81314186895;17656.73514897303;17665.82334176642;17692.557686057953;17721.556961076076;17789.644207116045;18051.458456246335;18238.088095594903;18245.908770456062;18295.908520457313;18468.353607623667;13377.799333010207;13439.865601343861;13487.527936522174;13667.79498307546;13833.584992450242;13749.931250343685;14599.489017884323;14862.993495877918;14972.850271497146;15116.924415377853 01:49:58 INFO - PID 24874 | __end_tp_report 01:49:58 INFO - PID 24874 | __start_cc_report 01:49:58 INFO - PID 24874 | _x_x_mozilla_cycle_collect,374 01:49:58 INFO - PID 24874 | __end_cc_report 01:49:58 INFO - PID 24874 | __startTimestamp1504687798591__endTimestamp 01:49:58 INFO - PID 24874 | 01:49:58 INFO - PID 24874 | ------- Summary: start ------- 01:49:58 INFO - PID 24874 | Number of tests: 6 01:49:58 INFO - PID 24874 | 01:49:58 INFO - PID 24874 | [#0] dojo.html Cycles:165 Average:6282.03 Median:6552.34 stddev:777.64 (11.9%) stddev-sans-first:742.80 01:49:58 INFO - PID 24874 | Values: 3251.6 3282.9 3299.9 3302.7 3347.3 6290.9 6335.6 6345.9 6354.8 6361.3 6261.1 6347.4 6351.4 6367.6 6384.5 5824.8 5844.6 5846.0 5857.6 5864.9 5864.5 5871.4 5876.7 5889.7 5909.5 5863.8 5932.3 5954.0 5991.2 6049.3 5800.8 5877.0 5882.4 5957.8 5981.6 5696.1 5725.9 5739.2 5827.9 5866.1 6276.4 6400.2 6413.5 6437.1 6442.1 6791.3 6810.9 6880.6 6881.5 6906.8 6855.6 6886.1 6936.3 6946.9 6947.6 6410.4 6442.2 6496.5 6499.6 6501.4 7065.0 7065.1 7078.5 7190.7 7191.4 6583.8 6652.6 6748.8 6770.2 6870.6 6843.2 6843.8 6877.6 6887.2 6891.2 6680.8 6688.6 6700.7 6780.2 6789.9 6777.6 6786.5 6821.7 6839.5 6902.9 4977.7 5033.5 5050.7 5052.1 5068.5 4392.9 4432.5 4443.6 4454.9 4497.4 5864.9 6010.9 6028.9 6035.5 6073.4 6764.3 6768.0 6776.2 6780.9 6783.6 5875.6 5896.7 5899.4 5911.5 5929.9 5905.6 5917.4 5941.4 5950.3 5961.5 6468.3 6552.3 6640.4 6660.0 6731.3 6705.4 6843.4 6929.9 6934.5 6944.4 6491.6 6563.1 6640.9 6648.8 6656.0 6790.7 6833.9 6836.5 6852.3 6853.5 6942.2 6945.2 6967.4 7006.4 7036.6 6451.8 6557.8 6630.3 6678.6 6744.6 6620.2 6621.4 6633.0 6639.3 6702.7 6573.3 6607.0 6642.2 6644.0 6647.2 6694.8 6701.3 6708.6 6729.6 6821.4 6345.6 6454.8 6482.7 6487.2 6589.4 01:49:58 INFO - PID 24874 | 01:49:58 INFO - PID 24874 | [#1] ext.html Cycles:180 Average:36951.12 Median:33486.58 stddev:52799.43 (157.7%) stddev-sans-first:52877.53 01:49:58 INFO - PID 24874 | Values: 738.3 774.6 788.1 797.7 1106.8 30682.0 31219.7 31238.7 40122.8 41183.6 42419.4 42748.8 42859.6 42901.0 42923.8 7292.6 7701.7 7875.6 7876.4 7891.6 7737.8 7847.1 7849.8 7852.5 7853.1 7770.5 7832.1 7851.3 7905.5 7912.9 7807.6 7815.5 7830.5 7862.6 7870.7 7681.7 7722.4 7728.6 7750.0 7826.6 37674.4 37790.2 37828.8 37911.0 37915.0 3486.3 3509.4 3513.1 3514.2 3516.8 31660.5 32105.5 32120.5 32155.7 32172.0 5221.5 5235.0 5236.7 5238.9 5265.7 185700.1 185746.0 185854.1 185928.0 186402.0 48107.8 48253.0 48324.0 48352.5 48425.8 50711.7 50944.2 50951.5 50991.0 51277.7 13981.9 14222.6 14243.5 14264.1 14295.4 10565.4 10569.3 11299.5 11397.6 11403.6 14339.4 14361.6 14382.2 14506.8 14568.1 5571.2 5758.8 5833.8 5877.7 5952.3 990.5 991.9 1016.7 1023.8 1027.5 22304.2 22329.0 22350.9 22644.5 23082.4 1096.6 1103.1 1111.9 1114.4 1119.2 11756.3 12007.5 12035.9 12046.3 12079.3 226497.0 226863.0 226959.0 229035.0 229629.0 51910.5 51931.5 52080.2 52344.0 53065.0 1414.9 1435.5 1451.2 1451.3 1452.3 22413.3 22511.7 22566.3 22573.3 22832.5 37193.1 37334.6 37340.3 37542.2 37613.8 194239.0 194789.0 194947.0 195380.0 195869.0 30923.2 31157.4 31371.5 33365.3 33607.8 42433.2 43034.6 43036.4 43097.6 43230.6 30495.2 30691.2 30798.8 30966.5 30982.4 30808.7 30968.0 31359.0 31643.8 31882.2 35599.8 35947.0 37841.0 37960.2 37998.8 31705.5 31951.0 32047.2 32157.7 33093.5 23442.6 23661.9 23911.4 23925.9 24320.0 01:49:58 INFO - PID 24874 | 01:49:58 INFO - PID 24874 | [#2] jquery.html Cycles:180 Average:34215.52 Median:23433.59 stddev:78611.44 (335.5%) stddev-sans-first:78791.36 01:49:58 INFO - PID 24874 | Values: 560.3 563.0 567.6 570.8 577.4 21756.2 22576.8 22802.5 22806.9 22851.9 23944.4 26361.2 26502.9 26553.2 26569.2 6747.0 6830.8 6905.3 6908.2 6909.9 6014.0 6578.9 6611.1 6741.3 6798.8 6562.7 6565.9 6566.6 6569.5 6584.6 6574.3 6577.8 6581.0 6594.6 6597.1 6055.3 6061.9 6063.0 6117.1 6130.7 28018.9 28632.3 28775.9 28957.0 29315.6 2070.4 2087.7 2089.5 2090.2 2092.9 19336.5 19872.1 19883.3 19884.5 19911.0 2383.7 2398.0 2438.8 2441.1 2446.0 138232.0 139791.0 141685.0 141998.0 142541.3 14467.9 14470.2 14504.5 14716.6 15510.1 23431.6 23435.5 23460.2 23471.5 23507.6 9200.4 9409.6 9456.5 9459.6 9626.8 8413.1 8451.9 8563.4 8573.7 8717.3 7723.5 8937.4 9125.4 9131.1 9161.0 2593.4 2646.6 2713.4 2734.5 2766.7 946.6 960.2 963.2 969.9 976.5 19118.4 19211.3 19430.0 19567.9 19747.9 1021.7 1027.7 1027.9 1030.3 1033.9 10699.5 10702.6 10780.7 10841.9 11057.3 444978.0 449311.0 450842.0 481888.0 482306.0 116069.0 121247.0 122468.4 122550.0 124442.0 1182.8 1207.4 1230.3 1230.5 1231.9 20542.5 20597.0 20676.5 20692.7 21015.6 56279.7 56612.0 57159.7 57476.1 59316.1 70824.0 71915.3 71935.6 72187.3 72294.6 11433.5 11694.2 12535.5 13846.9 14281.9 22660.2 22693.0 22734.4 22753.9 22813.7 17983.9 18141.8 18230.9 18244.4 18343.1 16450.3 16552.9 17165.9 17240.7 17447.1 18645.8 18820.3 18845.5 18847.5 19131.6 13997.2 14084.4 14088.2 14115.9 14237.9 18005.3 18090.2 18318.9 18413.2 18513.2 01:49:58 INFO - PID 24874 | 01:49:58 INFO - PID 24874 | [#3] mootools.html Cycles:180 Average:9310.59 Median:2496.21 stddev:6626.64 (265.5%) stddev-sans-first:6617.92 01:49:58 INFO - PID 24874 | Values: 1303.6 1321.7 1345.5 1351.4 1356.1 14716.8 15881.9 15893.7 15893.9 15912.9 15598.5 15638.3 15826.1 15894.0 15937.3 2647.0 2660.5 2669.7 2672.1 2676.2 2543.2 2590.3 2616.2 2617.4 2618.6 2574.4 2574.6 2598.8 2605.8 2614.5 2475.2 2494.3 2496.2 2496.2 2500.0 2457.6 2478.6 2491.3 2491.7 2501.1 14902.6 14981.3 15046.7 15048.6 15050.5 7860.6 8178.6 8182.1 8182.3 8196.6 5404.9 5502.2 5504.0 5627.7 5629.8 1904.0 1935.4 1939.4 1948.2 1961.1 26051.6 26284.7 26287.6 26295.2 26343.7 6396.3 6437.5 6571.4 6709.5 6897.0 16247.7 16409.3 16442.8 16553.1 16568.9 7689.0 7841.1 7861.3 7880.3 7900.9 5716.1 6021.4 6179.0 7190.3 7200.8 5627.4 5787.6 5804.9 5813.8 5902.5 1650.9 1656.3 1683.3 1699.3 1706.7 893.1 907.0 917.8 922.8 926.6 7787.7 7787.9 7836.3 7837.4 7917.6 704.2 708.7 711.9 723.7 726.9 4812.1 5021.2 5364.4 5527.5 5635.5 20360.8 20528.8 20873.6 21502.7 21539.4 20678.7 21115.2 21126.4 21433.4 21500.4 11187.5 11270.2 11331.9 11346.9 11562.6 8160.3 8292.6 8408.8 8412.1 8455.9 18182.7 18878.9 18931.4 19033.9 19242.9 18373.6 18904.4 19005.2 19029.4 19150.2 12131.5 12202.3 12266.9 12268.2 12279.1 13704.7 13864.1 13876.4 13878.5 13888.7 11215.5 11251.8 11303.8 11373.9 11425.7 10749.4 10759.3 10870.3 10876.2 10991.2 9925.8 10107.4 10111.7 10114.9 10127.2 7358.6 7568.3 7578.2 7662.0 7681.1 7633.0 7640.7 7760.0 7778.2 7861.1 01:49:58 INFO - PID 24874 | 01:49:58 INFO - PID 24874 | [#4] prototype.html Cycles:180 Average:5524.01 Median:6221.13 stddev:1550.86 (24.9%) stddev-sans-first:1508.63 01:49:58 INFO - PID 24874 | Values: 498.3 504.9 524.4 530.8 532.2 6255.3 6328.7 6421.5 6446.5 6472.0 6042.6 6118.9 6186.5 6221.1 6224.9 5042.8 5055.3 5058.4 5065.7 5072.8 4912.8 5006.4 5009.4 5018.9 5027.5 4998.7 5013.9 5019.0 5022.2 5036.8 4943.3 4962.4 4983.4 5010.8 5030.2 4415.5 4469.3 4469.3 4493.2 4501.5 6154.8 6206.9 6221.1 6223.9 6228.9 5691.0 5757.2 5761.4 5762.9 5767.5 6598.4 6600.1 6603.3 6607.2 6623.4 4899.8 4923.9 4935.8 4941.5 4960.7 6907.9 6949.9 6974.8 7030.6 7079.3 5006.0 5013.5 5021.5 5022.6 5044.6 5096.5 5115.2 5134.8 5135.6 5139.3 5718.2 5741.3 5754.3 5760.5 5765.9 6546.3 6577.9 6633.9 6669.0 6675.0 1638.9 1673.9 1692.3 1740.8 1752.8 1177.7 1225.2 1232.4 1235.0 1247.8 5571.6 5722.0 5736.7 5768.6 5809.4 6516.8 6600.5 6632.8 6771.0 6825.1 5786.9 5814.9 5875.9 5928.5 5961.7 3506.5 3547.2 3550.3 3554.9 3573.0 6365.5 6451.2 6479.4 6486.7 6497.7 6759.5 6826.4 6867.8 6891.7 6910.5 6335.5 6418.2 6441.0 6484.8 6493.2 6613.5 6670.5 6674.8 6715.4 6741.5 6579.3 6598.1 6671.4 6698.9 6702.8 6443.6 6458.6 6490.4 6564.3 6564.8 4864.6 4925.0 4928.7 4930.6 4952.7 6631.9 6734.2 6755.8 6785.6 6827.3 6376.3 6515.5 6534.2 6581.6 6588.9 6284.3 6375.5 6497.5 6527.0 6529.3 6255.8 6335.0 6387.8 6419.6 6436.7 6548.9 6550.9 6568.9 6579.3 6689.0 6289.9 6359.7 6401.2 6410.8 6441.6 01:49:58 INFO - PID 24874 | 01:49:58 INFO - PID 24874 | [#5] yui.html Cycles:180 Average:23270.64 Median:21030.53 stddev:31353.49 (149.1%) stddev-sans-first:31396.14 01:49:58 INFO - PID 24874 | Values: 824.7 829.4 841.5 900.8 916.7 18465.7 19290.7 19296.3 19316.7 21536.8 15901.0 16053.2 18094.8 21230.3 21539.1 946.5 965.1 970.5 975.1 976.3 898.5 981.4 990.8 992.0 992.5 1027.3 1028.9 1030.9 1031.5 1031.6 1028.6 1029.5 1029.6 1030.2 1032.1 753.8 753.8 755.1 755.4 760.9 19242.2 19702.5 19898.8 19916.5 20173.7 20510.3 20636.2 20788.3 20866.2 21051.5 16487.4 16971.5 16975.6 17121.3 17362.6 3722.0 3749.9 3752.4 3763.8 3765.1 118858.0 119343.4 119384.0 120710.8 122156.0 15605.9 15847.8 16003.3 16044.4 16169.4 28920.3 29833.3 30051.8 30138.8 30139.0 8808.6 8900.3 8904.7 8909.1 8932.8 4337.9 4425.1 4459.5 4475.2 4482.6 5154.5 5228.0 5236.3 5262.7 5290.4 931.0 952.9 967.1 973.1 973.8 685.5 700.8 703.1 707.8 715.9 11701.0 11813.9 12083.4 12877.9 12924.2 683.1 706.9 711.6 716.6 730.4 5170.6 5354.7 5428.3 5702.8 5759.7 105485.0 110313.4 113193.4 115008.0 117742.0 96430.5 96487.0 98803.5 98880.0 99562.5 85538.6 87184.6 87684.1 88642.0 89181.6 11671.9 11707.2 11961.7 12872.3 13051.9 40844.4 41395.0 41457.6 42085.8 42999.6 53902.7 54008.2 54432.0 54445.0 55466.0 17829.7 18210.9 18220.5 18296.0 18496.6 20333.3 20663.2 20682.4 20759.6 21009.6 18449.0 18543.7 18579.4 18629.1 18685.8 17656.7 17665.8 17692.6 17721.6 17789.6 18051.5 18238.1 18245.9 18295.9 18468.4 13377.8 13439.9 13487.5 13667.8 13833.6 13749.9 14599.5 14863.0 14972.9 15116.9 01:49:58 INFO - PID 24874 | -------- Summary: end -------- 01:49:58 INFO - PID 24874 | 01:49:59 INFO - TEST-INFO | 24874: exit 0 01:49:59 INFO - TEST-OK | dromaeo_css | took 1108170ms 01:49:59 INFO - TEST-START | kraken 01:49:59 INFO - Initialising browser for kraken test... 01:49:59 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45020/getInfo.html -profile /tmp/tmpKFeje3/profile 01:49:59 INFO - TEST-INFO | started process 25249 (/builds/slave/test/build/application/firefox/firefox http://localhost:45020/getInfo.html) 01:50:04 INFO - TEST-INFO | 25249: exit 0 01:50:04 INFO - Browser initialized. 01:50:04 INFO - Running cycle 1/1 for kraken test... 01:50:04 INFO - Using env: {'DISPLAY': ':0', 01:50:04 INFO - 'HOME': '/home/cltbld', 01:50:04 INFO - 'JSGC_DISABLE_POISONING': '1', 01:50:04 INFO - 'LANG': 'en_US.UTF-8', 01:50:04 INFO - 'LANGUAGE': 'en_US:en', 01:50:04 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 01:50:04 INFO - 'LOGNAME': 'cltbld', 01:50:04 INFO - 'MAIL': '/var/mail/cltbld', 01:50:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:50:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:50:04 INFO - 'MOZ_CRASHREPORTER': '1', 01:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:04 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 01:50:04 INFO - 'MOZ_NO_REMOTE': '1', 01:50:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:50:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:04 INFO - 'NO_EM_RESTART': '1', 01:50:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:50:04 INFO - 'PWD': '/builds/slave/test', 01:50:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:50:04 INFO - 'RUST_BACKTRACE': 'full', 01:50:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 01:50:04 INFO - 'SHELL': '/bin/bash', 01:50:04 INFO - 'SHLVL': '1', 01:50:04 INFO - 'TERM': 'linux', 01:50:04 INFO - 'TMOUT': '86400', 01:50:04 INFO - 'USER': 'cltbld', 01:50:04 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873', 01:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:04 INFO - '_': '/tools/buildbot/bin/python'} 01:50:04 INFO - TEST-INFO | started process 25434 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKFeje3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 01:50:05 INFO - PID 25434 | 01:50:05 INFO - PID 25434 | (/builds/slave/test/build/application/firefox/firefox:25486): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 01:50:05 INFO - PID 25434 | 01:50:15 INFO - PID 25434 | RSS: Main: 169615360 01:50:15 INFO - PID 25434 | 01:50:18 INFO - PID 25434 | Cycle 1(1): loaded http://localhost:45020/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45020/tests/kraken/driver.html?testName=audio-beat-detection) 01:50:19 INFO - PID 25434 | RSS: Main: 176046080 01:50:19 INFO - PID 25434 | 01:50:22 INFO - PID 25434 | Cycle 1(1): loaded http://localhost:45020/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45020/tests/kraken/driver.html?testName=audio-dft) 01:50:22 INFO - PID 25434 | RSS: Main: 180244480 01:50:22 INFO - PID 25434 | 01:50:25 INFO - PID 25434 | Cycle 1(1): loaded http://localhost:45020/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45020/tests/kraken/driver.html?testName=audio-fft) 01:50:26 INFO - PID 25434 | RSS: Main: 173039616 01:50:26 INFO - PID 25434 | 01:50:28 INFO - PID 25434 | Cycle 1(1): loaded http://localhost:45020/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45020/tests/kraken/driver.html?testName=audio-oscillator) 01:50:29 INFO - PID 25434 | RSS: Main: 173838336 01:50:29 INFO - PID 25434 | 01:50:31 INFO - PID 25434 | Cycle 1(1): loaded http://localhost:45020/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45020/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:50:31 INFO - PID 25434 | RSS: Main: 174784512 01:50:31 INFO - PID 25434 | 01:50:34 INFO - PID 25434 | Cycle 1(1): loaded http://localhost:45020/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45020/tests/kraken/driver.html?testName=imaging-darkroom) 01:50:35 INFO - PID 25434 | RSS: Main: 175263744 01:50:35 INFO - PID 25434 | 01:50:39 INFO - PID 25434 | Cycle 1(1): loaded http://localhost:45020/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45020/tests/kraken/driver.html?testName=imaging-desaturate) 01:50:39 INFO - PID 25434 | RSS: Main: 174964736 01:50:39 INFO - PID 25434 | 01:50:42 INFO - PID 25434 | Cycle 1(1): loaded http://localhost:45020/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45020/tests/kraken/driver.html?testName=json-parse-financial) 01:50:43 INFO - PID 25434 | RSS: Main: 175321088 01:50:43 INFO - PID 25434 | 01:50:44 INFO - PID 25434 | Cycle 1(1): loaded http://localhost:45020/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45020/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:50:45 INFO - PID 25434 | RSS: Main: 175648768 01:50:45 INFO - PID 25434 | 01:50:47 INFO - PID 25434 | Cycle 1(1): loaded http://localhost:45020/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45020/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:50:47 INFO - PID 25434 | RSS: Main: 176050176 01:50:47 INFO - PID 25434 | 01:50:49 INFO - PID 25434 | Cycle 1(1): loaded http://localhost:45020/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45020/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:50:50 INFO - PID 25434 | RSS: Main: 173178880 01:50:50 INFO - PID 25434 | 01:50:52 INFO - PID 25434 | Cycle 1(1): loaded http://localhost:45020/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45020/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:50:53 INFO - PID 25434 | RSS: Main: 171933696 01:50:53 INFO - PID 25434 | 01:50:56 INFO - PID 25434 | Cycle 1(1): loaded http://localhost:45020/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45020/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:50:57 INFO - PID 25434 | RSS: Main: 172888064 01:50:57 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | Cycle 1(1): loaded http://localhost:45020/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45020/tests/kraken/driver.html?testName=ai-astar) 01:50:59 INFO - PID 25434 | RSS: Main: 174743552 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | __start_tp_report 01:50:59 INFO - PID 25434 | _x_x_mozilla_page_load 01:50:59 INFO - PID 25434 | _x_x_mozilla_page_load_details 01:50:59 INFO - PID 25434 | |i|pagename|runs| 01:50:59 INFO - PID 25434 | |0;ai-astar;101;108;100;109;110;110;111;101;110;109 01:50:59 INFO - PID 25434 | |1;audio-beat-detection;152;118;147;173;120;147;151;119;173;122 01:50:59 INFO - PID 25434 | |2;audio-dft;163;164;164;163;163;163;169;174;162;165 01:50:59 INFO - PID 25434 | |3;audio-fft;75;74;75;172;75;75;77;73;162;75 01:50:59 INFO - PID 25434 | |4;audio-oscillator;80;79;134;80;80;80;126;81;81;131 01:50:59 INFO - PID 25434 | |5;imaging-gaussian-blur;115;115;114;114;114;114;114;115;113;115 01:50:59 INFO - PID 25434 | |6;imaging-darkroom;148;149;148;149;148;151;149;149;149;150 01:50:59 INFO - PID 25434 | |7;imaging-desaturate;94;94;94;94;96;94;97;95;94;95 01:50:59 INFO - PID 25434 | |8;json-parse-financial;52;53;57;52;51;53;52;52;51;54 01:50:59 INFO - PID 25434 | |9;json-stringify-tinderbox;38;38;38;38;38;38;38;38;38;39 01:50:59 INFO - PID 25434 | |10;stanford-crypto-aes;79;76;77;75;75;75;75;75;74;79 01:50:59 INFO - PID 25434 | |11;stanford-crypto-ccm;119;120;124;120;120;116;118;121;118;166 01:50:59 INFO - PID 25434 | |12;stanford-crypto-pbkdf2;169;190;175;170;170;177;167;173;179;169 01:50:59 INFO - PID 25434 | |13;stanford-crypto-sha256-iterative;69;65;63;65;63;65;64;68;63;64 01:50:59 INFO - PID 25434 | __end_tp_report 01:50:59 INFO - PID 25434 | __start_cc_report 01:50:59 INFO - PID 25434 | _x_x_mozilla_cycle_collect,928 01:50:59 INFO - PID 25434 | __end_cc_report 01:50:59 INFO - PID 25434 | __startTimestamp1504687859373__endTimestamp 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | ------- Summary: start ------- 01:50:59 INFO - PID 25434 | Number of tests: 14 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | [#0] ai-astar Cycles:10 Average:106.90 Median:109.50 stddev:4.38 (4.0%) stddev-sans-first:4.10 01:50:59 INFO - PID 25434 | Values: 101.0 108.0 100.0 109.0 110.0 110.0 111.0 101.0 110.0 109.0 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:149.00 stddev:21.45 (14.4%) stddev-sans-first:22.46 01:50:59 INFO - PID 25434 | Values: 152.0 118.0 147.0 173.0 120.0 147.0 151.0 119.0 173.0 122.0 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | [#2] audio-dft Cycles:10 Average:165.00 Median:164.00 stddev:3.71 (2.3%) stddev-sans-first:3.87 01:50:59 INFO - PID 25434 | Values: 163.0 164.0 164.0 163.0 163.0 163.0 169.0 174.0 162.0 165.0 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | [#3] audio-fft Cycles:10 Average:93.30 Median:75.00 stddev:38.93 (51.9%) stddev-sans-first:40.72 01:50:59 INFO - PID 25434 | Values: 75.0 74.0 75.0 172.0 75.0 75.0 77.0 73.0 162.0 75.0 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:80.00 stddev:24.33 (30.4%) stddev-sans-first:25.17 01:50:59 INFO - PID 25434 | Values: 80.0 79.0 134.0 80.0 80.0 80.0 126.0 81.0 81.0 131.0 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.50 stddev:0.67 (0.6%) stddev-sans-first:0.67 01:50:59 INFO - PID 25434 | Values: 115.0 115.0 114.0 114.0 114.0 114.0 114.0 115.0 113.0 115.0 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:149.00 stddev:0.94 (0.6%) stddev-sans-first:0.93 01:50:59 INFO - PID 25434 | Values: 148.0 149.0 148.0 149.0 148.0 151.0 149.0 149.0 149.0 150.0 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.50 stddev:1.06 (1.1%) stddev-sans-first:1.09 01:50:59 INFO - PID 25434 | Values: 94.0 94.0 94.0 94.0 96.0 94.0 97.0 95.0 94.0 95.0 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:52.50 stddev:1.77 (3.4%) stddev-sans-first:1.86 01:50:59 INFO - PID 25434 | Values: 52.0 53.0 57.0 52.0 51.0 53.0 52.0 52.0 51.0 54.0 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | [#9] json-stringify-tinderbox Cycles:10 Average:38.10 Median:38.00 stddev:0.32 (0.8%) stddev-sans-first:0.33 01:50:59 INFO - PID 25434 | Values: 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 39.0 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:75.50 stddev:1.76 (2.3%) stddev-sans-first:1.50 01:50:59 INFO - PID 25434 | Values: 79.0 76.0 77.0 75.0 75.0 75.0 75.0 75.0 74.0 79.0 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | [#11] stanford-crypto-ccm Cycles:10 Average:124.20 Median:120.00 stddev:14.84 (12.4%) stddev-sans-first:15.62 01:50:59 INFO - PID 25434 | Values: 119.0 120.0 124.0 120.0 120.0 116.0 118.0 121.0 118.0 166.0 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.90 Median:174.00 stddev:6.85 (3.9%) stddev-sans-first:7.04 01:50:59 INFO - PID 25434 | Values: 169.0 190.0 175.0 170.0 170.0 177.0 167.0 173.0 179.0 169.0 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:2.08 (3.2%) stddev-sans-first:1.59 01:50:59 INFO - PID 25434 | Values: 69.0 65.0 63.0 65.0 63.0 65.0 64.0 68.0 63.0 64.0 01:50:59 INFO - PID 25434 | -------- Summary: end -------- 01:50:59 INFO - PID 25434 | 01:50:59 INFO - PID 25434 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 01:51:00 INFO - TEST-INFO | 25434: exit 0 01:51:00 INFO - TEST-OK | kraken | took 60795ms 01:51:00 INFO - SUITE-END | took 1168s 01:51:00 INFO - Completed test suite (00:19:29) 01:51:00 INFO - javascript benchmark 01:51:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8510.618169139145, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3251.561039259701, 3282.8522716477764, 3299.91750206245, 3302.653221411752, 3347.2803347280346, 6290.905636415452, 6335.556511044218, 6345.873082538346, 6354.777582784609, 6361.300256971729, 6261.060840873871, 6347.36526347365, 6351.364863513646, 6367.554271201017, 6384.521160912938, 5824.796132135376, 5844.649321040752, 5846.0, 5857.560682948774, 5864.912026319608, 5864.530837532987, 5871.442212989759, 5876.70616469177, 5889.676067816268, 5909.527237820964, 5863.79476718315, 5932.2584676915385, 5953.970230148865, 5991.221141251653, 6049.274087109531, 5800.796972105998, 5876.97061514694, 5882.411758824105, 5957.821265362046, 5981.58128930975, 5696.088625819891, 5725.856853578653, 5739.196512488255, 5827.912581311284, 5866.14940833581, 6276.372362763733, 6400.199975003125, 6413.486921046305, 6437.098806167093, 6442.098106265126, 6791.320867913169, 6810.863782724367, 6880.58716477013, 6881.518293719441, 6906.792796216121, 6855.5886646801355, 6886.139232595926, 6936.306369363023, 6946.895796563055, 6947.617881016542, 6410.358964103599, 6442.194725659293, 6496.5127615428555, 6499.610023398611, 6501.447376972963, 7064.964675176694, 7065.0815393998455, 7078.469114816409, 7190.712371505186, 7191.3887319578425, 6583.835404114935, 6652.634105124168, 6748.763793267212, 6770.1537307836525, 6870.587764734132, 6843.213030501556, 6843.828904277433, 6877.58734475923, 6887.2079710832895, 6891.173059232824, 6680.832979175462, 6688.598684078971, 6700.731970721214, 6780.186377634617, 6789.932100679029, 6777.593344399352, 6786.524943254023, 6821.69302381394, 6839.521233513607, 6902.930970690229, 4977.726225057584, 5033.546980771748, 5050.696958182447, 5052.141136006888, 5068.493150684976, 4392.934105988413, 4432.512423633398, 4443.644508439317, 4454.888627784331, 4497.415336006298, 5864.944310024237, 6010.909836352371, 6028.879422411527, 6035.517158627299, 6073.423024812636, 6764.255931847493, 6768.0, 6776.220734615484, 6780.898286525706, 6783.559068660521, 5875.559333050039, 5896.705164741746, 5899.351071382144, 5911.497522710618, 5929.851753706192, 5905.557083218776, 5917.437843404869, 5941.405859414111, 5950.31571369298, 5961.463468287875, 6468.256150542654, 6552.3447655234095, 6640.402363787282, 6660.0, 6731.259561448237, 6705.430038446787, 6843.384095431236, 6929.93070069313, 6934.549254298556, 6944.375006249462, 6491.578047426902, 6563.113979612882, 6640.867182656417, 6648.767293144717, 6655.966720166368, 6790.72837086511, 6833.897491537531, 6836.453083753188, 6852.349026842442, 6853.520253582202, 6942.166939967236, 6945.236024037251, 6967.372936435745, 7006.404455621126, 7036.577805331696, 6451.838704032437, 6557.770478033246, 6630.336966303331, 6678.599284042973, 6744.5616034957575, 6620.2386725527185, 6621.437177839937, 6633.038209459698, 6639.2696803352555, 6702.698378573074, 6573.276939536551, 6607.0, 6642.236142843635, 6644.036614690939, 6647.235567909753, 6694.765683201064, 6701.262861085374, 6708.631025293461, 6729.596224226562, 6821.386075253251, 6345.619262844244, 6454.774082907075, 6482.675866206765, 6487.156669632917, 6589.374009469093], "value": 6221.817343788818, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.3207449146785, 774.5933384972889, 788.1015642167926, 797.6809276289483, 1106.8007758603449, 30682.0, 31219.68780312196, 31238.68761312389, 40122.799386003026, 41183.58816411835, 42419.363709544385, 42748.78625606876, 42859.57140428595, 42901.0, 42923.78538107313, 7292.635368231594, 7701.730439434635, 7875.645595948167, 7876.448648594601, 7891.644875980566, 7737.845243095163, 7847.1368149503505, 7849.8037549061155, 7852.450328476981, 7853.096893857194, 7770.456068075208, 7832.099308579529, 7851.254130857588, 7905.525668459911, 7912.881306780403, 7807.609619519001, 7815.491993020464, 7830.530168189898, 7862.567558784264, 7870.685172593061, 7681.731139410093, 7722.42081843864, 7728.613569321511, 7750.0, 7826.647800848976, 37674.43488347677, 37790.24419511622, 37828.81085594554, 37911.0, 37915.0, 3486.267883744417, 3509.385857474957, 3513.1041584395816, 3514.209302906851, 3516.841742121585, 31660.525092123637, 32105.51841722376, 32120.518192227137, 32155.678443215504, 32172.0, 5221.451747566489, 5235.0, 5236.7119808410525, 5238.926020165857, 5265.710385928773, 185700.07149964297, 185746.0, 185854.07072964683, 185928.0, 186402.0, 48107.75946120247, 48253.0, 48324.0, 48352.5164748355, 48425.757871210415, 50711.746441267554, 50944.23583646248, 50951.49048509468, 50991.0, 51277.7436112817, 13981.930090349686, 14222.644433889029, 14243.501477448239, 14264.144151350953, 14295.428182872776, 10565.366078035342, 10569.260151789302, 11299.49152288149, 11397.601083962187, 11403.63156421218, 14339.354729037219, 14361.64095897611, 14382.208978506073, 14506.782398264035, 14568.05307654999, 5571.247881536022, 5758.819442014377, 5833.79581714638, 5877.735501902424, 5952.285725712942, 990.4700984973005, 991.9355241909253, 1016.6645007147623, 1023.8157131716214, 1027.4759872464947, 22304.219352322914, 22329.0, 22350.888245558992, 22644.547109057727, 23082.422939426313, 1096.5668560918552, 1103.0844399148687, 1111.8721347045193, 1114.403793970222, 1119.1997721629075, 11756.294622322688, 12007.459664315127, 12035.856593623592, 12046.33745144008, 12079.335636540074, 226497.0, 226863.0, 226959.0, 229035.0, 229629.0, 51910.48089519132, 51931.48068519342, 52080.21879671808, 52344.0, 53065.0, 1414.8585141485976, 1435.4904009076836, 1451.151076620189, 1451.267110109409, 1452.2811208451883, 22413.327600172022, 22511.662325064808, 22566.323010309716, 22573.32280031602, 22832.543349132593, 37193.070173245884, 37334.62665373312, 37340.25319493649, 37542.24915501729, 37613.81193094017, 194239.0, 194789.0, 194947.0, 195380.0, 195869.0, 30923.226919327197, 31157.376852463276, 31371.529427059068, 33365.33269334551, 33607.83196084004, 42433.15133697371, 43034.56965430306, 43036.35445468258, 43097.569024309356, 43230.567694323916, 30495.237619059702, 30691.232719181306, 30798.84600576983, 30966.53550196704, 30982.380352392374, 30808.69191308148, 30968.0, 31359.0, 31643.84178079095, 31882.202944925633, 35599.82200089087, 35947.0, 37841.0, 37960.240795184494, 37998.810005949796, 31705.524417134224, 31951.041468755975, 32047.198820028752, 32157.678423215468, 33093.50359744654, 23442.64836027495, 23661.881690592127, 23911.402214944766, 23925.88037059873, 24320.0], "value": 16892.546827813574, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.271646859083, 562.9690367029812, 567.6423852972626, 570.7973669347383, 577.3793172339734, 21756.238531651394, 22576.774232257674, 22802.54394912101, 22806.88596557017, 22851.885740571295, 23944.40138996526, 26361.209163725118, 26502.86748566259, 26553.203403897867, 26569.202923912315, 6747.0, 6830.760923367683, 6905.274946130659, 6908.240093589701, 6909.928961011026, 6014.0, 6578.901316480233, 6611.074449577039, 6741.3258674132685, 6798.796036118924, 6562.671866406685, 6565.901511477332, 6566.63883486408, 6569.474442044625, 6584.637844918528, 6574.276829548746, 6577.835554111138, 6581.045748366484, 6594.648097140075, 6597.1423714916755, 6055.273367195965, 6061.878762424771, 6062.999605065159, 6117.051856962178, 6130.724117414727, 28018.85990570034, 28632.284192895135, 28775.85612071926, 28957.0, 29315.560266596018, 2070.430631576329, 2087.676410156413, 2089.540301133748, 2090.1639344262267, 2092.9476763080893, 19336.516587085294, 19872.105755240904, 19883.30408435712, 19884.508661850417, 19911.0, 2383.7377888432256, 2397.9520409591887, 2438.829281950264, 2441.084593277521, 2445.9755402445926, 138232.0, 139791.0, 141685.0, 141998.0, 142541.28729356392, 14467.855321446861, 14470.20413877247, 14504.492342768164, 14716.632084197767, 15510.069395836286, 23431.648525272136, 23435.531289374117, 23460.178893738637, 23471.530569388517, 23507.647385289234, 9200.355975081813, 9409.576569054272, 9456.479893605918, 9459.574319155654, 9626.807463850684, 8413.074561798197, 8451.873221901677, 8563.357748168912, 8573.657053717907, 8717.346199034972, 7723.497972631873, 8937.374383793072, 9125.406848554823, 9131.145342281408, 9161.0, 2593.4164812916943, 2646.625552420679, 2713.3894873653667, 2734.494118588028, 2766.7094955029434, 946.6308139825474, 960.2174228004154, 963.2403164932558, 969.8836139663144, 976.5312649928075, 19118.426447206606, 19211.32760353409, 19430.0, 19567.902160489106, 19747.901260493894, 1021.6985989133224, 1027.7173777211326, 1027.8715160604925, 1030.299396410433, 1033.8552602635637, 10699.518521666543, 10702.625408110678, 10780.676579702622, 10841.891581084088, 11057.336559806437, 444978.0, 449311.0, 450842.0, 481888.0, 482306.0, 116069.0, 121247.0, 122468.38765806114, 122550.0, 124442.0, 1182.8284898689642, 1207.3721664734464, 1230.2864338683542, 1230.4708975140775, 1231.852177738677, 20542.483237178632, 20597.0, 20676.483087922923, 20692.68960965617, 21015.57968840645, 56279.718601406734, 56612.0, 57159.714201428724, 57476.13785793133, 59316.110258345296, 70824.0, 71915.28084719086, 71935.64032179806, 72187.27812721806, 72294.63852680914, 11433.485493152995, 11694.239874408137, 12535.498580056696, 13846.93076534611, 14281.928590356982, 22660.206892758673, 22693.0, 22734.431639209153, 22753.886230569402, 22813.657795132753, 17983.910080449514, 18141.818581814012, 18230.908845455688, 18244.361447349278, 18343.082845857385, 16450.341986320414, 16552.917235413744, 17165.914170429067, 17240.65518689644, 17447.127643617514, 18645.81354186441, 18820.341288054853, 18845.528861778017, 18847.528811779815, 19131.61736765285, 13997.230152341719, 14084.436622534984, 14088.15471071739, 14115.858841411455, 14237.932155088203, 18005.2797888083, 18090.185941632393, 18318.90840545789, 18413.171407286973, 18513.166907489463], "value": 11919.679741951606, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1303.6349822049826, 1321.6960099177188, 1345.5492410042634, 1351.4121357209622, 1356.138851829089, 14716.779248311284, 15881.920590397061, 15893.68212635748, 15893.920530397347, 15912.920435397837, 15598.454054108108, 15638.296276667548, 15826.129562874037, 15894.0, 15937.282822272997, 2646.973530264697, 2660.494506043855, 2669.7330266973245, 2672.1182009936674, 2676.1703871799805, 2543.173468622705, 2590.3265151060673, 2616.1628278950743, 2617.41108250644, 2618.6203000564906, 2574.3692795265188, 2574.6395504629368, 2598.7791037761813, 2605.7524535169127, 2614.4901744159897, 2475.220305603738, 2494.2641920633455, 2496.188738659943, 2496.226169887341, 2499.962500562493, 2457.619069044301, 2478.6405971134263, 2491.2650768023477, 2491.7134529529153, 2501.062101711858, 14902.627434314229, 14981.250937453193, 15046.699066018728, 15048.623784405368, 15050.548483545512, 7860.6069696515515, 8178.591070446513, 8182.099969003405, 8182.345412366996, 8196.590170491452, 5404.891902161935, 5502.202180683778, 5504.0, 5627.6904770237625, 5629.802956896489, 1903.961920761577, 1935.4290484307164, 1939.4278687787137, 1948.1720268885667, 1961.1469010980127, 26051.609225861626, 26284.73715262842, 26287.60568591473, 26295.21114366553, 26343.736562634513, 6396.264429590564, 6437.485001199892, 6571.408573228432, 6709.463242940553, 6896.965515172392, 16247.675046499004, 16409.343626254817, 16442.75335869963, 16553.08958007297, 16568.917155414387, 7688.961555192188, 7841.098273698483, 7861.292483676497, 7880.290773830383, 7900.920990790133, 5716.085426331767, 6021.397860214031, 6179.0, 7190.316919892602, 7200.819979500555, 5627.409122042127, 5787.565932555075, 5804.9419505805245, 5813.79651712188, 5902.527797776168, 1650.8761842861834, 1656.2629629814858, 1683.276191237755, 1699.3032856528764, 1706.709859323918, 893.097971049245, 906.9637214511478, 917.8485549884327, 922.7739203894954, 926.5737760630051, 7787.688492460352, 7787.922120778833, 7836.294733473901, 7837.412194085467, 7917.604119793988, 704.1620471638734, 708.7094291340526, 711.8647456983157, 723.7213672736035, 726.8800647893144, 4812.06164797868, 5021.20407237789, 5364.3830959440165, 5527.50252477279, 5635.549156067505, 20360.796392036187, 20528.794712052986, 20873.582528349347, 21502.677459838116, 21539.353819385444, 20678.655887367575, 21115.155393784076, 21126.366209013755, 21433.356999290045, 21500.354989350344, 11187.4965626547, 11270.211085223955, 11331.886681133248, 11346.886531134747, 11562.595309164139, 8160.26557609818, 8292.585370731318, 8408.789780255542, 8412.116727743498, 8455.8731619027, 18182.72725909139, 18878.905605471886, 18931.43205703831, 19033.90483047576, 19242.903785481543, 18373.632527349644, 18904.43286701346, 19005.239790408228, 19029.429117126507, 19150.23399064022, 12131.45408456604, 12202.267863928193, 12266.938665306974, 12268.202566833128, 12279.079069070034, 13704.725905482033, 13864.098833575785, 13876.444942202199, 13878.514252001132, 13888.72222555523, 11215.49530271156, 11251.831222531504, 11303.830442543203, 11373.886261137282, 11425.657230283105, 10749.408782517037, 10759.30064545802, 10870.293430926964, 10876.18428617841, 10991.230613857273, 9925.751856203653, 10107.444090575089, 10111.747206319607, 10114.898851011396, 10127.189824814144, 7358.638651849425, 7568.318851303409, 7578.204288549623, 7662.0422447194105, 7681.116671582616, 7632.961835190788, 7640.656170472231, 7760.0, 7778.183290754389, 7861.095973962839], "value": 6608.332349605641, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.32974649096974, 504.88672477187794, 524.4440892653787, 530.7611574791345, 532.177785321678, 6255.311915689276, 6328.740698540244, 6421.518386121042, 6446.452051575622, 6471.967640161798, 6042.607230530023, 6118.877622447549, 6186.505079593633, 6221.129041934133, 6224.906626400608, 5042.84871453857, 5055.266986287004, 5058.367704036996, 5065.670731402465, 5072.771725272353, 4912.803487860481, 5006.399232092154, 5009.398872135331, 5018.899622007558, 5027.547520723134, 4998.650094493387, 5013.949860501385, 5019.0, 5022.221555658879, 5036.84889453317, 4943.258511223327, 4962.3548938638105, 4983.377077865267, 5010.799568017275, 5030.170021946375, 4415.536368681274, 4469.25698977399, 4469.284914413678, 4493.191225579394, 4501.549845015505, 6154.799814036265, 6206.937930620681, 6221.129041934133, 6223.875522489525, 6228.937710622927, 5691.0, 5757.1652110444165, 5761.395053519362, 5762.855928601776, 5767.480926716616, 6598.439132673729, 6600.108985286973, 6603.306652801435, 6607.240167380764, 6623.403893649546, 4899.755012249373, 4923.901521969577, 4935.7532123393685, 4941.530554597343, 4960.727160006199, 6907.896381554281, 6949.930500694979, 6974.825629359256, 7030.578165310098, 7079.29207079288, 5006.0, 5013.49865013503, 5021.51865199767, 5022.573081288058, 5044.596432285409, 5096.47105868238, 5115.18157094863, 5134.820281290212, 5135.589152867761, 5139.331886854685, 5718.199452076713, 5741.311042674905, 5754.309482862, 5760.452756988079, 5765.88468230633, 6546.279909209983, 6577.86844263112, 6633.867322653617, 6669.0, 6675.0, 1638.868890488782, 1673.8577220936113, 1692.280780668211, 1740.7736994190927, 1752.772139621841, 1177.7232350397649, 1225.197495640357, 1232.4084439469104, 1235.0, 1247.812828075796, 5571.582131340166, 5722.0, 5736.68448235351, 5768.596198266081, 5809.3609702932645, 6516.837079073061, 6600.537962342686, 6632.767853125214, 6770.966145169243, 6825.146856642919, 5786.942130578725, 5814.883702326015, 5875.9118613220835, 5928.525717942554, 5961.731722072517, 3506.457158850149, 3547.219611685425, 3550.343186510504, 3554.9157506960623, 3573.0, 6365.4989755634215, 6451.1936007999, 6479.449246814073, 6486.6756662167645, 6497.740090396331, 6759.459243260528, 6826.351496607815, 6867.759628412981, 6891.655417229119, 6910.516263861481, 6335.524835637438, 6418.19772528434, 6440.967795160994, 6484.773032943825, 6493.155889734304, 6613.470922326106, 6670.499712521483, 6674.766383176566, 6715.395614394728, 6741.494387920828, 6579.276279609335, 6598.109255250672, 6671.39957403836, 6698.866022679422, 6702.83242918931, 6443.613383197023, 6458.644774537445, 6490.415862572391, 6564.310747371457, 6564.770233041821, 4864.586510146677, 4924.975375123245, 4928.67963582366, 4930.580900623487, 4952.702837829742, 6631.900521492278, 6734.191896972395, 6755.831104222237, 6785.59286442815, 6827.317268273133, 6376.298607153302, 6515.543911926309, 6534.183227096613, 6581.572197807127, 6588.9361446925495, 6284.3087260402235, 6375.521835862236, 6497.480201583957, 6527.0, 6529.314421985623, 6255.811395834777, 6334.968325158345, 6387.840303992437, 6419.550631455753, 6436.678166091583, 6548.934510655023, 6550.901736473861, 6568.934310657023, 6579.289384759901, 6688.966555167193, 6289.874202516015, 6359.682015899094, 6401.207661854688, 6410.775622853178, 6441.584405847807], "value": 5065.772476870475, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [824.6907409721358, 829.4359835311985, 841.4866931171985, 900.842352588297, 916.6791622931972, 18465.7230141548, 19290.710639340403, 19296.324628638, 19316.71024934627, 21536.784632153674, 15901.045937243773, 16053.197340132976, 18094.819051809478, 21230.25694100707, 21539.138434462642, 946.4841661294611, 965.1265604627788, 970.5341436110671, 975.1029053270972, 976.3312131190122, 898.5327629632587, 981.4454833019357, 990.7721224118457, 992.0476342711006, 992.5285489392517, 1027.2603725317758, 1028.9022542858415, 1030.8711411073616, 1031.520343040486, 1031.6079889641926, 1028.6399760083948, 1029.5469993202967, 1029.6447725534679, 1030.206740809576, 1032.0866033560317, 753.7851712262034, 753.8002429356225, 755.0674916478146, 755.3768141283463, 760.9391248700146, 19242.23031078769, 19702.507437314038, 19898.80101198984, 19916.50208744793, 20173.697394539093, 20510.282140125026, 20636.17455301786, 20788.272410465714, 20866.165353385848, 21051.473713157295, 16487.422940197037, 16971.490855274238, 16975.575610609834, 17121.31514739409, 17362.565935851577, 3722.0, 3749.887503374903, 3752.418375151856, 3763.7741735495956, 3765.0775559987705, 118858.0, 119343.40328298302, 119384.0, 120710.77512287028, 122156.0, 15605.921970390076, 15847.762283565755, 16003.27985240667, 16044.358225670843, 16169.353225870833, 28920.27699307492, 29833.25416864596, 30051.849740751157, 30138.794448222263, 30139.0, 8808.559572021373, 8900.332475064397, 8904.688335908211, 8909.109089091038, 8932.821343573221, 4337.9132417351475, 4425.114977004612, 4459.487158976694, 4475.239209334421, 4482.574155455227, 5154.484551544815, 5228.0, 5236.266922630834, 5262.736863156827, 5290.444503327172, 930.9813803724023, 952.8951815300311, 967.052288757008, 973.1387721866199, 973.8052389521982, 685.4619123987745, 700.831800367908, 703.085988215318, 707.8265824872939, 715.9105111861018, 11701.0, 11813.900697922294, 12083.395830208454, 12877.935610322076, 12924.22454652705, 683.1494788987742, 706.9328413800681, 711.5659447736876, 716.6309350684402, 730.3536370312312, 5170.560502357342, 5354.678719276856, 5428.348598168245, 5702.800401985911, 5759.65442073477, 105485.0, 110313.44843275732, 113193.4340328293, 115008.0, 117742.0, 96430.51784741032, 96487.0, 98803.50598246962, 98880.0, 99562.5021874886, 85538.57230713931, 87184.56407717921, 87684.12315876887, 88642.0, 89181.55409222913, 11671.88328116708, 11707.180497365103, 11961.700957476132, 12872.292023938773, 13051.869481305066, 40844.387334189414, 41395.0, 41457.58542414537, 42085.78957105195, 42999.57000429956, 53902.73048634888, 54008.18987715109, 54432.0, 54445.0, 55466.0, 17829.73255401196, 18210.904146295426, 18220.45338639843, 18296.0, 18496.630067398844, 20333.288334908208, 20663.17347306091, 20682.379528614165, 20759.58480830405, 21009.57980840344, 18449.0, 18543.721844172618, 18579.44261672152, 18629.06854657289, 18685.81314186895, 17656.73514897303, 17665.82334176642, 17692.557686057953, 17721.556961076076, 17789.644207116045, 18051.458456246335, 18238.088095594903, 18245.908770456062, 18295.908520457313, 18468.353607623667, 13377.799333010207, 13439.865601343861, 13487.527936522174, 13667.79498307546, 13833.584992450242, 13749.931250343685, 14599.489017884323, 14862.993495877918, 14972.850271497146, 15116.924415377853], "value": 9059.974123726613, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1490.4000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 108.0, 100.0, 109.0, 110.0, 110.0, 111.0, 101.0, 110.0, 109.0], "value": 106.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 118.0, 147.0, 173.0, 120.0, 147.0, 151.0, 119.0, 173.0, 122.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 164.0, 163.0, 163.0, 163.0, 169.0, 174.0, 162.0, 165.0], "value": 165.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 172.0, 75.0, 75.0, 77.0, 73.0, 162.0, 75.0], "value": 93.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 134.0, 80.0, 80.0, 80.0, 126.0, 81.0, 81.0, 131.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0, 113.0, 115.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 148.0, 149.0, 148.0, 151.0, 149.0, 149.0, 149.0, 150.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 96.0, 94.0, 97.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 57.0, 52.0, 51.0, 53.0, 52.0, 52.0, 51.0, 54.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 39.0], "value": 38.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 77.0, 75.0, 75.0, 75.0, 75.0, 75.0, 74.0, 79.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 120.0, 124.0, 120.0, 120.0, 116.0, 118.0, 121.0, 118.0, 166.0], "value": 124.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 190.0, 175.0, 170.0, 170.0, 177.0, 167.0, 173.0, 179.0, 169.0], "value": 173.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 63.0, 65.0, 63.0, 65.0, 64.0, 68.0, 63.0, 64.0], "value": 64.9, "unit": "score"}]}]} 01:51:00 INFO - Return code: 0 01:51:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:51:00 INFO - # TBPL SUCCESS # 01:51:00 INFO - Running post-action listener: _package_coverage_data 01:51:00 INFO - Running post-action listener: _resource_record_post_action 01:51:00 INFO - [mozharness: 2017-09-06 08:51:00.658426Z] Finished run-tests step (success) 01:51:00 INFO - Running post-run listener: _resource_record_post_run 01:51:00 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 413696; Write bytes: 391004160; Read time: 584; Write time: 343076 01:51:00 INFO - TinderboxPrint: CPU usage
13.1% 01:51:00 INFO - TinderboxPrint: I/O read bytes / time
413,696 / 584 01:51:00 INFO - TinderboxPrint: I/O write bytes / time
391,004,160 / 343,076 01:51:00 INFO - TinderboxPrint: CPU idle
8,211.0 (86.9%) 01:51:00 INFO - TinderboxPrint: CPU user
1,192.2 (12.6%) 01:51:00 INFO - TinderboxPrint: Swap in / out
131,072 / 0 01:51:00 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 106659840; Read time: 0; Write time: 127184 01:51:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 01:51:00 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 413696; Write bytes: 284344320; Read time: 584; Write time: 215892 01:51:01 INFO - Running post-run listener: _upload_blobber_files 01:51:01 INFO - Blob upload gear active. 01:51:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:51:01 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 01:51:01 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'] 01:51:01 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 01:51:01 INFO - (blobuploader) - INFO - Open directory for files ... 01:51:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:51:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:51:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:51:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:51:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:51:02 INFO - (blobuploader) - INFO - Done attempting. 01:51:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:51:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:51:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:51:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:51:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:51:03 INFO - (blobuploader) - INFO - Done attempting. 01:51:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:51:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:51:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:51:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:51:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:51:10 INFO - (blobuploader) - INFO - Done attempting. 01:51:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:51:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:51:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:51:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:51:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:51:11 INFO - (blobuploader) - INFO - Done attempting. 01:51:11 INFO - (blobuploader) - INFO - Iteration through files over. 01:51:11 INFO - Return code: 0 01:51:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:51:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:51:11 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a552f4a81d0485974aa05cf5e205062f6b7c310c81d1d5253158cb2599a9381168b5f8e20450c62f23c16eb6149ad9cc06b2b6a0913889db3c225dbda724b07", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e24305fc7ee7124c3d350eb0691434cbdffe6955017adfb576ab1eb95f8ae5d47f5ac575d5311c46105d4efc2e12ec928a34bdd3e30cb4a42a727e2b5cad1c83", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8219c8db07ef043df16b4591a0374406fc28cc5488fb963faa0cc3c0677b558a72891d7127cb37ffb9ef23b75492f91bcece394f99290ed442322b3d11ff1cc1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/331740e7b9919f8d501787284f84430e1d04be83fc9ec502f91ca3124fc465b2097e5243abbef343f65f29438006ecbdbdf5f4bebe93c627d0fd7f56a085731b"} 01:51:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:51:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:51:11 INFO - Contents: 01:51:11 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a552f4a81d0485974aa05cf5e205062f6b7c310c81d1d5253158cb2599a9381168b5f8e20450c62f23c16eb6149ad9cc06b2b6a0913889db3c225dbda724b07", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e24305fc7ee7124c3d350eb0691434cbdffe6955017adfb576ab1eb95f8ae5d47f5ac575d5311c46105d4efc2e12ec928a34bdd3e30cb4a42a727e2b5cad1c83", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8219c8db07ef043df16b4591a0374406fc28cc5488fb963faa0cc3c0677b558a72891d7127cb37ffb9ef23b75492f91bcece394f99290ed442322b3d11ff1cc1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/331740e7b9919f8d501787284f84430e1d04be83fc9ec502f91ca3124fc465b2097e5243abbef343f65f29438006ecbdbdf5f4bebe93c627d0fd7f56a085731b"} 01:51:11 INFO - Running post-run listener: copy_logs_to_upload_dir 01:51:11 INFO - Copying logs to upload dir... 01:51:11 INFO - mkdir: /builds/slave/test/build/upload/logs 01:51:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.726567 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-06 01:51:11.423671) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-06 01:51:11.426717) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873 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/2a552f4a81d0485974aa05cf5e205062f6b7c310c81d1d5253158cb2599a9381168b5f8e20450c62f23c16eb6149ad9cc06b2b6a0913889db3c225dbda724b07", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e24305fc7ee7124c3d350eb0691434cbdffe6955017adfb576ab1eb95f8ae5d47f5ac575d5311c46105d4efc2e12ec928a34bdd3e30cb4a42a727e2b5cad1c83", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8219c8db07ef043df16b4591a0374406fc28cc5488fb963faa0cc3c0677b558a72891d7127cb37ffb9ef23b75492f91bcece394f99290ed442322b3d11ff1cc1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/331740e7b9919f8d501787284f84430e1d04be83fc9ec502f91ca3124fc465b2097e5243abbef343f65f29438006ecbdbdf5f4bebe93c627d0fd7f56a085731b"} build_url:https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011538 build_url: 'https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a552f4a81d0485974aa05cf5e205062f6b7c310c81d1d5253158cb2599a9381168b5f8e20450c62f23c16eb6149ad9cc06b2b6a0913889db3c225dbda724b07", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e24305fc7ee7124c3d350eb0691434cbdffe6955017adfb576ab1eb95f8ae5d47f5ac575d5311c46105d4efc2e12ec928a34bdd3e30cb4a42a727e2b5cad1c83", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8219c8db07ef043df16b4591a0374406fc28cc5488fb963faa0cc3c0677b558a72891d7127cb37ffb9ef23b75492f91bcece394f99290ed442322b3d11ff1cc1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/331740e7b9919f8d501787284f84430e1d04be83fc9ec502f91ca3124fc465b2097e5243abbef343f65f29438006ecbdbdf5f4bebe93c627d0fd7f56a085731b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-06 01:51:11.454820) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:51:11.455112) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1504686007.564558-865878873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004488 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:51:11.539257) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-06 01:51:11.539573) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-06 01:51:11.539856) ========= ========= Total master_lag: 0.18 =========