builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-066 starttime: 1495457846.92 results: success (0) revision: 5c8364be9b424103db60a00a8105f0ca8928d3f3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 05:57:26.924275) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 05:57:26.924758) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 05:57:26.925037) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 05:57:26.973340) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 05:57:26.973675) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-22 05:57:29-- 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% 87.1M=0s 2017-05-22 05:57:29 (87.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090340 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 05:57:27.078144) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 05:57:27.078448) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018532 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 05:57:27.128754) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 05:57:27.129057) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5c8364be9b424103db60a00a8105f0ca8928d3f3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5c8364be9b424103db60a00a8105f0ca8928d3f3 --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=82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-22 05:57:29,883 truncating revision to first 12 chars 2017-05-22 05:57:29,883 Setting DEBUG logging. 2017-05-22 05:57:29,883 attempt 1/10 2017-05-22 05:57:29,883 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/5c8364be9b42?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-22 05:57:30,198 unpacking tar archive at: mozilla-beta-5c8364be9b42/testing/mozharness/ program finished with exit code 0 elapsedTime=0.700372 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 05:57:27.841579) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 05:57:27.841894) ========= script_repo_revision: 5c8364be9b424103db60a00a8105f0ca8928d3f3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 05:57:27.842249) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 05:57:27.842526) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 05:57:27.849424) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-05-22 05:57:27.849684) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-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=82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:57:30 INFO - MultiFileLogger online at 20170522 05:57:30 in /builds/slave/test 05:57:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 05:57:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:57:30 INFO - {'append_to_log': False, 05:57:30 INFO - 'base_work_dir': '/builds/slave/test', 05:57:30 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 05:57:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:57:30 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 05:57:30 INFO - 'buildbot_json_path': 'buildprops.json', 05:57:30 INFO - 'config_files': ('talos/linux_config.py',), 05:57:30 INFO - 'default_actions': ('clobber', 05:57:30 INFO - 'read-buildbot-config', 05:57:30 INFO - 'download-and-extract', 05:57:30 INFO - 'populate-webroot', 05:57:30 INFO - 'create-virtualenv', 05:57:30 INFO - 'install', 05:57:30 INFO - 'run-tests'), 05:57:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:57:30 INFO - 'download_minidump_stackwalk': True, 05:57:30 INFO - 'download_symbols': 'ondemand', 05:57:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:57:30 INFO - 'tooltool.py': '/tools/tooltool.py', 05:57:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:57:30 INFO - '/tools/misc-python/virtualenv.py')}, 05:57:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:57:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:57:30 INFO - 'gecko_profile': False, 05:57:30 INFO - 'gecko_profile_interval': 0, 05:57:30 INFO - 'installer_path': 'installer.exe', 05:57:30 INFO - 'log_level': 'info', 05:57:30 INFO - 'log_name': 'talos', 05:57:30 INFO - 'log_to_console': True, 05:57:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:57:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:57:30 INFO - 'opt_config_files': (), 05:57:30 INFO - 'pip_index': False, 05:57:30 INFO - 'suite': 'dromaeojs', 05:57:30 INFO - 'system_bits': '32', 05:57:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:57:30 INFO - 'title': 'talos-linux64-ix-066', 05:57:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:57:30 INFO - 'use_talos_json': True, 05:57:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:57:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:57:30 INFO - 'work_dir': 'build'} 05:57:30 INFO - [mozharness: 2017-05-22 12:57:30.703359Z] Running clobber step. 05:57:30 INFO - Running pre-action listener: _resource_record_pre_action 05:57:30 INFO - Running main action method: clobber 05:57:30 INFO - rmtree: /builds/slave/test/build 05:57:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:57:32 INFO - Running post-action listener: _resource_record_post_action 05:57:32 INFO - [mozharness: 2017-05-22 12:57:32.253446Z] Finished clobber step (success) 05:57:32 INFO - [mozharness: 2017-05-22 12:57:32.253545Z] Running read-buildbot-config step. 05:57:32 INFO - Running pre-action listener: _resource_record_pre_action 05:57:32 INFO - Running main action method: read_buildbot_config 05:57:32 INFO - Using buildbot properties: 05:57:32 INFO - { 05:57:32 INFO - "project": "", 05:57:32 INFO - "product": "firefox", 05:57:32 INFO - "who": "ffxbld@noreply.mozilla.org", 05:57:32 INFO - "installer_path": "public/build/target.tar.bz2", 05:57:32 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 05:57:32 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 05:57:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 05:57:32 INFO - "stage_platform": "linux64", 05:57:32 INFO - "basedir": "/builds/slave/test", 05:57:32 INFO - "buildnumber": 51, 05:57:32 INFO - "platform": "ubuntu64_hw", 05:57:32 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:57:32 INFO - "slavebuilddir": "test", 05:57:32 INFO - "taskId": "EXU0yKaBSkuWm1Hy5ogJXA", 05:57:32 INFO - "branch": "mozilla-beta", 05:57:32 INFO - "script_repo_revision": "production", 05:57:32 INFO - "revision": "5c8364be9b424103db60a00a8105f0ca8928d3f3", 05:57:32 INFO - "slavename": "talos-linux64-ix-066", 05:57:32 INFO - "repo_path": "releases/mozilla-beta" 05:57:32 INFO - } 05:57:32 INFO - Finding installer, test and symbols from parent task. 05:57:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EXU0yKaBSkuWm1Hy5ogJXA'}, attempt #1 05:57:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EXU0yKaBSkuWm1Hy5ogJXA'}, attempt #1 05:57:33 INFO - Task dependencies: Rw1X2GC9T5616dIjwRqBgw 05:57:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw'}, attempt #1 05:57:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.tar.bz2 05:57:33 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/test_packages.json 05:57:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.crashreporter-symbols.zip 05:57:33 INFO - Running post-action listener: _resource_record_post_action 05:57:33 INFO - [mozharness: 2017-05-22 12:57:33.612003Z] Finished read-buildbot-config step (success) 05:57:33 INFO - [mozharness: 2017-05-22 12:57:33.612221Z] Running download-and-extract step. 05:57:33 INFO - Running pre-action listener: _resource_record_pre_action 05:57:33 INFO - Running main action method: download_and_extract 05:57:33 INFO - mkdir: /builds/slave/test/build/tests 05:57:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:33 INFO - trying https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.test_packages.json 05:57:33 INFO - Downloading https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:57:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:57:34 INFO - Downloaded 986 bytes. 05:57:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:57:34 INFO - Using the following test package requirements: 05:57:34 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:57:34 INFO - u'common': [u'target.common.tests.zip'], 05:57:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:57:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:57:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:57:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:57:34 INFO - u'mozbase': [u'target.common.tests.zip'], 05:57:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:57:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:57:34 INFO - u'web-platform': [u'target.common.tests.zip', 05:57:34 INFO - u'target.web-platform.tests.zip'], 05:57:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:57:34 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:57:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.common.tests.zip 05:57:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:57:34 INFO - Fetch https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.common.tests.zip into memory 05:57:35 INFO - Expected file size: 59712358 05:57:35 INFO - Obtained file size: 59712358 05:57:45 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:57:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.common.tests.zip 05:57:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:57:45 INFO - Fetch https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.common.tests.zip into memory 05:57:46 INFO - Expected file size: 59712358 05:57:46 INFO - Obtained file size: 59712358 05:57:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.talos.tests.zip 05:57:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:57:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.talos.tests.zip into memory 05:57:57 INFO - Expected file size: 14404449 05:57:57 INFO - Obtained file size: 14404449 05:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:57 INFO - trying https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.tar.bz2 05:57:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:57:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:57:58 INFO - Downloaded 59659185 bytes. 05:57:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.tar.bz2 05:57:58 INFO - mkdir: /builds/slave/test/properties 05:57:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:57:58 INFO - Writing to file /builds/slave/test/properties/build_url 05:57:58 INFO - Contents: 05:57:58 INFO - build_url:https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.tar.bz2 05:57:58 INFO - Running post-action listener: _resource_record_post_action 05:57:58 INFO - Running post-action listener: set_extra_try_arguments 05:57:58 INFO - [mozharness: 2017-05-22 12:57:58.835965Z] Finished download-and-extract step (success) 05:57:58 INFO - [mozharness: 2017-05-22 12:57:58.836062Z] Running populate-webroot step. 05:57:58 INFO - Running pre-action listener: _resource_record_pre_action 05:57:58 INFO - Running main action method: populate_webroot 05:57:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:57:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:57:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:57:58 INFO - '--tppagecycles', 05:57:58 INFO - '7'], 05:57:58 INFO - 'tests': ['tsvgm']}}, 05:57:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:57:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:57:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:57:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:57:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:57:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:57:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:57:58 INFO - 'talos_options': ['--disable-e10s'], 05:57:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:57:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:57:58 INFO - 'talos_options': ['--disable-e10s'], 05:57:58 INFO - 'tests': ['damp', 'tps']}, 05:57:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:57:58 INFO - 'tests': ['damp', 'tps']}, 05:57:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:57:58 INFO - 'tests': ['dromaeo_dom']}, 05:57:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:57:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:57:58 INFO - 'tests': ['basic_compositor_video']}, 05:57:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:57:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:57:58 INFO - 'tests': ['a11yr', 05:57:58 INFO - 'ts_paint', 05:57:58 INFO - 'tpaint', 05:57:58 INFO - 'sessionrestore', 05:57:58 INFO - 'sessionrestore_no_auto_restore', 05:57:58 INFO - 'tabpaint']}, 05:57:58 INFO - 'other-e10s': {'tests': ['a11yr', 05:57:58 INFO - 'ts_paint', 05:57:58 INFO - 'tpaint', 05:57:58 INFO - 'sessionrestore', 05:57:58 INFO - 'sessionrestore_no_auto_restore', 05:57:58 INFO - 'tabpaint']}, 05:57:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:57:58 INFO - 'ts_paint', 05:57:58 INFO - 'tpaint', 05:57:58 INFO - 'sessionrestore', 05:57:58 INFO - 'sessionrestore_no_auto_restore', 05:57:58 INFO - 'tabpaint']}, 05:57:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:57:58 INFO - 'ts_paint', 05:57:58 INFO - 'tpaint', 05:57:58 INFO - 'sessionrestore', 05:57:58 INFO - 'sessionrestore_no_auto_restore', 05:57:58 INFO - 'tabpaint']}, 05:57:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:57:58 INFO - 'tests': ['a11yr', 05:57:58 INFO - 'ts_paint', 05:57:58 INFO - 'tpaint', 05:57:58 INFO - 'sessionrestore', 05:57:58 INFO - 'sessionrestore_no_auto_restore', 05:57:58 INFO - 'tabpaint']}, 05:57:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:57:58 INFO - 'tests': ['a11yr', 05:57:58 INFO - 'ts_paint', 05:57:58 INFO - 'tpaint', 05:57:58 INFO - 'sessionrestore', 05:57:58 INFO - 'sessionrestore_no_auto_restore', 05:57:58 INFO - 'tabpaint']}, 05:57:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:57:58 INFO - 'tests': ['tsvgx', 05:57:58 INFO - 'tsvgr_opacity', 05:57:58 INFO - 'tart', 05:57:58 INFO - 'tscrollx', 05:57:58 INFO - 'cart', 05:57:58 INFO - 'tsvg_static']}, 05:57:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:57:58 INFO - 'tsvgr_opacity', 05:57:58 INFO - 'tart', 05:57:58 INFO - 'tscrollx', 05:57:58 INFO - 'cart', 05:57:58 INFO - 'tsvg_static']}, 05:57:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:57:58 INFO - 'talos_options': ['--disable-e10s'], 05:57:58 INFO - 'tests': ['tp5o']}, 05:57:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:57:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:57:58 INFO - 'talos_options': ['--disable-e10s', 05:57:58 INFO - '--xperf_path', 05:57:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:58 INFO - 'tests': ['tp5n']}, 05:57:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:57:58 INFO - 'talos_options': ['--xperf_path', 05:57:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:58 INFO - 'tests': ['tp5n']}}, 05:57:58 INFO - 'talos.zip': {'path': '', 05:57:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:57:58 INFO - Running post-action listener: _resource_record_post_action 05:57:58 INFO - [mozharness: 2017-05-22 12:57:58.849172Z] Finished populate-webroot step (success) 05:57:58 INFO - [mozharness: 2017-05-22 12:57:58.849263Z] Running create-virtualenv step. 05:57:58 INFO - Running pre-action listener: _resource_record_pre_action 05:57:58 INFO - Running main action method: create_virtualenv 05:57:58 INFO - Creating virtualenv /builds/slave/test/build/venv 05:57:58 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 05:57:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:57:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:57:58 INFO - Using real prefix '/usr' 05:57:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:57:59 INFO - Installing distribute.............................................................................................................................................................................................done. 05:58:01 INFO - Installing pip.................done. 05:58:01 INFO - Return code: 0 05:58:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:58:01 INFO - Reading from file tmpfile_stdout 05:58:01 INFO - Output received: 05:58:01 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) 05:58:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:01 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 0x183f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x187da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1add310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ada400>, '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 0x1adb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adb840>, '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': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:58:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:58:01 INFO - Using env: {'DISPLAY': ':0', 05:58:01 INFO - 'HOME': '/home/cltbld', 05:58:01 INFO - 'LANG': 'en_US.UTF-8', 05:58:01 INFO - 'LANGUAGE': 'en_US:en', 05:58:01 INFO - 'LOGNAME': 'cltbld', 05:58:01 INFO - 'MAIL': '/var/mail/cltbld', 05:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:01 INFO - 'MOZ_NO_REMOTE': '1', 05:58:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:01 INFO - 'NO_EM_RESTART': '1', 05:58:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:01 INFO - 'PWD': '/builds/slave/test', 05:58:01 INFO - 'SHELL': '/bin/bash', 05:58:01 INFO - 'SHLVL': '1', 05:58:01 INFO - 'TERM': 'linux', 05:58:01 INFO - 'TMOUT': '86400', 05:58:01 INFO - 'USER': 'cltbld', 05:58:01 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 05:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:01 INFO - '_': '/tools/buildbot/bin/python'} 05:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:01 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 05:58:01 INFO - Cleaning up... 05:58:01 INFO - Return code: 0 05:58:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:01 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 0x183f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x187da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1add310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ada400>, '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 0x1adb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adb840>, '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': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:58:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:58:01 INFO - Using env: {'DISPLAY': ':0', 05:58:01 INFO - 'HOME': '/home/cltbld', 05:58:01 INFO - 'LANG': 'en_US.UTF-8', 05:58:01 INFO - 'LANGUAGE': 'en_US:en', 05:58:01 INFO - 'LOGNAME': 'cltbld', 05:58:01 INFO - 'MAIL': '/var/mail/cltbld', 05:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:01 INFO - 'MOZ_NO_REMOTE': '1', 05:58:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:01 INFO - 'NO_EM_RESTART': '1', 05:58:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:01 INFO - 'PWD': '/builds/slave/test', 05:58:01 INFO - 'SHELL': '/bin/bash', 05:58:01 INFO - 'SHLVL': '1', 05:58:01 INFO - 'TERM': 'linux', 05:58:01 INFO - 'TMOUT': '86400', 05:58:01 INFO - 'USER': 'cltbld', 05:58:01 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 05:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:01 INFO - '_': '/tools/buildbot/bin/python'} 05:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:02 INFO - Downloading/unpacking psutil>=3.1.1 05:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:58:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:58:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:58:03 INFO - Installing collected packages: psutil 05:58:03 INFO - Running setup.py install for psutil 05:58:03 INFO - building 'psutil._psutil_linux' extension 05:58:03 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:58:03 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:58:03 INFO - building 'psutil._psutil_posix' extension 05:58:03 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:58:03 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:58:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:58:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:58:03 INFO - Successfully installed psutil 05:58:03 INFO - Cleaning up... 05:58:03 INFO - Return code: 0 05:58:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x187da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1add310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ada400>, '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 0x1adb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adb840>, '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': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:58:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:58:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:58:03 INFO - Using env: {'DISPLAY': ':0', 05:58:03 INFO - 'HOME': '/home/cltbld', 05:58:03 INFO - 'LANG': 'en_US.UTF-8', 05:58:03 INFO - 'LANGUAGE': 'en_US:en', 05:58:03 INFO - 'LOGNAME': 'cltbld', 05:58:03 INFO - 'MAIL': '/var/mail/cltbld', 05:58:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:03 INFO - 'MOZ_NO_REMOTE': '1', 05:58:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:03 INFO - 'NO_EM_RESTART': '1', 05:58:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:03 INFO - 'PWD': '/builds/slave/test', 05:58:03 INFO - 'SHELL': '/bin/bash', 05:58:03 INFO - 'SHLVL': '1', 05:58:03 INFO - 'TERM': 'linux', 05:58:03 INFO - 'TMOUT': '86400', 05:58:03 INFO - 'USER': 'cltbld', 05:58:03 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 05:58:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:03 INFO - '_': '/tools/buildbot/bin/python'} 05:58:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:58:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:58:05 INFO - Installing collected packages: mozsystemmonitor 05:58:05 INFO - Running setup.py install for mozsystemmonitor 05:58:05 INFO - Successfully installed mozsystemmonitor 05:58:05 INFO - Cleaning up... 05:58:05 INFO - Return code: 0 05:58:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:58:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x183f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x187da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1add310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ada400>, '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 0x1adb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adb840>, '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': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:58:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:58:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:58:05 INFO - Using env: {'DISPLAY': ':0', 05:58:05 INFO - 'HOME': '/home/cltbld', 05:58:05 INFO - 'LANG': 'en_US.UTF-8', 05:58:05 INFO - 'LANGUAGE': 'en_US:en', 05:58:05 INFO - 'LOGNAME': 'cltbld', 05:58:05 INFO - 'MAIL': '/var/mail/cltbld', 05:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:05 INFO - 'MOZ_NO_REMOTE': '1', 05:58:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:05 INFO - 'NO_EM_RESTART': '1', 05:58:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:05 INFO - 'PWD': '/builds/slave/test', 05:58:05 INFO - 'SHELL': '/bin/bash', 05:58:05 INFO - 'SHLVL': '1', 05:58:05 INFO - 'TERM': 'linux', 05:58:05 INFO - 'TMOUT': '86400', 05:58:05 INFO - 'USER': 'cltbld', 05:58:05 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 05:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:05 INFO - '_': '/tools/buildbot/bin/python'} 05:58:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:05 INFO - Downloading/unpacking jsonschema==2.5.1 05:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:58:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:06 INFO - Downloading functools32-3.2.3-2.tar.gz 05:58:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:58:06 INFO - warning: no files found matching '*.txt' 05:58:06 INFO - no previously-included directories found matching 'build' 05:58:06 INFO - no previously-included directories found matching 'dist' 05:58:06 INFO - no previously-included directories found matching '.git*' 05:58:06 INFO - Installing collected packages: jsonschema, functools32 05:58:06 INFO - Running setup.py install for functools32 05:58:06 INFO - warning: no files found matching '*.txt' 05:58:06 INFO - no previously-included directories found matching 'build' 05:58:06 INFO - no previously-included directories found matching 'dist' 05:58:06 INFO - no previously-included directories found matching '.git*' 05:58:06 INFO - Successfully installed jsonschema functools32 05:58:06 INFO - Cleaning up... 05:58:06 INFO - Return code: 0 05:58:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x187da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1add310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ada400>, '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 0x1adb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adb840>, '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': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:58:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:58:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:58:06 INFO - Using env: {'DISPLAY': ':0', 05:58:06 INFO - 'HOME': '/home/cltbld', 05:58:06 INFO - 'LANG': 'en_US.UTF-8', 05:58:06 INFO - 'LANGUAGE': 'en_US:en', 05:58:06 INFO - 'LOGNAME': 'cltbld', 05:58:06 INFO - 'MAIL': '/var/mail/cltbld', 05:58:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:06 INFO - 'MOZ_NO_REMOTE': '1', 05:58:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:06 INFO - 'NO_EM_RESTART': '1', 05:58:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:06 INFO - 'PWD': '/builds/slave/test', 05:58:06 INFO - 'SHELL': '/bin/bash', 05:58:06 INFO - 'SHLVL': '1', 05:58:06 INFO - 'TERM': 'linux', 05:58:06 INFO - 'TMOUT': '86400', 05:58:06 INFO - 'USER': 'cltbld', 05:58:06 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 05:58:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:06 INFO - '_': '/tools/buildbot/bin/python'} 05:58:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:58:07 INFO - Cleaning up... 05:58:07 INFO - Return code: 0 05:58:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:58:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:07 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 0x183f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x187da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1add310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ada400>, '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 0x1adb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adb840>, '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': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:58:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:58:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:58:07 INFO - Using env: {'DISPLAY': ':0', 05:58:07 INFO - 'HOME': '/home/cltbld', 05:58:07 INFO - 'LANG': 'en_US.UTF-8', 05:58:07 INFO - 'LANGUAGE': 'en_US:en', 05:58:07 INFO - 'LOGNAME': 'cltbld', 05:58:07 INFO - 'MAIL': '/var/mail/cltbld', 05:58:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:07 INFO - 'MOZ_NO_REMOTE': '1', 05:58:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:07 INFO - 'NO_EM_RESTART': '1', 05:58:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:07 INFO - 'PWD': '/builds/slave/test', 05:58:07 INFO - 'SHELL': '/bin/bash', 05:58:07 INFO - 'SHLVL': '1', 05:58:07 INFO - 'TERM': 'linux', 05:58:07 INFO - 'TMOUT': '86400', 05:58:07 INFO - 'USER': 'cltbld', 05:58:07 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 05:58:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:07 INFO - '_': '/tools/buildbot/bin/python'} 05:58:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:07 INFO - Downloading/unpacking blobuploader==1.2.4 05:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:08 INFO - Downloading blobuploader-1.2.4.tar.gz 05:58:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:58:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:58:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:09 INFO - Downloading docopt-0.6.1.tar.gz 05:58:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:58:09 INFO - Installing collected packages: blobuploader, requests, docopt 05:58:09 INFO - Running setup.py install for blobuploader 05:58:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:58:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:58:09 INFO - Running setup.py install for requests 05:58:09 INFO - Running setup.py install for docopt 05:58:09 INFO - Successfully installed blobuploader requests docopt 05:58:09 INFO - Cleaning up... 05:58:09 INFO - Return code: 0 05:58:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:58:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:09 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 0x183f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x187da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1add310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ada400>, '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 0x1adb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adb840>, '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': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:58:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:58:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:58:09 INFO - Using env: {'DISPLAY': ':0', 05:58:09 INFO - 'HOME': '/home/cltbld', 05:58:09 INFO - 'LANG': 'en_US.UTF-8', 05:58:09 INFO - 'LANGUAGE': 'en_US:en', 05:58:09 INFO - 'LOGNAME': 'cltbld', 05:58:09 INFO - 'MAIL': '/var/mail/cltbld', 05:58:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:09 INFO - 'MOZ_NO_REMOTE': '1', 05:58:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:09 INFO - 'NO_EM_RESTART': '1', 05:58:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:09 INFO - 'PWD': '/builds/slave/test', 05:58:09 INFO - 'SHELL': '/bin/bash', 05:58:09 INFO - 'SHLVL': '1', 05:58:09 INFO - 'TERM': 'linux', 05:58:09 INFO - 'TMOUT': '86400', 05:58:09 INFO - 'USER': 'cltbld', 05:58:09 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 05:58:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:09 INFO - '_': '/tools/buildbot/bin/python'} 05:58:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:58:09 INFO - Running setup.py (path:/tmp/pip-EfepxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:58:09 INFO - Running setup.py (path:/tmp/pip-QGwW55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:58:10 INFO - Running setup.py (path:/tmp/pip-Y0CbpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:58:10 INFO - Running setup.py (path:/tmp/pip-4oeLja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:58:10 INFO - Running setup.py (path:/tmp/pip-rfYWpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:58:10 INFO - Running setup.py (path:/tmp/pip-BRjGRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:58:10 INFO - Running setup.py (path:/tmp/pip-aqT9Ap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:58:10 INFO - Running setup.py (path:/tmp/pip-MS2q9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:58:10 INFO - Running setup.py (path:/tmp/pip-PIVu5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:58:10 INFO - Running setup.py (path:/tmp/pip-CZgKnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:58:10 INFO - Running setup.py (path:/tmp/pip-bHgkVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:58:10 INFO - Running setup.py (path:/tmp/pip-zJiiuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:58:10 INFO - Running setup.py (path:/tmp/pip-o6BcuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:58:10 INFO - Running setup.py (path:/tmp/pip-QK12za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:10 INFO - Running setup.py (path:/tmp/pip-__Xhct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:58:10 INFO - Running setup.py (path:/tmp/pip-_2Nxyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:58:11 INFO - Running setup.py (path:/tmp/pip-IfQk4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:58:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:58:11 INFO - Running setup.py install for manifestparser 05:58:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:58:11 INFO - Running setup.py install for mozcrash 05:58:11 INFO - Running setup.py install for mozdebug 05:58:11 INFO - Running setup.py install for mozdevice 05:58:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:58:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:58:11 INFO - Running setup.py install for mozfile 05:58:11 INFO - Running setup.py install for mozhttpd 05:58:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:58:11 INFO - Running setup.py install for mozinfo 05:58:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:58:11 INFO - Running setup.py install for mozInstall 05:58:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:58:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:58:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:58:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:58:11 INFO - Running setup.py install for mozleak 05:58:12 INFO - Running setup.py install for mozlog 05:58:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:58:12 INFO - Running setup.py install for moznetwork 05:58:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:58:12 INFO - Running setup.py install for mozprocess 05:58:12 INFO - Running setup.py install for mozprofile 05:58:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:58:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:58:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:58:12 INFO - Running setup.py install for mozrunner 05:58:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:58:12 INFO - Running setup.py install for mozscreenshot 05:58:12 INFO - Running setup.py install for moztest 05:58:12 INFO - Running setup.py install for mozversion 05:58:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:58:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:58:12 INFO - Cleaning up... 05:58:12 INFO - Return code: 0 05:58:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/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 0x183f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x187da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1add310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ada400>, '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 0x1adb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adb840>, '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': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:58:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:58:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:58:12 INFO - Using env: {'DISPLAY': ':0', 05:58:12 INFO - 'HOME': '/home/cltbld', 05:58:12 INFO - 'LANG': 'en_US.UTF-8', 05:58:12 INFO - 'LANGUAGE': 'en_US:en', 05:58:12 INFO - 'LOGNAME': 'cltbld', 05:58:12 INFO - 'MAIL': '/var/mail/cltbld', 05:58:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:12 INFO - 'MOZ_NO_REMOTE': '1', 05:58:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:12 INFO - 'NO_EM_RESTART': '1', 05:58:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:12 INFO - 'PWD': '/builds/slave/test', 05:58:12 INFO - 'SHELL': '/bin/bash', 05:58:12 INFO - 'SHLVL': '1', 05:58:12 INFO - 'TERM': 'linux', 05:58:12 INFO - 'TMOUT': '86400', 05:58:12 INFO - 'USER': 'cltbld', 05:58:12 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 05:58:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:12 INFO - '_': '/tools/buildbot/bin/python'} 05:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:58:13 INFO - Running setup.py (path:/tmp/pip-3cn0pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:58:13 INFO - Running setup.py (path:/tmp/pip-6uKs_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:58:13 INFO - Running setup.py (path:/tmp/pip-wEHlCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:58:13 INFO - Running setup.py (path:/tmp/pip-gFu_Eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:58:13 INFO - Running setup.py (path:/tmp/pip-IJ1FXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:58:13 INFO - Running setup.py (path:/tmp/pip-otgKWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:58:13 INFO - Running setup.py (path:/tmp/pip-IywIx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:58:13 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)) 05:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:58:13 INFO - Running setup.py (path:/tmp/pip-MucN3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:58:13 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)) 05:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:58:13 INFO - Running setup.py (path:/tmp/pip-CzJJLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:58:13 INFO - Running setup.py (path:/tmp/pip-2Hne5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:58:13 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)) 05:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:58:13 INFO - Running setup.py (path:/tmp/pip-ChX0uX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:58:13 INFO - Running setup.py (path:/tmp/pip-itF4iA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:58:14 INFO - Running setup.py (path:/tmp/pip-DdkjKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:58:14 INFO - Running setup.py (path:/tmp/pip-DylzKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:14 INFO - Running setup.py (path:/tmp/pip-54jRA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:58:14 INFO - Running setup.py (path:/tmp/pip-5BAOPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:58:14 INFO - Running setup.py (path:/tmp/pip-m4ctAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:58:14 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:58:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:15 INFO - Downloading blessings-1.6.tar.gz 05:58:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:58:15 INFO - Installing collected packages: blessings 05:58:15 INFO - Running setup.py install for blessings 05:58:15 INFO - Successfully installed blessings 05:58:15 INFO - Cleaning up... 05:58:15 INFO - Return code: 0 05:58:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:58:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:58:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:58:15 INFO - Reading from file tmpfile_stdout 05:58:15 INFO - Current package versions: 05:58:15 INFO - argparse == 1.2.1 05:58:15 INFO - blessings == 1.6 05:58:15 INFO - blobuploader == 1.2.4 05:58:15 INFO - docopt == 0.6.1 05:58:15 INFO - functools32 == 3.2.3-2 05:58:15 INFO - jsonschema == 2.5.1 05:58:15 INFO - manifestparser == 1.1 05:58:15 INFO - mozInstall == 1.12 05:58:15 INFO - mozcrash == 1.0 05:58:15 INFO - mozdebug == 0.1 05:58:15 INFO - mozdevice == 0.49 05:58:15 INFO - mozfile == 1.2 05:58:15 INFO - mozhttpd == 0.7 05:58:15 INFO - mozinfo == 0.9 05:58:15 INFO - mozleak == 0.1 05:58:15 INFO - mozlog == 3.4 05:58:15 INFO - moznetwork == 0.27 05:58:15 INFO - mozprocess == 0.25 05:58:15 INFO - mozprofile == 0.28 05:58:15 INFO - mozrunner == 6.13 05:58:15 INFO - mozscreenshot == 0.1 05:58:15 INFO - mozsystemmonitor == 0.3 05:58:15 INFO - moztest == 0.8 05:58:15 INFO - mozversion == 1.4 05:58:15 INFO - psutil == 3.1.1 05:58:15 INFO - requests == 1.2.3 05:58:15 INFO - wsgiref == 0.1.2 05:58:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:58:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x187da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1add310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ada400>, '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 0x1adb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adb840>, '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': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:58:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:58:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:58:15 INFO - Using env: {'DISPLAY': ':0', 05:58:15 INFO - 'HOME': '/home/cltbld', 05:58:15 INFO - 'LANG': 'en_US.UTF-8', 05:58:15 INFO - 'LANGUAGE': 'en_US:en', 05:58:15 INFO - 'LOGNAME': 'cltbld', 05:58:15 INFO - 'MAIL': '/var/mail/cltbld', 05:58:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:15 INFO - 'MOZ_NO_REMOTE': '1', 05:58:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:15 INFO - 'NO_EM_RESTART': '1', 05:58:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:15 INFO - 'PWD': '/builds/slave/test', 05:58:15 INFO - 'SHELL': '/bin/bash', 05:58:15 INFO - 'SHLVL': '1', 05:58:15 INFO - 'TERM': 'linux', 05:58:15 INFO - 'TMOUT': '86400', 05:58:15 INFO - 'USER': 'cltbld', 05:58:15 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 05:58:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:15 INFO - '_': '/tools/buildbot/bin/python'} 05:58:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:58:16 INFO - Cleaning up... 05:58:16 INFO - Return code: 0 05:58:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:58:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x187da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1add310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ada400>, '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 0x1adb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adb840>, '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': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:58:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:58:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:58:16 INFO - Using env: {'DISPLAY': ':0', 05:58:16 INFO - 'HOME': '/home/cltbld', 05:58:16 INFO - 'LANG': 'en_US.UTF-8', 05:58:16 INFO - 'LANGUAGE': 'en_US:en', 05:58:16 INFO - 'LOGNAME': 'cltbld', 05:58:16 INFO - 'MAIL': '/var/mail/cltbld', 05:58:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:16 INFO - 'MOZ_NO_REMOTE': '1', 05:58:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:16 INFO - 'NO_EM_RESTART': '1', 05:58:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:16 INFO - 'PWD': '/builds/slave/test', 05:58:16 INFO - 'SHELL': '/bin/bash', 05:58:16 INFO - 'SHLVL': '1', 05:58:16 INFO - 'TERM': 'linux', 05:58:16 INFO - 'TMOUT': '86400', 05:58:16 INFO - 'USER': 'cltbld', 05:58:16 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 05:58:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:16 INFO - '_': '/tools/buildbot/bin/python'} 05:58:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:58:16 INFO - Cleaning up... 05:58:16 INFO - Return code: 0 05:58:16 INFO - Running post-action listener: _resource_record_post_action 05:58:16 INFO - Running post-action listener: _start_resource_monitoring 05:58:16 INFO - Starting resource monitoring. 05:58:16 INFO - [mozharness: 2017-05-22 12:58:16.409519Z] Finished create-virtualenv step (success) 05:58:16 INFO - [mozharness: 2017-05-22 12:58:16.409986Z] Running install step. 05:58:16 INFO - Running pre-action listener: _resource_record_pre_action 05:58:16 INFO - Running main action method: install 05:58:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:58:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:58:16 INFO - Reading from file tmpfile_stdout 05:58:16 INFO - Detecting whether we're running mozinstall >=1.0... 05:58:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:58:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:58:16 INFO - Reading from file tmpfile_stdout 05:58:16 INFO - Output received: 05:58:16 INFO - Usage: mozinstall [options] installer 05:58:16 INFO - Options: 05:58:16 INFO - -h, --help show this help message and exit 05:58:16 INFO - -d DEST, --destination=DEST 05:58:16 INFO - Directory to install application into. [default: 05:58:16 INFO - "/builds/slave/test"] 05:58:16 INFO - --app=APP Application being installed. [default: firefox] 05:58:16 INFO - mkdir: /builds/slave/test/build/application 05:58:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:58:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:58:30 INFO - Reading from file tmpfile_stdout 05:58:30 INFO - Output received: 05:58:30 INFO - /builds/slave/test/build/application/firefox/firefox 05:58:30 INFO - Running post-action listener: _resource_record_post_action 05:58:30 INFO - [mozharness: 2017-05-22 12:58:30.815699Z] Finished install step (success) 05:58:30 INFO - [mozharness: 2017-05-22 12:58:30.815941Z] Running run-tests step. 05:58:30 INFO - Running pre-action listener: _resource_record_pre_action 05:58:30 INFO - Running main action method: run_tests 05:58:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:58:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:58:30 INFO - Python 2.7.3 05:58:30 INFO - Return code: 0 05:58:30 INFO - grabbing minidump binary from tooltool 05:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:30 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 0x1ada400>, '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 0x1adb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adb840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:58:30 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 05:58:30 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 05:58:30 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 05:58:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:58:30 INFO - Return code: 0 05:58:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:58:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:58:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:58:30 INFO - ENV: RUST_BACKTRACE is now 1 05:58:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:58:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:58:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:58:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:58:30 INFO - Using env: {'DISPLAY': ':0', 05:58:30 INFO - 'HOME': '/home/cltbld', 05:58:30 INFO - 'LANG': 'en_US.UTF-8', 05:58:30 INFO - 'LANGUAGE': 'en_US:en', 05:58:30 INFO - 'LOGNAME': 'cltbld', 05:58:30 INFO - 'MAIL': '/var/mail/cltbld', 05:58:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:58:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:58:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:30 INFO - 'MOZ_NO_REMOTE': '1', 05:58:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:58:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:30 INFO - 'NO_EM_RESTART': '1', 05:58:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:30 INFO - 'PWD': '/builds/slave/test', 05:58:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:58:30 INFO - 'RUST_BACKTRACE': '1', 05:58:30 INFO - 'SHELL': '/bin/bash', 05:58:30 INFO - 'SHLVL': '1', 05:58:30 INFO - 'TERM': 'linux', 05:58:30 INFO - 'TMOUT': '86400', 05:58:30 INFO - 'USER': 'cltbld', 05:58:30 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234', 05:58:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:30 INFO - '_': '/tools/buildbot/bin/python'} 05:58:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:58:31 INFO - mozversion application_buildid: 20170522120019 05:58:31 INFO - mozversion application_changeset: 5c8364be9b424103db60a00a8105f0ca8928d3f3 05:58:31 INFO - mozversion application_display_name: Firefox 05:58:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:58:31 INFO - mozversion application_name: Firefox 05:58:31 INFO - mozversion application_remotingname: firefox 05:58:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:58:31 INFO - mozversion application_vendor: Mozilla 05:58:31 INFO - mozversion application_version: 54.0 05:58:31 INFO - mozversion platform_buildid: 20170522120019 05:58:31 INFO - mozversion platform_changeset: 5c8364be9b424103db60a00a8105f0ca8928d3f3 05:58:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:58:31 INFO - mozversion platform_version: 54.0 05:58:31 INFO - using testdate: 1495457911 05:58:31 INFO - actual date: 1495457911 05:58:31 INFO - starting webserver on 'localhost:54193' 05:58:31 INFO - SUITE-START | Running 2 tests 05:58:31 INFO - TEST-START | dromaeo_css 05:58:31 INFO - operating with platform_type : linux_ 05:58:31 INFO - Initialising browser for dromaeo_css test... 05:58:31 INFO - TEST-INFO | started process 8193 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_2XVUM/profile http://localhost:54193/getInfo.html) 05:58:35 INFO - PROCESS | 8193 | __metrics Screen width/height:1600/1200 05:58:35 INFO - PROCESS | 8193 | colorDepth:24 05:58:35 INFO - PROCESS | 8193 | Browser inner width/height: 1024/697 05:58:35 INFO - PROCESS | 8193 | __metrics 05:58:36 INFO - PROCESS | 8193 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:58:36 INFO - PROCESS | 8193 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:58:36 INFO - PROCESS | 8193 | console.error: PushService: 05:58:36 INFO - PROCESS | 8193 | stateChangeProcessEnqueue: Error transitioning state 05:58:36 INFO - PROCESS | 8193 | UnknownError 05:58:37 INFO - TEST-INFO | 8193: exit 0 05:58:37 INFO - Browser initialized. 05:58:37 INFO - Running cycle 1/1 for dromaeo_css test... 05:58:37 INFO - TEST-INFO | started process 8285 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_2XVUM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:58:49 INFO - PROCESS | 8285 | RSS: Main: 141488128 05:58:49 INFO - PROCESS | 8285 | 06:01:50 INFO - PROCESS | 8285 | Cycle 1(1): loaded http://localhost:54193/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54193/tests/dromaeo/cssquery-ext.html) 06:01:51 INFO - PROCESS | 8285 | RSS: Main: 290668544 06:01:51 INFO - PROCESS | 8285 | 06:04:53 INFO - PROCESS | 8285 | Cycle 1(1): loaded http://localhost:54193/tests/dromaeo/cssquery-ext.html (next: http://localhost:54193/tests/dromaeo/cssquery-jquery.html) 06:04:54 INFO - PROCESS | 8285 | RSS: Main: 229175296 06:04:54 INFO - PROCESS | 8285 | 06:07:56 INFO - PROCESS | 8285 | Cycle 1(1): loaded http://localhost:54193/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54193/tests/dromaeo/cssquery-mootools.html) 06:07:57 INFO - PROCESS | 8285 | RSS: Main: 172027904 06:07:57 INFO - PROCESS | 8285 | 06:10:59 INFO - PROCESS | 8285 | Cycle 1(1): loaded http://localhost:54193/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54193/tests/dromaeo/cssquery-prototype.html) 06:10:59 INFO - PROCESS | 8285 | RSS: Main: 171782144 06:10:59 INFO - PROCESS | 8285 | 06:14:02 INFO - PROCESS | 8285 | Cycle 1(1): loaded http://localhost:54193/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54193/tests/dromaeo/cssquery-yui.html) 06:14:03 INFO - PROCESS | 8285 | RSS: Main: 174850048 06:14:03 INFO - PROCESS | 8285 | 06:17:05 INFO - PROCESS | 8285 | Cycle 1(1): loaded http://localhost:54193/tests/dromaeo/cssquery-yui.html (next: http://localhost:54193/tests/dromaeo/cssquery-dojo.html) 06:17:05 INFO - PROCESS | 8285 | RSS: Main: 265252864 06:17:05 INFO - PROCESS | 8285 | 06:17:05 INFO - PROCESS | 8285 | __start_tp_report 06:17:05 INFO - PROCESS | 8285 | _x_x_mozilla_page_load 06:17:05 INFO - PROCESS | 8285 | _x_x_mozilla_page_load_details 06:17:05 INFO - PROCESS | 8285 | |i|pagename|runs| 06:17:05 INFO - PROCESS | 8285 | |0;dojo.html;3334.716549093327;3371.292028673978;3408.6761757633017;3444.2250493638917;3455.118944669109;5714.8857022859565;5785.103308987103;5785.190073389724;5787.450192231742;5796.159556864258;5780.335261444935;5838.41615838416;5842.123681447785;5847.0352391855295;5852.209951656525;5133.050385678652;5162.070827251093;5167.276581278624;5190.195519694454;5210.557102646279;5038.470960549144;5069.645124841262;5258.47415258473;5262.131748261532;5263.6841789492755;5116.769745361449;5142.794288228485;5145.125328694131;5165.870853228681;5171.4311425743135;5214.713190774506;5230.424653288135;5230.6600070995455;5232.398274198478;5259.684418934876;4984.551390374847;5084.51697088776;5153.845384638467;5192.58459323257;5208.192730126836;5607;5666.348369937427;5756.625819321773;5761.711914404305;5772.769089236425;6178.783742568989;6217.3471785462325;6227.034809604474;6233.34549872266;6237.407446292595;6215.129881816504;6239.812805615838;6269.341719119518;6277.4350308472;6279.089532017878;5601.943980560142;5609.915851262234;5655.349634791969;5656.8868622627315;5662.037453632893;6155.138280640714;6171.352008039091;6173.41352571505;6177.042558403365;6197.349278325801;6072.301685306158;6082.422169893853;6084.60450070744;6107.9616465201025;6108;6119.694015299217;6180.969095154585;6181.35095814933;6193.442590166816;6221.222347206599;6002.339742628307;6090.9390906090375;6175.3824617538785;6177.7837775677635;6193.659348735863;6063.302720187146;6129.754809807647;6164.3835616438;6171.352008039181;6174.8147555573405;4679.508651591602;4713.858584242478;4713.858584242478;4742.051589682008;4749.26386410107;4117.3618089195625;4197.958020419818;4210.989362552965;4213.494380674278;4216.430781844473;5864.120381942657;5880.411958804171;5906.586538942232;5926.081457374114;5935.970320148372;6258.217722784652;6258.812235632939;6263.342349053375;6274.1843560336865;6283.622982621057;5961.225040744675;5969.611975221596;5979.133025711248;5980.581359304894;6021.759129634853;5096.9490305097215;5123.5644970177955;5207.765650545734;5240.161574148118;5269.446708095595;6366.7771627993725;6403.871922561616;6422.74309027643;6424.357564243538;6426.8714625707225;6194.442500175006;6255.530835187288;6258.687065646791;6259.7496100157305;6268.467180289544;6124;6126.029144987462;6215.254169499689;6420.518461115528;6428.517861160338;6123.526657168115;6132.233136608343;6132.355508992456;6134.5590473767;6141.36327073057;6028.186194863637;6243.531735119794;6249.093881387264;6256.374362563707;6263.1857858479925;5789.536837052941;5982.1325907744;6008.188894499362;6046.90929636047;6050.2437195350585;6046.54650901193;6073.605215660968;6079;6095.1466794647895;6107.908381374372;6071.241094863142;6071.423214794588;6082.817515474543;6084.847878803065;6092.573519853568;5834.241548598655;5902.675352855463;6031.69841507914;6065.787697430569;6089.269287685506;5973.641581505123;5974.880502390015;5997.880042399215;6008.639481631116;6039.818805435844 06:17:05 INFO - PROCESS | 8285 | |1;ext.html;756.1266736918857;822.4982760516084;839.777458973372;886.8625363068727;891.1088911088915;31875.52186717198;43899.34150987734;44909.77545112278;44920;45124.77437612807;39595.40606890892;39894.80052599733;39908;43819.78090109545;43848.3422748659;7568.470207085478;7692.115406728242;8976.59605317759;9147.405418647766;9152.267818574532;10627.41549214793;10765.677029689123;10808.243422960395;10815.080718138928;10821.458927053616;10908.890911090906;10979.890201098007;10982.121430285599;10987.230893837435;10989.725256868562;10895.182861285395;10909.563617455331;10914.672559823139;10929.180311476672;10932.617358392417;10592.470376481146;10641.893581064167;10642.201834862339;10655.84016239757;10662.147028237723;42974.57025429768;43055.56944430578;43199.568004319866;43236.3514547282;43262;4331.6534677225745;4332.263515202423;4369.541198174178;4454.933176002362;4522.977385113087;29632.555511667342;30567.69432305693;30897;30903.53644695309;31591;4213.389058586487;4243.745375277463;4303.655707543389;4366.323219900889;4398.472183337988;227767;228874;229440;229988;230157;53063.73468132634;53477.73261133747;53631.19553206705;53708.46291537034;53901;55328.723356383765;55684.72157639186;55708;55722.442775572534;55727.44272557303;16470;16980.40568580094;16983.91508042452;16988.150592470327;16997.40509082201;12157.69605759863;12323.75352492945;15155.393784248727;15162.696746065238;15169.772453413208;13418.39617217208;13782;14047.929760351133;14102.224377659122;14240.14559126435;5259.474752321784;5350.384705758887;5362;5385.3268341457315;5438.347398312228;1234.5925844471303;1242.87571242875;1286.6461723026055;1287.5879718489994;1311.0232876506873;28456.549935149073;28511.57232641512;28630;28662.283442914093;29042.854785725936;1314.0998416085013;1346.811446397505;1347.2792056250055;1374.223563686534;1378.5795332423706;14670.559883203521;14712.485063022743;14795.408183672747;14803;15102.622434439227;311862;312359;312762.7447451038;313414;313718;58468.415315846294;58530;58539.41460585425;58831;58886.705566471894;1459.9927000364717;1474.852514748538;1519.4074311018712;1526.130105839683;1562.6015366081804;27267.454650907268;28628.14115576536;28773.568396473653;29527.557086643286;29571.85214074002;41235.38146927858;44333;45177.096458069995;50243;50845.98308033893;313796;314696;315951;317835;318887;25503.6174457387;25833.225003249932;29597;30492.237694057825;30578.08265752031;39197.804010979766;39452;39478;39538;39677;30337.848310758305;30745.385092298475;30907.2273193163;31016.224594384417;31153.53269700911;30021.69978300189;30314.090577282717;30390.54414183833;30397.088087357413;30735.84632076915;34801;35127.82436087803;35155;36604.63395366012;37212.81393593015;30967.845160774054;31065.8446707765;31254.843725781226;31448.528272076393;31805.681943181196;24999.50001000006;25926.71802804119;25980.8700956494;26117.608235876098;26227.213183604523 06:17:05 INFO - PROCESS | 8285 | |2;jquery.html;447.1972808808189;454.5568071130648;458.4796256249156;462.8125609128303;479.46779075226493;15935.601609959756;16485.093319867403;20519.179232830687;22423.887880560615;22992.65511017334;22356.888215558898;22479;23072.42318942027;23406.29781106565;23596.882015589897;6708.463322934178;6904.896426553606;6937.271586483397;6949.339812717783;6951.200611929642;5349.5185433311;6724.932750672504;7695.076590809111;7705.422093342993;7716.151223365426;7578.0906291244855;7704.26809453101;7889.132195458495;7909;7915.920840791576;7834.921650783504;7861.606919654052;7876.960615196887;7880.5271683698875;7888.171741967097;7629.961850190769;7680.231976802331;7680.308772210472;7690.461667683265;7693.153753087156;25776.613350799755;26029.219123426137;26112.738872611222;26193.345166370993;26275.474490510274;3081.3683194945206;3159.4944808830705;3161.1148878314107;3179.5707579476707;3229.7254733347695;16295.755563666555;16556.172191390382;16589.419370321983;16621.335146594123;17192.398266060754;2279.464325883416;2305.319930620471;2317.7450480447137;2366.1600131953255;2375.8930848111877;84128.15871841264;84671;88014.5599272006;99056.5047174774;115956;17423.73864392035;18379;18466.07669616514;18636.534086647942;18744.343947961755;31505;31629.36741265162;31642.050738477883;31682.207944801103;31776.36447271088;10549.419781912069;10668.199885008655;10679.19906007037;10709;10905.34567925927;8942.73171804847;8965.462072275684;8985.236254918405;9221.123993220634;9224.677136300197;8880.73357799267;9047.638094476148;9288.677830542365;9311.208547273422;9446.81914760655;3622.4747411625167;3846.8265430031943;3961.593029994203;4048.206737351218;4063.756174629532;979.1383582447401;989.1690979577189;991.1228562722043;1015.3349556040805;1016.7915577306634;19848.60302793936;20840.1663933444;21127.894360528408;21266.2556810514;21423.357299281048;982.1700662939804;986.9407835529892;988.2538683294067;1002.2983911262146;1003.267614641316;11071.612493562849;11409.942950285196;11491.770164596828;11522.193446458667;11564.537418503334;743799;766600;776416;780852;794337;83587.58206209051;84163;109407;113327;114481.42759286151;1281.5770795637604;1290.3290289049642;1322.814805927171;1325.7746183148695;1342.2148043394723;18568.44294671162;19016.809831902057;19048.904755476688;19213;19250.422487325402;51950;52126.47873521368;52465.475345247585;52577;52737.47262527479;66705;66710;67341.3265867335;67836;68199.65900170467;12450.626481205578;12794.986356412472;12922.418491168108;13053.34733263314;13200.273984930922;14239;14257.287135643384;14444.85555144435;14659.706805864036;14728.189949552878;11160.218784684994;11212.719682008015;11316.15128865355;11384.203105782846;11633.360165190996;11540.884591153981;11750.17748757613;12024.158308918291;12218.572349967708;12342;11771.52913883435;12052;12065.577704780291;12195.329256890956;12221.816672749737;12510.18683785551;12528.185667931555;12657.240565566095;12729.618111456672;13015.804762928374;11663.24188927751;11805.940970295094;12146.878531214928;12287.385630718607;12314.876851231373 06:17:05 INFO - PROCESS | 8285 | |3;mootools.html;1571.481411134325;1575.1809059289178;1599.4401959314241;1606.0765060090448;1606.3092870065875;10868.836967445484;11716.472758725868;11733.882661173386;11747.060235181187;11758.353290569015;11328.09375249978;11475.483603237853;11588.59439919603;11590.130740194478;11606;2520.7731304182576;2551.515212109693;2552.591585346345;2558.6417901493805;2561.5261176682375;2462.2982450001755;2471.789897858684;2475.10896077412;2480.7643273888953;2482.900683972639;2460.889259983296;2488.688913885764;2500.099964012954;2503.8998440062373;2508.8871000804916;2467.6668649732324;2494.3888747256947;2515.9119430820015;2521.773040426361;2530.8354956927924;2443.0960544598433;2496.488219914931;2503.5493611149986;2509.2346834215555;2523.2178024812365;11393.088552915748;11567.595134170348;11585.826212606818;11610.36143012134;11614.709632259177;5842.824715258549;5958.165856780054;5966.761329546813;5966.761329546813;5984.371640977722;3490.0925759302763;3576.2489877125836;3639.181184233553;3663.303972245265;3689.7048236141045;1253.9435725392289;1290.2967882504058;1291.9160254583421;1321.7951217561294;1347.5081595217773;15554.688906221925;16046.919765401099;16086.195690215442;17204.397846075328;17413.390531331468;4774.665773395898;4840.177169881129;4892.315075889378;4966.404031516241;5004.9499505005215;11217;11617.186796924303;12309.32298723561;13405.932970335281;13423.194608323533;6036.9698151508965;6144.139820425144;6146.0473626587955;6146.78486252979;6147.907781383283;5654.37801841797;5725.656460612377;5727.255456790591;5729.5702822288495;5785.508231800344;3944.585818489075;4027.436158937751;4045.554988951213;4090.550039495653;4120.320147175742;1007.1590222164428;1014.1278500489573;1016.9119042624321;1018.2871989607305;1020.0972139656458;1026.97432564185;1041.2763129624839;1049.375621505203;1056.9852941176403;1057.1701214546697;6815.250322464525;7010.334018268256;7039;7186.1376634804155;7203.89194162088;1033.1476531861094;1050.3907733514543;1064.8509208710786;1066.5094056733835;1067.5908497903608;4089.3865920111625;4151.086760912594;4176.102138040336;4187.392828039917;4195.244855925902;14123.081999669988;14650.267486625626;14660.120392776467;14709.779353309495;14768.556943291718;14594.927025364805;14617.19605421712;14617.634559135893;14630.195339256443;14641.780373294408;10183.134433573081;10505.31715438494;10786.244962852525;10813.83779243312;10814.567417303377;7076.646167691698;7083.610401427764;7158.3915367192285;7204;7205.855882882208;12647.936760316139;13445.596632101053;13455.663608409477;13568.1859088455;13632.522861699794;13443;13502.932485337511;13627.523036693668;13715.1085179463;13731.313434328044;9776.657816976372;10073.294869359075;10078;10091.69724908254;10095.54570044335;10636.574537018432;10752;10799.838002429813;10841.18691098155;10863.619773307897;9372.578233979624;9402.2948278878;9448.858267125861;9512.429254244767;9631.133198012212;9273.582688778886;9462.384944978554;9467.958524562431;9602.567884445078;9619.374740641835;8140.592970351578;8159.959200203961;8169.101398846004;8217.424780265563;8264.917350826414;6270.9372906270355;6359.332270111757;6445.355464453704;6448.226212854487;6585.769498067736;6398.968005159944;6522.15212022453;6561.278259391559;6568.113304703804;6679.799606011828 06:17:05 INFO - PROCESS | 8285 | |4;prototype.html;397.642122090119;430.3768530804746;433.9999999999999;434.38751360581585;436.0559388922982;5239.240310155025;5247.816326428576;5282.339707536558;5291.4708529147065;5363.731813409334;5043.571296439807;5272.156454967197;5289.285946397235;5296.443873393296;5305.071612467812;4424.225760491917;4451.933221001679;4486.416765820455;4490.371348011281;4493.258612328962;4425.77871106445;4432.20220360335;4485.394471746322;4488.910221795562;4496.280595104784;4403.273459879116;4403.669724770639;4421.0494743630115;4465.37484752135;4509.7970591323465;4473.7986790594505;4478.283474644058;4487.394201782783;4492.910141797179;4516.979887899772;4322.805473753689;4324.545922678105;4327.567243275647;4333;4333.804978775963;5171.637985341028;5173.508516690908;5177.618295584036;5179.896402071938;5234;5100.489951004907;5322.387925388591;5347.893042139174;5352.946470535323;5377.274068000809;5538.75075621598;5554.805581804618;5570.359408668014;5629.311206637993;5648.971755141239;4687.578117969365;4867.415910090812;4888.633352498541;4889.652966576028;4905.40574313346;5505.394406615269;5521.779128834842;5661.160122960055;5699.857503562403;5705;4956.826511072095;4974.253861920669;4987.301777751118;5004.674696144812;5026.632156455184;5064.392272927199;5071.949280507221;5074.843191643537;5078.577998160521;5080.618953578496;5039.899202016012;5250.7637156328055;5277.8416647500635;5286.651903764516;5343.919841202385;5490.862728431741;5542.307211598551;5546.639468434539;5564.4991950724625;5572.442755724395;1727.5508367824457;1791.4682946081173;1800.261892624018;1809.4481183239238;1820.5084627150595;1427.8500757420322;1429.6140042188758;1466.8606482384157;1467.6330917270682;1480.7556753135827;5446;5466.021018544902;5526.613137080366;5527.296711203503;5566.916496252559;5637.633553819071;5646.576506761927;5661.858453538694;5665.988574056233;5690.971545142331;5237.690577355661;5417.783288668409;5421.2952316199435;5427.511523962862;5441.972790136103;2959.186223788475;2959.5264757638674;2971.4205729882897;2972.197506673186;2986.2385091801452;5629.211910332557;5740.138979153077;5746.827595172152;5762.020456522401;5936;5697.544196464273;5732.369439361667;5745.224394706661;5778.624389414675;5784.818349907534;5770.221020162225;5785.495771099544;5796.681182535001;5816.331121920949;5852.4440178183;5378.865528361822;5555.000099982042;5650.463205995424;5661.801836935688;5678.914816277842;5349.304590403378;5497.3952865184;5617.662940223599;5621.747021383966;5650.85872853182;5734.139879018181;5744.109663002241;5775.971120144541;5818.94181058184;5822;4705.905881882411;4713.552212539803;4738.194506933898;4765.928511072406;4768.40258513388;5488.835334939959;5665;5676.517496012885;5681.9431805681415;5692.231548740867;5544.861378465571;5554.833354999357;5564.35851423839;5580.01909322201;5591.217229587943;5410.404855465974;5570.972145139248;5573.21974923503;5581.576697941999;5592.496675299242;5444.6188766785945;5444.646098003617;5460.890782184255;5474.7810087597645;5533.6956467393065;5525.281713377235;5533.944660553343;5567.331920169606;5569.159728538393;5598.35618903815;5281.234221038005;5384.811531596375;5389.380221274447;5425.186222066722;5427.864303392289 06:17:05 INFO - PROCESS | 8285 | |5;yui.html;892.9687460938868;926.5691453474135;932.4358762948416;942.5240253671894;944.3955868244325;15976.360945562177;17160.742588861147;17274.65450690987;17294.567635809097;19544.609107817836;18388.816111838878;18740.718889216638;19507.5123121922;19623.11695973681;19698.50753731154;877.1097336203758;896.2964073202521;898.9955050224739;918.5407296351824;921.4609453469728;904.3624244907357;905.7056456651615;906.2885634776669;907.6505545365048;908.3868388837541;949.1220620925651;950.6862735297335;952.2239374909461;953.952302384878;955.6750704760381;947.829390709672;949.6106596295521;949.7862980829327;951.5813042261383;952.2953014769021;692.3318997167758;699.5697645947756;700.5971566349318;700.7127077898089;700.982475438113;18233.908830455897;18271.451856444328;18535;19483.707744383704;19620.313289034817;17289.913550432168;17457.47627571175;17725.379611713528;17747.290108395777;18246.178921948544;12842.935785321108;13216.933915330457;13257.072004959657;13262.204267743966;13457.86542134576;3919.5884432134785;3966.0679740261303;3978.8408463661417;3993.381025940984;4039.9596004039877;50823.74588127073;55474.16788748172;71498.92751608782;71974.28025719729;91216;14573.271336433136;14603.612656797588;14627.92686036563;14637.414503419957;14745.92627036858;27028;27286.317842054108;27349.45301093967;27397;27485.587716184273;9017.684381046633;9206.309526785384;9215.53922303882;9290.210332121846;9317.068293170765;5072.695638261716;5082.898342033139;5123.462036486116;5187.63686541946;5222.712750798667;3927.23418933311;4024.9195016099516;4027.456293400412;4037.454943582579;4039.0508230565792;657.9736810527621;662.718344703499;672.3478226120656;675.6587923098901;682.3483573187594;790.6679194738224;797.5055465611242;814.2061490047157;815.3314282288467;819.1399031017407;11405.942970285261;11639.418029098511;11963.521459141537;12133.575324863765;12315.261084334968;766.5822126940831;771.2711487644123;783.2637320918318;796.2913007423398;799.1968072087515;5274.446183150791;5528.281323428009;5617.43825617435;5624.437556244343;5661.575381846378;85250.14749852546;85876;86010.56994714987;86783.13216867877;86966.56516717376;68738.31261687419;69114.64137403789;74257.62871185718;74914;75371;61074;61738;61962.69018654878;62010.68994655088;62023.37976620266;11754.353510556659;11831.585894493652;12255.816162757745;12278.140530162802;12395.132340736061;42396;43178.568214317456;43682;44102;44432;51027;51055.48944510507;51237.74381128071;51973.480265196864;53689;13288.534901278408;13335;13381.933090334485;13522.661933451742;13569;15709.293081811118;15912.443064492687;16198.271077801292;16248.918755406148;16341.836581634032;14734.484293049692;15278.388864445298;15668.68662626764;16298.918505407872;16332.346706131622;14666.413343466142;14925;15498.457553985556;15617.297221624827;15860.60348491297;13973.371198295898;14225.359858806172;14499.420023198954;14544.418223271374;14823.703525929637;9298.628054877729;9691;9838.508074596384;10066.8489972649;10081.495925203564;11667;11878.643640690792;12545.372731363577;12721.681957951125;12858.549950751678 06:17:05 INFO - PROCESS | 8285 | __end_tp_report 06:17:05 INFO - PROCESS | 8285 | __start_cc_report 06:17:05 INFO - PROCESS | 8285 | _x_x_mozilla_cycle_collect,1502 06:17:05 INFO - PROCESS | 8285 | __end_cc_report 06:17:05 INFO - PROCESS | 8285 | __startTimestamp1495459025729__endTimestamp 06:17:05 INFO - PROCESS | 8285 | 06:17:05 INFO - PROCESS | 8285 | ------- Summary: start ------- 06:17:05 INFO - PROCESS | 8285 | Number of tests: 6 06:17:05 INFO - PROCESS | 8285 | 06:17:05 INFO - PROCESS | 8285 | [#0] dojo.html Cycles:165 Average:5728.39 Median:6008.19 stddev:662.13 (11.0%) stddev-sans-first:636.98 06:17:05 INFO - PROCESS | 8285 | Values: 3334.7 3371.3 3408.7 3444.2 3455.1 5714.9 5785.1 5785.2 5787.5 5796.2 5780.3 5838.4 5842.1 5847.0 5852.2 5133.1 5162.1 5167.3 5190.2 5210.6 5038.5 5069.6 5258.5 5262.1 5263.7 5116.8 5142.8 5145.1 5165.9 5171.4 5214.7 5230.4 5230.7 5232.4 5259.7 4984.6 5084.5 5153.8 5192.6 5208.2 5607.0 5666.3 5756.6 5761.7 5772.8 6178.8 6217.3 6227.0 6233.3 6237.4 6215.1 6239.8 6269.3 6277.4 6279.1 5601.9 5609.9 5655.3 5656.9 5662.0 6155.1 6171.4 6173.4 6177.0 6197.3 6072.3 6082.4 6084.6 6108.0 6108.0 6119.7 6181.0 6181.4 6193.4 6221.2 6002.3 6090.9 6175.4 6177.8 6193.7 6063.3 6129.8 6164.4 6171.4 6174.8 4679.5 4713.9 4713.9 4742.1 4749.3 4117.4 4198.0 4211.0 4213.5 4216.4 5864.1 5880.4 5906.6 5926.1 5936.0 6258.2 6258.8 6263.3 6274.2 6283.6 5961.2 5969.6 5979.1 5980.6 6021.8 5096.9 5123.6 5207.8 5240.2 5269.4 6366.8 6403.9 6422.7 6424.4 6426.9 6194.4 6255.5 6258.7 6259.7 6268.5 6124.0 6126.0 6215.3 6420.5 6428.5 6123.5 6132.2 6132.4 6134.6 6141.4 6028.2 6243.5 6249.1 6256.4 6263.2 5789.5 5982.1 6008.2 6046.9 6050.2 6046.5 6073.6 6079.0 6095.1 6107.9 6071.2 6071.4 6082.8 6084.8 6092.6 5834.2 5902.7 6031.7 6065.8 6089.3 5973.6 5974.9 5997.9 6008.6 6039.8 06:17:05 INFO - PROCESS | 8285 | 06:17:05 INFO - PROCESS | 8285 | [#1] ext.html Cycles:180 Average:45550.32 Median:30905.38 stddev:75478.47 (244.2%) stddev-sans-first:75615.27 06:17:05 INFO - PROCESS | 8285 | Values: 756.1 822.5 839.8 886.9 891.1 31875.5 43899.3 44909.8 44920.0 45124.8 39595.4 39894.8 39908.0 43819.8 43848.3 7568.5 7692.1 8976.6 9147.4 9152.3 10627.4 10765.7 10808.2 10815.1 10821.5 10908.9 10979.9 10982.1 10987.2 10989.7 10895.2 10909.6 10914.7 10929.2 10932.6 10592.5 10641.9 10642.2 10655.8 10662.1 42974.6 43055.6 43199.6 43236.4 43262.0 4331.7 4332.3 4369.5 4454.9 4523.0 29632.6 30567.7 30897.0 30903.5 31591.0 4213.4 4243.7 4303.7 4366.3 4398.5 227767.0 228874.0 229440.0 229988.0 230157.0 53063.7 53477.7 53631.2 53708.5 53901.0 55328.7 55684.7 55708.0 55722.4 55727.4 16470.0 16980.4 16983.9 16988.2 16997.4 12157.7 12323.8 15155.4 15162.7 15169.8 13418.4 13782.0 14047.9 14102.2 14240.1 5259.5 5350.4 5362.0 5385.3 5438.3 1234.6 1242.9 1286.6 1287.6 1311.0 28456.5 28511.6 28630.0 28662.3 29042.9 1314.1 1346.8 1347.3 1374.2 1378.6 14670.6 14712.5 14795.4 14803.0 15102.6 311862.0 312359.0 312762.7 313414.0 313718.0 58468.4 58530.0 58539.4 58831.0 58886.7 1460.0 1474.9 1519.4 1526.1 1562.6 27267.5 28628.1 28773.6 29527.6 29571.9 41235.4 44333.0 45177.1 50243.0 50846.0 313796.0 314696.0 315951.0 317835.0 318887.0 25503.6 25833.2 29597.0 30492.2 30578.1 39197.8 39452.0 39478.0 39538.0 39677.0 30337.8 30745.4 30907.2 31016.2 31153.5 30021.7 30314.1 30390.5 30397.1 30735.8 34801.0 35127.8 35155.0 36604.6 37212.8 30967.8 31065.8 31254.8 31448.5 31805.7 24999.5 25926.7 25980.9 26117.6 26227.2 06:17:05 INFO - PROCESS | 8285 | 06:17:05 INFO - PROCESS | 8285 | [#2] jquery.html Cycles:180 Average:40028.90 Median:12981.52 stddev:126377.93 (973.5%) stddev-sans-first:126697.51 06:17:05 INFO - PROCESS | 8285 | Values: 447.2 454.6 458.5 462.8 479.5 15935.6 16485.1 20519.2 22423.9 22992.7 22356.9 22479.0 23072.4 23406.3 23596.9 6708.5 6904.9 6937.3 6949.3 6951.2 5349.5 6724.9 7695.1 7705.4 7716.2 7578.1 7704.3 7889.1 7909.0 7915.9 7834.9 7861.6 7877.0 7880.5 7888.2 7630.0 7680.2 7680.3 7690.5 7693.2 25776.6 26029.2 26112.7 26193.3 26275.5 3081.4 3159.5 3161.1 3179.6 3229.7 16295.8 16556.2 16589.4 16621.3 17192.4 2279.5 2305.3 2317.7 2366.2 2375.9 84128.2 84671.0 88014.6 99056.5 115956.0 17423.7 18379.0 18466.1 18636.5 18744.3 31505.0 31629.4 31642.1 31682.2 31776.4 10549.4 10668.2 10679.2 10709.0 10905.3 8942.7 8965.5 8985.2 9221.1 9224.7 8880.7 9047.6 9288.7 9311.2 9446.8 3622.5 3846.8 3961.6 4048.2 4063.8 979.1 989.2 991.1 1015.3 1016.8 19848.6 20840.2 21127.9 21266.3 21423.4 982.2 986.9 988.3 1002.3 1003.3 11071.6 11409.9 11491.8 11522.2 11564.5 743799.0 766600.0 776416.0 780852.0 794337.0 83587.6 84163.0 109407.0 113327.0 114481.4 1281.6 1290.3 1322.8 1325.8 1342.2 18568.4 19016.8 19048.9 19213.0 19250.4 51950.0 52126.5 52465.5 52577.0 52737.5 66705.0 66710.0 67341.3 67836.0 68199.7 12450.6 12795.0 12922.4 13053.3 13200.3 14239.0 14257.3 14444.9 14659.7 14728.2 11160.2 11212.7 11316.2 11384.2 11633.4 11540.9 11750.2 12024.2 12218.6 12342.0 11771.5 12052.0 12065.6 12195.3 12221.8 12510.2 12528.2 12657.2 12729.6 13015.8 11663.2 11805.9 12146.9 12287.4 12314.9 06:17:05 INFO - PROCESS | 8285 | 06:17:05 INFO - PROCESS | 8285 | [#3] mootools.html Cycles:180 Average:7178.31 Median:2481.83 stddev:4537.95 (182.8%) stddev-sans-first:4531.12 06:17:05 INFO - PROCESS | 8285 | Values: 1571.5 1575.2 1599.4 1606.1 1606.3 10868.8 11716.5 11733.9 11747.1 11758.4 11328.1 11475.5 11588.6 11590.1 11606.0 2520.8 2551.5 2552.6 2558.6 2561.5 2462.3 2471.8 2475.1 2480.8 2482.9 2460.9 2488.7 2500.1 2503.9 2508.9 2467.7 2494.4 2515.9 2521.8 2530.8 2443.1 2496.5 2503.5 2509.2 2523.2 11393.1 11567.6 11585.8 11610.4 11614.7 5842.8 5958.2 5966.8 5966.8 5984.4 3490.1 3576.2 3639.2 3663.3 3689.7 1253.9 1290.3 1291.9 1321.8 1347.5 15554.7 16046.9 16086.2 17204.4 17413.4 4774.7 4840.2 4892.3 4966.4 5004.9 11217.0 11617.2 12309.3 13405.9 13423.2 6037.0 6144.1 6146.0 6146.8 6147.9 5654.4 5725.7 5727.3 5729.6 5785.5 3944.6 4027.4 4045.6 4090.6 4120.3 1007.2 1014.1 1016.9 1018.3 1020.1 1027.0 1041.3 1049.4 1057.0 1057.2 6815.3 7010.3 7039.0 7186.1 7203.9 1033.1 1050.4 1064.9 1066.5 1067.6 4089.4 4151.1 4176.1 4187.4 4195.2 14123.1 14650.3 14660.1 14709.8 14768.6 14594.9 14617.2 14617.6 14630.2 14641.8 10183.1 10505.3 10786.2 10813.8 10814.6 7076.6 7083.6 7158.4 7204.0 7205.9 12647.9 13445.6 13455.7 13568.2 13632.5 13443.0 13502.9 13627.5 13715.1 13731.3 9776.7 10073.3 10078.0 10091.7 10095.5 10636.6 10752.0 10799.8 10841.2 10863.6 9372.6 9402.3 9448.9 9512.4 9631.1 9273.6 9462.4 9468.0 9602.6 9619.4 8140.6 8160.0 8169.1 8217.4 8264.9 6270.9 6359.3 6445.4 6448.2 6585.8 6399.0 6522.2 6561.3 6568.1 6679.8 06:17:05 INFO - PROCESS | 8285 | 06:17:05 INFO - PROCESS | 8285 | [#4] prototype.html Cycles:180 Average:4873.25 Median:5358.34 stddev:1251.61 (23.4%) stddev-sans-first:1209.20 06:17:05 INFO - PROCESS | 8285 | Values: 397.6 430.4 434.0 434.4 436.1 5239.2 5247.8 5282.3 5291.5 5363.7 5043.6 5272.2 5289.3 5296.4 5305.1 4424.2 4451.9 4486.4 4490.4 4493.3 4425.8 4432.2 4485.4 4488.9 4496.3 4403.3 4403.7 4421.0 4465.4 4509.8 4473.8 4478.3 4487.4 4492.9 4517.0 4322.8 4324.5 4327.6 4333.0 4333.8 5171.6 5173.5 5177.6 5179.9 5234.0 5100.5 5322.4 5347.9 5352.9 5377.3 5538.8 5554.8 5570.4 5629.3 5649.0 4687.6 4867.4 4888.6 4889.7 4905.4 5505.4 5521.8 5661.2 5699.9 5705.0 4956.8 4974.3 4987.3 5004.7 5026.6 5064.4 5071.9 5074.8 5078.6 5080.6 5039.9 5250.8 5277.8 5286.7 5343.9 5490.9 5542.3 5546.6 5564.5 5572.4 1727.6 1791.5 1800.3 1809.4 1820.5 1427.9 1429.6 1466.9 1467.6 1480.8 5446.0 5466.0 5526.6 5527.3 5566.9 5637.6 5646.6 5661.9 5666.0 5691.0 5237.7 5417.8 5421.3 5427.5 5442.0 2959.2 2959.5 2971.4 2972.2 2986.2 5629.2 5740.1 5746.8 5762.0 5936.0 5697.5 5732.4 5745.2 5778.6 5784.8 5770.2 5785.5 5796.7 5816.3 5852.4 5378.9 5555.0 5650.5 5661.8 5678.9 5349.3 5497.4 5617.7 5621.7 5650.9 5734.1 5744.1 5776.0 5818.9 5822.0 4705.9 4713.6 4738.2 4765.9 4768.4 5488.8 5665.0 5676.5 5681.9 5692.2 5544.9 5554.8 5564.4 5580.0 5591.2 5410.4 5571.0 5573.2 5581.6 5592.5 5444.6 5444.6 5460.9 5474.8 5533.7 5525.3 5533.9 5567.3 5569.2 5598.4 5281.2 5384.8 5389.4 5425.2 5427.9 06:17:05 INFO - PROCESS | 8285 | 06:17:05 INFO - PROCESS | 8285 | [#5] yui.html Cycles:180 Average:18577.28 Median:44267.00 stddev:22320.95 (50.4%) stddev-sans-first:22344.06 06:17:05 INFO - PROCESS | 8285 | Values: 893.0 926.6 932.4 942.5 944.4 15976.4 17160.7 17274.7 17294.6 19544.6 18388.8 18740.7 19507.5 19623.1 19698.5 877.1 896.3 899.0 918.5 921.5 904.4 905.7 906.3 907.7 908.4 949.1 950.7 952.2 954.0 955.7 947.8 949.6 949.8 951.6 952.3 692.3 699.6 700.6 700.7 701.0 18233.9 18271.5 18535.0 19483.7 19620.3 17289.9 17457.5 17725.4 17747.3 18246.2 12842.9 13216.9 13257.1 13262.2 13457.9 3919.6 3966.1 3978.8 3993.4 4040.0 50823.7 55474.2 71498.9 71974.3 91216.0 14573.3 14603.6 14627.9 14637.4 14745.9 27028.0 27286.3 27349.5 27397.0 27485.6 9017.7 9206.3 9215.5 9290.2 9317.1 5072.7 5082.9 5123.5 5187.6 5222.7 3927.2 4024.9 4027.5 4037.5 4039.1 658.0 662.7 672.3 675.7 682.3 790.7 797.5 814.2 815.3 819.1 11405.9 11639.4 11963.5 12133.6 12315.3 766.6 771.3 783.3 796.3 799.2 5274.4 5528.3 5617.4 5624.4 5661.6 85250.1 85876.0 86010.6 86783.1 86966.6 68738.3 69114.6 74257.6 74914.0 75371.0 61074.0 61738.0 61962.7 62010.7 62023.4 11754.4 11831.6 12255.8 12278.1 12395.1 42396.0 43178.6 43682.0 44102.0 44432.0 51027.0 51055.5 51237.7 51973.5 53689.0 13288.5 13335.0 13381.9 13522.7 13569.0 15709.3 15912.4 16198.3 16248.9 16341.8 14734.5 15278.4 15668.7 16298.9 16332.3 14666.4 14925.0 15498.5 15617.3 15860.6 13973.4 14225.4 14499.4 14544.4 14823.7 9298.6 9691.0 9838.5 10066.8 10081.5 11667.0 11878.6 12545.4 12721.7 12858.5 06:17:05 INFO - PROCESS | 8285 | -------- Summary: end -------- 06:17:05 INFO - PROCESS | 8285 | 06:17:06 INFO - TEST-INFO | 8285: exit 0 06:17:06 INFO - TEST-OK | dromaeo_css | took 1115385ms 06:17:06 INFO - TEST-START | kraken 06:17:06 INFO - operating with platform_type : linux_ 06:17:06 INFO - Initialising browser for kraken test... 06:17:06 INFO - TEST-INFO | started process 8594 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI7s_X4/profile http://localhost:54193/getInfo.html) 06:17:10 INFO - PROCESS | 8594 | __metrics Screen width/height:1600/1200 06:17:10 INFO - PROCESS | 8594 | colorDepth:24 06:17:10 INFO - PROCESS | 8594 | Browser inner width/height: 1024/697 06:17:10 INFO - PROCESS | 8594 | __metrics 06:17:11 INFO - PROCESS | 8594 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:17:11 INFO - PROCESS | 8594 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:17:12 INFO - TEST-INFO | 8594: exit 0 06:17:12 INFO - Browser initialized. 06:17:12 INFO - Running cycle 1/1 for kraken test... 06:17:12 INFO - TEST-INFO | started process 8686 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI7s_X4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:17:24 INFO - PROCESS | 8686 | RSS: Main: 141418496 06:17:24 INFO - PROCESS | 8686 | 06:17:26 INFO - PROCESS | 8686 | Cycle 1(1): loaded http://localhost:54193/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54193/tests/kraken/driver.html?testName=audio-beat-detection) 06:17:27 INFO - PROCESS | 8686 | RSS: Main: 183996416 06:17:27 INFO - PROCESS | 8686 | 06:17:30 INFO - PROCESS | 8686 | Cycle 1(1): loaded http://localhost:54193/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54193/tests/kraken/driver.html?testName=audio-dft) 06:17:30 INFO - PROCESS | 8686 | RSS: Main: 173506560 06:17:30 INFO - PROCESS | 8686 | 06:17:33 INFO - PROCESS | 8686 | Cycle 1(1): loaded http://localhost:54193/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54193/tests/kraken/driver.html?testName=audio-fft) 06:17:34 INFO - PROCESS | 8686 | RSS: Main: 265281536 06:17:34 INFO - PROCESS | 8686 | 06:17:36 INFO - PROCESS | 8686 | Cycle 1(1): loaded http://localhost:54193/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54193/tests/kraken/driver.html?testName=audio-oscillator) 06:17:36 INFO - PROCESS | 8686 | RSS: Main: 171388928 06:17:36 INFO - PROCESS | 8686 | 06:17:38 INFO - PROCESS | 8686 | Cycle 1(1): loaded http://localhost:54193/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54193/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:17:39 INFO - PROCESS | 8686 | RSS: Main: 173801472 06:17:39 INFO - PROCESS | 8686 | 06:17:42 INFO - PROCESS | 8686 | Cycle 1(1): loaded http://localhost:54193/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54193/tests/kraken/driver.html?testName=imaging-darkroom) 06:17:43 INFO - PROCESS | 8686 | RSS: Main: 211628032 06:17:43 INFO - PROCESS | 8686 | 06:17:46 INFO - PROCESS | 8686 | Cycle 1(1): loaded http://localhost:54193/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54193/tests/kraken/driver.html?testName=imaging-desaturate) 06:17:47 INFO - PROCESS | 8686 | RSS: Main: 212094976 06:17:47 INFO - PROCESS | 8686 | 06:17:49 INFO - PROCESS | 8686 | Cycle 1(1): loaded http://localhost:54193/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54193/tests/kraken/driver.html?testName=json-parse-financial) 06:17:50 INFO - PROCESS | 8686 | RSS: Main: 211136512 06:17:50 INFO - PROCESS | 8686 | 06:17:51 INFO - PROCESS | 8686 | Cycle 1(1): loaded http://localhost:54193/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54193/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:17:52 INFO - PROCESS | 8686 | RSS: Main: 173547520 06:17:52 INFO - PROCESS | 8686 | 06:17:54 INFO - PROCESS | 8686 | Cycle 1(1): loaded http://localhost:54193/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54193/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:17:54 INFO - PROCESS | 8686 | RSS: Main: 180211712 06:17:54 INFO - PROCESS | 8686 | 06:17:56 INFO - PROCESS | 8686 | Cycle 1(1): loaded http://localhost:54193/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54193/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:17:57 INFO - PROCESS | 8686 | RSS: Main: 190021632 06:17:57 INFO - PROCESS | 8686 | 06:17:59 INFO - PROCESS | 8686 | Cycle 1(1): loaded http://localhost:54193/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54193/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:18:00 INFO - PROCESS | 8686 | RSS: Main: 198426624 06:18:00 INFO - PROCESS | 8686 | 06:18:03 INFO - PROCESS | 8686 | Cycle 1(1): loaded http://localhost:54193/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54193/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:18:03 INFO - PROCESS | 8686 | RSS: Main: 195194880 06:18:03 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | Cycle 1(1): loaded http://localhost:54193/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54193/tests/kraken/driver.html?testName=ai-astar) 06:18:05 INFO - PROCESS | 8686 | RSS: Main: 245862400 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | __start_tp_report 06:18:05 INFO - PROCESS | 8686 | _x_x_mozilla_page_load 06:18:05 INFO - PROCESS | 8686 | _x_x_mozilla_page_load_details 06:18:05 INFO - PROCESS | 8686 | |i|pagename|runs| 06:18:05 INFO - PROCESS | 8686 | |0;ai-astar;103;112;114;113;113;113;111;113;113;113 06:18:05 INFO - PROCESS | 8686 | |1;audio-beat-detection;136;114;115;145;120;118;118;120;140;116 06:18:05 INFO - PROCESS | 8686 | |2;audio-dft;161;161;160;161;161;159;162;160;160;161 06:18:05 INFO - PROCESS | 8686 | |3;audio-fft;74;75;75;109;76;75;75;76;89;75 06:18:05 INFO - PROCESS | 8686 | |4;audio-oscillator;82;82;101;82;81;82;99;81;82;115 06:18:05 INFO - PROCESS | 8686 | |5;imaging-gaussian-blur;113;113;112;113;113;113;113;113;113;113 06:18:05 INFO - PROCESS | 8686 | |6;imaging-darkroom;146;145;146;147;148;146;145;146;146;146 06:18:05 INFO - PROCESS | 8686 | |7;imaging-desaturate;94;94;94;94;94;94;95;94;94;94 06:18:05 INFO - PROCESS | 8686 | |8;json-parse-financial;55;57;54;53;55;54;55;55;61;54 06:18:05 INFO - PROCESS | 8686 | |9;json-stringify-tinderbox;52;53;52;52;52;52;52;52;52;52 06:18:05 INFO - PROCESS | 8686 | |10;stanford-crypto-aes;82;82;77;76;84;77;75;80;84;80 06:18:05 INFO - PROCESS | 8686 | |11;stanford-crypto-ccm;123;128;121;137;119;118;117;118;157;127 06:18:05 INFO - PROCESS | 8686 | |12;stanford-crypto-pbkdf2;159;163;165;161;170;164;158;161;161;159 06:18:05 INFO - PROCESS | 8686 | |13;stanford-crypto-sha256-iterative;62;60;59;60;60;59;60;59;59;60 06:18:05 INFO - PROCESS | 8686 | __end_tp_report 06:18:05 INFO - PROCESS | 8686 | __start_cc_report 06:18:05 INFO - PROCESS | 8686 | _x_x_mozilla_cycle_collect,1642 06:18:05 INFO - PROCESS | 8686 | __end_cc_report 06:18:05 INFO - PROCESS | 8686 | __startTimestamp1495459085769__endTimestamp 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | ------- Summary: start ------- 06:18:05 INFO - PROCESS | 8686 | Number of tests: 14 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.19 (2.8%) stddev-sans-first:0.83 06:18:05 INFO - PROCESS | 8686 | Values: 103.0 112.0 114.0 113.0 113.0 113.0 111.0 113.0 113.0 113.0 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | [#1] audio-beat-detection Cycles:10 Average:124.20 Median:120.00 stddev:11.50 (9.6%) stddev-sans-first:11.37 06:18:05 INFO - PROCESS | 8686 | Values: 136.0 114.0 115.0 145.0 120.0 118.0 118.0 120.0 140.0 116.0 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.84 (0.5%) stddev-sans-first:0.88 06:18:05 INFO - PROCESS | 8686 | Values: 161.0 161.0 160.0 161.0 161.0 159.0 162.0 160.0 160.0 161.0 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | [#3] audio-fft Cycles:10 Average:79.90 Median:75.00 stddev:11.13 (14.8%) stddev-sans-first:11.60 06:18:05 INFO - PROCESS | 8686 | Values: 74.0 75.0 75.0 109.0 76.0 75.0 75.0 76.0 89.0 75.0 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:82.00 stddev:11.98 (14.6%) stddev-sans-first:12.46 06:18:05 INFO - PROCESS | 8686 | Values: 82.0 82.0 101.0 82.0 81.0 82.0 99.0 81.0 82.0 115.0 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 06:18:05 INFO - PROCESS | 8686 | Values: 113.0 113.0 112.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 06:18:05 INFO - PROCESS | 8686 | Values: 146.0 145.0 146.0 147.0 148.0 146.0 145.0 146.0 146.0 146.0 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 06:18:05 INFO - PROCESS | 8686 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.00 stddev:2.26 (4.1%) stddev-sans-first:2.40 06:18:05 INFO - PROCESS | 8686 | Values: 55.0 57.0 54.0 53.0 55.0 54.0 55.0 55.0 61.0 54.0 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | [#9] json-stringify-tinderbox Cycles:10 Average:52.10 Median:52.00 stddev:0.32 (0.6%) stddev-sans-first:0.33 06:18:05 INFO - PROCESS | 8686 | Values: 52.0 53.0 52.0 52.0 52.0 52.0 52.0 52.0 52.0 52.0 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | [#10] stanford-crypto-aes Cycles:10 Average:79.70 Median:81.00 stddev:3.30 (4.1%) stddev-sans-first:3.40 06:18:05 INFO - PROCESS | 8686 | Values: 82.0 82.0 77.0 76.0 84.0 77.0 75.0 80.0 84.0 80.0 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:125.00 stddev:12.37 (9.9%) stddev-sans-first:13.05 06:18:05 INFO - PROCESS | 8686 | Values: 123.0 128.0 121.0 137.0 119.0 118.0 117.0 118.0 157.0 127.0 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.10 Median:162.00 stddev:3.57 (2.2%) stddev-sans-first:3.61 06:18:05 INFO - PROCESS | 8686 | Values: 159.0 163.0 165.0 161.0 170.0 164.0 158.0 161.0 161.0 159.0 06:18:05 INFO - PROCESS | 8686 | 06:18:05 INFO - PROCESS | 8686 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.80 Median:60.00 stddev:0.92 (1.5%) stddev-sans-first:0.53 06:18:05 INFO - PROCESS | 8686 | Values: 62.0 60.0 59.0 60.0 60.0 59.0 60.0 59.0 59.0 60.0 06:18:05 INFO - PROCESS | 8686 | -------- Summary: end -------- 06:18:05 INFO - PROCESS | 8686 | 06:18:06 INFO - TEST-INFO | 8686: exit 0 06:18:06 INFO - TEST-OK | kraken | took 60343ms 06:18:06 INFO - SUITE-END | took 1175s 06:18:06 INFO - Completed test suite (00:19:35) 06:18:06 INFO - javascript benchmark 06:18:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7934.802670512354, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3334.716549093327, 3371.292028673978, 3408.6761757633017, 3444.2250493638917, 3455.118944669109, 5714.8857022859565, 5785.103308987103, 5785.190073389724, 5787.450192231742, 5796.159556864258, 5780.335261444935, 5838.41615838416, 5842.123681447785, 5847.0352391855295, 5852.209951656525, 5133.050385678652, 5162.070827251093, 5167.276581278624, 5190.195519694454, 5210.557102646279, 5038.470960549144, 5069.645124841262, 5258.47415258473, 5262.131748261532, 5263.6841789492755, 5116.769745361449, 5142.794288228485, 5145.125328694131, 5165.870853228681, 5171.4311425743135, 5214.713190774506, 5230.424653288135, 5230.6600070995455, 5232.398274198478, 5259.684418934876, 4984.551390374847, 5084.51697088776, 5153.845384638467, 5192.58459323257, 5208.192730126836, 5607.0, 5666.348369937427, 5756.625819321773, 5761.711914404305, 5772.769089236425, 6178.783742568989, 6217.3471785462325, 6227.034809604474, 6233.34549872266, 6237.407446292595, 6215.129881816504, 6239.812805615838, 6269.341719119518, 6277.4350308472, 6279.089532017878, 5601.943980560142, 5609.915851262234, 5655.349634791969, 5656.8868622627315, 5662.037453632893, 6155.138280640714, 6171.352008039091, 6173.41352571505, 6177.042558403365, 6197.349278325801, 6072.301685306158, 6082.422169893853, 6084.60450070744, 6107.9616465201025, 6108.0, 6119.694015299217, 6180.969095154585, 6181.35095814933, 6193.442590166816, 6221.222347206599, 6002.339742628307, 6090.9390906090375, 6175.3824617538785, 6177.7837775677635, 6193.659348735863, 6063.302720187146, 6129.754809807647, 6164.3835616438, 6171.352008039181, 6174.8147555573405, 4679.508651591602, 4713.858584242478, 4713.858584242478, 4742.051589682008, 4749.26386410107, 4117.3618089195625, 4197.958020419818, 4210.989362552965, 4213.494380674278, 4216.430781844473, 5864.120381942657, 5880.411958804171, 5906.586538942232, 5926.081457374114, 5935.970320148372, 6258.217722784652, 6258.812235632939, 6263.342349053375, 6274.1843560336865, 6283.622982621057, 5961.225040744675, 5969.611975221596, 5979.133025711248, 5980.581359304894, 6021.759129634853, 5096.9490305097215, 5123.5644970177955, 5207.765650545734, 5240.161574148118, 5269.446708095595, 6366.7771627993725, 6403.871922561616, 6422.74309027643, 6424.357564243538, 6426.8714625707225, 6194.442500175006, 6255.530835187288, 6258.687065646791, 6259.7496100157305, 6268.467180289544, 6124.0, 6126.029144987462, 6215.254169499689, 6420.518461115528, 6428.517861160338, 6123.526657168115, 6132.233136608343, 6132.355508992456, 6134.5590473767, 6141.36327073057, 6028.186194863637, 6243.531735119794, 6249.093881387264, 6256.374362563707, 6263.1857858479925, 5789.536837052941, 5982.1325907744, 6008.188894499362, 6046.90929636047, 6050.2437195350585, 6046.54650901193, 6073.605215660968, 6079.0, 6095.1466794647895, 6107.908381374372, 6071.241094863142, 6071.423214794588, 6082.817515474543, 6084.847878803065, 6092.573519853568, 5834.241548598655, 5902.675352855463, 6031.69841507914, 6065.787697430569, 6089.269287685506, 5973.641581505123, 5974.880502390015, 5997.880042399215, 6008.639481631116, 6039.818805435844], "value": 5683.540248039214, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.1266736918857, 822.4982760516084, 839.777458973372, 886.8625363068727, 891.1088911088915, 31875.52186717198, 43899.34150987734, 44909.77545112278, 44920.0, 45124.77437612807, 39595.40606890892, 39894.80052599733, 39908.0, 43819.78090109545, 43848.3422748659, 7568.470207085478, 7692.115406728242, 8976.59605317759, 9147.405418647766, 9152.267818574532, 10627.41549214793, 10765.677029689123, 10808.243422960395, 10815.080718138928, 10821.458927053616, 10908.890911090906, 10979.890201098007, 10982.121430285599, 10987.230893837435, 10989.725256868562, 10895.182861285395, 10909.563617455331, 10914.672559823139, 10929.180311476672, 10932.617358392417, 10592.470376481146, 10641.893581064167, 10642.201834862339, 10655.84016239757, 10662.147028237723, 42974.57025429768, 43055.56944430578, 43199.568004319866, 43236.3514547282, 43262.0, 4331.6534677225745, 4332.263515202423, 4369.541198174178, 4454.933176002362, 4522.977385113087, 29632.555511667342, 30567.69432305693, 30897.0, 30903.53644695309, 31591.0, 4213.389058586487, 4243.745375277463, 4303.655707543389, 4366.323219900889, 4398.472183337988, 227767.0, 228874.0, 229440.0, 229988.0, 230157.0, 53063.73468132634, 53477.73261133747, 53631.19553206705, 53708.46291537034, 53901.0, 55328.723356383765, 55684.72157639186, 55708.0, 55722.442775572534, 55727.44272557303, 16470.0, 16980.40568580094, 16983.91508042452, 16988.150592470327, 16997.40509082201, 12157.69605759863, 12323.75352492945, 15155.393784248727, 15162.696746065238, 15169.772453413208, 13418.39617217208, 13782.0, 14047.929760351133, 14102.224377659122, 14240.14559126435, 5259.474752321784, 5350.384705758887, 5362.0, 5385.3268341457315, 5438.347398312228, 1234.5925844471303, 1242.87571242875, 1286.6461723026055, 1287.5879718489994, 1311.0232876506873, 28456.549935149073, 28511.57232641512, 28630.0, 28662.283442914093, 29042.854785725936, 1314.0998416085013, 1346.811446397505, 1347.2792056250055, 1374.223563686534, 1378.5795332423706, 14670.559883203521, 14712.485063022743, 14795.408183672747, 14803.0, 15102.622434439227, 311862.0, 312359.0, 312762.7447451038, 313414.0, 313718.0, 58468.415315846294, 58530.0, 58539.41460585425, 58831.0, 58886.705566471894, 1459.9927000364717, 1474.852514748538, 1519.4074311018712, 1526.130105839683, 1562.6015366081804, 27267.454650907268, 28628.14115576536, 28773.568396473653, 29527.557086643286, 29571.85214074002, 41235.38146927858, 44333.0, 45177.096458069995, 50243.0, 50845.98308033893, 313796.0, 314696.0, 315951.0, 317835.0, 318887.0, 25503.6174457387, 25833.225003249932, 29597.0, 30492.237694057825, 30578.08265752031, 39197.804010979766, 39452.0, 39478.0, 39538.0, 39677.0, 30337.848310758305, 30745.385092298475, 30907.2273193163, 31016.224594384417, 31153.53269700911, 30021.69978300189, 30314.090577282717, 30390.54414183833, 30397.088087357413, 30735.84632076915, 34801.0, 35127.82436087803, 35155.0, 36604.63395366012, 37212.81393593015, 30967.845160774054, 31065.8446707765, 31254.843725781226, 31448.528272076393, 31805.681943181196, 24999.50001000006, 25926.71802804119, 25980.8700956494, 26117.608235876098, 26227.213183604523], "value": 19113.831206034116, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [447.1972808808189, 454.5568071130648, 458.4796256249156, 462.8125609128303, 479.46779075226493, 15935.601609959756, 16485.093319867403, 20519.179232830687, 22423.887880560615, 22992.65511017334, 22356.888215558898, 22479.0, 23072.42318942027, 23406.29781106565, 23596.882015589897, 6708.463322934178, 6904.896426553606, 6937.271586483397, 6949.339812717783, 6951.200611929642, 5349.5185433311, 6724.932750672504, 7695.076590809111, 7705.422093342993, 7716.151223365426, 7578.0906291244855, 7704.26809453101, 7889.132195458495, 7909.0, 7915.920840791576, 7834.921650783504, 7861.606919654052, 7876.960615196887, 7880.5271683698875, 7888.171741967097, 7629.961850190769, 7680.231976802331, 7680.308772210472, 7690.461667683265, 7693.153753087156, 25776.613350799755, 26029.219123426137, 26112.738872611222, 26193.345166370993, 26275.474490510274, 3081.3683194945206, 3159.4944808830705, 3161.1148878314107, 3179.5707579476707, 3229.7254733347695, 16295.755563666555, 16556.172191390382, 16589.419370321983, 16621.335146594123, 17192.398266060754, 2279.464325883416, 2305.319930620471, 2317.7450480447137, 2366.1600131953255, 2375.8930848111877, 84128.15871841264, 84671.0, 88014.5599272006, 99056.5047174774, 115956.0, 17423.73864392035, 18379.0, 18466.07669616514, 18636.534086647942, 18744.343947961755, 31505.0, 31629.36741265162, 31642.050738477883, 31682.207944801103, 31776.36447271088, 10549.419781912069, 10668.199885008655, 10679.19906007037, 10709.0, 10905.34567925927, 8942.73171804847, 8965.462072275684, 8985.236254918405, 9221.123993220634, 9224.677136300197, 8880.73357799267, 9047.638094476148, 9288.677830542365, 9311.208547273422, 9446.81914760655, 3622.4747411625167, 3846.8265430031943, 3961.593029994203, 4048.206737351218, 4063.756174629532, 979.1383582447401, 989.1690979577189, 991.1228562722043, 1015.3349556040805, 1016.7915577306634, 19848.60302793936, 20840.1663933444, 21127.894360528408, 21266.2556810514, 21423.357299281048, 982.1700662939804, 986.9407835529892, 988.2538683294067, 1002.2983911262146, 1003.267614641316, 11071.612493562849, 11409.942950285196, 11491.770164596828, 11522.193446458667, 11564.537418503334, 743799.0, 766600.0, 776416.0, 780852.0, 794337.0, 83587.58206209051, 84163.0, 109407.0, 113327.0, 114481.42759286151, 1281.5770795637604, 1290.3290289049642, 1322.814805927171, 1325.7746183148695, 1342.2148043394723, 18568.44294671162, 19016.809831902057, 19048.904755476688, 19213.0, 19250.422487325402, 51950.0, 52126.47873521368, 52465.475345247585, 52577.0, 52737.47262527479, 66705.0, 66710.0, 67341.3265867335, 67836.0, 68199.65900170467, 12450.626481205578, 12794.986356412472, 12922.418491168108, 13053.34733263314, 13200.273984930922, 14239.0, 14257.287135643384, 14444.85555144435, 14659.706805864036, 14728.189949552878, 11160.218784684994, 11212.719682008015, 11316.15128865355, 11384.203105782846, 11633.360165190996, 11540.884591153981, 11750.17748757613, 12024.158308918291, 12218.572349967708, 12342.0, 11771.52913883435, 12052.0, 12065.577704780291, 12195.329256890956, 12221.816672749737, 12510.18683785551, 12528.185667931555, 12657.240565566095, 12729.618111456672, 13015.804762928374, 11663.24188927751, 11805.940970295094, 12146.878531214928, 12287.385630718607, 12314.876851231373], "value": 11617.766058398243, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1571.481411134325, 1575.1809059289178, 1599.4401959314241, 1606.0765060090448, 1606.3092870065875, 10868.836967445484, 11716.472758725868, 11733.882661173386, 11747.060235181187, 11758.353290569015, 11328.09375249978, 11475.483603237853, 11588.59439919603, 11590.130740194478, 11606.0, 2520.7731304182576, 2551.515212109693, 2552.591585346345, 2558.6417901493805, 2561.5261176682375, 2462.2982450001755, 2471.789897858684, 2475.10896077412, 2480.7643273888953, 2482.900683972639, 2460.889259983296, 2488.688913885764, 2500.099964012954, 2503.8998440062373, 2508.8871000804916, 2467.6668649732324, 2494.3888747256947, 2515.9119430820015, 2521.773040426361, 2530.8354956927924, 2443.0960544598433, 2496.488219914931, 2503.5493611149986, 2509.2346834215555, 2523.2178024812365, 11393.088552915748, 11567.595134170348, 11585.826212606818, 11610.36143012134, 11614.709632259177, 5842.824715258549, 5958.165856780054, 5966.761329546813, 5966.761329546813, 5984.371640977722, 3490.0925759302763, 3576.2489877125836, 3639.181184233553, 3663.303972245265, 3689.7048236141045, 1253.9435725392289, 1290.2967882504058, 1291.9160254583421, 1321.7951217561294, 1347.5081595217773, 15554.688906221925, 16046.919765401099, 16086.195690215442, 17204.397846075328, 17413.390531331468, 4774.665773395898, 4840.177169881129, 4892.315075889378, 4966.404031516241, 5004.9499505005215, 11217.0, 11617.186796924303, 12309.32298723561, 13405.932970335281, 13423.194608323533, 6036.9698151508965, 6144.139820425144, 6146.0473626587955, 6146.78486252979, 6147.907781383283, 5654.37801841797, 5725.656460612377, 5727.255456790591, 5729.5702822288495, 5785.508231800344, 3944.585818489075, 4027.436158937751, 4045.554988951213, 4090.550039495653, 4120.320147175742, 1007.1590222164428, 1014.1278500489573, 1016.9119042624321, 1018.2871989607305, 1020.0972139656458, 1026.97432564185, 1041.2763129624839, 1049.375621505203, 1056.9852941176403, 1057.1701214546697, 6815.250322464525, 7010.334018268256, 7039.0, 7186.1376634804155, 7203.89194162088, 1033.1476531861094, 1050.3907733514543, 1064.8509208710786, 1066.5094056733835, 1067.5908497903608, 4089.3865920111625, 4151.086760912594, 4176.102138040336, 4187.392828039917, 4195.244855925902, 14123.081999669988, 14650.267486625626, 14660.120392776467, 14709.779353309495, 14768.556943291718, 14594.927025364805, 14617.19605421712, 14617.634559135893, 14630.195339256443, 14641.780373294408, 10183.134433573081, 10505.31715438494, 10786.244962852525, 10813.83779243312, 10814.567417303377, 7076.646167691698, 7083.610401427764, 7158.3915367192285, 7204.0, 7205.855882882208, 12647.936760316139, 13445.596632101053, 13455.663608409477, 13568.1859088455, 13632.522861699794, 13443.0, 13502.932485337511, 13627.523036693668, 13715.1085179463, 13731.313434328044, 9776.657816976372, 10073.294869359075, 10078.0, 10091.69724908254, 10095.54570044335, 10636.574537018432, 10752.0, 10799.838002429813, 10841.18691098155, 10863.619773307897, 9372.578233979624, 9402.2948278878, 9448.858267125861, 9512.429254244767, 9631.133198012212, 9273.582688778886, 9462.384944978554, 9467.958524562431, 9602.567884445078, 9619.374740641835, 8140.592970351578, 8159.959200203961, 8169.101398846004, 8217.424780265563, 8264.917350826414, 6270.9372906270355, 6359.332270111757, 6445.355464453704, 6448.226212854487, 6585.769498067736, 6398.968005159944, 6522.15212022453, 6561.278259391559, 6568.113304703804, 6679.799606011828], "value": 5442.9624628824085, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.642122090119, 430.3768530804746, 433.9999999999999, 434.38751360581585, 436.0559388922982, 5239.240310155025, 5247.816326428576, 5282.339707536558, 5291.4708529147065, 5363.731813409334, 5043.571296439807, 5272.156454967197, 5289.285946397235, 5296.443873393296, 5305.071612467812, 4424.225760491917, 4451.933221001679, 4486.416765820455, 4490.371348011281, 4493.258612328962, 4425.77871106445, 4432.20220360335, 4485.394471746322, 4488.910221795562, 4496.280595104784, 4403.273459879116, 4403.669724770639, 4421.0494743630115, 4465.37484752135, 4509.7970591323465, 4473.7986790594505, 4478.283474644058, 4487.394201782783, 4492.910141797179, 4516.979887899772, 4322.805473753689, 4324.545922678105, 4327.567243275647, 4333.0, 4333.804978775963, 5171.637985341028, 5173.508516690908, 5177.618295584036, 5179.896402071938, 5234.0, 5100.489951004907, 5322.387925388591, 5347.893042139174, 5352.946470535323, 5377.274068000809, 5538.75075621598, 5554.805581804618, 5570.359408668014, 5629.311206637993, 5648.971755141239, 4687.578117969365, 4867.415910090812, 4888.633352498541, 4889.652966576028, 4905.40574313346, 5505.394406615269, 5521.779128834842, 5661.160122960055, 5699.857503562403, 5705.0, 4956.826511072095, 4974.253861920669, 4987.301777751118, 5004.674696144812, 5026.632156455184, 5064.392272927199, 5071.949280507221, 5074.843191643537, 5078.577998160521, 5080.618953578496, 5039.899202016012, 5250.7637156328055, 5277.8416647500635, 5286.651903764516, 5343.919841202385, 5490.862728431741, 5542.307211598551, 5546.639468434539, 5564.4991950724625, 5572.442755724395, 1727.5508367824457, 1791.4682946081173, 1800.261892624018, 1809.4481183239238, 1820.5084627150595, 1427.8500757420322, 1429.6140042188758, 1466.8606482384157, 1467.6330917270682, 1480.7556753135827, 5446.0, 5466.021018544902, 5526.613137080366, 5527.296711203503, 5566.916496252559, 5637.633553819071, 5646.576506761927, 5661.858453538694, 5665.988574056233, 5690.971545142331, 5237.690577355661, 5417.783288668409, 5421.2952316199435, 5427.511523962862, 5441.972790136103, 2959.186223788475, 2959.5264757638674, 2971.4205729882897, 2972.197506673186, 2986.2385091801452, 5629.211910332557, 5740.138979153077, 5746.827595172152, 5762.020456522401, 5936.0, 5697.544196464273, 5732.369439361667, 5745.224394706661, 5778.624389414675, 5784.818349907534, 5770.221020162225, 5785.495771099544, 5796.681182535001, 5816.331121920949, 5852.4440178183, 5378.865528361822, 5555.000099982042, 5650.463205995424, 5661.801836935688, 5678.914816277842, 5349.304590403378, 5497.3952865184, 5617.662940223599, 5621.747021383966, 5650.85872853182, 5734.139879018181, 5744.109663002241, 5775.971120144541, 5818.94181058184, 5822.0, 4705.905881882411, 4713.552212539803, 4738.194506933898, 4765.928511072406, 4768.40258513388, 5488.835334939959, 5665.0, 5676.517496012885, 5681.9431805681415, 5692.231548740867, 5544.861378465571, 5554.833354999357, 5564.35851423839, 5580.01909322201, 5591.217229587943, 5410.404855465974, 5570.972145139248, 5573.21974923503, 5581.576697941999, 5592.496675299242, 5444.6188766785945, 5444.646098003617, 5460.890782184255, 5474.7810087597645, 5533.6956467393065, 5525.281713377235, 5533.944660553343, 5567.331920169606, 5569.159728538393, 5598.35618903815, 5281.234221038005, 5384.811531596375, 5389.380221274447, 5425.186222066722, 5427.864303392289], "value": 4518.159450052647, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [892.9687460938868, 926.5691453474135, 932.4358762948416, 942.5240253671894, 944.3955868244325, 15976.360945562177, 17160.742588861147, 17274.65450690987, 17294.567635809097, 19544.609107817836, 18388.816111838878, 18740.718889216638, 19507.5123121922, 19623.11695973681, 19698.50753731154, 877.1097336203758, 896.2964073202521, 898.9955050224739, 918.5407296351824, 921.4609453469728, 904.3624244907357, 905.7056456651615, 906.2885634776669, 907.6505545365048, 908.3868388837541, 949.1220620925651, 950.6862735297335, 952.2239374909461, 953.952302384878, 955.6750704760381, 947.829390709672, 949.6106596295521, 949.7862980829327, 951.5813042261383, 952.2953014769021, 692.3318997167758, 699.5697645947756, 700.5971566349318, 700.7127077898089, 700.982475438113, 18233.908830455897, 18271.451856444328, 18535.0, 19483.707744383704, 19620.313289034817, 17289.913550432168, 17457.47627571175, 17725.379611713528, 17747.290108395777, 18246.178921948544, 12842.935785321108, 13216.933915330457, 13257.072004959657, 13262.204267743966, 13457.86542134576, 3919.5884432134785, 3966.0679740261303, 3978.8408463661417, 3993.381025940984, 4039.9596004039877, 50823.74588127073, 55474.16788748172, 71498.92751608782, 71974.28025719729, 91216.0, 14573.271336433136, 14603.612656797588, 14627.92686036563, 14637.414503419957, 14745.92627036858, 27028.0, 27286.317842054108, 27349.45301093967, 27397.0, 27485.587716184273, 9017.684381046633, 9206.309526785384, 9215.53922303882, 9290.210332121846, 9317.068293170765, 5072.695638261716, 5082.898342033139, 5123.462036486116, 5187.63686541946, 5222.712750798667, 3927.23418933311, 4024.9195016099516, 4027.456293400412, 4037.454943582579, 4039.0508230565792, 657.9736810527621, 662.718344703499, 672.3478226120656, 675.6587923098901, 682.3483573187594, 790.6679194738224, 797.5055465611242, 814.2061490047157, 815.3314282288467, 819.1399031017407, 11405.942970285261, 11639.418029098511, 11963.521459141537, 12133.575324863765, 12315.261084334968, 766.5822126940831, 771.2711487644123, 783.2637320918318, 796.2913007423398, 799.1968072087515, 5274.446183150791, 5528.281323428009, 5617.43825617435, 5624.437556244343, 5661.575381846378, 85250.14749852546, 85876.0, 86010.56994714987, 86783.13216867877, 86966.56516717376, 68738.31261687419, 69114.64137403789, 74257.62871185718, 74914.0, 75371.0, 61074.0, 61738.0, 61962.69018654878, 62010.68994655088, 62023.37976620266, 11754.353510556659, 11831.585894493652, 12255.816162757745, 12278.140530162802, 12395.132340736061, 42396.0, 43178.568214317456, 43682.0, 44102.0, 44432.0, 51027.0, 51055.48944510507, 51237.74381128071, 51973.480265196864, 53689.0, 13288.534901278408, 13335.0, 13381.933090334485, 13522.661933451742, 13569.0, 15709.293081811118, 15912.443064492687, 16198.271077801292, 16248.918755406148, 16341.836581634032, 14734.484293049692, 15278.388864445298, 15668.68662626764, 16298.918505407872, 16332.346706131622, 14666.413343466142, 14925.0, 15498.457553985556, 15617.297221624827, 15860.60348491297, 13973.371198295898, 14225.359858806172, 14499.420023198954, 14544.418223271374, 14823.703525929637, 9298.628054877729, 9691.0, 9838.508074596384, 10066.8489972649, 10081.495925203564, 11667.0, 11878.643640690792, 12545.372731363577, 12721.681957951125, 12858.549950751678], "value": 8040.675680603302, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1453.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 114.0, 113.0, 113.0, 113.0, 111.0, 113.0, 113.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 114.0, 115.0, 145.0, 120.0, 118.0, 118.0, 120.0, 140.0, 116.0], "value": 124.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 161.0, 159.0, 162.0, 160.0, 160.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 109.0, 76.0, 75.0, 75.0, 76.0, 89.0, 75.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 101.0, 82.0, 81.0, 82.0, 99.0, 81.0, 82.0, 115.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 147.0, 148.0, 146.0, 145.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 54.0, 53.0, 55.0, 54.0, 55.0, 55.0, 61.0, 54.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 52.0, 52.0, 52.0, 52.0, 52.0, 52.0, 52.0, 52.0], "value": 52.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 77.0, 76.0, 84.0, 77.0, 75.0, 80.0, 84.0, 80.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 128.0, 121.0, 137.0, 119.0, 118.0, 117.0, 118.0, 157.0, 127.0], "value": 126.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 163.0, 165.0, 161.0, 170.0, 164.0, 158.0, 161.0, 161.0, 159.0], "value": 162.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 59.0, 60.0, 60.0, 59.0, 60.0, 59.0, 59.0, 60.0], "value": 59.8, "unit": "score"}]}]} 06:18:06 INFO - Return code: 0 06:18:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:18:07 INFO - # TBPL SUCCESS # 06:18:07 INFO - Running post-action listener: _resource_record_post_action 06:18:07 INFO - [mozharness: 2017-05-22 13:18:07.070123Z] Finished run-tests step (success) 06:18:07 INFO - Running post-run listener: _resource_record_post_run 06:18:07 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 317124608; Read time: 12; Write time: 223412 06:18:07 INFO - TinderboxPrint: CPU usage
13.2% 06:18:07 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 06:18:07 INFO - TinderboxPrint: I/O write bytes / time
317,124,608 / 223,412 06:18:07 INFO - TinderboxPrint: CPU idle
8,249.9 (86.8%) 06:18:07 INFO - TinderboxPrint: CPU user
1,200.7 (12.6%) 06:18:07 INFO - TinderboxPrint: Swap in / out
0 / 0 06:18:07 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 80117760; Read time: 0; Write time: 55664 06:18:07 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 217808896; Read time: 0; Write time: 116476 06:18:07 INFO - Running post-run listener: _upload_blobber_files 06:18:07 INFO - Blob upload gear active. 06:18:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:18:07 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 06:18:07 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:18:07 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:18:08 INFO - (blobuploader) - INFO - Open directory for files ... 06:18:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:18:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:18:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:09 INFO - (blobuploader) - INFO - Done attempting. 06:18:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:18:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:18:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:12 INFO - (blobuploader) - INFO - Done attempting. 06:18:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:18:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:18:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:13 INFO - (blobuploader) - INFO - Done attempting. 06:18:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:18:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:18:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:14 INFO - (blobuploader) - INFO - Done attempting. 06:18:14 INFO - (blobuploader) - INFO - Iteration through files over. 06:18:14 INFO - Return code: 0 06:18:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:18:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:18:14 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/703f50aa71751969fe3d9435fa7b0cefa45800331b80aaa136b1fe364df092a3c6e89d1db9923ca44a3a561abebca26064692669f367c9b25a554b4879819abb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e33f8b57215598c991a4b4af8dee0af86a3c9e82df4f73960636f53bd8ac8bf77ee7dfc890822f3d7594860bd7f6795d9eb869bfb4d61feea945b9b145a733fc", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/de9462fea2fa80b57e8d8ec922cbe89151d8645eae9f3f430aaffb3f6d41eaf5083b3ee25c336f0de253fcf00f5d3665020c14709104cbb14175712e3b548ea6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b84dce34357a64f63804ca39ca0d0aea6a61cb1d547f9082eddbb4d1b4dd21ebf32cb548ee8ec56e52c1603103c5af8c3b8ca6c15cb8dde1bed5a142c145f480"} 06:18:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:18:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:18:14 INFO - Contents: 06:18:14 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/703f50aa71751969fe3d9435fa7b0cefa45800331b80aaa136b1fe364df092a3c6e89d1db9923ca44a3a561abebca26064692669f367c9b25a554b4879819abb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e33f8b57215598c991a4b4af8dee0af86a3c9e82df4f73960636f53bd8ac8bf77ee7dfc890822f3d7594860bd7f6795d9eb869bfb4d61feea945b9b145a733fc", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/de9462fea2fa80b57e8d8ec922cbe89151d8645eae9f3f430aaffb3f6d41eaf5083b3ee25c336f0de253fcf00f5d3665020c14709104cbb14175712e3b548ea6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b84dce34357a64f63804ca39ca0d0aea6a61cb1d547f9082eddbb4d1b4dd21ebf32cb548ee8ec56e52c1603103c5af8c3b8ca6c15cb8dde1bed5a142c145f480"} 06:18:14 INFO - Running post-run listener: copy_logs_to_upload_dir 06:18:14 INFO - Copying logs to upload dir... 06:18:14 INFO - mkdir: /builds/slave/test/build/upload/logs 06:18:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.033640 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-05-22 06:18:11.873560) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 06:18:11.876815) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/703f50aa71751969fe3d9435fa7b0cefa45800331b80aaa136b1fe364df092a3c6e89d1db9923ca44a3a561abebca26064692669f367c9b25a554b4879819abb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e33f8b57215598c991a4b4af8dee0af86a3c9e82df4f73960636f53bd8ac8bf77ee7dfc890822f3d7594860bd7f6795d9eb869bfb4d61feea945b9b145a733fc", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/de9462fea2fa80b57e8d8ec922cbe89151d8645eae9f3f430aaffb3f6d41eaf5083b3ee25c336f0de253fcf00f5d3665020c14709104cbb14175712e3b548ea6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b84dce34357a64f63804ca39ca0d0aea6a61cb1d547f9082eddbb4d1b4dd21ebf32cb548ee8ec56e52c1603103c5af8c3b8ca6c15cb8dde1bed5a142c145f480"} build_url:https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011311 build_url: 'https://queue.taskcluster.net/v1/task/Rw1X2GC9T5616dIjwRqBgw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/703f50aa71751969fe3d9435fa7b0cefa45800331b80aaa136b1fe364df092a3c6e89d1db9923ca44a3a561abebca26064692669f367c9b25a554b4879819abb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e33f8b57215598c991a4b4af8dee0af86a3c9e82df4f73960636f53bd8ac8bf77ee7dfc890822f3d7594860bd7f6795d9eb869bfb4d61feea945b9b145a733fc", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/de9462fea2fa80b57e8d8ec922cbe89151d8645eae9f3f430aaffb3f6d41eaf5083b3ee25c336f0de253fcf00f5d3665020c14709104cbb14175712e3b548ea6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b84dce34357a64f63804ca39ca0d0aea6a61cb1d547f9082eddbb4d1b4dd21ebf32cb548ee8ec56e52c1603103c5af8c3b8ca6c15cb8dde1bed5a142c145f480"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 06:18:11.901069) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 06:18:11.901405) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1495455257.223668-456598234 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004465 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 06:18:11.986218) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 06:18:11.986537) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 06:18:11.986857) ========= ========= Total master_lag: 0.14 =========