builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-047 starttime: 1504787542.33 results: success (0) revision: 37b95547f0d27565452136d16b2df2857be840f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:22.333369) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:22.333856) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:22.334125) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:22.384967) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:22.385597) ========= 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=03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-07 05:32: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% 47.8M=0s 2017-09-07 05:32:22 (47.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.154774 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:22.558040) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:22.558366) ========= 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=03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.099005 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:22.672622) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-07 05:32:22.672956) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 37b95547f0d27565452136d16b2df2857be840f6 --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 37b95547f0d27565452136d16b2df2857be840f6 --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=03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-07 05:32:22,703 truncating revision to first 12 chars 2017-09-07 05:32:22,703 Setting DEBUG logging. 2017-09-07 05:32:22,703 attempt 1/10 2017-09-07 05:32:22,703 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/37b95547f0d2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-07 05:32:23,447 unpacking tar archive at: mozilla-central-37b95547f0d2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.018679 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-07 05:32:23.708211) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:23.708815) ========= script_repo_revision: 37b95547f0d27565452136d16b2df2857be840f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:23.709494) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:23.713499) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:23.729059) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-07 05:32:23.729498) ========= /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=03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:32:23 INFO - MultiFileLogger online at 20170907 05:32:23 in /builds/slave/test 05:32:23 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 05:32:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:32:23 INFO - {'append_to_log': False, 05:32:23 INFO - 'base_work_dir': '/builds/slave/test', 05:32:23 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:32:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:32:23 INFO - 'branch': 'Firefox-Non-PGO', 05:32:23 INFO - 'buildbot_json_path': 'buildprops.json', 05:32:23 INFO - 'code_coverage': False, 05:32:23 INFO - 'config_files': ('talos/linux_config.py',), 05:32:23 INFO - 'default_actions': ('clobber', 05:32:23 INFO - 'read-buildbot-config', 05:32:23 INFO - 'download-and-extract', 05:32:23 INFO - 'populate-webroot', 05:32:23 INFO - 'create-virtualenv', 05:32:23 INFO - 'install', 05:32:23 INFO - 'setup-mitmproxy', 05:32:23 INFO - 'run-tests'), 05:32:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:32:23 INFO - 'disable_ccov_upload': False, 05:32:23 INFO - 'disable_stylo': False, 05:32:23 INFO - 'download_minidump_stackwalk': True, 05:32:23 INFO - 'download_symbols': 'ondemand', 05:32:23 INFO - 'enable_stylo': False, 05:32:23 INFO - 'enable_webrender': False, 05:32:23 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 05:32:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:32:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:32:23 INFO - 'gecko_profile': False, 05:32:23 INFO - 'gecko_profile_interval': 0, 05:32:23 INFO - 'installer_path': 'installer.exe', 05:32:23 INFO - 'log_level': 'info', 05:32:23 INFO - 'log_name': 'talos', 05:32:23 INFO - 'log_to_console': True, 05:32:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:32:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:32:23 INFO - 'opt_config_files': (), 05:32:23 INFO - 'pip_index': False, 05:32:23 INFO - 'suite': 'dromaeojs-e10s', 05:32:23 INFO - 'system_bits': '32', 05:32:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:32:23 INFO - 'title': 'talos-linux64-ix-047', 05:32:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:32:23 INFO - 'use_talos_json': True, 05:32:23 INFO - 'verify': 'False', 05:32:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:32:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:32:23 INFO - 'work_dir': 'build'} 05:32:23 INFO - [mozharness: 2017-09-07 12:32:23.992051Z] Running clobber step. 05:32:23 INFO - Running pre-action listener: _resource_record_pre_action 05:32:23 INFO - Running main action method: clobber 05:32:23 INFO - rmtree: /builds/slave/test/build 05:32:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:32:26 INFO - Running post-action listener: _resource_record_post_action 05:32:26 INFO - [mozharness: 2017-09-07 12:32:26.585604Z] Finished clobber step (success) 05:32:26 INFO - [mozharness: 2017-09-07 12:32:26.585779Z] Running read-buildbot-config step. 05:32:26 INFO - Running pre-action listener: _resource_record_pre_action 05:32:26 INFO - Running main action method: read_buildbot_config 05:32:26 INFO - Using buildbot properties: 05:32:26 INFO - { 05:32:26 INFO - "project": "", 05:32:26 INFO - "product": "firefox", 05:32:26 INFO - "who": "archaeopteryx@coole-files.de", 05:32:26 INFO - "installer_path": "public/build/target.tar.bz2", 05:32:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:32:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:32:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 05:32:26 INFO - "stage_platform": "linux64", 05:32:26 INFO - "basedir": "/builds/slave/test", 05:32:26 INFO - "buildnumber": 1121, 05:32:26 INFO - "platform": "ubuntu64_hw", 05:32:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:32:26 INFO - "slavebuilddir": "test", 05:32:26 INFO - "taskId": "O-qHhVPCRiOMz4gVylmgGw", 05:32:26 INFO - "branch": "mozilla-central", 05:32:26 INFO - "script_repo_revision": "production", 05:32:26 INFO - "revision": "37b95547f0d27565452136d16b2df2857be840f6", 05:32:26 INFO - "slavename": "talos-linux64-ix-047", 05:32:26 INFO - "repo_path": "mozilla-central" 05:32:26 INFO - } 05:32:26 INFO - Finding installer, test and symbols from parent task. 05:32:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O-qHhVPCRiOMz4gVylmgGw'}, attempt #1 05:32:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O-qHhVPCRiOMz4gVylmgGw'}, attempt #1 05:32:27 INFO - Task dependencies: O4Y3kdYKTk6AYNU8oh2jiA 05:32:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA'}, attempt #1 05:32:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2 05:32:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.test_packages.json 05:32:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.crashreporter-symbols.zip 05:32:28 INFO - Running post-action listener: _resource_record_post_action 05:32:28 INFO - [mozharness: 2017-09-07 12:32:28.059305Z] Finished read-buildbot-config step (success) 05:32:28 INFO - [mozharness: 2017-09-07 12:32:28.059520Z] Running download-and-extract step. 05:32:28 INFO - Running pre-action listener: _resource_record_pre_action 05:32:28 INFO - Running main action method: download_and_extract 05:32:28 INFO - mkdir: /builds/slave/test/build/tests 05:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:28 INFO - trying https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.test_packages.json 05:32:28 INFO - Downloading https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:32:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:32:28 INFO - Downloaded 989 bytes. 05:32:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:32:28 INFO - Using the following test package requirements: 05:32:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:32:28 INFO - u'common': [u'target.common.tests.zip'], 05:32:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:32:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:32:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:32:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:32:28 INFO - u'mozbase': [u'target.common.tests.zip'], 05:32:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:32:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:32:28 INFO - u'web-platform': [u'target.common.tests.zip', 05:32:28 INFO - u'target.web-platform.tests.tar.gz'], 05:32:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:32:28 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:32:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.common.tests.zip 05:32:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:32:28 INFO - Fetch https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.common.tests.zip into memory 05:32:29 INFO - Content-Length response header: 55021160 05:32:29 INFO - Bytes received: 55021160 05:32:40 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:32:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.common.tests.zip 05:32:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:32:40 INFO - Fetch https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.common.tests.zip into memory 05:32:41 INFO - Content-Length response header: 55021160 05:32:41 INFO - Bytes received: 55021160 05:32:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.talos.tests.zip 05:32:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:32:54 INFO - Fetch https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.talos.tests.zip into memory 05:32:54 INFO - Content-Length response header: 14496665 05:32:54 INFO - Bytes received: 14496665 05:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:55 INFO - trying https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2 05:32:55 INFO - Downloading https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:32:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:32:56 INFO - Downloaded 62729505 bytes. 05:32:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2 05:32:56 INFO - mkdir: /builds/slave/test/properties 05:32:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:32:56 INFO - Writing to file /builds/slave/test/properties/build_url 05:32:56 INFO - Contents: 05:32:56 INFO - build_url:https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2 05:32:56 INFO - Running post-action listener: _resource_record_post_action 05:32:56 INFO - Running post-action listener: find_tests_for_verification 05:32:56 INFO - Running post-action listener: set_extra_try_arguments 05:32:56 INFO - [mozharness: 2017-09-07 12:32:56.102581Z] Finished download-and-extract step (success) 05:32:56 INFO - [mozharness: 2017-09-07 12:32:56.102679Z] Running populate-webroot step. 05:32:56 INFO - Running pre-action listener: _resource_record_pre_action 05:32:56 INFO - Running main action method: populate_webroot 05:32:56 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:32:56 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:56 INFO - 'tests': ['tresize', 05:32:56 INFO - 'tcanvasmark']}, 05:32:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:32:56 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:56 INFO - 'tests': ['dromaeo_css', 05:32:56 INFO - 'kraken']}, 05:32:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:32:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:56 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:32:56 INFO - 'talos_options': ['--disable-stylo'], 05:32:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:32:56 INFO - 'tests': ['damp', 'tps']}, 05:32:56 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:32:56 INFO - 'talos_options': ['--disable-stylo'], 05:32:56 INFO - 'tests': ['damp', 'tps']}, 05:32:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:32:56 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:56 INFO - 'tests': ['dromaeo_dom']}, 05:32:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:32:56 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:56 INFO - 'tests': ['basic_compositor_video', 05:32:56 INFO - 'glvideo']}, 05:32:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:32:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:32:56 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:32:56 INFO - 'talos_options': ['--disable-stylo'], 05:32:56 INFO - 'tests': ['ts_paint_webext', 05:32:56 INFO - 'tp5o_webext']}, 05:32:56 INFO - 'other-e10s': {'tests': ['a11yr', 05:32:56 INFO - 'ts_paint', 05:32:56 INFO - 'tpaint', 05:32:56 INFO - 'sessionrestore', 05:32:56 INFO - 'sessionrestore_many_windows', 05:32:56 INFO - 'sessionrestore_no_auto_restore', 05:32:56 INFO - 'tabpaint']}, 05:32:56 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:56 INFO - 'tests': ['a11yr', 05:32:56 INFO - 'ts_paint', 05:32:56 INFO - 'tpaint', 05:32:56 INFO - 'sessionrestore', 05:32:56 INFO - 'sessionrestore_many_windows', 05:32:56 INFO - 'sessionrestore_no_auto_restore', 05:32:56 INFO - 'tabpaint']}, 05:32:56 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:32:56 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:32:56 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:56 INFO - 'tests': ['bloom_basic_singleton']}, 05:32:56 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:56 INFO - 'tests': ['bloom_basic']}, 05:32:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:32:56 INFO - 'tsvgr_opacity', 05:32:56 INFO - 'tart', 05:32:56 INFO - 'tscrollx', 05:32:56 INFO - 'tsvg_static']}, 05:32:56 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:56 INFO - 'tests': ['tsvgx', 05:32:56 INFO - 'tsvgr_opacity', 05:32:56 INFO - 'tart', 05:32:56 INFO - 'tscrollx', 05:32:56 INFO - 'tsvg_static']}, 05:32:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:32:56 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:32:56 INFO - 'talos_options': ['--disable-stylo'], 05:32:56 INFO - 'tests': ['tp5o']}, 05:32:56 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:32:56 INFO - 'talos_options': ['--mitmproxy', 05:32:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:32:56 INFO - '--firstNonBlankPaint'], 05:32:56 INFO - 'tests': ['tp6_google', 05:32:56 INFO - 'tp6_youtube', 05:32:56 INFO - 'tp6_amazon', 05:32:56 INFO - 'tp6_facebook']}, 05:32:56 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:32:56 INFO - 'talos_options': ['--disable-stylo', 05:32:56 INFO - '--mitmproxy', 05:32:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:32:56 INFO - '--firstNonBlankPaint'], 05:32:56 INFO - 'tests': ['tp6_google', 05:32:56 INFO - 'tp6_youtube', 05:32:56 INFO - 'tp6_amazon', 05:32:56 INFO - 'tp6_facebook']}, 05:32:56 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:32:56 INFO - 'talos_options': ['--stylo-threads=1', 05:32:56 INFO - '--mitmproxy', 05:32:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:32:56 INFO - '--firstNonBlankPaint'], 05:32:56 INFO - 'tests': ['tp6_google', 05:32:56 INFO - 'tp6_youtube', 05:32:56 INFO - 'tp6_amazon', 05:32:56 INFO - 'tp6_facebook']}, 05:32:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:32:56 INFO - 'talos_options': ['--xperf_path', 05:32:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:56 INFO - 'tests': ['tp5n']}, 05:32:56 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:32:56 INFO - 'talos_options': ['--disable-stylo', 05:32:56 INFO - '--xperf_path', 05:32:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:56 INFO - 'tests': ['tp5n']}}} 05:32:56 INFO - Running post-action listener: _resource_record_post_action 05:32:56 INFO - [mozharness: 2017-09-07 12:32:56.118164Z] Finished populate-webroot step (success) 05:32:56 INFO - [mozharness: 2017-09-07 12:32:56.118253Z] Running create-virtualenv step. 05:32:56 INFO - Running pre-action listener: _resource_record_pre_action 05:32:56 INFO - Running main action method: create_virtualenv 05:32:56 INFO - Creating virtualenv /builds/slave/test/build/venv 05:32:56 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:32:56 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 05:32:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:32:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:32:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:32:57 INFO - Installing distribute.............................................................................................................................................................................................done. 05:32:57 INFO - Installing pip...............done. 05:32:57 INFO - Return code: 0 05:32:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:32:58 INFO - Reading from file tmpfile_stdout 05:32:58 INFO - Output received: 05:32:58 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 05:32:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1090>, '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 0xea1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bf90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', '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 05:32:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:32:58 INFO - Using env: {'DISPLAY': ':0', 05:32:58 INFO - 'HOME': '/home/cltbld', 05:32:58 INFO - 'LANG': 'en_US.UTF-8', 05:32:58 INFO - 'LANGUAGE': 'en_US:en', 05:32:58 INFO - 'LOGNAME': 'cltbld', 05:32:58 INFO - 'MAIL': '/var/mail/cltbld', 05:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:58 INFO - 'MOZ_NO_REMOTE': '1', 05:32:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:58 INFO - 'NO_EM_RESTART': '1', 05:32:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:58 INFO - 'PWD': '/builds/slave/test', 05:32:58 INFO - 'SHELL': '/bin/bash', 05:32:58 INFO - 'SHLVL': '1', 05:32:58 INFO - 'TERM': 'linux', 05:32:58 INFO - 'TMOUT': '86400', 05:32:58 INFO - 'USER': 'cltbld', 05:32:58 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', 05:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:58 INFO - '_': '/tools/buildbot/bin/python'} 05:32:59 INFO - Ignoring indexes: http://pypi.python.org/simple/ 05:32:59 INFO - Downloading/unpacking pip>=1.5 05:32:59 INFO - Running setup.py egg_info for package pip 05:32:59 INFO - warning: no files found matching 'pip/cacert.pem' 05:32:59 INFO - warning: no files found matching '*.html' under directory 'docs' 05:32:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:32:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:32:59 INFO - Installing collected packages: pip 05:32:59 INFO - Found existing installation: pip 1.1 05:32:59 INFO - Uninstalling pip: 05:32:59 INFO - Successfully uninstalled pip 05:32:59 INFO - Running setup.py install for pip 05:32:59 INFO - warning: no files found matching 'pip/cacert.pem' 05:32:59 INFO - warning: no files found matching '*.html' under directory 'docs' 05:32:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:32:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:32:59 INFO - Installing pip script to /builds/slave/test/build/venv/bin 05:32:59 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 05:32:59 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 05:32:59 INFO - Successfully installed pip 05:32:59 INFO - Cleaning up... 05:32:59 INFO - Return code: 0 05:32:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1090>, '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 0xea1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bf90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', '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 05:32:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:32:59 INFO - Using env: {'DISPLAY': ':0', 05:32:59 INFO - 'HOME': '/home/cltbld', 05:32:59 INFO - 'LANG': 'en_US.UTF-8', 05:32:59 INFO - 'LANGUAGE': 'en_US:en', 05:32:59 INFO - 'LOGNAME': 'cltbld', 05:32:59 INFO - 'MAIL': '/var/mail/cltbld', 05:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:59 INFO - 'MOZ_NO_REMOTE': '1', 05:32:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:59 INFO - 'NO_EM_RESTART': '1', 05:32:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:59 INFO - 'PWD': '/builds/slave/test', 05:32:59 INFO - 'SHELL': '/bin/bash', 05:32:59 INFO - 'SHLVL': '1', 05:32:59 INFO - 'TERM': 'linux', 05:32:59 INFO - 'TMOUT': '86400', 05:32:59 INFO - 'USER': 'cltbld', 05:32:59 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', 05:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:59 INFO - '_': '/tools/buildbot/bin/python'} 05:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:59 INFO - Downloading/unpacking psutil>=3.1.1 05:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:33:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:33:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:33:00 INFO - Installing collected packages: psutil 05:33:00 INFO - Running setup.py install for psutil 05:33:00 INFO - building 'psutil._psutil_linux' extension 05:33:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:33:01 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:33:01 INFO - building 'psutil._psutil_posix' extension 05:33:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:33:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:33:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:33:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:33:02 INFO - Successfully installed psutil 05:33:02 INFO - Cleaning up... 05:33:02 INFO - Return code: 0 05:33:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1090>, '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 0xea1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bf90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', '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 05:33:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:33:02 INFO - Using env: {'DISPLAY': ':0', 05:33:02 INFO - 'HOME': '/home/cltbld', 05:33:02 INFO - 'LANG': 'en_US.UTF-8', 05:33:02 INFO - 'LANGUAGE': 'en_US:en', 05:33:02 INFO - 'LOGNAME': 'cltbld', 05:33:02 INFO - 'MAIL': '/var/mail/cltbld', 05:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:02 INFO - 'MOZ_NO_REMOTE': '1', 05:33:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:02 INFO - 'NO_EM_RESTART': '1', 05:33:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:02 INFO - 'PWD': '/builds/slave/test', 05:33:02 INFO - 'SHELL': '/bin/bash', 05:33:02 INFO - 'SHLVL': '1', 05:33:02 INFO - 'TERM': 'linux', 05:33:02 INFO - 'TMOUT': '86400', 05:33:02 INFO - 'USER': 'cltbld', 05:33:02 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', 05:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:02 INFO - '_': '/tools/buildbot/bin/python'} 05:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:33:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:33:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:33:03 INFO - Installing collected packages: mozsystemmonitor 05:33:03 INFO - Running setup.py install for mozsystemmonitor 05:33:03 INFO - Successfully installed mozsystemmonitor 05:33:03 INFO - Cleaning up... 05:33:03 INFO - Return code: 0 05:33:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:33:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1090>, '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 0xea1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bf90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', '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 05:33:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:33:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:33:03 INFO - Using env: {'DISPLAY': ':0', 05:33:03 INFO - 'HOME': '/home/cltbld', 05:33:03 INFO - 'LANG': 'en_US.UTF-8', 05:33:03 INFO - 'LANGUAGE': 'en_US:en', 05:33:03 INFO - 'LOGNAME': 'cltbld', 05:33:03 INFO - 'MAIL': '/var/mail/cltbld', 05:33:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:03 INFO - 'MOZ_NO_REMOTE': '1', 05:33:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:03 INFO - 'NO_EM_RESTART': '1', 05:33:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:03 INFO - 'PWD': '/builds/slave/test', 05:33:03 INFO - 'SHELL': '/bin/bash', 05:33:03 INFO - 'SHLVL': '1', 05:33:03 INFO - 'TERM': 'linux', 05:33:03 INFO - 'TMOUT': '86400', 05:33:03 INFO - 'USER': 'cltbld', 05:33:03 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', 05:33:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:03 INFO - '_': '/tools/buildbot/bin/python'} 05:33:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:03 INFO - Downloading/unpacking jsonschema==2.5.1 05:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:33:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:05 INFO - Downloading functools32-3.2.3-2.tar.gz 05:33:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:33:05 INFO - warning: no files found matching '*.txt' 05:33:05 INFO - no previously-included directories found matching 'build' 05:33:05 INFO - no previously-included directories found matching 'dist' 05:33:05 INFO - no previously-included directories found matching '.git*' 05:33:05 INFO - Installing collected packages: jsonschema, functools32 05:33:05 INFO - Running setup.py install for functools32 05:33:05 INFO - warning: no files found matching '*.txt' 05:33:05 INFO - no previously-included directories found matching 'build' 05:33:05 INFO - no previously-included directories found matching 'dist' 05:33:05 INFO - no previously-included directories found matching '.git*' 05:33:05 INFO - Successfully installed jsonschema functools32 05:33:05 INFO - Cleaning up... 05:33:05 INFO - Return code: 0 05:33:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:33: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')]} 05:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33: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')]} 05:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1090>, '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 0xea1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bf90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', '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 05:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:33:05 INFO - Using env: {'DISPLAY': ':0', 05:33:05 INFO - 'HOME': '/home/cltbld', 05:33:05 INFO - 'LANG': 'en_US.UTF-8', 05:33:05 INFO - 'LANGUAGE': 'en_US:en', 05:33:05 INFO - 'LOGNAME': 'cltbld', 05:33:05 INFO - 'MAIL': '/var/mail/cltbld', 05:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:05 INFO - 'MOZ_NO_REMOTE': '1', 05:33:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:05 INFO - 'NO_EM_RESTART': '1', 05:33:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:05 INFO - 'PWD': '/builds/slave/test', 05:33:05 INFO - 'SHELL': '/bin/bash', 05:33:05 INFO - 'SHLVL': '1', 05:33:05 INFO - 'TERM': 'linux', 05:33:05 INFO - 'TMOUT': '86400', 05:33:05 INFO - 'USER': 'cltbld', 05:33:05 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', 05:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:05 INFO - '_': '/tools/buildbot/bin/python'} 05:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:33:05 INFO - Cleaning up... 05:33:05 INFO - Return code: 0 05:33:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:33: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')]} 05:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33: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')]} 05:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1090>, '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 0xea1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bf90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', '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 05:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:33:05 INFO - Using env: {'DISPLAY': ':0', 05:33:05 INFO - 'HOME': '/home/cltbld', 05:33:05 INFO - 'LANG': 'en_US.UTF-8', 05:33:05 INFO - 'LANGUAGE': 'en_US:en', 05:33:05 INFO - 'LOGNAME': 'cltbld', 05:33:05 INFO - 'MAIL': '/var/mail/cltbld', 05:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:05 INFO - 'MOZ_NO_REMOTE': '1', 05:33:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:05 INFO - 'NO_EM_RESTART': '1', 05:33:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:05 INFO - 'PWD': '/builds/slave/test', 05:33:05 INFO - 'SHELL': '/bin/bash', 05:33:05 INFO - 'SHLVL': '1', 05:33:05 INFO - 'TERM': 'linux', 05:33:05 INFO - 'TMOUT': '86400', 05:33:05 INFO - 'USER': 'cltbld', 05:33:05 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', 05:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:05 INFO - '_': '/tools/buildbot/bin/python'} 05:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:05 INFO - Downloading/unpacking blobuploader==1.2.4 05:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:06 INFO - Downloading blobuploader-1.2.4.tar.gz 05:33:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:33:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:33:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:07 INFO - Downloading docopt-0.6.1.tar.gz 05:33:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:33:07 INFO - Installing collected packages: blobuploader, requests, docopt 05:33:07 INFO - Running setup.py install for blobuploader 05:33:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:33:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:33:07 INFO - Running setup.py install for requests 05:33:08 INFO - Running setup.py install for docopt 05:33:08 INFO - Successfully installed blobuploader requests docopt 05:33:08 INFO - Cleaning up... 05:33:08 INFO - Return code: 0 05:33:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:33: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')]} 05:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33: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')]} 05:33:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1090>, '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 0xea1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bf90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', '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 05:33:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:33:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:33:08 INFO - Using env: {'DISPLAY': ':0', 05:33:08 INFO - 'HOME': '/home/cltbld', 05:33:08 INFO - 'LANG': 'en_US.UTF-8', 05:33:08 INFO - 'LANGUAGE': 'en_US:en', 05:33:08 INFO - 'LOGNAME': 'cltbld', 05:33:08 INFO - 'MAIL': '/var/mail/cltbld', 05:33:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:08 INFO - 'MOZ_NO_REMOTE': '1', 05:33:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:08 INFO - 'NO_EM_RESTART': '1', 05:33:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:08 INFO - 'PWD': '/builds/slave/test', 05:33:08 INFO - 'SHELL': '/bin/bash', 05:33:08 INFO - 'SHLVL': '1', 05:33:08 INFO - 'TERM': 'linux', 05:33:08 INFO - 'TMOUT': '86400', 05:33:08 INFO - 'USER': 'cltbld', 05:33:08 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', 05:33:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:08 INFO - '_': '/tools/buildbot/bin/python'} 05:33:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:33:08 INFO - Running setup.py (path:/tmp/pip-EVJcU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:33:08 INFO - Running setup.py (path:/tmp/pip-QGwSRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:33:08 INFO - Running setup.py (path:/tmp/pip-sAtEv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:33:08 INFO - Running setup.py (path:/tmp/pip-M0pIS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:33:08 INFO - Running setup.py (path:/tmp/pip-au3sdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:33:08 INFO - Running setup.py (path:/tmp/pip-vnB6vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:33:08 INFO - Running setup.py (path:/tmp/pip-ClaspM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:33:08 INFO - Running setup.py (path:/tmp/pip-nhjLMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:33:08 INFO - Running setup.py (path:/tmp/pip-6y25ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:33:09 INFO - Running setup.py (path:/tmp/pip-qknAbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:33:09 INFO - Running setup.py (path:/tmp/pip-DwKMCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:33:09 INFO - Running setup.py (path:/tmp/pip-MyeOkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:33:09 INFO - Running setup.py (path:/tmp/pip-t2K3TG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:33:09 INFO - Running setup.py (path:/tmp/pip-p8OmhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:09 INFO - Running setup.py (path:/tmp/pip-aLbNVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:33:09 INFO - Running setup.py (path:/tmp/pip-90HtE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:33:09 INFO - Running setup.py (path:/tmp/pip-lMoc1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:33:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:33:09 INFO - Running setup.py install for manifestparser 05:33:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:33:09 INFO - Running setup.py install for mozcrash 05:33:09 INFO - Running setup.py install for mozdebug 05:33:09 INFO - Running setup.py install for mozdevice 05:33:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for mozfile 05:33:10 INFO - Running setup.py install for mozhttpd 05:33:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for mozinfo 05:33:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for mozInstall 05:33:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for mozleak 05:33:10 INFO - Running setup.py install for mozlog 05:33:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for moznetwork 05:33:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for mozprocess 05:33:10 INFO - Running setup.py install for mozprofile 05:33:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for mozrunner 05:33:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:33:11 INFO - Running setup.py install for mozscreenshot 05:33:11 INFO - Running setup.py install for moztest 05:33:11 INFO - Running setup.py install for mozversion 05:33:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:33:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:33:11 INFO - Cleaning up... 05:33:11 INFO - Return code: 0 05:33:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:33: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')]} 05:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33: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')]} 05:33:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1090>, '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 0xea1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bf90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', '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 05:33:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:33:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:33:11 INFO - Using env: {'DISPLAY': ':0', 05:33:11 INFO - 'HOME': '/home/cltbld', 05:33:11 INFO - 'LANG': 'en_US.UTF-8', 05:33:11 INFO - 'LANGUAGE': 'en_US:en', 05:33:11 INFO - 'LOGNAME': 'cltbld', 05:33:11 INFO - 'MAIL': '/var/mail/cltbld', 05:33:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:11 INFO - 'MOZ_NO_REMOTE': '1', 05:33:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:11 INFO - 'NO_EM_RESTART': '1', 05:33:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:11 INFO - 'PWD': '/builds/slave/test', 05:33:11 INFO - 'SHELL': '/bin/bash', 05:33:11 INFO - 'SHLVL': '1', 05:33:11 INFO - 'TERM': 'linux', 05:33:11 INFO - 'TMOUT': '86400', 05:33:11 INFO - 'USER': 'cltbld', 05:33:11 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', 05:33:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:11 INFO - '_': '/tools/buildbot/bin/python'} 05:33:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:33:11 INFO - Running setup.py (path:/tmp/pip-FbB8Ye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:33:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:33:11 INFO - Running setup.py (path:/tmp/pip-gSeieq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:33:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:33:11 INFO - Running setup.py (path:/tmp/pip-4fmeKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:33:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:33:11 INFO - Running setup.py (path:/tmp/pip-krNF8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:33:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:33:11 INFO - Running setup.py (path:/tmp/pip-x7hhNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:33:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:33:11 INFO - Running setup.py (path:/tmp/pip-YHYo6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:33:12 INFO - Running setup.py (path:/tmp/pip-wGOAsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:33:12 INFO - Running setup.py (path:/tmp/pip-39jSEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:33:12 INFO - Running setup.py (path:/tmp/pip-xMRXSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:33:12 INFO - Running setup.py (path:/tmp/pip-SXBHwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:33:12 INFO - Running setup.py (path:/tmp/pip-9FXkEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:33:12 INFO - Running setup.py (path:/tmp/pip-83_XBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:33:12 INFO - Running setup.py (path:/tmp/pip-UR1_CP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:33:12 INFO - Running setup.py (path:/tmp/pip-YDNsXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:12 INFO - Running setup.py (path:/tmp/pip-D2RM5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:33:12 INFO - Running setup.py (path:/tmp/pip-JeahQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:33:12 INFO - Running setup.py (path:/tmp/pip-12WQJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:33:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:13 INFO - Downloading blessings-1.6.tar.gz 05:33:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:33:14 INFO - Installing collected packages: blessings 05:33:14 INFO - Running setup.py install for blessings 05:33:14 INFO - Successfully installed blessings 05:33:14 INFO - Cleaning up... 05:33:14 INFO - Return code: 0 05:33:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:33:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:33:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:33:14 INFO - Reading from file tmpfile_stdout 05:33:14 INFO - Current package versions: 05:33:14 INFO - argparse == 1.2.1 05:33:14 INFO - blessings == 1.6 05:33:14 INFO - blobuploader == 1.2.4 05:33:14 INFO - docopt == 0.6.1 05:33:14 INFO - functools32 == 3.2.3-2 05:33:14 INFO - jsonschema == 2.5.1 05:33:14 INFO - manifestparser == 1.1 05:33:14 INFO - mozInstall == 1.13 05:33:14 INFO - mozcrash == 1.0 05:33:14 INFO - mozdebug == 0.1 05:33:14 INFO - mozdevice == 0.51 05:33:14 INFO - mozfile == 1.2 05:33:14 INFO - mozhttpd == 0.7 05:33:14 INFO - mozinfo == 0.10 05:33:14 INFO - mozleak == 0.1 05:33:14 INFO - mozlog == 3.5 05:33:14 INFO - moznetwork == 0.27 05:33:14 INFO - mozprocess == 0.25 05:33:14 INFO - mozprofile == 0.28 05:33:14 INFO - mozrunner == 6.13 05:33:14 INFO - mozscreenshot == 0.1 05:33:14 INFO - mozsystemmonitor == 0.3 05:33:14 INFO - moztest == 0.8 05:33:14 INFO - mozversion == 1.4 05:33:14 INFO - psutil == 3.1.1 05:33:14 INFO - requests == 1.2.3 05:33:14 INFO - wsgiref == 0.1.2 05:33:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1090>, '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 0xea1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bf90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', '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 05:33:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:33:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:33:14 INFO - Using env: {'DISPLAY': ':0', 05:33:14 INFO - 'HOME': '/home/cltbld', 05:33:14 INFO - 'LANG': 'en_US.UTF-8', 05:33:14 INFO - 'LANGUAGE': 'en_US:en', 05:33:14 INFO - 'LOGNAME': 'cltbld', 05:33:14 INFO - 'MAIL': '/var/mail/cltbld', 05:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:14 INFO - 'MOZ_NO_REMOTE': '1', 05:33:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:14 INFO - 'NO_EM_RESTART': '1', 05:33:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:14 INFO - 'PWD': '/builds/slave/test', 05:33:14 INFO - 'SHELL': '/bin/bash', 05:33:14 INFO - 'SHLVL': '1', 05:33:14 INFO - 'TERM': 'linux', 05:33:14 INFO - 'TMOUT': '86400', 05:33:14 INFO - 'USER': 'cltbld', 05:33:14 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', 05:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:14 INFO - '_': '/tools/buildbot/bin/python'} 05:33:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:33:14 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:33:15 INFO - Installing collected packages: simplejson 05:33:15 INFO - Running setup.py install for simplejson 05:33:15 INFO - building 'simplejson._speedups' extension 05:33:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 05:33:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 05:33:16 INFO - Successfully installed simplejson 05:33:16 INFO - Cleaning up... 05:33:16 INFO - Return code: 0 05:33:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:33:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1090>, '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 0xea1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bf90>, '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': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', '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 05:33:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:33:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:33:16 INFO - Using env: {'DISPLAY': ':0', 05:33:16 INFO - 'HOME': '/home/cltbld', 05:33:16 INFO - 'LANG': 'en_US.UTF-8', 05:33:16 INFO - 'LANGUAGE': 'en_US:en', 05:33:16 INFO - 'LOGNAME': 'cltbld', 05:33:16 INFO - 'MAIL': '/var/mail/cltbld', 05:33:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:16 INFO - 'MOZ_NO_REMOTE': '1', 05:33:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:16 INFO - 'NO_EM_RESTART': '1', 05:33:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:16 INFO - 'PWD': '/builds/slave/test', 05:33:16 INFO - 'SHELL': '/bin/bash', 05:33:16 INFO - 'SHLVL': '1', 05:33:16 INFO - 'TERM': 'linux', 05:33:16 INFO - 'TMOUT': '86400', 05:33:16 INFO - 'USER': 'cltbld', 05:33:16 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', 05:33:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:16 INFO - '_': '/tools/buildbot/bin/python'} 05:33:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:33:17 INFO - Cleaning up... 05:33:17 INFO - Return code: 0 05:33:17 INFO - Running post-action listener: _resource_record_post_action 05:33:17 INFO - Running post-action listener: _start_resource_monitoring 05:33:17 INFO - Starting resource monitoring. 05:33:17 INFO - [mozharness: 2017-09-07 12:33:17.115382Z] Finished create-virtualenv step (success) 05:33:17 INFO - [mozharness: 2017-09-07 12:33:17.116361Z] Running install step. 05:33:17 INFO - Running pre-action listener: _resource_record_pre_action 05:33:17 INFO - Running main action method: install 05:33:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:33:17 INFO - Reading from file tmpfile_stdout 05:33:17 INFO - Detecting whether we're running mozinstall >=1.0... 05:33:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:33:17 INFO - Reading from file tmpfile_stdout 05:33:17 INFO - Output received: 05:33:17 INFO - Usage: mozinstall [options] installer 05:33:17 INFO - Options: 05:33:17 INFO - -h, --help show this help message and exit 05:33:17 INFO - -d DEST, --destination=DEST 05:33:17 INFO - Directory to install application into. [default: 05:33:17 INFO - "/builds/slave/test"] 05:33:17 INFO - --app=APP Application being installed. [default: firefox] 05:33:17 INFO - mkdir: /builds/slave/test/build/application 05:33:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:33:33 INFO - Reading from file tmpfile_stdout 05:33:33 INFO - Output received: 05:33:33 INFO - /builds/slave/test/build/application/firefox/firefox 05:33:33 INFO - Running post-action listener: _resource_record_post_action 05:33:33 INFO - [mozharness: 2017-09-07 12:33:33.961322Z] Finished install step (success) 05:33:33 INFO - [mozharness: 2017-09-07 12:33:33.961552Z] Running setup-mitmproxy step. 05:33:33 INFO - Running pre-action listener: _resource_record_pre_action 05:33:33 INFO - Running main action method: setup_mitmproxy 05:33:33 INFO - Skipping: mitmproxy is not required 05:33:33 INFO - Running post-action listener: _resource_record_post_action 05:33:33 INFO - [mozharness: 2017-09-07 12:33:33.962672Z] Finished setup-mitmproxy step (success) 05:33:33 INFO - [mozharness: 2017-09-07 12:33:33.962889Z] Running run-tests step. 05:33:33 INFO - Running pre-action listener: _resource_record_pre_action 05:33:33 INFO - Running pre-action listener: _set_gcov_prefix 05:33:33 INFO - Running main action method: run_tests 05:33:33 WARNING - Try message not found. 05:33:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:33:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:33:33 INFO - Python 2.7.3 05:33:33 INFO - Return code: 0 05:33:33 INFO - grabbing minidump binary from tooltool 05:33:33 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')]} 05:33:33 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 0xea1090>, '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 0xea1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:33:33 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 05:33:33 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 05:33:33 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 05:33:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:33:34 INFO - Return code: 0 05:33:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:33:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:33:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:33:34 INFO - ENV: RUST_BACKTRACE is now full 05:33:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:33:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:33:34 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/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 05:33:34 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/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-047 --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 05:33:34 INFO - Using env: {'DISPLAY': ':0', 05:33:34 INFO - 'HOME': '/home/cltbld', 05:33:34 INFO - 'LANG': 'en_US.UTF-8', 05:33:34 INFO - 'LANGUAGE': 'en_US:en', 05:33:34 INFO - 'LOGNAME': 'cltbld', 05:33:34 INFO - 'MAIL': '/var/mail/cltbld', 05:33:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:33:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:33:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:34 INFO - 'MOZ_NO_REMOTE': '1', 05:33:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:33:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:34 INFO - 'NO_EM_RESTART': '1', 05:33:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:34 INFO - 'PWD': '/builds/slave/test', 05:33:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:33:34 INFO - 'RUST_BACKTRACE': 'full', 05:33:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:33:34 INFO - 'SHELL': '/bin/bash', 05:33:34 INFO - 'SHLVL': '1', 05:33:34 INFO - 'TERM': 'linux', 05:33:34 INFO - 'TMOUT': '86400', 05:33:34 INFO - 'USER': 'cltbld', 05:33:34 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', 05:33:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:34 INFO - '_': '/tools/buildbot/bin/python'} 05:33:34 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/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 05:33:34 INFO - mozversion application_buildid: 20170907103944 05:33:34 INFO - mozversion application_changeset: 37b95547f0d27565452136d16b2df2857be840f6 05:33:34 INFO - mozversion application_display_name: Nightly 05:33:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:33:34 INFO - mozversion application_name: Firefox 05:33:34 INFO - mozversion application_remotingname: firefox 05:33:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:33:34 INFO - mozversion application_vendor: Mozilla 05:33:34 INFO - mozversion application_version: 57.0a1 05:33:34 INFO - mozversion platform_buildid: 20170907103944 05:33:34 INFO - mozversion platform_changeset: 37b95547f0d27565452136d16b2df2857be840f6 05:33:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:33:34 INFO - mozversion platform_version: 57.0a1 05:33:34 INFO - using testdate: 1504787614 05:33:34 INFO - actual date: 1504787614 05:33:34 INFO - starting webserver on 'localhost:46643' 05:33:34 INFO - SUITE-START | Running 2 tests 05:33:34 INFO - TEST-START | dromaeo_css 05:33:34 INFO - Initialising browser for dromaeo_css test... 05:33:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46643/getInfo.html -profile /tmp/tmprsZYuB/profile 05:33:34 INFO - TEST-INFO | started process 3544 (/builds/slave/test/build/application/firefox/firefox http://localhost:46643/getInfo.html) 05:33:42 INFO - TEST-INFO | 3544: exit 0 05:33:42 INFO - Browser initialized. 05:33:42 INFO - Running cycle 1/1 for dromaeo_css test... 05:33:42 INFO - Using env: {'DISPLAY': ':0', 05:33:42 INFO - 'HOME': '/home/cltbld', 05:33:42 INFO - 'JSGC_DISABLE_POISONING': '1', 05:33:42 INFO - 'LANG': 'en_US.UTF-8', 05:33:42 INFO - 'LANGUAGE': 'en_US:en', 05:33:42 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 05:33:42 INFO - 'LOGNAME': 'cltbld', 05:33:42 INFO - 'MAIL': '/var/mail/cltbld', 05:33:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:33:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:33:42 INFO - 'MOZ_CRASHREPORTER': '1', 05:33:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:42 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 05:33:42 INFO - 'MOZ_NO_REMOTE': '1', 05:33:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:33:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:42 INFO - 'NO_EM_RESTART': '1', 05:33:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:42 INFO - 'PWD': '/builds/slave/test', 05:33:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:33:42 INFO - 'RUST_BACKTRACE': 'full', 05:33:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:33:42 INFO - 'SHELL': '/bin/bash', 05:33:42 INFO - 'SHLVL': '1', 05:33:42 INFO - 'TERM': 'linux', 05:33:42 INFO - 'TMOUT': '86400', 05:33:42 INFO - 'USER': 'cltbld', 05:33:42 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', 05:33:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:42 INFO - '_': '/tools/buildbot/bin/python'} 05:33:42 INFO - TEST-INFO | started process 3742 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprsZYuB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:33:43 INFO - PID 3742 | 05:33:43 INFO - PID 3742 | (/builds/slave/test/build/application/firefox/firefox:3793): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:33:43 INFO - PID 3742 | 05:33:53 INFO - PID 3742 | RSS: Main: 173031424 05:33:53 INFO - PID 3742 | 05:36:52 INFO - PID 3742 | Cycle 1(1): loaded http://localhost:46643/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46643/tests/dromaeo/cssquery-ext.html) 05:36:53 INFO - PID 3742 | RSS: Main: 175345664 05:36:53 INFO - PID 3742 | 05:39:55 INFO - PID 3742 | Cycle 1(1): loaded http://localhost:46643/tests/dromaeo/cssquery-ext.html (next: http://localhost:46643/tests/dromaeo/cssquery-jquery.html) 05:39:55 INFO - PID 3742 | RSS: Main: 171577344 05:39:55 INFO - PID 3742 | 05:42:57 INFO - PID 3742 | Cycle 1(1): loaded http://localhost:46643/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46643/tests/dromaeo/cssquery-mootools.html) 05:42:58 INFO - PID 3742 | RSS: Main: 172019712 05:42:58 INFO - PID 3742 | 05:45:59 INFO - PID 3742 | Cycle 1(1): loaded http://localhost:46643/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46643/tests/dromaeo/cssquery-prototype.html) 05:46:00 INFO - PID 3742 | RSS: Main: 170561536 05:46:00 INFO - PID 3742 | 05:49:03 INFO - PID 3742 | Cycle 1(1): loaded http://localhost:46643/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46643/tests/dromaeo/cssquery-yui.html) 05:49:04 INFO - PID 3742 | RSS: Main: 173486080 05:49:04 INFO - PID 3742 | 05:52:05 INFO - PID 3742 | Cycle 1(1): loaded http://localhost:46643/tests/dromaeo/cssquery-yui.html (next: http://localhost:46643/tests/dromaeo/cssquery-dojo.html) 05:52:06 INFO - PID 3742 | RSS: Main: 175878144 05:52:06 INFO - PID 3742 | 05:52:06 INFO - PID 3742 | __start_tp_report 05:52:06 INFO - PID 3742 | _x_x_mozilla_page_load 05:52:06 INFO - PID 3742 | _x_x_mozilla_page_load_details 05:52:06 INFO - PID 3742 | |i|pagename|runs| 05:52:06 INFO - PID 3742 | |0;dojo.html;3270.2314955985325;3279.803211807291;3304.7851889627173;3309.685579869913;3316.403047451458;6137.785177518787;6378.170837791086;6399.104125422445;6479.611223326598;6508.414242718154;6288.339724328948;6385.872282554346;6500.544961852672;6516.185476815398;6542.378474044958;5857.589968702193;5905.291365036202;5934.228550288456;5957.344692083867;6001.309849367313;5859.326177489579;5932.961731696957;5963.956307646166;6036.215292012054;6047.909281360783;5668.943310566903;5771.740271687784;5800.622959507618;5867.2372591563035;5932.762689492415;5892.970535147297;5902.6458412495385;5908.261467316585;5908.67502287374;5916.4675179233645;5823.039198532237;5881.294244690622;5881.558883083786;5908.14331921869;5925.081612350093;6343.524235682342;6376.553641245115;6385;6414.775482858077;6476.935230647728;6626.304238055031;6776.186857577073;6790.694418751168;6855.4172895303955;6891.345322194384;6948.44412447003;6955.6869940852785;6964.965175174142;6979.022936788853;6989.965050174716;6099.634021958653;6241.188645476104;6248.031555108965;6256.092866534327;6363.9045414318825;6951.443884489228;6955.756548520778;6971.51199416036;6977.651117444209;6983.336583024605;6612.768553100618;6635.369639884203;6636.8672626547195;6659.567128136656;6665.500087493458;6706.396424321834;6710.832229194211;6713.160854893138;6717.261101278856;6734.730610775514;6674.466042716571;6686.264510903797;6811.965940170367;6829.52193346461;6906.620135892575;6845.794626161223;6855.691493882788;6921.550099243533;6927.861442771116;6930.13373328334;5211.140161873324;5301.628885977944;5423.783048678088;5485.50630443262;5486.423925487767;4718;4783.234682450791;4806.543378379049;4812.639052071038;4859.781309841065;5949.048152295699;5951.047832346804;6015.789447369321;6021.126936594169;6030.095485677183;6571.769988050395;6916.861662766716;6927.203371612229;7005.824854378681;7021.332973367522;5868.09044598088;5877.500412464989;5883.617564858356;5886.1465087562065;5887.676177810262;5861.062230043172;5869.119632055141;5870.941290587125;5871.266091738533;5877.764889404461;6496.512761542903;6549.410553050248;6561.245456772437;6565.934340656628;6578.967105164444;5934.347221805684;6776.390124888783;6792.7962161134255;6901.585904845725;6991.930080699128;6638.468922486093;6656;6673.9332606673315;6748.223954245128;6763.7632682855865;6449.903251451138;6516.771912983023;6769.559978601375;6840.623765692736;6843.555168914005;6769.763058292936;6790.864182716219;6791.93208067913;6805.795826125224;6806.693698783468;6557.278699343164;6589;6644.302348253557;6667.733290668319;6676.732930682719;6713.832154195989;6733.932660673527;6756.155480564929;6764.830879228059;6769.627670478171;6267.780627678009;6373.203349581302;6447.4197322241225;6454.838629034311;6584.572002819801;6641.402273795382;6728.3271672832325;6759.898601521079;6775.390214880685;6793.422559082534;6326.4622507087415;6405.423511883952;6476.190476190476;6498;6581.111549940888 05:52:06 INFO - PID 3742 | |1;ext.html;828.6726742936544;835.2190701693917;860.1613426908765;874.8687696845473;881.4667126388539;40784.18431631366;41495.37756933641;41498.792506037425;41618.79190604047;41709.58290417095;38131;38589.80705096471;38602.8069859651;38610;38617.806910965475;7043.366097051252;7150.785476435715;7158.319959603856;7169.068021157243;7169.390601798853;7014.894776578355;7104.964475177617;7114.324139206793;7115.928840711578;7119.466040046991;7634.083909930816;7861.56761378124;7868.25251601097;7871.173526779663;7873.409494287923;7738.883916741253;7813.921860781376;7814.452988290822;7814.531128132274;7821.178776228471;7478.32695057442;7672.271134242238;7682.347000504964;7686.1929497402525;7808.726694565663;36578;36889.44665829988;36975.63024369749;36996.630033699585;37124.44313335302;3529.5588051493564;3560.9821950890337;3561.857525698969;3562.71498280137;3567.518385018015;30668.38663226723;30753.38493230123;30806.691933080605;30943.535846962313;30999.845000775076;5193.5585475234275;5195.610329225286;5220.2430647556275;5224.190250511138;5231.398389185254;191579;191637;192521;193556;194695;47298.76350618225;48226.517734822904;48313.75843120762;48334;48346.5165348342;49184.75407622939;49244.753776231606;49284.50715492871;49299.50700492949;49300.75349623229;16225.594360140854;16501.33994640225;16511.917440412722;16545.586360341087;16576.171191440622;13626.1143025703;13631.931840340734;13647.181169129686;13649;13666.11170273929;14202.431902723776;14242.287885605678;14257.287135643177;14419.206943617992;14438.989270750946;5591.797763480871;5650.604457688005;5771.653700777967;5772.538196944234;5823.388544202882;1001.8046480936148;1011.0546638892658;1017.6590842067842;1017.862588550551;1028.454918892988;22782.31653050411;23051.077956881538;23083.422914427272;23226;23440.882795585912;1121.1311233793874;1121.4000509727257;1123.039800970175;1123.3203911633507;1125.997861902907;12237.449314780857;12265.69335766613;12275;12344.876551234373;12471.688207794878;232745;235018;235633;235656;235862;52327;52643;53006.73496632569;55604.72197638986;55710;1405.592378210328;1443.4082026369138;1452.6731485415696;1452.8642894944626;1457.1330058615108;26717.198484045508;26748.331291717863;26888.865555672095;27239.86380068166;27346.726532735214;36435.635643643225;36455.27089458249;36497.27005459823;36575.26849463049;36600.26799464049;199977;201006;201421;201451;201691;32088.518672219467;32089.197770055936;32105.67894321027;32424.35151297008;32458.188545286557;44638.330425043;45422.09155816799;45878.54121458743;45960.54039459697;46044.769776150904;32606.673933260365;32618.51072233966;32701.182470437478;33721.49417758785;33753.83123084467;33314.16714582155;33385.666143338254;33399.49900751539;33638.32723345568;34098.14754631154;36042;36463.81768091142;36489.08777280589;36494.087647809014;36643.45034824427;32424;32678.50982235316;32831.67168328286;33282.33435331328;33834;24270.393240169138;24318.81130698175;24681.382965426008;24806.875965620056;24945.875270623532 05:52:06 INFO - PID 3742 | |2;jquery.html;552.5772783820378;569.712295290878;570.3925319534696;576.1300436341123;593.5192494079797;21839.563208735835;22526.88736556317;22686.43283917903;22736.65895011572;22757.886210568922;22993.770062299373;23808.642870356915;24168;24558.140465083725;24609;6274.215723034609;6415;6418.807435776934;6434;6479.1901012373455;6298.433141017307;6358.491320694333;6392.520560957923;6406.103145559626;6706.39642432181;6656.301088385722;6657.667116644148;6659.5671281366795;6660.2340730816095;6660.633665148415;6258.843528911768;6551.312112228243;6551.737930482775;6656.833579160536;6669.733210671591;5998.820035398945;6011.489023433013;6043.123747056652;6060.151578778975;6066.423689749511;27684.307892302855;28431.715682843114;28448;28455.57316640252;28475.57286640702;2033.2578608807678;2058.269314393399;2058.938231853047;2060.042080432591;2064.834813214939;18485.63028739418;18846.623067538574;19217;19223.807761922482;19225.423237302763;2352.8353015288767;2365.113082594027;2418.492116655487;2428.3929017745563;2428.5992811186134;118253;124463.377683111;124819.37590312082;131422;142165;14115.717685646436;14326;14415.42338306456;14544.490942817163;14716.411343546353;22225.777742222694;22241.221557245743;22256.55486890253;22259.443513912283;22277.108915643516;10184.643537476153;10357.482125893675;10369.68890933258;10404.635837745642;10482.580696772196;9661.275404344702;9682.90317096834;9727.902720972841;9784.951075244579;9831.213502919749;8821.735347939572;8969.551522423853;8974.326925480484;9011.324150688724;9052.728418147335;2760.7239276072232;2763.1434255380896;2773.209635253943;2780.263230243987;2905;900.8378491871526;937.2501998401252;948.3551185193996;960.870282511866;969.2246203037682;19181.424557263304;19369;19406.902965485366;19618.80381196198;20008.799912000984;1013.2046343620223;1039.0907955538903;1041.8280983637615;1043.155044414027;1050.8844027157006;9719.9028009719;10752.139828813677;10886.945565272123;10950.671479855617;11196.608118715805;481705.5914720404;492940;521621;524276;538558;120065;120919;125052;125889.37055314665;126014;1096.9852886080344;1135.1089394825024;1142.702897246705;1196.1447564991074;1220.5178954312996;19094.427167185007;19553.60892782165;19712.01439927969;19949.301774437827;20016.399508014783;58689;59007.40992590019;59614;61414.38585614086;62494.37505624886;77825;78132.60933695296;78322.21677783149;78352.21647783677;78475.60762196152;18573.721394179367;19187.232510699414;19251.51871203231;19316.454683624994;19326.226950921806;21915.232966846084;21949.451263717896;21995.450113747283;22014.779852201915;22096.116155353608;17624.735628965318;17759.378421755177;17829.286828526714;17870.285188592312;17873.821261787736;15981.68036639284;16209.837901621304;16342.10118443497;16491.58771030734;16653.084080375695;18122.637547249244;18869.811301886806;19127.23491060342;19143.138558765168;19198.13608387598;19460.513487162934;19748.111334989673;19951.800481994993;20094.49291303129;20105.19579216815;17996.460106196835;18234.908825455786;18297.359592414203;18356.632867342847;18361.54096147607 05:52:06 INFO - PID 3742 | |3;mootools.html;1304.6216597186817;1374.628850210444;1379.910305830121;1380.620329409413;1384.079587074595;15518.612034699137;16179.919100404511;16217.513474595751;16221.756673649905;16237.756433653474;15739.527814165593;15786.684266314696;15871.365145394198;15919.522414327559;15928.12395318257;2711.783057355407;2742.8765705543246;2771.6673999120135;2773.334399744067;2773.5423655096884;2726.209399274208;2748.2991837081518;2755.834649921001;2757.7518023377893;2759.1308737747645;2712.6473558437374;2714.7285271472892;2735.4392349568293;2735.6717193936656;2739.055026016031;2644.4578861333475;2668.1595297481326;2673.652425184733;2676.0633778177676;2676.2372723773633;2560.1807421625085;2587.2626301503983;2587.922362329133;2590.5078035173256;2594.040205124097;14457.421703131862;14661.48684796027;14706.70586588273;14771;14794.112353258839;7827.373810095179;8150.714724984657;8153.633086511121;8158.388120890957;8160.224778646019;5112.514311140436;5162.406323272834;5413.566914646819;5430.5384042356445;5468.070428027245;1972.80271972803;1976.0811222781476;2021.3935819254166;2323.802476789475;2495.6755621769234;26903.32741681454;27106.72893271062;27144.45711085767;27155.321116972038;27209.72790272092;6660.433863121689;6775.4918381121615;6953.304669533006;7011.8597628047155;7027.262137475594;16989.23548440323;17076.658466830595;17080.48758537246;17100.657986840193;17108.828911710723;7542.547447153188;7677.500962437423;7680.5391676499585;7683.193264707237;7683.807904802425;6935.653217339112;7025.578465292099;7030.789076327718;7038.296170383023;7061.223265440797;5241.9737901310245;5382.085045542267;5391.245225668409;5397.136458166675;5418.431064738145;1464.4727897956727;1482.629342664334;1488.5087920986048;1509.6980603879267;1510.0713061467338;875.3741075131185;906.5648488725449;908.3096846396786;917.27535247154;921.2721949659693;7678.500897441649;7691.423143264277;7732.30409263169;7753.263439973193;7830.530168189927;720.3768740039933;723.884178531443;742.7586034538797;982.4940155819759;1021.341234903483;5189.273501709764;5227.790888364499;5270.78916843322;5405.7567409466665;5437.918431223456;20687.8965605171;21584.568308633738;22183.001764920617;22208.666869996963;22212.333629991124;20841.79158208429;21044.368668939955;21676.13295468195;21759.564808704054;21858;9780.853287200698;10130.848037279447;11235.606753763579;11366.545338186379;11706.356150411639;9030.729078127666;9053.637854485747;9070.773230669287;9131.0412406699;9185.954070229605;17595.736063958793;17895.642087157925;18324.541886452946;18687;18697.439076827715;18617;18644;18877.05614719286;18910.52723681919;19046.904765476083;9619.134277915022;9838.508074596099;9869.506524673594;9887.555060022172;9896.406215627085;13896.861031389557;13905;13932.930335348663;14014;14060.296985150497;11424.600138995427;11461.426928653367;11585.88414115848;11595.304281743123;11914.106442017057;11125.777484450427;11224.94387528057;11353.886461135284;11405.087592992375;11541.653750387502;9764.9023509764;10100.343477673927;10213.285070045025;10236.641717539851;10248.180145588483;7430.405567554474;7474.7757567273065;7479.551226926402;7541.924580754122;7545.207753185837;7584.62076896164;7587.279208475186;7630.313271805342;7641.159472457914;7717.845643086995 05:52:06 INFO - PID 3742 | |4;prototype.html;512.3288492075379;528.3422139436402;534.5723421262993;535.1918602909606;536.8818859850832;6317.210348706411;6375.61746295222;6379.553431259814;6398.9680051599735;6451.709673064712;6258.624482531051;6260.968695156518;6267.925848198662;6294.3705629437145;6318.5892916960365;4853.684510506823;5000.67495612784;5005.2492126181;5177.430482646915;5221.2429197766305;5051.974740126294;5054.85168803259;5072.949270507284;5073.771680274396;5075.720835354054;4968.751562421882;4978.377702787152;4989.501049895;4992.850214493571;5048;4977.452480227172;4992.151334273182;5009.624278179114;5023.472535383768;5026.165449610902;4512.616427603658;4513.548645135493;4517.34498497716;4535.614472769818;4552.066826300634;6120.693965301717;6131.969340153315;6238.157848690459;6243.65659888706;6286.151369565142;5586.469285417878;5667.149927510865;5670.574706896958;5670.858228544278;5688.345840228385;6532.902006469907;6553.836154096138;6558.967205163944;6562.901556476657;6581;4881.194602890519;4886.389201349832;4894.238074293252;4910.8281210157475;4912.533309335608;6591.406773390418;6725.865482690368;6804.6257455839905;6878.209005964378;6899.827504312382;5013.448520662724;5014.812631452778;5019.146745053276;5019.29729837823;5026.924596131061;5043.646944713835;5056.646034777604;5072.137736584789;5084.669496482717;5086.16078347076;5644.266245388156;5656;5670.376258611549;5692.23154874095;5696.800611978644;6593.439557637558;6602.646457476206;6614.735410583619;6632.535722499379;6640.402363787282;1752.2115048228093;1759.727242277449;1760.331074191799;1768.938087166943;1772.3619496981075;1173.9471723772451;1213.6225384189008;1218.451696736472;1228.7788198124424;1240.3736113262926;5775.797847075416;5777;5797.768089276382;5809.215755872988;5816.796412125555;6682.431993280626;6754.223264324665;6759.594424334556;6775.830604234933;6804.115464989619;5779.248697669276;5801;5807.709614519257;5815.621984570989;5844.26946631671;3564.144605294711;3574.410222313289;3592.3533763922746;3617.04148400674;3618.909527261787;6510.414062734377;6511.609303441809;6515.631717339317;6580.934190658128;6580.9470484722215;6602;6938.722451102;6938.8612227756175;6949.791506254821;6972.546784458994;6517.706703198368;6522.804315870531;6533.836654083686;6592.538522303393;6594.714030764147;6693.464522838064;6699.8325041872395;6734.3602357775935;6748.966255168692;6782.559133656297;6600.735970561316;6676.566023208476;6705.597664140168;6705.698243579148;6718.462522998246;6528.281888992112;6554.541182117397;6564.901526477011;6580.565190879405;6612.933870661424;4984.476629953948;4986.32684587576;5001.549860512571;5018.42288136869;5034.798608055637;6478.902816457663;6617.470602351897;6670.866582668416;6787.217240931194;6821.28376520478;6488.9026664601015;6501.512386570931;6557.9179435394535;6561.770338038145;6566.179227596551;6487.3188315226225;6512.218533775977;6539.70571324282;6561.212654481493;6596.835079122869;6468.935310647022;6498.155239818793;6510.544261901621;6524.739010439529;6548.738050477928;6525.477961763143;6572.65260621581;6605.471562275103;6626.801195963936;6646.546813634973;6321.841953951188;6334.841628959313;6349.396807303298;6368.395002474757;6368.617882926854 05:52:06 INFO - PID 3742 | |5;yui.html;904.8506996345602;907.7186072317578;914.0813482450137;919.2875521470859;933.7852293972388;19420.51448713783;19962.70055949162;20030.399088027363;20056.899715501437;20061.699074513894;18403.17185726642;18597;18607.255709771594;18615.255389784394;18615.34846280377;909.9954500227506;913.5614904845678;917.2661870503604;918.5177781664613;918.6646873891049;965.4352203960691;968.6319198704482;968.8449848024318;968.8982656821038;972.5526257921365;992.776625259318;1015.4364327798069;1022.1209759606733;1027.2501074215827;1027.5992362978445;1010.2776514791923;1016.7559785651428;1017.1862509991977;1019.0522813783252;1020.6376736258633;713.304528085118;727.8398752274492;729.3581648149648;734.673070483635;736.7752835385205;18143;18199.181036853242;18257.17842697082;18258.178381972844;18285;21201.8939905301;21746.456338591503;21821;22374.776252237432;22447.102115915506;17652.470425887244;17809.643807123914;17828.91085544564;17933.551661208574;18005.189766460542;3818.8281527331337;3820.6752426043818;3839.1361943562756;3842.711796615265;3849.595792441781;96295.0370496293;140276;143276.28361858125;143683.28158359247;145503.27248363796;17309.7403538947;17781.555461113574;17905.641887162183;17923.820761792475;17959;31615.20961975969;32169.51745723816;32189;32189.51715724266;32223.51664725031;8558.871616925751;8666.220040196287;8704.869426958601;8711.607977640895;8740.606672699743;4008;4074.3277359235985;4074.4499492568125;4078.0416602098485;4080.6327430531396;5224.425313215619;5300.9734951325;5305.734713264322;5311.867203319871;5315.468453154731;1115.6931843743037;1117.3128525956479;1151.3782557419067;1152;1154.7517283784027;687.8521117959663;698.9271468296141;700.7512333121771;709.042792230495;717.0678118446103;11747;11750.70623234409;11829.17195796286;11878;11953.70115747096;689.4381079420257;696.6481926627021;696.8083776961377;704.6758491094051;708.1785129250045;5835.883282334329;5856.677882716492;5870.941290587125;5915.408459154136;6087.482563982111;120391.39804301098;123024.38487807683;125143;126562.36718816531;127444;96171;96321;97006.51496742471;97275;98495.50752246194;92493.53753231325;92984.53507732418;94758.52620736852;95986.0401395991;97474.51262743642;10944.945275273572;10967.287126336763;11060.502277397698;11116.22186446941;11326.603568875049;44082.77958610187;44495;44599.33101003423;44995.77502112468;45009.09981800411;55026.724866375414;55686.44313556812;56072;57695;57839;15221;15663.921680391524;15720.135392553519;15725.370985160465;15746.842531574537;17966.910165449088;18093.276268949096;18219.54451138732;18290.451286461426;18474.630507390048;16052.357905684108;16254.51236462908;16275.837241627905;16286.59283517874;18397.72403413923;18790.436286911416;18956.336528221997;18992.62014759725;19546.511337216114;19689.21243150258;17986.460406187834;18060.277588896297;19124.713129302792;19297.710534342277;20072.799272007094;13252.403641835948;13411.798823017858;13455.394507247001;13547.051706380458;13674.247916364298;13627.863721362659;14106.576802695936;14280.357383917957;14842.109473431628;15087.547373578811 05:52:06 INFO - PID 3742 | __end_tp_report 05:52:06 INFO - PID 3742 | __start_cc_report 05:52:06 INFO - PID 3742 | _x_x_mozilla_cycle_collect,376 05:52:06 INFO - PID 3742 | __end_cc_report 05:52:06 INFO - PID 3742 | __startTimestamp1504788726245__endTimestamp 05:52:06 INFO - PID 3742 | 05:52:06 INFO - PID 3742 | ------- Summary: start ------- 05:52:06 INFO - PID 3742 | Number of tests: 6 05:52:06 INFO - PID 3742 | 05:52:06 INFO - PID 3742 | [#0] dojo.html Cycles:165 Average:6279.77 Median:6500.54 stddev:728.13 (11.2%) stddev-sans-first:691.03 05:52:06 INFO - PID 3742 | Values: 3270.2 3279.8 3304.8 3309.7 3316.4 6137.8 6378.2 6399.1 6479.6 6508.4 6288.3 6385.9 6500.5 6516.2 6542.4 5857.6 5905.3 5934.2 5957.3 6001.3 5859.3 5933.0 5964.0 6036.2 6047.9 5668.9 5771.7 5800.6 5867.2 5932.8 5893.0 5902.6 5908.3 5908.7 5916.5 5823.0 5881.3 5881.6 5908.1 5925.1 6343.5 6376.6 6385.0 6414.8 6476.9 6626.3 6776.2 6790.7 6855.4 6891.3 6948.4 6955.7 6965.0 6979.0 6990.0 6099.6 6241.2 6248.0 6256.1 6363.9 6951.4 6955.8 6971.5 6977.7 6983.3 6612.8 6635.4 6636.9 6659.6 6665.5 6706.4 6710.8 6713.2 6717.3 6734.7 6674.5 6686.3 6812.0 6829.5 6906.6 6845.8 6855.7 6921.6 6927.9 6930.1 5211.1 5301.6 5423.8 5485.5 5486.4 4718.0 4783.2 4806.5 4812.6 4859.8 5949.0 5951.0 6015.8 6021.1 6030.1 6571.8 6916.9 6927.2 7005.8 7021.3 5868.1 5877.5 5883.6 5886.1 5887.7 5861.1 5869.1 5870.9 5871.3 5877.8 6496.5 6549.4 6561.2 6565.9 6579.0 5934.3 6776.4 6792.8 6901.6 6991.9 6638.5 6656.0 6673.9 6748.2 6763.8 6449.9 6516.8 6769.6 6840.6 6843.6 6769.8 6790.9 6791.9 6805.8 6806.7 6557.3 6589.0 6644.3 6667.7 6676.7 6713.8 6733.9 6756.2 6764.8 6769.6 6267.8 6373.2 6447.4 6454.8 6584.6 6641.4 6728.3 6759.9 6775.4 6793.4 6326.5 6405.4 6476.2 6498.0 6581.1 05:52:06 INFO - PID 3742 | 05:52:06 INFO - PID 3742 | [#1] ext.html Cycles:180 Average:37947.71 Median:3545.27 stddev:54486.51 (1536.9%) stddev-sans-first:54568.07 05:52:06 INFO - PID 3742 | Values: 828.7 835.2 860.2 874.9 881.5 40784.2 41495.4 41498.8 41618.8 41709.6 38131.0 38589.8 38602.8 38610.0 38617.8 7043.4 7150.8 7158.3 7169.1 7169.4 7014.9 7105.0 7114.3 7115.9 7119.5 7634.1 7861.6 7868.3 7871.2 7873.4 7738.9 7813.9 7814.5 7814.5 7821.2 7478.3 7672.3 7682.3 7686.2 7808.7 36578.0 36889.4 36975.6 36996.6 37124.4 3529.6 3561.0 3561.9 3562.7 3567.5 30668.4 30753.4 30806.7 30943.5 30999.8 5193.6 5195.6 5220.2 5224.2 5231.4 191579.0 191637.0 192521.0 193556.0 194695.0 47298.8 48226.5 48313.8 48334.0 48346.5 49184.8 49244.8 49284.5 49299.5 49300.8 16225.6 16501.3 16511.9 16545.6 16576.2 13626.1 13631.9 13647.2 13649.0 13666.1 14202.4 14242.3 14257.3 14419.2 14439.0 5591.8 5650.6 5771.7 5772.5 5823.4 1001.8 1011.1 1017.7 1017.9 1028.5 22782.3 23051.1 23083.4 23226.0 23440.9 1121.1 1121.4 1123.0 1123.3 1126.0 12237.4 12265.7 12275.0 12344.9 12471.7 232745.0 235018.0 235633.0 235656.0 235862.0 52327.0 52643.0 53006.7 55604.7 55710.0 1405.6 1443.4 1452.7 1452.9 1457.1 26717.2 26748.3 26888.9 27239.9 27346.7 36435.6 36455.3 36497.3 36575.3 36600.3 199977.0 201006.0 201421.0 201451.0 201691.0 32088.5 32089.2 32105.7 32424.4 32458.2 44638.3 45422.1 45878.5 45960.5 46044.8 32606.7 32618.5 32701.2 33721.5 33753.8 33314.2 33385.7 33399.5 33638.3 34098.1 36042.0 36463.8 36489.1 36494.1 36643.5 32424.0 32678.5 32831.7 33282.3 33834.0 24270.4 24318.8 24681.4 24806.9 24945.9 05:52:06 INFO - PID 3742 | 05:52:06 INFO - PID 3742 | [#2] jquery.html Cycles:180 Average:35745.92 Median:22233.50 stddev:86004.54 (386.8%) stddev-sans-first:86205.21 05:52:06 INFO - PID 3742 | Values: 552.6 569.7 570.4 576.1 593.5 21839.6 22526.9 22686.4 22736.7 22757.9 22993.8 23808.6 24168.0 24558.1 24609.0 6274.2 6415.0 6418.8 6434.0 6479.2 6298.4 6358.5 6392.5 6406.1 6706.4 6656.3 6657.7 6659.6 6660.2 6660.6 6258.8 6551.3 6551.7 6656.8 6669.7 5998.8 6011.5 6043.1 6060.2 6066.4 27684.3 28431.7 28448.0 28455.6 28475.6 2033.3 2058.3 2058.9 2060.0 2064.8 18485.6 18846.6 19217.0 19223.8 19225.4 2352.8 2365.1 2418.5 2428.4 2428.6 118253.0 124463.4 124819.4 131422.0 142165.0 14115.7 14326.0 14415.4 14544.5 14716.4 22225.8 22241.2 22256.6 22259.4 22277.1 10184.6 10357.5 10369.7 10404.6 10482.6 9661.3 9682.9 9727.9 9785.0 9831.2 8821.7 8969.6 8974.3 9011.3 9052.7 2760.7 2763.1 2773.2 2780.3 2905.0 900.8 937.3 948.4 960.9 969.2 19181.4 19369.0 19406.9 19618.8 20008.8 1013.2 1039.1 1041.8 1043.2 1050.9 9719.9 10752.1 10886.9 10950.7 11196.6 481705.6 492940.0 521621.0 524276.0 538558.0 120065.0 120919.0 125052.0 125889.4 126014.0 1097.0 1135.1 1142.7 1196.1 1220.5 19094.4 19553.6 19712.0 19949.3 20016.4 58689.0 59007.4 59614.0 61414.4 62494.4 77825.0 78132.6 78322.2 78352.2 78475.6 18573.7 19187.2 19251.5 19316.5 19326.2 21915.2 21949.5 21995.5 22014.8 22096.1 17624.7 17759.4 17829.3 17870.3 17873.8 15981.7 16209.8 16342.1 16491.6 16653.1 18122.6 18869.8 19127.2 19143.1 19198.1 19460.5 19748.1 19951.8 20094.5 20105.2 17996.5 18234.9 18297.4 18356.6 18361.5 05:52:06 INFO - PID 3742 | 05:52:06 INFO - PID 3742 | [#3] mootools.html Cycles:180 Average:9348.66 Median:14909.26 stddev:6707.47 (45.0%) stddev-sans-first:6699.06 05:52:06 INFO - PID 3742 | Values: 1304.6 1374.6 1379.9 1380.6 1384.1 15518.6 16179.9 16217.5 16221.8 16237.8 15739.5 15786.7 15871.4 15919.5 15928.1 2711.8 2742.9 2771.7 2773.3 2773.5 2726.2 2748.3 2755.8 2757.8 2759.1 2712.6 2714.7 2735.4 2735.7 2739.1 2644.5 2668.2 2673.7 2676.1 2676.2 2560.2 2587.3 2587.9 2590.5 2594.0 14457.4 14661.5 14706.7 14771.0 14794.1 7827.4 8150.7 8153.6 8158.4 8160.2 5112.5 5162.4 5413.6 5430.5 5468.1 1972.8 1976.1 2021.4 2323.8 2495.7 26903.3 27106.7 27144.5 27155.3 27209.7 6660.4 6775.5 6953.3 7011.9 7027.3 16989.2 17076.7 17080.5 17100.7 17108.8 7542.5 7677.5 7680.5 7683.2 7683.8 6935.7 7025.6 7030.8 7038.3 7061.2 5242.0 5382.1 5391.2 5397.1 5418.4 1464.5 1482.6 1488.5 1509.7 1510.1 875.4 906.6 908.3 917.3 921.3 7678.5 7691.4 7732.3 7753.3 7830.5 720.4 723.9 742.8 982.5 1021.3 5189.3 5227.8 5270.8 5405.8 5437.9 20687.9 21584.6 22183.0 22208.7 22212.3 20841.8 21044.4 21676.1 21759.6 21858.0 9780.9 10130.8 11235.6 11366.5 11706.4 9030.7 9053.6 9070.8 9131.0 9186.0 17595.7 17895.6 18324.5 18687.0 18697.4 18617.0 18644.0 18877.1 18910.5 19046.9 9619.1 9838.5 9869.5 9887.6 9896.4 13896.9 13905.0 13932.9 14014.0 14060.3 11424.6 11461.4 11585.9 11595.3 11914.1 11125.8 11224.9 11353.9 11405.1 11541.7 9764.9 10100.3 10213.3 10236.6 10248.2 7430.4 7474.8 7479.6 7541.9 7545.2 7584.6 7587.3 7630.3 7641.2 7717.8 05:52:06 INFO - PID 3742 | 05:52:06 INFO - PID 3742 | [#4] prototype.html Cycles:180 Average:5539.04 Median:6264.45 stddev:1548.97 (24.7%) stddev-sans-first:1506.67 05:52:06 INFO - PID 3742 | Values: 512.3 528.3 534.6 535.2 536.9 6317.2 6375.6 6379.6 6399.0 6451.7 6258.6 6261.0 6267.9 6294.4 6318.6 4853.7 5000.7 5005.2 5177.4 5221.2 5052.0 5054.9 5072.9 5073.8 5075.7 4968.8 4978.4 4989.5 4992.9 5048.0 4977.5 4992.2 5009.6 5023.5 5026.2 4512.6 4513.5 4517.3 4535.6 4552.1 6120.7 6132.0 6238.2 6243.7 6286.2 5586.5 5667.1 5670.6 5670.9 5688.3 6532.9 6553.8 6559.0 6562.9 6581.0 4881.2 4886.4 4894.2 4910.8 4912.5 6591.4 6725.9 6804.6 6878.2 6899.8 5013.4 5014.8 5019.1 5019.3 5026.9 5043.6 5056.6 5072.1 5084.7 5086.2 5644.3 5656.0 5670.4 5692.2 5696.8 6593.4 6602.6 6614.7 6632.5 6640.4 1752.2 1759.7 1760.3 1768.9 1772.4 1173.9 1213.6 1218.5 1228.8 1240.4 5775.8 5777.0 5797.8 5809.2 5816.8 6682.4 6754.2 6759.6 6775.8 6804.1 5779.2 5801.0 5807.7 5815.6 5844.3 3564.1 3574.4 3592.4 3617.0 3618.9 6510.4 6511.6 6515.6 6580.9 6580.9 6602.0 6938.7 6938.9 6949.8 6972.5 6517.7 6522.8 6533.8 6592.5 6594.7 6693.5 6699.8 6734.4 6749.0 6782.6 6600.7 6676.6 6705.6 6705.7 6718.5 6528.3 6554.5 6564.9 6580.6 6612.9 4984.5 4986.3 5001.5 5018.4 5034.8 6478.9 6617.5 6670.9 6787.2 6821.3 6488.9 6501.5 6557.9 6561.8 6566.2 6487.3 6512.2 6539.7 6561.2 6596.8 6468.9 6498.2 6510.5 6524.7 6548.7 6525.5 6572.7 6605.5 6626.8 6646.5 6321.8 6334.8 6349.4 6368.4 6368.6 05:52:06 INFO - PID 3742 | 05:52:06 INFO - PID 3742 | [#5] yui.html Cycles:180 Average:24243.55 Median:20059.30 stddev:34177.19 (170.4%) stddev-sans-first:34228.14 05:52:06 INFO - PID 3742 | Values: 904.9 907.7 914.1 919.3 933.8 19420.5 19962.7 20030.4 20056.9 20061.7 18403.2 18597.0 18607.3 18615.3 18615.3 910.0 913.6 917.3 918.5 918.7 965.4 968.6 968.8 968.9 972.6 992.8 1015.4 1022.1 1027.3 1027.6 1010.3 1016.8 1017.2 1019.1 1020.6 713.3 727.8 729.4 734.7 736.8 18143.0 18199.2 18257.2 18258.2 18285.0 21201.9 21746.5 21821.0 22374.8 22447.1 17652.5 17809.6 17828.9 17933.6 18005.2 3818.8 3820.7 3839.1 3842.7 3849.6 96295.0 140276.0 143276.3 143683.3 145503.3 17309.7 17781.6 17905.6 17923.8 17959.0 31615.2 32169.5 32189.0 32189.5 32223.5 8558.9 8666.2 8704.9 8711.6 8740.6 4008.0 4074.3 4074.4 4078.0 4080.6 5224.4 5301.0 5305.7 5311.9 5315.5 1115.7 1117.3 1151.4 1152.0 1154.8 687.9 698.9 700.8 709.0 717.1 11747.0 11750.7 11829.2 11878.0 11953.7 689.4 696.6 696.8 704.7 708.2 5835.9 5856.7 5870.9 5915.4 6087.5 120391.4 123024.4 125143.0 126562.4 127444.0 96171.0 96321.0 97006.5 97275.0 98495.5 92493.5 92984.5 94758.5 95986.0 97474.5 10944.9 10967.3 11060.5 11116.2 11326.6 44082.8 44495.0 44599.3 44995.8 45009.1 55026.7 55686.4 56072.0 57695.0 57839.0 15221.0 15663.9 15720.1 15725.4 15746.8 17966.9 18093.3 18219.5 18290.5 18474.6 16052.4 16254.5 16275.8 16286.6 18397.7 18790.4 18956.3 18992.6 19546.5 19689.2 17986.5 18060.3 19124.7 19297.7 20072.8 13252.4 13411.8 13455.4 13547.1 13674.2 13627.9 14106.6 14280.4 14842.1 15087.5 05:52:06 INFO - PID 3742 | -------- Summary: end -------- 05:52:06 INFO - PID 3742 | 05:52:06 INFO - TEST-INFO | 3742: exit 0 05:52:06 INFO - TEST-OK | dromaeo_css | took 1112522ms 05:52:06 INFO - TEST-START | kraken 05:52:06 INFO - Initialising browser for kraken test... 05:52:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46643/getInfo.html -profile /tmp/tmp_PO8IQ/profile 05:52:06 INFO - TEST-INFO | started process 4170 (/builds/slave/test/build/application/firefox/firefox http://localhost:46643/getInfo.html) 05:52:12 INFO - TEST-INFO | 4170: exit 0 05:52:12 INFO - Browser initialized. 05:52:12 INFO - Running cycle 1/1 for kraken test... 05:52:12 INFO - Using env: {'DISPLAY': ':0', 05:52:12 INFO - 'HOME': '/home/cltbld', 05:52:12 INFO - 'JSGC_DISABLE_POISONING': '1', 05:52:12 INFO - 'LANG': 'en_US.UTF-8', 05:52:12 INFO - 'LANGUAGE': 'en_US:en', 05:52:12 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 05:52:12 INFO - 'LOGNAME': 'cltbld', 05:52:12 INFO - 'MAIL': '/var/mail/cltbld', 05:52:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:52:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:52:12 INFO - 'MOZ_CRASHREPORTER': '1', 05:52:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 05:52:12 INFO - 'MOZ_NO_REMOTE': '1', 05:52:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:52:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:12 INFO - 'NO_EM_RESTART': '1', 05:52:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:12 INFO - 'PWD': '/builds/slave/test', 05:52:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:52:12 INFO - 'RUST_BACKTRACE': 'full', 05:52:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:52:12 INFO - 'SHELL': '/bin/bash', 05:52:12 INFO - 'SHLVL': '1', 05:52:12 INFO - 'TERM': 'linux', 05:52:12 INFO - 'TMOUT': '86400', 05:52:12 INFO - 'USER': 'cltbld', 05:52:12 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236', 05:52:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:12 INFO - '_': '/tools/buildbot/bin/python'} 05:52:12 INFO - TEST-INFO | started process 4368 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_PO8IQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:52:13 INFO - PID 4368 | 05:52:13 INFO - PID 4368 | (/builds/slave/test/build/application/firefox/firefox:4418): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:52:13 INFO - PID 4368 | 05:52:23 INFO - PID 4368 | RSS: Main: 166834176 05:52:23 INFO - PID 4368 | 05:52:26 INFO - PID 4368 | Cycle 1(1): loaded http://localhost:46643/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46643/tests/kraken/driver.html?testName=audio-beat-detection) 05:52:27 INFO - PID 4368 | RSS: Main: 172556288 05:52:27 INFO - PID 4368 | 05:52:30 INFO - PID 4368 | Cycle 1(1): loaded http://localhost:46643/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46643/tests/kraken/driver.html?testName=audio-dft) 05:52:30 INFO - PID 4368 | RSS: Main: 173965312 05:52:30 INFO - PID 4368 | 05:52:33 INFO - PID 4368 | Cycle 1(1): loaded http://localhost:46643/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46643/tests/kraken/driver.html?testName=audio-fft) 05:52:34 INFO - PID 4368 | RSS: Main: 168210432 05:52:34 INFO - PID 4368 | 05:52:36 INFO - PID 4368 | Cycle 1(1): loaded http://localhost:46643/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46643/tests/kraken/driver.html?testName=audio-oscillator) 05:52:37 INFO - PID 4368 | RSS: Main: 168972288 05:52:37 INFO - PID 4368 | 05:52:39 INFO - PID 4368 | Cycle 1(1): loaded http://localhost:46643/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46643/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:52:39 INFO - PID 4368 | RSS: Main: 169082880 05:52:39 INFO - PID 4368 | 05:52:43 INFO - PID 4368 | Cycle 1(1): loaded http://localhost:46643/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46643/tests/kraken/driver.html?testName=imaging-darkroom) 05:52:43 INFO - PID 4368 | RSS: Main: 171720704 05:52:43 INFO - PID 4368 | 05:52:47 INFO - PID 4368 | Cycle 1(1): loaded http://localhost:46643/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46643/tests/kraken/driver.html?testName=imaging-desaturate) 05:52:47 INFO - PID 4368 | RSS: Main: 171806720 05:52:47 INFO - PID 4368 | 05:52:50 INFO - PID 4368 | Cycle 1(1): loaded http://localhost:46643/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46643/tests/kraken/driver.html?testName=json-parse-financial) 05:52:51 INFO - PID 4368 | RSS: Main: 172253184 05:52:51 INFO - PID 4368 | 05:52:53 INFO - PID 4368 | Cycle 1(1): loaded http://localhost:46643/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46643/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:52:53 INFO - PID 4368 | RSS: Main: 171794432 05:52:53 INFO - PID 4368 | 05:52:55 INFO - PID 4368 | Cycle 1(1): loaded http://localhost:46643/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46643/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:52:55 INFO - PID 4368 | RSS: Main: 172478464 05:52:55 INFO - PID 4368 | 05:52:58 INFO - PID 4368 | Cycle 1(1): loaded http://localhost:46643/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46643/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:52:58 INFO - PID 4368 | RSS: Main: 172855296 05:52:58 INFO - PID 4368 | 05:53:01 INFO - PID 4368 | Cycle 1(1): loaded http://localhost:46643/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46643/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:53:02 INFO - PID 4368 | RSS: Main: 173330432 05:53:02 INFO - PID 4368 | 05:53:05 INFO - PID 4368 | Cycle 1(1): loaded http://localhost:46643/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46643/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:53:05 INFO - PID 4368 | RSS: Main: 173047808 05:53:05 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | Cycle 1(1): loaded http://localhost:46643/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46643/tests/kraken/driver.html?testName=ai-astar) 05:53:07 INFO - PID 4368 | RSS: Main: 174489600 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | __start_tp_report 05:53:07 INFO - PID 4368 | _x_x_mozilla_page_load 05:53:07 INFO - PID 4368 | _x_x_mozilla_page_load_details 05:53:07 INFO - PID 4368 | |i|pagename|runs| 05:53:07 INFO - PID 4368 | |0;ai-astar;107;108;106;107;106;109;110;106;105;112 05:53:07 INFO - PID 4368 | |1;audio-beat-detection;117;115;120;115;115;115;119;116;116;140 05:53:07 INFO - PID 4368 | |2;audio-dft;163;165;163;163;163;163;168;167;163;163 05:53:07 INFO - PID 4368 | |3;audio-fft;75;75;75;74;73;73;74;75;161;74 05:53:07 INFO - PID 4368 | |4;audio-oscillator;79;79;79;79;79;78;78;78;78;78 05:53:07 INFO - PID 4368 | |5;imaging-gaussian-blur;110;113;114;114;114;114;115;114;110;114 05:53:07 INFO - PID 4368 | |6;imaging-darkroom;147;148;147;148;147;149;148;150;150;154 05:53:07 INFO - PID 4368 | |7;imaging-desaturate;93;94;95;94;94;94;93;94;95;95 05:53:07 INFO - PID 4368 | |8;json-parse-financial;54;51;52;52;53;54;52;53;52;54 05:53:07 INFO - PID 4368 | |9;json-stringify-tinderbox;37;38;38;38;38;37;37;38;38;37 05:53:07 INFO - PID 4368 | |10;stanford-crypto-aes;84;76;78;75;76;76;76;75;75;76 05:53:07 INFO - PID 4368 | |11;stanford-crypto-ccm;123;130;122;118;123;120;117;125;121;118 05:53:07 INFO - PID 4368 | |12;stanford-crypto-pbkdf2;170;174;189;179;172;166;166;182;170;168 05:53:07 INFO - PID 4368 | |13;stanford-crypto-sha256-iterative;65;63;69;65;63;69;68;65;70;66 05:53:07 INFO - PID 4368 | __end_tp_report 05:53:07 INFO - PID 4368 | __start_cc_report 05:53:07 INFO - PID 4368 | _x_x_mozilla_cycle_collect,937 05:53:07 INFO - PID 4368 | __end_cc_report 05:53:07 INFO - PID 4368 | __startTimestamp1504788787725__endTimestamp 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | ------- Summary: start ------- 05:53:07 INFO - PID 4368 | Number of tests: 14 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | [#0] ai-astar Cycles:10 Average:107.60 Median:107.50 stddev:2.17 (2.0%) stddev-sans-first:2.29 05:53:07 INFO - PID 4368 | Values: 107.0 108.0 106.0 107.0 106.0 109.0 110.0 106.0 105.0 112.0 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | [#1] audio-beat-detection Cycles:10 Average:118.80 Median:116.50 stddev:7.66 (6.6%) stddev-sans-first:8.09 05:53:07 INFO - PID 4368 | Values: 117.0 115.0 120.0 115.0 115.0 115.0 119.0 116.0 116.0 140.0 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | [#2] audio-dft Cycles:10 Average:164.10 Median:163.00 stddev:1.91 (1.2%) stddev-sans-first:1.99 05:53:07 INFO - PID 4368 | Values: 163.0 165.0 163.0 163.0 163.0 163.0 168.0 167.0 163.0 163.0 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | [#3] audio-fft Cycles:10 Average:82.90 Median:74.50 stddev:27.45 (36.8%) stddev-sans-first:28.97 05:53:07 INFO - PID 4368 | Values: 75.0 75.0 75.0 74.0 73.0 73.0 74.0 75.0 161.0 74.0 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | [#4] audio-oscillator Cycles:10 Average:78.50 Median:79.00 stddev:0.53 (0.7%) stddev-sans-first:0.53 05:53:07 INFO - PID 4368 | Values: 79.0 79.0 79.0 79.0 79.0 78.0 78.0 78.0 78.0 78.0 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | [#5] imaging-gaussian-blur Cycles:10 Average:113.20 Median:114.00 stddev:1.75 (1.5%) stddev-sans-first:1.42 05:53:07 INFO - PID 4368 | Values: 110.0 113.0 114.0 114.0 114.0 114.0 115.0 114.0 110.0 114.0 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:148.50 stddev:2.15 (1.4%) stddev-sans-first:2.18 05:53:07 INFO - PID 4368 | Values: 147.0 148.0 147.0 148.0 147.0 149.0 148.0 150.0 150.0 154.0 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 05:53:07 INFO - PID 4368 | Values: 93.0 94.0 95.0 94.0 94.0 94.0 93.0 94.0 95.0 95.0 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:1.06 (2.0%) stddev-sans-first:1.01 05:53:07 INFO - PID 4368 | Values: 54.0 51.0 52.0 52.0 53.0 54.0 52.0 53.0 52.0 54.0 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | [#9] json-stringify-tinderbox Cycles:10 Average:37.60 Median:38.00 stddev:0.52 (1.4%) stddev-sans-first:0.50 05:53:07 INFO - PID 4368 | Values: 37.0 38.0 38.0 38.0 38.0 37.0 37.0 38.0 38.0 37.0 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | [#10] stanford-crypto-aes Cycles:10 Average:76.70 Median:76.00 stddev:2.71 (3.6%) stddev-sans-first:0.93 05:53:07 INFO - PID 4368 | Values: 84.0 76.0 78.0 75.0 76.0 76.0 76.0 75.0 75.0 76.0 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | [#11] stanford-crypto-ccm Cycles:10 Average:121.70 Median:122.50 stddev:3.89 (3.2%) stddev-sans-first:4.10 05:53:07 INFO - PID 4368 | Values: 123.0 130.0 122.0 118.0 123.0 120.0 117.0 125.0 121.0 118.0 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.60 Median:173.00 stddev:7.55 (4.4%) stddev-sans-first:7.89 05:53:07 INFO - PID 4368 | Values: 170.0 174.0 189.0 179.0 172.0 166.0 166.0 182.0 170.0 168.0 05:53:07 INFO - PID 4368 | 05:53:07 INFO - PID 4368 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:67.00 stddev:2.54 (3.8%) stddev-sans-first:2.65 05:53:07 INFO - PID 4368 | Values: 65.0 63.0 69.0 65.0 63.0 69.0 68.0 65.0 70.0 66.0 05:53:07 INFO - PID 4368 | -------- Summary: end -------- 05:53:07 INFO - PID 4368 | 05:53:08 INFO - PID 4368 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:53:08 INFO - TEST-INFO | 4368: exit 0 05:53:08 INFO - TEST-OK | kraken | took 61534ms 05:53:08 INFO - SUITE-END | took 1174s 05:53:08 INFO - Completed test suite (00:19:34) 05:53:08 INFO - javascript benchmark 05:53:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8596.6122777201, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3270.2314955985325, 3279.803211807291, 3304.7851889627173, 3309.685579869913, 3316.403047451458, 6137.785177518787, 6378.170837791086, 6399.104125422445, 6479.611223326598, 6508.414242718154, 6288.339724328948, 6385.872282554346, 6500.544961852672, 6516.185476815398, 6542.378474044958, 5857.589968702193, 5905.291365036202, 5934.228550288456, 5957.344692083867, 6001.309849367313, 5859.326177489579, 5932.961731696957, 5963.956307646166, 6036.215292012054, 6047.909281360783, 5668.943310566903, 5771.740271687784, 5800.622959507618, 5867.2372591563035, 5932.762689492415, 5892.970535147297, 5902.6458412495385, 5908.261467316585, 5908.67502287374, 5916.4675179233645, 5823.039198532237, 5881.294244690622, 5881.558883083786, 5908.14331921869, 5925.081612350093, 6343.524235682342, 6376.553641245115, 6385.0, 6414.775482858077, 6476.935230647728, 6626.304238055031, 6776.186857577073, 6790.694418751168, 6855.4172895303955, 6891.345322194384, 6948.44412447003, 6955.6869940852785, 6964.965175174142, 6979.022936788853, 6989.965050174716, 6099.634021958653, 6241.188645476104, 6248.031555108965, 6256.092866534327, 6363.9045414318825, 6951.443884489228, 6955.756548520778, 6971.51199416036, 6977.651117444209, 6983.336583024605, 6612.768553100618, 6635.369639884203, 6636.8672626547195, 6659.567128136656, 6665.500087493458, 6706.396424321834, 6710.832229194211, 6713.160854893138, 6717.261101278856, 6734.730610775514, 6674.466042716571, 6686.264510903797, 6811.965940170367, 6829.52193346461, 6906.620135892575, 6845.794626161223, 6855.691493882788, 6921.550099243533, 6927.861442771116, 6930.13373328334, 5211.140161873324, 5301.628885977944, 5423.783048678088, 5485.50630443262, 5486.423925487767, 4718.0, 4783.234682450791, 4806.543378379049, 4812.639052071038, 4859.781309841065, 5949.048152295699, 5951.047832346804, 6015.789447369321, 6021.126936594169, 6030.095485677183, 6571.769988050395, 6916.861662766716, 6927.203371612229, 7005.824854378681, 7021.332973367522, 5868.09044598088, 5877.500412464989, 5883.617564858356, 5886.1465087562065, 5887.676177810262, 5861.062230043172, 5869.119632055141, 5870.941290587125, 5871.266091738533, 5877.764889404461, 6496.512761542903, 6549.410553050248, 6561.245456772437, 6565.934340656628, 6578.967105164444, 5934.347221805684, 6776.390124888783, 6792.7962161134255, 6901.585904845725, 6991.930080699128, 6638.468922486093, 6656.0, 6673.9332606673315, 6748.223954245128, 6763.7632682855865, 6449.903251451138, 6516.771912983023, 6769.559978601375, 6840.623765692736, 6843.555168914005, 6769.763058292936, 6790.864182716219, 6791.93208067913, 6805.795826125224, 6806.693698783468, 6557.278699343164, 6589.0, 6644.302348253557, 6667.733290668319, 6676.732930682719, 6713.832154195989, 6733.932660673527, 6756.155480564929, 6764.830879228059, 6769.627670478171, 6267.780627678009, 6373.203349581302, 6447.4197322241225, 6454.838629034311, 6584.572002819801, 6641.402273795382, 6728.3271672832325, 6759.898601521079, 6775.390214880685, 6793.422559082534, 6326.4622507087415, 6405.423511883952, 6476.190476190476, 6498.0, 6581.111549940888], "value": 6227.192594618152, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [828.6726742936544, 835.2190701693917, 860.1613426908765, 874.8687696845473, 881.4667126388539, 40784.18431631366, 41495.37756933641, 41498.792506037425, 41618.79190604047, 41709.58290417095, 38131.0, 38589.80705096471, 38602.8069859651, 38610.0, 38617.806910965475, 7043.366097051252, 7150.785476435715, 7158.319959603856, 7169.068021157243, 7169.390601798853, 7014.894776578355, 7104.964475177617, 7114.324139206793, 7115.928840711578, 7119.466040046991, 7634.083909930816, 7861.56761378124, 7868.25251601097, 7871.173526779663, 7873.409494287923, 7738.883916741253, 7813.921860781376, 7814.452988290822, 7814.531128132274, 7821.178776228471, 7478.32695057442, 7672.271134242238, 7682.347000504964, 7686.1929497402525, 7808.726694565663, 36578.0, 36889.44665829988, 36975.63024369749, 36996.630033699585, 37124.44313335302, 3529.5588051493564, 3560.9821950890337, 3561.857525698969, 3562.71498280137, 3567.518385018015, 30668.38663226723, 30753.38493230123, 30806.691933080605, 30943.535846962313, 30999.845000775076, 5193.5585475234275, 5195.610329225286, 5220.2430647556275, 5224.190250511138, 5231.398389185254, 191579.0, 191637.0, 192521.0, 193556.0, 194695.0, 47298.76350618225, 48226.517734822904, 48313.75843120762, 48334.0, 48346.5165348342, 49184.75407622939, 49244.753776231606, 49284.50715492871, 49299.50700492949, 49300.75349623229, 16225.594360140854, 16501.33994640225, 16511.917440412722, 16545.586360341087, 16576.171191440622, 13626.1143025703, 13631.931840340734, 13647.181169129686, 13649.0, 13666.11170273929, 14202.431902723776, 14242.287885605678, 14257.287135643177, 14419.206943617992, 14438.989270750946, 5591.797763480871, 5650.604457688005, 5771.653700777967, 5772.538196944234, 5823.388544202882, 1001.8046480936148, 1011.0546638892658, 1017.6590842067842, 1017.862588550551, 1028.454918892988, 22782.31653050411, 23051.077956881538, 23083.422914427272, 23226.0, 23440.882795585912, 1121.1311233793874, 1121.4000509727257, 1123.039800970175, 1123.3203911633507, 1125.997861902907, 12237.449314780857, 12265.69335766613, 12275.0, 12344.876551234373, 12471.688207794878, 232745.0, 235018.0, 235633.0, 235656.0, 235862.0, 52327.0, 52643.0, 53006.73496632569, 55604.72197638986, 55710.0, 1405.592378210328, 1443.4082026369138, 1452.6731485415696, 1452.8642894944626, 1457.1330058615108, 26717.198484045508, 26748.331291717863, 26888.865555672095, 27239.86380068166, 27346.726532735214, 36435.635643643225, 36455.27089458249, 36497.27005459823, 36575.26849463049, 36600.26799464049, 199977.0, 201006.0, 201421.0, 201451.0, 201691.0, 32088.518672219467, 32089.197770055936, 32105.67894321027, 32424.35151297008, 32458.188545286557, 44638.330425043, 45422.09155816799, 45878.54121458743, 45960.54039459697, 46044.769776150904, 32606.673933260365, 32618.51072233966, 32701.182470437478, 33721.49417758785, 33753.83123084467, 33314.16714582155, 33385.666143338254, 33399.49900751539, 33638.32723345568, 34098.14754631154, 36042.0, 36463.81768091142, 36489.08777280589, 36494.087647809014, 36643.45034824427, 32424.0, 32678.50982235316, 32831.67168328286, 33282.33435331328, 33834.0, 24270.393240169138, 24318.81130698175, 24681.382965426008, 24806.875965620056, 24945.875270623532], "value": 17238.617284573153, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.5772783820378, 569.712295290878, 570.3925319534696, 576.1300436341123, 593.5192494079797, 21839.563208735835, 22526.88736556317, 22686.43283917903, 22736.65895011572, 22757.886210568922, 22993.770062299373, 23808.642870356915, 24168.0, 24558.140465083725, 24609.0, 6274.215723034609, 6415.0, 6418.807435776934, 6434.0, 6479.1901012373455, 6298.433141017307, 6358.491320694333, 6392.520560957923, 6406.103145559626, 6706.39642432181, 6656.301088385722, 6657.667116644148, 6659.5671281366795, 6660.2340730816095, 6660.633665148415, 6258.843528911768, 6551.312112228243, 6551.737930482775, 6656.833579160536, 6669.733210671591, 5998.820035398945, 6011.489023433013, 6043.123747056652, 6060.151578778975, 6066.423689749511, 27684.307892302855, 28431.715682843114, 28448.0, 28455.57316640252, 28475.57286640702, 2033.2578608807678, 2058.269314393399, 2058.938231853047, 2060.042080432591, 2064.834813214939, 18485.63028739418, 18846.623067538574, 19217.0, 19223.807761922482, 19225.423237302763, 2352.8353015288767, 2365.113082594027, 2418.492116655487, 2428.3929017745563, 2428.5992811186134, 118253.0, 124463.377683111, 124819.37590312082, 131422.0, 142165.0, 14115.717685646436, 14326.0, 14415.42338306456, 14544.490942817163, 14716.411343546353, 22225.777742222694, 22241.221557245743, 22256.55486890253, 22259.443513912283, 22277.108915643516, 10184.643537476153, 10357.482125893675, 10369.68890933258, 10404.635837745642, 10482.580696772196, 9661.275404344702, 9682.90317096834, 9727.902720972841, 9784.951075244579, 9831.213502919749, 8821.735347939572, 8969.551522423853, 8974.326925480484, 9011.324150688724, 9052.728418147335, 2760.7239276072232, 2763.1434255380896, 2773.209635253943, 2780.263230243987, 2905.0, 900.8378491871526, 937.2501998401252, 948.3551185193996, 960.870282511866, 969.2246203037682, 19181.424557263304, 19369.0, 19406.902965485366, 19618.80381196198, 20008.799912000984, 1013.2046343620223, 1039.0907955538903, 1041.8280983637615, 1043.155044414027, 1050.8844027157006, 9719.9028009719, 10752.139828813677, 10886.945565272123, 10950.671479855617, 11196.608118715805, 481705.5914720404, 492940.0, 521621.0, 524276.0, 538558.0, 120065.0, 120919.0, 125052.0, 125889.37055314665, 126014.0, 1096.9852886080344, 1135.1089394825024, 1142.702897246705, 1196.1447564991074, 1220.5178954312996, 19094.427167185007, 19553.60892782165, 19712.01439927969, 19949.301774437827, 20016.399508014783, 58689.0, 59007.40992590019, 59614.0, 61414.38585614086, 62494.37505624886, 77825.0, 78132.60933695296, 78322.21677783149, 78352.21647783677, 78475.60762196152, 18573.721394179367, 19187.232510699414, 19251.51871203231, 19316.454683624994, 19326.226950921806, 21915.232966846084, 21949.451263717896, 21995.450113747283, 22014.779852201915, 22096.116155353608, 17624.735628965318, 17759.378421755177, 17829.286828526714, 17870.285188592312, 17873.821261787736, 15981.68036639284, 16209.837901621304, 16342.10118443497, 16491.58771030734, 16653.084080375695, 18122.637547249244, 18869.811301886806, 19127.23491060342, 19143.138558765168, 19198.13608387598, 19460.513487162934, 19748.111334989673, 19951.800481994993, 20094.49291303129, 20105.19579216815, 17996.460106196835, 18234.908825455786, 18297.359592414203, 18356.632867342847, 18361.54096147607], "value": 12141.404265496933, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1304.6216597186817, 1374.628850210444, 1379.910305830121, 1380.620329409413, 1384.079587074595, 15518.612034699137, 16179.919100404511, 16217.513474595751, 16221.756673649905, 16237.756433653474, 15739.527814165593, 15786.684266314696, 15871.365145394198, 15919.522414327559, 15928.12395318257, 2711.783057355407, 2742.8765705543246, 2771.6673999120135, 2773.334399744067, 2773.5423655096884, 2726.209399274208, 2748.2991837081518, 2755.834649921001, 2757.7518023377893, 2759.1308737747645, 2712.6473558437374, 2714.7285271472892, 2735.4392349568293, 2735.6717193936656, 2739.055026016031, 2644.4578861333475, 2668.1595297481326, 2673.652425184733, 2676.0633778177676, 2676.2372723773633, 2560.1807421625085, 2587.2626301503983, 2587.922362329133, 2590.5078035173256, 2594.040205124097, 14457.421703131862, 14661.48684796027, 14706.70586588273, 14771.0, 14794.112353258839, 7827.373810095179, 8150.714724984657, 8153.633086511121, 8158.388120890957, 8160.224778646019, 5112.514311140436, 5162.406323272834, 5413.566914646819, 5430.5384042356445, 5468.070428027245, 1972.80271972803, 1976.0811222781476, 2021.3935819254166, 2323.802476789475, 2495.6755621769234, 26903.32741681454, 27106.72893271062, 27144.45711085767, 27155.321116972038, 27209.72790272092, 6660.433863121689, 6775.4918381121615, 6953.304669533006, 7011.8597628047155, 7027.262137475594, 16989.23548440323, 17076.658466830595, 17080.48758537246, 17100.657986840193, 17108.828911710723, 7542.547447153188, 7677.500962437423, 7680.5391676499585, 7683.193264707237, 7683.807904802425, 6935.653217339112, 7025.578465292099, 7030.789076327718, 7038.296170383023, 7061.223265440797, 5241.9737901310245, 5382.085045542267, 5391.245225668409, 5397.136458166675, 5418.431064738145, 1464.4727897956727, 1482.629342664334, 1488.5087920986048, 1509.6980603879267, 1510.0713061467338, 875.3741075131185, 906.5648488725449, 908.3096846396786, 917.27535247154, 921.2721949659693, 7678.500897441649, 7691.423143264277, 7732.30409263169, 7753.263439973193, 7830.530168189927, 720.3768740039933, 723.884178531443, 742.7586034538797, 982.4940155819759, 1021.341234903483, 5189.273501709764, 5227.790888364499, 5270.78916843322, 5405.7567409466665, 5437.918431223456, 20687.8965605171, 21584.568308633738, 22183.001764920617, 22208.666869996963, 22212.333629991124, 20841.79158208429, 21044.368668939955, 21676.13295468195, 21759.564808704054, 21858.0, 9780.853287200698, 10130.848037279447, 11235.606753763579, 11366.545338186379, 11706.356150411639, 9030.729078127666, 9053.637854485747, 9070.773230669287, 9131.0412406699, 9185.954070229605, 17595.736063958793, 17895.642087157925, 18324.541886452946, 18687.0, 18697.439076827715, 18617.0, 18644.0, 18877.05614719286, 18910.52723681919, 19046.904765476083, 9619.134277915022, 9838.508074596099, 9869.506524673594, 9887.555060022172, 9896.406215627085, 13896.861031389557, 13905.0, 13932.930335348663, 14014.0, 14060.296985150497, 11424.600138995427, 11461.426928653367, 11585.88414115848, 11595.304281743123, 11914.106442017057, 11125.777484450427, 11224.94387528057, 11353.886461135284, 11405.087592992375, 11541.653750387502, 9764.9023509764, 10100.343477673927, 10213.285070045025, 10236.641717539851, 10248.180145588483, 7430.405567554474, 7474.7757567273065, 7479.551226926402, 7541.924580754122, 7545.207753185837, 7584.62076896164, 7587.279208475186, 7630.313271805342, 7641.159472457914, 7717.845643086995], "value": 6673.7799289411605, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.3288492075379, 528.3422139436402, 534.5723421262993, 535.1918602909606, 536.8818859850832, 6317.210348706411, 6375.61746295222, 6379.553431259814, 6398.9680051599735, 6451.709673064712, 6258.624482531051, 6260.968695156518, 6267.925848198662, 6294.3705629437145, 6318.5892916960365, 4853.684510506823, 5000.67495612784, 5005.2492126181, 5177.430482646915, 5221.2429197766305, 5051.974740126294, 5054.85168803259, 5072.949270507284, 5073.771680274396, 5075.720835354054, 4968.751562421882, 4978.377702787152, 4989.501049895, 4992.850214493571, 5048.0, 4977.452480227172, 4992.151334273182, 5009.624278179114, 5023.472535383768, 5026.165449610902, 4512.616427603658, 4513.548645135493, 4517.34498497716, 4535.614472769818, 4552.066826300634, 6120.693965301717, 6131.969340153315, 6238.157848690459, 6243.65659888706, 6286.151369565142, 5586.469285417878, 5667.149927510865, 5670.574706896958, 5670.858228544278, 5688.345840228385, 6532.902006469907, 6553.836154096138, 6558.967205163944, 6562.901556476657, 6581.0, 4881.194602890519, 4886.389201349832, 4894.238074293252, 4910.8281210157475, 4912.533309335608, 6591.406773390418, 6725.865482690368, 6804.6257455839905, 6878.209005964378, 6899.827504312382, 5013.448520662724, 5014.812631452778, 5019.146745053276, 5019.29729837823, 5026.924596131061, 5043.646944713835, 5056.646034777604, 5072.137736584789, 5084.669496482717, 5086.16078347076, 5644.266245388156, 5656.0, 5670.376258611549, 5692.23154874095, 5696.800611978644, 6593.439557637558, 6602.646457476206, 6614.735410583619, 6632.535722499379, 6640.402363787282, 1752.2115048228093, 1759.727242277449, 1760.331074191799, 1768.938087166943, 1772.3619496981075, 1173.9471723772451, 1213.6225384189008, 1218.451696736472, 1228.7788198124424, 1240.3736113262926, 5775.797847075416, 5777.0, 5797.768089276382, 5809.215755872988, 5816.796412125555, 6682.431993280626, 6754.223264324665, 6759.594424334556, 6775.830604234933, 6804.115464989619, 5779.248697669276, 5801.0, 5807.709614519257, 5815.621984570989, 5844.26946631671, 3564.144605294711, 3574.410222313289, 3592.3533763922746, 3617.04148400674, 3618.909527261787, 6510.414062734377, 6511.609303441809, 6515.631717339317, 6580.934190658128, 6580.9470484722215, 6602.0, 6938.722451102, 6938.8612227756175, 6949.791506254821, 6972.546784458994, 6517.706703198368, 6522.804315870531, 6533.836654083686, 6592.538522303393, 6594.714030764147, 6693.464522838064, 6699.8325041872395, 6734.3602357775935, 6748.966255168692, 6782.559133656297, 6600.735970561316, 6676.566023208476, 6705.597664140168, 6705.698243579148, 6718.462522998246, 6528.281888992112, 6554.541182117397, 6564.901526477011, 6580.565190879405, 6612.933870661424, 4984.476629953948, 4986.32684587576, 5001.549860512571, 5018.42288136869, 5034.798608055637, 6478.902816457663, 6617.470602351897, 6670.866582668416, 6787.217240931194, 6821.28376520478, 6488.9026664601015, 6501.512386570931, 6557.9179435394535, 6561.770338038145, 6566.179227596551, 6487.3188315226225, 6512.218533775977, 6539.70571324282, 6561.212654481493, 6596.835079122869, 6468.935310647022, 6498.155239818793, 6510.544261901621, 6524.739010439529, 6548.738050477928, 6525.477961763143, 6572.65260621581, 6605.471562275103, 6626.801195963936, 6646.546813634973, 6321.841953951188, 6334.841628959313, 6349.396807303298, 6368.395002474757, 6368.617882926854], "value": 5084.852868408854, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [904.8506996345602, 907.7186072317578, 914.0813482450137, 919.2875521470859, 933.7852293972388, 19420.51448713783, 19962.70055949162, 20030.399088027363, 20056.899715501437, 20061.699074513894, 18403.17185726642, 18597.0, 18607.255709771594, 18615.255389784394, 18615.34846280377, 909.9954500227506, 913.5614904845678, 917.2661870503604, 918.5177781664613, 918.6646873891049, 965.4352203960691, 968.6319198704482, 968.8449848024318, 968.8982656821038, 972.5526257921365, 992.776625259318, 1015.4364327798069, 1022.1209759606733, 1027.2501074215827, 1027.5992362978445, 1010.2776514791923, 1016.7559785651428, 1017.1862509991977, 1019.0522813783252, 1020.6376736258633, 713.304528085118, 727.8398752274492, 729.3581648149648, 734.673070483635, 736.7752835385205, 18143.0, 18199.181036853242, 18257.17842697082, 18258.178381972844, 18285.0, 21201.8939905301, 21746.456338591503, 21821.0, 22374.776252237432, 22447.102115915506, 17652.470425887244, 17809.643807123914, 17828.91085544564, 17933.551661208574, 18005.189766460542, 3818.8281527331337, 3820.6752426043818, 3839.1361943562756, 3842.711796615265, 3849.595792441781, 96295.0370496293, 140276.0, 143276.28361858125, 143683.28158359247, 145503.27248363796, 17309.7403538947, 17781.555461113574, 17905.641887162183, 17923.820761792475, 17959.0, 31615.20961975969, 32169.51745723816, 32189.0, 32189.51715724266, 32223.51664725031, 8558.871616925751, 8666.220040196287, 8704.869426958601, 8711.607977640895, 8740.606672699743, 4008.0, 4074.3277359235985, 4074.4499492568125, 4078.0416602098485, 4080.6327430531396, 5224.425313215619, 5300.9734951325, 5305.734713264322, 5311.867203319871, 5315.468453154731, 1115.6931843743037, 1117.3128525956479, 1151.3782557419067, 1152.0, 1154.7517283784027, 687.8521117959663, 698.9271468296141, 700.7512333121771, 709.042792230495, 717.0678118446103, 11747.0, 11750.70623234409, 11829.17195796286, 11878.0, 11953.70115747096, 689.4381079420257, 696.6481926627021, 696.8083776961377, 704.6758491094051, 708.1785129250045, 5835.883282334329, 5856.677882716492, 5870.941290587125, 5915.408459154136, 6087.482563982111, 120391.39804301098, 123024.38487807683, 125143.0, 126562.36718816531, 127444.0, 96171.0, 96321.0, 97006.51496742471, 97275.0, 98495.50752246194, 92493.53753231325, 92984.53507732418, 94758.52620736852, 95986.0401395991, 97474.51262743642, 10944.945275273572, 10967.287126336763, 11060.502277397698, 11116.22186446941, 11326.603568875049, 44082.77958610187, 44495.0, 44599.33101003423, 44995.77502112468, 45009.09981800411, 55026.724866375414, 55686.44313556812, 56072.0, 57695.0, 57839.0, 15221.0, 15663.921680391524, 15720.135392553519, 15725.370985160465, 15746.842531574537, 17966.910165449088, 18093.276268949096, 18219.54451138732, 18290.451286461426, 18474.630507390048, 16052.357905684108, 16254.51236462908, 16275.837241627905, 16286.59283517874, 18397.72403413923, 18790.436286911416, 18956.336528221997, 18992.62014759725, 19546.511337216114, 19689.21243150258, 17986.460406187834, 18060.277588896297, 19124.713129302792, 19297.710534342277, 20072.799272007094, 13252.403641835948, 13411.798823017858, 13455.394507247001, 13547.051706380458, 13674.247916364298, 13627.863721362659, 14106.576802695936, 14280.357383917957, 14842.109473431628, 15087.547373578811], "value": 9124.823201032807, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1436.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 108.0, 106.0, 107.0, 106.0, 109.0, 110.0, 106.0, 105.0, 112.0], "value": 107.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 115.0, 120.0, 115.0, 115.0, 115.0, 119.0, 116.0, 116.0, 140.0], "value": 118.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 163.0, 163.0, 163.0, 163.0, 168.0, 167.0, 163.0, 163.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 74.0, 73.0, 73.0, 74.0, 75.0, 161.0, 74.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 79.0, 79.0, 78.0, 78.0, 78.0, 78.0, 78.0], "value": 78.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 114.0, 114.0, 114.0, 114.0, 115.0, 114.0, 110.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 148.0, 147.0, 149.0, 148.0, 150.0, 150.0, 154.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 94.0, 94.0, 94.0, 93.0, 94.0, 95.0, 95.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 52.0, 52.0, 53.0, 54.0, 52.0, 53.0, 52.0, 54.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 38.0, 38.0, 38.0, 37.0, 37.0, 38.0, 38.0, 37.0], "value": 37.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 76.0, 78.0, 75.0, 76.0, 76.0, 76.0, 75.0, 75.0, 76.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 130.0, 122.0, 118.0, 123.0, 120.0, 117.0, 125.0, 121.0, 118.0], "value": 121.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 174.0, 189.0, 179.0, 172.0, 166.0, 166.0, 182.0, 170.0, 168.0], "value": 173.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 69.0, 65.0, 63.0, 69.0, 68.0, 65.0, 70.0, 66.0], "value": 66.3, "unit": "score"}]}]} 05:53:08 INFO - Return code: 0 05:53:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:53:08 INFO - # TBPL SUCCESS # 05:53:08 INFO - Running post-action listener: _package_coverage_data 05:53:08 INFO - Running post-action listener: _resource_record_post_action 05:53:08 INFO - [mozharness: 2017-09-07 12:53:08.993749Z] Finished run-tests step (success) 05:53:08 INFO - Running post-run listener: _resource_record_post_run 05:53:09 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 9166848; Write bytes: 407269376; Read time: 2988; Write time: 353356 05:53:09 INFO - TinderboxPrint: CPU usage
13.1% 05:53:09 INFO - TinderboxPrint: I/O read bytes / time
9,166,848 / 2,988 05:53:09 INFO - TinderboxPrint: I/O write bytes / time
407,269,376 / 353,356 05:53:09 INFO - TinderboxPrint: CPU idle
8,249.8 (86.9%) 05:53:09 INFO - TinderboxPrint: CPU user
1,194.8 (12.6%) 05:53:09 INFO - TinderboxPrint: Swap in / out
0 / 0 05:53:09 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 118714368; Read time: 120; Write time: 107748 05:53:09 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:53:09 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 7417856; Write bytes: 288555008; Read time: 2836; Write time: 245608 05:53:09 INFO - Running post-run listener: _upload_blobber_files 05:53:09 INFO - Blob upload gear active. 05:53:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:53:09 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 05:53:09 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'] 05:53:09 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 05:53:10 INFO - (blobuploader) - INFO - Open directory for files ... 05:53:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:53:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:53:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:13 INFO - (blobuploader) - INFO - Done attempting. 05:53:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:53:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:53:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:14 INFO - (blobuploader) - INFO - Done attempting. 05:53:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:53:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:53:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:15 INFO - (blobuploader) - INFO - Done attempting. 05:53:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:53:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:53:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:16 INFO - (blobuploader) - INFO - Done attempting. 05:53:16 INFO - (blobuploader) - INFO - Iteration through files over. 05:53:16 INFO - Return code: 0 05:53:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:53:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:53:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e79fc89548401b76eec355a65411656f14fea0207df63fee6cb100fbb8ebd5ecdbeb8d9e8c0d8c89c25faea97912f417546d91224a7f431669663b0801402add", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0650c3ac2d5dc6504e1b70f6b1330516b9bdd8386613270b7b905b3ee8f3418d36e6ed54a5d383616969e1ddc0783208a0c4671ff778950bd5c29d9952fa77e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0195d607426821ebb8ca4328f58f449c23bf8687e34bac2f296b14230990e8213479bdf96dd5f16133da5e250b4f53b33d3fb0791bc2521eb57e7e0a3de6a8a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0be6d3455448049607a4bbb3e79fedc458217fe9ee04d1b40fb88b59ae81a3953d018b8b65f56a5214707221e98a0960ac82fa416df79d29c68eff3ca900be2"} 05:53:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:53:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:53:16 INFO - Contents: 05:53:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e79fc89548401b76eec355a65411656f14fea0207df63fee6cb100fbb8ebd5ecdbeb8d9e8c0d8c89c25faea97912f417546d91224a7f431669663b0801402add", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0650c3ac2d5dc6504e1b70f6b1330516b9bdd8386613270b7b905b3ee8f3418d36e6ed54a5d383616969e1ddc0783208a0c4671ff778950bd5c29d9952fa77e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0195d607426821ebb8ca4328f58f449c23bf8687e34bac2f296b14230990e8213479bdf96dd5f16133da5e250b4f53b33d3fb0791bc2521eb57e7e0a3de6a8a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0be6d3455448049607a4bbb3e79fedc458217fe9ee04d1b40fb88b59ae81a3953d018b8b65f56a5214707221e98a0960ac82fa416df79d29c68eff3ca900be2"} 05:53:16 INFO - Running post-run listener: copy_logs_to_upload_dir 05:53:16 INFO - Copying logs to upload dir... 05:53:16 INFO - mkdir: /builds/slave/test/build/upload/logs 05:53:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.862893 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-07 05:53:16.592151) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 05:53:16.595733) ========= 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=03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e79fc89548401b76eec355a65411656f14fea0207df63fee6cb100fbb8ebd5ecdbeb8d9e8c0d8c89c25faea97912f417546d91224a7f431669663b0801402add", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0650c3ac2d5dc6504e1b70f6b1330516b9bdd8386613270b7b905b3ee8f3418d36e6ed54a5d383616969e1ddc0783208a0c4671ff778950bd5c29d9952fa77e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0195d607426821ebb8ca4328f58f449c23bf8687e34bac2f296b14230990e8213479bdf96dd5f16133da5e250b4f53b33d3fb0791bc2521eb57e7e0a3de6a8a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0be6d3455448049607a4bbb3e79fedc458217fe9ee04d1b40fb88b59ae81a3953d018b8b65f56a5214707221e98a0960ac82fa416df79d29c68eff3ca900be2"} build_url:https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011283 build_url: 'https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e79fc89548401b76eec355a65411656f14fea0207df63fee6cb100fbb8ebd5ecdbeb8d9e8c0d8c89c25faea97912f417546d91224a7f431669663b0801402add", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0650c3ac2d5dc6504e1b70f6b1330516b9bdd8386613270b7b905b3ee8f3418d36e6ed54a5d383616969e1ddc0783208a0c4671ff778950bd5c29d9952fa77e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0195d607426821ebb8ca4328f58f449c23bf8687e34bac2f296b14230990e8213479bdf96dd5f16133da5e250b4f53b33d3fb0791bc2521eb57e7e0a3de6a8a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0be6d3455448049607a4bbb3e79fedc458217fe9ee04d1b40fb88b59ae81a3953d018b8b65f56a5214707221e98a0960ac82fa416df79d29c68eff3ca900be2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 05:53:16.624375) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:53:16.624717) ========= 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=03627c79f6bb3d1578c4a402000001ba-1504787540.156682-1975288236 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004454 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:53:16.709476) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 05:53:16.709809) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 05:53:16.710129) ========= ========= Total master_lag: 0.15 =========