builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-083 starttime: 1501495040.9 results: success (0) revision: b1dcb20102b7598ec4d467530cd20ba9e8754de6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-31 02:57:20.900188) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-31 02:57:20.900559) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-31 02:57:20.900835) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-31 02:57:20.961442) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 02:57:20.961707) ========= 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=3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-31 02:57:21-- 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% 53.4K=0.2s 2017-07-31 02:57:21 (53.4 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.323408 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 02:57:21.298463) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 02:57:21.298795) ========= 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=3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019652 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 02:57:21.350872) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 02:57:21.351669) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b1dcb20102b7598ec4d467530cd20ba9e8754de6 --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 b1dcb20102b7598ec4d467530cd20ba9e8754de6 --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=3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-31 02:57:21,710 truncating revision to first 12 chars 2017-07-31 02:57:21,710 Setting DEBUG logging. 2017-07-31 02:57:21,710 attempt 1/10 2017-07-31 02:57:21,710 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b1dcb20102b7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-31 02:57:22,012 unpacking tar archive at: mozilla-central-b1dcb20102b7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.710920 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 02:57:22.076725) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-31 02:57:22.077086) ========= script_repo_revision: b1dcb20102b7598ec4d467530cd20ba9e8754de6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-31 02:57:22.077478) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-31 02:57:22.077743) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-31 02:57:22.085343) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-07-31 02:57:22.085622) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:57:22 INFO - MultiFileLogger online at 20170731 02:57:22 in /builds/slave/test 02:57:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:57:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:57:22 INFO - {'append_to_log': False, 02:57:22 INFO - 'base_work_dir': '/builds/slave/test', 02:57:22 INFO - 'blob_upload_branch': 'Firefox', 02:57:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:57:22 INFO - 'branch': 'Firefox', 02:57:22 INFO - 'buildbot_json_path': 'buildprops.json', 02:57:22 INFO - 'code_coverage': False, 02:57:22 INFO - 'config_files': ('talos/linux_config.py',), 02:57:22 INFO - 'default_actions': ('clobber', 02:57:22 INFO - 'read-buildbot-config', 02:57:22 INFO - 'download-and-extract', 02:57:22 INFO - 'populate-webroot', 02:57:22 INFO - 'create-virtualenv', 02:57:22 INFO - 'install', 02:57:22 INFO - 'setup-mitmproxy', 02:57:22 INFO - 'run-tests'), 02:57:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:57:22 INFO - 'disable_ccov_upload': False, 02:57:22 INFO - 'download_minidump_stackwalk': True, 02:57:22 INFO - 'download_symbols': 'ondemand', 02:57:22 INFO - 'enable_stylo': False, 02:57:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:57:22 INFO - 'tooltool.py': '/tools/tooltool.py', 02:57:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:57:22 INFO - '/tools/misc-python/virtualenv.py')}, 02:57:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:57:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:57:22 INFO - 'gecko_profile': False, 02:57:22 INFO - 'gecko_profile_interval': 0, 02:57:22 INFO - 'installer_path': 'installer.exe', 02:57:22 INFO - 'log_level': 'info', 02:57:22 INFO - 'log_name': 'talos', 02:57:22 INFO - 'log_to_console': True, 02:57:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:57:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:57:22 INFO - 'opt_config_files': (), 02:57:22 INFO - 'pip_index': False, 02:57:22 INFO - 'suite': 'dromaeojs-e10s', 02:57:22 INFO - 'system_bits': '32', 02:57:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:57:22 INFO - 'title': 'talos-linux64-ix-083', 02:57:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:57:22 INFO - 'use_talos_json': True, 02:57:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:57:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:57:22 INFO - 'work_dir': 'build'} 02:57:22 INFO - [mozharness: 2017-07-31 09:57:22.551492Z] Running clobber step. 02:57:22 INFO - Running pre-action listener: _resource_record_pre_action 02:57:22 INFO - Running main action method: clobber 02:57:22 INFO - rmtree: /builds/slave/test/build 02:57:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:57:24 INFO - Running post-action listener: _resource_record_post_action 02:57:24 INFO - [mozharness: 2017-07-31 09:57:24.095922Z] Finished clobber step (success) 02:57:24 INFO - [mozharness: 2017-07-31 09:57:24.096023Z] Running read-buildbot-config step. 02:57:24 INFO - Running pre-action listener: _resource_record_pre_action 02:57:24 INFO - Running main action method: read_buildbot_config 02:57:24 INFO - Using buildbot properties: 02:57:24 INFO - { 02:57:24 INFO - "project": "", 02:57:24 INFO - "product": "firefox", 02:57:24 INFO - "who": "archaeopteryx@coole-files.de", 02:57:24 INFO - "installer_path": "public/build/target.tar.bz2", 02:57:24 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 02:57:24 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:57:24 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 02:57:24 INFO - "stage_platform": "linux64-stylo", 02:57:24 INFO - "basedir": "/builds/slave/test", 02:57:24 INFO - "buildnumber": 246, 02:57:24 INFO - "platform": "ubuntu64_hw_stylo", 02:57:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:57:24 INFO - "slavebuilddir": "test", 02:57:24 INFO - "taskId": "KLEw1LFQQjKzyaQqAWmmPQ", 02:57:24 INFO - "branch": "mozilla-central", 02:57:24 INFO - "script_repo_revision": "production", 02:57:24 INFO - "revision": "b1dcb20102b7598ec4d467530cd20ba9e8754de6", 02:57:24 INFO - "slavename": "talos-linux64-ix-083", 02:57:24 INFO - "repo_path": "mozilla-central" 02:57:24 INFO - } 02:57:24 INFO - Finding installer, test and symbols from parent task. 02:57:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KLEw1LFQQjKzyaQqAWmmPQ'}, attempt #1 02:57:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KLEw1LFQQjKzyaQqAWmmPQ'}, attempt #1 02:57:25 INFO - Task dependencies: OypQKKTlTgyj1Ao4ZC_kMg 02:57:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg'}, attempt #1 02:57:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.tar.bz2 02:57:25 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.test_packages.json 02:57:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.crashreporter-symbols.zip 02:57:25 INFO - Running post-action listener: _resource_record_post_action 02:57:25 INFO - [mozharness: 2017-07-31 09:57:25.452075Z] Finished read-buildbot-config step (success) 02:57:25 INFO - [mozharness: 2017-07-31 09:57:25.452299Z] Running download-and-extract step. 02:57:25 INFO - Running pre-action listener: _resource_record_pre_action 02:57:25 INFO - Running main action method: download_and_extract 02:57:25 INFO - mkdir: /builds/slave/test/build/tests 02:57:25 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')]} 02:57:25 INFO - trying https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.test_packages.json 02:57:25 INFO - Downloading https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:57:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:57:25 INFO - Downloaded 989 bytes. 02:57:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:57:25 INFO - Using the following test package requirements: 02:57:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:57:25 INFO - u'common': [u'target.common.tests.zip'], 02:57:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:57:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:57:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:57:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:57:25 INFO - u'mozbase': [u'target.common.tests.zip'], 02:57:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:57:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:57:26 INFO - u'web-platform': [u'target.common.tests.zip', 02:57:26 INFO - u'target.web-platform.tests.tar.gz'], 02:57:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:57:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:57:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.common.tests.zip 02:57:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:57:26 INFO - Fetch https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.common.tests.zip into memory 02:57:28 INFO - Content-Length response header: 72389793 02:57:28 INFO - Bytes received: 72389793 02:57:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:57:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.common.tests.zip 02:57:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:57:39 INFO - Fetch https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.common.tests.zip into memory 02:57:41 INFO - Content-Length response header: 72389793 02:57:41 INFO - Bytes received: 72389793 02:57:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.talos.tests.zip 02:57:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:57:53 INFO - Fetch https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.talos.tests.zip into memory 02:57:54 INFO - Content-Length response header: 14496686 02:57:54 INFO - Bytes received: 14496686 02:57:54 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')]} 02:57:54 INFO - trying https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.tar.bz2 02:57:54 INFO - Downloading https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:57:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:57:55 INFO - Downloaded 62271564 bytes. 02:57:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.tar.bz2 02:57:55 INFO - mkdir: /builds/slave/test/properties 02:57:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:57:55 INFO - Writing to file /builds/slave/test/properties/build_url 02:57:55 INFO - Contents: 02:57:55 INFO - build_url:https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.tar.bz2 02:57:55 INFO - Running post-action listener: _resource_record_post_action 02:57:55 INFO - Running post-action listener: set_extra_try_arguments 02:57:55 INFO - [mozharness: 2017-07-31 09:57:55.901982Z] Finished download-and-extract step (success) 02:57:55 INFO - [mozharness: 2017-07-31 09:57:55.902080Z] Running populate-webroot step. 02:57:55 INFO - Running pre-action listener: _resource_record_pre_action 02:57:55 INFO - Running main action method: populate_webroot 02:57:55 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:57:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:57:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:57:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:57:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:57:55 INFO - 'tests': ['damp', 'tps']}, 02:57:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:57:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:57:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:57:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:57:55 INFO - 'other-e10s': {'tests': ['a11yr', 02:57:55 INFO - 'ts_paint', 02:57:55 INFO - 'tpaint', 02:57:55 INFO - 'sessionrestore', 02:57:55 INFO - 'sessionrestore_many_windows', 02:57:55 INFO - 'sessionrestore_no_auto_restore', 02:57:55 INFO - 'tabpaint']}, 02:57:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:57:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:57:55 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:57:55 INFO - 'talos_options': ['--mitmproxy', 02:57:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:57:55 INFO - '--firstNonBlankPaint'], 02:57:55 INFO - 'tests': ['quantum_pageload_google', 02:57:55 INFO - 'quantum_pageload_youtube', 02:57:55 INFO - 'quantum_pageload_amazon', 02:57:55 INFO - 'quantum_pageload_facebook']}, 02:57:55 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:57:55 INFO - 'talos_options': ['--stylo', 02:57:55 INFO - '--mitmproxy', 02:57:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:57:55 INFO - '--firstNonBlankPaint'], 02:57:55 INFO - 'tests': ['quantum_pageload_google', 02:57:55 INFO - 'quantum_pageload_youtube', 02:57:55 INFO - 'quantum_pageload_amazon', 02:57:55 INFO - 'quantum_pageload_facebook']}, 02:57:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:57:55 INFO - 'tsvgr_opacity', 02:57:55 INFO - 'tart', 02:57:55 INFO - 'tscrollx', 02:57:55 INFO - 'cart', 02:57:55 INFO - 'tsvg_static']}, 02:57:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:57:55 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:57:55 INFO - 'talos_options': ['--stylo', 02:57:55 INFO - '--stylo-threads=1', 02:57:55 INFO - '--mitmproxy', 02:57:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:57:55 INFO - '--firstNonBlankPaint'], 02:57:55 INFO - 'tests': ['tp6_google', 02:57:55 INFO - 'tp6_youtube', 02:57:55 INFO - 'tp6_amazon', 02:57:55 INFO - 'tp6_facebook']}, 02:57:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:57:55 INFO - 'talos_options': ['--xperf_path', 02:57:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:57:55 INFO - 'tests': ['tp5n']}}} 02:57:55 INFO - Running post-action listener: _resource_record_post_action 02:57:55 INFO - [mozharness: 2017-07-31 09:57:55.910740Z] Finished populate-webroot step (success) 02:57:55 INFO - [mozharness: 2017-07-31 09:57:55.910832Z] Running create-virtualenv step. 02:57:55 INFO - Running pre-action listener: _resource_record_pre_action 02:57:55 INFO - Running main action method: create_virtualenv 02:57:55 INFO - Creating virtualenv /builds/slave/test/build/venv 02:57:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:57:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:57:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:57:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:57:55 INFO - Using real prefix '/usr' 02:57:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:57:56 INFO - Installing distribute.............................................................................................................................................................................................done. 02:57:58 INFO - Installing pip.................done. 02:57:58 INFO - Return code: 0 02:57:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:57:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:57:58 INFO - Reading from file tmpfile_stdout 02:57:58 INFO - Output received: 02:57:58 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:57:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57: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')]} 02:57:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57: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 0x28711f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bddeb0>, '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 0x2bdedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdf240>, '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': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', '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 02:57: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 02:57: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 02:57:58 INFO - Using env: {'DISPLAY': ':0', 02:57:58 INFO - 'HOME': '/home/cltbld', 02:57:58 INFO - 'LANG': 'en_US.UTF-8', 02:57:58 INFO - 'LANGUAGE': 'en_US:en', 02:57:58 INFO - 'LOGNAME': 'cltbld', 02:57:58 INFO - 'MAIL': '/var/mail/cltbld', 02:57:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:58 INFO - 'MOZ_NO_REMOTE': '1', 02:57:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:58 INFO - 'NO_EM_RESTART': '1', 02:57:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:58 INFO - 'PWD': '/builds/slave/test', 02:57:58 INFO - 'SHELL': '/bin/bash', 02:57:58 INFO - 'SHLVL': '1', 02:57:58 INFO - 'TERM': 'linux', 02:57:58 INFO - 'TMOUT': '86400', 02:57:58 INFO - 'USER': 'cltbld', 02:57:58 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', 02:57:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:58 INFO - '_': '/tools/buildbot/bin/python'} 02:57:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:57:58 INFO - Cleaning up... 02:57:58 INFO - Return code: 0 02:57:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57: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')]} 02:57:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57: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', '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 0x28711f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bddeb0>, '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 0x2bdedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdf240>, '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': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', '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 02:57: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', 'psutil>=3.1.1'] in /builds/slave/test/build 02:57: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 psutil>=3.1.1 02:57:58 INFO - Using env: {'DISPLAY': ':0', 02:57:58 INFO - 'HOME': '/home/cltbld', 02:57:58 INFO - 'LANG': 'en_US.UTF-8', 02:57:58 INFO - 'LANGUAGE': 'en_US:en', 02:57:58 INFO - 'LOGNAME': 'cltbld', 02:57:58 INFO - 'MAIL': '/var/mail/cltbld', 02:57:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:58 INFO - 'MOZ_NO_REMOTE': '1', 02:57:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:58 INFO - 'NO_EM_RESTART': '1', 02:57:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:58 INFO - 'PWD': '/builds/slave/test', 02:57:58 INFO - 'SHELL': '/bin/bash', 02:57:58 INFO - 'SHLVL': '1', 02:57:58 INFO - 'TERM': 'linux', 02:57:58 INFO - 'TMOUT': '86400', 02:57:58 INFO - 'USER': 'cltbld', 02:57:58 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', 02:57:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:58 INFO - '_': '/tools/buildbot/bin/python'} 02:57:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:59 INFO - Downloading/unpacking psutil>=3.1.1 02:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:58:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:58:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:58:00 INFO - Installing collected packages: psutil 02:58:00 INFO - Running setup.py install for psutil 02:58:00 INFO - building 'psutil._psutil_linux' extension 02:58:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 02:58:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 02:58:00 INFO - building 'psutil._psutil_posix' extension 02:58:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 02:58:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 02:58:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:58:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:58:00 INFO - Successfully installed psutil 02:58:00 INFO - Cleaning up... 02:58:00 INFO - Return code: 0 02:58:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:58: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')]} 02:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58: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')]} 02:58:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28711f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bddeb0>, '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 0x2bdedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdf240>, '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': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', '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 02:58:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:58:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:58:00 INFO - Using env: {'DISPLAY': ':0', 02:58:00 INFO - 'HOME': '/home/cltbld', 02:58:00 INFO - 'LANG': 'en_US.UTF-8', 02:58:00 INFO - 'LANGUAGE': 'en_US:en', 02:58:00 INFO - 'LOGNAME': 'cltbld', 02:58:00 INFO - 'MAIL': '/var/mail/cltbld', 02:58:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:00 INFO - 'MOZ_NO_REMOTE': '1', 02:58:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:58:00 INFO - 'NO_EM_RESTART': '1', 02:58:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:58:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:00 INFO - 'PWD': '/builds/slave/test', 02:58:00 INFO - 'SHELL': '/bin/bash', 02:58:00 INFO - 'SHLVL': '1', 02:58:00 INFO - 'TERM': 'linux', 02:58:00 INFO - 'TMOUT': '86400', 02:58:00 INFO - 'USER': 'cltbld', 02:58:00 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', 02:58:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:00 INFO - '_': '/tools/buildbot/bin/python'} 02:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:58: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 02:58: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 02:58: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 02:58: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 02:58:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:58:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:58:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:58:02 INFO - Installing collected packages: mozsystemmonitor 02:58:02 INFO - Running setup.py install for mozsystemmonitor 02:58:02 INFO - Successfully installed mozsystemmonitor 02:58:02 INFO - Cleaning up... 02:58:02 INFO - Return code: 0 02:58:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:58: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')]} 02:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58: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')]} 02:58:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28711f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bddeb0>, '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 0x2bdedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdf240>, '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': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', '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 02:58:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:58:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:58:02 INFO - Using env: {'DISPLAY': ':0', 02:58:02 INFO - 'HOME': '/home/cltbld', 02:58:02 INFO - 'LANG': 'en_US.UTF-8', 02:58:02 INFO - 'LANGUAGE': 'en_US:en', 02:58:02 INFO - 'LOGNAME': 'cltbld', 02:58:02 INFO - 'MAIL': '/var/mail/cltbld', 02:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:02 INFO - 'MOZ_NO_REMOTE': '1', 02:58:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:58:02 INFO - 'NO_EM_RESTART': '1', 02:58:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:02 INFO - 'PWD': '/builds/slave/test', 02:58:02 INFO - 'SHELL': '/bin/bash', 02:58:02 INFO - 'SHLVL': '1', 02:58:02 INFO - 'TERM': 'linux', 02:58:02 INFO - 'TMOUT': '86400', 02:58:02 INFO - 'USER': 'cltbld', 02:58:02 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', 02:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:02 INFO - '_': '/tools/buildbot/bin/python'} 02:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:02 INFO - Downloading/unpacking jsonschema==2.5.1 02:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:58:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:58: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 02:58: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 02:58: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 02:58: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 02:58:03 INFO - Downloading functools32-3.2.3-2.tar.gz 02:58:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:58:03 INFO - warning: no files found matching '*.txt' 02:58:03 INFO - no previously-included directories found matching 'build' 02:58:03 INFO - no previously-included directories found matching 'dist' 02:58:03 INFO - no previously-included directories found matching '.git*' 02:58:03 INFO - Installing collected packages: jsonschema, functools32 02:58:03 INFO - Running setup.py install for functools32 02:58:03 INFO - warning: no files found matching '*.txt' 02:58:03 INFO - no previously-included directories found matching 'build' 02:58:03 INFO - no previously-included directories found matching 'dist' 02:58:03 INFO - no previously-included directories found matching '.git*' 02:58:03 INFO - Successfully installed jsonschema functools32 02:58:03 INFO - Cleaning up... 02:58:04 INFO - Return code: 0 02:58:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:58: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')]} 02:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58: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')]} 02:58:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28711f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bddeb0>, '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 0x2bdedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdf240>, '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': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', '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 02:58:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:58:04 INFO - Using env: {'DISPLAY': ':0', 02:58:04 INFO - 'HOME': '/home/cltbld', 02:58:04 INFO - 'LANG': 'en_US.UTF-8', 02:58:04 INFO - 'LANGUAGE': 'en_US:en', 02:58:04 INFO - 'LOGNAME': 'cltbld', 02:58:04 INFO - 'MAIL': '/var/mail/cltbld', 02:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:04 INFO - 'MOZ_NO_REMOTE': '1', 02:58:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:58:04 INFO - 'NO_EM_RESTART': '1', 02:58:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:04 INFO - 'PWD': '/builds/slave/test', 02:58:04 INFO - 'SHELL': '/bin/bash', 02:58:04 INFO - 'SHLVL': '1', 02:58:04 INFO - 'TERM': 'linux', 02:58:04 INFO - 'TMOUT': '86400', 02:58:04 INFO - 'USER': 'cltbld', 02:58:04 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', 02:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:04 INFO - '_': '/tools/buildbot/bin/python'} 02:58:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:58:04 INFO - Cleaning up... 02:58:04 INFO - Return code: 0 02:58:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:58: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')]} 02:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58: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')]} 02:58:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28711f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bddeb0>, '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 0x2bdedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdf240>, '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': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', '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 02:58:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:58:04 INFO - Using env: {'DISPLAY': ':0', 02:58:04 INFO - 'HOME': '/home/cltbld', 02:58:04 INFO - 'LANG': 'en_US.UTF-8', 02:58:04 INFO - 'LANGUAGE': 'en_US:en', 02:58:04 INFO - 'LOGNAME': 'cltbld', 02:58:04 INFO - 'MAIL': '/var/mail/cltbld', 02:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:04 INFO - 'MOZ_NO_REMOTE': '1', 02:58:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:58:04 INFO - 'NO_EM_RESTART': '1', 02:58:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:04 INFO - 'PWD': '/builds/slave/test', 02:58:04 INFO - 'SHELL': '/bin/bash', 02:58:04 INFO - 'SHLVL': '1', 02:58:04 INFO - 'TERM': 'linux', 02:58:04 INFO - 'TMOUT': '86400', 02:58:04 INFO - 'USER': 'cltbld', 02:58:04 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', 02:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:04 INFO - '_': '/tools/buildbot/bin/python'} 02:58:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:04 INFO - Downloading/unpacking blobuploader==1.2.4 02:58: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 02:58: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 02:58: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 02:58: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 02:58:05 INFO - Downloading blobuploader-1.2.4.tar.gz 02:58:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:58:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:58: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 02:58: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 02:58: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 02:58: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 02:58:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:58:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:58: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 02:58: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 02:58: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 02:58: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 02:58:06 INFO - Downloading docopt-0.6.1.tar.gz 02:58:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:58:06 INFO - Installing collected packages: blobuploader, requests, docopt 02:58:06 INFO - Running setup.py install for blobuploader 02:58:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:58:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:58:06 INFO - Running setup.py install for requests 02:58:06 INFO - Running setup.py install for docopt 02:58:06 INFO - Successfully installed blobuploader requests docopt 02:58:06 INFO - Cleaning up... 02:58:06 INFO - Return code: 0 02:58:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:58: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')]} 02:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58: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')]} 02:58:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28711f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bddeb0>, '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 0x2bdedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdf240>, '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': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', '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 02:58:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:58:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:58:06 INFO - Using env: {'DISPLAY': ':0', 02:58:06 INFO - 'HOME': '/home/cltbld', 02:58:06 INFO - 'LANG': 'en_US.UTF-8', 02:58:06 INFO - 'LANGUAGE': 'en_US:en', 02:58:06 INFO - 'LOGNAME': 'cltbld', 02:58:06 INFO - 'MAIL': '/var/mail/cltbld', 02:58:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:06 INFO - 'MOZ_NO_REMOTE': '1', 02:58:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:58:06 INFO - 'NO_EM_RESTART': '1', 02:58:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:58:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:06 INFO - 'PWD': '/builds/slave/test', 02:58:06 INFO - 'SHELL': '/bin/bash', 02:58:06 INFO - 'SHLVL': '1', 02:58:06 INFO - 'TERM': 'linux', 02:58:06 INFO - 'TMOUT': '86400', 02:58:06 INFO - 'USER': 'cltbld', 02:58:06 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', 02:58:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:06 INFO - '_': '/tools/buildbot/bin/python'} 02:58:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:58:06 INFO - Running setup.py (path:/tmp/pip-TVax42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:58:07 INFO - Running setup.py (path:/tmp/pip-qeWcQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:58:07 INFO - Running setup.py (path:/tmp/pip-oEdBoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:58:07 INFO - Running setup.py (path:/tmp/pip-P8HwHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:58:07 INFO - Running setup.py (path:/tmp/pip-vrvckH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:58:07 INFO - Running setup.py (path:/tmp/pip-83pbBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:58:07 INFO - Running setup.py (path:/tmp/pip-IUc8ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:58:07 INFO - Running setup.py (path:/tmp/pip-7ywv24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:58:07 INFO - Running setup.py (path:/tmp/pip-UlEVGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:58:07 INFO - Running setup.py (path:/tmp/pip-aeXRnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:58:07 INFO - Running setup.py (path:/tmp/pip-R2AjiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:58:07 INFO - Running setup.py (path:/tmp/pip-mPA03C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:58:07 INFO - Running setup.py (path:/tmp/pip-1uG7O1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:58:07 INFO - Running setup.py (path:/tmp/pip-MYKPVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:58:07 INFO - Running setup.py (path:/tmp/pip-dnrcsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:58:08 INFO - Running setup.py (path:/tmp/pip-_nyrX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:58:08 INFO - Running setup.py (path:/tmp/pip-jhXSKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:58:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:58:08 INFO - Running setup.py install for manifestparser 02:58:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:58:08 INFO - Running setup.py install for mozcrash 02:58:08 INFO - Running setup.py install for mozdebug 02:58:08 INFO - Running setup.py install for mozdevice 02:58:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:58:08 INFO - Running setup.py install for mozfile 02:58:08 INFO - Running setup.py install for mozhttpd 02:58:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:58:08 INFO - Running setup.py install for mozinfo 02:58:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:58:08 INFO - Running setup.py install for mozInstall 02:58:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:58:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:58:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:58:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:58:08 INFO - Running setup.py install for mozleak 02:58:09 INFO - Running setup.py install for mozlog 02:58:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:58:09 INFO - Running setup.py install for moznetwork 02:58:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:58:09 INFO - Running setup.py install for mozprocess 02:58:09 INFO - Running setup.py install for mozprofile 02:58:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:58:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:58:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:58:09 INFO - Running setup.py install for mozrunner 02:58:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:58:09 INFO - Running setup.py install for mozscreenshot 02:58:09 INFO - Running setup.py install for moztest 02:58:09 INFO - Running setup.py install for mozversion 02:58:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:58:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:58:09 INFO - Cleaning up... 02:58:09 INFO - Return code: 0 02:58:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:58:09 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')]} 02:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:09 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')]} 02:58:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:09 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 0x28711f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bddeb0>, '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 0x2bdedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdf240>, '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': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', '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 02:58:09 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 02:58:09 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 02:58:09 INFO - Using env: {'DISPLAY': ':0', 02:58:09 INFO - 'HOME': '/home/cltbld', 02:58:09 INFO - 'LANG': 'en_US.UTF-8', 02:58:09 INFO - 'LANGUAGE': 'en_US:en', 02:58:09 INFO - 'LOGNAME': 'cltbld', 02:58:09 INFO - 'MAIL': '/var/mail/cltbld', 02:58:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:09 INFO - 'MOZ_NO_REMOTE': '1', 02:58:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:58:09 INFO - 'NO_EM_RESTART': '1', 02:58:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:58:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:09 INFO - 'PWD': '/builds/slave/test', 02:58:09 INFO - 'SHELL': '/bin/bash', 02:58:09 INFO - 'SHLVL': '1', 02:58:09 INFO - 'TERM': 'linux', 02:58:09 INFO - 'TMOUT': '86400', 02:58:09 INFO - 'USER': 'cltbld', 02:58:09 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', 02:58:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:09 INFO - '_': '/tools/buildbot/bin/python'} 02:58:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:58:10 INFO - Running setup.py (path:/tmp/pip-Jk6Ygu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:58:10 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:58:10 INFO - Running setup.py (path:/tmp/pip-Xp00yL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:58:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:58:10 INFO - Running setup.py (path:/tmp/pip-e50_cm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:58:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:58:10 INFO - Running setup.py (path:/tmp/pip-QnsOwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:58:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:58:10 INFO - Running setup.py (path:/tmp/pip-4C4WqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:58:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:58:10 INFO - Running setup.py (path:/tmp/pip-JXyFyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:58:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:58:10 INFO - Running setup.py (path:/tmp/pip-jkwAoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:58:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:58:10 INFO - Running setup.py (path:/tmp/pip-m6fn9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:58:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:58:10 INFO - Running setup.py (path:/tmp/pip-AYI89X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:58:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:58:10 INFO - Running setup.py (path:/tmp/pip-_rOlU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:58:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:58:10 INFO - Running setup.py (path:/tmp/pip-kRz1CE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:58:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:58:10 INFO - Running setup.py (path:/tmp/pip-DwAc4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:58:11 INFO - Running setup.py (path:/tmp/pip-5ELKXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:58:11 INFO - Running setup.py (path:/tmp/pip-I8fFcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:58:11 INFO - Running setup.py (path:/tmp/pip-oZLL9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:58:11 INFO - Running setup.py (path:/tmp/pip-TXjDfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:58:11 INFO - Running setup.py (path:/tmp/pip-ZjYM2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:58:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:12 INFO - Downloading blessings-1.6.tar.gz 02:58:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:58:12 INFO - Installing collected packages: blessings 02:58:12 INFO - Running setup.py install for blessings 02:58:12 INFO - Successfully installed blessings 02:58:12 INFO - Cleaning up... 02:58:12 INFO - Return code: 0 02:58:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:58:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:58:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:58:12 INFO - Reading from file tmpfile_stdout 02:58:12 INFO - Current package versions: 02:58:12 INFO - argparse == 1.2.1 02:58:12 INFO - blessings == 1.6 02:58:12 INFO - blobuploader == 1.2.4 02:58:12 INFO - docopt == 0.6.1 02:58:12 INFO - functools32 == 3.2.3-2 02:58:12 INFO - jsonschema == 2.5.1 02:58:12 INFO - manifestparser == 1.1 02:58:12 INFO - mozInstall == 1.13 02:58:12 INFO - mozcrash == 1.0 02:58:12 INFO - mozdebug == 0.1 02:58:12 INFO - mozdevice == 0.51 02:58:12 INFO - mozfile == 1.2 02:58:12 INFO - mozhttpd == 0.7 02:58:12 INFO - mozinfo == 0.10 02:58:12 INFO - mozleak == 0.1 02:58:12 INFO - mozlog == 3.5 02:58:12 INFO - moznetwork == 0.27 02:58:12 INFO - mozprocess == 0.25 02:58:12 INFO - mozprofile == 0.28 02:58:12 INFO - mozrunner == 6.13 02:58:12 INFO - mozscreenshot == 0.1 02:58:12 INFO - mozsystemmonitor == 0.3 02:58:12 INFO - moztest == 0.8 02:58:12 INFO - mozversion == 1.4 02:58:12 INFO - psutil == 3.1.1 02:58:12 INFO - requests == 1.2.3 02:58:12 INFO - wsgiref == 0.1.2 02:58:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:58: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')]} 02:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58: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')]} 02:58:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28711f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bddeb0>, '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 0x2bdedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdf240>, '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': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', '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 02:58:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:58:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:58:12 INFO - Using env: {'DISPLAY': ':0', 02:58:12 INFO - 'HOME': '/home/cltbld', 02:58:12 INFO - 'LANG': 'en_US.UTF-8', 02:58:12 INFO - 'LANGUAGE': 'en_US:en', 02:58:12 INFO - 'LOGNAME': 'cltbld', 02:58:12 INFO - 'MAIL': '/var/mail/cltbld', 02:58:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:12 INFO - 'MOZ_NO_REMOTE': '1', 02:58:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:58:12 INFO - 'NO_EM_RESTART': '1', 02:58:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:58:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:12 INFO - 'PWD': '/builds/slave/test', 02:58:12 INFO - 'SHELL': '/bin/bash', 02:58:12 INFO - 'SHLVL': '1', 02:58:12 INFO - 'TERM': 'linux', 02:58:12 INFO - 'TMOUT': '86400', 02:58:12 INFO - 'USER': 'cltbld', 02:58:12 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', 02:58:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:12 INFO - '_': '/tools/buildbot/bin/python'} 02:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:58:13 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:58: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 02:58: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 02:58: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 02:58: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 02:58:14 INFO - Downloading marionette_driver-1.4.0.tar.gz 02:58:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 02:58:14 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 02:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:58:14 INFO - Installing collected packages: marionette-driver, simplejson 02:58:14 INFO - Running setup.py install for marionette-driver 02:58:14 INFO - Running setup.py install for simplejson 02:58:14 INFO - building 'simplejson._speedups' extension 02:58:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 02:58:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 02:58:15 INFO - Successfully installed marionette-driver simplejson 02:58:15 INFO - Cleaning up... 02:58:15 INFO - Return code: 0 02:58:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:58: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')]} 02:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58: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')]} 02:58:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28711f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bddeb0>, '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 0x2bdedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdf240>, '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': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', '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 02:58:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:58:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:58:15 INFO - Using env: {'DISPLAY': ':0', 02:58:15 INFO - 'HOME': '/home/cltbld', 02:58:15 INFO - 'LANG': 'en_US.UTF-8', 02:58:15 INFO - 'LANGUAGE': 'en_US:en', 02:58:15 INFO - 'LOGNAME': 'cltbld', 02:58:15 INFO - 'MAIL': '/var/mail/cltbld', 02:58:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:15 INFO - 'MOZ_NO_REMOTE': '1', 02:58:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:58:15 INFO - 'NO_EM_RESTART': '1', 02:58:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:58:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:15 INFO - 'PWD': '/builds/slave/test', 02:58:15 INFO - 'SHELL': '/bin/bash', 02:58:15 INFO - 'SHLVL': '1', 02:58:15 INFO - 'TERM': 'linux', 02:58:15 INFO - 'TMOUT': '86400', 02:58:15 INFO - 'USER': 'cltbld', 02:58:15 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', 02:58:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:15 INFO - '_': '/tools/buildbot/bin/python'} 02:58:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:58:15 INFO - Cleaning up... 02:58:15 INFO - Return code: 0 02:58:15 INFO - Running post-action listener: _resource_record_post_action 02:58:15 INFO - Running post-action listener: _start_resource_monitoring 02:58:15 INFO - Starting resource monitoring. 02:58:15 INFO - [mozharness: 2017-07-31 09:58:15.979804Z] Finished create-virtualenv step (success) 02:58:15 INFO - [mozharness: 2017-07-31 09:58:15.980307Z] Running install step. 02:58:15 INFO - Running pre-action listener: _resource_record_pre_action 02:58:15 INFO - Running main action method: install 02:58:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:58:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:58:16 INFO - Reading from file tmpfile_stdout 02:58:16 INFO - Detecting whether we're running mozinstall >=1.0... 02:58:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:58:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:58:16 INFO - Reading from file tmpfile_stdout 02:58:16 INFO - Output received: 02:58:16 INFO - Usage: mozinstall [options] installer 02:58:16 INFO - Options: 02:58:16 INFO - -h, --help show this help message and exit 02:58:16 INFO - -d DEST, --destination=DEST 02:58:16 INFO - Directory to install application into. [default: 02:58:16 INFO - "/builds/slave/test"] 02:58:16 INFO - --app=APP Application being installed. [default: firefox] 02:58:16 INFO - mkdir: /builds/slave/test/build/application 02:58:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:58:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:58:34 INFO - Reading from file tmpfile_stdout 02:58:34 INFO - Output received: 02:58:34 INFO - /builds/slave/test/build/application/firefox/firefox 02:58:34 INFO - Running post-action listener: _resource_record_post_action 02:58:34 INFO - [mozharness: 2017-07-31 09:58:34.423775Z] Finished install step (success) 02:58:34 INFO - [mozharness: 2017-07-31 09:58:34.424030Z] Running setup-mitmproxy step. 02:58:34 INFO - Running pre-action listener: _resource_record_pre_action 02:58:34 INFO - Running main action method: setup_mitmproxy 02:58:34 INFO - Skipping: mitmproxy is not required 02:58:34 INFO - Running post-action listener: _resource_record_post_action 02:58:34 INFO - [mozharness: 2017-07-31 09:58:34.425175Z] Finished setup-mitmproxy step (success) 02:58:34 INFO - [mozharness: 2017-07-31 09:58:34.425399Z] Running run-tests step. 02:58:34 INFO - Running pre-action listener: _resource_record_pre_action 02:58:34 INFO - Running pre-action listener: _set_gcov_prefix 02:58:34 INFO - Running main action method: run_tests 02:58:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:58:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:58:34 INFO - Python 2.7.3 02:58:34 INFO - Return code: 0 02:58:34 INFO - grabbing minidump binary from tooltool 02:58: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')]} 02:58:34 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 0x2bddeb0>, '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 0x2bdedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdf240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:58:34 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 02:58:34 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 02:58:34 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 02:58:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:58:34 INFO - Return code: 0 02:58:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:58:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:58:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:58:34 INFO - ENV: RUST_BACKTRACE is now 1 02:58:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:58:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:58:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-083', '--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 02:58:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-083 --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 02:58:34 INFO - Using env: {'DISPLAY': ':0', 02:58:34 INFO - 'HOME': '/home/cltbld', 02:58:34 INFO - 'LANG': 'en_US.UTF-8', 02:58:34 INFO - 'LANGUAGE': 'en_US:en', 02:58:34 INFO - 'LOGNAME': 'cltbld', 02:58:34 INFO - 'MAIL': '/var/mail/cltbld', 02:58:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:58:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:58:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:34 INFO - 'MOZ_NO_REMOTE': '1', 02:58:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:58:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:58:34 INFO - 'NO_EM_RESTART': '1', 02:58:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:58:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:34 INFO - 'PWD': '/builds/slave/test', 02:58:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:58:34 INFO - 'RUST_BACKTRACE': '1', 02:58:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:58:34 INFO - 'SHELL': '/bin/bash', 02:58:34 INFO - 'SHLVL': '1', 02:58:34 INFO - 'STYLO_FORCE_ENABLED': '1', 02:58:34 INFO - 'TERM': 'linux', 02:58:34 INFO - 'TMOUT': '86400', 02:58:34 INFO - 'USER': 'cltbld', 02:58:34 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066', 02:58:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:34 INFO - '_': '/tools/buildbot/bin/python'} 02:58:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-083', '--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 02:58:34 INFO - mozversion application_buildid: 20170731091001 02:58:34 INFO - mozversion application_changeset: b1dcb20102b7598ec4d467530cd20ba9e8754de6 02:58:34 INFO - mozversion application_display_name: Nightly 02:58:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:58:34 INFO - mozversion application_name: Firefox 02:58:34 INFO - mozversion application_remotingname: firefox 02:58:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:58:34 INFO - mozversion application_vendor: Mozilla 02:58:34 INFO - mozversion application_version: 56.0a1 02:58:34 INFO - mozversion platform_buildid: 20170731091001 02:58:34 INFO - mozversion platform_changeset: b1dcb20102b7598ec4d467530cd20ba9e8754de6 02:58:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:58:34 INFO - mozversion platform_version: 56.0a1 02:58:34 INFO - using testdate: 1501495114 02:58:34 INFO - actual date: 1501495114 02:58:34 INFO - starting webserver on 'localhost:33309' 02:58:34 INFO - SUITE-START | Running 2 tests 02:58:34 INFO - TEST-START | dromaeo_css 02:58:34 INFO - Initialising browser for dromaeo_css test... 02:58:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33309/getInfo.html -profile /tmp/tmps6CTQo/profile 02:58:34 INFO - TEST-INFO | started process 26057 (/builds/slave/test/build/application/firefox/firefox http://localhost:33309/getInfo.html) 02:58:40 INFO - TEST-INFO | 26057: exit 0 02:58:40 INFO - Browser initialized. 02:58:40 INFO - Running cycle 1/1 for dromaeo_css test... 02:58:40 INFO - TEST-INFO | started process 26283 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps6CTQo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:58:41 INFO - PID 26283 | 02:58:41 INFO - PID 26283 | (/builds/slave/test/build/application/firefox/firefox:26338): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:58:41 INFO - PID 26283 | 02:58:52 INFO - PID 26283 | RSS: Main: 173694976 02:58:52 INFO - PID 26283 | 03:01:52 INFO - PID 26283 | Cycle 1(1): loaded http://localhost:33309/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33309/tests/dromaeo/cssquery-ext.html) 03:01:53 INFO - PID 26283 | RSS: Main: 175861760 03:01:53 INFO - PID 26283 | 03:04:55 INFO - PID 26283 | Cycle 1(1): loaded http://localhost:33309/tests/dromaeo/cssquery-ext.html (next: http://localhost:33309/tests/dromaeo/cssquery-jquery.html) 03:04:56 INFO - PID 26283 | RSS: Main: 173400064 03:04:56 INFO - PID 26283 | 03:07:58 INFO - PID 26283 | Cycle 1(1): loaded http://localhost:33309/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33309/tests/dromaeo/cssquery-mootools.html) 03:07:58 INFO - PID 26283 | RSS: Main: 175394816 03:07:58 INFO - PID 26283 | 03:11:00 INFO - PID 26283 | Cycle 1(1): loaded http://localhost:33309/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33309/tests/dromaeo/cssquery-prototype.html) 03:11:01 INFO - PID 26283 | RSS: Main: 173690880 03:11:01 INFO - PID 26283 | 03:14:05 INFO - PID 26283 | Cycle 1(1): loaded http://localhost:33309/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33309/tests/dromaeo/cssquery-yui.html) 03:14:05 INFO - PID 26283 | RSS: Main: 177262592 03:14:05 INFO - PID 26283 | 03:17:07 INFO - PID 26283 | Cycle 1(1): loaded http://localhost:33309/tests/dromaeo/cssquery-yui.html (next: http://localhost:33309/tests/dromaeo/cssquery-dojo.html) 03:17:08 INFO - PID 26283 | RSS: Main: 177061888 03:17:08 INFO - PID 26283 | 03:17:08 INFO - PID 26283 | __start_tp_report 03:17:08 INFO - PID 26283 | _x_x_mozilla_page_load 03:17:08 INFO - PID 26283 | _x_x_mozilla_page_load_details 03:17:08 INFO - PID 26283 | |i|pagename|runs| 03:17:08 INFO - PID 26283 | |0;dojo.html;3163.5571020057196;3169.7305729013033;3183.1087295557254;3187.07574803307;3202.1994501374656;6192.102145188945;6262.937370626292;6272.717727702258;6331.2085989251345;6336.968315158418;6130.356312587181;6261;6271.686415679221;6283.057541368797;6286;5730.828075157752;5739.1965124882445;5759.4816466518005;5777.740001699934;5785.508231800302;5783.421657834204;5812.157237200603;5819.709014549256;5828.883422331551;5832;5753.395893431192;5792.044312688401;5793.073108302672;5793.594448388614;5811.302643682764;5778.133280008012;5785.161151633031;5785.710714464281;5810.883782324372;5812.564057695689;5700.942990570083;5702.686352250625;5740.799072032459;5781.392953739839;5811.389804070554;6005.879882402372;6036.667983260919;6115.847103822395;6135.355787642278;6137.508999280091;6688.030235615857;6689.966550167267;6699.430548403391;6703.162104736908;6709.3290670933;6726.058351830748;6748.6288254146;6748.8312792180095;6767.796966091025;6778.796636100925;6211.409916057967;6241.28225254097;6295.748170073191;6298.905516417258;6326.114343991845;6943.6875340608785;6948.096747422803;6977.441804655616;6993.6503174841055;7047.119110111236;6456.192975878015;6571.605703657796;6577.243616984012;6602.86794264112;6712.697928593225;6657.933420665829;6662.267150613428;6688.531802773759;6690.397864192246;6693.899591506131;6591.802245932629;6599;6634.23706273775;6692.899606505906;6762.357576030269;6626.304238055031;6636.435902948207;6705.631190284484;6725.19297684281;6729.528932974645;5309.946900531023;5357.534656427661;5390.164524498709;5423.647462914898;5429.918551221735;4599.862004139881;4617.515160908123;4642.303654451792;4647.581717645428;4721.669483136147;5789.9710501447225;5832.562557808182;5849.356570777211;5850.531957443394;5855.121731740273;6722.394984451423;6805.931940680629;6807.2512023677355;6807.863842723217;6810.62541560219;5694.174344719992;5764.279465066867;5789.334226563915;5799.159121927381;5847;5764.682942438206;5851.1515830205235;5870.853228669317;5901.0558310670085;5932.614380065368;6507.642079685568;6509.349065093547;6512.479001679854;6517.315681853337;6525.445337146396;6646.401823835878;6712.362325579063;6734.023566582916;6734.932650673627;6737.629430381376;6435.2599451063725;6506.121673574007;6519.543631945908;6528.543001989815;6529.5102867284195;5699.430056994267;6561.343865613591;6587.901181482186;6686.197656281277;6690.799276021728;6709.4632429404555;6710.16122984627;6782;6850.965745171242;6871.793846184622;6423;6428.742850285937;6486.708098135501;6506.154199953976;6619.768308109193;6457.935420645734;6530.020496925499;6586.50601204902;6590.406863382127;6594.076829244005;6460.45086167681;6514.478841692748;6517.641529715911;6522.836929076811;6528.314526974789;6517.608943463408;6533.379328963741;6595.076689263603;6636.933630663631;6649.534532582867;6294.779682711083;6351.777687780906;6376.107344971801;6402.423781859655;6460.806175814733 03:17:08 INFO - PID 26283 | |1;ext.html;800.7917941335255;868.2229404682809;898.5911410308311;898.9820203595929;1162.0761494611784;29198.85400572997;29852.55221171684;39916.20167596646;40680;40706.7964660177;40564;40733.79633101838;40802.38796418056;40977.59022409775;41035.38446923299;5773.278340207474;5904.793332233373;7503.512271702322;7654.3111119999185;7714.267144621252;7501.887471687929;7573.469857110004;7595.924040759577;7601.201873803254;7602;7555.69777208911;7598.924010759904;7602.125755538101;7650.043744531934;7653.119891212526;7553.433492488058;7576.204498527658;7580.6967721291085;7584.317411432998;7585;7472.252774722539;7483.812904677427;7491.850162996764;7548.5848278344665;7550.0939887213335;37492.62507374919;37541;37564;37721.24557508862;37804.243915121544;3481.425564781808;3508.5965114011706;3513.086597484647;3516.841742121611;3523.541939547868;30259.697403025908;30464;30467.695323046708;30541.23646908845;30557.847210764026;4553.236731373034;4702.059588082398;4751.619870410359;4801.975990120062;4892.437369702494;183446;184241;184454;185043.07478462657;185157;46509.30236046462;46657;48042.75978620085;48734.26898596524;48884;50429.24356134661;50647.74676126596;50718;50788;50813.23780143301;15923.363065477251;16291.429799956944;16326.918365408097;16334.918325408296;16335.101569413797;11084.556617735361;11241.943790280997;13083.215007099605;13345.866541334462;13355.332233388292;13807.792883106762;13890.23603701786;13939.581812545639;13961.581152565439;14107.224102674247;5560.471755183251;5802.796902108406;5815.360310365856;5833.629097162164;5835.503982161479;966.6036924860773;982.872226610536;988.268681175939;1008.3899240959261;1010.2927950434726;22338.66492002621;22675.43311417195;22822.8858855708;23065.42336441569;23349;1047.8428235764695;1053.4627340056472;1095.3373209208473;1102.6471529110768;1108.706192858893;11810.40947952599;11832.822507662391;11914.821277680841;12096;12104.87895121055;234613;236050;236139;236587;236819;49904.251436228486;50161.74919125381;50473.24290135651;50509.494905051215;50649;1443.4154167561944;1452.5701039184705;1477.7709455034487;1481.8221813382247;1494.9327280272414;21517.892410537846;21831.126754929624;21924.780752192273;21925.451863703536;22117.77882221157;35412.46881296731;35587.64412355947;35664.82167589146;35680.464793027604;35720.464193037646;141102;141906;142150;142559.28720356332;142594;32694.182645434055;36111.45832812563;36788.816055919546;36963.44554831626;37006.814965925;44386;45456.77271613753;45546.31680524728;45711.54288457206;45924.540754592024;26472.338191545365;26795.732042680105;26811.865940670174;27591;27649.170524884285;24911.377215569755;25366.492670146126;25749.871250643628;25784.226473205836;27998.580021300106;36145.81927090348;37690.811545942095;37701;37796.24407511889;38115.42826857544;32359.352812944082;32715.509267361485;32870.835645821615;32903;32980.83509582437;23420.414489637897;30973.380532389678;31332.843335783175;31531.84234078815;32125 03:17:08 INFO - PID 26283 | |2;jquery.html;568.3236948031841;573.667272981671;578.7193211292525;592.5052581094785;596.1564386393254;22137.557248855035;22590.77409225907;22973.08107675691;23007.884960575193;23036.88481557594;25664.23007309779;26103.73896261037;26105.21684349468;26160.215193544176;26246.343841403926;6413.390727880844;6588.670566471681;6604.438622717074;6612.173478315211;6619.139511863451;5884.323302820167;6411.262704788963;6474.579152355105;6482.805515834509;6489.1888513935755;6441.3880681335195;6450.2259728832605;6451.548391612589;6451.645159516224;6458.354164583551;6417;6422.068800023971;6425.164728585254;6430.967845160768;6458.354164583551;5878.2064421303;5920.230370051909;5940.554458415636;6002.399760023963;6036.939630603725;27757.444851102864;28222;28805.56791648127;29151.41697166045;29294.560581591293;1903.714442833572;1955.452473307476;1959.4121763470903;1959.8628096033283;1960.137539482623;18566.814331856644;19296.807031929642;19374.80625193744;19387.612247754965;19395.903020484948;2226.7327920649627;2266.614675505168;2318.7449380568123;2320.060375547906;2335.43949452132;138474;141333.58666413306;142194;142390;142501;14168.079074860101;14951.401943922121;15179.696406071816;15296.235188240766;15735.527934161993;23567.646485302736;23632.29103126909;23641.645375319385;23664.7633523666;24668.753312467004;9782.804343913223;9919.50402479873;10056.798864022825;10057.748556286006;10185.541650625739;9031.096890311048;9035.825342705406;9077.45535267886;9121.81756364869;9208.769780755534;9384.8592271116;9539.284553658503;9569.425834449956;9728;9796;2758.558630619091;2883.9423211536073;2887.307046308913;2916.6645835728737;2957.349383135707;908.8909330880205;935.7379933618598;943.613118621362;962;983.7442265011149;18841.717374239397;19025.524361891064;19399.4180174595;19452.22191112368;19616.901915490333;983.179045497018;983.4394395194813;991.9652812151685;1018.0786388318584;1033.204432586919;10394.48027598617;10618.522166502526;10729.03438690506;10766.138708903269;10813.351198927932;510842;512968;535630;538249;606575;123310;129357;131961.34019330033;134644;134825;1216.4161202622613;1265.487477571585;1283.8972882169403;1292.8513220979519;1304.2956803326288;18747.531311717317;21701.34895953124;21811.12755489826;22977.540449191256;23085;48138.5186148134;50072.74963625159;55208.17187742107;57035.429645703014;58759;67090.66454667696;67329.3267067323;67510;67658;68098;20183.39449816508;21086.367408977756;21375.358739237847;21435.24976625873;21506.139754409647;23538.764612353658;23677.64483532714;23694.526109478058;23918.402039949142;23947.640785388583;18359.449216523524;18472.907635462274;18501.907490463;18774.53063673419;18873.811261887753;14783;15186.924065379602;15440;15692.136932468367;15846.207689615243;17796.46610601684;19237.519062023563;19264.90367548153;19399.2240310386;19419.70870436973;19734.30929917446;20662.690059648816;20801.791982079987;20831.687524687444;20896.268630597857;15094.773578396112;15972.520824375288;16026;16103.597410064842;16146.546810787086 03:17:08 INFO - PID 26283 | |3;mootools.html;1311.2591385866986;1371.6845125621107;1373.2172661582895;1376.9105008174467;1394.184402124757;11966.34185119818;13355.131916425426;14098.295085245734;14114.788278175824;14277.714445711079;12080.516779328838;12176.08679349048;13664;14295.356708948097;14305.928470357634;2579.2133399313157;2622.0167437211044;2639.089514117636;2648.1393547097173;2650.2976711171555;2519.8488090714523;2577.7035640901254;2593.5072336256053;2596.9610455843176;2600.5059038782665;2524.1670248817936;2545.4272788622598;2571.035861551918;2574.3435423966957;2578.729233430491;2480.516299321633;2520.873956302187;2522.646829443861;2528.4311030018284;2528.4816612594373;2447.3881529617597;2464.199135281041;2464.2484042367073;2465.2234546118093;2475.2574227731607;13404.530841420503;13719.314034298344;13736.519221827288;13742.312884355742;13773;7507.399408047343;7775.261350171724;7814.687412503493;7828.334591559725;7831.530108193527;4759.357486739281;4981.701097934136;4991.051700176955;5180.6373553851245;5229.320188375525;1786.2765579940144;1802.8107048759953;1814.0748218408576;1850.6668799616064;1863.1988245054756;21498.892505537373;21608.459788505414;21657.783422165736;21677.67483487749;21732.89133554338;6764.458843292623;6879.105716256855;6890.483213758988;7100.076989991268;7116.181639111465;15421.460248891235;16013.279402426919;16060.67878642444;17846.464606061578;17949.7307540387;7430.182679905206;7626.656800443993;7641.885371719429;7651.426143039294;7651.808704782425;6916.757913473105;7075.71697132109;7082;7274.418046556369;7325.340719335285;5033.823816166417;5114.820981265638;5120.129577971754;5188.247704082887;5193.506616871391;1529.793477880494;1555.175756848872;1558.9688206235812;1562.9609259768597;1571.4264293532779;850.961706723199;852.7484392104344;887.933404994628;900.8378491871526;902.4540153207346;6909;6915.51591388598;6972.1981972072845;7081.398081163159;7082.256363081905;694.5936627073219;697.7313734212365;705.1890326124998;724.1961422820665;731.8536292741474;4373.431453910971;4665.090307390026;4692.389989301437;4801.159797035505;4852.393450818648;18121.456356309332;18890.716639250422;18980.240790368232;19021.524461888286;19072.80927190738;18328.175232114587;18499.722504162448;19324.903375483034;19532.80467195338;19604.313849015216;10586.629467968583;10794;10824.404657743742;10855.565777368816;10869.293495922899;7011.894821577578;7594.392448604209;7839.804004899923;7895.289423951872;7933.761987140395;16165.919170404073;16802.24389902482;17038.403655871985;17508.47474575765;17566.560835979202;17118;17605.91197044058;17620.29518819233;17682.5579360517;17689.292428302724;10756.623518177139;10843;10952.233343665866;10981.341119532854;10989.450527473755;12640.6839829005;12651.93674031624;12707.428165732748;12749.362531873183;12843.42204600814;9143.13140251675;9462.952685236805;9668.9033109668;9764.609415623296;9827;9276.16514513697;9430.811383772423;9475.194608458358;9591.088846559565;9841.360311579725;8658.740237792876;8722.25860801839;8780.078091800524;8786.736397908073;8812.823743525221;6337.112804207322;6453.903191452038;6675.666216689243;6763.391294783296;6786.59280443175;6478.1578394808375;6519.546657346771;6930.7227710891;6997.755078572225;7091.574505529685 03:17:08 INFO - PID 26283 | |4;prototype.html;470.80696914265155;474.96200303975684;501.89962007598484;513.1789137380192;515.0625860933101;6178.980468222738;6250.468710159642;6280;6282.591631543952;6314.210723659543;5885.909897303917;6005.849853753658;6039.248617900815;6100.725467353968;6138.232720909887;4911.814242662017;4917.950820491785;4934.4325402578625;4944.901101977958;5004.449510553836;4937.308776771237;4944.579710724593;4948.628852836034;4964.304997300381;4988.900221995558;4736.644751643623;4905.82829600964;4937.703737775727;4941.925871111936;4956.851294461172;4918.36061312029;4942.382202224722;4943.283223932528;4969.503049695038;4996.67588089153;4412.801423935931;4430.585330994616;4461.107778444291;4499.155346308031;4502.16709908668;5935.347111817697;5986.191864098335;6074.119252708332;6080.908786368208;6086.2087928569;5687.203791469197;5726.284214473191;5726.828195154152;5744.540436765049;5750.309962804448;6484.221893372778;6489.967550162266;6502.512311576651;6507.316731743146;6586;4829.6860704054125;4867.148249056401;4867.610591152699;4911.705297682151;4930.580900623451;6775.898361524581;6788.261382024496;6895.379415852597;6932.306769323028;7004.719811207495;4931.284963680247;4968.652194346433;4999.325091112726;5024.397072351341;5042.9747851261245;5029;5037.848864534069;5055.140626093573;5071.873203169876;5100.7959681613065;5691.186904773812;5697.9430205698245;5737.741801618937;5739.426057394227;5750.3099628044065;6405.615663060231;6475.935240647627;6569.67151642416;6599.175103112111;6603.438707709803;1653.4956838164235;1662.6674665066794;1674.983250167507;1729.2477772169148;1782.5989152440598;1226.650404634675;1227.098082909061;1235.2958813476232;1238.8416830263552;1249.4689756853302;5634.6337488063145;5720.942790572124;5741.655500669973;5750.741216645178;5764.213093940892;6415.647139407284;6559.114519539897;6640;6658.267590565034;6780.389764921082;5739.995500787346;5781.53747700183;5801.970990145022;5805.332386775575;5859.53123750099;3520.2255503789124;3521.2253304273017;3562.786232826039;3568.357695614762;3576.91057723559;6411.711472983727;6489.286178520359;6493.577917435352;6539.822831890306;6566.474682025427;6664.900026499607;6810.557313774688;6871.522622636157;6930.584164950119;6937.7571784987285;6280.780172693934;6304.590201636971;6482.708278127496;6483;6513.41379275858;6611.206655201407;6619.642973190581;6673.466122710074;6682.920980055096;6710.429613482908;6525.216973962965;6656.633885136171;6692.732290708317;6722.563033402813;6739.157605299338;6387.425131738166;6477.222733272038;6481.319461456667;6485.254195767548;6567.2447668518735;4921.384826896638;4986.351774269322;4993.300937868774;5020.297158397901;5021.171506701354;6675.766348177791;6732.225794033749;6762.966185169043;6772.356626120511;6776.525643204928;6389.169407976933;6487.9026814598765;6496.252930913006;6516.446102081376;6528.738850445929;6122.969385153046;6435.967820160869;6456.192975878015;6521.587082721235;6532.902006469812;6091.7867874625945;6280.654563999024;6312.179416675986;6358.332375100548;6380.085974207812;6360.173177486897;6366.840828979312;6471.417572418505;6503.122078519339;6545.37818907203;6261.874762504815;6304;6317.336679648754;6325.430711236011;6352.36476352361 03:17:08 INFO - PID 26283 | |5;yui.html;920.3511524375311;926.0693003531451;937.7608709779005;939.5396255834639;943.5942544705775;18014.459566213;18880.716789248156;18899;18929.33747318844;18959.24163033479;19201.519962000923;20733.896330518364;21473.67789483155;21528.461788455297;21602;1021.8620486234356;1038.4755698372312;1039.5893622019298;1040.2406243442292;1040.921930855185;956.617353058775;1037.1029059863201;1037.19617296595;1037.8287582548908;1048.0201012053717;1093.283899046126;1094.9178811589122;1095.0473088413091;1095.2552264460164;1095.9232853700244;1076.05844885725;1076.354187487509;1084.4144162152468;1084.8264277715566;1086.6957251969461;801.5070731500085;801.8396320735876;804.4047404920375;804.6781287484994;805.9919400805976;19274.421767347;19833.80166198334;19922.203111875508;20034.499137521532;20270;20132.49668758278;20186;20565.5886882263;20751.273705420237;20782.48043798902;15852.603684907854;16080;16288.267027983651;16313.102779347131;16456.259468323955;3574.088607405095;3591.048372181374;3592.6407359264126;3592.7305452090936;3611.3319035978166;120415.3979230107;121724;123572;125557.99999999908;128949;15854.44509442187;15928.522144335688;16064.75902861458;16139.919300403422;16210.67578648444;28552.857235713687;29657.40685186284;29787.255318617208;29846.552301715492;29937;8056.677732890619;8271.255586997198;8273.751787446387;8291.170882911661;8336.541490218096;4068.7558746475306;4153.127843152912;4155.231282212801;4165.708400411942;4165.91668166635;4558.817647294138;5126.5898728101665;5655.7454914528125;5673.8297851064535;5675.8864822704145;1035.435687550287;1056.915446764257;1070.844727514506;1081.9242653014371;1082.6481393547128;503.639897473301;519.5791408958755;531.3703261634975;537.0574641504213;539.0189854464836;10929;11403.858816017824;11654.359010254517;11989.76020479603;12337.136400451882;571.5598988778701;572.3303734630492;583.3175185033521;589.6609449566516;596.1296507099684;5314.813981510629;5340.385855626652;5340.519353258225;5354.196870469383;5582.692951887685;96154.51922740482;100574.49712751535;100937;102242;103847.48076259722;83117.58441207756;87799.56100219458;88471.55764221266;91384;93902.53048734713;75606;76966.6151669238;80752;81481.59259203785;82721.17278827255;10535.473226338805;10609.89390106089;10703.464826758474;10760.89239107599;10957.506912188812;34140.829295853364;35527.82236088803;35933.28133437369;37207.81396093002;38027.42958855675;44560.77719611381;64051.112210083724;65462.67268663626;65904.67047664731;66711.66644166748;17009.659806803546;17034.74447883258;17238.310467581156;17471.912640436716;17487.912560437115;20375.592488149858;20664.896675516527;20914.47713807167;20958.161673532886;21232.256871009442;17232.569185770455;17786.37747678825;17977.73033404474;18725.438236852915;18919.905400472908;16192.757108643615;16328.020318780911;18438.170282337058;18643.627127457647;18897.90551047236;16215.270312836186;16483.91758041202;16578.83421165773;16792.916035419745;17074.40239591658;12420.254784712946;12683.175593586388;13497.190168589916;13693.109947853358;13694.65763355924;12976;14231.928840355731;14550.563483095524;14594.635134121732;14626.488072917396 03:17:08 INFO - PID 26283 | __end_tp_report 03:17:08 INFO - PID 26283 | __start_cc_report 03:17:08 INFO - PID 26283 | _x_x_mozilla_cycle_collect,361 03:17:08 INFO - PID 26283 | __end_cc_report 03:17:08 INFO - PID 26283 | __startTimestamp1501496228091__endTimestamp 03:17:08 INFO - PID 26283 | 03:17:08 INFO - PID 26283 | ------- Summary: start ------- 03:17:08 INFO - PID 26283 | Number of tests: 6 03:17:08 INFO - PID 26283 | 03:17:08 INFO - PID 26283 | [#0] dojo.html Cycles:165 Average:6175.31 Median:6435.26 stddev:728.13 (11.3%) stddev-sans-first:690.97 03:17:08 INFO - PID 26283 | Values: 3163.6 3169.7 3183.1 3187.1 3202.2 6192.1 6262.9 6272.7 6331.2 6337.0 6130.4 6261.0 6271.7 6283.1 6286.0 5730.8 5739.2 5759.5 5777.7 5785.5 5783.4 5812.2 5819.7 5828.9 5832.0 5753.4 5792.0 5793.1 5793.6 5811.3 5778.1 5785.2 5785.7 5810.9 5812.6 5700.9 5702.7 5740.8 5781.4 5811.4 6005.9 6036.7 6115.8 6135.4 6137.5 6688.0 6690.0 6699.4 6703.2 6709.3 6726.1 6748.6 6748.8 6767.8 6778.8 6211.4 6241.3 6295.7 6298.9 6326.1 6943.7 6948.1 6977.4 6993.7 7047.1 6456.2 6571.6 6577.2 6602.9 6712.7 6657.9 6662.3 6688.5 6690.4 6693.9 6591.8 6599.0 6634.2 6692.9 6762.4 6626.3 6636.4 6705.6 6725.2 6729.5 5309.9 5357.5 5390.2 5423.6 5429.9 4599.9 4617.5 4642.3 4647.6 4721.7 5790.0 5832.6 5849.4 5850.5 5855.1 6722.4 6805.9 6807.3 6807.9 6810.6 5694.2 5764.3 5789.3 5799.2 5847.0 5764.7 5851.2 5870.9 5901.1 5932.6 6507.6 6509.3 6512.5 6517.3 6525.4 6646.4 6712.4 6734.0 6734.9 6737.6 6435.3 6506.1 6519.5 6528.5 6529.5 5699.4 6561.3 6587.9 6686.2 6690.8 6709.5 6710.2 6782.0 6851.0 6871.8 6423.0 6428.7 6486.7 6506.2 6619.8 6457.9 6530.0 6586.5 6590.4 6594.1 6460.5 6514.5 6517.6 6522.8 6528.3 6517.6 6533.4 6595.1 6636.9 6649.5 6294.8 6351.8 6376.1 6402.4 6460.8 03:17:08 INFO - PID 26283 | 03:17:08 INFO - PID 26283 | [#1] ext.html Cycles:180 Average:35593.42 Median:19468.01 stddev:49738.06 (255.5%) stddev-sans-first:49808.98 03:17:08 INFO - PID 26283 | Values: 800.8 868.2 898.6 899.0 1162.1 29198.9 29852.6 39916.2 40680.0 40706.8 40564.0 40733.8 40802.4 40977.6 41035.4 5773.3 5904.8 7503.5 7654.3 7714.3 7501.9 7573.5 7595.9 7601.2 7602.0 7555.7 7598.9 7602.1 7650.0 7653.1 7553.4 7576.2 7580.7 7584.3 7585.0 7472.3 7483.8 7491.9 7548.6 7550.1 37492.6 37541.0 37564.0 37721.2 37804.2 3481.4 3508.6 3513.1 3516.8 3523.5 30259.7 30464.0 30467.7 30541.2 30557.8 4553.2 4702.1 4751.6 4802.0 4892.4 183446.0 184241.0 184454.0 185043.1 185157.0 46509.3 46657.0 48042.8 48734.3 48884.0 50429.2 50647.7 50718.0 50788.0 50813.2 15923.4 16291.4 16326.9 16334.9 16335.1 11084.6 11241.9 13083.2 13345.9 13355.3 13807.8 13890.2 13939.6 13961.6 14107.2 5560.5 5802.8 5815.4 5833.6 5835.5 966.6 982.9 988.3 1008.4 1010.3 22338.7 22675.4 22822.9 23065.4 23349.0 1047.8 1053.5 1095.3 1102.6 1108.7 11810.4 11832.8 11914.8 12096.0 12104.9 234613.0 236050.0 236139.0 236587.0 236819.0 49904.3 50161.7 50473.2 50509.5 50649.0 1443.4 1452.6 1477.8 1481.8 1494.9 21517.9 21831.1 21924.8 21925.5 22117.8 35412.5 35587.6 35664.8 35680.5 35720.5 141102.0 141906.0 142150.0 142559.3 142594.0 32694.2 36111.5 36788.8 36963.4 37006.8 44386.0 45456.8 45546.3 45711.5 45924.5 26472.3 26795.7 26811.9 27591.0 27649.2 24911.4 25366.5 25749.9 25784.2 27998.6 36145.8 37690.8 37701.0 37796.2 38115.4 32359.4 32715.5 32870.8 32903.0 32980.8 23420.4 30973.4 31332.8 31531.8 32125.0 03:17:08 INFO - PID 26283 | 03:17:08 INFO - PID 26283 | [#2] jquery.html Cycles:180 Average:36869.37 Median:23553.21 stddev:91030.58 (386.5%) stddev-sans-first:91245.14 03:17:08 INFO - PID 26283 | Values: 568.3 573.7 578.7 592.5 596.2 22137.6 22590.8 22973.1 23007.9 23036.9 25664.2 26103.7 26105.2 26160.2 26246.3 6413.4 6588.7 6604.4 6612.2 6619.1 5884.3 6411.3 6474.6 6482.8 6489.2 6441.4 6450.2 6451.5 6451.6 6458.4 6417.0 6422.1 6425.2 6431.0 6458.4 5878.2 5920.2 5940.6 6002.4 6036.9 27757.4 28222.0 28805.6 29151.4 29294.6 1903.7 1955.5 1959.4 1959.9 1960.1 18566.8 19296.8 19374.8 19387.6 19395.9 2226.7 2266.6 2318.7 2320.1 2335.4 138474.0 141333.6 142194.0 142390.0 142501.0 14168.1 14951.4 15179.7 15296.2 15735.5 23567.6 23632.3 23641.6 23664.8 24668.8 9782.8 9919.5 10056.8 10057.7 10185.5 9031.1 9035.8 9077.5 9121.8 9208.8 9384.9 9539.3 9569.4 9728.0 9796.0 2758.6 2883.9 2887.3 2916.7 2957.3 908.9 935.7 943.6 962.0 983.7 18841.7 19025.5 19399.4 19452.2 19616.9 983.2 983.4 992.0 1018.1 1033.2 10394.5 10618.5 10729.0 10766.1 10813.4 510842.0 512968.0 535630.0 538249.0 606575.0 123310.0 129357.0 131961.3 134644.0 134825.0 1216.4 1265.5 1283.9 1292.9 1304.3 18747.5 21701.3 21811.1 22977.5 23085.0 48138.5 50072.7 55208.2 57035.4 58759.0 67090.7 67329.3 67510.0 67658.0 68098.0 20183.4 21086.4 21375.4 21435.2 21506.1 23538.8 23677.6 23694.5 23918.4 23947.6 18359.4 18472.9 18501.9 18774.5 18873.8 14783.0 15186.9 15440.0 15692.1 15846.2 17796.5 19237.5 19264.9 19399.2 19419.7 19734.3 20662.7 20801.8 20831.7 20896.3 15094.8 15972.5 16026.0 16103.6 16146.5 03:17:08 INFO - PID 26283 | 03:17:08 INFO - PID 26283 | [#3] mootools.html Cycles:180 Average:8470.33 Median:2578.22 stddev:5824.56 (225.9%) stddev-sans-first:5816.06 03:17:08 INFO - PID 26283 | Values: 1311.3 1371.7 1373.2 1376.9 1394.2 11966.3 13355.1 14098.3 14114.8 14277.7 12080.5 12176.1 13664.0 14295.4 14305.9 2579.2 2622.0 2639.1 2648.1 2650.3 2519.8 2577.7 2593.5 2597.0 2600.5 2524.2 2545.4 2571.0 2574.3 2578.7 2480.5 2520.9 2522.6 2528.4 2528.5 2447.4 2464.2 2464.2 2465.2 2475.3 13404.5 13719.3 13736.5 13742.3 13773.0 7507.4 7775.3 7814.7 7828.3 7831.5 4759.4 4981.7 4991.1 5180.6 5229.3 1786.3 1802.8 1814.1 1850.7 1863.2 21498.9 21608.5 21657.8 21677.7 21732.9 6764.5 6879.1 6890.5 7100.1 7116.2 15421.5 16013.3 16060.7 17846.5 17949.7 7430.2 7626.7 7641.9 7651.4 7651.8 6916.8 7075.7 7082.0 7274.4 7325.3 5033.8 5114.8 5120.1 5188.2 5193.5 1529.8 1555.2 1559.0 1563.0 1571.4 851.0 852.7 887.9 900.8 902.5 6909.0 6915.5 6972.2 7081.4 7082.3 694.6 697.7 705.2 724.2 731.9 4373.4 4665.1 4692.4 4801.2 4852.4 18121.5 18890.7 18980.2 19021.5 19072.8 18328.2 18499.7 19324.9 19532.8 19604.3 10586.6 10794.0 10824.4 10855.6 10869.3 7011.9 7594.4 7839.8 7895.3 7933.8 16165.9 16802.2 17038.4 17508.5 17566.6 17118.0 17605.9 17620.3 17682.6 17689.3 10756.6 10843.0 10952.2 10981.3 10989.5 12640.7 12651.9 12707.4 12749.4 12843.4 9143.1 9463.0 9668.9 9764.6 9827.0 9276.2 9430.8 9475.2 9591.1 9841.4 8658.7 8722.3 8780.1 8786.7 8812.8 6337.1 6453.9 6675.7 6763.4 6786.6 6478.2 6519.5 6930.7 6997.8 7091.6 03:17:08 INFO - PID 26283 | 03:17:08 INFO - PID 26283 | [#4] prototype.html Cycles:180 Average:5477.70 Median:6077.51 stddev:1536.22 (25.3%) stddev-sans-first:1493.85 03:17:08 INFO - PID 26283 | Values: 470.8 475.0 501.9 513.2 515.1 6179.0 6250.5 6280.0 6282.6 6314.2 5885.9 6005.8 6039.2 6100.7 6138.2 4911.8 4918.0 4934.4 4944.9 5004.4 4937.3 4944.6 4948.6 4964.3 4988.9 4736.6 4905.8 4937.7 4941.9 4956.9 4918.4 4942.4 4943.3 4969.5 4996.7 4412.8 4430.6 4461.1 4499.2 4502.2 5935.3 5986.2 6074.1 6080.9 6086.2 5687.2 5726.3 5726.8 5744.5 5750.3 6484.2 6490.0 6502.5 6507.3 6586.0 4829.7 4867.1 4867.6 4911.7 4930.6 6775.9 6788.3 6895.4 6932.3 7004.7 4931.3 4968.7 4999.3 5024.4 5043.0 5029.0 5037.8 5055.1 5071.9 5100.8 5691.2 5697.9 5737.7 5739.4 5750.3 6405.6 6475.9 6569.7 6599.2 6603.4 1653.5 1662.7 1675.0 1729.2 1782.6 1226.7 1227.1 1235.3 1238.8 1249.5 5634.6 5720.9 5741.7 5750.7 5764.2 6415.6 6559.1 6640.0 6658.3 6780.4 5740.0 5781.5 5802.0 5805.3 5859.5 3520.2 3521.2 3562.8 3568.4 3576.9 6411.7 6489.3 6493.6 6539.8 6566.5 6664.9 6810.6 6871.5 6930.6 6937.8 6280.8 6304.6 6482.7 6483.0 6513.4 6611.2 6619.6 6673.5 6682.9 6710.4 6525.2 6656.6 6692.7 6722.6 6739.2 6387.4 6477.2 6481.3 6485.3 6567.2 4921.4 4986.4 4993.3 5020.3 5021.2 6675.8 6732.2 6763.0 6772.4 6776.5 6389.2 6487.9 6496.3 6516.4 6528.7 6123.0 6436.0 6456.2 6521.6 6532.9 6091.8 6280.7 6312.2 6358.3 6380.1 6360.2 6366.8 6471.4 6503.1 6545.4 6261.9 6304.0 6317.3 6325.4 6352.4 03:17:08 INFO - PID 26283 | 03:17:08 INFO - PID 26283 | [#5] yui.html Cycles:180 Average:22435.75 Median:19878.00 stddev:30021.77 (151.0%) stddev-sans-first:30062.52 03:17:08 INFO - PID 26283 | Values: 920.4 926.1 937.8 939.5 943.6 18014.5 18880.7 18899.0 18929.3 18959.2 19201.5 20733.9 21473.7 21528.5 21602.0 1021.9 1038.5 1039.6 1040.2 1040.9 956.6 1037.1 1037.2 1037.8 1048.0 1093.3 1094.9 1095.0 1095.3 1095.9 1076.1 1076.4 1084.4 1084.8 1086.7 801.5 801.8 804.4 804.7 806.0 19274.4 19833.8 19922.2 20034.5 20270.0 20132.5 20186.0 20565.6 20751.3 20782.5 15852.6 16080.0 16288.3 16313.1 16456.3 3574.1 3591.0 3592.6 3592.7 3611.3 120415.4 121724.0 123572.0 125558.0 128949.0 15854.4 15928.5 16064.8 16139.9 16210.7 28552.9 29657.4 29787.3 29846.6 29937.0 8056.7 8271.3 8273.8 8291.2 8336.5 4068.8 4153.1 4155.2 4165.7 4165.9 4558.8 5126.6 5655.7 5673.8 5675.9 1035.4 1056.9 1070.8 1081.9 1082.6 503.6 519.6 531.4 537.1 539.0 10929.0 11403.9 11654.4 11989.8 12337.1 571.6 572.3 583.3 589.7 596.1 5314.8 5340.4 5340.5 5354.2 5582.7 96154.5 100574.5 100937.0 102242.0 103847.5 83117.6 87799.6 88471.6 91384.0 93902.5 75606.0 76966.6 80752.0 81481.6 82721.2 10535.5 10609.9 10703.5 10760.9 10957.5 34140.8 35527.8 35933.3 37207.8 38027.4 44560.8 64051.1 65462.7 65904.7 66711.7 17009.7 17034.7 17238.3 17471.9 17487.9 20375.6 20664.9 20914.5 20958.2 21232.3 17232.6 17786.4 17977.7 18725.4 18919.9 16192.8 16328.0 18438.2 18643.6 18897.9 16215.3 16483.9 16578.8 16792.9 17074.4 12420.3 12683.2 13497.2 13693.1 13694.7 12976.0 14231.9 14550.6 14594.6 14626.5 03:17:08 INFO - PID 26283 | -------- Summary: end -------- 03:17:08 INFO - PID 26283 | 03:17:08 INFO - TEST-INFO | 26283: exit 0 03:17:08 INFO - TEST-OK | dromaeo_css | took 1114042ms 03:17:08 INFO - TEST-START | kraken 03:17:08 INFO - Initialising browser for kraken test... 03:17:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33309/getInfo.html -profile /tmp/tmpJ4cY_g/profile 03:17:08 INFO - TEST-INFO | started process 26655 (/builds/slave/test/build/application/firefox/firefox http://localhost:33309/getInfo.html) 03:17:14 INFO - TEST-INFO | 26655: exit 0 03:17:15 INFO - Browser initialized. 03:17:15 INFO - Running cycle 1/1 for kraken test... 03:17:15 INFO - TEST-INFO | started process 26885 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ4cY_g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:17:16 INFO - PID 26885 | 03:17:16 INFO - PID 26885 | (/builds/slave/test/build/application/firefox/firefox:26939): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:17:16 INFO - PID 26885 | 03:17:26 INFO - PID 26885 | RSS: Main: 173199360 03:17:26 INFO - PID 26885 | 03:17:29 INFO - PID 26885 | Cycle 1(1): loaded http://localhost:33309/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33309/tests/kraken/driver.html?testName=audio-beat-detection) 03:17:30 INFO - PID 26885 | RSS: Main: 178405376 03:17:30 INFO - PID 26885 | 03:17:32 INFO - PID 26885 | Cycle 1(1): loaded http://localhost:33309/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33309/tests/kraken/driver.html?testName=audio-dft) 03:17:33 INFO - PID 26885 | RSS: Main: 178638848 03:17:33 INFO - PID 26885 | 03:17:36 INFO - PID 26885 | Cycle 1(1): loaded http://localhost:33309/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33309/tests/kraken/driver.html?testName=audio-fft) 03:17:37 INFO - PID 26885 | RSS: Main: 173514752 03:17:37 INFO - PID 26885 | 03:17:39 INFO - PID 26885 | Cycle 1(1): loaded http://localhost:33309/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33309/tests/kraken/driver.html?testName=audio-oscillator) 03:17:39 INFO - PID 26885 | RSS: Main: 174096384 03:17:39 INFO - PID 26885 | 03:17:42 INFO - PID 26885 | Cycle 1(1): loaded http://localhost:33309/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33309/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:17:42 INFO - PID 26885 | RSS: Main: 174317568 03:17:42 INFO - PID 26885 | 03:17:45 INFO - PID 26885 | Cycle 1(1): loaded http://localhost:33309/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33309/tests/kraken/driver.html?testName=imaging-darkroom) 03:17:46 INFO - PID 26885 | RSS: Main: 176779264 03:17:46 INFO - PID 26885 | 03:17:50 INFO - PID 26885 | Cycle 1(1): loaded http://localhost:33309/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33309/tests/kraken/driver.html?testName=imaging-desaturate) 03:17:50 INFO - PID 26885 | RSS: Main: 176345088 03:17:50 INFO - PID 26885 | 03:17:53 INFO - PID 26885 | Cycle 1(1): loaded http://localhost:33309/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33309/tests/kraken/driver.html?testName=json-parse-financial) 03:17:54 INFO - PID 26885 | RSS: Main: 177295360 03:17:54 INFO - PID 26885 | 03:17:56 INFO - PID 26885 | Cycle 1(1): loaded http://localhost:33309/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33309/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:17:56 INFO - PID 26885 | RSS: Main: 177647616 03:17:56 INFO - PID 26885 | 03:17:58 INFO - PID 26885 | Cycle 1(1): loaded http://localhost:33309/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33309/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:17:59 INFO - PID 26885 | RSS: Main: 174534656 03:17:59 INFO - PID 26885 | 03:18:01 INFO - PID 26885 | Cycle 1(1): loaded http://localhost:33309/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33309/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:18:02 INFO - PID 26885 | RSS: Main: 174399488 03:18:02 INFO - PID 26885 | 03:18:04 INFO - PID 26885 | Cycle 1(1): loaded http://localhost:33309/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33309/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:18:05 INFO - PID 26885 | RSS: Main: 174825472 03:18:05 INFO - PID 26885 | 03:18:08 INFO - PID 26885 | Cycle 1(1): loaded http://localhost:33309/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33309/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:18:09 INFO - PID 26885 | RSS: Main: 174387200 03:18:09 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | Cycle 1(1): loaded http://localhost:33309/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33309/tests/kraken/driver.html?testName=ai-astar) 03:18:11 INFO - PID 26885 | RSS: Main: 176779264 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | __start_tp_report 03:18:11 INFO - PID 26885 | _x_x_mozilla_page_load 03:18:11 INFO - PID 26885 | _x_x_mozilla_page_load_details 03:18:11 INFO - PID 26885 | |i|pagename|runs| 03:18:11 INFO - PID 26885 | |0;ai-astar;111;111;103;110;108;112;110;104;108;113 03:18:11 INFO - PID 26885 | |1;audio-beat-detection;149;120;145;176;118;155;147;119;168;118 03:18:11 INFO - PID 26885 | |2;audio-dft;163;167;170;164;164;168;167;163;170;164 03:18:11 INFO - PID 26885 | |3;audio-fft;80;78;77;172;77;81;76;74;158;80 03:18:11 INFO - PID 26885 | |4;audio-oscillator;82;79;138;82;79;81;130;82;79;125 03:18:11 INFO - PID 26885 | |5;imaging-gaussian-blur;121;114;116;117;117;114;114;116;116;115 03:18:11 INFO - PID 26885 | |6;imaging-darkroom;152;151;152;150;150;153;152;152;150;151 03:18:11 INFO - PID 26885 | |7;imaging-desaturate;94;97;94;96;94;94;100;98;98;99 03:18:11 INFO - PID 26885 | |8;json-parse-financial;56;56;57;56;56;57;57;57;58;56 03:18:11 INFO - PID 26885 | |9;json-stringify-tinderbox;62;54;55;55;57;55;55;54;55;54 03:18:11 INFO - PID 26885 | |10;stanford-crypto-aes;80;97;80;82;81;101;90;80;80;88 03:18:11 INFO - PID 26885 | |11;stanford-crypto-ccm;137;131;133;134;141;140;134;130;138;170 03:18:11 INFO - PID 26885 | |12;stanford-crypto-pbkdf2;178;186;186;182;201;181;181;189;183;185 03:18:11 INFO - PID 26885 | |13;stanford-crypto-sha256-iterative;69;67;69;68;69;68;68;68;67;66 03:18:11 INFO - PID 26885 | __end_tp_report 03:18:11 INFO - PID 26885 | __start_cc_report 03:18:11 INFO - PID 26885 | _x_x_mozilla_cycle_collect,956 03:18:11 INFO - PID 26885 | __end_cc_report 03:18:11 INFO - PID 26885 | __startTimestamp1501496291699__endTimestamp 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | ------- Summary: start ------- 03:18:11 INFO - PID 26885 | Number of tests: 14 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | [#0] ai-astar Cycles:10 Average:109.00 Median:110.50 stddev:3.30 (3.0%) stddev-sans-first:3.42 03:18:11 INFO - PID 26885 | Values: 111.0 111.0 103.0 110.0 108.0 112.0 110.0 104.0 108.0 113.0 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:148.00 stddev:21.72 (14.7%) stddev-sans-first:22.87 03:18:11 INFO - PID 26885 | Values: 149.0 120.0 145.0 176.0 118.0 155.0 147.0 119.0 168.0 118.0 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | [#2] audio-dft Cycles:10 Average:166.00 Median:167.00 stddev:2.75 (1.6%) stddev-sans-first:2.69 03:18:11 INFO - PID 26885 | Values: 163.0 167.0 170.0 164.0 164.0 168.0 167.0 163.0 170.0 164.0 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | [#3] audio-fft Cycles:10 Average:95.30 Median:77.50 stddev:36.94 (47.7%) stddev-sans-first:38.77 03:18:11 INFO - PID 26885 | Values: 80.0 78.0 77.0 172.0 77.0 81.0 76.0 74.0 158.0 80.0 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:80.00 stddev:24.59 (30.7%) stddev-sans-first:25.57 03:18:11 INFO - PID 26885 | Values: 82.0 79.0 138.0 82.0 79.0 81.0 130.0 82.0 79.0 125.0 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | [#5] imaging-gaussian-blur Cycles:10 Average:116.00 Median:116.00 stddev:2.11 (1.8%) stddev-sans-first:1.24 03:18:11 INFO - PID 26885 | Values: 121.0 114.0 116.0 117.0 117.0 114.0 114.0 116.0 116.0 115.0 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | [#6] imaging-darkroom Cycles:10 Average:151.30 Median:152.00 stddev:1.06 (0.7%) stddev-sans-first:1.09 03:18:11 INFO - PID 26885 | Values: 152.0 151.0 152.0 150.0 150.0 153.0 152.0 152.0 150.0 151.0 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.50 stddev:2.32 (2.4%) stddev-sans-first:2.29 03:18:11 INFO - PID 26885 | Values: 94.0 97.0 94.0 96.0 94.0 94.0 100.0 98.0 98.0 99.0 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:0.70 (1.2%) stddev-sans-first:0.71 03:18:11 INFO - PID 26885 | Values: 56.0 56.0 57.0 56.0 56.0 57.0 57.0 57.0 58.0 56.0 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | [#9] json-stringify-tinderbox Cycles:10 Average:55.60 Median:55.00 stddev:2.41 (4.4%) stddev-sans-first:0.93 03:18:11 INFO - PID 26885 | Values: 62.0 54.0 55.0 55.0 57.0 55.0 55.0 54.0 55.0 54.0 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:81.50 stddev:7.82 (9.6%) stddev-sans-first:8.00 03:18:11 INFO - PID 26885 | Values: 80.0 97.0 80.0 82.0 81.0 101.0 90.0 80.0 80.0 88.0 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | [#11] stanford-crypto-ccm Cycles:10 Average:138.80 Median:137.50 stddev:11.55 (8.4%) stddev-sans-first:12.24 03:18:11 INFO - PID 26885 | Values: 137.0 131.0 133.0 134.0 141.0 140.0 134.0 130.0 138.0 170.0 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.20 Median:185.50 stddev:6.39 (3.4%) stddev-sans-first:6.22 03:18:11 INFO - PID 26885 | Values: 178.0 186.0 186.0 182.0 201.0 181.0 181.0 189.0 183.0 185.0 03:18:11 INFO - PID 26885 | 03:18:11 INFO - PID 26885 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:0.97 03:18:11 INFO - PID 26885 | Values: 69.0 67.0 69.0 68.0 69.0 68.0 68.0 68.0 67.0 66.0 03:18:11 INFO - PID 26885 | -------- Summary: end -------- 03:18:11 INFO - PID 26885 | 03:18:12 INFO - PID 26885 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:18:12 INFO - TEST-INFO | 26885: exit 0 03:18:12 INFO - TEST-OK | kraken | took 63594ms 03:18:12 INFO - SUITE-END | took 1177s 03:18:12 INFO - Completed test suite (00:19:38) 03:18:12 INFO - javascript benchmark 03:18:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8358.4227364003, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3163.5571020057196, 3169.7305729013033, 3183.1087295557254, 3187.07574803307, 3202.1994501374656, 6192.102145188945, 6262.937370626292, 6272.717727702258, 6331.2085989251345, 6336.968315158418, 6130.356312587181, 6261.0, 6271.686415679221, 6283.057541368797, 6286.0, 5730.828075157752, 5739.1965124882445, 5759.4816466518005, 5777.740001699934, 5785.508231800302, 5783.421657834204, 5812.157237200603, 5819.709014549256, 5828.883422331551, 5832.0, 5753.395893431192, 5792.044312688401, 5793.073108302672, 5793.594448388614, 5811.302643682764, 5778.133280008012, 5785.161151633031, 5785.710714464281, 5810.883782324372, 5812.564057695689, 5700.942990570083, 5702.686352250625, 5740.799072032459, 5781.392953739839, 5811.389804070554, 6005.879882402372, 6036.667983260919, 6115.847103822395, 6135.355787642278, 6137.508999280091, 6688.030235615857, 6689.966550167267, 6699.430548403391, 6703.162104736908, 6709.3290670933, 6726.058351830748, 6748.6288254146, 6748.8312792180095, 6767.796966091025, 6778.796636100925, 6211.409916057967, 6241.28225254097, 6295.748170073191, 6298.905516417258, 6326.114343991845, 6943.6875340608785, 6948.096747422803, 6977.441804655616, 6993.6503174841055, 7047.119110111236, 6456.192975878015, 6571.605703657796, 6577.243616984012, 6602.86794264112, 6712.697928593225, 6657.933420665829, 6662.267150613428, 6688.531802773759, 6690.397864192246, 6693.899591506131, 6591.802245932629, 6599.0, 6634.23706273775, 6692.899606505906, 6762.357576030269, 6626.304238055031, 6636.435902948207, 6705.631190284484, 6725.19297684281, 6729.528932974645, 5309.946900531023, 5357.534656427661, 5390.164524498709, 5423.647462914898, 5429.918551221735, 4599.862004139881, 4617.515160908123, 4642.303654451792, 4647.581717645428, 4721.669483136147, 5789.9710501447225, 5832.562557808182, 5849.356570777211, 5850.531957443394, 5855.121731740273, 6722.394984451423, 6805.931940680629, 6807.2512023677355, 6807.863842723217, 6810.62541560219, 5694.174344719992, 5764.279465066867, 5789.334226563915, 5799.159121927381, 5847.0, 5764.682942438206, 5851.1515830205235, 5870.853228669317, 5901.0558310670085, 5932.614380065368, 6507.642079685568, 6509.349065093547, 6512.479001679854, 6517.315681853337, 6525.445337146396, 6646.401823835878, 6712.362325579063, 6734.023566582916, 6734.932650673627, 6737.629430381376, 6435.2599451063725, 6506.121673574007, 6519.543631945908, 6528.543001989815, 6529.5102867284195, 5699.430056994267, 6561.343865613591, 6587.901181482186, 6686.197656281277, 6690.799276021728, 6709.4632429404555, 6710.16122984627, 6782.0, 6850.965745171242, 6871.793846184622, 6423.0, 6428.742850285937, 6486.708098135501, 6506.154199953976, 6619.768308109193, 6457.935420645734, 6530.020496925499, 6586.50601204902, 6590.406863382127, 6594.076829244005, 6460.45086167681, 6514.478841692748, 6517.641529715911, 6522.836929076811, 6528.314526974789, 6517.608943463408, 6533.379328963741, 6595.076689263603, 6636.933630663631, 6649.534532582867, 6294.779682711083, 6351.777687780906, 6376.107344971801, 6402.423781859655, 6460.806175814733], "value": 6121.101931677131, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [800.7917941335255, 868.2229404682809, 898.5911410308311, 898.9820203595929, 1162.0761494611784, 29198.85400572997, 29852.55221171684, 39916.20167596646, 40680.0, 40706.7964660177, 40564.0, 40733.79633101838, 40802.38796418056, 40977.59022409775, 41035.38446923299, 5773.278340207474, 5904.793332233373, 7503.512271702322, 7654.3111119999185, 7714.267144621252, 7501.887471687929, 7573.469857110004, 7595.924040759577, 7601.201873803254, 7602.0, 7555.69777208911, 7598.924010759904, 7602.125755538101, 7650.043744531934, 7653.119891212526, 7553.433492488058, 7576.204498527658, 7580.6967721291085, 7584.317411432998, 7585.0, 7472.252774722539, 7483.812904677427, 7491.850162996764, 7548.5848278344665, 7550.0939887213335, 37492.62507374919, 37541.0, 37564.0, 37721.24557508862, 37804.243915121544, 3481.425564781808, 3508.5965114011706, 3513.086597484647, 3516.841742121611, 3523.541939547868, 30259.697403025908, 30464.0, 30467.695323046708, 30541.23646908845, 30557.847210764026, 4553.236731373034, 4702.059588082398, 4751.619870410359, 4801.975990120062, 4892.437369702494, 183446.0, 184241.0, 184454.0, 185043.07478462657, 185157.0, 46509.30236046462, 46657.0, 48042.75978620085, 48734.26898596524, 48884.0, 50429.24356134661, 50647.74676126596, 50718.0, 50788.0, 50813.23780143301, 15923.363065477251, 16291.429799956944, 16326.918365408097, 16334.918325408296, 16335.101569413797, 11084.556617735361, 11241.943790280997, 13083.215007099605, 13345.866541334462, 13355.332233388292, 13807.792883106762, 13890.23603701786, 13939.581812545639, 13961.581152565439, 14107.224102674247, 5560.471755183251, 5802.796902108406, 5815.360310365856, 5833.629097162164, 5835.503982161479, 966.6036924860773, 982.872226610536, 988.268681175939, 1008.3899240959261, 1010.2927950434726, 22338.66492002621, 22675.43311417195, 22822.8858855708, 23065.42336441569, 23349.0, 1047.8428235764695, 1053.4627340056472, 1095.3373209208473, 1102.6471529110768, 1108.706192858893, 11810.40947952599, 11832.822507662391, 11914.821277680841, 12096.0, 12104.87895121055, 234613.0, 236050.0, 236139.0, 236587.0, 236819.0, 49904.251436228486, 50161.74919125381, 50473.24290135651, 50509.494905051215, 50649.0, 1443.4154167561944, 1452.5701039184705, 1477.7709455034487, 1481.8221813382247, 1494.9327280272414, 21517.892410537846, 21831.126754929624, 21924.780752192273, 21925.451863703536, 22117.77882221157, 35412.46881296731, 35587.64412355947, 35664.82167589146, 35680.464793027604, 35720.464193037646, 141102.0, 141906.0, 142150.0, 142559.28720356332, 142594.0, 32694.182645434055, 36111.45832812563, 36788.816055919546, 36963.44554831626, 37006.814965925, 44386.0, 45456.77271613753, 45546.31680524728, 45711.54288457206, 45924.540754592024, 26472.338191545365, 26795.732042680105, 26811.865940670174, 27591.0, 27649.170524884285, 24911.377215569755, 25366.492670146126, 25749.871250643628, 25784.226473205836, 27998.580021300106, 36145.81927090348, 37690.811545942095, 37701.0, 37796.24407511889, 38115.42826857544, 32359.352812944082, 32715.509267361485, 32870.835645821615, 32903.0, 32980.83509582437, 23420.414489637897, 30973.380532389678, 31332.843335783175, 31531.84234078815, 32125.0], "value": 16689.77695757263, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.3236948031841, 573.667272981671, 578.7193211292525, 592.5052581094785, 596.1564386393254, 22137.557248855035, 22590.77409225907, 22973.08107675691, 23007.884960575193, 23036.88481557594, 25664.23007309779, 26103.73896261037, 26105.21684349468, 26160.215193544176, 26246.343841403926, 6413.390727880844, 6588.670566471681, 6604.438622717074, 6612.173478315211, 6619.139511863451, 5884.323302820167, 6411.262704788963, 6474.579152355105, 6482.805515834509, 6489.1888513935755, 6441.3880681335195, 6450.2259728832605, 6451.548391612589, 6451.645159516224, 6458.354164583551, 6417.0, 6422.068800023971, 6425.164728585254, 6430.967845160768, 6458.354164583551, 5878.2064421303, 5920.230370051909, 5940.554458415636, 6002.399760023963, 6036.939630603725, 27757.444851102864, 28222.0, 28805.56791648127, 29151.41697166045, 29294.560581591293, 1903.714442833572, 1955.452473307476, 1959.4121763470903, 1959.8628096033283, 1960.137539482623, 18566.814331856644, 19296.807031929642, 19374.80625193744, 19387.612247754965, 19395.903020484948, 2226.7327920649627, 2266.614675505168, 2318.7449380568123, 2320.060375547906, 2335.43949452132, 138474.0, 141333.58666413306, 142194.0, 142390.0, 142501.0, 14168.079074860101, 14951.401943922121, 15179.696406071816, 15296.235188240766, 15735.527934161993, 23567.646485302736, 23632.29103126909, 23641.645375319385, 23664.7633523666, 24668.753312467004, 9782.804343913223, 9919.50402479873, 10056.798864022825, 10057.748556286006, 10185.541650625739, 9031.096890311048, 9035.825342705406, 9077.45535267886, 9121.81756364869, 9208.769780755534, 9384.8592271116, 9539.284553658503, 9569.425834449956, 9728.0, 9796.0, 2758.558630619091, 2883.9423211536073, 2887.307046308913, 2916.6645835728737, 2957.349383135707, 908.8909330880205, 935.7379933618598, 943.613118621362, 962.0, 983.7442265011149, 18841.717374239397, 19025.524361891064, 19399.4180174595, 19452.22191112368, 19616.901915490333, 983.179045497018, 983.4394395194813, 991.9652812151685, 1018.0786388318584, 1033.204432586919, 10394.48027598617, 10618.522166502526, 10729.03438690506, 10766.138708903269, 10813.351198927932, 510842.0, 512968.0, 535630.0, 538249.0, 606575.0, 123310.0, 129357.0, 131961.34019330033, 134644.0, 134825.0, 1216.4161202622613, 1265.487477571585, 1283.8972882169403, 1292.8513220979519, 1304.2956803326288, 18747.531311717317, 21701.34895953124, 21811.12755489826, 22977.540449191256, 23085.0, 48138.5186148134, 50072.74963625159, 55208.17187742107, 57035.429645703014, 58759.0, 67090.66454667696, 67329.3267067323, 67510.0, 67658.0, 68098.0, 20183.39449816508, 21086.367408977756, 21375.358739237847, 21435.24976625873, 21506.139754409647, 23538.764612353658, 23677.64483532714, 23694.526109478058, 23918.402039949142, 23947.640785388583, 18359.449216523524, 18472.907635462274, 18501.907490463, 18774.53063673419, 18873.811261887753, 14783.0, 15186.924065379602, 15440.0, 15692.136932468367, 15846.207689615243, 17796.46610601684, 19237.519062023563, 19264.90367548153, 19399.2240310386, 19419.70870436973, 19734.30929917446, 20662.690059648816, 20801.791982079987, 20831.687524687444, 20896.268630597857, 15094.773578396112, 15972.520824375288, 16026.0, 16103.597410064842, 16146.546810787086], "value": 12224.8188636085, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1311.2591385866986, 1371.6845125621107, 1373.2172661582895, 1376.9105008174467, 1394.184402124757, 11966.34185119818, 13355.131916425426, 14098.295085245734, 14114.788278175824, 14277.714445711079, 12080.516779328838, 12176.08679349048, 13664.0, 14295.356708948097, 14305.928470357634, 2579.2133399313157, 2622.0167437211044, 2639.089514117636, 2648.1393547097173, 2650.2976711171555, 2519.8488090714523, 2577.7035640901254, 2593.5072336256053, 2596.9610455843176, 2600.5059038782665, 2524.1670248817936, 2545.4272788622598, 2571.035861551918, 2574.3435423966957, 2578.729233430491, 2480.516299321633, 2520.873956302187, 2522.646829443861, 2528.4311030018284, 2528.4816612594373, 2447.3881529617597, 2464.199135281041, 2464.2484042367073, 2465.2234546118093, 2475.2574227731607, 13404.530841420503, 13719.314034298344, 13736.519221827288, 13742.312884355742, 13773.0, 7507.399408047343, 7775.261350171724, 7814.687412503493, 7828.334591559725, 7831.530108193527, 4759.357486739281, 4981.701097934136, 4991.051700176955, 5180.6373553851245, 5229.320188375525, 1786.2765579940144, 1802.8107048759953, 1814.0748218408576, 1850.6668799616064, 1863.1988245054756, 21498.892505537373, 21608.459788505414, 21657.783422165736, 21677.67483487749, 21732.89133554338, 6764.458843292623, 6879.105716256855, 6890.483213758988, 7100.076989991268, 7116.181639111465, 15421.460248891235, 16013.279402426919, 16060.67878642444, 17846.464606061578, 17949.7307540387, 7430.182679905206, 7626.656800443993, 7641.885371719429, 7651.426143039294, 7651.808704782425, 6916.757913473105, 7075.71697132109, 7082.0, 7274.418046556369, 7325.340719335285, 5033.823816166417, 5114.820981265638, 5120.129577971754, 5188.247704082887, 5193.506616871391, 1529.793477880494, 1555.175756848872, 1558.9688206235812, 1562.9609259768597, 1571.4264293532779, 850.961706723199, 852.7484392104344, 887.933404994628, 900.8378491871526, 902.4540153207346, 6909.0, 6915.51591388598, 6972.1981972072845, 7081.398081163159, 7082.256363081905, 694.5936627073219, 697.7313734212365, 705.1890326124998, 724.1961422820665, 731.8536292741474, 4373.431453910971, 4665.090307390026, 4692.389989301437, 4801.159797035505, 4852.393450818648, 18121.456356309332, 18890.716639250422, 18980.240790368232, 19021.524461888286, 19072.80927190738, 18328.175232114587, 18499.722504162448, 19324.903375483034, 19532.80467195338, 19604.313849015216, 10586.629467968583, 10794.0, 10824.404657743742, 10855.565777368816, 10869.293495922899, 7011.894821577578, 7594.392448604209, 7839.804004899923, 7895.289423951872, 7933.761987140395, 16165.919170404073, 16802.24389902482, 17038.403655871985, 17508.47474575765, 17566.560835979202, 17118.0, 17605.91197044058, 17620.29518819233, 17682.5579360517, 17689.292428302724, 10756.623518177139, 10843.0, 10952.233343665866, 10981.341119532854, 10989.450527473755, 12640.6839829005, 12651.93674031624, 12707.428165732748, 12749.362531873183, 12843.42204600814, 9143.13140251675, 9462.952685236805, 9668.9033109668, 9764.609415623296, 9827.0, 9276.16514513697, 9430.811383772423, 9475.194608458358, 9591.088846559565, 9841.360311579725, 8658.740237792876, 8722.25860801839, 8780.078091800524, 8786.736397908073, 8812.823743525221, 6337.112804207322, 6453.903191452038, 6675.666216689243, 6763.391294783296, 6786.59280443175, 6478.1578394808375, 6519.546657346771, 6930.7227710891, 6997.755078572225, 7091.574505529685], "value": 6150.201914698588, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.80696914265155, 474.96200303975684, 501.89962007598484, 513.1789137380192, 515.0625860933101, 6178.980468222738, 6250.468710159642, 6280.0, 6282.591631543952, 6314.210723659543, 5885.909897303917, 6005.849853753658, 6039.248617900815, 6100.725467353968, 6138.232720909887, 4911.814242662017, 4917.950820491785, 4934.4325402578625, 4944.901101977958, 5004.449510553836, 4937.308776771237, 4944.579710724593, 4948.628852836034, 4964.304997300381, 4988.900221995558, 4736.644751643623, 4905.82829600964, 4937.703737775727, 4941.925871111936, 4956.851294461172, 4918.36061312029, 4942.382202224722, 4943.283223932528, 4969.503049695038, 4996.67588089153, 4412.801423935931, 4430.585330994616, 4461.107778444291, 4499.155346308031, 4502.16709908668, 5935.347111817697, 5986.191864098335, 6074.119252708332, 6080.908786368208, 6086.2087928569, 5687.203791469197, 5726.284214473191, 5726.828195154152, 5744.540436765049, 5750.309962804448, 6484.221893372778, 6489.967550162266, 6502.512311576651, 6507.316731743146, 6586.0, 4829.6860704054125, 4867.148249056401, 4867.610591152699, 4911.705297682151, 4930.580900623451, 6775.898361524581, 6788.261382024496, 6895.379415852597, 6932.306769323028, 7004.719811207495, 4931.284963680247, 4968.652194346433, 4999.325091112726, 5024.397072351341, 5042.9747851261245, 5029.0, 5037.848864534069, 5055.140626093573, 5071.873203169876, 5100.7959681613065, 5691.186904773812, 5697.9430205698245, 5737.741801618937, 5739.426057394227, 5750.3099628044065, 6405.615663060231, 6475.935240647627, 6569.67151642416, 6599.175103112111, 6603.438707709803, 1653.4956838164235, 1662.6674665066794, 1674.983250167507, 1729.2477772169148, 1782.5989152440598, 1226.650404634675, 1227.098082909061, 1235.2958813476232, 1238.8416830263552, 1249.4689756853302, 5634.6337488063145, 5720.942790572124, 5741.655500669973, 5750.741216645178, 5764.213093940892, 6415.647139407284, 6559.114519539897, 6640.0, 6658.267590565034, 6780.389764921082, 5739.995500787346, 5781.53747700183, 5801.970990145022, 5805.332386775575, 5859.53123750099, 3520.2255503789124, 3521.2253304273017, 3562.786232826039, 3568.357695614762, 3576.91057723559, 6411.711472983727, 6489.286178520359, 6493.577917435352, 6539.822831890306, 6566.474682025427, 6664.900026499607, 6810.557313774688, 6871.522622636157, 6930.584164950119, 6937.7571784987285, 6280.780172693934, 6304.590201636971, 6482.708278127496, 6483.0, 6513.41379275858, 6611.206655201407, 6619.642973190581, 6673.466122710074, 6682.920980055096, 6710.429613482908, 6525.216973962965, 6656.633885136171, 6692.732290708317, 6722.563033402813, 6739.157605299338, 6387.425131738166, 6477.222733272038, 6481.319461456667, 6485.254195767548, 6567.2447668518735, 4921.384826896638, 4986.351774269322, 4993.300937868774, 5020.297158397901, 5021.171506701354, 6675.766348177791, 6732.225794033749, 6762.966185169043, 6772.356626120511, 6776.525643204928, 6389.169407976933, 6487.9026814598765, 6496.252930913006, 6516.446102081376, 6528.738850445929, 6122.969385153046, 6435.967820160869, 6456.192975878015, 6521.587082721235, 6532.902006469812, 6091.7867874625945, 6280.654563999024, 6312.179416675986, 6358.332375100548, 6380.085974207812, 6360.173177486897, 6366.840828979312, 6471.417572418505, 6503.122078519339, 6545.37818907203, 6261.874762504815, 6304.0, 6317.336679648754, 6325.430711236011, 6352.36476352361], "value": 5021.958666565276, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [920.3511524375311, 926.0693003531451, 937.7608709779005, 939.5396255834639, 943.5942544705775, 18014.459566213, 18880.716789248156, 18899.0, 18929.33747318844, 18959.24163033479, 19201.519962000923, 20733.896330518364, 21473.67789483155, 21528.461788455297, 21602.0, 1021.8620486234356, 1038.4755698372312, 1039.5893622019298, 1040.2406243442292, 1040.921930855185, 956.617353058775, 1037.1029059863201, 1037.19617296595, 1037.8287582548908, 1048.0201012053717, 1093.283899046126, 1094.9178811589122, 1095.0473088413091, 1095.2552264460164, 1095.9232853700244, 1076.05844885725, 1076.354187487509, 1084.4144162152468, 1084.8264277715566, 1086.6957251969461, 801.5070731500085, 801.8396320735876, 804.4047404920375, 804.6781287484994, 805.9919400805976, 19274.421767347, 19833.80166198334, 19922.203111875508, 20034.499137521532, 20270.0, 20132.49668758278, 20186.0, 20565.5886882263, 20751.273705420237, 20782.48043798902, 15852.603684907854, 16080.0, 16288.267027983651, 16313.102779347131, 16456.259468323955, 3574.088607405095, 3591.048372181374, 3592.6407359264126, 3592.7305452090936, 3611.3319035978166, 120415.3979230107, 121724.0, 123572.0, 125557.99999999908, 128949.0, 15854.44509442187, 15928.522144335688, 16064.75902861458, 16139.919300403422, 16210.67578648444, 28552.857235713687, 29657.40685186284, 29787.255318617208, 29846.552301715492, 29937.0, 8056.677732890619, 8271.255586997198, 8273.751787446387, 8291.170882911661, 8336.541490218096, 4068.7558746475306, 4153.127843152912, 4155.231282212801, 4165.708400411942, 4165.91668166635, 4558.817647294138, 5126.5898728101665, 5655.7454914528125, 5673.8297851064535, 5675.8864822704145, 1035.435687550287, 1056.915446764257, 1070.844727514506, 1081.9242653014371, 1082.6481393547128, 503.639897473301, 519.5791408958755, 531.3703261634975, 537.0574641504213, 539.0189854464836, 10929.0, 11403.858816017824, 11654.359010254517, 11989.76020479603, 12337.136400451882, 571.5598988778701, 572.3303734630492, 583.3175185033521, 589.6609449566516, 596.1296507099684, 5314.813981510629, 5340.385855626652, 5340.519353258225, 5354.196870469383, 5582.692951887685, 96154.51922740482, 100574.49712751535, 100937.0, 102242.0, 103847.48076259722, 83117.58441207756, 87799.56100219458, 88471.55764221266, 91384.0, 93902.53048734713, 75606.0, 76966.6151669238, 80752.0, 81481.59259203785, 82721.17278827255, 10535.473226338805, 10609.89390106089, 10703.464826758474, 10760.89239107599, 10957.506912188812, 34140.829295853364, 35527.82236088803, 35933.28133437369, 37207.81396093002, 38027.42958855675, 44560.77719611381, 64051.112210083724, 65462.67268663626, 65904.67047664731, 66711.66644166748, 17009.659806803546, 17034.74447883258, 17238.310467581156, 17471.912640436716, 17487.912560437115, 20375.592488149858, 20664.896675516527, 20914.47713807167, 20958.161673532886, 21232.256871009442, 17232.569185770455, 17786.37747678825, 17977.73033404474, 18725.438236852915, 18919.905400472908, 16192.757108643615, 16328.020318780911, 18438.170282337058, 18643.627127457647, 18897.90551047236, 16215.270312836186, 16483.91758041202, 16578.83421165773, 16792.916035419745, 17074.40239591658, 12420.254784712946, 12683.175593586388, 13497.190168589916, 13693.109947853358, 13694.65763355924, 12976.0, 14231.928840355731, 14550.563483095524, 14594.635134121732, 14626.488072917396], "value": 8839.627136751438, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1561.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 103.0, 110.0, 108.0, 112.0, 110.0, 104.0, 108.0, 113.0], "value": 109.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 120.0, 145.0, 176.0, 118.0, 155.0, 147.0, 119.0, 168.0, 118.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 167.0, 170.0, 164.0, 164.0, 168.0, 167.0, 163.0, 170.0, 164.0], "value": 166.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 77.0, 172.0, 77.0, 81.0, 76.0, 74.0, 158.0, 80.0], "value": 95.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 79.0, 138.0, 82.0, 79.0, 81.0, 130.0, 82.0, 79.0, 125.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 114.0, 116.0, 117.0, 117.0, 114.0, 114.0, 116.0, 116.0, 115.0], "value": 116.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 152.0, 150.0, 150.0, 153.0, 152.0, 152.0, 150.0, 151.0], "value": 151.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 94.0, 96.0, 94.0, 94.0, 100.0, 98.0, 98.0, 99.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 57.0, 56.0, 56.0, 57.0, 57.0, 57.0, 58.0, 56.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 54.0, 55.0, 55.0, 57.0, 55.0, 55.0, 54.0, 55.0, 54.0], "value": 55.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 97.0, 80.0, 82.0, 81.0, 101.0, 90.0, 80.0, 80.0, 88.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 131.0, 133.0, 134.0, 141.0, 140.0, 134.0, 130.0, 138.0, 170.0], "value": 138.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 186.0, 186.0, 182.0, 201.0, 181.0, 181.0, 189.0, 183.0, 185.0], "value": 185.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 69.0, 68.0, 69.0, 68.0, 68.0, 68.0, 67.0, 66.0], "value": 67.9, "unit": "score"}]}]} 03:18:12 INFO - Return code: 0 03:18:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:18:12 INFO - # TBPL SUCCESS # 03:18:12 INFO - Running post-action listener: _package_coverage_data 03:18:12 INFO - Running post-action listener: _resource_record_post_action 03:18:12 INFO - [mozharness: 2017-07-31 10:18:12.956113Z] Finished run-tests step (success) 03:18:12 INFO - Running post-run listener: _resource_record_post_run 03:18:13 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 442290176; Read time: 12; Write time: 348000 03:18:13 INFO - TinderboxPrint: CPU usage
13.2% 03:18:13 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 03:18:13 INFO - TinderboxPrint: I/O write bytes / time
442,290,176 / 348,000 03:18:13 INFO - TinderboxPrint: CPU idle
8,280.1 (86.8%) 03:18:13 INFO - TinderboxPrint: CPU user
1,204.9 (12.6%) 03:18:13 INFO - TinderboxPrint: Swap in / out
0 / 0 03:18:13 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107565056; Read time: 0; Write time: 115024 03:18:13 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:18:13 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 334725120; Read time: 0; Write time: 232976 03:18:13 INFO - Running post-run listener: _upload_blobber_files 03:18:13 INFO - Blob upload gear active. 03:18:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:18:13 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 03:18:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:18:14 INFO - (blobuploader) - INFO - Open directory for files ... 03:18:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:18:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:18:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:14 INFO - (blobuploader) - INFO - Done attempting. 03:18:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:18:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:18:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:15 INFO - (blobuploader) - INFO - Done attempting. 03:18:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:18:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:18:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:16 INFO - (blobuploader) - INFO - Done attempting. 03:18:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:18:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:18:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:23 INFO - (blobuploader) - INFO - Done attempting. 03:18:23 INFO - (blobuploader) - INFO - Iteration through files over. 03:18:23 INFO - Return code: 0 03:18:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:18:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:18:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/084102e6e1cf68dbfae3ea3368fa153ad6684dda757d65b2cab1bd4724bc837c98ba16e7ca79f084500bf3adb58287f598d7f74db666fa4ea35c309aef0c4a2c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/707ecdcd61f96d9bb1e4257c050ace653b7315aae9231eb23795ebbcffa7725a473e08732e0f87297ddccc89fed0c52e8209a6c1ac618aaf39b9f764647e88be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3144038c94f829d0ce9a8e593fa8e6007a5372ae7adea70315970e8b78c5c4e3440d01fb1df13d82f23b6aa9250213db9926d49e5ae52af4aec90481fa85793", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea89ab0576d1b28ff910ad2e78fccdf22ec212e5480b0cef412bf5319bcd447959c68d5eea7220e835ecf7beff6df3524408bf17f628feca17506094c0df4c88"} 03:18:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:18:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:18:23 INFO - Contents: 03:18:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/084102e6e1cf68dbfae3ea3368fa153ad6684dda757d65b2cab1bd4724bc837c98ba16e7ca79f084500bf3adb58287f598d7f74db666fa4ea35c309aef0c4a2c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/707ecdcd61f96d9bb1e4257c050ace653b7315aae9231eb23795ebbcffa7725a473e08732e0f87297ddccc89fed0c52e8209a6c1ac618aaf39b9f764647e88be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3144038c94f829d0ce9a8e593fa8e6007a5372ae7adea70315970e8b78c5c4e3440d01fb1df13d82f23b6aa9250213db9926d49e5ae52af4aec90481fa85793", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea89ab0576d1b28ff910ad2e78fccdf22ec212e5480b0cef412bf5319bcd447959c68d5eea7220e835ecf7beff6df3524408bf17f628feca17506094c0df4c88"} 03:18:23 INFO - Running post-run listener: copy_logs_to_upload_dir 03:18:23 INFO - Copying logs to upload dir... 03:18:23 INFO - mkdir: /builds/slave/test/build/upload/logs 03:18:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.408651 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-07-31 03:18:23.506346) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-31 03:18:23.509497) ========= 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=3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/084102e6e1cf68dbfae3ea3368fa153ad6684dda757d65b2cab1bd4724bc837c98ba16e7ca79f084500bf3adb58287f598d7f74db666fa4ea35c309aef0c4a2c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/707ecdcd61f96d9bb1e4257c050ace653b7315aae9231eb23795ebbcffa7725a473e08732e0f87297ddccc89fed0c52e8209a6c1ac618aaf39b9f764647e88be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3144038c94f829d0ce9a8e593fa8e6007a5372ae7adea70315970e8b78c5c4e3440d01fb1df13d82f23b6aa9250213db9926d49e5ae52af4aec90481fa85793", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea89ab0576d1b28ff910ad2e78fccdf22ec212e5480b0cef412bf5319bcd447959c68d5eea7220e835ecf7beff6df3524408bf17f628feca17506094c0df4c88"} build_url:https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011327 build_url: 'https://queue.taskcluster.net/v1/task/OypQKKTlTgyj1Ao4ZC_kMg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/084102e6e1cf68dbfae3ea3368fa153ad6684dda757d65b2cab1bd4724bc837c98ba16e7ca79f084500bf3adb58287f598d7f74db666fa4ea35c309aef0c4a2c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/707ecdcd61f96d9bb1e4257c050ace653b7315aae9231eb23795ebbcffa7725a473e08732e0f87297ddccc89fed0c52e8209a6c1ac618aaf39b9f764647e88be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3144038c94f829d0ce9a8e593fa8e6007a5372ae7adea70315970e8b78c5c4e3440d01fb1df13d82f23b6aa9250213db9926d49e5ae52af4aec90481fa85793", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea89ab0576d1b28ff910ad2e78fccdf22ec212e5480b0cef412bf5319bcd447959c68d5eea7220e835ecf7beff6df3524408bf17f628feca17506094c0df4c88"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-31 03:18:23.534360) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 03:18:23.534723) ========= 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=3297cc2eebb5444a7688c33f000001b5-1501494692.704873-348590066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004494 ========= master_lag: 0.11 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 03:18:23.644596) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-31 03:18:23.644916) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-31 03:18:23.645249) ========= ========= Total master_lag: 0.19 =========