builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-065 starttime: 1496679116.38 results: success (0) revision: 83a7d3a213da7ea0042d806082853540d13a6fb8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 09:11:56.384921) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 09:11:56.385383) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 09:11:56.385713) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 09:11:56.398766) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 09:11:56.399067) ========= 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=523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-06-05 09:11:56-- 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% 69.5M=0s 2017-06-05 09:11:56 (69.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145172 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 09:11:56.558535) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 09:11:56.558879) ========= 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=523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017979 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 09:11:56.608430) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 09:11:56.611834) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 83a7d3a213da7ea0042d806082853540d13a6fb8 --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 83a7d3a213da7ea0042d806082853540d13a6fb8 --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=523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-06-05 09:11:56,526 truncating revision to first 12 chars 2017-06-05 09:11:56,526 Setting DEBUG logging. 2017-06-05 09:11:56,526 attempt 1/10 2017-06-05 09:11:56,526 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/83a7d3a213da?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 09:11:57,291 unpacking tar archive at: mozilla-central-83a7d3a213da/testing/mozharness/ program finished with exit code 0 elapsedTime=1.058262 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 09:11:57.685125) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 09:11:57.685440) ========= script_repo_revision: 83a7d3a213da7ea0042d806082853540d13a6fb8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 09:11:57.685820) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 09:11:57.686073) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 09:11:57.694790) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 49 secs) (at 2017-06-05 09:11:57.695076) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:11:57 INFO - MultiFileLogger online at 20170605 09:11:57 in /builds/slave/test 09:11:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 09:11:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:11:57 INFO - {'append_to_log': False, 09:11:57 INFO - 'base_work_dir': '/builds/slave/test', 09:11:57 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:11:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:11:57 INFO - 'branch': 'Firefox-Non-PGO', 09:11:57 INFO - 'buildbot_json_path': 'buildprops.json', 09:11:57 INFO - 'config_files': ('talos/linux_config.py',), 09:11:57 INFO - 'default_actions': ('clobber', 09:11:57 INFO - 'read-buildbot-config', 09:11:57 INFO - 'download-and-extract', 09:11:57 INFO - 'populate-webroot', 09:11:57 INFO - 'create-virtualenv', 09:11:57 INFO - 'install', 09:11:57 INFO - 'setup-mitmproxy', 09:11:57 INFO - 'run-tests'), 09:11:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:11:57 INFO - 'download_minidump_stackwalk': True, 09:11:57 INFO - 'download_symbols': 'ondemand', 09:11:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:11:57 INFO - 'tooltool.py': '/tools/tooltool.py', 09:11:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:11:57 INFO - '/tools/misc-python/virtualenv.py')}, 09:11:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:11:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:11:57 INFO - 'gecko_profile': False, 09:11:57 INFO - 'gecko_profile_interval': 0, 09:11:57 INFO - 'installer_path': 'installer.exe', 09:11:57 INFO - 'log_level': 'info', 09:11:57 INFO - 'log_name': 'talos', 09:11:57 INFO - 'log_to_console': True, 09:11:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:11:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:11:57 INFO - 'opt_config_files': (), 09:11:57 INFO - 'pip_index': False, 09:11:57 INFO - 'suite': 'dromaeojs-e10s', 09:11:57 INFO - 'system_bits': '32', 09:11:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:11:57 INFO - 'title': 'talos-linux64-ix-065', 09:11:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:11:57 INFO - 'use_talos_json': True, 09:11:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:11:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:11:57 INFO - 'work_dir': 'build'} 09:11:57 INFO - [mozharness: 2017-06-05 16:11:57.713712Z] Running clobber step. 09:11:57 INFO - Running pre-action listener: _resource_record_pre_action 09:11:57 INFO - Running main action method: clobber 09:11:57 INFO - rmtree: /builds/slave/test/build 09:11:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:11:58 INFO - Running post-action listener: _resource_record_post_action 09:11:58 INFO - [mozharness: 2017-06-05 16:11:58.841800Z] Finished clobber step (success) 09:11:58 INFO - [mozharness: 2017-06-05 16:11:58.841902Z] Running read-buildbot-config step. 09:11:58 INFO - Running pre-action listener: _resource_record_pre_action 09:11:58 INFO - Running main action method: read_buildbot_config 09:11:58 INFO - Using buildbot properties: 09:11:58 INFO - { 09:11:58 INFO - "project": "", 09:11:58 INFO - "product": "firefox", 09:11:58 INFO - "who": "ffxbld@noreply.mozilla.org", 09:11:58 INFO - "installer_path": "public/build/target.tar.bz2", 09:11:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 09:11:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:11:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 09:11:58 INFO - "stage_platform": "linux64", 09:11:58 INFO - "basedir": "/builds/slave/test", 09:11:58 INFO - "buildnumber": 891, 09:11:58 INFO - "platform": "ubuntu64_hw", 09:11:58 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:11:58 INFO - "slavebuilddir": "test", 09:11:58 INFO - "taskId": "Hi3fY-ZcQ7iuTMsMn4b_QQ", 09:11:58 INFO - "branch": "mozilla-central", 09:11:58 INFO - "script_repo_revision": "production", 09:11:58 INFO - "revision": "83a7d3a213da7ea0042d806082853540d13a6fb8", 09:11:58 INFO - "slavename": "talos-linux64-ix-065", 09:11:58 INFO - "repo_path": "mozilla-central" 09:11:58 INFO - } 09:11:58 INFO - Finding installer, test and symbols from parent task. 09:11:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hi3fY-ZcQ7iuTMsMn4b_QQ'}, attempt #1 09:11:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hi3fY-ZcQ7iuTMsMn4b_QQ'}, attempt #1 09:11:59 INFO - Task dependencies: N4t0oD4YRD-pSxxulKq4Qw 09:11:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw'}, attempt #1 09:12:00 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.tar.bz2 09:12:00 INFO - Set test_url: https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/test_packages.json 09:12:00 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.crashreporter-symbols.zip 09:12:00 INFO - Running post-action listener: _resource_record_post_action 09:12:00 INFO - [mozharness: 2017-06-05 16:12:00.210620Z] Finished read-buildbot-config step (success) 09:12:00 INFO - [mozharness: 2017-06-05 16:12:00.210840Z] Running download-and-extract step. 09:12:00 INFO - Running pre-action listener: _resource_record_pre_action 09:12:00 INFO - Running main action method: download_and_extract 09:12:00 INFO - mkdir: /builds/slave/test/build/tests 09:12: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')]} 09:12:00 INFO - trying https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.test_packages.json 09:12:00 INFO - Downloading https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:12:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:12:00 INFO - Downloaded 989 bytes. 09:12:00 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:12:00 INFO - Using the following test package requirements: 09:12:00 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:12:00 INFO - u'common': [u'target.common.tests.zip'], 09:12:00 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:12:00 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:12:00 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:12:00 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:12:00 INFO - u'mozbase': [u'target.common.tests.zip'], 09:12:00 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:12:00 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:12:00 INFO - u'web-platform': [u'target.common.tests.zip', 09:12:00 INFO - u'target.web-platform.tests.tar.gz'], 09:12:00 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:12:00 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:12:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.common.tests.zip 09:12:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:12:00 INFO - Fetch https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.common.tests.zip into memory 09:12:01 INFO - Content-Length response header: 65102987 09:12:01 INFO - Bytes received: 65102987 09:12:12 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:12:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.common.tests.zip 09:12:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:12:12 INFO - Fetch https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.common.tests.zip into memory 09:12:13 INFO - Content-Length response header: 65102987 09:12:13 INFO - Bytes received: 65102987 09:12:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.talos.tests.zip 09:12:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:12:26 INFO - Fetch https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.talos.tests.zip into memory 09:12:26 INFO - Content-Length response header: 14447425 09:12:26 INFO - Bytes received: 14447425 09:12:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:27 INFO - trying https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.tar.bz2 09:12:27 INFO - Downloading https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:12:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:12:28 INFO - Downloaded 58704630 bytes. 09:12:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.tar.bz2 09:12:28 INFO - mkdir: /builds/slave/test/properties 09:12:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:12:28 INFO - Writing to file /builds/slave/test/properties/build_url 09:12:28 INFO - Contents: 09:12:28 INFO - build_url:https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.tar.bz2 09:12:28 INFO - Running post-action listener: _resource_record_post_action 09:12:28 INFO - Running post-action listener: set_extra_try_arguments 09:12:28 INFO - [mozharness: 2017-06-05 16:12:28.318357Z] Finished download-and-extract step (success) 09:12:28 INFO - [mozharness: 2017-06-05 16:12:28.318455Z] Running populate-webroot step. 09:12:28 INFO - Running pre-action listener: _resource_record_pre_action 09:12:28 INFO - Running main action method: populate_webroot 09:12:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:12:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:12:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:12:28 INFO - '--tppagecycles', 09:12:28 INFO - '7'], 09:12:28 INFO - 'tests': ['tsvgm']}}, 09:12:28 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:12:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:12:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:12:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:12:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:12:28 INFO - 'talos_options': ['--disable-e10s'], 09:12:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:12:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:12:28 INFO - 'talos_options': ['--disable-e10s'], 09:12:28 INFO - 'tests': ['damp', 'tps']}, 09:12:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:12:28 INFO - 'tests': ['damp', 'tps']}, 09:12:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:12:28 INFO - 'tests': ['dromaeo_dom']}, 09:12:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:12:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:12:28 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:12:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:12:28 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:12:28 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:12:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:12:28 INFO - 'tests': ['a11yr', 09:12:28 INFO - 'ts_paint', 09:12:28 INFO - 'tpaint', 09:12:28 INFO - 'sessionrestore', 09:12:28 INFO - 'sessionrestore_no_auto_restore', 09:12:28 INFO - 'tabpaint']}, 09:12:28 INFO - 'other-e10s': {'tests': ['a11yr', 09:12:28 INFO - 'ts_paint', 09:12:28 INFO - 'tpaint', 09:12:28 INFO - 'sessionrestore', 09:12:28 INFO - 'sessionrestore_no_auto_restore', 09:12:28 INFO - 'tabpaint']}, 09:12:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:12:28 INFO - 'ts_paint', 09:12:28 INFO - 'tpaint', 09:12:28 INFO - 'sessionrestore', 09:12:28 INFO - 'sessionrestore_no_auto_restore', 09:12:28 INFO - 'tabpaint']}, 09:12:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:12:28 INFO - 'ts_paint', 09:12:28 INFO - 'tpaint', 09:12:28 INFO - 'sessionrestore', 09:12:28 INFO - 'sessionrestore_no_auto_restore', 09:12:28 INFO - 'tabpaint']}, 09:12:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:12:28 INFO - 'tests': ['a11yr', 09:12:28 INFO - 'ts_paint', 09:12:28 INFO - 'tpaint', 09:12:28 INFO - 'sessionrestore', 09:12:28 INFO - 'sessionrestore_no_auto_restore', 09:12:28 INFO - 'tabpaint']}, 09:12:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:12:28 INFO - 'tests': ['a11yr', 09:12:28 INFO - 'ts_paint', 09:12:28 INFO - 'tpaint', 09:12:28 INFO - 'sessionrestore', 09:12:28 INFO - 'sessionrestore_no_auto_restore', 09:12:28 INFO - 'tabpaint']}, 09:12:28 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:12:28 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:12:28 INFO - 'bloom_basic_ref']}, 09:12:28 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 09:12:28 INFO - 'talos_options': ['--mitmproxy', 09:12:28 INFO - 'mitmproxy-recording-1.mp', 09:12:28 INFO - '--firstNonBlankPaint'], 09:12:28 INFO - 'tests': ['Quantum_1']}, 09:12:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:12:28 INFO - 'tests': ['tsvgx', 09:12:28 INFO - 'tsvgr_opacity', 09:12:28 INFO - 'tart', 09:12:28 INFO - 'tscrollx', 09:12:28 INFO - 'cart', 09:12:28 INFO - 'tsvg_static']}, 09:12:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:12:28 INFO - 'tsvgr_opacity', 09:12:28 INFO - 'tart', 09:12:28 INFO - 'tscrollx', 09:12:28 INFO - 'cart', 09:12:28 INFO - 'tsvg_static']}, 09:12:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:12:28 INFO - 'talos_options': ['--disable-e10s'], 09:12:28 INFO - 'tests': ['tp5o']}, 09:12:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:12:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:12:28 INFO - 'talos_options': ['--disable-e10s', 09:12:28 INFO - '--xperf_path', 09:12:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:28 INFO - 'tests': ['tp5n']}, 09:12:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:12:28 INFO - 'talos_options': ['--xperf_path', 09:12:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:28 INFO - 'tests': ['tp5n']}}, 09:12:28 INFO - 'talos.zip': {'path': '', 09:12:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:12:28 INFO - Running post-action listener: _resource_record_post_action 09:12:28 INFO - [mozharness: 2017-06-05 16:12:28.332909Z] Finished populate-webroot step (success) 09:12:28 INFO - [mozharness: 2017-06-05 16:12:28.333005Z] Running create-virtualenv step. 09:12:28 INFO - Running pre-action listener: _resource_record_pre_action 09:12:28 INFO - Running main action method: create_virtualenv 09:12:28 INFO - Creating virtualenv /builds/slave/test/build/venv 09:12:28 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 09:12:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:12:28 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:12:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:12:28 INFO - Using real prefix '/usr' 09:12:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:12:28 INFO - Installing distribute.............................................................................................................................................................................................done. 09:12:30 INFO - Installing pip.................done. 09:12:30 INFO - Return code: 0 09:12:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:12:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:12:31 INFO - Reading from file tmpfile_stdout 09:12:31 INFO - Output received: 09:12:31 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) 09:12:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:12:31 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')]} 09:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:31 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')]} 09:12:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:31 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 0x13cc3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x174add0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1747840>, '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 0x1748740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1748bd0>, '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': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', '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 09:12:31 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 09:12:31 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 09:12:31 INFO - Using env: {'DISPLAY': ':0', 09:12:31 INFO - 'HOME': '/home/cltbld', 09:12:31 INFO - 'LANG': 'en_US.UTF-8', 09:12:31 INFO - 'LANGUAGE': 'en_US:en', 09:12:31 INFO - 'LOGNAME': 'cltbld', 09:12:31 INFO - 'MAIL': '/var/mail/cltbld', 09:12:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:31 INFO - 'MOZ_NO_REMOTE': '1', 09:12:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:31 INFO - 'NO_EM_RESTART': '1', 09:12:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:31 INFO - 'PWD': '/builds/slave/test', 09:12:31 INFO - 'SHELL': '/bin/bash', 09:12:31 INFO - 'SHLVL': '1', 09:12:31 INFO - 'TERM': 'linux', 09:12:31 INFO - 'TMOUT': '86400', 09:12:31 INFO - 'USER': 'cltbld', 09:12:31 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', 09:12:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:31 INFO - '_': '/tools/buildbot/bin/python'} 09:12:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:31 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 09:12:31 INFO - Cleaning up... 09:12:31 INFO - Return code: 0 09:12:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:12:31 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')]} 09:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:31 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')]} 09:12:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:31 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 0x13cc3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x174add0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1747840>, '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 0x1748740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1748bd0>, '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': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', '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 09:12:31 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 09:12:31 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 09:12:31 INFO - Using env: {'DISPLAY': ':0', 09:12:31 INFO - 'HOME': '/home/cltbld', 09:12:31 INFO - 'LANG': 'en_US.UTF-8', 09:12:31 INFO - 'LANGUAGE': 'en_US:en', 09:12:31 INFO - 'LOGNAME': 'cltbld', 09:12:31 INFO - 'MAIL': '/var/mail/cltbld', 09:12:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:31 INFO - 'MOZ_NO_REMOTE': '1', 09:12:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:31 INFO - 'NO_EM_RESTART': '1', 09:12:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:31 INFO - 'PWD': '/builds/slave/test', 09:12:31 INFO - 'SHELL': '/bin/bash', 09:12:31 INFO - 'SHLVL': '1', 09:12:31 INFO - 'TERM': 'linux', 09:12:31 INFO - 'TMOUT': '86400', 09:12:31 INFO - 'USER': 'cltbld', 09:12:31 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', 09:12:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:31 INFO - '_': '/tools/buildbot/bin/python'} 09:12:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:31 INFO - Downloading/unpacking psutil>=3.1.1 09:12:31 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 09:12:31 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 09:12:31 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 09:12:31 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 09:12:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:12:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:32 INFO - Installing collected packages: psutil 09:12:32 INFO - Running setup.py install for psutil 09:12:32 INFO - building 'psutil._psutil_linux' extension 09:12:32 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 09:12:32 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 09:12:33 INFO - building 'psutil._psutil_posix' extension 09:12:33 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 09:12:33 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 09:12:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:33 INFO - Successfully installed psutil 09:12:33 INFO - Cleaning up... 09:12:33 INFO - Return code: 0 09:12:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:12:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:33 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 0x13cc3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x174add0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1747840>, '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 0x1748740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1748bd0>, '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': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', '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 09:12:33 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 09:12:33 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 09:12:33 INFO - Using env: {'DISPLAY': ':0', 09:12:33 INFO - 'HOME': '/home/cltbld', 09:12:33 INFO - 'LANG': 'en_US.UTF-8', 09:12:33 INFO - 'LANGUAGE': 'en_US:en', 09:12:33 INFO - 'LOGNAME': 'cltbld', 09:12:33 INFO - 'MAIL': '/var/mail/cltbld', 09:12:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:33 INFO - 'MOZ_NO_REMOTE': '1', 09:12:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:33 INFO - 'NO_EM_RESTART': '1', 09:12:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:33 INFO - 'PWD': '/builds/slave/test', 09:12:33 INFO - 'SHELL': '/bin/bash', 09:12:33 INFO - 'SHLVL': '1', 09:12:33 INFO - 'TERM': 'linux', 09:12:33 INFO - 'TMOUT': '86400', 09:12:33 INFO - 'USER': 'cltbld', 09:12:33 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', 09:12:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:33 INFO - '_': '/tools/buildbot/bin/python'} 09:12:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:12:33 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 09:12:33 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 09:12:33 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 09:12:33 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 09:12:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:12:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:12:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:12:34 INFO - Installing collected packages: mozsystemmonitor 09:12:34 INFO - Running setup.py install for mozsystemmonitor 09:12:34 INFO - Successfully installed mozsystemmonitor 09:12:34 INFO - Cleaning up... 09:12:34 INFO - Return code: 0 09:12:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12: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')]} 09:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:34 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 0x13cc3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x174add0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1747840>, '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 0x1748740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1748bd0>, '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': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', '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 09:12:34 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 09:12:34 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 09:12:34 INFO - Using env: {'DISPLAY': ':0', 09:12:34 INFO - 'HOME': '/home/cltbld', 09:12:34 INFO - 'LANG': 'en_US.UTF-8', 09:12:34 INFO - 'LANGUAGE': 'en_US:en', 09:12:34 INFO - 'LOGNAME': 'cltbld', 09:12:34 INFO - 'MAIL': '/var/mail/cltbld', 09:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:34 INFO - 'MOZ_NO_REMOTE': '1', 09:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:34 INFO - 'NO_EM_RESTART': '1', 09:12:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:34 INFO - 'PWD': '/builds/slave/test', 09:12:34 INFO - 'SHELL': '/bin/bash', 09:12:34 INFO - 'SHLVL': '1', 09:12:34 INFO - 'TERM': 'linux', 09:12:34 INFO - 'TMOUT': '86400', 09:12:34 INFO - 'USER': 'cltbld', 09:12:34 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', 09:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:34 INFO - '_': '/tools/buildbot/bin/python'} 09:12:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:34 INFO - Downloading/unpacking jsonschema==2.5.1 09:12:34 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 09:12:34 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 09:12:34 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 09:12:34 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 09:12:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:12:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:12:36 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 09:12:36 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 09:12:36 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 09:12:36 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 09:12:36 INFO - Downloading functools32-3.2.3-2.tar.gz 09:12:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:12:36 INFO - warning: no files found matching '*.txt' 09:12:36 INFO - no previously-included directories found matching 'build' 09:12:36 INFO - no previously-included directories found matching 'dist' 09:12:36 INFO - no previously-included directories found matching '.git*' 09:12:36 INFO - Installing collected packages: jsonschema, functools32 09:12:36 INFO - Running setup.py install for functools32 09:12:36 INFO - warning: no files found matching '*.txt' 09:12:36 INFO - no previously-included directories found matching 'build' 09:12:36 INFO - no previously-included directories found matching 'dist' 09:12:36 INFO - no previously-included directories found matching '.git*' 09:12:36 INFO - Successfully installed jsonschema functools32 09:12:36 INFO - Cleaning up... 09:12:36 INFO - Return code: 0 09:12:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:12:36 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')]} 09:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:36 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')]} 09:12:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:36 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 0x13cc3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x174add0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1747840>, '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 0x1748740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1748bd0>, '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': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', '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 09:12:36 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 09:12:36 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 09:12:36 INFO - Using env: {'DISPLAY': ':0', 09:12:36 INFO - 'HOME': '/home/cltbld', 09:12:36 INFO - 'LANG': 'en_US.UTF-8', 09:12:36 INFO - 'LANGUAGE': 'en_US:en', 09:12:36 INFO - 'LOGNAME': 'cltbld', 09:12:36 INFO - 'MAIL': '/var/mail/cltbld', 09:12:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:36 INFO - 'MOZ_NO_REMOTE': '1', 09:12:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:36 INFO - 'NO_EM_RESTART': '1', 09:12:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:36 INFO - 'PWD': '/builds/slave/test', 09:12:36 INFO - 'SHELL': '/bin/bash', 09:12:36 INFO - 'SHLVL': '1', 09:12:36 INFO - 'TERM': 'linux', 09:12:36 INFO - 'TMOUT': '86400', 09:12:36 INFO - 'USER': 'cltbld', 09:12:36 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', 09:12:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:36 INFO - '_': '/tools/buildbot/bin/python'} 09:12:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:12:36 INFO - Cleaning up... 09:12:36 INFO - Return code: 0 09:12:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:12:36 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')]} 09:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:36 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')]} 09:12:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:36 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 0x13cc3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x174add0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1747840>, '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 0x1748740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1748bd0>, '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': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', '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 09:12:36 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 09:12:36 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 09:12:36 INFO - Using env: {'DISPLAY': ':0', 09:12:36 INFO - 'HOME': '/home/cltbld', 09:12:36 INFO - 'LANG': 'en_US.UTF-8', 09:12:36 INFO - 'LANGUAGE': 'en_US:en', 09:12:36 INFO - 'LOGNAME': 'cltbld', 09:12:36 INFO - 'MAIL': '/var/mail/cltbld', 09:12:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:36 INFO - 'MOZ_NO_REMOTE': '1', 09:12:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:36 INFO - 'NO_EM_RESTART': '1', 09:12:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:36 INFO - 'PWD': '/builds/slave/test', 09:12:36 INFO - 'SHELL': '/bin/bash', 09:12:36 INFO - 'SHLVL': '1', 09:12:36 INFO - 'TERM': 'linux', 09:12:36 INFO - 'TMOUT': '86400', 09:12:36 INFO - 'USER': 'cltbld', 09:12:36 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', 09:12:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:36 INFO - '_': '/tools/buildbot/bin/python'} 09:12:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:36 INFO - Downloading/unpacking blobuploader==1.2.4 09:12:36 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 09:12:36 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 09:12:36 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 09:12:36 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 09:12:37 INFO - Downloading blobuploader-1.2.4.tar.gz 09:12:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:12:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:12:37 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 09:12:37 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 09:12:37 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 09:12:37 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 09:12:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:12:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:12:38 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 09:12:38 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 09:12:38 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 09:12:38 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 09:12:38 INFO - Downloading docopt-0.6.1.tar.gz 09:12:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:12:38 INFO - Installing collected packages: blobuploader, requests, docopt 09:12:38 INFO - Running setup.py install for blobuploader 09:12:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:12:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:12:38 INFO - Running setup.py install for requests 09:12:38 INFO - Running setup.py install for docopt 09:12:39 INFO - Successfully installed blobuploader requests docopt 09:12:39 INFO - Cleaning up... 09:12:39 INFO - Return code: 0 09:12:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:39 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')]} 09:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:39 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')]} 09:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:39 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 0x13cc3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x174add0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1747840>, '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 0x1748740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1748bd0>, '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': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', '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 09:12:39 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 09:12:39 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 09:12:39 INFO - Using env: {'DISPLAY': ':0', 09:12:39 INFO - 'HOME': '/home/cltbld', 09:12:39 INFO - 'LANG': 'en_US.UTF-8', 09:12:39 INFO - 'LANGUAGE': 'en_US:en', 09:12:39 INFO - 'LOGNAME': 'cltbld', 09:12:39 INFO - 'MAIL': '/var/mail/cltbld', 09:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:39 INFO - 'MOZ_NO_REMOTE': '1', 09:12:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:39 INFO - 'NO_EM_RESTART': '1', 09:12:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:39 INFO - 'PWD': '/builds/slave/test', 09:12:39 INFO - 'SHELL': '/bin/bash', 09:12:39 INFO - 'SHLVL': '1', 09:12:39 INFO - 'TERM': 'linux', 09:12:39 INFO - 'TMOUT': '86400', 09:12:39 INFO - 'USER': 'cltbld', 09:12:39 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', 09:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:39 INFO - '_': '/tools/buildbot/bin/python'} 09:12:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:12:39 INFO - Running setup.py (path:/tmp/pip-NQyYKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:12:39 INFO - Running setup.py (path:/tmp/pip-iBeoST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:12:39 INFO - Running setup.py (path:/tmp/pip-MLfXY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:12:39 INFO - Running setup.py (path:/tmp/pip-STVEB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:12:39 INFO - Running setup.py (path:/tmp/pip-ox_6iT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:12:39 INFO - Running setup.py (path:/tmp/pip-Tto2ly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:12:39 INFO - Running setup.py (path:/tmp/pip-fXo997-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:12:39 INFO - Running setup.py (path:/tmp/pip-xiPmFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:12:39 INFO - Running setup.py (path:/tmp/pip-keJ1VM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:12:39 INFO - Running setup.py (path:/tmp/pip-hysxXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:12:40 INFO - Running setup.py (path:/tmp/pip-wwKi1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:12:40 INFO - Running setup.py (path:/tmp/pip-Zq6ZOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:12:40 INFO - Running setup.py (path:/tmp/pip-Q0_C8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:12:40 INFO - Running setup.py (path:/tmp/pip-dr6jRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:40 INFO - Running setup.py (path:/tmp/pip-xSkLTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:12:40 INFO - Running setup.py (path:/tmp/pip-ZnuRtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:12:40 INFO - Running setup.py (path:/tmp/pip-eS3QJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:12:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:12:40 INFO - Running setup.py install for manifestparser 09:12:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:12:40 INFO - Running setup.py install for mozcrash 09:12:40 INFO - Running setup.py install for mozdebug 09:12:40 INFO - Running setup.py install for mozdevice 09:12:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:12:40 INFO - Running setup.py install for mozfile 09:12:41 INFO - Running setup.py install for mozhttpd 09:12:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:12:41 INFO - Running setup.py install for mozinfo 09:12:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:12:41 INFO - Running setup.py install for mozInstall 09:12:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:12:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:12:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:12:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:12:41 INFO - Running setup.py install for mozleak 09:12:41 INFO - Running setup.py install for mozlog 09:12:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:12:41 INFO - Running setup.py install for moznetwork 09:12:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:12:41 INFO - Running setup.py install for mozprocess 09:12:41 INFO - Running setup.py install for mozprofile 09:12:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:12:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:12:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:12:41 INFO - Running setup.py install for mozrunner 09:12:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:12:42 INFO - Running setup.py install for mozscreenshot 09:12:42 INFO - Running setup.py install for moztest 09:12:42 INFO - Running setup.py install for mozversion 09:12:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:12:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:12:42 INFO - Cleaning up... 09:12:42 INFO - Return code: 0 09:12:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:42 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')]} 09:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:42 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')]} 09:12:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:42 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 0x13cc3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x174add0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1747840>, '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 0x1748740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1748bd0>, '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': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', '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 09:12:42 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 09:12:42 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 09:12:42 INFO - Using env: {'DISPLAY': ':0', 09:12:42 INFO - 'HOME': '/home/cltbld', 09:12:42 INFO - 'LANG': 'en_US.UTF-8', 09:12:42 INFO - 'LANGUAGE': 'en_US:en', 09:12:42 INFO - 'LOGNAME': 'cltbld', 09:12:42 INFO - 'MAIL': '/var/mail/cltbld', 09:12:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:42 INFO - 'MOZ_NO_REMOTE': '1', 09:12:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:42 INFO - 'NO_EM_RESTART': '1', 09:12:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:42 INFO - 'PWD': '/builds/slave/test', 09:12:42 INFO - 'SHELL': '/bin/bash', 09:12:42 INFO - 'SHLVL': '1', 09:12:42 INFO - 'TERM': 'linux', 09:12:42 INFO - 'TMOUT': '86400', 09:12:42 INFO - 'USER': 'cltbld', 09:12:42 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', 09:12:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:42 INFO - '_': '/tools/buildbot/bin/python'} 09:12:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:12:42 INFO - Running setup.py (path:/tmp/pip-0OCDZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:12:42 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)) 09:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:12:42 INFO - Running setup.py (path:/tmp/pip-3lt5iI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:12:42 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)) 09:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:12:42 INFO - Running setup.py (path:/tmp/pip-8KfIt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:12:42 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)) 09:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:12:42 INFO - Running setup.py (path:/tmp/pip-dp01ZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:12:42 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)) 09:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:12:42 INFO - Running setup.py (path:/tmp/pip-0Rn8aZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:12:42 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)) 09:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:12:42 INFO - Running setup.py (path:/tmp/pip-y0bRxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:12:43 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)) 09:12:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:12:43 INFO - Running setup.py (path:/tmp/pip-sJGymY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:12:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 09:12:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:12:43 INFO - Running setup.py (path:/tmp/pip-kK4Gg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:12:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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)) 09:12:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:12:43 INFO - Running setup.py (path:/tmp/pip-IFxHP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:12:43 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)) 09:12:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:12:43 INFO - Running setup.py (path:/tmp/pip-JWQZb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:12:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 09:12:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:12:43 INFO - Running setup.py (path:/tmp/pip-3YhHNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:12:43 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)) 09:12:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:12:43 INFO - Running setup.py (path:/tmp/pip-FjgF6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:12:43 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)) 09:12:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:12:43 INFO - Running setup.py (path:/tmp/pip-anN5za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:12:43 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)) 09:12:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:12:43 INFO - Running setup.py (path:/tmp/pip-Xr7Gcl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:12:43 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)) 09:12:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:43 INFO - Running setup.py (path:/tmp/pip-EqKf6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:43 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)) 09:12:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:12:43 INFO - Running setup.py (path:/tmp/pip-7PeEUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:12:43 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)) 09:12:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:12:43 INFO - Running setup.py (path:/tmp/pip-faywvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:12:43 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)) 09:12:43 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)) 09:12:43 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)) 09:12:43 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)) 09:12:43 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)) 09:12:43 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)) 09:12:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:12:43 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 09:12:43 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 09:12:43 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 09:12:43 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 09:12:44 INFO - Downloading blessings-1.6.tar.gz 09:12:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:12:44 INFO - Installing collected packages: blessings 09:12:44 INFO - Running setup.py install for blessings 09:12:45 INFO - Successfully installed blessings 09:12:45 INFO - Cleaning up... 09:12:45 INFO - Return code: 0 09:12:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:12:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:12:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:12:45 INFO - Reading from file tmpfile_stdout 09:12:45 INFO - Current package versions: 09:12:45 INFO - argparse == 1.2.1 09:12:45 INFO - blessings == 1.6 09:12:45 INFO - blobuploader == 1.2.4 09:12:45 INFO - docopt == 0.6.1 09:12:45 INFO - functools32 == 3.2.3-2 09:12:45 INFO - jsonschema == 2.5.1 09:12:45 INFO - manifestparser == 1.1 09:12:45 INFO - mozInstall == 1.12 09:12:45 INFO - mozcrash == 1.0 09:12:45 INFO - mozdebug == 0.1 09:12:45 INFO - mozdevice == 0.51 09:12:45 INFO - mozfile == 1.2 09:12:45 INFO - mozhttpd == 0.7 09:12:45 INFO - mozinfo == 0.9 09:12:45 INFO - mozleak == 0.1 09:12:45 INFO - mozlog == 3.4 09:12:45 INFO - moznetwork == 0.27 09:12:45 INFO - mozprocess == 0.25 09:12:45 INFO - mozprofile == 0.28 09:12:45 INFO - mozrunner == 6.13 09:12:45 INFO - mozscreenshot == 0.1 09:12:45 INFO - mozsystemmonitor == 0.3 09:12:45 INFO - moztest == 0.8 09:12:45 INFO - mozversion == 1.4 09:12:45 INFO - psutil == 3.1.1 09:12:45 INFO - requests == 1.2.3 09:12:45 INFO - wsgiref == 0.1.2 09:12:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:45 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')]} 09:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:45 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')]} 09:12:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:45 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 0x13cc3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x174add0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1747840>, '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 0x1748740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1748bd0>, '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': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', '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 09:12:45 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 09:12:45 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 09:12:45 INFO - Using env: {'DISPLAY': ':0', 09:12:45 INFO - 'HOME': '/home/cltbld', 09:12:45 INFO - 'LANG': 'en_US.UTF-8', 09:12:45 INFO - 'LANGUAGE': 'en_US:en', 09:12:45 INFO - 'LOGNAME': 'cltbld', 09:12:45 INFO - 'MAIL': '/var/mail/cltbld', 09:12:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:45 INFO - 'MOZ_NO_REMOTE': '1', 09:12:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:45 INFO - 'NO_EM_RESTART': '1', 09:12:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:45 INFO - 'PWD': '/builds/slave/test', 09:12:45 INFO - 'SHELL': '/bin/bash', 09:12:45 INFO - 'SHLVL': '1', 09:12:45 INFO - 'TERM': 'linux', 09:12:45 INFO - 'TMOUT': '86400', 09:12:45 INFO - 'USER': 'cltbld', 09:12:45 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', 09:12:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:45 INFO - '_': '/tools/buildbot/bin/python'} 09:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:45 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)) 09:12:45 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)) 09:12:45 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)) 09:12:45 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)) 09:12:45 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)) 09:12:45 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)) 09:12:45 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)) 09:12:45 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)) 09:12:45 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)) 09:12:45 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:12:45 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 09:12:45 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 09:12:45 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 09:12:45 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 09:12:46 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:12:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:12:46 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)) 09:12:46 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)) 09:12:46 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)) 09:12:46 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)) 09:12:46 INFO - Installing collected packages: marionette-driver 09:12:46 INFO - Running setup.py install for marionette-driver 09:12:46 INFO - Successfully installed marionette-driver 09:12:46 INFO - Cleaning up... 09:12:46 INFO - Return code: 0 09:12:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:12:46 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')]} 09:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:46 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')]} 09:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:46 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 0x13cc3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x174add0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1747840>, '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 0x1748740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1748bd0>, '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': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', '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 09:12:46 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 09:12:46 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 09:12:46 INFO - Using env: {'DISPLAY': ':0', 09:12:46 INFO - 'HOME': '/home/cltbld', 09:12:46 INFO - 'LANG': 'en_US.UTF-8', 09:12:46 INFO - 'LANGUAGE': 'en_US:en', 09:12:46 INFO - 'LOGNAME': 'cltbld', 09:12:46 INFO - 'MAIL': '/var/mail/cltbld', 09:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:46 INFO - 'MOZ_NO_REMOTE': '1', 09:12:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:46 INFO - 'NO_EM_RESTART': '1', 09:12:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:46 INFO - 'PWD': '/builds/slave/test', 09:12:46 INFO - 'SHELL': '/bin/bash', 09:12:46 INFO - 'SHLVL': '1', 09:12:46 INFO - 'TERM': 'linux', 09:12:46 INFO - 'TMOUT': '86400', 09:12:46 INFO - 'USER': 'cltbld', 09:12:46 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', 09:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:46 INFO - '_': '/tools/buildbot/bin/python'} 09:12:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:12:47 INFO - Cleaning up... 09:12:47 INFO - Return code: 0 09:12:47 INFO - Running post-action listener: _resource_record_post_action 09:12:47 INFO - Running post-action listener: _start_resource_monitoring 09:12:47 INFO - Starting resource monitoring. 09:12:47 INFO - [mozharness: 2017-06-05 16:12:47.125347Z] Finished create-virtualenv step (success) 09:12:47 INFO - [mozharness: 2017-06-05 16:12:47.125851Z] Running install step. 09:12:47 INFO - Running pre-action listener: _resource_record_pre_action 09:12:47 INFO - Running main action method: install 09:12:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:12:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:12:47 INFO - Reading from file tmpfile_stdout 09:12:47 INFO - Detecting whether we're running mozinstall >=1.0... 09:12:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:12:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:12:47 INFO - Reading from file tmpfile_stdout 09:12:47 INFO - Output received: 09:12:47 INFO - Usage: mozinstall [options] installer 09:12:47 INFO - Options: 09:12:47 INFO - -h, --help show this help message and exit 09:12:47 INFO - -d DEST, --destination=DEST 09:12:47 INFO - Directory to install application into. [default: 09:12:47 INFO - "/builds/slave/test"] 09:12:47 INFO - --app=APP Application being installed. [default: firefox] 09:12:47 INFO - mkdir: /builds/slave/test/build/application 09:12:47 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'] 09:12:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:13:04 INFO - Reading from file tmpfile_stdout 09:13:04 INFO - Output received: 09:13:04 INFO - /builds/slave/test/build/application/firefox/firefox 09:13:04 INFO - Running post-action listener: _resource_record_post_action 09:13:04 INFO - [mozharness: 2017-06-05 16:13:04.362739Z] Finished install step (success) 09:13:04 INFO - [mozharness: 2017-06-05 16:13:04.363017Z] Running setup-mitmproxy step. 09:13:04 INFO - Running pre-action listener: _resource_record_pre_action 09:13:04 INFO - Running main action method: setup_mitmproxy 09:13:04 INFO - Skipping: mitmproxy is not required 09:13:04 INFO - Running post-action listener: _resource_record_post_action 09:13:04 INFO - [mozharness: 2017-06-05 16:13:04.364155Z] Finished setup-mitmproxy step (success) 09:13:04 INFO - [mozharness: 2017-06-05 16:13:04.364378Z] Running run-tests step. 09:13:04 INFO - Running pre-action listener: _resource_record_pre_action 09:13:04 INFO - Running main action method: run_tests 09:13:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:13:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:13:04 INFO - Python 2.7.3 09:13:04 INFO - Return code: 0 09:13:04 INFO - grabbing minidump binary from tooltool 09:13: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')]} 09:13:04 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 0x1747840>, '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 0x1748740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1748bd0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:13:04 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 09:13:04 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 09:13:04 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 09:13:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:13:04 INFO - Return code: 0 09:13:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:13:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:13:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:13:04 INFO - ENV: RUST_BACKTRACE is now 1 09:13:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:13:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:13:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 09:13:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-065 --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 09:13:04 INFO - Using env: {'DISPLAY': ':0', 09:13:04 INFO - 'HOME': '/home/cltbld', 09:13:04 INFO - 'LANG': 'en_US.UTF-8', 09:13:04 INFO - 'LANGUAGE': 'en_US:en', 09:13:04 INFO - 'LOGNAME': 'cltbld', 09:13:04 INFO - 'MAIL': '/var/mail/cltbld', 09:13:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:13:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:13:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:04 INFO - 'MOZ_NO_REMOTE': '1', 09:13:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:13:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:04 INFO - 'NO_EM_RESTART': '1', 09:13:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:04 INFO - 'PWD': '/builds/slave/test', 09:13:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:13:04 INFO - 'RUST_BACKTRACE': '1', 09:13:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:13:04 INFO - 'SHELL': '/bin/bash', 09:13:04 INFO - 'SHLVL': '1', 09:13:04 INFO - 'TERM': 'linux', 09:13:04 INFO - 'TMOUT': '86400', 09:13:04 INFO - 'USER': 'cltbld', 09:13:04 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044', 09:13:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:04 INFO - '_': '/tools/buildbot/bin/python'} 09:13:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 09:13:04 INFO - mozversion application_buildid: 20170605153956 09:13:04 INFO - mozversion application_changeset: 83a7d3a213da7ea0042d806082853540d13a6fb8 09:13:04 INFO - mozversion application_display_name: Nightly 09:13:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:13:04 INFO - mozversion application_name: Firefox 09:13:04 INFO - mozversion application_remotingname: firefox 09:13:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:13:04 INFO - mozversion application_vendor: Mozilla 09:13:04 INFO - mozversion application_version: 55.0a1 09:13:04 INFO - mozversion platform_buildid: 20170605153956 09:13:04 INFO - mozversion platform_changeset: 83a7d3a213da7ea0042d806082853540d13a6fb8 09:13:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:13:04 INFO - mozversion platform_version: 55.0a1 09:13:04 INFO - using testdate: 1496679184 09:13:04 INFO - actual date: 1496679184 09:13:04 INFO - starting webserver on 'localhost:34757' 09:13:04 INFO - SUITE-START | Running 2 tests 09:13:04 INFO - TEST-START | dromaeo_css 09:13:04 INFO - operating with platform_type : linux_ 09:13:04 INFO - Initialising browser for dromaeo_css test... 09:13:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34757/getInfo.html -profile /tmp/tmpHQtcul/profile 09:13:04 INFO - TEST-INFO | started process 4873 (/builds/slave/test/build/application/firefox/firefox http://localhost:34757/getInfo.html) 09:13:39 INFO - TEST-INFO | 4873: killed by SIGTERM 09:13:39 INFO - Browser initialized. 09:13:39 INFO - Running cycle 1/1 for dromaeo_css test... 09:13:39 INFO - TEST-INFO | started process 5077 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHQtcul/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:51 INFO - PID 5077 | RSS: Main: 187604992 09:13:51 INFO - PID 5077 | 09:16:52 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:34757/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34757/tests/dromaeo/cssquery-ext.html) 09:16:53 INFO - PID 5077 | RSS: Main: 182112256 09:16:53 INFO - PID 5077 | 09:19:55 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:34757/tests/dromaeo/cssquery-ext.html (next: http://localhost:34757/tests/dromaeo/cssquery-jquery.html) 09:19:56 INFO - PID 5077 | RSS: Main: 187097088 09:19:56 INFO - PID 5077 | 09:22:58 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:34757/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34757/tests/dromaeo/cssquery-mootools.html) 09:22:58 INFO - PID 5077 | RSS: Main: 184651776 09:22:58 INFO - PID 5077 | 09:26:00 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:34757/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34757/tests/dromaeo/cssquery-prototype.html) 09:26:01 INFO - PID 5077 | RSS: Main: 190427136 09:26:01 INFO - PID 5077 | 09:29:03 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:34757/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34757/tests/dromaeo/cssquery-yui.html) 09:29:04 INFO - PID 5077 | RSS: Main: 187944960 09:29:04 INFO - PID 5077 | 09:32:06 INFO - PID 5077 | Cycle 1(1): loaded http://localhost:34757/tests/dromaeo/cssquery-yui.html (next: http://localhost:34757/tests/dromaeo/cssquery-dojo.html) 09:32:06 INFO - PID 5077 | RSS: Main: 187330560 09:32:06 INFO - PID 5077 | 09:32:06 INFO - PID 5077 | __start_tp_report 09:32:06 INFO - PID 5077 | _x_x_mozilla_page_load 09:32:06 INFO - PID 5077 | _x_x_mozilla_page_load_details 09:32:06 INFO - PID 5077 | |i|pagename|runs| 09:32:06 INFO - PID 5077 | |0;dojo.html;3099.7520198384127;3110.346827166295;3136.529520571914;3150.3541773936345;3153.1644114309697;5908.615939963899;5962.373950735176;6010.819675409739;6019.578629495937;6032.065029920358;5943.4056594340545;5985.8204253872345;6027.336992930774;6039.3960603939695;6130.938690613092;5462.590305727066;5557.5831812613915;5571.331440227178;5613.242212301348;5664.688442135681;5591.245181900432;5649.067903795869;5667.688277144755;5670.659760414368;5682.034054210794;5432.75552600134;5500.1749737539285;5532.30846144232;5585.692786896699;5628.127640215773;5568.972155139239;5602.635828671164;5609;5633.183188437653;5657.321121465409;5555.416681248451;5573.27547418837;5573.554115670737;5580.302462192226;5586.944130558683;5849.590528662953;5914.8817023659285;5936.881262374728;6022.247219097613;6036.064410016411;6321.336259692758;6436.839079023015;6462.515311351667;6489.286178520359;6498.285188629247;6553.279139294674;6563.409293163638;6580.24327202373;6584.176977877765;6589.011648252753;5935.436133567304;6023.036314189796;6062.1209924561135;6085.9391406085815;6089.604175728564;6574.145361103026;6629.403353698191;6655.134832471747;6672.799816005528;6681.398674119256;6094.542909281821;6212.1303017577575;6313.779017734358;6321.462675672527;6328.746850125944;6490.350964903566;6509.02364645307;6515.022746587955;6518.348165183444;6530.2816690164045;6299.748010079546;6462.806115816439;6489.805305840832;6501;6510.283868774431;6238.438540531374;6324.209473815773;6344.111824344595;6372.426481616584;6387.393197646216;5272.525472707474;5276.551493123051;5282.577393808486;5283.6037297202865;5312.1766126250495;4500.167469018242;4502.5047244803045;4516.07420478801;4541.500434952219;4554.316852472156;5565.526930210977;5634.774609015593;5635.267415236075;5724.685142317213;5740.885182296331;6470.223573171155;6474.1907261592305;6476.546641735127;6483;6498.740050397932;5658.434156584309;5685.431456854364;5689.943100569024;5691.146328050742;5740.42595740431;5591.133374326986;5665.348484924204;5714.7428365723645;5735.8852822944145;5754.712264386764;6163.075538669146;6237.282712488122;6249.312575616678;6256.906146407899;6275.058741188858;6521.67391630426;6534.248561415308;6540.37866402691;6557.508186885907;6564.146660934048;6143.539234557516;6305.1172835803945;6311.463525600376;6321.747130114744;6339.809805705836;6225.470834979077;6247.0629405589525;6348.396902294275;6349.6507692077375;6385.87228255423;6517.771877984248;6550.705218265094;6576.309487503743;6585.868282634225;6604.339566043357;6130.969345153424;6148.754049837957;6223.844403889758;6357.93642063592;6387.297397286387;6290.905636415366;6292.528060395397;6312.43188113072;6328.778492752732;6337.524685648503;6208.751649933952;6248.812535623939;6269.655168965751;6269.780557680459;6271.3728627136925;6151.815445536641;6252.093446450148;6358.300586935526;6370.77702280418;6385.584936979268;6115.240341084353;6222.439980401786;6224;6229.283632382153;6238.625682459067 09:32:06 INFO - PID 5077 | |1;ext.html;660.3924389561603;720.4092644031895;736.889466580013;1139.6125317392084;1147.8852114788517;35273;35301;35325.82337088314;35364.823175884114;35374.64625353752;37564;38041.04897377567;38047;38056.80971595145;38141.80929095358;7207.891881621754;7318.451116166296;7345.926540734605;7474.925250747505;7482.3639990600595;7325.560466372035;7398.482106252564;7404.370628496585;7408.888866667005;7410.29602187794;7367.631618419085;7410.777676669682;7416.888746668804;7433.665485053159;7438.628068596576;7342.596157211351;7382.926170738305;7388.889166662504;7403.074615673041;7403.481756277063;7264.128304603429;7305.89041164383;7308.342249197544;7314.634268286564;7339.816504587428;35286.82356588226;35422.64577354219;35537.28925421503;35628.64371356279;35761.64238357635;3477.739169562293;3519.1554027033585;3532.116970757311;3534.2224710563637;3556.1465248340214;27862.860685696443;27969.160925172277;28044.579331310048;28061.298467538272;28134.577981330298;3590.4973303737497;3598.5501812273465;3632.1827588792576;3634.945475817865;3643.2167084076787;163716;163817;164034;164903;171796;41572.79213603913;41576.1684766303;41634.791826041284;41651.37522937158;41671.7916410416;43408.3488747669;43494;44729;45124.77437612791;45210.32184517235;14959.775603365959;15195.240237988057;15344.156071415957;15345.232738363036;15357.923210383877;12296.569620063256;12384.938075309567;12386.071044671686;12407.193532420364;12479.937600312122;13954.720905582035;13999;14064.71870562583;14075.155490670593;14120.646983825487;5598.776048958078;5627.155926611041;5696.715164241771;5736.110902810071;5764.654120752768;950.805084957582;977.9070988256104;980.0839359053034;980.8038392321564;987.3532835992437;22066;22465;23023.42441438944;23682.88158559196;23737.169199078297;1061.2624226162743;1064.5050051725984;1069.823479125951;1076.4563895232857;1113.1039513191959;11992.160548761678;12055.819162712567;12152.939235303767;12609.369531523387;12622.179558328678;214730;215039;216025;216595;216904;47596.28605570919;48608;48727.756361217966;48750.51249487531;48799;1361.918284902909;1450.2893582144673;1451.2453524167586;1458.7155504676698;1466.5893549806071;20922.790772092085;21220.575588487834;22947.6557851629;23196.18813341525;23402.18092366759;34923;34927.91874112698;35150.64849351474;35250.64749352474;35288.47067293942;182539;182692;182932;183238;183447;32048.5192722114;32574.674253257162;32668.34663306673;32774.50838237381;32776.50835237426;38716;39108.21783564256;39359.016024599616;39400;39515.40726889156;28277.293067673472;28953.697083630865;29023.564646529896;29166.708332916398;30675;28125.578116327863;28497.857510713144;28940.7105928938;30227.244318892204;30528.08415747531;32580.674193258714;32844.1788955278;33346.499802503466;33394.4990825133;33467;27707.86013004364;29126.27184320409;29160.854195728887;29338.55992160162;30159.698403016566;21523.246686365903;24135.51728965446;24169.51660966806;24313.75686243115;24655.38361540976 09:32:06 INFO - PID 5077 | |2;jquery.html;418.43511259799254;427.5803894762868;430.3437258181272;437.6170850505808;443.9600435960765;19140;20414.591708165826;20424.59150816983;20520.38438846837;22694.659580106312;22807.31578052661;23356.5328693426;23363.261878099464;23368.01371883392;23417.882910585467;5775.422457754223;5853.5317174625925;5877.8824423511505;5894.174815525808;5914.585978981473;5801.767929282844;5825.417458254162;5840.182374467578;5844.298684157886;5852.092925596518;5812.767489300444;5814.825555233349;6021.397860213965;6055.273367195942;6071.241094863142;5969.432903874147;5998.430149135826;6000.159977603139;6015.578909476339;6020.4581587656885;5419.295491586108;5423.457654234545;5431.646942948735;5441.211024401439;5443.1835224716215;25608.23175304744;25761.227163185133;25918.74081259201;26016.479670406487;26019.73980260211;1818.681730697136;1889.6409682160483;1899.838513726335;1907.2561700936647;1907.895065771382;16852.325906963706;17023;17088.743668844978;17158.48524544266;17171;2014.2043892662316;2034.918603255868;2070.3685375960326;2080.126346934291;2084.27050532314;111403.44298278537;112541;114285.42857285743;121118;121191.36425907238;11375.601853935239;11736.647900562997;11787.88212117885;11999.500187429714;12059.638210853689;14901.478448254258;15079;15096.16971066602;15111.924440377728;15161.620959476102;8442.07137214906;8793.736187914374;8859.955700221457;8902.955485222532;9130.360874738837;8691.304695624334;8786.472811631322;8821.38250322484;8883.466991980502;8937.73186804397;7519.8496030079095;7530.548167109991;7642.3504002159325;7743.303102720783;7823.491473054347;1942.7960064193055;1953.2675246782458;1957.2268953763185;1968.8424926005885;2157.2341818654154;902.8871391076116;909.4906852162678;925.5881132895931;958.0706714486937;971.1939090554822;17956.820431795775;18363.816361836478;18396.724049139273;18430.17064232113;18691.635060060846;953.5137080089204;957.4303289542711;972.3145182646359;973.9756506087405;1003.6738060130486;9995.850062249072;10104.898951010542;10121.746956326004;10506.264561480622;10635.787284254271;440898;442505;443445;443450;457616;94893.52553237327;96364;96829;97646;104975;1152.4929031226234;1195.3126952002633;1227.4844565282604;1233.3586535001568;1234.9567765128177;18587;19326.4202073938;20152.29466968649;21480.462988425414;21577.892110539975;47663;49354.506454936425;50116.749416252685;50158.24762628491;50645;60179;60421.395786041576;61134.694326528086;61342.386576135454;61676.69161654163;10314.690559283234;10318.48407579633;10333.793324133325;10356.171506279328;10453.013927353779;17786.37747678825;17977.370792022215;18111.909440452713;18127.184276707318;18197.818021819614;14287.357068931715;14491.130532168103;15150.54548363551;15235.619109522351;15274.618134546725;12871.356432178167;12903.225806451643;12976.480940762263;13278.53525126616;13476.460941562227;15196.392144314104;15221.543353699406;15322.616934576725;15324.616884577974;15327.157006364758;10170.79658406813;10398.116160126474;10462.581496740046;10488.10851077667;10490.31812932157;14230.644233894236;14413.495527656481;14473.710525789636;14480.275986200859;14583.85416145825 09:32:06 INFO - PID 5077 | |3;mootools.html;1309.5921883974727;1360.9659758506043;1364.5633397312863;1364.6588352911779;1370.7738223193173;10820.08029317508;11070.889291107107;11290.717732056692;11330.603428879991;11531.942340288288;11086.16853735969;11258.493367798448;11284.717882052953;11344.546218151265;11354.091672666187;2321.5124823787023;2367.0176876596192;2367.6566897799808;2376.92869213923;2384.9761502384927;2248.325502349297;2300.620397634388;2302.26327575176;2309.1225334373;2312.6646636237733;2273.8863056847176;2293.86236825791;2300.4363930836967;2301.827362947777;2302.1942320187886;2181.2801775414077;2182.378022263655;2204.581129585382;2215.5901158285687;2219.2787344113226;1904.2002359009111;1990.5421752996747;2200.1859312054644;2210.8784016879067;2218;10879.292845965067;10945.069669078139;10949.507272172772;10953.288036277696;10974.890251097546;6044.879102417971;6309.968450157719;6314.4001319875;6318.71565779541;6319.5576309658345;3548.361294966905;3720.795356255405;3743.5507739071486;3809.485719427897;3812.275667623143;1228.5822820241071;1239.070696977267;1250.3873102179957;1268.657462485133;1276.5149243287585;16056.919715401466;16201.756973645404;16285.755713664304;16303;16316.673666526722;5062.797488100435;5102.668326558762;5222.817201397933;5239.607029472729;5240.323096609049;11921.34432606215;11961.222520536312;11999.940000299943;12071.879281207252;12135.150539462153;5744.138379243063;6018.037114061729;6091.451769340781;6106.114613381035;6174.8147555573405;5716.5998380113;5726.4273572642405;5739.368669446358;5746.166805813134;5758.510526605285;3933.6263055009726;3984.4820173377275;4176.352665336817;4195.349720793282;4207.431996680471;1179.4102948525738;1186.1815347410259;1205.5057426455112;1205.8372119763894;1213.8725433829497;874.1127755328347;882.2544949517647;891.6210610490516;902.7427183252872;927.823713494434;6603.834904127435;7036.753713620101;7076.823079423056;7280.453965952574;7477.626118694044;929.8377028714108;969.7381706939087;970.1850445625709;978.8384916488842;979.6962941488162;4642.605378542862;4651.09303685778;4792.7363994980615;4860.1008813368935;4913.115639184981;13637.318134093255;13899.374528146258;14153;14162.221077840612;14193.574192774233;13645.522406715718;14115.78826317606;14526.20105894186;14554;14588.48940287106;8890.155435233642;8998.190162885241;9029.187373136318;9112.316576256808;9137.360384773003;5949.100820667813;6728.327167283428;6819.488538359544;6827.146606674166;6953.79138625842;12234.143609947218;12336.938315308365;12377.504899803907;12415.62753117408;12611.43248553799;12487.06347023995;12510.687232819253;12622.684432889251;12703.555375561811;12859.742805143658;9436.292278079034;9646.083622055894;9696.418214907128;9703.417794932328;9724;10143;10517.211209159497;10587.89412105869;10607.469626518798;10671.57313707443;8657.52383618907;8674.175953284428;8688.348373872112;8739.694110706096;8888.64445422176;8164.5917704115745;8166.714165004197;8207.466514676527;8209.261166494805;8215.835683286181;7964.561949092856;7983.321417679563;8087.555184464911;8153.1846815317995;8171.223733745284;6281.811545653638;6314.652694101868;6316.11574379578;6380.553361264667;6647.5014373921185;6460.903086453801;6469;6486.772962946275;6706.530542861953;6723.26044135135 09:32:06 INFO - PID 5077 | |4;prototype.html;400.27350359098233;401.6746435387337;405.7462441057133;406.8006676728404;409.0979390444069;6232.875342493147;6256.436920677138;6266.749330026793;6295.559310848237;6309.747610095596;5866.941330586693;6065.817165652699;6066.4236897494775;6077.696115194245;6081.209442772433;4754.096721622898;4754.619630429557;4765.285207218928;4781.8087276508895;4813.302071199674;4754.833580824672;4761.880962971479;4766.451858036336;4773.90452190956;4840.733759643218;4714.528547145275;4723.929141062887;4725.881852953669;4731.597814185799;4757.381540399743;4738.5972192363515;4751.429828420594;4756.286557016475;4792.9760351198365;4796.640251981081;4191.958080419217;4198.412222288852;4198.412222288882;4201.621854033152;4203.306454435045;6046.637201767908;6054.606450580698;6055.364186760415;6072.240969878765;6085.6348619083;5432.103702889019;5440.755166017539;5449.782008719647;5455.727213639342;5489.64317319373;6490.156279683611;6504.869902601921;6512.251091124534;6520.011396524105;6525.738970441176;4543.7728113594185;4548.815828919736;4555.316702494619;4559.863204103882;4572.611328037121;6507.609543427409;6713.932860671429;6801.557898736616;6860.5197636165485;6877.140357455318;4364.170807546556;4586.4496260448295;4594.770261486912;4626.976865115653;4642.605378542862;4639.466461356919;4659.487456379796;4675.485696573374;4687.086018226481;4700.213918710868;5208.427073021964;5389.56883449331;5394.217838413408;5398.73006349681;5400.135978243462;6463.4506066983895;6487.448566871869;6497.122888410004;6510.18622672166;6526.771562995273;1617.4015614222812;1624.764409160665;1628.0882705684855;1632.7877375941052;1635.7219272723428;1052.6000119954522;1081.4484612847505;1100.4882729530807;1102.9393383363993;1107.1751545098953;5781.913271300934;5799.82600521985;5824.097264923944;5838.5037271831525;5853.502452291603;6671.566348187449;6680.86638267232;6697.196336439659;6717.395434410926;6719.865602687919;5783.277090363705;5784.566157538202;5788.450097240755;5801.129830525455;5806.535477161817;3499.3526197653514;3522.788632681996;3569.7322700797545;3580.910477238038;3583.408737558326;6492.642904640195;6513.413792758674;6545.21457425112;6547.770828021092;6571.769988050395;6784.966075169593;6793.762218322335;6824.556403833735;6896;6902.585844849325;6415.775447859302;6462.579932304385;6471.123374221517;6501;6556.967215163989;6627.867442651216;6660.603092422803;6709.194896612437;6729.125213722381;6732.764353247613;6617.305182955721;6667.633280169443;6679.8270821359465;6683.231428385798;6702.865942681216;6139.907901381573;6489.707963141575;6517.478601711947;6551.213854337319;6572.441342485751;4617.976910115428;4619.676622636383;4620.54452847352;4627.75050736306;4628.722276663277;6603.306652801579;6694.866102677822;6694.933050669626;6720.596764194164;6726.226483954212;6264.59280146789;6505.967470162619;6531.804045878631;6532.3995621073145;6552.24649165333;6492.0261960706275;6527.379898909596;6528.836779080561;6539.149910511603;6543.672816359068;6398.264199617103;6423.614583125028;6428.967855160694;6436.517261205522;6479.870402592016;6488.480921526361;6553.967230163818;6555.672216389257;6575.145231120116;6581.044155039696;6316.873662526816;6346.270178929298;6355.88771964895;6356.5232607555545;6367.968160159169 09:32:06 INFO - PID 5077 | |5;yui.html;788.546585713215;831.1273163178663;831.5634291996697;832.1636755061164;841.1462365698817;14807.55577332679;15358.308876100575;16986.91506542467;17601.471955841313;17643.55891102723;16074.517764467055;16566;16576.58558536037;16582.253798579062;16680.49958501244;922.2990527199344;931.5388882503171;945.7162851144664;954.5227386306847;955.44106697582;941.8069295794346;941.8822647169104;942.8962814090479;947.2280091725222;968.1915600473613;932.3240650528346;1007.3351587951953;1013.8276492996172;1015.578534908016;1016.5730393234859;959.6689142245914;1009.3035805294323;1010.8635334229858;1014.6144465103288;1014.9543270552842;677.5595862689242;680.3536640191837;681.4344094401685;695.4644923408947;708.125465050662;15519.767203491956;15539.922300388425;15567.844321556753;15635.061896286259;15892;18891.244350225974;18920.243190272373;19280.710789338173;19350.032498375025;19426.61146777071;15322.310496027705;15671.294791734284;15745.370185192578;15761.527154185393;15769;3321.086701157177;3329.5505106810756;3331.800091994488;3332.950005749916;3432.6910578047846;85050;85716;86212.56893715491;86812.56593716991;87258.56370718169;14194;14420.495282665057;14728.926355368369;14845.851541484662;14895.627609309853;25259.494810103693;25784;26094.608580871303;27174.184774456797;27224.863875680494;7567.545947243073;7622.161562228151;7731.729389471341;7770.45606807518;7777.922220777833;3466.6706662866986;3539.345221134098;3627.2926779277786;3636.581793093828;3647.215848592565;4118.320477121301;4203.390508376268;4209.978950105291;4214.957850421457;4218.7468751874985;824.3116997307196;835.6239692138562;853.176684499461;854.5897968974926;856.4818284937523;655.9442447392025;682.6484360554318;700.2157583506505;701.7684164225793;714.0217901475013;10180.185585153318;10643.574257029632;11511;11725.120615953838;11778.352190629426;655.2922843329193;674.5413119078976;676.8713944350558;698.1029377250311;706.7243774927782;4601.815927362868;4804.135255654016;4950.430700469492;4979.228219625964;5007.84976450707;92480.53759731159;94411.52794236122;94953.5252323734;95790.52104739432;97147;77305;78323;80671;83715.5814220925;84379;70777;72551.2744872555;73323.63338183275;73682;74111.25888741152;9039.23166530826;9059.048799876182;9283.814323713354;9646.855297170408;9677.274204434554;31238;31687.52468713017;31947.36105277928;32056.67943320537;32106.51840222352;39015;39650.40524392079;40787;41141;42121.78939105285;14476.855231447971;14521.128732276096;14762.040467369587;15014.324355403814;15026;16298.945145577993;16306.918465407596;16532.173391330627;16714.832851671326;16943.491695249162;14912.701745965236;15342.156181409684;15386.615334616725;15903;15938.123403212934;15297.923510382821;15369;15725.528234152993;15734.29195686174;15823.287952042416;14680.779788303398;14854.183019934007;15048.774268386202;15050.623734406727;15372.615684607526;10324.174066074545;10685.626003089854;10704.197185210984;11112.444377781241;11158;10877.564897404332;11221.438928053727;11952.402379880798;12975.610731678065;13642.727145456836 09:32:06 INFO - PID 5077 | __end_tp_report 09:32:06 INFO - PID 5077 | __start_cc_report 09:32:06 INFO - PID 5077 | _x_x_mozilla_cycle_collect,366 09:32:06 INFO - PID 5077 | __end_cc_report 09:32:06 INFO - PID 5077 | __startTimestamp1496680326469__endTimestamp 09:32:06 INFO - PID 5077 | 09:32:06 INFO - PID 5077 | ------- Summary: start ------- 09:32:06 INFO - PID 5077 | Number of tests: 6 09:32:06 INFO - PID 5077 | 09:32:06 INFO - PID 5077 | [#0] dojo.html Cycles:165 Average:5966.57 Median:6208.75 stddev:679.90 (11.0%) stddev-sans-first:643.72 09:32:06 INFO - PID 5077 | Values: 3099.8 3110.3 3136.5 3150.4 3153.2 5908.6 5962.4 6010.8 6019.6 6032.1 5943.4 5985.8 6027.3 6039.4 6130.9 5462.6 5557.6 5571.3 5613.2 5664.7 5591.2 5649.1 5667.7 5670.7 5682.0 5432.8 5500.2 5532.3 5585.7 5628.1 5569.0 5602.6 5609.0 5633.2 5657.3 5555.4 5573.3 5573.6 5580.3 5586.9 5849.6 5914.9 5936.9 6022.2 6036.1 6321.3 6436.8 6462.5 6489.3 6498.3 6553.3 6563.4 6580.2 6584.2 6589.0 5935.4 6023.0 6062.1 6085.9 6089.6 6574.1 6629.4 6655.1 6672.8 6681.4 6094.5 6212.1 6313.8 6321.5 6328.7 6490.4 6509.0 6515.0 6518.3 6530.3 6299.7 6462.8 6489.8 6501.0 6510.3 6238.4 6324.2 6344.1 6372.4 6387.4 5272.5 5276.6 5282.6 5283.6 5312.2 4500.2 4502.5 4516.1 4541.5 4554.3 5565.5 5634.8 5635.3 5724.7 5740.9 6470.2 6474.2 6476.5 6483.0 6498.7 5658.4 5685.4 5689.9 5691.1 5740.4 5591.1 5665.3 5714.7 5735.9 5754.7 6163.1 6237.3 6249.3 6256.9 6275.1 6521.7 6534.2 6540.4 6557.5 6564.1 6143.5 6305.1 6311.5 6321.7 6339.8 6225.5 6247.1 6348.4 6349.7 6385.9 6517.8 6550.7 6576.3 6585.9 6604.3 6131.0 6148.8 6223.8 6357.9 6387.3 6290.9 6292.5 6312.4 6328.8 6337.5 6208.8 6248.8 6269.7 6269.8 6271.4 6151.8 6252.1 6358.3 6370.8 6385.6 6115.2 6222.4 6224.0 6229.3 6238.6 09:32:06 INFO - PID 5077 | 09:32:06 INFO - PID 5077 | [#1] ext.html Cycles:180 Average:34459.71 Median:33430.75 stddev:48932.71 (146.4%) stddev-sans-first:49004.16 09:32:06 INFO - PID 5077 | Values: 660.4 720.4 736.9 1139.6 1147.9 35273.0 35301.0 35325.8 35364.8 35374.6 37564.0 38041.0 38047.0 38056.8 38141.8 7207.9 7318.5 7345.9 7474.9 7482.4 7325.6 7398.5 7404.4 7408.9 7410.3 7367.6 7410.8 7416.9 7433.7 7438.6 7342.6 7382.9 7388.9 7403.1 7403.5 7264.1 7305.9 7308.3 7314.6 7339.8 35286.8 35422.6 35537.3 35628.6 35761.6 3477.7 3519.2 3532.1 3534.2 3556.1 27862.9 27969.2 28044.6 28061.3 28134.6 3590.5 3598.6 3632.2 3634.9 3643.2 163716.0 163817.0 164034.0 164903.0 171796.0 41572.8 41576.2 41634.8 41651.4 41671.8 43408.3 43494.0 44729.0 45124.8 45210.3 14959.8 15195.2 15344.2 15345.2 15357.9 12296.6 12384.9 12386.1 12407.2 12479.9 13954.7 13999.0 14064.7 14075.2 14120.6 5598.8 5627.2 5696.7 5736.1 5764.7 950.8 977.9 980.1 980.8 987.4 22066.0 22465.0 23023.4 23682.9 23737.2 1061.3 1064.5 1069.8 1076.5 1113.1 11992.2 12055.8 12152.9 12609.4 12622.2 214730.0 215039.0 216025.0 216595.0 216904.0 47596.3 48608.0 48727.8 48750.5 48799.0 1361.9 1450.3 1451.2 1458.7 1466.6 20922.8 21220.6 22947.7 23196.2 23402.2 34923.0 34927.9 35150.6 35250.6 35288.5 182539.0 182692.0 182932.0 183238.0 183447.0 32048.5 32574.7 32668.3 32774.5 32776.5 38716.0 39108.2 39359.0 39400.0 39515.4 28277.3 28953.7 29023.6 29166.7 30675.0 28125.6 28497.9 28940.7 30227.2 30528.1 32580.7 32844.2 33346.5 33394.5 33467.0 27707.9 29126.3 29160.9 29338.6 30159.7 21523.2 24135.5 24169.5 24313.8 24655.4 09:32:06 INFO - PID 5077 | 09:32:06 INFO - PID 5077 | [#2] jquery.html Cycles:180 Average:30369.25 Median:2082.20 stddev:74672.57 (3586.2%) stddev-sans-first:74848.18 09:32:06 INFO - PID 5077 | Values: 418.4 427.6 430.3 437.6 444.0 19140.0 20414.6 20424.6 20520.4 22694.7 22807.3 23356.5 23363.3 23368.0 23417.9 5775.4 5853.5 5877.9 5894.2 5914.6 5801.8 5825.4 5840.2 5844.3 5852.1 5812.8 5814.8 6021.4 6055.3 6071.2 5969.4 5998.4 6000.2 6015.6 6020.5 5419.3 5423.5 5431.6 5441.2 5443.2 25608.2 25761.2 25918.7 26016.5 26019.7 1818.7 1889.6 1899.8 1907.3 1907.9 16852.3 17023.0 17088.7 17158.5 17171.0 2014.2 2034.9 2070.4 2080.1 2084.3 111403.4 112541.0 114285.4 121118.0 121191.4 11375.6 11736.6 11787.9 11999.5 12059.6 14901.5 15079.0 15096.2 15111.9 15161.6 8442.1 8793.7 8860.0 8903.0 9130.4 8691.3 8786.5 8821.4 8883.5 8937.7 7519.8 7530.5 7642.4 7743.3 7823.5 1942.8 1953.3 1957.2 1968.8 2157.2 902.9 909.5 925.6 958.1 971.2 17956.8 18363.8 18396.7 18430.2 18691.6 953.5 957.4 972.3 974.0 1003.7 9995.9 10104.9 10121.7 10506.3 10635.8 440898.0 442505.0 443445.0 443450.0 457616.0 94893.5 96364.0 96829.0 97646.0 104975.0 1152.5 1195.3 1227.5 1233.4 1235.0 18587.0 19326.4 20152.3 21480.5 21577.9 47663.0 49354.5 50116.7 50158.2 50645.0 60179.0 60421.4 61134.7 61342.4 61676.7 10314.7 10318.5 10333.8 10356.2 10453.0 17786.4 17977.4 18111.9 18127.2 18197.8 14287.4 14491.1 15150.5 15235.6 15274.6 12871.4 12903.2 12976.5 13278.5 13476.5 15196.4 15221.5 15322.6 15324.6 15327.2 10170.8 10398.1 10462.6 10488.1 10490.3 14230.6 14413.5 14473.7 14480.3 14583.9 09:32:06 INFO - PID 5077 | 09:32:06 INFO - PID 5077 | [#3] mootools.html Cycles:180 Average:6905.48 Median:3634.58 stddev:4327.79 (119.1%) stddev-sans-first:4319.50 09:32:06 INFO - PID 5077 | Values: 1309.6 1361.0 1364.6 1364.7 1370.8 10820.1 11070.9 11290.7 11330.6 11531.9 11086.2 11258.5 11284.7 11344.5 11354.1 2321.5 2367.0 2367.7 2376.9 2385.0 2248.3 2300.6 2302.3 2309.1 2312.7 2273.9 2293.9 2300.4 2301.8 2302.2 2181.3 2182.4 2204.6 2215.6 2219.3 1904.2 1990.5 2200.2 2210.9 2218.0 10879.3 10945.1 10949.5 10953.3 10974.9 6044.9 6310.0 6314.4 6318.7 6319.6 3548.4 3720.8 3743.6 3809.5 3812.3 1228.6 1239.1 1250.4 1268.7 1276.5 16056.9 16201.8 16285.8 16303.0 16316.7 5062.8 5102.7 5222.8 5239.6 5240.3 11921.3 11961.2 11999.9 12071.9 12135.2 5744.1 6018.0 6091.5 6106.1 6174.8 5716.6 5726.4 5739.4 5746.2 5758.5 3933.6 3984.5 4176.4 4195.3 4207.4 1179.4 1186.2 1205.5 1205.8 1213.9 874.1 882.3 891.6 902.7 927.8 6603.8 7036.8 7076.8 7280.5 7477.6 929.8 969.7 970.2 978.8 979.7 4642.6 4651.1 4792.7 4860.1 4913.1 13637.3 13899.4 14153.0 14162.2 14193.6 13645.5 14115.8 14526.2 14554.0 14588.5 8890.2 8998.2 9029.2 9112.3 9137.4 5949.1 6728.3 6819.5 6827.1 6953.8 12234.1 12336.9 12377.5 12415.6 12611.4 12487.1 12510.7 12622.7 12703.6 12859.7 9436.3 9646.1 9696.4 9703.4 9724.0 10143.0 10517.2 10587.9 10607.5 10671.6 8657.5 8674.2 8688.3 8739.7 8888.6 8164.6 8166.7 8207.5 8209.3 8215.8 7964.6 7983.3 8087.6 8153.2 8171.2 6281.8 6314.7 6316.1 6380.6 6647.5 6460.9 6469.0 6486.8 6706.5 6723.3 09:32:06 INFO - PID 5077 | 09:32:06 INFO - PID 5077 | [#4] prototype.html Cycles:180 Average:5396.72 Median:6069.33 stddev:1595.08 (26.3%) stddev-sans-first:1554.84 09:32:06 INFO - PID 5077 | Values: 400.3 401.7 405.7 406.8 409.1 6232.9 6256.4 6266.7 6295.6 6309.7 5866.9 6065.8 6066.4 6077.7 6081.2 4754.1 4754.6 4765.3 4781.8 4813.3 4754.8 4761.9 4766.5 4773.9 4840.7 4714.5 4723.9 4725.9 4731.6 4757.4 4738.6 4751.4 4756.3 4793.0 4796.6 4192.0 4198.4 4198.4 4201.6 4203.3 6046.6 6054.6 6055.4 6072.2 6085.6 5432.1 5440.8 5449.8 5455.7 5489.6 6490.2 6504.9 6512.3 6520.0 6525.7 4543.8 4548.8 4555.3 4559.9 4572.6 6507.6 6713.9 6801.6 6860.5 6877.1 4364.2 4586.4 4594.8 4627.0 4642.6 4639.5 4659.5 4675.5 4687.1 4700.2 5208.4 5389.6 5394.2 5398.7 5400.1 6463.5 6487.4 6497.1 6510.2 6526.8 1617.4 1624.8 1628.1 1632.8 1635.7 1052.6 1081.4 1100.5 1102.9 1107.2 5781.9 5799.8 5824.1 5838.5 5853.5 6671.6 6680.9 6697.2 6717.4 6719.9 5783.3 5784.6 5788.5 5801.1 5806.5 3499.4 3522.8 3569.7 3580.9 3583.4 6492.6 6513.4 6545.2 6547.8 6571.8 6785.0 6793.8 6824.6 6896.0 6902.6 6415.8 6462.6 6471.1 6501.0 6557.0 6627.9 6660.6 6709.2 6729.1 6732.8 6617.3 6667.6 6679.8 6683.2 6702.9 6139.9 6489.7 6517.5 6551.2 6572.4 4618.0 4619.7 4620.5 4627.8 4628.7 6603.3 6694.9 6694.9 6720.6 6726.2 6264.6 6506.0 6531.8 6532.4 6552.2 6492.0 6527.4 6528.8 6539.1 6543.7 6398.3 6423.6 6429.0 6436.5 6479.9 6488.5 6554.0 6555.7 6575.1 6581.0 6316.9 6346.3 6355.9 6356.5 6368.0 09:32:06 INFO - PID 5077 | 09:32:06 INFO - PID 5077 | [#5] yui.html Cycles:180 Average:18994.44 Median:3332.38 stddev:24846.84 (745.6%) stddev-sans-first:24878.93 09:32:06 INFO - PID 5077 | Values: 788.5 831.1 831.6 832.2 841.1 14807.6 15358.3 16986.9 17601.5 17643.6 16074.5 16566.0 16576.6 16582.3 16680.5 922.3 931.5 945.7 954.5 955.4 941.8 941.9 942.9 947.2 968.2 932.3 1007.3 1013.8 1015.6 1016.6 959.7 1009.3 1010.9 1014.6 1015.0 677.6 680.4 681.4 695.5 708.1 15519.8 15539.9 15567.8 15635.1 15892.0 18891.2 18920.2 19280.7 19350.0 19426.6 15322.3 15671.3 15745.4 15761.5 15769.0 3321.1 3329.6 3331.8 3333.0 3432.7 85050.0 85716.0 86212.6 86812.6 87258.6 14194.0 14420.5 14728.9 14845.9 14895.6 25259.5 25784.0 26094.6 27174.2 27224.9 7567.5 7622.2 7731.7 7770.5 7777.9 3466.7 3539.3 3627.3 3636.6 3647.2 4118.3 4203.4 4210.0 4215.0 4218.7 824.3 835.6 853.2 854.6 856.5 655.9 682.6 700.2 701.8 714.0 10180.2 10643.6 11511.0 11725.1 11778.4 655.3 674.5 676.9 698.1 706.7 4601.8 4804.1 4950.4 4979.2 5007.8 92480.5 94411.5 94953.5 95790.5 97147.0 77305.0 78323.0 80671.0 83715.6 84379.0 70777.0 72551.3 73323.6 73682.0 74111.3 9039.2 9059.0 9283.8 9646.9 9677.3 31238.0 31687.5 31947.4 32056.7 32106.5 39015.0 39650.4 40787.0 41141.0 42121.8 14476.9 14521.1 14762.0 15014.3 15026.0 16298.9 16306.9 16532.2 16714.8 16943.5 14912.7 15342.2 15386.6 15903.0 15938.1 15297.9 15369.0 15725.5 15734.3 15823.3 14680.8 14854.2 15048.8 15050.6 15372.6 10324.2 10685.6 10704.2 11112.4 11158.0 10877.6 11221.4 11952.4 12975.6 13642.7 09:32:06 INFO - PID 5077 | -------- Summary: end -------- 09:32:06 INFO - PID 5077 | 09:32:07 INFO - TEST-INFO | 5077: exit 0 09:32:07 INFO - TEST-OK | dromaeo_css | took 1142709ms 09:32:07 INFO - TEST-START | kraken 09:32:07 INFO - operating with platform_type : linux_ 09:32:07 INFO - Initialising browser for kraken test... 09:32:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34757/getInfo.html -profile /tmp/tmpvOh_Ye/profile 09:32:07 INFO - TEST-INFO | started process 5477 (/builds/slave/test/build/application/firefox/firefox http://localhost:34757/getInfo.html) 09:32:42 INFO - TEST-INFO | 5477: killed by SIGTERM 09:32:42 INFO - Browser initialized. 09:32:42 INFO - Running cycle 1/1 for kraken test... 09:32:42 INFO - TEST-INFO | started process 5682 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvOh_Ye/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:54 INFO - PID 5682 | RSS: Main: 187346944 09:32:54 INFO - PID 5682 | 09:32:56 INFO - PID 5682 | Cycle 1(1): loaded http://localhost:34757/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34757/tests/kraken/driver.html?testName=audio-beat-detection) 09:32:57 INFO - PID 5682 | RSS: Main: 190967808 09:32:57 INFO - PID 5682 | 09:33:00 INFO - PID 5682 | Cycle 1(1): loaded http://localhost:34757/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34757/tests/kraken/driver.html?testName=audio-dft) 09:33:00 INFO - PID 5682 | RSS: Main: 189644800 09:33:00 INFO - PID 5682 | 09:33:03 INFO - PID 5682 | Cycle 1(1): loaded http://localhost:34757/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34757/tests/kraken/driver.html?testName=audio-fft) 09:33:04 INFO - PID 5682 | RSS: Main: 184893440 09:33:04 INFO - PID 5682 | 09:33:06 INFO - PID 5682 | Cycle 1(1): loaded http://localhost:34757/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34757/tests/kraken/driver.html?testName=audio-oscillator) 09:33:07 INFO - PID 5682 | RSS: Main: 185892864 09:33:07 INFO - PID 5682 | 09:33:09 INFO - PID 5682 | Cycle 1(1): loaded http://localhost:34757/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34757/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:33:09 INFO - PID 5682 | RSS: Main: 185688064 09:33:09 INFO - PID 5682 | 09:33:12 INFO - PID 5682 | Cycle 1(1): loaded http://localhost:34757/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34757/tests/kraken/driver.html?testName=imaging-darkroom) 09:33:13 INFO - PID 5682 | RSS: Main: 186953728 09:33:13 INFO - PID 5682 | 09:33:16 INFO - PID 5682 | Cycle 1(1): loaded http://localhost:34757/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34757/tests/kraken/driver.html?testName=imaging-desaturate) 09:33:17 INFO - PID 5682 | RSS: Main: 186503168 09:33:17 INFO - PID 5682 | 09:33:20 INFO - PID 5682 | Cycle 1(1): loaded http://localhost:34757/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34757/tests/kraken/driver.html?testName=json-parse-financial) 09:33:21 INFO - PID 5682 | RSS: Main: 186916864 09:33:21 INFO - PID 5682 | 09:33:22 INFO - PID 5682 | Cycle 1(1): loaded http://localhost:34757/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34757/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:33:23 INFO - PID 5682 | RSS: Main: 187056128 09:33:23 INFO - PID 5682 | 09:33:24 INFO - PID 5682 | Cycle 1(1): loaded http://localhost:34757/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34757/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:33:25 INFO - PID 5682 | RSS: Main: 186687488 09:33:25 INFO - PID 5682 | 09:33:27 INFO - PID 5682 | Cycle 1(1): loaded http://localhost:34757/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34757/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:33:28 INFO - PID 5682 | RSS: Main: 187072512 09:33:28 INFO - PID 5682 | 09:33:31 INFO - PID 5682 | Cycle 1(1): loaded http://localhost:34757/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34757/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:33:31 INFO - PID 5682 | RSS: Main: 187211776 09:33:31 INFO - PID 5682 | 09:33:35 INFO - PID 5682 | Cycle 1(1): loaded http://localhost:34757/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34757/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:33:35 INFO - PID 5682 | RSS: Main: 187715584 09:33:35 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | Cycle 1(1): loaded http://localhost:34757/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34757/tests/kraken/driver.html?testName=ai-astar) 09:33:37 INFO - PID 5682 | RSS: Main: 189235200 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | __start_tp_report 09:33:37 INFO - PID 5682 | _x_x_mozilla_page_load 09:33:37 INFO - PID 5682 | _x_x_mozilla_page_load_details 09:33:37 INFO - PID 5682 | |i|pagename|runs| 09:33:37 INFO - PID 5682 | |0;ai-astar;104;111;113;110;109;110;112;116;112;111 09:33:37 INFO - PID 5682 | |1;audio-beat-detection;151;121;123;166;125;122;124;122;143;122 09:33:37 INFO - PID 5682 | |2;audio-dft;163;166;166;163;163;166;171;168;172;163 09:33:37 INFO - PID 5682 | |3;audio-fft;74;74;74;115;75;73;78;74;108;74 09:33:37 INFO - PID 5682 | |4;audio-oscillator;85;81;106;83;83;81;93;85;84;103 09:33:37 INFO - PID 5682 | |5;imaging-gaussian-blur;114;114;114;114;114;113;114;114;113;114 09:33:37 INFO - PID 5682 | |6;imaging-darkroom;150;151;150;150;151;150;150;150;153;152 09:33:37 INFO - PID 5682 | |7;imaging-desaturate;94;95;95;95;95;94;94;99;95;97 09:33:37 INFO - PID 5682 | |8;json-parse-financial;56;53;53;55;57;53;55;54;57;54 09:33:37 INFO - PID 5682 | |9;json-stringify-tinderbox;54;57;56;55;55;55;56;55;56;55 09:33:37 INFO - PID 5682 | |10;stanford-crypto-aes;86;86;86;88;87;95;87;90;141;89 09:33:37 INFO - PID 5682 | |11;stanford-crypto-ccm;145;157;137;130;131;142;131;132;146;133 09:33:37 INFO - PID 5682 | |12;stanford-crypto-pbkdf2;187;208;199;199;193;207;215;197;202;192 09:33:37 INFO - PID 5682 | |13;stanford-crypto-sha256-iterative;78;86;73;76;72;73;73;74;75;70 09:33:37 INFO - PID 5682 | __end_tp_report 09:33:37 INFO - PID 5682 | __start_cc_report 09:33:37 INFO - PID 5682 | _x_x_mozilla_cycle_collect,975 09:33:37 INFO - PID 5682 | __end_cc_report 09:33:37 INFO - PID 5682 | __startTimestamp1496680417793__endTimestamp 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | ------- Summary: start ------- 09:33:37 INFO - PID 5682 | Number of tests: 14 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.50 stddev:3.08 (2.8%) stddev-sans-first:2.07 09:33:37 INFO - PID 5682 | Values: 104.0 111.0 113.0 110.0 109.0 110.0 112.0 116.0 112.0 111.0 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:124.50 stddev:15.82 (12.7%) stddev-sans-first:15.20 09:33:37 INFO - PID 5682 | Values: 151.0 121.0 123.0 166.0 125.0 122.0 124.0 122.0 143.0 122.0 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | [#2] audio-dft Cycles:10 Average:166.10 Median:166.00 stddev:3.35 (2.0%) stddev-sans-first:3.36 09:33:37 INFO - PID 5682 | Values: 163.0 166.0 166.0 163.0 163.0 166.0 171.0 168.0 172.0 163.0 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | [#3] audio-fft Cycles:10 Average:81.90 Median:74.00 stddev:15.74 (21.3%) stddev-sans-first:16.44 09:33:37 INFO - PID 5682 | Values: 74.0 74.0 74.0 115.0 75.0 73.0 78.0 74.0 108.0 74.0 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:83.50 stddev:9.16 (11.0%) stddev-sans-first:9.63 09:33:37 INFO - PID 5682 | Values: 85.0 81.0 106.0 83.0 83.0 81.0 93.0 85.0 84.0 103.0 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:33:37 INFO - PID 5682 | Values: 114.0 114.0 114.0 114.0 114.0 113.0 114.0 114.0 113.0 114.0 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | [#6] imaging-darkroom Cycles:10 Average:150.70 Median:150.50 stddev:1.06 (0.7%) stddev-sans-first:1.09 09:33:37 INFO - PID 5682 | Values: 150.0 151.0 150.0 150.0 151.0 150.0 150.0 150.0 153.0 152.0 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:1.57 (1.6%) stddev-sans-first:1.59 09:33:37 INFO - PID 5682 | Values: 94.0 95.0 95.0 95.0 95.0 94.0 94.0 99.0 95.0 97.0 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.57 (2.8%) stddev-sans-first:1.59 09:33:37 INFO - PID 5682 | Values: 56.0 53.0 53.0 55.0 57.0 53.0 55.0 54.0 57.0 54.0 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | [#9] json-stringify-tinderbox Cycles:10 Average:55.40 Median:55.50 stddev:0.84 (1.5%) stddev-sans-first:0.73 09:33:37 INFO - PID 5682 | Values: 54.0 57.0 56.0 55.0 55.0 55.0 56.0 55.0 56.0 55.0 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | [#10] stanford-crypto-aes Cycles:10 Average:93.50 Median:87.50 stddev:16.91 (19.3%) stddev-sans-first:17.72 09:33:37 INFO - PID 5682 | Values: 86.0 86.0 86.0 88.0 87.0 95.0 87.0 90.0 141.0 89.0 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | [#11] stanford-crypto-ccm Cycles:10 Average:138.40 Median:139.50 stddev:8.90 (6.4%) stddev-sans-first:9.11 09:33:37 INFO - PID 5682 | Values: 145.0 157.0 137.0 130.0 131.0 142.0 131.0 132.0 146.0 133.0 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.90 Median:200.50 stddev:8.40 (4.2%) stddev-sans-first:7.50 09:33:37 INFO - PID 5682 | Values: 187.0 208.0 199.0 199.0 193.0 207.0 215.0 197.0 202.0 192.0 09:33:37 INFO - PID 5682 | 09:33:37 INFO - PID 5682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.00 Median:74.50 stddev:4.45 (6.0%) stddev-sans-first:4.58 09:33:37 INFO - PID 5682 | Values: 78.0 86.0 73.0 76.0 72.0 73.0 73.0 74.0 75.0 70.0 09:33:37 INFO - PID 5682 | -------- Summary: end -------- 09:33:37 INFO - PID 5682 | 09:33:39 INFO - TEST-INFO | 5682: exit 0 09:33:39 INFO - TEST-OK | kraken | took 91657ms 09:33:39 INFO - SUITE-END | took 1234s 09:33:39 INFO - Completed test suite (00:20:34) 09:33:39 INFO - javascript benchmark 09:33:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7616.524100758299, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3099.7520198384127, 3110.346827166295, 3136.529520571914, 3150.3541773936345, 3153.1644114309697, 5908.615939963899, 5962.373950735176, 6010.819675409739, 6019.578629495937, 6032.065029920358, 5943.4056594340545, 5985.8204253872345, 6027.336992930774, 6039.3960603939695, 6130.938690613092, 5462.590305727066, 5557.5831812613915, 5571.331440227178, 5613.242212301348, 5664.688442135681, 5591.245181900432, 5649.067903795869, 5667.688277144755, 5670.659760414368, 5682.034054210794, 5432.75552600134, 5500.1749737539285, 5532.30846144232, 5585.692786896699, 5628.127640215773, 5568.972155139239, 5602.635828671164, 5609.0, 5633.183188437653, 5657.321121465409, 5555.416681248451, 5573.27547418837, 5573.554115670737, 5580.302462192226, 5586.944130558683, 5849.590528662953, 5914.8817023659285, 5936.881262374728, 6022.247219097613, 6036.064410016411, 6321.336259692758, 6436.839079023015, 6462.515311351667, 6489.286178520359, 6498.285188629247, 6553.279139294674, 6563.409293163638, 6580.24327202373, 6584.176977877765, 6589.011648252753, 5935.436133567304, 6023.036314189796, 6062.1209924561135, 6085.9391406085815, 6089.604175728564, 6574.145361103026, 6629.403353698191, 6655.134832471747, 6672.799816005528, 6681.398674119256, 6094.542909281821, 6212.1303017577575, 6313.779017734358, 6321.462675672527, 6328.746850125944, 6490.350964903566, 6509.02364645307, 6515.022746587955, 6518.348165183444, 6530.2816690164045, 6299.748010079546, 6462.806115816439, 6489.805305840832, 6501.0, 6510.283868774431, 6238.438540531374, 6324.209473815773, 6344.111824344595, 6372.426481616584, 6387.393197646216, 5272.525472707474, 5276.551493123051, 5282.577393808486, 5283.6037297202865, 5312.1766126250495, 4500.167469018242, 4502.5047244803045, 4516.07420478801, 4541.500434952219, 4554.316852472156, 5565.526930210977, 5634.774609015593, 5635.267415236075, 5724.685142317213, 5740.885182296331, 6470.223573171155, 6474.1907261592305, 6476.546641735127, 6483.0, 6498.740050397932, 5658.434156584309, 5685.431456854364, 5689.943100569024, 5691.146328050742, 5740.42595740431, 5591.133374326986, 5665.348484924204, 5714.7428365723645, 5735.8852822944145, 5754.712264386764, 6163.075538669146, 6237.282712488122, 6249.312575616678, 6256.906146407899, 6275.058741188858, 6521.67391630426, 6534.248561415308, 6540.37866402691, 6557.508186885907, 6564.146660934048, 6143.539234557516, 6305.1172835803945, 6311.463525600376, 6321.747130114744, 6339.809805705836, 6225.470834979077, 6247.0629405589525, 6348.396902294275, 6349.6507692077375, 6385.87228255423, 6517.771877984248, 6550.705218265094, 6576.309487503743, 6585.868282634225, 6604.339566043357, 6130.969345153424, 6148.754049837957, 6223.844403889758, 6357.93642063592, 6387.297397286387, 6290.905636415366, 6292.528060395397, 6312.43188113072, 6328.778492752732, 6337.524685648503, 6208.751649933952, 6248.812535623939, 6269.655168965751, 6269.780557680459, 6271.3728627136925, 6151.815445536641, 6252.093446450148, 6358.300586935526, 6370.77702280418, 6385.584936979268, 6115.240341084353, 6222.439980401786, 6224.0, 6229.283632382153, 6238.625682459067], "value": 5917.4839858480245, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [660.3924389561603, 720.4092644031895, 736.889466580013, 1139.6125317392084, 1147.8852114788517, 35273.0, 35301.0, 35325.82337088314, 35364.823175884114, 35374.64625353752, 37564.0, 38041.04897377567, 38047.0, 38056.80971595145, 38141.80929095358, 7207.891881621754, 7318.451116166296, 7345.926540734605, 7474.925250747505, 7482.3639990600595, 7325.560466372035, 7398.482106252564, 7404.370628496585, 7408.888866667005, 7410.29602187794, 7367.631618419085, 7410.777676669682, 7416.888746668804, 7433.665485053159, 7438.628068596576, 7342.596157211351, 7382.926170738305, 7388.889166662504, 7403.074615673041, 7403.481756277063, 7264.128304603429, 7305.89041164383, 7308.342249197544, 7314.634268286564, 7339.816504587428, 35286.82356588226, 35422.64577354219, 35537.28925421503, 35628.64371356279, 35761.64238357635, 3477.739169562293, 3519.1554027033585, 3532.116970757311, 3534.2224710563637, 3556.1465248340214, 27862.860685696443, 27969.160925172277, 28044.579331310048, 28061.298467538272, 28134.577981330298, 3590.4973303737497, 3598.5501812273465, 3632.1827588792576, 3634.945475817865, 3643.2167084076787, 163716.0, 163817.0, 164034.0, 164903.0, 171796.0, 41572.79213603913, 41576.1684766303, 41634.791826041284, 41651.37522937158, 41671.7916410416, 43408.3488747669, 43494.0, 44729.0, 45124.77437612791, 45210.32184517235, 14959.775603365959, 15195.240237988057, 15344.156071415957, 15345.232738363036, 15357.923210383877, 12296.569620063256, 12384.938075309567, 12386.071044671686, 12407.193532420364, 12479.937600312122, 13954.720905582035, 13999.0, 14064.71870562583, 14075.155490670593, 14120.646983825487, 5598.776048958078, 5627.155926611041, 5696.715164241771, 5736.110902810071, 5764.654120752768, 950.805084957582, 977.9070988256104, 980.0839359053034, 980.8038392321564, 987.3532835992437, 22066.0, 22465.0, 23023.42441438944, 23682.88158559196, 23737.169199078297, 1061.2624226162743, 1064.5050051725984, 1069.823479125951, 1076.4563895232857, 1113.1039513191959, 11992.160548761678, 12055.819162712567, 12152.939235303767, 12609.369531523387, 12622.179558328678, 214730.0, 215039.0, 216025.0, 216595.0, 216904.0, 47596.28605570919, 48608.0, 48727.756361217966, 48750.51249487531, 48799.0, 1361.918284902909, 1450.2893582144673, 1451.2453524167586, 1458.7155504676698, 1466.5893549806071, 20922.790772092085, 21220.575588487834, 22947.6557851629, 23196.18813341525, 23402.18092366759, 34923.0, 34927.91874112698, 35150.64849351474, 35250.64749352474, 35288.47067293942, 182539.0, 182692.0, 182932.0, 183238.0, 183447.0, 32048.5192722114, 32574.674253257162, 32668.34663306673, 32774.50838237381, 32776.50835237426, 38716.0, 39108.21783564256, 39359.016024599616, 39400.0, 39515.40726889156, 28277.293067673472, 28953.697083630865, 29023.564646529896, 29166.708332916398, 30675.0, 28125.578116327863, 28497.857510713144, 28940.7105928938, 30227.244318892204, 30528.08415747531, 32580.674193258714, 32844.1788955278, 33346.499802503466, 33394.4990825133, 33467.0, 27707.86013004364, 29126.27184320409, 29160.854195728887, 29338.55992160162, 30159.698403016566, 21523.246686365903, 24135.51728965446, 24169.51660966806, 24313.75686243115, 24655.38361540976], "value": 16037.472023295422, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.43511259799254, 427.5803894762868, 430.3437258181272, 437.6170850505808, 443.9600435960765, 19140.0, 20414.591708165826, 20424.59150816983, 20520.38438846837, 22694.659580106312, 22807.31578052661, 23356.5328693426, 23363.261878099464, 23368.01371883392, 23417.882910585467, 5775.422457754223, 5853.5317174625925, 5877.8824423511505, 5894.174815525808, 5914.585978981473, 5801.767929282844, 5825.417458254162, 5840.182374467578, 5844.298684157886, 5852.092925596518, 5812.767489300444, 5814.825555233349, 6021.397860213965, 6055.273367195942, 6071.241094863142, 5969.432903874147, 5998.430149135826, 6000.159977603139, 6015.578909476339, 6020.4581587656885, 5419.295491586108, 5423.457654234545, 5431.646942948735, 5441.211024401439, 5443.1835224716215, 25608.23175304744, 25761.227163185133, 25918.74081259201, 26016.479670406487, 26019.73980260211, 1818.681730697136, 1889.6409682160483, 1899.838513726335, 1907.2561700936647, 1907.895065771382, 16852.325906963706, 17023.0, 17088.743668844978, 17158.48524544266, 17171.0, 2014.2043892662316, 2034.918603255868, 2070.3685375960326, 2080.126346934291, 2084.27050532314, 111403.44298278537, 112541.0, 114285.42857285743, 121118.0, 121191.36425907238, 11375.601853935239, 11736.647900562997, 11787.88212117885, 11999.500187429714, 12059.638210853689, 14901.478448254258, 15079.0, 15096.16971066602, 15111.924440377728, 15161.620959476102, 8442.07137214906, 8793.736187914374, 8859.955700221457, 8902.955485222532, 9130.360874738837, 8691.304695624334, 8786.472811631322, 8821.38250322484, 8883.466991980502, 8937.73186804397, 7519.8496030079095, 7530.548167109991, 7642.3504002159325, 7743.303102720783, 7823.491473054347, 1942.7960064193055, 1953.2675246782458, 1957.2268953763185, 1968.8424926005885, 2157.2341818654154, 902.8871391076116, 909.4906852162678, 925.5881132895931, 958.0706714486937, 971.1939090554822, 17956.820431795775, 18363.816361836478, 18396.724049139273, 18430.17064232113, 18691.635060060846, 953.5137080089204, 957.4303289542711, 972.3145182646359, 973.9756506087405, 1003.6738060130486, 9995.850062249072, 10104.898951010542, 10121.746956326004, 10506.264561480622, 10635.787284254271, 440898.0, 442505.0, 443445.0, 443450.0, 457616.0, 94893.52553237327, 96364.0, 96829.0, 97646.0, 104975.0, 1152.4929031226234, 1195.3126952002633, 1227.4844565282604, 1233.3586535001568, 1234.9567765128177, 18587.0, 19326.4202073938, 20152.29466968649, 21480.462988425414, 21577.892110539975, 47663.0, 49354.506454936425, 50116.749416252685, 50158.24762628491, 50645.0, 60179.0, 60421.395786041576, 61134.694326528086, 61342.386576135454, 61676.69161654163, 10314.690559283234, 10318.48407579633, 10333.793324133325, 10356.171506279328, 10453.013927353779, 17786.37747678825, 17977.370792022215, 18111.909440452713, 18127.184276707318, 18197.818021819614, 14287.357068931715, 14491.130532168103, 15150.54548363551, 15235.619109522351, 15274.618134546725, 12871.356432178167, 12903.225806451643, 12976.480940762263, 13278.53525126616, 13476.460941562227, 15196.392144314104, 15221.543353699406, 15322.616934576725, 15324.616884577974, 15327.157006364758, 10170.79658406813, 10398.116160126474, 10462.581496740046, 10488.10851077667, 10490.31812932157, 14230.644233894236, 14413.495527656481, 14473.710525789636, 14480.275986200859, 14583.85416145825], "value": 10278.17564679063, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.5921883974727, 1360.9659758506043, 1364.5633397312863, 1364.6588352911779, 1370.7738223193173, 10820.08029317508, 11070.889291107107, 11290.717732056692, 11330.603428879991, 11531.942340288288, 11086.16853735969, 11258.493367798448, 11284.717882052953, 11344.546218151265, 11354.091672666187, 2321.5124823787023, 2367.0176876596192, 2367.6566897799808, 2376.92869213923, 2384.9761502384927, 2248.325502349297, 2300.620397634388, 2302.26327575176, 2309.1225334373, 2312.6646636237733, 2273.8863056847176, 2293.86236825791, 2300.4363930836967, 2301.827362947777, 2302.1942320187886, 2181.2801775414077, 2182.378022263655, 2204.581129585382, 2215.5901158285687, 2219.2787344113226, 1904.2002359009111, 1990.5421752996747, 2200.1859312054644, 2210.8784016879067, 2218.0, 10879.292845965067, 10945.069669078139, 10949.507272172772, 10953.288036277696, 10974.890251097546, 6044.879102417971, 6309.968450157719, 6314.4001319875, 6318.71565779541, 6319.5576309658345, 3548.361294966905, 3720.795356255405, 3743.5507739071486, 3809.485719427897, 3812.275667623143, 1228.5822820241071, 1239.070696977267, 1250.3873102179957, 1268.657462485133, 1276.5149243287585, 16056.919715401466, 16201.756973645404, 16285.755713664304, 16303.0, 16316.673666526722, 5062.797488100435, 5102.668326558762, 5222.817201397933, 5239.607029472729, 5240.323096609049, 11921.34432606215, 11961.222520536312, 11999.940000299943, 12071.879281207252, 12135.150539462153, 5744.138379243063, 6018.037114061729, 6091.451769340781, 6106.114613381035, 6174.8147555573405, 5716.5998380113, 5726.4273572642405, 5739.368669446358, 5746.166805813134, 5758.510526605285, 3933.6263055009726, 3984.4820173377275, 4176.352665336817, 4195.349720793282, 4207.431996680471, 1179.4102948525738, 1186.1815347410259, 1205.5057426455112, 1205.8372119763894, 1213.8725433829497, 874.1127755328347, 882.2544949517647, 891.6210610490516, 902.7427183252872, 927.823713494434, 6603.834904127435, 7036.753713620101, 7076.823079423056, 7280.453965952574, 7477.626118694044, 929.8377028714108, 969.7381706939087, 970.1850445625709, 978.8384916488842, 979.6962941488162, 4642.605378542862, 4651.09303685778, 4792.7363994980615, 4860.1008813368935, 4913.115639184981, 13637.318134093255, 13899.374528146258, 14153.0, 14162.221077840612, 14193.574192774233, 13645.522406715718, 14115.78826317606, 14526.20105894186, 14554.0, 14588.48940287106, 8890.155435233642, 8998.190162885241, 9029.187373136318, 9112.316576256808, 9137.360384773003, 5949.100820667813, 6728.327167283428, 6819.488538359544, 6827.146606674166, 6953.79138625842, 12234.143609947218, 12336.938315308365, 12377.504899803907, 12415.62753117408, 12611.43248553799, 12487.06347023995, 12510.687232819253, 12622.684432889251, 12703.555375561811, 12859.742805143658, 9436.292278079034, 9646.083622055894, 9696.418214907128, 9703.417794932328, 9724.0, 10143.0, 10517.211209159497, 10587.89412105869, 10607.469626518798, 10671.57313707443, 8657.52383618907, 8674.175953284428, 8688.348373872112, 8739.694110706096, 8888.64445422176, 8164.5917704115745, 8166.714165004197, 8207.466514676527, 8209.261166494805, 8215.835683286181, 7964.561949092856, 7983.321417679563, 8087.555184464911, 8153.1846815317995, 8171.223733745284, 6281.811545653638, 6314.652694101868, 6316.11574379578, 6380.553361264667, 6647.5014373921185, 6460.903086453801, 6469.0, 6486.772962946275, 6706.530542861953, 6723.26044135135], "value": 5229.909771530087, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.27350359098233, 401.6746435387337, 405.7462441057133, 406.8006676728404, 409.0979390444069, 6232.875342493147, 6256.436920677138, 6266.749330026793, 6295.559310848237, 6309.747610095596, 5866.941330586693, 6065.817165652699, 6066.4236897494775, 6077.696115194245, 6081.209442772433, 4754.096721622898, 4754.619630429557, 4765.285207218928, 4781.8087276508895, 4813.302071199674, 4754.833580824672, 4761.880962971479, 4766.451858036336, 4773.90452190956, 4840.733759643218, 4714.528547145275, 4723.929141062887, 4725.881852953669, 4731.597814185799, 4757.381540399743, 4738.5972192363515, 4751.429828420594, 4756.286557016475, 4792.9760351198365, 4796.640251981081, 4191.958080419217, 4198.412222288852, 4198.412222288882, 4201.621854033152, 4203.306454435045, 6046.637201767908, 6054.606450580698, 6055.364186760415, 6072.240969878765, 6085.6348619083, 5432.103702889019, 5440.755166017539, 5449.782008719647, 5455.727213639342, 5489.64317319373, 6490.156279683611, 6504.869902601921, 6512.251091124534, 6520.011396524105, 6525.738970441176, 4543.7728113594185, 4548.815828919736, 4555.316702494619, 4559.863204103882, 4572.611328037121, 6507.609543427409, 6713.932860671429, 6801.557898736616, 6860.5197636165485, 6877.140357455318, 4364.170807546556, 4586.4496260448295, 4594.770261486912, 4626.976865115653, 4642.605378542862, 4639.466461356919, 4659.487456379796, 4675.485696573374, 4687.086018226481, 4700.213918710868, 5208.427073021964, 5389.56883449331, 5394.217838413408, 5398.73006349681, 5400.135978243462, 6463.4506066983895, 6487.448566871869, 6497.122888410004, 6510.18622672166, 6526.771562995273, 1617.4015614222812, 1624.764409160665, 1628.0882705684855, 1632.7877375941052, 1635.7219272723428, 1052.6000119954522, 1081.4484612847505, 1100.4882729530807, 1102.9393383363993, 1107.1751545098953, 5781.913271300934, 5799.82600521985, 5824.097264923944, 5838.5037271831525, 5853.502452291603, 6671.566348187449, 6680.86638267232, 6697.196336439659, 6717.395434410926, 6719.865602687919, 5783.277090363705, 5784.566157538202, 5788.450097240755, 5801.129830525455, 5806.535477161817, 3499.3526197653514, 3522.788632681996, 3569.7322700797545, 3580.910477238038, 3583.408737558326, 6492.642904640195, 6513.413792758674, 6545.21457425112, 6547.770828021092, 6571.769988050395, 6784.966075169593, 6793.762218322335, 6824.556403833735, 6896.0, 6902.585844849325, 6415.775447859302, 6462.579932304385, 6471.123374221517, 6501.0, 6556.967215163989, 6627.867442651216, 6660.603092422803, 6709.194896612437, 6729.125213722381, 6732.764353247613, 6617.305182955721, 6667.633280169443, 6679.8270821359465, 6683.231428385798, 6702.865942681216, 6139.907901381573, 6489.707963141575, 6517.478601711947, 6551.213854337319, 6572.441342485751, 4617.976910115428, 4619.676622636383, 4620.54452847352, 4627.75050736306, 4628.722276663277, 6603.306652801579, 6694.866102677822, 6694.933050669626, 6720.596764194164, 6726.226483954212, 6264.59280146789, 6505.967470162619, 6531.804045878631, 6532.3995621073145, 6552.24649165333, 6492.0261960706275, 6527.379898909596, 6528.836779080561, 6539.149910511603, 6543.672816359068, 6398.264199617103, 6423.614583125028, 6428.967855160694, 6436.517261205522, 6479.870402592016, 6488.480921526361, 6553.967230163818, 6555.672216389257, 6575.145231120116, 6581.044155039696, 6316.873662526816, 6346.270178929298, 6355.88771964895, 6356.5232607555545, 6367.968160159169], "value": 4894.663626384474, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.546585713215, 831.1273163178663, 831.5634291996697, 832.1636755061164, 841.1462365698817, 14807.55577332679, 15358.308876100575, 16986.91506542467, 17601.471955841313, 17643.55891102723, 16074.517764467055, 16566.0, 16576.58558536037, 16582.253798579062, 16680.49958501244, 922.2990527199344, 931.5388882503171, 945.7162851144664, 954.5227386306847, 955.44106697582, 941.8069295794346, 941.8822647169104, 942.8962814090479, 947.2280091725222, 968.1915600473613, 932.3240650528346, 1007.3351587951953, 1013.8276492996172, 1015.578534908016, 1016.5730393234859, 959.6689142245914, 1009.3035805294323, 1010.8635334229858, 1014.6144465103288, 1014.9543270552842, 677.5595862689242, 680.3536640191837, 681.4344094401685, 695.4644923408947, 708.125465050662, 15519.767203491956, 15539.922300388425, 15567.844321556753, 15635.061896286259, 15892.0, 18891.244350225974, 18920.243190272373, 19280.710789338173, 19350.032498375025, 19426.61146777071, 15322.310496027705, 15671.294791734284, 15745.370185192578, 15761.527154185393, 15769.0, 3321.086701157177, 3329.5505106810756, 3331.800091994488, 3332.950005749916, 3432.6910578047846, 85050.0, 85716.0, 86212.56893715491, 86812.56593716991, 87258.56370718169, 14194.0, 14420.495282665057, 14728.926355368369, 14845.851541484662, 14895.627609309853, 25259.494810103693, 25784.0, 26094.608580871303, 27174.184774456797, 27224.863875680494, 7567.545947243073, 7622.161562228151, 7731.729389471341, 7770.45606807518, 7777.922220777833, 3466.6706662866986, 3539.345221134098, 3627.2926779277786, 3636.581793093828, 3647.215848592565, 4118.320477121301, 4203.390508376268, 4209.978950105291, 4214.957850421457, 4218.7468751874985, 824.3116997307196, 835.6239692138562, 853.176684499461, 854.5897968974926, 856.4818284937523, 655.9442447392025, 682.6484360554318, 700.2157583506505, 701.7684164225793, 714.0217901475013, 10180.185585153318, 10643.574257029632, 11511.0, 11725.120615953838, 11778.352190629426, 655.2922843329193, 674.5413119078976, 676.8713944350558, 698.1029377250311, 706.7243774927782, 4601.815927362868, 4804.135255654016, 4950.430700469492, 4979.228219625964, 5007.84976450707, 92480.53759731159, 94411.52794236122, 94953.5252323734, 95790.52104739432, 97147.0, 77305.0, 78323.0, 80671.0, 83715.5814220925, 84379.0, 70777.0, 72551.2744872555, 73323.63338183275, 73682.0, 74111.25888741152, 9039.23166530826, 9059.048799876182, 9283.814323713354, 9646.855297170408, 9677.274204434554, 31238.0, 31687.52468713017, 31947.36105277928, 32056.67943320537, 32106.51840222352, 39015.0, 39650.40524392079, 40787.0, 41141.0, 42121.78939105285, 14476.855231447971, 14521.128732276096, 14762.040467369587, 15014.324355403814, 15026.0, 16298.945145577993, 16306.918465407596, 16532.173391330627, 16714.832851671326, 16943.491695249162, 14912.701745965236, 15342.156181409684, 15386.615334616725, 15903.0, 15938.123403212934, 15297.923510382821, 15369.0, 15725.528234152993, 15734.29195686174, 15823.287952042416, 14680.779788303398, 14854.183019934007, 15048.774268386202, 15050.623734406727, 15372.615684607526, 10324.174066074545, 10685.626003089854, 10704.197185210984, 11112.444377781241, 11158.0, 10877.564897404332, 11221.438928053727, 11952.402379880798, 12975.610731678065, 13642.727145456836], "value": 7818.202622788215, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1555.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 113.0, 110.0, 109.0, 110.0, 112.0, 116.0, 112.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 121.0, 123.0, 166.0, 125.0, 122.0, 124.0, 122.0, 143.0, 122.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 166.0, 166.0, 163.0, 163.0, 166.0, 171.0, 168.0, 172.0, 163.0], "value": 166.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 115.0, 75.0, 73.0, 78.0, 74.0, 108.0, 74.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 106.0, 83.0, 83.0, 81.0, 93.0, 85.0, 84.0, 103.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 113.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 150.0, 150.0, 151.0, 150.0, 150.0, 150.0, 153.0, 152.0], "value": 150.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 99.0, 95.0, 97.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 53.0, 55.0, 57.0, 53.0, 55.0, 54.0, 57.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 56.0, 55.0, 55.0, 55.0, 56.0, 55.0, 56.0, 55.0], "value": 55.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 88.0, 87.0, 95.0, 87.0, 90.0, 141.0, 89.0], "value": 93.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 157.0, 137.0, 130.0, 131.0, 142.0, 131.0, 132.0, 146.0, 133.0], "value": 138.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 208.0, 199.0, 199.0, 193.0, 207.0, 215.0, 197.0, 202.0, 192.0], "value": 199.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 86.0, 73.0, 76.0, 72.0, 73.0, 73.0, 74.0, 75.0, 70.0], "value": 75.0, "unit": "score"}]}]} 09:33:39 INFO - Return code: 0 09:33:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:33:39 INFO - # TBPL SUCCESS # 09:33:39 INFO - Running post-action listener: _resource_record_post_action 09:33:39 INFO - [mozharness: 2017-06-05 16:33:39.673931Z] Finished run-tests step (success) 09:33:39 INFO - Running post-run listener: _resource_record_post_run 09:33:39 INFO - Total resource usage - Wall time: 1252s; CPU: 13.0%; Read bytes: 40960; Write bytes: 439934976; Read time: 36; Write time: 286660 09:33:39 INFO - TinderboxPrint: CPU usage
12.6% 09:33:39 INFO - TinderboxPrint: I/O read bytes / time
40,960 / 36 09:33:39 INFO - TinderboxPrint: I/O write bytes / time
439,934,976 / 286,660 09:33:39 INFO - TinderboxPrint: CPU idle
8,727.8 (87.3%) 09:33:39 INFO - TinderboxPrint: CPU user
1,210.1 (12.1%) 09:33:39 INFO - TinderboxPrint: Swap in / out
0 / 0 09:33:39 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 115916800; Read time: 0; Write time: 80308 09:33:39 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:33:39 INFO - run-tests - Wall time: 1235s; CPU: 13.0%; Read bytes: 36864; Write bytes: 323833856; Read time: 32; Write time: 206352 09:33:40 INFO - Running post-run listener: _upload_blobber_files 09:33:40 INFO - Blob upload gear active. 09:33:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:33:40 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 09:33:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:33:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:33:40 INFO - (blobuploader) - INFO - Open directory for files ... 09:33:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:33:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:33:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:41 INFO - (blobuploader) - INFO - Done attempting. 09:33:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:33:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:33:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:42 INFO - (blobuploader) - INFO - Done attempting. 09:33:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:33:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:33:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:43 INFO - (blobuploader) - INFO - Done attempting. 09:33:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:33:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:33:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:47 INFO - (blobuploader) - INFO - Done attempting. 09:33:47 INFO - (blobuploader) - INFO - Iteration through files over. 09:33:47 INFO - Return code: 0 09:33:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:33:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:33:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3bb7ea8eb848d9a505ffa8649fe382fb28f58f0fb26023173ff84a5dfd59c463137e60f31097f6eedad72ecef185068234db3b1ff6cd8b3a58a49fc41603f9cd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4438ceec55365f20d91161b086e797fe0f6158dee50c6b0bd32dd6eba959f96b25091f66c43508f60f9722b126bed27a9372e12b72bedade2be426cb9e3dd5f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9324b455270986581581a2a6a449dff99de5c14aee3b8d307a45600dbedc3adbe42485d828208c934cfc025dbc81770210ddeef6cdb573a059f2056870dbbd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/44b0a4d6a1d1452629b0a513904256526420d192979337d750d958cb5dd39dc92136b043ac453a993b8ddb4c20b75d9a0016c846f69950b7d62a6cbc250a4695"} 09:33:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:33:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:33:47 INFO - Contents: 09:33:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3bb7ea8eb848d9a505ffa8649fe382fb28f58f0fb26023173ff84a5dfd59c463137e60f31097f6eedad72ecef185068234db3b1ff6cd8b3a58a49fc41603f9cd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4438ceec55365f20d91161b086e797fe0f6158dee50c6b0bd32dd6eba959f96b25091f66c43508f60f9722b126bed27a9372e12b72bedade2be426cb9e3dd5f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9324b455270986581581a2a6a449dff99de5c14aee3b8d307a45600dbedc3adbe42485d828208c934cfc025dbc81770210ddeef6cdb573a059f2056870dbbd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/44b0a4d6a1d1452629b0a513904256526420d192979337d750d958cb5dd39dc92136b043ac453a993b8ddb4c20b75d9a0016c846f69950b7d62a6cbc250a4695"} 09:33:47 INFO - Running post-run listener: copy_logs_to_upload_dir 09:33:47 INFO - Copying logs to upload dir... 09:33:47 INFO - mkdir: /builds/slave/test/build/upload/logs 09:33:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1310.175240 ========= master_lag: -0.64 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 49 secs) (at 2017-06-05 09:33:47.227368) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 09:33:47.239072) ========= 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=523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044 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-Non-PGO/sha512/3bb7ea8eb848d9a505ffa8649fe382fb28f58f0fb26023173ff84a5dfd59c463137e60f31097f6eedad72ecef185068234db3b1ff6cd8b3a58a49fc41603f9cd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4438ceec55365f20d91161b086e797fe0f6158dee50c6b0bd32dd6eba959f96b25091f66c43508f60f9722b126bed27a9372e12b72bedade2be426cb9e3dd5f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9324b455270986581581a2a6a449dff99de5c14aee3b8d307a45600dbedc3adbe42485d828208c934cfc025dbc81770210ddeef6cdb573a059f2056870dbbd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/44b0a4d6a1d1452629b0a513904256526420d192979337d750d958cb5dd39dc92136b043ac453a993b8ddb4c20b75d9a0016c846f69950b7d62a6cbc250a4695"} build_url:https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011162 build_url: 'https://queue.taskcluster.net/v1/task/N4t0oD4YRD-pSxxulKq4Qw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3bb7ea8eb848d9a505ffa8649fe382fb28f58f0fb26023173ff84a5dfd59c463137e60f31097f6eedad72ecef185068234db3b1ff6cd8b3a58a49fc41603f9cd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4438ceec55365f20d91161b086e797fe0f6158dee50c6b0bd32dd6eba959f96b25091f66c43508f60f9722b126bed27a9372e12b72bedade2be426cb9e3dd5f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9324b455270986581581a2a6a449dff99de5c14aee3b8d307a45600dbedc3adbe42485d828208c934cfc025dbc81770210ddeef6cdb573a059f2056870dbbd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/44b0a4d6a1d1452629b0a513904256526420d192979337d750d958cb5dd39dc92136b043ac453a993b8ddb4c20b75d9a0016c846f69950b7d62a6cbc250a4695"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 09:33:47.268416) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 09:33:47.268757) ========= 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=523ca41ca1e0623de333ad52000001bf-1496679115.459143-1284758044 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004421 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 09:33:47.347384) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 09:33:47.350716) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 09:33:47.351013) ========= ========= Total master_lag: -0.49 =========