builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-058 starttime: 1504049362.67 results: success (0) revision: 8f221407c6006ac1de529da77ecc18431dde3e99 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:22.673103) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:22.675586) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:22.675940) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:22.696729) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:22.697109) ========= 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=0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-29 16:29: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% 42.2M=0s 2017-08-29 16:29:22 (42.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124710 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:22.842955) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:22.843288) ========= 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=0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021155 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:22.907487) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-29 16:29:22.907849) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8f221407c6006ac1de529da77ecc18431dde3e99 --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 8f221407c6006ac1de529da77ecc18431dde3e99 --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=0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-29 16:29:22,962 truncating revision to first 12 chars 2017-08-29 16:29:22,962 Setting DEBUG logging. 2017-08-29 16:29:22,962 attempt 1/10 2017-08-29 16:29:22,962 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8f221407c600?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-29 16:29:23,645 unpacking tar archive at: mozilla-central-8f221407c600/testing/mozharness/ program finished with exit code 0 elapsedTime=0.995051 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-29 16:29:23.919574) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:23.919895) ========= script_repo_revision: 8f221407c6006ac1de529da77ecc18431dde3e99 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:23.920268) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:23.920524) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:23.931140) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-29 16:29:23.931413) ========= /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=0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:29:24 INFO - MultiFileLogger online at 20170829 16:29:24 in /builds/slave/test 16:29:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:29:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:29:24 INFO - {'append_to_log': False, 16:29:24 INFO - 'base_work_dir': '/builds/slave/test', 16:29:24 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:29:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:29:24 INFO - 'branch': 'Firefox-Non-PGO', 16:29:24 INFO - 'buildbot_json_path': 'buildprops.json', 16:29:24 INFO - 'code_coverage': False, 16:29:24 INFO - 'config_files': ('talos/linux_config.py',), 16:29:24 INFO - 'default_actions': ('clobber', 16:29:24 INFO - 'read-buildbot-config', 16:29:24 INFO - 'download-and-extract', 16:29:24 INFO - 'populate-webroot', 16:29:24 INFO - 'create-virtualenv', 16:29:24 INFO - 'install', 16:29:24 INFO - 'setup-mitmproxy', 16:29:24 INFO - 'run-tests'), 16:29:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:29:24 INFO - 'disable_ccov_upload': False, 16:29:24 INFO - 'disable_stylo': False, 16:29:24 INFO - 'download_minidump_stackwalk': True, 16:29:24 INFO - 'download_symbols': 'ondemand', 16:29:24 INFO - 'enable_stylo': False, 16:29:24 INFO - 'enable_webrender': False, 16:29:24 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:29:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:29:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:29:24 INFO - 'gecko_profile': False, 16:29:24 INFO - 'gecko_profile_interval': 0, 16:29:24 INFO - 'installer_path': 'installer.exe', 16:29:24 INFO - 'log_level': 'info', 16:29:24 INFO - 'log_name': 'talos', 16:29:24 INFO - 'log_to_console': True, 16:29:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:29:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:29:24 INFO - 'opt_config_files': (), 16:29:24 INFO - 'pip_index': False, 16:29:24 INFO - 'suite': 'dromaeojs-e10s', 16:29:24 INFO - 'system_bits': '32', 16:29:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:29:24 INFO - 'title': 'talos-linux64-ix-058', 16:29:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:29:24 INFO - 'use_talos_json': True, 16:29:24 INFO - 'verify': 'False', 16:29:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:29:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:29:24 INFO - 'work_dir': 'build'} 16:29:24 INFO - [mozharness: 2017-08-29 23:29:24.130134Z] Running clobber step. 16:29:24 INFO - Running pre-action listener: _resource_record_pre_action 16:29:24 INFO - Running main action method: clobber 16:29:24 INFO - rmtree: /builds/slave/test/build 16:29:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:29:25 INFO - Running post-action listener: _resource_record_post_action 16:29:25 INFO - [mozharness: 2017-08-29 23:29:25.157791Z] Finished clobber step (success) 16:29:25 INFO - [mozharness: 2017-08-29 23:29:25.157888Z] Running read-buildbot-config step. 16:29:25 INFO - Running pre-action listener: _resource_record_pre_action 16:29:25 INFO - Running main action method: read_buildbot_config 16:29:25 INFO - Using buildbot properties: 16:29:25 INFO - { 16:29:25 INFO - "project": "", 16:29:25 INFO - "product": "firefox", 16:29:25 INFO - "who": "kwierso@gmail.com", 16:29:25 INFO - "installer_path": "public/build/target.tar.bz2", 16:29:25 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:29:25 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:29:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 16:29:25 INFO - "stage_platform": "linux64", 16:29:25 INFO - "basedir": "/builds/slave/test", 16:29:25 INFO - "buildnumber": 1105, 16:29:25 INFO - "platform": "ubuntu64_hw", 16:29:25 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:29:25 INFO - "slavebuilddir": "test", 16:29:25 INFO - "taskId": "aTi11z_GS4-GGmo21yurGg", 16:29:25 INFO - "branch": "mozilla-central", 16:29:25 INFO - "script_repo_revision": "production", 16:29:25 INFO - "revision": "8f221407c6006ac1de529da77ecc18431dde3e99", 16:29:25 INFO - "slavename": "talos-linux64-ix-058", 16:29:25 INFO - "repo_path": "mozilla-central" 16:29:25 INFO - } 16:29:25 INFO - Finding installer, test and symbols from parent task. 16:29:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aTi11z_GS4-GGmo21yurGg'}, attempt #1 16:29:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aTi11z_GS4-GGmo21yurGg'}, attempt #1 16:29:26 INFO - Task dependencies: OLOX1rRMRjiLLTkG5J30Ew 16:29:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew'}, attempt #1 16:29:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2 16:29:26 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.test_packages.json 16:29:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.crashreporter-symbols.zip 16:29:26 INFO - Running post-action listener: _resource_record_post_action 16:29:26 INFO - [mozharness: 2017-08-29 23:29:26.480381Z] Finished read-buildbot-config step (success) 16:29:26 INFO - [mozharness: 2017-08-29 23:29:26.480596Z] Running download-and-extract step. 16:29:26 INFO - Running pre-action listener: _resource_record_pre_action 16:29:26 INFO - Running main action method: download_and_extract 16:29:26 INFO - mkdir: /builds/slave/test/build/tests 16:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:26 INFO - trying https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.test_packages.json 16:29:26 INFO - Downloading https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:29:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:29:27 INFO - Downloaded 989 bytes. 16:29:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:29:27 INFO - Using the following test package requirements: 16:29:27 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:29:27 INFO - u'common': [u'target.common.tests.zip'], 16:29:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:29:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:29:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:29:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:29:27 INFO - u'mozbase': [u'target.common.tests.zip'], 16:29:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:29:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:29:27 INFO - u'web-platform': [u'target.common.tests.zip', 16:29:27 INFO - u'target.web-platform.tests.tar.gz'], 16:29:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:29:27 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:29:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.common.tests.zip 16:29:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:29:27 INFO - Fetch https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.common.tests.zip into memory 16:29:30 INFO - Content-Length response header: 54980876 16:29:30 INFO - Bytes received: 54980876 16:29:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:29:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.common.tests.zip 16:29:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:29:41 INFO - Fetch https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.common.tests.zip into memory 16:29:42 INFO - Content-Length response header: 54980876 16:29:42 INFO - Bytes received: 54980876 16:29:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.talos.tests.zip 16:29:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:29:54 INFO - Fetch https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.talos.tests.zip into memory 16:29:55 INFO - Content-Length response header: 14496610 16:29:55 INFO - Bytes received: 14496610 16:29: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')]} 16:29:55 INFO - trying https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2 16:29:55 INFO - Downloading https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:29:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:29:57 INFO - Downloaded 62574194 bytes. 16:29:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2 16:29:57 INFO - mkdir: /builds/slave/test/properties 16:29:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:29:57 INFO - Writing to file /builds/slave/test/properties/build_url 16:29:57 INFO - Contents: 16:29:57 INFO - build_url:https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2 16:29:57 INFO - Running post-action listener: _resource_record_post_action 16:29:57 INFO - Running post-action listener: find_tests_for_verification 16:29:57 INFO - Running post-action listener: set_extra_try_arguments 16:29:57 INFO - [mozharness: 2017-08-29 23:29:57.723654Z] Finished download-and-extract step (success) 16:29:57 INFO - [mozharness: 2017-08-29 23:29:57.723882Z] Running populate-webroot step. 16:29:57 INFO - Running pre-action listener: _resource_record_pre_action 16:29:57 INFO - Running main action method: populate_webroot 16:29:57 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:29:57 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:29:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:29:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:29:57 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:29:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:29:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:29:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:57 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:29:57 INFO - 'talos_options': ['--enable-stylo'], 16:29:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:29:57 INFO - 'tests': ['damp', 'tps']}, 16:29:57 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:29:57 INFO - 'talos_options': ['--enable-stylo'], 16:29:57 INFO - 'tests': ['damp', 'tps']}, 16:29:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:29:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:29:57 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:29:57 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:29:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:29:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:29:57 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:29:57 INFO - 'talos_options': ['--enable-stylo'], 16:29:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:29:57 INFO - 'other-e10s': {'tests': ['a11yr', 16:29:57 INFO - 'ts_paint', 16:29:57 INFO - 'tpaint', 16:29:57 INFO - 'sessionrestore', 16:29:57 INFO - 'sessionrestore_many_windows', 16:29:57 INFO - 'sessionrestore_no_auto_restore', 16:29:57 INFO - 'tabpaint']}, 16:29:57 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:29:57 INFO - 'tests': ['a11yr', 16:29:57 INFO - 'ts_paint', 16:29:57 INFO - 'tpaint', 16:29:57 INFO - 'sessionrestore', 16:29:57 INFO - 'sessionrestore_many_windows', 16:29:57 INFO - 'sessionrestore_no_auto_restore', 16:29:57 INFO - 'tabpaint']}, 16:29:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:29:57 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:29:57 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:29:57 INFO - 'tests': ['bloom_basic_singleton']}, 16:29:57 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:29:57 INFO - 'tests': ['bloom_basic']}, 16:29:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:29:57 INFO - 'tsvgr_opacity', 16:29:57 INFO - 'tart', 16:29:57 INFO - 'tscrollx', 16:29:57 INFO - 'tsvg_static']}, 16:29:57 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:29:57 INFO - 'tests': ['tsvgx', 16:29:57 INFO - 'tsvgr_opacity', 16:29:57 INFO - 'tart', 16:29:57 INFO - 'tscrollx', 16:29:57 INFO - 'tsvg_static']}, 16:29:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:29:57 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:29:57 INFO - 'talos_options': ['--enable-stylo'], 16:29:57 INFO - 'tests': ['tp5o']}, 16:29:57 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:29:57 INFO - 'talos_options': ['--mitmproxy', 16:29:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:29:57 INFO - '--firstNonBlankPaint'], 16:29:57 INFO - 'tests': ['tp6_google', 16:29:57 INFO - 'tp6_youtube', 16:29:57 INFO - 'tp6_amazon', 16:29:57 INFO - 'tp6_facebook']}, 16:29:57 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:29:57 INFO - 'talos_options': ['--enable-stylo', 16:29:57 INFO - '--mitmproxy', 16:29:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:29:57 INFO - '--firstNonBlankPaint'], 16:29:57 INFO - 'tests': ['tp6_google', 16:29:57 INFO - 'tp6_youtube', 16:29:57 INFO - 'tp6_amazon', 16:29:57 INFO - 'tp6_facebook']}, 16:29:57 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:29:57 INFO - 'talos_options': ['--enable-stylo', 16:29:57 INFO - '--stylo-threads=1', 16:29:57 INFO - '--mitmproxy', 16:29:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:29:57 INFO - '--firstNonBlankPaint'], 16:29:57 INFO - 'tests': ['tp6_google', 16:29:57 INFO - 'tp6_youtube', 16:29:57 INFO - 'tp6_amazon', 16:29:57 INFO - 'tp6_facebook']}, 16:29:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:29:57 INFO - 'talos_options': ['--xperf_path', 16:29:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:57 INFO - 'tests': ['tp5n']}, 16:29:57 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:29:57 INFO - 'talos_options': ['--enable-stylo', 16:29:57 INFO - '--xperf_path', 16:29:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:57 INFO - 'tests': ['tp5n']}}} 16:29:57 INFO - Running post-action listener: _resource_record_post_action 16:29:57 INFO - [mozharness: 2017-08-29 23:29:57.740681Z] Finished populate-webroot step (success) 16:29:57 INFO - [mozharness: 2017-08-29 23:29:57.740770Z] Running create-virtualenv step. 16:29:57 INFO - Running pre-action listener: _resource_record_pre_action 16:29:57 INFO - Running main action method: create_virtualenv 16:29:57 INFO - Creating virtualenv /builds/slave/test/build/venv 16:29:57 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:29:57 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:29:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:29:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:29:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:29:58 INFO - Installing distribute.............................................................................................................................................................................................done. 16:29:58 INFO - Installing pip...............done. 16:29:58 INFO - Return code: 0 16:29:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:29:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:29:58 INFO - Reading from file tmpfile_stdout 16:29:58 INFO - Output received: 16:29: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) 16:29:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29: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')]} 16:29:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29: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 0x1ea81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2070a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214580>, '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 0x2215480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2215910>, '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': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', '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 16:29: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 16:29: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 16:29:58 INFO - Using env: {'DISPLAY': ':0', 16:29:58 INFO - 'HOME': '/home/cltbld', 16:29:58 INFO - 'LANG': 'en_US.UTF-8', 16:29:58 INFO - 'LANGUAGE': 'en_US:en', 16:29:58 INFO - 'LOGNAME': 'cltbld', 16:29:58 INFO - 'MAIL': '/var/mail/cltbld', 16:29:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:58 INFO - 'MOZ_NO_REMOTE': '1', 16:29:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:58 INFO - 'NO_EM_RESTART': '1', 16:29:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:58 INFO - 'PWD': '/builds/slave/test', 16:29:58 INFO - 'SHELL': '/bin/bash', 16:29:58 INFO - 'SHLVL': '1', 16:29:58 INFO - 'TERM': 'linux', 16:29:58 INFO - 'TMOUT': '86400', 16:29:58 INFO - 'USER': 'cltbld', 16:29:58 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', 16:29:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:58 INFO - '_': '/tools/buildbot/bin/python'} 16:30:00 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:30:00 INFO - Downloading/unpacking pip>=1.5 16:30:00 INFO - Running setup.py egg_info for package pip 16:30:00 INFO - warning: no files found matching 'pip/cacert.pem' 16:30:00 INFO - warning: no files found matching '*.html' under directory 'docs' 16:30:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:30:00 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:30:00 INFO - Installing collected packages: pip 16:30:00 INFO - Found existing installation: pip 1.1 16:30:00 INFO - Uninstalling pip: 16:30:00 INFO - Successfully uninstalled pip 16:30:00 INFO - Running setup.py install for pip 16:30:00 INFO - warning: no files found matching 'pip/cacert.pem' 16:30:00 INFO - warning: no files found matching '*.html' under directory 'docs' 16:30:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:30:00 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:30:00 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:30:00 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:30:00 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:30:00 INFO - Successfully installed pip 16:30:00 INFO - Cleaning up... 16:30:00 INFO - Return code: 0 16:30:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ea81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2070a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214580>, '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 0x2215480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2215910>, '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': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', '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 16:30:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:30:00 INFO - Using env: {'DISPLAY': ':0', 16:30:00 INFO - 'HOME': '/home/cltbld', 16:30:00 INFO - 'LANG': 'en_US.UTF-8', 16:30:00 INFO - 'LANGUAGE': 'en_US:en', 16:30:00 INFO - 'LOGNAME': 'cltbld', 16:30:00 INFO - 'MAIL': '/var/mail/cltbld', 16:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:00 INFO - 'MOZ_NO_REMOTE': '1', 16:30:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:00 INFO - 'NO_EM_RESTART': '1', 16:30:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:00 INFO - 'PWD': '/builds/slave/test', 16:30:00 INFO - 'SHELL': '/bin/bash', 16:30:00 INFO - 'SHLVL': '1', 16:30:00 INFO - 'TERM': 'linux', 16:30:00 INFO - 'TMOUT': '86400', 16:30:00 INFO - 'USER': 'cltbld', 16:30:00 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', 16:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:00 INFO - '_': '/tools/buildbot/bin/python'} 16:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:01 INFO - Downloading/unpacking psutil>=3.1.1 16:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:30:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:30:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:30:02 INFO - Installing collected packages: psutil 16:30:02 INFO - Running setup.py install for psutil 16:30:02 INFO - building 'psutil._psutil_linux' extension 16:30:02 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 16:30: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_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:30:02 INFO - building 'psutil._psutil_posix' extension 16:30:02 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 16:30: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 16:30:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:30:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:30:02 INFO - Successfully installed psutil 16:30:02 INFO - Cleaning up... 16:30:02 INFO - Return code: 0 16:30:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:30: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')]} 16:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30: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')]} 16:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30: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 0x1ea81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2070a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214580>, '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 0x2215480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2215910>, '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': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', '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 16:30: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 16:30: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 16:30:02 INFO - Using env: {'DISPLAY': ':0', 16:30:02 INFO - 'HOME': '/home/cltbld', 16:30:02 INFO - 'LANG': 'en_US.UTF-8', 16:30:02 INFO - 'LANGUAGE': 'en_US:en', 16:30:02 INFO - 'LOGNAME': 'cltbld', 16:30:02 INFO - 'MAIL': '/var/mail/cltbld', 16:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:02 INFO - 'MOZ_NO_REMOTE': '1', 16:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:02 INFO - 'NO_EM_RESTART': '1', 16:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:02 INFO - 'PWD': '/builds/slave/test', 16:30:02 INFO - 'SHELL': '/bin/bash', 16:30:02 INFO - 'SHLVL': '1', 16:30:02 INFO - 'TERM': 'linux', 16:30:02 INFO - 'TMOUT': '86400', 16:30:02 INFO - 'USER': 'cltbld', 16:30:02 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', 16:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:02 INFO - '_': '/tools/buildbot/bin/python'} 16:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:30: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 16:30: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 16:30: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 16:30: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 16:30:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:30:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:30:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:30:04 INFO - Installing collected packages: mozsystemmonitor 16:30:04 INFO - Running setup.py install for mozsystemmonitor 16:30:04 INFO - Successfully installed mozsystemmonitor 16:30:04 INFO - Cleaning up... 16:30:04 INFO - Return code: 0 16:30:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ea81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2070a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214580>, '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 0x2215480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2215910>, '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': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', '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 16:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:30:04 INFO - Using env: {'DISPLAY': ':0', 16:30:04 INFO - 'HOME': '/home/cltbld', 16:30:04 INFO - 'LANG': 'en_US.UTF-8', 16:30:04 INFO - 'LANGUAGE': 'en_US:en', 16:30:04 INFO - 'LOGNAME': 'cltbld', 16:30:04 INFO - 'MAIL': '/var/mail/cltbld', 16:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:04 INFO - 'MOZ_NO_REMOTE': '1', 16:30:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:04 INFO - 'NO_EM_RESTART': '1', 16:30:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:04 INFO - 'PWD': '/builds/slave/test', 16:30:04 INFO - 'SHELL': '/bin/bash', 16:30:04 INFO - 'SHLVL': '1', 16:30:04 INFO - 'TERM': 'linux', 16:30:04 INFO - 'TMOUT': '86400', 16:30:04 INFO - 'USER': 'cltbld', 16:30:04 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', 16:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:04 INFO - '_': '/tools/buildbot/bin/python'} 16:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:04 INFO - Downloading/unpacking jsonschema==2.5.1 16:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:30:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:30: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 16:30: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 16:30: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 16:30: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 16:30:05 INFO - Downloading functools32-3.2.3-2.tar.gz 16:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:30:05 INFO - warning: no files found matching '*.txt' 16:30:05 INFO - no previously-included directories found matching 'build' 16:30:05 INFO - no previously-included directories found matching 'dist' 16:30:05 INFO - no previously-included directories found matching '.git*' 16:30:05 INFO - Installing collected packages: jsonschema, functools32 16:30:06 INFO - Running setup.py install for functools32 16:30:06 INFO - warning: no files found matching '*.txt' 16:30:06 INFO - no previously-included directories found matching 'build' 16:30:06 INFO - no previously-included directories found matching 'dist' 16:30:06 INFO - no previously-included directories found matching '.git*' 16:30:06 INFO - Successfully installed jsonschema functools32 16:30:06 INFO - Cleaning up... 16:30:06 INFO - Return code: 0 16:30:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:30:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:06 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 0x1ea81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2070a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214580>, '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 0x2215480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2215910>, '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': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', '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 16:30:06 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 16:30:06 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 16:30:06 INFO - Using env: {'DISPLAY': ':0', 16:30:06 INFO - 'HOME': '/home/cltbld', 16:30:06 INFO - 'LANG': 'en_US.UTF-8', 16:30:06 INFO - 'LANGUAGE': 'en_US:en', 16:30:06 INFO - 'LOGNAME': 'cltbld', 16:30:06 INFO - 'MAIL': '/var/mail/cltbld', 16:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:06 INFO - 'MOZ_NO_REMOTE': '1', 16:30:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:06 INFO - 'NO_EM_RESTART': '1', 16:30:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:06 INFO - 'PWD': '/builds/slave/test', 16:30:06 INFO - 'SHELL': '/bin/bash', 16:30:06 INFO - 'SHLVL': '1', 16:30:06 INFO - 'TERM': 'linux', 16:30:06 INFO - 'TMOUT': '86400', 16:30:06 INFO - 'USER': 'cltbld', 16:30:06 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', 16:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:06 INFO - '_': '/tools/buildbot/bin/python'} 16:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:30:06 INFO - Cleaning up... 16:30:06 INFO - Return code: 0 16:30:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:30:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:06 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 0x1ea81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2070a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214580>, '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 0x2215480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2215910>, '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': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', '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 16:30:06 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 16:30:06 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 16:30:06 INFO - Using env: {'DISPLAY': ':0', 16:30:06 INFO - 'HOME': '/home/cltbld', 16:30:06 INFO - 'LANG': 'en_US.UTF-8', 16:30:06 INFO - 'LANGUAGE': 'en_US:en', 16:30:06 INFO - 'LOGNAME': 'cltbld', 16:30:06 INFO - 'MAIL': '/var/mail/cltbld', 16:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:06 INFO - 'MOZ_NO_REMOTE': '1', 16:30:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:06 INFO - 'NO_EM_RESTART': '1', 16:30:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:06 INFO - 'PWD': '/builds/slave/test', 16:30:06 INFO - 'SHELL': '/bin/bash', 16:30:06 INFO - 'SHLVL': '1', 16:30:06 INFO - 'TERM': 'linux', 16:30:06 INFO - 'TMOUT': '86400', 16:30:06 INFO - 'USER': 'cltbld', 16:30:06 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', 16:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:06 INFO - '_': '/tools/buildbot/bin/python'} 16:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:06 INFO - Downloading/unpacking blobuploader==1.2.4 16:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:07 INFO - Downloading blobuploader-1.2.4.tar.gz 16:30:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:30:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:30: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 16:30: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 16:30: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 16:30: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 16:30:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:30:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:08 INFO - Downloading docopt-0.6.1.tar.gz 16:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:30:08 INFO - Installing collected packages: blobuploader, requests, docopt 16:30:08 INFO - Running setup.py install for blobuploader 16:30:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:30:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:30:08 INFO - Running setup.py install for requests 16:30:08 INFO - Running setup.py install for docopt 16:30:08 INFO - Successfully installed blobuploader requests docopt 16:30:08 INFO - Cleaning up... 16:30:08 INFO - Return code: 0 16:30:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:30: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')]} 16:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30: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')]} 16:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30: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 0x1ea81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2070a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214580>, '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 0x2215480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2215910>, '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': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', '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 16:30: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 16:30: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 16:30:08 INFO - Using env: {'DISPLAY': ':0', 16:30:08 INFO - 'HOME': '/home/cltbld', 16:30:08 INFO - 'LANG': 'en_US.UTF-8', 16:30:08 INFO - 'LANGUAGE': 'en_US:en', 16:30:08 INFO - 'LOGNAME': 'cltbld', 16:30:08 INFO - 'MAIL': '/var/mail/cltbld', 16:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:08 INFO - 'MOZ_NO_REMOTE': '1', 16:30:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:08 INFO - 'NO_EM_RESTART': '1', 16:30:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:08 INFO - 'PWD': '/builds/slave/test', 16:30:08 INFO - 'SHELL': '/bin/bash', 16:30:08 INFO - 'SHLVL': '1', 16:30:08 INFO - 'TERM': 'linux', 16:30:08 INFO - 'TMOUT': '86400', 16:30:08 INFO - 'USER': 'cltbld', 16:30:08 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', 16:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:08 INFO - '_': '/tools/buildbot/bin/python'} 16:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:30:09 INFO - Running setup.py (path:/tmp/pip-VV7h5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:30:09 INFO - Running setup.py (path:/tmp/pip-3WOs2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:30:09 INFO - Running setup.py (path:/tmp/pip-pPLubg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:30:09 INFO - Running setup.py (path:/tmp/pip-p6i1RC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:30:09 INFO - Running setup.py (path:/tmp/pip-zw7XGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:30:09 INFO - Running setup.py (path:/tmp/pip-bXRtf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:30:09 INFO - Running setup.py (path:/tmp/pip-xfbpQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:30:09 INFO - Running setup.py (path:/tmp/pip-FMYdIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:30:09 INFO - Running setup.py (path:/tmp/pip-ID6QaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:30:09 INFO - Running setup.py (path:/tmp/pip-gPiulO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:30:09 INFO - Running setup.py (path:/tmp/pip-zj4utJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:30:09 INFO - Running setup.py (path:/tmp/pip-g2DidE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:30:09 INFO - Running setup.py (path:/tmp/pip-wdF0EV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:30:10 INFO - Running setup.py (path:/tmp/pip-Wb3Hlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:30:10 INFO - Running setup.py (path:/tmp/pip-bs0wTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:30:10 INFO - Running setup.py (path:/tmp/pip-tfv1wi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:30:10 INFO - Running setup.py (path:/tmp/pip-vF3ovw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:30:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:30:10 INFO - Running setup.py install for manifestparser 16:30:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:30:10 INFO - Running setup.py install for mozcrash 16:30:10 INFO - Running setup.py install for mozdebug 16:30:10 INFO - Running setup.py install for mozdevice 16:30:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:30:10 INFO - Running setup.py install for mozfile 16:30:10 INFO - Running setup.py install for mozhttpd 16:30:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:30:10 INFO - Running setup.py install for mozinfo 16:30:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:30:10 INFO - Running setup.py install for mozInstall 16:30:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:30:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:30:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:30:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:30:11 INFO - Running setup.py install for mozleak 16:30:11 INFO - Running setup.py install for mozlog 16:30:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:30:11 INFO - Running setup.py install for moznetwork 16:30:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:30:11 INFO - Running setup.py install for mozprocess 16:30:11 INFO - Running setup.py install for mozprofile 16:30:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:30:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:30:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:30:11 INFO - Running setup.py install for mozrunner 16:30:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:30:11 INFO - Running setup.py install for mozscreenshot 16:30:11 INFO - Running setup.py install for moztest 16:30:11 INFO - Running setup.py install for mozversion 16:30:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:30:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:30:12 INFO - Cleaning up... 16:30:12 INFO - Return code: 0 16:30:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:30:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:12 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 0x1ea81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2070a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214580>, '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 0x2215480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2215910>, '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': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', '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 16:30:12 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 16:30:12 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 16:30:12 INFO - Using env: {'DISPLAY': ':0', 16:30:12 INFO - 'HOME': '/home/cltbld', 16:30:12 INFO - 'LANG': 'en_US.UTF-8', 16:30:12 INFO - 'LANGUAGE': 'en_US:en', 16:30:12 INFO - 'LOGNAME': 'cltbld', 16:30:12 INFO - 'MAIL': '/var/mail/cltbld', 16:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:12 INFO - 'MOZ_NO_REMOTE': '1', 16:30:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:12 INFO - 'NO_EM_RESTART': '1', 16:30:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:12 INFO - 'PWD': '/builds/slave/test', 16:30:12 INFO - 'SHELL': '/bin/bash', 16:30:12 INFO - 'SHLVL': '1', 16:30:12 INFO - 'TERM': 'linux', 16:30:12 INFO - 'TMOUT': '86400', 16:30:12 INFO - 'USER': 'cltbld', 16:30:12 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', 16:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:12 INFO - '_': '/tools/buildbot/bin/python'} 16:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:30:12 INFO - Running setup.py (path:/tmp/pip-lEHLkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:30:12 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)) 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:30:12 INFO - Running setup.py (path:/tmp/pip-E7zqqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:30:12 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)) 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:30:12 INFO - Running setup.py (path:/tmp/pip-aUId9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:30:12 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)) 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:30:12 INFO - Running setup.py (path:/tmp/pip-k3RtUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:30:12 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)) 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:30:12 INFO - Running setup.py (path:/tmp/pip-ysaIws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:30:12 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)) 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:30:12 INFO - Running setup.py (path:/tmp/pip-0Wprn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:30: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)) 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:30:12 INFO - Running setup.py (path:/tmp/pip-83s40Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:30: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)) 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:30:12 INFO - Running setup.py (path:/tmp/pip-_TuVVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:30: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)) 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:30:12 INFO - Running setup.py (path:/tmp/pip-lxzAYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:30: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)) 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:30:12 INFO - Running setup.py (path:/tmp/pip-pQoXfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:30: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)) 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:30:12 INFO - Running setup.py (path:/tmp/pip-hQgUbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:30:13 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)) 16:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:30:13 INFO - Running setup.py (path:/tmp/pip-IlZc9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:30:13 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)) 16:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:30:13 INFO - Running setup.py (path:/tmp/pip-QtRbsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:30:13 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)) 16:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:30:13 INFO - Running setup.py (path:/tmp/pip-vGMmeS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:30:13 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)) 16:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:30:13 INFO - Running setup.py (path:/tmp/pip-fQ4Yza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:30:13 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)) 16:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:30:13 INFO - Running setup.py (path:/tmp/pip-VjJfqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:30:13 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)) 16:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:30:13 INFO - Running setup.py (path:/tmp/pip-W23_fI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:30:13 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)) 16:30:13 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)) 16:30:13 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)) 16:30:13 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)) 16:30:13 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)) 16:30:13 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)) 16:30:13 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)) 16:30:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:14 INFO - Downloading blessings-1.6.tar.gz 16:30:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:30:14 INFO - Installing collected packages: blessings 16:30:14 INFO - Running setup.py install for blessings 16:30:14 INFO - Successfully installed blessings 16:30:14 INFO - Cleaning up... 16:30:14 INFO - Return code: 0 16:30:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:30:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:30:15 INFO - Reading from file tmpfile_stdout 16:30:15 INFO - Current package versions: 16:30:15 INFO - argparse == 1.2.1 16:30:15 INFO - blessings == 1.6 16:30:15 INFO - blobuploader == 1.2.4 16:30:15 INFO - docopt == 0.6.1 16:30:15 INFO - functools32 == 3.2.3-2 16:30:15 INFO - jsonschema == 2.5.1 16:30:15 INFO - manifestparser == 1.1 16:30:15 INFO - mozInstall == 1.13 16:30:15 INFO - mozcrash == 1.0 16:30:15 INFO - mozdebug == 0.1 16:30:15 INFO - mozdevice == 0.51 16:30:15 INFO - mozfile == 1.2 16:30:15 INFO - mozhttpd == 0.7 16:30:15 INFO - mozinfo == 0.10 16:30:15 INFO - mozleak == 0.1 16:30:15 INFO - mozlog == 3.5 16:30:15 INFO - moznetwork == 0.27 16:30:15 INFO - mozprocess == 0.25 16:30:15 INFO - mozprofile == 0.28 16:30:15 INFO - mozrunner == 6.13 16:30:15 INFO - mozscreenshot == 0.1 16:30:15 INFO - mozsystemmonitor == 0.3 16:30:15 INFO - moztest == 0.8 16:30:15 INFO - mozversion == 1.4 16:30:15 INFO - psutil == 3.1.1 16:30:15 INFO - requests == 1.2.3 16:30:15 INFO - wsgiref == 0.1.2 16:30:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:15 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 0x1ea81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2070a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214580>, '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 0x2215480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2215910>, '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': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', '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 16:30:15 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 16:30:15 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 16:30:15 INFO - Using env: {'DISPLAY': ':0', 16:30:15 INFO - 'HOME': '/home/cltbld', 16:30:15 INFO - 'LANG': 'en_US.UTF-8', 16:30:15 INFO - 'LANGUAGE': 'en_US:en', 16:30:15 INFO - 'LOGNAME': 'cltbld', 16:30:15 INFO - 'MAIL': '/var/mail/cltbld', 16:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:15 INFO - 'MOZ_NO_REMOTE': '1', 16:30:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:15 INFO - 'NO_EM_RESTART': '1', 16:30:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:15 INFO - 'PWD': '/builds/slave/test', 16:30:15 INFO - 'SHELL': '/bin/bash', 16:30:15 INFO - 'SHLVL': '1', 16:30:15 INFO - 'TERM': 'linux', 16:30:15 INFO - 'TMOUT': '86400', 16:30:15 INFO - 'USER': 'cltbld', 16:30:15 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', 16:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:15 INFO - '_': '/tools/buildbot/bin/python'} 16:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:15 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)) 16:30:15 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)) 16:30:15 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)) 16:30:15 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)) 16:30:15 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)) 16:30:15 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)) 16:30:15 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)) 16:30:15 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)) 16:30:15 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)) 16:30:15 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:30:16 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)) 16:30:16 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)) 16:30:16 INFO - Installing collected packages: simplejson 16:30:16 INFO - Running setup.py install for simplejson 16:30:16 INFO - building 'simplejson._speedups' extension 16:30:16 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 16:30:17 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 16:30:17 INFO - Successfully installed simplejson 16:30:17 INFO - Cleaning up... 16:30:17 INFO - Return code: 0 16:30:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:17 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 0x1ea81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2070a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214580>, '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 0x2215480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2215910>, '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': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', '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 16:30:17 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 16:30:17 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 16:30:17 INFO - Using env: {'DISPLAY': ':0', 16:30:17 INFO - 'HOME': '/home/cltbld', 16:30:17 INFO - 'LANG': 'en_US.UTF-8', 16:30:17 INFO - 'LANGUAGE': 'en_US:en', 16:30:17 INFO - 'LOGNAME': 'cltbld', 16:30:17 INFO - 'MAIL': '/var/mail/cltbld', 16:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:17 INFO - 'MOZ_NO_REMOTE': '1', 16:30:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:17 INFO - 'NO_EM_RESTART': '1', 16:30:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:17 INFO - 'PWD': '/builds/slave/test', 16:30:17 INFO - 'SHELL': '/bin/bash', 16:30:17 INFO - 'SHLVL': '1', 16:30:17 INFO - 'TERM': 'linux', 16:30:17 INFO - 'TMOUT': '86400', 16:30:17 INFO - 'USER': 'cltbld', 16:30:17 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', 16:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:17 INFO - '_': '/tools/buildbot/bin/python'} 16:30:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:30:17 INFO - Cleaning up... 16:30:17 INFO - Return code: 0 16:30:17 INFO - Running post-action listener: _resource_record_post_action 16:30:17 INFO - Running post-action listener: _start_resource_monitoring 16:30:17 INFO - Starting resource monitoring. 16:30:17 INFO - [mozharness: 2017-08-29 23:30:17.789356Z] Finished create-virtualenv step (success) 16:30:17 INFO - [mozharness: 2017-08-29 23:30:17.789830Z] Running install step. 16:30:17 INFO - Running pre-action listener: _resource_record_pre_action 16:30:17 INFO - Running main action method: install 16:30:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:30:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:30:18 INFO - Reading from file tmpfile_stdout 16:30:18 INFO - Detecting whether we're running mozinstall >=1.0... 16:30:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:30:18 INFO - Reading from file tmpfile_stdout 16:30:18 INFO - Output received: 16:30:18 INFO - Usage: mozinstall [options] installer 16:30:18 INFO - Options: 16:30:18 INFO - -h, --help show this help message and exit 16:30:18 INFO - -d DEST, --destination=DEST 16:30:18 INFO - Directory to install application into. [default: 16:30:18 INFO - "/builds/slave/test"] 16:30:18 INFO - --app=APP Application being installed. [default: firefox] 16:30:18 INFO - mkdir: /builds/slave/test/build/application 16:30:18 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'] 16:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:30:34 INFO - Reading from file tmpfile_stdout 16:30:34 INFO - Output received: 16:30:34 INFO - /builds/slave/test/build/application/firefox/firefox 16:30:34 INFO - Running post-action listener: _resource_record_post_action 16:30:34 INFO - [mozharness: 2017-08-29 23:30:34.609890Z] Finished install step (success) 16:30:34 INFO - [mozharness: 2017-08-29 23:30:34.610176Z] Running setup-mitmproxy step. 16:30:34 INFO - Running pre-action listener: _resource_record_pre_action 16:30:34 INFO - Running main action method: setup_mitmproxy 16:30:34 INFO - Skipping: mitmproxy is not required 16:30:34 INFO - Running post-action listener: _resource_record_post_action 16:30:34 INFO - [mozharness: 2017-08-29 23:30:34.611302Z] Finished setup-mitmproxy step (success) 16:30:34 INFO - [mozharness: 2017-08-29 23:30:34.611586Z] Running run-tests step. 16:30:34 INFO - Running pre-action listener: _resource_record_pre_action 16:30:34 INFO - Running pre-action listener: _set_gcov_prefix 16:30:34 INFO - Running main action method: run_tests 16:30:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:30:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:30:34 INFO - Python 2.7.3 16:30:34 INFO - Return code: 0 16:30:34 INFO - grabbing minidump binary from tooltool 16:30:34 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')]} 16:30:34 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 0x2214580>, '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 0x2215480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2215910>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:30:34 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 16:30:34 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 16:30:34 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 16:30:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:30:34 INFO - Return code: 0 16:30:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:30:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:30:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:30:34 INFO - ENV: RUST_BACKTRACE is now full 16:30:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:30:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:30: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/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 16:30: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/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-058 --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 16:30:34 INFO - Using env: {'DISPLAY': ':0', 16:30:34 INFO - 'HOME': '/home/cltbld', 16:30:34 INFO - 'LANG': 'en_US.UTF-8', 16:30:34 INFO - 'LANGUAGE': 'en_US:en', 16:30:34 INFO - 'LOGNAME': 'cltbld', 16:30:34 INFO - 'MAIL': '/var/mail/cltbld', 16:30:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:30:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:34 INFO - 'MOZ_NO_REMOTE': '1', 16:30:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:30:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:34 INFO - 'NO_EM_RESTART': '1', 16:30:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:34 INFO - 'PWD': '/builds/slave/test', 16:30:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:30:34 INFO - 'RUST_BACKTRACE': 'full', 16:30:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:30:34 INFO - 'SHELL': '/bin/bash', 16:30:34 INFO - 'SHLVL': '1', 16:30:34 INFO - 'TERM': 'linux', 16:30:34 INFO - 'TMOUT': '86400', 16:30:34 INFO - 'USER': 'cltbld', 16:30:34 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', 16:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:34 INFO - '_': '/tools/buildbot/bin/python'} 16:30: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/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 16:30:34 INFO - mozversion application_buildid: 20170829223806 16:30:34 INFO - mozversion application_changeset: 8f221407c6006ac1de529da77ecc18431dde3e99 16:30:34 INFO - mozversion application_display_name: Nightly 16:30:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:30:34 INFO - mozversion application_name: Firefox 16:30:34 INFO - mozversion application_remotingname: firefox 16:30:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:30:34 INFO - mozversion application_vendor: Mozilla 16:30:34 INFO - mozversion application_version: 57.0a1 16:30:34 INFO - mozversion platform_buildid: 20170829223806 16:30:34 INFO - mozversion platform_changeset: 8f221407c6006ac1de529da77ecc18431dde3e99 16:30:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:30:34 INFO - mozversion platform_version: 57.0a1 16:30:34 INFO - using testdate: 1504049434 16:30:34 INFO - actual date: 1504049434 16:30:34 INFO - starting webserver on 'localhost:35522' 16:30:34 INFO - SUITE-START | Running 2 tests 16:30:34 INFO - TEST-START | dromaeo_css 16:30:34 INFO - Initialising browser for dromaeo_css test... 16:30:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35522/getInfo.html -profile /tmp/tmp14xWVC/profile 16:30:34 INFO - TEST-INFO | started process 26866 (/builds/slave/test/build/application/firefox/firefox http://localhost:35522/getInfo.html) 16:30:41 INFO - TEST-INFO | 26866: exit 0 16:30:41 INFO - Browser initialized. 16:30:41 INFO - Running cycle 1/1 for dromaeo_css test... 16:30:41 INFO - Using env: {'DISPLAY': ':0', 16:30:41 INFO - 'HOME': '/home/cltbld', 16:30:41 INFO - 'JSGC_DISABLE_POISONING': '1', 16:30:41 INFO - 'LANG': 'en_US.UTF-8', 16:30:41 INFO - 'LANGUAGE': 'en_US:en', 16:30:41 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:30:41 INFO - 'LOGNAME': 'cltbld', 16:30:41 INFO - 'MAIL': '/var/mail/cltbld', 16:30:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:30:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:30:41 INFO - 'MOZ_CRASHREPORTER': '1', 16:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:41 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:30:41 INFO - 'MOZ_NO_REMOTE': '1', 16:30:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:30:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:41 INFO - 'NO_EM_RESTART': '1', 16:30:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:41 INFO - 'PWD': '/builds/slave/test', 16:30:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:30:41 INFO - 'RUST_BACKTRACE': 'full', 16:30:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:30:41 INFO - 'SHELL': '/bin/bash', 16:30:41 INFO - 'SHLVL': '1', 16:30:41 INFO - 'TERM': 'linux', 16:30:41 INFO - 'TMOUT': '86400', 16:30:41 INFO - 'USER': 'cltbld', 16:30:41 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', 16:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:41 INFO - '_': '/tools/buildbot/bin/python'} 16:30:41 INFO - TEST-INFO | started process 27053 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp14xWVC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:42 INFO - PID 27053 | 16:30:42 INFO - PID 27053 | (/builds/slave/test/build/application/firefox/firefox:27105): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:30:42 INFO - PID 27053 | 16:30:52 INFO - PID 27053 | RSS: Main: 165216256 16:30:52 INFO - PID 27053 | 16:33:50 INFO - PID 27053 | Cycle 1(1): loaded http://localhost:35522/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35522/tests/dromaeo/cssquery-ext.html) 16:33:52 INFO - PID 27053 | RSS: Main: 170987520 16:33:52 INFO - PID 27053 | 16:36:54 INFO - PID 27053 | Cycle 1(1): loaded http://localhost:35522/tests/dromaeo/cssquery-ext.html (next: http://localhost:35522/tests/dromaeo/cssquery-jquery.html) 16:36:54 INFO - PID 27053 | RSS: Main: 167751680 16:36:54 INFO - PID 27053 | 16:39:56 INFO - PID 27053 | Cycle 1(1): loaded http://localhost:35522/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35522/tests/dromaeo/cssquery-mootools.html) 16:39:57 INFO - PID 27053 | RSS: Main: 170123264 16:39:57 INFO - PID 27053 | 16:42:58 INFO - PID 27053 | Cycle 1(1): loaded http://localhost:35522/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35522/tests/dromaeo/cssquery-prototype.html) 16:42:59 INFO - PID 27053 | RSS: Main: 166273024 16:42:59 INFO - PID 27053 | 16:46:02 INFO - PID 27053 | Cycle 1(1): loaded http://localhost:35522/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35522/tests/dromaeo/cssquery-yui.html) 16:46:03 INFO - PID 27053 | RSS: Main: 172007424 16:46:03 INFO - PID 27053 | 16:49:05 INFO - PID 27053 | Cycle 1(1): loaded http://localhost:35522/tests/dromaeo/cssquery-yui.html (next: http://localhost:35522/tests/dromaeo/cssquery-dojo.html) 16:49:05 INFO - PID 27053 | RSS: Main: 172490752 16:49:05 INFO - PID 27053 | 16:49:05 INFO - PID 27053 | __start_tp_report 16:49:05 INFO - PID 27053 | _x_x_mozilla_page_load 16:49:05 INFO - PID 27053 | _x_x_mozilla_page_load_details 16:49:05 INFO - PID 27053 | |i|pagename|runs| 16:49:05 INFO - PID 27053 | |0;dojo.html;3280.360329735701;3313.7514686398517;3325.1188435064696;3336.4328064229085;3351.865925362984;6212.881681297365;6285.968570157154;6289.308176100625;6308.810735677926;6341.175647165862;6123.081537769326;6221.377862213776;6226.159468471755;6264.154339164223;6264.4048815362585;5702.344230413514;5712.428757124275;5731.598788084836;5747.281589801275;5762.6254293471;5742.827715168552;5745.166950792153;5762.798302059428;5792.478676919077;5795.536357091443;5737.684427356494;5747.712614369286;5793.391693872146;5796.188533605298;5863.032599621058;5787.623804452718;5818.912716309259;5844;5845.970770146143;5917.644941303535;5756.050251708463;5773.682447465388;5849.561282903799;5855.941440585582;5900.114982752579;5884.352721200665;5985.670788106652;6179.50563954887;6312.652804095773;6342.270638876542;6578.046183303442;6634.0712300278;6640.7675731349655;6826.692798824065;6835.043093966849;6709.060731497594;6813;6901.171859376856;6906.102206713145;6916.446684265246;6210.9068363974575;6294.496440284812;6303.779367722108;6308.526860485482;6389;6777.423918966844;6816.147981502312;6869.690863911036;6909.481788865855;6959.652017399109;6545.639989800511;6605.537612367184;6612.570182938093;6632.403083722392;6636.734530618818;6666.533342665967;6749.122614060239;6753.831154221184;6829.180498340231;6829.248782633907;6713.597184168868;6721.092652491949;6810.114685091005;6814.2163651180745;6819.863602728017;6589.4069533742195;6650;6700.061991321219;6735.461163106939;6777.220619628707;5295.232191332235;5347.705876176769;5377.650452720561;5387;5404.32445944257;4615.892185875433;4664.072761517709;4721.126591580569;4726.71639701619;4743.762811859393;5845.532357411397;5854.385289544536;5925.288965324189;5925.703714814242;5942;6614.669266536654;6637.070810086591;6716.328367163244;6727.899081513781;6791.185057793096;5873.911891321633;5934.1692163097205;5954.583179177503;5962.850928726816;5965.075413310944;5731.140328950607;5745.597808153389;5751.9354632052255;5818.709064546756;5843.357230704619;6453.645049522322;6478.125453063776;6518.869622607522;6549.377809108127;6565.507586930904;6590.637514936724;6668.599884006975;6677.532572720057;6695.397414232548;6713.194416669836;6387.265464471459;6400.167978162809;6469.708863101077;6471.385218404244;6485.675716214265;6428.260750013808;6561.835954101186;6565.573237739532;6570.211574611078;6589.308122647245;6612.537122401386;6683.933160668331;6784.118064651564;6905.654717264017;6912.585244885323;6478.838029049123;6487.351264873475;6557.508186885907;6566.9343306566325;6567.179102612174;6269.96865015672;6497.9476769289895;6504.642244676588;6597.0764093026055;6598.10925525048;6347.143135676624;6358.459530939922;6372.776952806815;6374.681265936777;6374.840628984312;6487.967560162169;6603.075569420381;6603.9009414859775;6680.9331906682255;6692.297308782508;6186.288576813724;6296.905546416716;6387.584806987531;6393.424591786576;6395.42441180296 16:49:05 INFO - PID 27053 | |1;ext.html;762.8588711088449;792.4492477727978;810.6068556749977;814.588632740466;1141.6632093532407;31055.2236194095;31566.368672626562;31598.526022109687;42643;43692.563074369245;43419.13161736763;43791.56208437915;43798.34302485465;43812.780936095274;43822.78088609553;7517.398608111338;7922.168172341908;8082.43422960393;8085.838283234331;8124.350051995826;8000.119986801447;8113.797155071112;8119.350451963829;8122.472039317455;8125.5530945797955;8105.1084380718075;8155.836883262331;8156.388270879678;8163.469374490669;8171.142030086845;8109.107998120202;8121.593920303991;8130.593470326489;8130.837383252361;8131.268185863241;7936;7979;7979.281864632153;7979.601019948979;7989.600519973978;37660.435093473345;37773;37839;37899.621003789885;37931.620683793364;3523.9647603523895;3548.503209550665;3553.1117220694828;3564.1624218308675;3567.625399333059;31552.368952620818;31565.684343156732;31610.841945790355;31636.683633163604;31843.52234716481;5138.46046165151;5140.8457746267295;5218.947810521884;5250.7112108834;5256.185291279858;186645;187958;188171;188260;188680;48249;48920.5107948916;49077.50922490729;49305.26042109371;49313.75343123262;50997.23504147441;51062.74468627633;51091.489085108675;51101;51181.232281515804;16319.755203671955;16486.34054637825;16491.752623710654;16497.505074847773;16524.6695066098;11070;11319.773604527863;13407.597772066854;13544.45822167122;13856.237906915216;13759.10565813219;13853.792193117111;13859.930700346435;13869.930650346683;14004.229767362893;5642.097264437671;5649.548036157097;5737.110747834093;5751.798687045934;5811.622244554091;996.6710985374809;1020.5560581147224;1023.7287118913493;1027.209049032256;1027.2706378471369;23079.92202729065;23303.30090097266;23319.88340058323;23331.41671458193;23614.409639759142;1068.6206396729206;1123.4438952718456;1125.431657013203;1126.4649291586431;1134.7843909657138;11792.469338879771;12266.632001039983;12322.260664360167;12322.32227227511;12522.248665080124;234560;236832;236954;237241;237395;51941;51957.740211298704;52046;52163.47836521662;52224.738876305375;1451.3759083593945;1452.7385070687167;1462.393106860641;1467.6477645365035;1470.4559313054237;21587.568248635253;21874.343769686933;22191.22330718417;22318;22373.552528949655;37117.62882371142;37404;37488.625113748516;37517;37552.43671344987;201515;201857;201919;202076;202348;32625.510617341235;32830.67169328276;36866.26267474689;38576;38779.418308724824;46906.296405554625;47658.28512572245;47693.761531192125;47711.28433073437;47745.52254477411;34844;35082.12294692653;35232.82383588168;35310.47034294436;35589.46615800713;34366.65633343734;34556.48165277573;35036.299274014884;35111.12222194363;35566.466503002994;37790.244195116495;38706.80646596749;38775.6122438772;39082.80458597689;39121.41317880177;33749.83125084359;34256;34430.48354274638;34458;34599.30801383908;24846.751532484443;24861.378465538506;25278.721278721277;25406.89008858107;25506 16:49:05 INFO - PID 27053 | |2;jquery.html;564.2044716949102;566.501478698745;566.5495930735068;569.4647031790114;576.6367188671137;20617;22506.77493225067;23315.417114572123;23368.182113626048;23431.882840585815;24687.876560617173;25191.49617007659;25279;25320.620190697155;25344.366390840238;6583.604983700993;6771.525993180479;6787.4570034397375;6790.151231096113;6791.354821291994;6264.436200741932;6535.281119076922;6567.704453299589;6572.967135164317;6663.833404164886;6521.6413097279865;6522.249941256769;6528;6528.249251336086;6530.412262896339;6520.7065682044185;6525.119108920307;6525.6737163142125;6530.216374035099;6532;5979;5980.073088671263;6029.613188966562;6031.276246850406;6054.757809687607;28573.428531429254;28964.8551757242;29128.417431651247;29145;29373.70626293731;2010.2160157538572;2047.406252186864;2051.2205361962506;2052.2509284111343;2053.1376821734907;19344.516387090294;19362;20209;20219.090140943692;20249.898750506152;2352.0826877517784;2417.3352328109736;2427.490227052321;2439.829211955164;2526.2926380613276;145332;149193.50806491903;151392.2430387841;151480;152045.23977380153;14769.92615036918;15151.393944242107;15171.317290721943;15226.086434813946;16322.428714994918;23091.88454057719;23097.88451057734;23101.653475197887;23129.653055204184;23133.190338338412;10149.492525373702;10369.066783989478;10461.84307235392;10521.631742888962;10524.947375263228;9932.751681207883;10052.296339256329;10092;10171.186305095574;10336.17310615149;9209.901593790699;9313.953430232941;9390.90609093914;9422.575984080595;9514.762130946643;2772.6534183227095;2778.5554311310498;2788.512010398172;2802.285417218596;2856.485832550161;976.7997560500222;978.4667356290838;989.0900371658092;993.7863359377768;1009.7929924365487;19145.2341906325;19336.226550938085;19619.01904904749;19979.20083196685;20120.19519219244;1052.4106500359824;1057.9947100264449;1063.7553362726617;1072.683558350273;1079.384750692098;10969.890301096886;10973.83539246912;11067.335959842436;11160.991021616188;11193;457965;469564;472534;480961;482956;111131;111338.44330778295;111793.44103279595;111861;112081.43959280151;1038.5897570459626;1063.3354153653966;1176.588194132064;1200.1119171812795;1220.7497463020059;20167.29414470545;20179;20216.696749548475;20293.086811093242;20357.592848142656;55122;56405;56570;56867.431325686215;57248.71375643095;69244.30755692378;69572.652136739;69762.3023769776;69815;70013.64993175001;14078.648033799236;14392.568222953329;14409.495667651581;14441.7833732492;14442.927785361006;22428.439289017253;22678.886605566866;22781.658275126218;22919.65620515727;22981.88509057444;17722.46832595024;18178.90910545439;18190.181441834884;18298.085095744893;18438.89366638006;16203.675926481641;16301.184940753152;16425.028996520017;16474.258658360162;16649.75126865514;17838.91080544589;18105.094745262948;18797.624047519246;18991.24035038583;19012.714809277597;14095;14186.645333866736;14194.219317937612;14194.432222710975;14199.503017394341;16868.57828554247;18154.546136346697;18173.81826181774;18218.36235731743;18347.90826045861 16:49:05 INFO - PID 27053 | |3;mootools.html;1298.8831005209531;1346.6027521881044;1362.23033985798;1378.207530669865;1379.5033787836378;14377;14474.348654310556;15123.546293611182;15124.621884452894;15145.242737863091;15184.6963060739;15377.84622153778;15408.07551546908;15413.537593872174;15427.92286038571;2723.6459260296133;2757.9724202758016;2762.2541913683294;2762.2818067302546;2765.046059109614;2654.66816647919;2707.3096360428062;2722.673279206498;2848.615436916021;2849.487092323381;2812.6624805023425;2841.8294902305825;2843.0760002999004;2843.758280546156;2849.3873817129315;2663.826851254672;2718.510668079745;2781.1517487166407;2790.6232658591034;2794.9441011179865;2618.882150303241;2662.334416395901;2664.467106578692;2666.6666666666665;2668.746469085434;14519.20144392058;14543.418263269457;14669.853301467063;14677.412903483848;14693.632659183606;7818.530888146729;8109.837803243961;8120;8124.268815806606;8129.187081291823;5284.365876094893;5323.547498462635;5461.563074954034;5494.450554944514;5528.336599608073;1893.6875415556417;1898.9905050474797;1930.8938008409532;1982.3557343863301;1998.9700154497693;25447.872760636077;25652.102176423923;25814.354641133934;25825.612615810776;25857.353566160808;6671.199456065303;6687.799366019027;6693.631850248283;6726.663666816639;6798.116244888133;16387.83612163887;16391.70516127743;16401.0979396132;16421.58946026359;16442.75335869963;7455.366293864974;7622.390208783284;7666.156722760492;7667.156612772591;7713.074431068308;7028.578285302899;7178.4616153788675;7297.744578939712;7357.558546487228;7362.153352364439;5098.490150984946;5273.578113750891;5303.939212157584;5319.335083114611;5360;1396.9720605588034;1439.5825210688783;1506.0888162661652;1515.3256800723793;1543.074155506687;876.1545108969875;882.4087861132928;892.3842548641417;897.3135551303258;906.6781292641017;7251.948467472187;7403.22266162056;7470.439717021245;7486.251374862579;7528.924710752932;972.7130496503407;974.7563109222694;981.8821741390936;997.9550920208608;1005.939643621385;5694.829155125353;5701.287339082614;5906.881862362815;5917.644941303535;5957.927573036895;20372.083256253503;21144.259950901644;21301.254456093964;21308.147674093172;21314.893425532773;19850.404487865388;20198.899005504878;20499.180032798522;20602.381928542167;20630.690539641917;11156.386398747984;11356.772864542663;11468.369239691898;11487.310761354345;11511.251768635011;8711.47731136134;8744.606492707971;8902.109789021306;9001.954990225007;9055.95472022662;17896.46310610684;18009.549761256072;18041.36855210114;18327.816721833144;18462.07689615487;18056.90971545134;18122.81877181211;18134.909325453817;18176.818231817513;18442.907785460986;10805.513751881026;10982.121430285399;10990.17573681993;10990.230683852373;10995.560177592806;13312.134711243738;13537.390817413043;13661.38523766413;13740.450381984609;13746.381412836647;9230.261579073524;10325.63860264887;11299.943500282447;11325.377104259345;11608.535658573563;10387.324823886423;10388.37669739818;10434.530446129791;10556.366618002945;10769.084627806411;8962.955185224033;9153.084691530794;9156.633734650806;9165;9170.312226583166;6898.206706228647;6920.480963927826;6936.375726184668;6950.096487456598;6982.33667801558;6593.076969224217;6614.702338394687;6634.170728658918;6648.501362397743;6808.38724514796 16:49:05 INFO - PID 27053 | |4;prototype.html;493.8390682645706;499.64486027088583;512.1702841396936;512.4004914250327;519.5116590405019;6304.684765761705;6311.400416960387;6319.873602527952;6357.777477788272;6391.360863913606;6115.205023346959;6132.969335153329;6156.4766994805495;6193.659348735818;6287.748490060403;4938.111204945111;4989.65072444929;5035.748212589374;5041.269460599148;5043.697378157304;4751.049790041988;4968.975155124219;4972.602191824645;5034.395872495305;5057.393112826448;4915.3855768029;4925.778339974692;4943.77753001114;4960.23116416956;4963.826266080688;4946.72792996385;4957.157283261854;4976.6516343855765;4993.375828021497;5005.2492126181;4354.912901741979;4376.474823021226;4405.449318835145;4407.647388208936;4417.801198946023;6086.452219300284;6163.691815409256;6233.345498722614;6238;6248.87502249957;5579.888402231933;5703.17303990919;5704.914426283609;5726.742296596663;5747.310322761254;6509.869802603921;6513.413792758674;6518.348165183491;6535.215774107138;6566.409023187888;4778.378810754614;4807.759612019385;4818.301346304801;4820.734859582722;4830.637702172351;6872.862542749217;6948.166220053626;6981.685824137875;7011.298870112999;7042.507024508286;4876.804927802848;4935.728534930541;4936.555709986119;4943.3573635427165;4965.47862474435;4974.228994505857;5001.299818025479;5023.2214006829;5039.732120545769;5050.873728156752;5666.9799436101075;5671.404502527175;5698.971505142447;5700.40145784695;5710.3433105193435;6516.6415847128865;6598.47212223021;6603.141591593062;6656.301088385747;6703.597784132968;1625.252383903394;1651.7687523746686;1653.768472413863;1658.477579562434;1736.5224563244956;1190.1787766441128;1190.9702257443632;1207.4264724255909;1220.6399112261727;1221.2428294457493;5785.334686511021;5791.131330300489;5833.358330583633;5842.620229685057;5873.647581145145;6673.232578253563;6753.12209412783;6756.898646520306;6807.693653785592;6839.521233513607;5812.9709351452975;5865.94134058654;5868.471837534557;5868.471837534642;5891.556568640711;3568.233724306433;3603.801790901473;3620.8913732588067;3626.3110009098714;3626.3291291111227;6536.444402225769;6574.802755917234;6611.33886611335;6641.601503909781;6645.700943457459;6908;6937.410320122745;6941.167059952737;6949.7567585134275;7006.509544331848;6540.215174179129;6573.835654108686;6577.2436169841085;6593.901091483535;6613.66931653425;6828.829279268058;6833.794986150423;6845.4865885057825;6862.142232220973;6877.58734475933;6744.089547911165;6746.291639377795;6765.9363894012795;6769.74759669443;6806.217285012287;6507.902381464377;6547.148870646785;6566.80299591013;6616.867662646624;6633.60198388079;4879.658423910292;4899.755012249445;4909.484504127158;4909.828156014522;4912.582430493305;6593.835154121185;6721.428678562182;6759.391654751096;6786.151731033621;6787.830304242236;6370.713317900613;6423.229212494531;6504.284528701744;6562;6614.570052946543;6461.709223085067;6474.021949903097;6491.350864913471;6550.541462097607;6589.0775291460195;6272.620023594802;6369.426751592379;6373.649449280334;6379.808605741835;6394.7442102317245;6613.702383392662;6621.867562648817;6662.766803161866;6700.4588944544;6723.462123030244;6400.263969643551;6441.645709485836;6471.417572418505;6474.902876456763;6481.643509607017 16:49:05 INFO - PID 27053 | |5;yui.html;882.8101958079016;903.3721563513361;907.7276816954914;910.4946754551225;915.8809354783879;15905.44330948417;16480;16515.421960231393;16548.586285342873;20278.391648250577;19072.523186920298;19323.806761932377;19341.129649165785;19363.515912102204;19378.51553711158;907.1790030022806;945.6406565505133;987.9555420006081;990.1039559198937;993.6075250276136;998.7453199434134;1007.5012868630039;1007.9949600251988;1008.4655132779639;1010.0152351457307;1039.8492218628294;1047.8847326794046;1050.1074087026043;1050.2910535388621;1050.333038520541;1037.190991027;1038.9688309350731;1040.5109598488737;1041.0318403884385;1041.1930753665931;769.2230846844672;770.9845803083907;771.8880762289436;772.7411317208741;772.9072511298624;16686.33254669812;16871.493855184362;17817.732734009;17958.640827183382;18066.819331806644;20924.895375523178;21223.78776212249;21325.146994120372;21562.1375144994;21769.23807666724;15228.238588070551;15373.615659608598;15390.461333853373;15416.3833446662;16706.749398759028;3755.248950209969;3764.8117594120185;3767.2842159989787;3773.811309434517;3786.1859700164323;120666.3966680161;121267.39366303112;123298.38350808188;124212;129414;16234;16505.17474126313;17074.231659575347;17205.56986075358;17259.309627614755;29874.253143671584;29991.550126748116;30169.849150754108;30215;30274.243143921136;6472.935270647234;8936.8659470108;9025.097490250922;9033.36766426357;9049.366544341832;3783.1109689223013;4124.587541245852;4289.635380992589;4312.439382880206;4314.546972567898;5113.565346945551;5155.046316431472;5213.791448342024;5242.816501422432;5243.711595862264;1046.2885238038054;1055.551390658967;1059.0997651995742;1062.3201151263197;1065.5577935156823;650.1677852349001;663.3929954091952;683.1938312790855;692.3491917597443;692.9341712537401;11296.661100167008;11400.315981041163;11606.535738570363;11735.765284694258;12637.620871373874;668.1047396488729;674.3559900295206;674.9527533072638;677.1873751498222;682.9146356705412;5735.3977832327;5828.737706803204;5984.551158663117;6048.727807248684;6106.053561697944;114590.28114578288;114700.42649786698;118117.40941295239;121396.39301803435;123773.38113309376;98752.50623746835;101947.49026254969;103490;107386;107719.46140269405;86022;88251.1174888243;89282.10717892868;91848.54075729712;93858;10295.897041029493;12741.87258127444;12933.676658083246;13120.278384688934;13127;40330.193396131326;41302.79348603238;41836.3724544126;41992.320307188595;42403;72421;73896.26103739109;74960;76021.6198919024;78254.608726956;17685.55786105306;17965.64068718592;18116.637667246843;18277;18298.085095744893;20558;21298.25456109029;21890.562188756456;22062.558748825253;22103.889480552494;18039.549011274823;18783.718244226075;19429.805701943365;20034.59930801405;20292.79707202909;16830.831691682928;17229.91385043117;17239.3966211182;18141.36505222364;18603.06984650789;17525.386611468537;17543.298268069135;17691.469255922344;17769.822301776818;18595.256189752257;13552.93223533876;13625.795613065613;13644.386002629706;13855.79216311736;14033.649158771112;14578.125312481285;14785.630359241106;14793.852061479247;14865.182414967281;14999.250037497863 16:49:05 INFO - PID 27053 | __end_tp_report 16:49:05 INFO - PID 27053 | __start_cc_report 16:49:05 INFO - PID 27053 | _x_x_mozilla_cycle_collect,390 16:49:05 INFO - PID 27053 | __end_cc_report 16:49:05 INFO - PID 27053 | __startTimestamp1504050545497__endTimestamp 16:49:05 INFO - PID 27053 | 16:49:05 INFO - PID 27053 | ------- Summary: start ------- 16:49:05 INFO - PID 27053 | Number of tests: 6 16:49:05 INFO - PID 27053 | 16:49:05 INFO - PID 27053 | [#0] dojo.html Cycles:165 Average:6185.99 Median:6387.27 stddev:704.11 (11.0%) stddev-sans-first:668.36 16:49:05 INFO - PID 27053 | Values: 3280.4 3313.8 3325.1 3336.4 3351.9 6212.9 6286.0 6289.3 6308.8 6341.2 6123.1 6221.4 6226.2 6264.2 6264.4 5702.3 5712.4 5731.6 5747.3 5762.6 5742.8 5745.2 5762.8 5792.5 5795.5 5737.7 5747.7 5793.4 5796.2 5863.0 5787.6 5818.9 5844.0 5846.0 5917.6 5756.1 5773.7 5849.6 5855.9 5900.1 5884.4 5985.7 6179.5 6312.7 6342.3 6578.0 6634.1 6640.8 6826.7 6835.0 6709.1 6813.0 6901.2 6906.1 6916.4 6210.9 6294.5 6303.8 6308.5 6389.0 6777.4 6816.1 6869.7 6909.5 6959.7 6545.6 6605.5 6612.6 6632.4 6636.7 6666.5 6749.1 6753.8 6829.2 6829.2 6713.6 6721.1 6810.1 6814.2 6819.9 6589.4 6650.0 6700.1 6735.5 6777.2 5295.2 5347.7 5377.7 5387.0 5404.3 4615.9 4664.1 4721.1 4726.7 4743.8 5845.5 5854.4 5925.3 5925.7 5942.0 6614.7 6637.1 6716.3 6727.9 6791.2 5873.9 5934.2 5954.6 5962.9 5965.1 5731.1 5745.6 5751.9 5818.7 5843.4 6453.6 6478.1 6518.9 6549.4 6565.5 6590.6 6668.6 6677.5 6695.4 6713.2 6387.3 6400.2 6469.7 6471.4 6485.7 6428.3 6561.8 6565.6 6570.2 6589.3 6612.5 6683.9 6784.1 6905.7 6912.6 6478.8 6487.4 6557.5 6566.9 6567.2 6270.0 6497.9 6504.6 6597.1 6598.1 6347.1 6358.5 6372.8 6374.7 6374.8 6488.0 6603.1 6603.9 6680.9 6692.3 6186.3 6296.9 6387.6 6393.4 6395.4 16:49:05 INFO - PID 27053 | 16:49:05 INFO - PID 27053 | [#1] ext.html Cycles:180 Average:38248.82 Median:19559.79 stddev:54350.44 (277.9%) stddev-sans-first:54430.02 16:49:05 INFO - PID 27053 | Values: 762.9 792.4 810.6 814.6 1141.7 31055.2 31566.4 31598.5 42643.0 43692.6 43419.1 43791.6 43798.3 43812.8 43822.8 7517.4 7922.2 8082.4 8085.8 8124.4 8000.1 8113.8 8119.4 8122.5 8125.6 8105.1 8155.8 8156.4 8163.5 8171.1 8109.1 8121.6 8130.6 8130.8 8131.3 7936.0 7979.0 7979.3 7979.6 7989.6 37660.4 37773.0 37839.0 37899.6 37931.6 3524.0 3548.5 3553.1 3564.2 3567.6 31552.4 31565.7 31610.8 31636.7 31843.5 5138.5 5140.8 5218.9 5250.7 5256.2 186645.0 187958.0 188171.0 188260.0 188680.0 48249.0 48920.5 49077.5 49305.3 49313.8 50997.2 51062.7 51091.5 51101.0 51181.2 16319.8 16486.3 16491.8 16497.5 16524.7 11070.0 11319.8 13407.6 13544.5 13856.2 13759.1 13853.8 13859.9 13869.9 14004.2 5642.1 5649.5 5737.1 5751.8 5811.6 996.7 1020.6 1023.7 1027.2 1027.3 23079.9 23303.3 23319.9 23331.4 23614.4 1068.6 1123.4 1125.4 1126.5 1134.8 11792.5 12266.6 12322.3 12322.3 12522.2 234560.0 236832.0 236954.0 237241.0 237395.0 51941.0 51957.7 52046.0 52163.5 52224.7 1451.4 1452.7 1462.4 1467.6 1470.5 21587.6 21874.3 22191.2 22318.0 22373.6 37117.6 37404.0 37488.6 37517.0 37552.4 201515.0 201857.0 201919.0 202076.0 202348.0 32625.5 32830.7 36866.3 38576.0 38779.4 46906.3 47658.3 47693.8 47711.3 47745.5 34844.0 35082.1 35232.8 35310.5 35589.5 34366.7 34556.5 35036.3 35111.1 35566.5 37790.2 38706.8 38775.6 39082.8 39121.4 33749.8 34256.0 34430.5 34458.0 34599.3 24846.8 24861.4 25278.7 25406.9 25506.0 16:49:05 INFO - PID 27053 | 16:49:05 INFO - PID 27053 | [#2] jquery.html Cycles:180 Average:34505.54 Median:21522.72 stddev:80245.10 (372.8%) stddev-sans-first:80429.74 16:49:05 INFO - PID 27053 | Values: 564.2 566.5 566.5 569.5 576.6 20617.0 22506.8 23315.4 23368.2 23431.9 24687.9 25191.5 25279.0 25320.6 25344.4 6583.6 6771.5 6787.5 6790.2 6791.4 6264.4 6535.3 6567.7 6573.0 6663.8 6521.6 6522.2 6528.0 6528.2 6530.4 6520.7 6525.1 6525.7 6530.2 6532.0 5979.0 5980.1 6029.6 6031.3 6054.8 28573.4 28964.9 29128.4 29145.0 29373.7 2010.2 2047.4 2051.2 2052.3 2053.1 19344.5 19362.0 20209.0 20219.1 20249.9 2352.1 2417.3 2427.5 2439.8 2526.3 145332.0 149193.5 151392.2 151480.0 152045.2 14769.9 15151.4 15171.3 15226.1 16322.4 23091.9 23097.9 23101.7 23129.7 23133.2 10149.5 10369.1 10461.8 10521.6 10524.9 9932.8 10052.3 10092.0 10171.2 10336.2 9209.9 9314.0 9390.9 9422.6 9514.8 2772.7 2778.6 2788.5 2802.3 2856.5 976.8 978.5 989.1 993.8 1009.8 19145.2 19336.2 19619.0 19979.2 20120.2 1052.4 1058.0 1063.8 1072.7 1079.4 10969.9 10973.8 11067.3 11161.0 11193.0 457965.0 469564.0 472534.0 480961.0 482956.0 111131.0 111338.4 111793.4 111861.0 112081.4 1038.6 1063.3 1176.6 1200.1 1220.7 20167.3 20179.0 20216.7 20293.1 20357.6 55122.0 56405.0 56570.0 56867.4 57248.7 69244.3 69572.7 69762.3 69815.0 70013.6 14078.6 14392.6 14409.5 14441.8 14442.9 22428.4 22678.9 22781.7 22919.7 22981.9 17722.5 18178.9 18190.2 18298.1 18438.9 16203.7 16301.2 16425.0 16474.3 16649.8 17838.9 18105.1 18797.6 18991.2 19012.7 14095.0 14186.6 14194.2 14194.4 14199.5 16868.6 18154.5 18173.8 18218.4 18347.9 16:49:05 INFO - PID 27053 | 16:49:05 INFO - PID 27053 | [#3] mootools.html Cycles:180 Average:9098.91 Median:2667.71 stddev:6410.42 (240.3%) stddev-sans-first:6401.61 16:49:05 INFO - PID 27053 | Values: 1298.9 1346.6 1362.2 1378.2 1379.5 14377.0 14474.3 15123.5 15124.6 15145.2 15184.7 15377.8 15408.1 15413.5 15427.9 2723.6 2758.0 2762.3 2762.3 2765.0 2654.7 2707.3 2722.7 2848.6 2849.5 2812.7 2841.8 2843.1 2843.8 2849.4 2663.8 2718.5 2781.2 2790.6 2794.9 2618.9 2662.3 2664.5 2666.7 2668.7 14519.2 14543.4 14669.9 14677.4 14693.6 7818.5 8109.8 8120.0 8124.3 8129.2 5284.4 5323.5 5461.6 5494.5 5528.3 1893.7 1899.0 1930.9 1982.4 1999.0 25447.9 25652.1 25814.4 25825.6 25857.4 6671.2 6687.8 6693.6 6726.7 6798.1 16387.8 16391.7 16401.1 16421.6 16442.8 7455.4 7622.4 7666.2 7667.2 7713.1 7028.6 7178.5 7297.7 7357.6 7362.2 5098.5 5273.6 5303.9 5319.3 5360.0 1397.0 1439.6 1506.1 1515.3 1543.1 876.2 882.4 892.4 897.3 906.7 7251.9 7403.2 7470.4 7486.3 7528.9 972.7 974.8 981.9 998.0 1005.9 5694.8 5701.3 5906.9 5917.6 5957.9 20372.1 21144.3 21301.3 21308.1 21314.9 19850.4 20198.9 20499.2 20602.4 20630.7 11156.4 11356.8 11468.4 11487.3 11511.3 8711.5 8744.6 8902.1 9002.0 9056.0 17896.5 18009.5 18041.4 18327.8 18462.1 18056.9 18122.8 18134.9 18176.8 18442.9 10805.5 10982.1 10990.2 10990.2 10995.6 13312.1 13537.4 13661.4 13740.5 13746.4 9230.3 10325.6 11299.9 11325.4 11608.5 10387.3 10388.4 10434.5 10556.4 10769.1 8963.0 9153.1 9156.6 9165.0 9170.3 6898.2 6920.5 6936.4 6950.1 6982.3 6593.1 6614.7 6634.2 6648.5 6808.4 16:49:05 INFO - PID 27053 | 16:49:05 INFO - PID 27053 | [#4] prototype.html Cycles:180 Average:5531.15 Median:6213.50 stddev:1576.94 (25.4%) stddev-sans-first:1535.37 16:49:05 INFO - PID 27053 | Values: 493.8 499.6 512.2 512.4 519.5 6304.7 6311.4 6319.9 6357.8 6391.4 6115.2 6133.0 6156.5 6193.7 6287.7 4938.1 4989.7 5035.7 5041.3 5043.7 4751.0 4969.0 4972.6 5034.4 5057.4 4915.4 4925.8 4943.8 4960.2 4963.8 4946.7 4957.2 4976.7 4993.4 5005.2 4354.9 4376.5 4405.4 4407.6 4417.8 6086.5 6163.7 6233.3 6238.0 6248.9 5579.9 5703.2 5704.9 5726.7 5747.3 6509.9 6513.4 6518.3 6535.2 6566.4 4778.4 4807.8 4818.3 4820.7 4830.6 6872.9 6948.2 6981.7 7011.3 7042.5 4876.8 4935.7 4936.6 4943.4 4965.5 4974.2 5001.3 5023.2 5039.7 5050.9 5667.0 5671.4 5699.0 5700.4 5710.3 6516.6 6598.5 6603.1 6656.3 6703.6 1625.3 1651.8 1653.8 1658.5 1736.5 1190.2 1191.0 1207.4 1220.6 1221.2 5785.3 5791.1 5833.4 5842.6 5873.6 6673.2 6753.1 6756.9 6807.7 6839.5 5813.0 5865.9 5868.5 5868.5 5891.6 3568.2 3603.8 3620.9 3626.3 3626.3 6536.4 6574.8 6611.3 6641.6 6645.7 6908.0 6937.4 6941.2 6949.8 7006.5 6540.2 6573.8 6577.2 6593.9 6613.7 6828.8 6833.8 6845.5 6862.1 6877.6 6744.1 6746.3 6765.9 6769.7 6806.2 6507.9 6547.1 6566.8 6616.9 6633.6 4879.7 4899.8 4909.5 4909.8 4912.6 6593.8 6721.4 6759.4 6786.2 6787.8 6370.7 6423.2 6504.3 6562.0 6614.6 6461.7 6474.0 6491.4 6550.5 6589.1 6272.6 6369.4 6373.6 6379.8 6394.7 6613.7 6621.9 6662.8 6700.5 6723.5 6400.3 6441.6 6471.4 6474.9 6481.6 16:49:05 INFO - PID 27053 | 16:49:05 INFO - PID 27053 | [#5] yui.html Cycles:180 Average:24260.91 Median:19332.47 stddev:33422.20 (172.9%) stddev-sans-first:33469.86 16:49:05 INFO - PID 27053 | Values: 882.8 903.4 907.7 910.5 915.9 15905.4 16480.0 16515.4 16548.6 20278.4 19072.5 19323.8 19341.1 19363.5 19378.5 907.2 945.6 988.0 990.1 993.6 998.7 1007.5 1008.0 1008.5 1010.0 1039.8 1047.9 1050.1 1050.3 1050.3 1037.2 1039.0 1040.5 1041.0 1041.2 769.2 771.0 771.9 772.7 772.9 16686.3 16871.5 17817.7 17958.6 18066.8 20924.9 21223.8 21325.1 21562.1 21769.2 15228.2 15373.6 15390.5 15416.4 16706.7 3755.2 3764.8 3767.3 3773.8 3786.2 120666.4 121267.4 123298.4 124212.0 129414.0 16234.0 16505.2 17074.2 17205.6 17259.3 29874.3 29991.6 30169.8 30215.0 30274.2 6472.9 8936.9 9025.1 9033.4 9049.4 3783.1 4124.6 4289.6 4312.4 4314.5 5113.6 5155.0 5213.8 5242.8 5243.7 1046.3 1055.6 1059.1 1062.3 1065.6 650.2 663.4 683.2 692.3 692.9 11296.7 11400.3 11606.5 11735.8 12637.6 668.1 674.4 675.0 677.2 682.9 5735.4 5828.7 5984.6 6048.7 6106.1 114590.3 114700.4 118117.4 121396.4 123773.4 98752.5 101947.5 103490.0 107386.0 107719.5 86022.0 88251.1 89282.1 91848.5 93858.0 10295.9 12741.9 12933.7 13120.3 13127.0 40330.2 41302.8 41836.4 41992.3 42403.0 72421.0 73896.3 74960.0 76021.6 78254.6 17685.6 17965.6 18116.6 18277.0 18298.1 20558.0 21298.3 21890.6 22062.6 22103.9 18039.5 18783.7 19429.8 20034.6 20292.8 16830.8 17229.9 17239.4 18141.4 18603.1 17525.4 17543.3 17691.5 17769.8 18595.3 13552.9 13625.8 13644.4 13855.8 14033.6 14578.1 14785.6 14793.9 14865.2 14999.3 16:49:05 INFO - PID 27053 | -------- Summary: end -------- 16:49:05 INFO - PID 27053 | 16:49:06 INFO - TEST-INFO | 27053: exit 0 16:49:06 INFO - TEST-OK | dromaeo_css | took 1111186ms 16:49:06 INFO - TEST-START | kraken 16:49:06 INFO - Initialising browser for kraken test... 16:49:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35522/getInfo.html -profile /tmp/tmpxQ4HFn/profile 16:49:06 INFO - TEST-INFO | started process 27428 (/builds/slave/test/build/application/firefox/firefox http://localhost:35522/getInfo.html) 16:49:11 INFO - TEST-INFO | 27428: exit 0 16:49:11 INFO - Browser initialized. 16:49:11 INFO - Running cycle 1/1 for kraken test... 16:49:11 INFO - Using env: {'DISPLAY': ':0', 16:49:11 INFO - 'HOME': '/home/cltbld', 16:49:11 INFO - 'JSGC_DISABLE_POISONING': '1', 16:49:11 INFO - 'LANG': 'en_US.UTF-8', 16:49:11 INFO - 'LANGUAGE': 'en_US:en', 16:49:11 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:49:11 INFO - 'LOGNAME': 'cltbld', 16:49:11 INFO - 'MAIL': '/var/mail/cltbld', 16:49:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:49:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:49:11 INFO - 'MOZ_CRASHREPORTER': '1', 16:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:11 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:49:11 INFO - 'MOZ_NO_REMOTE': '1', 16:49:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:49:11 INFO - 'NO_EM_RESTART': '1', 16:49:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:11 INFO - 'PWD': '/builds/slave/test', 16:49:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:49:11 INFO - 'RUST_BACKTRACE': 'full', 16:49:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:49:11 INFO - 'SHELL': '/bin/bash', 16:49:11 INFO - 'SHLVL': '1', 16:49:11 INFO - 'TERM': 'linux', 16:49:11 INFO - 'TMOUT': '86400', 16:49:11 INFO - 'USER': 'cltbld', 16:49:11 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012', 16:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:11 INFO - '_': '/tools/buildbot/bin/python'} 16:49:11 INFO - TEST-INFO | started process 27607 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxQ4HFn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:49:12 INFO - PID 27607 | 16:49:12 INFO - PID 27607 | (/builds/slave/test/build/application/firefox/firefox:27659): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:49:12 INFO - PID 27607 | 16:49:22 INFO - PID 27607 | RSS: Main: 171048960 16:49:22 INFO - PID 27607 | 16:49:25 INFO - PID 27607 | Cycle 1(1): loaded http://localhost:35522/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35522/tests/kraken/driver.html?testName=audio-beat-detection) 16:49:26 INFO - PID 27607 | RSS: Main: 176582656 16:49:26 INFO - PID 27607 | 16:49:29 INFO - PID 27607 | Cycle 1(1): loaded http://localhost:35522/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35522/tests/kraken/driver.html?testName=audio-dft) 16:49:29 INFO - PID 27607 | RSS: Main: 177098752 16:49:29 INFO - PID 27607 | 16:49:32 INFO - PID 27607 | Cycle 1(1): loaded http://localhost:35522/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35522/tests/kraken/driver.html?testName=audio-fft) 16:49:33 INFO - PID 27607 | RSS: Main: 170418176 16:49:33 INFO - PID 27607 | 16:49:35 INFO - PID 27607 | Cycle 1(1): loaded http://localhost:35522/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35522/tests/kraken/driver.html?testName=audio-oscillator) 16:49:36 INFO - PID 27607 | RSS: Main: 171061248 16:49:36 INFO - PID 27607 | 16:49:38 INFO - PID 27607 | Cycle 1(1): loaded http://localhost:35522/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35522/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:49:38 INFO - PID 27607 | RSS: Main: 171458560 16:49:38 INFO - PID 27607 | 16:49:41 INFO - PID 27607 | Cycle 1(1): loaded http://localhost:35522/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35522/tests/kraken/driver.html?testName=imaging-darkroom) 16:49:42 INFO - PID 27607 | RSS: Main: 173744128 16:49:42 INFO - PID 27607 | 16:49:46 INFO - PID 27607 | Cycle 1(1): loaded http://localhost:35522/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35522/tests/kraken/driver.html?testName=imaging-desaturate) 16:49:46 INFO - PID 27607 | RSS: Main: 174010368 16:49:46 INFO - PID 27607 | 16:49:49 INFO - PID 27607 | Cycle 1(1): loaded http://localhost:35522/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35522/tests/kraken/driver.html?testName=json-parse-financial) 16:49:50 INFO - PID 27607 | RSS: Main: 173928448 16:49:50 INFO - PID 27607 | 16:49:51 INFO - PID 27607 | Cycle 1(1): loaded http://localhost:35522/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35522/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:49:52 INFO - PID 27607 | RSS: Main: 173981696 16:49:52 INFO - PID 27607 | 16:49:54 INFO - PID 27607 | Cycle 1(1): loaded http://localhost:35522/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35522/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:49:54 INFO - PID 27607 | RSS: Main: 174129152 16:49:54 INFO - PID 27607 | 16:49:56 INFO - PID 27607 | Cycle 1(1): loaded http://localhost:35522/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35522/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:49:57 INFO - PID 27607 | RSS: Main: 171061248 16:49:57 INFO - PID 27607 | 16:50:00 INFO - PID 27607 | Cycle 1(1): loaded http://localhost:35522/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35522/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:50:00 INFO - PID 27607 | RSS: Main: 171102208 16:50:00 INFO - PID 27607 | 16:50:03 INFO - PID 27607 | Cycle 1(1): loaded http://localhost:35522/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35522/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:50:04 INFO - PID 27607 | RSS: Main: 171065344 16:50:04 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | Cycle 1(1): loaded http://localhost:35522/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35522/tests/kraken/driver.html?testName=ai-astar) 16:50:06 INFO - PID 27607 | RSS: Main: 172711936 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | __start_tp_report 16:50:06 INFO - PID 27607 | _x_x_mozilla_page_load 16:50:06 INFO - PID 27607 | _x_x_mozilla_page_load_details 16:50:06 INFO - PID 27607 | |i|pagename|runs| 16:50:06 INFO - PID 27607 | |0;ai-astar;101;108;94;108;107;117;112;107;108;108 16:50:06 INFO - PID 27607 | |1;audio-beat-detection;149;117;150;172;121;146;139;121;176;122 16:50:06 INFO - PID 27607 | |2;audio-dft;163;162;163;164;163;162;167;163;165;164 16:50:06 INFO - PID 27607 | |3;audio-fft;74;73;73;171;78;77;76;77;152;74 16:50:06 INFO - PID 27607 | |4;audio-oscillator;80;80;133;78;80;79;133;80;81;135 16:50:06 INFO - PID 27607 | |5;imaging-gaussian-blur;114;114;114;113;114;114;114;114;113;114 16:50:06 INFO - PID 27607 | |6;imaging-darkroom;148;147;147;147;150;147;146;149;148;148 16:50:06 INFO - PID 27607 | |7;imaging-desaturate;94;94;94;95;95;94;95;94;94;94 16:50:06 INFO - PID 27607 | |8;json-parse-financial;53;53;55;52;51;52;52;54;52;61 16:50:06 INFO - PID 27607 | |9;json-stringify-tinderbox;38;38;38;37;37;37;38;38;37;38 16:50:06 INFO - PID 27607 | |10;stanford-crypto-aes;76;77;75;78;78;77;75;74;77;77 16:50:06 INFO - PID 27607 | |11;stanford-crypto-ccm;125;132;120;120;124;130;124;118;120;161 16:50:06 INFO - PID 27607 | |12;stanford-crypto-pbkdf2;190;170;180;177;168;175;181;172;173;189 16:50:06 INFO - PID 27607 | |13;stanford-crypto-sha256-iterative;66;65;65;66;64;65;66;64;63;63 16:50:06 INFO - PID 27607 | __end_tp_report 16:50:06 INFO - PID 27607 | __start_cc_report 16:50:06 INFO - PID 27607 | _x_x_mozilla_cycle_collect,927 16:50:06 INFO - PID 27607 | __end_cc_report 16:50:06 INFO - PID 27607 | __startTimestamp1504050606462__endTimestamp 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | ------- Summary: start ------- 16:50:06 INFO - PID 27607 | Number of tests: 14 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | [#0] ai-astar Cycles:10 Average:107.00 Median:108.00 stddev:6.09 (5.6%) stddev-sans-first:6.06 16:50:06 INFO - PID 27607 | Values: 101.0 108.0 94.0 108.0 107.0 117.0 112.0 107.0 108.0 108.0 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:147.50 stddev:21.33 (14.5%) stddev-sans-first:22.44 16:50:06 INFO - PID 27607 | Values: 149.0 117.0 150.0 172.0 121.0 146.0 139.0 121.0 176.0 122.0 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | [#2] audio-dft Cycles:10 Average:163.60 Median:163.50 stddev:1.51 (0.9%) stddev-sans-first:1.58 16:50:06 INFO - PID 27607 | Values: 163.0 162.0 163.0 164.0 163.0 162.0 167.0 163.0 165.0 164.0 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | [#3] audio-fft Cycles:10 Average:92.50 Median:75.00 stddev:36.68 (48.9%) stddev-sans-first:38.29 16:50:06 INFO - PID 27607 | Values: 74.0 73.0 73.0 171.0 78.0 77.0 76.0 77.0 152.0 74.0 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:80.00 stddev:26.08 (32.6%) stddev-sans-first:27.02 16:50:06 INFO - PID 27607 | Values: 80.0 80.0 133.0 78.0 80.0 79.0 133.0 80.0 81.0 135.0 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:50:06 INFO - PID 27607 | Values: 114.0 114.0 114.0 113.0 114.0 114.0 114.0 114.0 113.0 114.0 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:1.16 (0.8%) stddev-sans-first:1.22 16:50:06 INFO - PID 27607 | Values: 148.0 147.0 147.0 147.0 150.0 147.0 146.0 149.0 148.0 148.0 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 16:50:06 INFO - PID 27607 | Values: 94.0 94.0 94.0 95.0 95.0 94.0 95.0 94.0 94.0 94.0 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:53.00 stddev:2.88 (5.4%) stddev-sans-first:3.05 16:50:06 INFO - PID 27607 | Values: 53.0 53.0 55.0 52.0 51.0 52.0 52.0 54.0 52.0 61.0 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | [#9] json-stringify-tinderbox Cycles:10 Average:37.60 Median:38.00 stddev:0.52 (1.4%) stddev-sans-first:0.53 16:50:06 INFO - PID 27607 | Values: 38.0 38.0 38.0 37.0 37.0 37.0 38.0 38.0 37.0 38.0 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | [#10] stanford-crypto-aes Cycles:10 Average:76.40 Median:77.00 stddev:1.35 (1.8%) stddev-sans-first:1.42 16:50:06 INFO - PID 27607 | Values: 76.0 77.0 75.0 78.0 78.0 77.0 75.0 74.0 77.0 77.0 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | [#11] stanford-crypto-ccm Cycles:10 Average:127.40 Median:124.50 stddev:12.64 (10.2%) stddev-sans-first:13.38 16:50:06 INFO - PID 27607 | Values: 125.0 132.0 120.0 120.0 124.0 130.0 124.0 118.0 120.0 161.0 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.50 Median:178.50 stddev:7.53 (4.2%) stddev-sans-first:6.49 16:50:06 INFO - PID 27607 | Values: 190.0 170.0 180.0 177.0 168.0 175.0 181.0 172.0 173.0 189.0 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:65.00 stddev:1.16 (1.8%) stddev-sans-first:1.13 16:50:06 INFO - PID 27607 | Values: 66.0 65.0 65.0 66.0 64.0 65.0 66.0 64.0 63.0 63.0 16:50:06 INFO - PID 27607 | -------- Summary: end -------- 16:50:06 INFO - PID 27607 | 16:50:06 INFO - PID 27607 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:50:07 INFO - TEST-INFO | 27607: exit 0 16:50:07 INFO - TEST-OK | kraken | took 61018ms 16:50:07 INFO - SUITE-END | took 1172s 16:50:07 INFO - Completed test suite (00:19:32) 16:50:07 INFO - javascript benchmark 16:50:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8562.68188756277, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3280.360329735701, 3313.7514686398517, 3325.1188435064696, 3336.4328064229085, 3351.865925362984, 6212.881681297365, 6285.968570157154, 6289.308176100625, 6308.810735677926, 6341.175647165862, 6123.081537769326, 6221.377862213776, 6226.159468471755, 6264.154339164223, 6264.4048815362585, 5702.344230413514, 5712.428757124275, 5731.598788084836, 5747.281589801275, 5762.6254293471, 5742.827715168552, 5745.166950792153, 5762.798302059428, 5792.478676919077, 5795.536357091443, 5737.684427356494, 5747.712614369286, 5793.391693872146, 5796.188533605298, 5863.032599621058, 5787.623804452718, 5818.912716309259, 5844.0, 5845.970770146143, 5917.644941303535, 5756.050251708463, 5773.682447465388, 5849.561282903799, 5855.941440585582, 5900.114982752579, 5884.352721200665, 5985.670788106652, 6179.50563954887, 6312.652804095773, 6342.270638876542, 6578.046183303442, 6634.0712300278, 6640.7675731349655, 6826.692798824065, 6835.043093966849, 6709.060731497594, 6813.0, 6901.171859376856, 6906.102206713145, 6916.446684265246, 6210.9068363974575, 6294.496440284812, 6303.779367722108, 6308.526860485482, 6389.0, 6777.423918966844, 6816.147981502312, 6869.690863911036, 6909.481788865855, 6959.652017399109, 6545.639989800511, 6605.537612367184, 6612.570182938093, 6632.403083722392, 6636.734530618818, 6666.533342665967, 6749.122614060239, 6753.831154221184, 6829.180498340231, 6829.248782633907, 6713.597184168868, 6721.092652491949, 6810.114685091005, 6814.2163651180745, 6819.863602728017, 6589.4069533742195, 6650.0, 6700.061991321219, 6735.461163106939, 6777.220619628707, 5295.232191332235, 5347.705876176769, 5377.650452720561, 5387.0, 5404.32445944257, 4615.892185875433, 4664.072761517709, 4721.126591580569, 4726.71639701619, 4743.762811859393, 5845.532357411397, 5854.385289544536, 5925.288965324189, 5925.703714814242, 5942.0, 6614.669266536654, 6637.070810086591, 6716.328367163244, 6727.899081513781, 6791.185057793096, 5873.911891321633, 5934.1692163097205, 5954.583179177503, 5962.850928726816, 5965.075413310944, 5731.140328950607, 5745.597808153389, 5751.9354632052255, 5818.709064546756, 5843.357230704619, 6453.645049522322, 6478.125453063776, 6518.869622607522, 6549.377809108127, 6565.507586930904, 6590.637514936724, 6668.599884006975, 6677.532572720057, 6695.397414232548, 6713.194416669836, 6387.265464471459, 6400.167978162809, 6469.708863101077, 6471.385218404244, 6485.675716214265, 6428.260750013808, 6561.835954101186, 6565.573237739532, 6570.211574611078, 6589.308122647245, 6612.537122401386, 6683.933160668331, 6784.118064651564, 6905.654717264017, 6912.585244885323, 6478.838029049123, 6487.351264873475, 6557.508186885907, 6566.9343306566325, 6567.179102612174, 6269.96865015672, 6497.9476769289895, 6504.642244676588, 6597.0764093026055, 6598.10925525048, 6347.143135676624, 6358.459530939922, 6372.776952806815, 6374.681265936777, 6374.840628984312, 6487.967560162169, 6603.075569420381, 6603.9009414859775, 6680.9331906682255, 6692.297308782508, 6186.288576813724, 6296.905546416716, 6387.584806987531, 6393.424591786576, 6395.42441180296], "value": 6136.211775962978, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.8588711088449, 792.4492477727978, 810.6068556749977, 814.588632740466, 1141.6632093532407, 31055.2236194095, 31566.368672626562, 31598.526022109687, 42643.0, 43692.563074369245, 43419.13161736763, 43791.56208437915, 43798.34302485465, 43812.780936095274, 43822.78088609553, 7517.398608111338, 7922.168172341908, 8082.43422960393, 8085.838283234331, 8124.350051995826, 8000.119986801447, 8113.797155071112, 8119.350451963829, 8122.472039317455, 8125.5530945797955, 8105.1084380718075, 8155.836883262331, 8156.388270879678, 8163.469374490669, 8171.142030086845, 8109.107998120202, 8121.593920303991, 8130.593470326489, 8130.837383252361, 8131.268185863241, 7936.0, 7979.0, 7979.281864632153, 7979.601019948979, 7989.600519973978, 37660.435093473345, 37773.0, 37839.0, 37899.621003789885, 37931.620683793364, 3523.9647603523895, 3548.503209550665, 3553.1117220694828, 3564.1624218308675, 3567.625399333059, 31552.368952620818, 31565.684343156732, 31610.841945790355, 31636.683633163604, 31843.52234716481, 5138.46046165151, 5140.8457746267295, 5218.947810521884, 5250.7112108834, 5256.185291279858, 186645.0, 187958.0, 188171.0, 188260.0, 188680.0, 48249.0, 48920.5107948916, 49077.50922490729, 49305.26042109371, 49313.75343123262, 50997.23504147441, 51062.74468627633, 51091.489085108675, 51101.0, 51181.232281515804, 16319.755203671955, 16486.34054637825, 16491.752623710654, 16497.505074847773, 16524.6695066098, 11070.0, 11319.773604527863, 13407.597772066854, 13544.45822167122, 13856.237906915216, 13759.10565813219, 13853.792193117111, 13859.930700346435, 13869.930650346683, 14004.229767362893, 5642.097264437671, 5649.548036157097, 5737.110747834093, 5751.798687045934, 5811.622244554091, 996.6710985374809, 1020.5560581147224, 1023.7287118913493, 1027.209049032256, 1027.2706378471369, 23079.92202729065, 23303.30090097266, 23319.88340058323, 23331.41671458193, 23614.409639759142, 1068.6206396729206, 1123.4438952718456, 1125.431657013203, 1126.4649291586431, 1134.7843909657138, 11792.469338879771, 12266.632001039983, 12322.260664360167, 12322.32227227511, 12522.248665080124, 234560.0, 236832.0, 236954.0, 237241.0, 237395.0, 51941.0, 51957.740211298704, 52046.0, 52163.47836521662, 52224.738876305375, 1451.3759083593945, 1452.7385070687167, 1462.393106860641, 1467.6477645365035, 1470.4559313054237, 21587.568248635253, 21874.343769686933, 22191.22330718417, 22318.0, 22373.552528949655, 37117.62882371142, 37404.0, 37488.625113748516, 37517.0, 37552.43671344987, 201515.0, 201857.0, 201919.0, 202076.0, 202348.0, 32625.510617341235, 32830.67169328276, 36866.26267474689, 38576.0, 38779.418308724824, 46906.296405554625, 47658.28512572245, 47693.761531192125, 47711.28433073437, 47745.52254477411, 34844.0, 35082.12294692653, 35232.82383588168, 35310.47034294436, 35589.46615800713, 34366.65633343734, 34556.48165277573, 35036.299274014884, 35111.12222194363, 35566.466503002994, 37790.244195116495, 38706.80646596749, 38775.6122438772, 39082.80458597689, 39121.41317880177, 33749.83125084359, 34256.0, 34430.48354274638, 34458.0, 34599.30801383908, 24846.751532484443, 24861.378465538506, 25278.721278721277, 25406.89008858107, 25506.0], "value": 17486.78845298682, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.2044716949102, 566.501478698745, 566.5495930735068, 569.4647031790114, 576.6367188671137, 20617.0, 22506.77493225067, 23315.417114572123, 23368.182113626048, 23431.882840585815, 24687.876560617173, 25191.49617007659, 25279.0, 25320.620190697155, 25344.366390840238, 6583.604983700993, 6771.525993180479, 6787.4570034397375, 6790.151231096113, 6791.354821291994, 6264.436200741932, 6535.281119076922, 6567.704453299589, 6572.967135164317, 6663.833404164886, 6521.6413097279865, 6522.249941256769, 6528.0, 6528.249251336086, 6530.412262896339, 6520.7065682044185, 6525.119108920307, 6525.6737163142125, 6530.216374035099, 6532.0, 5979.0, 5980.073088671263, 6029.613188966562, 6031.276246850406, 6054.757809687607, 28573.428531429254, 28964.8551757242, 29128.417431651247, 29145.0, 29373.70626293731, 2010.2160157538572, 2047.406252186864, 2051.2205361962506, 2052.2509284111343, 2053.1376821734907, 19344.516387090294, 19362.0, 20209.0, 20219.090140943692, 20249.898750506152, 2352.0826877517784, 2417.3352328109736, 2427.490227052321, 2439.829211955164, 2526.2926380613276, 145332.0, 149193.50806491903, 151392.2430387841, 151480.0, 152045.23977380153, 14769.92615036918, 15151.393944242107, 15171.317290721943, 15226.086434813946, 16322.428714994918, 23091.88454057719, 23097.88451057734, 23101.653475197887, 23129.653055204184, 23133.190338338412, 10149.492525373702, 10369.066783989478, 10461.84307235392, 10521.631742888962, 10524.947375263228, 9932.751681207883, 10052.296339256329, 10092.0, 10171.186305095574, 10336.17310615149, 9209.901593790699, 9313.953430232941, 9390.90609093914, 9422.575984080595, 9514.762130946643, 2772.6534183227095, 2778.5554311310498, 2788.512010398172, 2802.285417218596, 2856.485832550161, 976.7997560500222, 978.4667356290838, 989.0900371658092, 993.7863359377768, 1009.7929924365487, 19145.2341906325, 19336.226550938085, 19619.01904904749, 19979.20083196685, 20120.19519219244, 1052.4106500359824, 1057.9947100264449, 1063.7553362726617, 1072.683558350273, 1079.384750692098, 10969.890301096886, 10973.83539246912, 11067.335959842436, 11160.991021616188, 11193.0, 457965.0, 469564.0, 472534.0, 480961.0, 482956.0, 111131.0, 111338.44330778295, 111793.44103279595, 111861.0, 112081.43959280151, 1038.5897570459626, 1063.3354153653966, 1176.588194132064, 1200.1119171812795, 1220.7497463020059, 20167.29414470545, 20179.0, 20216.696749548475, 20293.086811093242, 20357.592848142656, 55122.0, 56405.0, 56570.0, 56867.431325686215, 57248.71375643095, 69244.30755692378, 69572.652136739, 69762.3023769776, 69815.0, 70013.64993175001, 14078.648033799236, 14392.568222953329, 14409.495667651581, 14441.7833732492, 14442.927785361006, 22428.439289017253, 22678.886605566866, 22781.658275126218, 22919.65620515727, 22981.88509057444, 17722.46832595024, 18178.90910545439, 18190.181441834884, 18298.085095744893, 18438.89366638006, 16203.675926481641, 16301.184940753152, 16425.028996520017, 16474.258658360162, 16649.75126865514, 17838.91080544589, 18105.094745262948, 18797.624047519246, 18991.24035038583, 19012.714809277597, 14095.0, 14186.645333866736, 14194.219317937612, 14194.432222710975, 14199.503017394341, 16868.57828554247, 18154.546136346697, 18173.81826181774, 18218.36235731743, 18347.90826045861], "value": 12031.544692349671, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1298.8831005209531, 1346.6027521881044, 1362.23033985798, 1378.207530669865, 1379.5033787836378, 14377.0, 14474.348654310556, 15123.546293611182, 15124.621884452894, 15145.242737863091, 15184.6963060739, 15377.84622153778, 15408.07551546908, 15413.537593872174, 15427.92286038571, 2723.6459260296133, 2757.9724202758016, 2762.2541913683294, 2762.2818067302546, 2765.046059109614, 2654.66816647919, 2707.3096360428062, 2722.673279206498, 2848.615436916021, 2849.487092323381, 2812.6624805023425, 2841.8294902305825, 2843.0760002999004, 2843.758280546156, 2849.3873817129315, 2663.826851254672, 2718.510668079745, 2781.1517487166407, 2790.6232658591034, 2794.9441011179865, 2618.882150303241, 2662.334416395901, 2664.467106578692, 2666.6666666666665, 2668.746469085434, 14519.20144392058, 14543.418263269457, 14669.853301467063, 14677.412903483848, 14693.632659183606, 7818.530888146729, 8109.837803243961, 8120.0, 8124.268815806606, 8129.187081291823, 5284.365876094893, 5323.547498462635, 5461.563074954034, 5494.450554944514, 5528.336599608073, 1893.6875415556417, 1898.9905050474797, 1930.8938008409532, 1982.3557343863301, 1998.9700154497693, 25447.872760636077, 25652.102176423923, 25814.354641133934, 25825.612615810776, 25857.353566160808, 6671.199456065303, 6687.799366019027, 6693.631850248283, 6726.663666816639, 6798.116244888133, 16387.83612163887, 16391.70516127743, 16401.0979396132, 16421.58946026359, 16442.75335869963, 7455.366293864974, 7622.390208783284, 7666.156722760492, 7667.156612772591, 7713.074431068308, 7028.578285302899, 7178.4616153788675, 7297.744578939712, 7357.558546487228, 7362.153352364439, 5098.490150984946, 5273.578113750891, 5303.939212157584, 5319.335083114611, 5360.0, 1396.9720605588034, 1439.5825210688783, 1506.0888162661652, 1515.3256800723793, 1543.074155506687, 876.1545108969875, 882.4087861132928, 892.3842548641417, 897.3135551303258, 906.6781292641017, 7251.948467472187, 7403.22266162056, 7470.439717021245, 7486.251374862579, 7528.924710752932, 972.7130496503407, 974.7563109222694, 981.8821741390936, 997.9550920208608, 1005.939643621385, 5694.829155125353, 5701.287339082614, 5906.881862362815, 5917.644941303535, 5957.927573036895, 20372.083256253503, 21144.259950901644, 21301.254456093964, 21308.147674093172, 21314.893425532773, 19850.404487865388, 20198.899005504878, 20499.180032798522, 20602.381928542167, 20630.690539641917, 11156.386398747984, 11356.772864542663, 11468.369239691898, 11487.310761354345, 11511.251768635011, 8711.47731136134, 8744.606492707971, 8902.109789021306, 9001.954990225007, 9055.95472022662, 17896.46310610684, 18009.549761256072, 18041.36855210114, 18327.816721833144, 18462.07689615487, 18056.90971545134, 18122.81877181211, 18134.909325453817, 18176.818231817513, 18442.907785460986, 10805.513751881026, 10982.121430285399, 10990.17573681993, 10990.230683852373, 10995.560177592806, 13312.134711243738, 13537.390817413043, 13661.38523766413, 13740.450381984609, 13746.381412836647, 9230.261579073524, 10325.63860264887, 11299.943500282447, 11325.377104259345, 11608.535658573563, 10387.324823886423, 10388.37669739818, 10434.530446129791, 10556.366618002945, 10769.084627806411, 8962.955185224033, 9153.084691530794, 9156.633734650806, 9165.0, 9170.312226583166, 6898.206706228647, 6920.480963927826, 6936.375726184668, 6950.096487456598, 6982.33667801558, 6593.076969224217, 6614.702338394687, 6634.170728658918, 6648.501362397743, 6808.38724514796], "value": 6580.178666691867, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.8390682645706, 499.64486027088583, 512.1702841396936, 512.4004914250327, 519.5116590405019, 6304.684765761705, 6311.400416960387, 6319.873602527952, 6357.777477788272, 6391.360863913606, 6115.205023346959, 6132.969335153329, 6156.4766994805495, 6193.659348735818, 6287.748490060403, 4938.111204945111, 4989.65072444929, 5035.748212589374, 5041.269460599148, 5043.697378157304, 4751.049790041988, 4968.975155124219, 4972.602191824645, 5034.395872495305, 5057.393112826448, 4915.3855768029, 4925.778339974692, 4943.77753001114, 4960.23116416956, 4963.826266080688, 4946.72792996385, 4957.157283261854, 4976.6516343855765, 4993.375828021497, 5005.2492126181, 4354.912901741979, 4376.474823021226, 4405.449318835145, 4407.647388208936, 4417.801198946023, 6086.452219300284, 6163.691815409256, 6233.345498722614, 6238.0, 6248.87502249957, 5579.888402231933, 5703.17303990919, 5704.914426283609, 5726.742296596663, 5747.310322761254, 6509.869802603921, 6513.413792758674, 6518.348165183491, 6535.215774107138, 6566.409023187888, 4778.378810754614, 4807.759612019385, 4818.301346304801, 4820.734859582722, 4830.637702172351, 6872.862542749217, 6948.166220053626, 6981.685824137875, 7011.298870112999, 7042.507024508286, 4876.804927802848, 4935.728534930541, 4936.555709986119, 4943.3573635427165, 4965.47862474435, 4974.228994505857, 5001.299818025479, 5023.2214006829, 5039.732120545769, 5050.873728156752, 5666.9799436101075, 5671.404502527175, 5698.971505142447, 5700.40145784695, 5710.3433105193435, 6516.6415847128865, 6598.47212223021, 6603.141591593062, 6656.301088385747, 6703.597784132968, 1625.252383903394, 1651.7687523746686, 1653.768472413863, 1658.477579562434, 1736.5224563244956, 1190.1787766441128, 1190.9702257443632, 1207.4264724255909, 1220.6399112261727, 1221.2428294457493, 5785.334686511021, 5791.131330300489, 5833.358330583633, 5842.620229685057, 5873.647581145145, 6673.232578253563, 6753.12209412783, 6756.898646520306, 6807.693653785592, 6839.521233513607, 5812.9709351452975, 5865.94134058654, 5868.471837534557, 5868.471837534642, 5891.556568640711, 3568.233724306433, 3603.801790901473, 3620.8913732588067, 3626.3110009098714, 3626.3291291111227, 6536.444402225769, 6574.802755917234, 6611.33886611335, 6641.601503909781, 6645.700943457459, 6908.0, 6937.410320122745, 6941.167059952737, 6949.7567585134275, 7006.509544331848, 6540.215174179129, 6573.835654108686, 6577.2436169841085, 6593.901091483535, 6613.66931653425, 6828.829279268058, 6833.794986150423, 6845.4865885057825, 6862.142232220973, 6877.58734475933, 6744.089547911165, 6746.291639377795, 6765.9363894012795, 6769.74759669443, 6806.217285012287, 6507.902381464377, 6547.148870646785, 6566.80299591013, 6616.867662646624, 6633.60198388079, 4879.658423910292, 4899.755012249445, 4909.484504127158, 4909.828156014522, 4912.582430493305, 6593.835154121185, 6721.428678562182, 6759.391654751096, 6786.151731033621, 6787.830304242236, 6370.713317900613, 6423.229212494531, 6504.284528701744, 6562.0, 6614.570052946543, 6461.709223085067, 6474.021949903097, 6491.350864913471, 6550.541462097607, 6589.0775291460195, 6272.620023594802, 6369.426751592379, 6373.649449280334, 6379.808605741835, 6394.7442102317245, 6613.702383392662, 6621.867562648817, 6662.766803161866, 6700.4588944544, 6723.462123030244, 6400.263969643551, 6441.645709485836, 6471.417572418505, 6474.902876456763, 6481.643509607017], "value": 5061.333046114297, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [882.8101958079016, 903.3721563513361, 907.7276816954914, 910.4946754551225, 915.8809354783879, 15905.44330948417, 16480.0, 16515.421960231393, 16548.586285342873, 20278.391648250577, 19072.523186920298, 19323.806761932377, 19341.129649165785, 19363.515912102204, 19378.51553711158, 907.1790030022806, 945.6406565505133, 987.9555420006081, 990.1039559198937, 993.6075250276136, 998.7453199434134, 1007.5012868630039, 1007.9949600251988, 1008.4655132779639, 1010.0152351457307, 1039.8492218628294, 1047.8847326794046, 1050.1074087026043, 1050.2910535388621, 1050.333038520541, 1037.190991027, 1038.9688309350731, 1040.5109598488737, 1041.0318403884385, 1041.1930753665931, 769.2230846844672, 770.9845803083907, 771.8880762289436, 772.7411317208741, 772.9072511298624, 16686.33254669812, 16871.493855184362, 17817.732734009, 17958.640827183382, 18066.819331806644, 20924.895375523178, 21223.78776212249, 21325.146994120372, 21562.1375144994, 21769.23807666724, 15228.238588070551, 15373.615659608598, 15390.461333853373, 15416.3833446662, 16706.749398759028, 3755.248950209969, 3764.8117594120185, 3767.2842159989787, 3773.811309434517, 3786.1859700164323, 120666.3966680161, 121267.39366303112, 123298.38350808188, 124212.0, 129414.0, 16234.0, 16505.17474126313, 17074.231659575347, 17205.56986075358, 17259.309627614755, 29874.253143671584, 29991.550126748116, 30169.849150754108, 30215.0, 30274.243143921136, 6472.935270647234, 8936.8659470108, 9025.097490250922, 9033.36766426357, 9049.366544341832, 3783.1109689223013, 4124.587541245852, 4289.635380992589, 4312.439382880206, 4314.546972567898, 5113.565346945551, 5155.046316431472, 5213.791448342024, 5242.816501422432, 5243.711595862264, 1046.2885238038054, 1055.551390658967, 1059.0997651995742, 1062.3201151263197, 1065.5577935156823, 650.1677852349001, 663.3929954091952, 683.1938312790855, 692.3491917597443, 692.9341712537401, 11296.661100167008, 11400.315981041163, 11606.535738570363, 11735.765284694258, 12637.620871373874, 668.1047396488729, 674.3559900295206, 674.9527533072638, 677.1873751498222, 682.9146356705412, 5735.3977832327, 5828.737706803204, 5984.551158663117, 6048.727807248684, 6106.053561697944, 114590.28114578288, 114700.42649786698, 118117.40941295239, 121396.39301803435, 123773.38113309376, 98752.50623746835, 101947.49026254969, 103490.0, 107386.0, 107719.46140269405, 86022.0, 88251.1174888243, 89282.10717892868, 91848.54075729712, 93858.0, 10295.897041029493, 12741.87258127444, 12933.676658083246, 13120.278384688934, 13127.0, 40330.193396131326, 41302.79348603238, 41836.3724544126, 41992.320307188595, 42403.0, 72421.0, 73896.26103739109, 74960.0, 76021.6198919024, 78254.608726956, 17685.55786105306, 17965.64068718592, 18116.637667246843, 18277.0, 18298.085095744893, 20558.0, 21298.25456109029, 21890.562188756456, 22062.558748825253, 22103.889480552494, 18039.549011274823, 18783.718244226075, 19429.805701943365, 20034.59930801405, 20292.79707202909, 16830.831691682928, 17229.91385043117, 17239.3966211182, 18141.36505222364, 18603.06984650789, 17525.386611468537, 17543.298268069135, 17691.469255922344, 17769.822301776818, 18595.256189752257, 13552.93223533876, 13625.795613065613, 13644.386002629706, 13855.79216311736, 14033.649158771112, 14578.125312481285, 14785.630359241106, 14793.852061479247, 14865.182414967281, 14999.250037497863], "value": 9166.380952136687, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1493.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 108.0, 94.0, 108.0, 107.0, 117.0, 112.0, 107.0, 108.0, 108.0], "value": 107.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 150.0, 172.0, 121.0, 146.0, 139.0, 121.0, 176.0, 122.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 164.0, 163.0, 162.0, 167.0, 163.0, 165.0, 164.0], "value": 163.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 171.0, 78.0, 77.0, 76.0, 77.0, 152.0, 74.0], "value": 92.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 133.0, 78.0, 80.0, 79.0, 133.0, 80.0, 81.0, 135.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 147.0, 150.0, 147.0, 146.0, 149.0, 148.0, 148.0], "value": 147.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 55.0, 52.0, 51.0, 52.0, 52.0, 54.0, 52.0, 61.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 37.0, 37.0, 37.0, 38.0, 38.0, 37.0, 38.0], "value": 37.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 75.0, 78.0, 78.0, 77.0, 75.0, 74.0, 77.0, 77.0], "value": 76.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 132.0, 120.0, 120.0, 124.0, 130.0, 124.0, 118.0, 120.0, 161.0], "value": 127.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 170.0, 180.0, 177.0, 168.0, 175.0, 181.0, 172.0, 173.0, 189.0], "value": 177.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 66.0, 64.0, 65.0, 66.0, 64.0, 63.0, 63.0], "value": 64.7, "unit": "score"}]}]} 16:50:07 INFO - Return code: 0 16:50:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:50:07 INFO - # TBPL SUCCESS # 16:50:07 INFO - Running post-action listener: _package_coverage_data 16:50:07 INFO - Running post-action listener: _resource_record_post_action 16:50:07 INFO - [mozharness: 2017-08-29 23:50:07.740101Z] Finished run-tests step (success) 16:50:07 INFO - Running post-run listener: _resource_record_post_run 16:50:07 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 393019392; Read time: 12; Write time: 224732 16:50:07 INFO - TinderboxPrint: CPU usage
13.1% 16:50:07 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:50:07 INFO - TinderboxPrint: I/O write bytes / time
393,019,392 / 224,732 16:50:07 INFO - TinderboxPrint: CPU idle
8,239.6 (86.9%) 16:50:07 INFO - TinderboxPrint: CPU user
1,193.4 (12.6%) 16:50:07 INFO - TinderboxPrint: Swap in / out
0 / 0 16:50:07 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 107110400; Read time: 0; Write time: 77972 16:50:07 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:50:07 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 285908992; Read time: 12; Write time: 146760 16:50:08 INFO - Running post-run listener: _upload_blobber_files 16:50:08 INFO - Blob upload gear active. 16:50:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:50:08 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 16:50:08 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'] 16:50:08 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 16:50:08 INFO - (blobuploader) - INFO - Open directory for files ... 16:50:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:50:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:50:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:09 INFO - (blobuploader) - INFO - Done attempting. 16:50:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:50:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:50:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:17 INFO - (blobuploader) - INFO - Done attempting. 16:50:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:50:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:50:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:18 INFO - (blobuploader) - INFO - Done attempting. 16:50:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:50:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:50:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:19 INFO - (blobuploader) - INFO - Done attempting. 16:50:19 INFO - (blobuploader) - INFO - Iteration through files over. 16:50:19 INFO - Return code: 0 16:50:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:50:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:50:19 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d676439cf49e78a32af4be9453b098644f4244f5aa9aad9922a6131cbdbdc5fa1a1e99335c84672220f75c0c5cdce9814ef3399056e622f3721ddab7cf71c967", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/03c581c5f812bb2ca2c9f0e4e890ab22bfc903de4fb98ce3744f8028ed80e3d407439134ad3a9d50377a876c00aa9ea23885119d4673a83e1c5cbd3f3fc70dc0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9eb297399737f51325ff8384368a48b5f041d04fba0e71b03fba0275b0b8c525570205a663b899ea2a7bc1994830fe6de5f7f34466dc8dcc77ca654fcf6cfe15", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c7e6b6b6a822821173f5014de56023f2a1cc94b33a2c6bfa40a0708e4e35f8447d0deb1c594038804233b2348d12f40d46b2f7a11167b959f8d7f1dbf0918d81"} 16:50:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:50:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:50:19 INFO - Contents: 16:50:19 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d676439cf49e78a32af4be9453b098644f4244f5aa9aad9922a6131cbdbdc5fa1a1e99335c84672220f75c0c5cdce9814ef3399056e622f3721ddab7cf71c967", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/03c581c5f812bb2ca2c9f0e4e890ab22bfc903de4fb98ce3744f8028ed80e3d407439134ad3a9d50377a876c00aa9ea23885119d4673a83e1c5cbd3f3fc70dc0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9eb297399737f51325ff8384368a48b5f041d04fba0e71b03fba0275b0b8c525570205a663b899ea2a7bc1994830fe6de5f7f34466dc8dcc77ca654fcf6cfe15", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c7e6b6b6a822821173f5014de56023f2a1cc94b33a2c6bfa40a0708e4e35f8447d0deb1c594038804233b2348d12f40d46b2f7a11167b959f8d7f1dbf0918d81"} 16:50:19 INFO - Running post-run listener: copy_logs_to_upload_dir 16:50:19 INFO - Copying logs to upload dir... 16:50:19 INFO - mkdir: /builds/slave/test/build/upload/logs 16:50:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.343770 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-29 16:50:19.305812) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 16:50:19.309259) ========= 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=0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d676439cf49e78a32af4be9453b098644f4244f5aa9aad9922a6131cbdbdc5fa1a1e99335c84672220f75c0c5cdce9814ef3399056e622f3721ddab7cf71c967", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/03c581c5f812bb2ca2c9f0e4e890ab22bfc903de4fb98ce3744f8028ed80e3d407439134ad3a9d50377a876c00aa9ea23885119d4673a83e1c5cbd3f3fc70dc0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9eb297399737f51325ff8384368a48b5f041d04fba0e71b03fba0275b0b8c525570205a663b899ea2a7bc1994830fe6de5f7f34466dc8dcc77ca654fcf6cfe15", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c7e6b6b6a822821173f5014de56023f2a1cc94b33a2c6bfa40a0708e4e35f8447d0deb1c594038804233b2348d12f40d46b2f7a11167b959f8d7f1dbf0918d81"} build_url:https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011540 build_url: 'https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d676439cf49e78a32af4be9453b098644f4244f5aa9aad9922a6131cbdbdc5fa1a1e99335c84672220f75c0c5cdce9814ef3399056e622f3721ddab7cf71c967", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/03c581c5f812bb2ca2c9f0e4e890ab22bfc903de4fb98ce3744f8028ed80e3d407439134ad3a9d50377a876c00aa9ea23885119d4673a83e1c5cbd3f3fc70dc0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9eb297399737f51325ff8384368a48b5f041d04fba0e71b03fba0275b0b8c525570205a663b899ea2a7bc1994830fe6de5f7f34466dc8dcc77ca654fcf6cfe15", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c7e6b6b6a822821173f5014de56023f2a1cc94b33a2c6bfa40a0708e4e35f8447d0deb1c594038804233b2348d12f40d46b2f7a11167b959f8d7f1dbf0918d81"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 16:50:19.336314) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 16:50:19.336635) ========= 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=0e2a256762fed810e2f0f810000001b3-1504047651.858203-1483892012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004499 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 16:50:19.419021) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 16:50:19.419376) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 16:50:19.419690) ========= ========= Total master_lag: 0.20 =========