builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-009 starttime: 1495208052.43 results: success (0) revision: 61932206bf73a5c510f97eb3089b823b97b1141e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 08:34:12.435212) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 08:34:12.435683) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 08:34:12.435964) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 08:34:12.459058) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 08:34:12.459435) ========= 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=17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-19 08:34:12-- 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% 183K=0.07s 2017-05-19 08:34:13 (183 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.201803 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 08:34:12.679048) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 08:34:12.679431) ========= 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=17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020263 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 08:34:12.729618) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 08:34:12.733114) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 61932206bf73a5c510f97eb3089b823b97b1141e --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 61932206bf73a5c510f97eb3089b823b97b1141e --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=17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-19 08:34:13,290 truncating revision to first 12 chars 2017-05-19 08:34:13,290 Setting DEBUG logging. 2017-05-19 08:34:13,290 attempt 1/10 2017-05-19 08:34:13,290 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/61932206bf73?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-19 08:34:13,668 unpacking tar archive at: mozilla-beta-61932206bf73/testing/mozharness/ program finished with exit code 0 elapsedTime=0.686763 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 08:34:13.442304) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 08:34:13.442597) ========= script_repo_revision: 61932206bf73a5c510f97eb3089b823b97b1141e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 08:34:13.442963) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 08:34:13.443228) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 08:34:13.459264) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-05-19 08:34:13.459652) ========= /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=17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:34:14 INFO - MultiFileLogger online at 20170519 08:34:14 in /builds/slave/test 08:34:14 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 08:34:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:34:14 INFO - {'append_to_log': False, 08:34:14 INFO - 'base_work_dir': '/builds/slave/test', 08:34:14 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 08:34:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:34:14 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 08:34:14 INFO - 'buildbot_json_path': 'buildprops.json', 08:34:14 INFO - 'config_files': ('talos/linux_config.py',), 08:34:14 INFO - 'default_actions': ('clobber', 08:34:14 INFO - 'read-buildbot-config', 08:34:14 INFO - 'download-and-extract', 08:34:14 INFO - 'populate-webroot', 08:34:14 INFO - 'create-virtualenv', 08:34:14 INFO - 'install', 08:34:14 INFO - 'run-tests'), 08:34:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:34:14 INFO - 'download_minidump_stackwalk': True, 08:34:14 INFO - 'download_symbols': 'ondemand', 08:34:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:34:14 INFO - 'tooltool.py': '/tools/tooltool.py', 08:34:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:34:14 INFO - '/tools/misc-python/virtualenv.py')}, 08:34:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:34:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:34:14 INFO - 'gecko_profile': False, 08:34:14 INFO - 'gecko_profile_interval': 0, 08:34:14 INFO - 'installer_path': 'installer.exe', 08:34:14 INFO - 'log_level': 'info', 08:34:14 INFO - 'log_name': 'talos', 08:34:14 INFO - 'log_to_console': True, 08:34:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:34:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:34:14 INFO - 'opt_config_files': (), 08:34:14 INFO - 'pip_index': False, 08:34:14 INFO - 'suite': 'dromaeojs', 08:34:14 INFO - 'system_bits': '32', 08:34:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:34:14 INFO - 'title': 'talos-linux64-ix-009', 08:34:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:34:14 INFO - 'use_talos_json': True, 08:34:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:34:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:34:14 INFO - 'work_dir': 'build'} 08:34:14 INFO - [mozharness: 2017-05-19 15:34:14.121133Z] Running clobber step. 08:34:14 INFO - Running pre-action listener: _resource_record_pre_action 08:34:14 INFO - Running main action method: clobber 08:34:14 INFO - rmtree: /builds/slave/test/build 08:34:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:34:15 INFO - Running post-action listener: _resource_record_post_action 08:34:15 INFO - [mozharness: 2017-05-19 15:34:15.151950Z] Finished clobber step (success) 08:34:15 INFO - [mozharness: 2017-05-19 15:34:15.152051Z] Running read-buildbot-config step. 08:34:15 INFO - Running pre-action listener: _resource_record_pre_action 08:34:15 INFO - Running main action method: read_buildbot_config 08:34:15 INFO - Using buildbot properties: 08:34:15 INFO - { 08:34:15 INFO - "project": "", 08:34:15 INFO - "product": "firefox", 08:34:15 INFO - "who": "mhowell@mozilla.com", 08:34:15 INFO - "installer_path": "public/build/target.tar.bz2", 08:34:15 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 08:34:15 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 08:34:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 08:34:15 INFO - "stage_platform": "linux64", 08:34:15 INFO - "basedir": "/builds/slave/test", 08:34:15 INFO - "buildnumber": 50, 08:34:15 INFO - "platform": "ubuntu64_hw", 08:34:15 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:34:15 INFO - "slavebuilddir": "test", 08:34:15 INFO - "taskId": "Kv72Ap0LQWiW9FdumuIrvQ", 08:34:15 INFO - "branch": "mozilla-beta", 08:34:15 INFO - "script_repo_revision": "production", 08:34:15 INFO - "revision": "61932206bf73a5c510f97eb3089b823b97b1141e", 08:34:15 INFO - "slavename": "talos-linux64-ix-009", 08:34:15 INFO - "repo_path": "releases/mozilla-beta" 08:34:15 INFO - } 08:34:15 INFO - Finding installer, test and symbols from parent task. 08:34:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kv72Ap0LQWiW9FdumuIrvQ'}, attempt #1 08:34:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kv72Ap0LQWiW9FdumuIrvQ'}, attempt #1 08:34:16 INFO - Task dependencies: LOTEA0biQh-r5RQNNMcs-w 08:34:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w'}, attempt #1 08:34:16 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.tar.bz2 08:34:16 INFO - Set test_url: https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/test_packages.json 08:34:16 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.crashreporter-symbols.zip 08:34:16 INFO - Running post-action listener: _resource_record_post_action 08:34:16 INFO - [mozharness: 2017-05-19 15:34:16.496755Z] Finished read-buildbot-config step (success) 08:34:16 INFO - [mozharness: 2017-05-19 15:34:16.496974Z] Running download-and-extract step. 08:34:16 INFO - Running pre-action listener: _resource_record_pre_action 08:34:16 INFO - Running main action method: download_and_extract 08:34:16 INFO - mkdir: /builds/slave/test/build/tests 08:34: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')]} 08:34:16 INFO - trying https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.test_packages.json 08:34:16 INFO - Downloading https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:34:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:34:17 INFO - Downloaded 986 bytes. 08:34:17 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:34:17 INFO - Using the following test package requirements: 08:34:17 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:34:17 INFO - u'common': [u'target.common.tests.zip'], 08:34:17 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:34:17 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:34:17 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:34:17 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:34:17 INFO - u'mozbase': [u'target.common.tests.zip'], 08:34:17 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:34:17 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:34:17 INFO - u'web-platform': [u'target.common.tests.zip', 08:34:17 INFO - u'target.web-platform.tests.zip'], 08:34:17 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:34:17 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:34:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.common.tests.zip 08:34:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:34:17 INFO - Fetch https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.common.tests.zip into memory 08:34:19 INFO - Expected file size: 59543848 08:34:19 INFO - Obtained file size: 59543848 08:34:28 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:34:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.common.tests.zip 08:34:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:34:28 INFO - Fetch https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.common.tests.zip into memory 08:34:30 INFO - Expected file size: 59543848 08:34:30 INFO - Obtained file size: 59543848 08:34:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.talos.tests.zip 08:34:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:34:41 INFO - Fetch https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.talos.tests.zip into memory 08:34:41 INFO - Expected file size: 14404449 08:34:41 INFO - Obtained file size: 14404449 08:34:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:42 INFO - trying https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.tar.bz2 08:34:42 INFO - Downloading https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:34:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:34:44 INFO - Downloaded 59728992 bytes. 08:34:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.tar.bz2 08:34:44 INFO - mkdir: /builds/slave/test/properties 08:34:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:34:44 INFO - Writing to file /builds/slave/test/properties/build_url 08:34:44 INFO - Contents: 08:34:44 INFO - build_url:https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.tar.bz2 08:34:44 INFO - Running post-action listener: _resource_record_post_action 08:34:44 INFO - Running post-action listener: set_extra_try_arguments 08:34:44 INFO - [mozharness: 2017-05-19 15:34:44.981693Z] Finished download-and-extract step (success) 08:34:44 INFO - [mozharness: 2017-05-19 15:34:44.981792Z] Running populate-webroot step. 08:34:44 INFO - Running pre-action listener: _resource_record_pre_action 08:34:44 INFO - Running main action method: populate_webroot 08:34:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:34:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:34:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:34:44 INFO - '--tppagecycles', 08:34:44 INFO - '7'], 08:34:44 INFO - 'tests': ['tsvgm']}}, 08:34:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:34:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:34:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:34:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:34:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:34:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:34:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:34:44 INFO - 'talos_options': ['--disable-e10s'], 08:34:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:34:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:34:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:34:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:34:44 INFO - 'talos_options': ['--disable-e10s'], 08:34:44 INFO - 'tests': ['damp', 'tps']}, 08:34:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:34:44 INFO - 'tests': ['damp', 'tps']}, 08:34:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:34:44 INFO - 'tests': ['dromaeo_dom']}, 08:34:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:34:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:34:44 INFO - 'tests': ['basic_compositor_video']}, 08:34:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:34:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:34:44 INFO - 'tests': ['a11yr', 08:34:44 INFO - 'ts_paint', 08:34:44 INFO - 'tpaint', 08:34:44 INFO - 'sessionrestore', 08:34:44 INFO - 'sessionrestore_no_auto_restore', 08:34:44 INFO - 'tabpaint']}, 08:34:44 INFO - 'other-e10s': {'tests': ['a11yr', 08:34:44 INFO - 'ts_paint', 08:34:44 INFO - 'tpaint', 08:34:44 INFO - 'sessionrestore', 08:34:44 INFO - 'sessionrestore_no_auto_restore', 08:34:44 INFO - 'tabpaint']}, 08:34:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:34:44 INFO - 'ts_paint', 08:34:44 INFO - 'tpaint', 08:34:44 INFO - 'sessionrestore', 08:34:44 INFO - 'sessionrestore_no_auto_restore', 08:34:44 INFO - 'tabpaint']}, 08:34:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:34:44 INFO - 'ts_paint', 08:34:44 INFO - 'tpaint', 08:34:44 INFO - 'sessionrestore', 08:34:44 INFO - 'sessionrestore_no_auto_restore', 08:34:44 INFO - 'tabpaint']}, 08:34:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:34:44 INFO - 'tests': ['a11yr', 08:34:44 INFO - 'ts_paint', 08:34:44 INFO - 'tpaint', 08:34:44 INFO - 'sessionrestore', 08:34:44 INFO - 'sessionrestore_no_auto_restore', 08:34:44 INFO - 'tabpaint']}, 08:34:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:34:44 INFO - 'tests': ['a11yr', 08:34:44 INFO - 'ts_paint', 08:34:44 INFO - 'tpaint', 08:34:44 INFO - 'sessionrestore', 08:34:44 INFO - 'sessionrestore_no_auto_restore', 08:34:44 INFO - 'tabpaint']}, 08:34:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:34:44 INFO - 'tests': ['tsvgx', 08:34:44 INFO - 'tsvgr_opacity', 08:34:44 INFO - 'tart', 08:34:44 INFO - 'tscrollx', 08:34:44 INFO - 'cart', 08:34:44 INFO - 'tsvg_static']}, 08:34:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:34:44 INFO - 'tsvgr_opacity', 08:34:44 INFO - 'tart', 08:34:44 INFO - 'tscrollx', 08:34:44 INFO - 'cart', 08:34:44 INFO - 'tsvg_static']}, 08:34:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:34:44 INFO - 'talos_options': ['--disable-e10s'], 08:34:44 INFO - 'tests': ['tp5o']}, 08:34:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:34:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:34:44 INFO - 'talos_options': ['--disable-e10s', 08:34:44 INFO - '--xperf_path', 08:34:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:34:44 INFO - 'tests': ['tp5n']}, 08:34:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:34:44 INFO - 'talos_options': ['--xperf_path', 08:34:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:34:44 INFO - 'tests': ['tp5n']}}, 08:34:44 INFO - 'talos.zip': {'path': '', 08:34:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:34:44 INFO - Running post-action listener: _resource_record_post_action 08:34:44 INFO - [mozharness: 2017-05-19 15:34:44.994926Z] Finished populate-webroot step (success) 08:34:44 INFO - [mozharness: 2017-05-19 15:34:44.995017Z] Running create-virtualenv step. 08:34:44 INFO - Running pre-action listener: _resource_record_pre_action 08:34:44 INFO - Running main action method: create_virtualenv 08:34:44 INFO - Creating virtualenv /builds/slave/test/build/venv 08:34:44 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 08:34:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:34:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:34:45 INFO - Using real prefix '/usr' 08:34:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:34:45 INFO - Installing distribute.............................................................................................................................................................................................done. 08:34:47 INFO - Installing pip.................done. 08:34:47 INFO - Return code: 0 08:34:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:34:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:34:47 INFO - Reading from file tmpfile_stdout 08:34:47 INFO - Output received: 08:34:47 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) 08:34:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:34:47 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')]} 08:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:47 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')]} 08:34:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:47 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 0xb681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe06310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03400>, '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 0xe043b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe04840>, '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': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', '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 08:34:47 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 08:34:47 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 08:34:47 INFO - Using env: {'DISPLAY': ':0', 08:34:47 INFO - 'HOME': '/home/cltbld', 08:34:47 INFO - 'LANG': 'en_US.UTF-8', 08:34:47 INFO - 'LANGUAGE': 'en_US:en', 08:34:47 INFO - 'LOGNAME': 'cltbld', 08:34:47 INFO - 'MAIL': '/var/mail/cltbld', 08:34:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:47 INFO - 'MOZ_NO_REMOTE': '1', 08:34:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:34:47 INFO - 'NO_EM_RESTART': '1', 08:34:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:34:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:47 INFO - 'PWD': '/builds/slave/test', 08:34:47 INFO - 'SHELL': '/bin/bash', 08:34:47 INFO - 'SHLVL': '1', 08:34:47 INFO - 'TERM': 'linux', 08:34:47 INFO - 'TMOUT': '86400', 08:34:47 INFO - 'USER': 'cltbld', 08:34:47 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', 08:34:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:47 INFO - '_': '/tools/buildbot/bin/python'} 08:34:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:47 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 08:34:47 INFO - Cleaning up... 08:34:47 INFO - Return code: 0 08:34:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:34:47 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')]} 08:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:47 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')]} 08:34:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:48 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 0xb681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe06310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03400>, '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 0xe043b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe04840>, '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': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', '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 08:34:48 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 08:34:48 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 08:34:48 INFO - Using env: {'DISPLAY': ':0', 08:34:48 INFO - 'HOME': '/home/cltbld', 08:34:48 INFO - 'LANG': 'en_US.UTF-8', 08:34:48 INFO - 'LANGUAGE': 'en_US:en', 08:34:48 INFO - 'LOGNAME': 'cltbld', 08:34:48 INFO - 'MAIL': '/var/mail/cltbld', 08:34:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:48 INFO - 'MOZ_NO_REMOTE': '1', 08:34:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:34:48 INFO - 'NO_EM_RESTART': '1', 08:34:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:34:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:48 INFO - 'PWD': '/builds/slave/test', 08:34:48 INFO - 'SHELL': '/bin/bash', 08:34:48 INFO - 'SHLVL': '1', 08:34:48 INFO - 'TERM': 'linux', 08:34:48 INFO - 'TMOUT': '86400', 08:34:48 INFO - 'USER': 'cltbld', 08:34:48 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', 08:34:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:48 INFO - '_': '/tools/buildbot/bin/python'} 08:34:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:48 INFO - Downloading/unpacking psutil>=3.1.1 08:34:48 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 08:34:48 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 08:34:48 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 08:34:48 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 08:34:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:34:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:34:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:34:49 INFO - Installing collected packages: psutil 08:34:49 INFO - Running setup.py install for psutil 08:34:49 INFO - building 'psutil._psutil_linux' extension 08:34:49 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 08:34:49 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 08:34:49 INFO - building 'psutil._psutil_posix' extension 08:34:49 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 08:34:49 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 08:34:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:34:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:34:49 INFO - Successfully installed psutil 08:34:49 INFO - Cleaning up... 08:34:49 INFO - Return code: 0 08:34:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:34:49 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')]} 08:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:49 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')]} 08:34:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:49 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 0xb681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe06310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03400>, '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 0xe043b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe04840>, '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': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', '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 08:34:49 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 08:34:49 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 08:34:49 INFO - Using env: {'DISPLAY': ':0', 08:34:49 INFO - 'HOME': '/home/cltbld', 08:34:49 INFO - 'LANG': 'en_US.UTF-8', 08:34:49 INFO - 'LANGUAGE': 'en_US:en', 08:34:49 INFO - 'LOGNAME': 'cltbld', 08:34:49 INFO - 'MAIL': '/var/mail/cltbld', 08:34:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:49 INFO - 'MOZ_NO_REMOTE': '1', 08:34:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:34:49 INFO - 'NO_EM_RESTART': '1', 08:34:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:34:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:49 INFO - 'PWD': '/builds/slave/test', 08:34:49 INFO - 'SHELL': '/bin/bash', 08:34:49 INFO - 'SHLVL': '1', 08:34:49 INFO - 'TERM': 'linux', 08:34:49 INFO - 'TMOUT': '86400', 08:34:49 INFO - 'USER': 'cltbld', 08:34:49 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', 08:34:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:49 INFO - '_': '/tools/buildbot/bin/python'} 08:34:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:34:50 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 08:34:50 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 08:34:50 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 08:34:50 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 08:34:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:34:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:34:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:34:51 INFO - Installing collected packages: mozsystemmonitor 08:34:51 INFO - Running setup.py install for mozsystemmonitor 08:34:51 INFO - Successfully installed mozsystemmonitor 08:34:51 INFO - Cleaning up... 08:34:51 INFO - Return code: 0 08:34:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:34:51 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')]} 08:34:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:51 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')]} 08:34:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:51 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 0xb681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe06310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03400>, '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 0xe043b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe04840>, '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': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', '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 08:34:51 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 08:34:51 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 08:34:51 INFO - Using env: {'DISPLAY': ':0', 08:34:51 INFO - 'HOME': '/home/cltbld', 08:34:51 INFO - 'LANG': 'en_US.UTF-8', 08:34:51 INFO - 'LANGUAGE': 'en_US:en', 08:34:51 INFO - 'LOGNAME': 'cltbld', 08:34:51 INFO - 'MAIL': '/var/mail/cltbld', 08:34:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:51 INFO - 'MOZ_NO_REMOTE': '1', 08:34:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:34:51 INFO - 'NO_EM_RESTART': '1', 08:34:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:34:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:51 INFO - 'PWD': '/builds/slave/test', 08:34:51 INFO - 'SHELL': '/bin/bash', 08:34:51 INFO - 'SHLVL': '1', 08:34:51 INFO - 'TERM': 'linux', 08:34:51 INFO - 'TMOUT': '86400', 08:34:51 INFO - 'USER': 'cltbld', 08:34:51 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', 08:34:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:51 INFO - '_': '/tools/buildbot/bin/python'} 08:34:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:51 INFO - Downloading/unpacking jsonschema==2.5.1 08:34:51 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 08:34:51 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 08:34:51 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 08:34:51 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 08:34:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:34:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:34:52 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 08:34:52 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 08:34:52 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 08:34:52 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 08:34:52 INFO - Downloading functools32-3.2.3-2.tar.gz 08:34:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:34:52 INFO - warning: no files found matching '*.txt' 08:34:52 INFO - no previously-included directories found matching 'build' 08:34:52 INFO - no previously-included directories found matching 'dist' 08:34:52 INFO - no previously-included directories found matching '.git*' 08:34:52 INFO - Installing collected packages: jsonschema, functools32 08:34:53 INFO - Running setup.py install for functools32 08:34:53 INFO - warning: no files found matching '*.txt' 08:34:53 INFO - no previously-included directories found matching 'build' 08:34:53 INFO - no previously-included directories found matching 'dist' 08:34:53 INFO - no previously-included directories found matching '.git*' 08:34:53 INFO - Successfully installed jsonschema functools32 08:34:53 INFO - Cleaning up... 08:34:53 INFO - Return code: 0 08:34:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:34:53 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')]} 08:34:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:53 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')]} 08:34:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:53 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 0xb681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe06310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03400>, '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 0xe043b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe04840>, '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': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', '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 08:34:53 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 08:34:53 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 08:34:53 INFO - Using env: {'DISPLAY': ':0', 08:34:53 INFO - 'HOME': '/home/cltbld', 08:34:53 INFO - 'LANG': 'en_US.UTF-8', 08:34:53 INFO - 'LANGUAGE': 'en_US:en', 08:34:53 INFO - 'LOGNAME': 'cltbld', 08:34:53 INFO - 'MAIL': '/var/mail/cltbld', 08:34:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:53 INFO - 'MOZ_NO_REMOTE': '1', 08:34:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:34:53 INFO - 'NO_EM_RESTART': '1', 08:34:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:34:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:53 INFO - 'PWD': '/builds/slave/test', 08:34:53 INFO - 'SHELL': '/bin/bash', 08:34:53 INFO - 'SHLVL': '1', 08:34:53 INFO - 'TERM': 'linux', 08:34:53 INFO - 'TMOUT': '86400', 08:34:53 INFO - 'USER': 'cltbld', 08:34:53 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', 08:34:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:53 INFO - '_': '/tools/buildbot/bin/python'} 08:34:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:34:53 INFO - Cleaning up... 08:34:53 INFO - Return code: 0 08:34:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:34:53 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')]} 08:34:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:53 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')]} 08:34:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:53 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 0xb681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe06310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03400>, '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 0xe043b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe04840>, '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': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', '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 08:34:53 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 08:34:53 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 08:34:53 INFO - Using env: {'DISPLAY': ':0', 08:34:53 INFO - 'HOME': '/home/cltbld', 08:34:53 INFO - 'LANG': 'en_US.UTF-8', 08:34:53 INFO - 'LANGUAGE': 'en_US:en', 08:34:53 INFO - 'LOGNAME': 'cltbld', 08:34:53 INFO - 'MAIL': '/var/mail/cltbld', 08:34:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:53 INFO - 'MOZ_NO_REMOTE': '1', 08:34:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:34:53 INFO - 'NO_EM_RESTART': '1', 08:34:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:34:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:53 INFO - 'PWD': '/builds/slave/test', 08:34:53 INFO - 'SHELL': '/bin/bash', 08:34:53 INFO - 'SHLVL': '1', 08:34:53 INFO - 'TERM': 'linux', 08:34:53 INFO - 'TMOUT': '86400', 08:34:53 INFO - 'USER': 'cltbld', 08:34:53 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', 08:34:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:53 INFO - '_': '/tools/buildbot/bin/python'} 08:34:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:53 INFO - Downloading/unpacking blobuploader==1.2.4 08:34:53 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 08:34:53 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 08:34:53 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 08:34:53 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 08:34:54 INFO - Downloading blobuploader-1.2.4.tar.gz 08:34:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:34:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:34:54 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 08:34:54 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 08:34:54 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 08:34:54 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 08:34:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:34:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:34:55 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 08:34:55 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 08:34:55 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 08:34:55 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 08:34:55 INFO - Downloading docopt-0.6.1.tar.gz 08:34:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:34:55 INFO - Installing collected packages: blobuploader, requests, docopt 08:34:55 INFO - Running setup.py install for blobuploader 08:34:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:34:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:34:55 INFO - Running setup.py install for requests 08:34:55 INFO - Running setup.py install for docopt 08:34:55 INFO - Successfully installed blobuploader requests docopt 08:34:55 INFO - Cleaning up... 08:34:55 INFO - Return code: 0 08:34:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:34:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:56 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 0xb681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe06310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03400>, '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 0xe043b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe04840>, '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': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', '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 08:34:56 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 08:34:56 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 08:34:56 INFO - Using env: {'DISPLAY': ':0', 08:34:56 INFO - 'HOME': '/home/cltbld', 08:34:56 INFO - 'LANG': 'en_US.UTF-8', 08:34:56 INFO - 'LANGUAGE': 'en_US:en', 08:34:56 INFO - 'LOGNAME': 'cltbld', 08:34:56 INFO - 'MAIL': '/var/mail/cltbld', 08:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:56 INFO - 'MOZ_NO_REMOTE': '1', 08:34:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:34:56 INFO - 'NO_EM_RESTART': '1', 08:34:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:56 INFO - 'PWD': '/builds/slave/test', 08:34:56 INFO - 'SHELL': '/bin/bash', 08:34:56 INFO - 'SHLVL': '1', 08:34:56 INFO - 'TERM': 'linux', 08:34:56 INFO - 'TMOUT': '86400', 08:34:56 INFO - 'USER': 'cltbld', 08:34:56 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', 08:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:56 INFO - '_': '/tools/buildbot/bin/python'} 08:34:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:34:56 INFO - Running setup.py (path:/tmp/pip-Bm3Myq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:34:56 INFO - Running setup.py (path:/tmp/pip-h5ZfIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:34:56 INFO - Running setup.py (path:/tmp/pip-CcwgH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:34:56 INFO - Running setup.py (path:/tmp/pip-Bh478t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:34:56 INFO - Running setup.py (path:/tmp/pip-UcChxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:34:56 INFO - Running setup.py (path:/tmp/pip-r97rgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:34:56 INFO - Running setup.py (path:/tmp/pip-REm3c1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:34:56 INFO - Running setup.py (path:/tmp/pip-KKZomg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:34:56 INFO - Running setup.py (path:/tmp/pip-hvbjrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:34:56 INFO - Running setup.py (path:/tmp/pip-fWedsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:34:56 INFO - Running setup.py (path:/tmp/pip-W2jdIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:34:57 INFO - Running setup.py (path:/tmp/pip-d9O4Yn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:34:57 INFO - Running setup.py (path:/tmp/pip-2qExrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:34:57 INFO - Running setup.py (path:/tmp/pip-qgEJpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:34:57 INFO - Running setup.py (path:/tmp/pip-g4VkvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:34:57 INFO - Running setup.py (path:/tmp/pip-QxN6hA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:34:57 INFO - Running setup.py (path:/tmp/pip-AD8nHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:34:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:34:57 INFO - Running setup.py install for manifestparser 08:34:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:34:57 INFO - Running setup.py install for mozcrash 08:34:57 INFO - Running setup.py install for mozdebug 08:34:57 INFO - Running setup.py install for mozdevice 08:34:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:34:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:34:57 INFO - Running setup.py install for mozfile 08:34:57 INFO - Running setup.py install for mozhttpd 08:34:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:34:58 INFO - Running setup.py install for mozinfo 08:34:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:34:58 INFO - Running setup.py install for mozInstall 08:34:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:34:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:34:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:34:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:34:58 INFO - Running setup.py install for mozleak 08:34:58 INFO - Running setup.py install for mozlog 08:34:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:34:58 INFO - Running setup.py install for moznetwork 08:34:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:34:58 INFO - Running setup.py install for mozprocess 08:34:58 INFO - Running setup.py install for mozprofile 08:34:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:34:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:34:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:34:58 INFO - Running setup.py install for mozrunner 08:34:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:34:58 INFO - Running setup.py install for mozscreenshot 08:34:59 INFO - Running setup.py install for moztest 08:34:59 INFO - Running setup.py install for mozversion 08:34:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:34:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:34:59 INFO - Cleaning up... 08:34:59 INFO - Return code: 0 08:34:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:34:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:59 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 0xb681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe06310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03400>, '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 0xe043b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe04840>, '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': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', '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 08:34:59 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 08:34:59 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 08:34:59 INFO - Using env: {'DISPLAY': ':0', 08:34:59 INFO - 'HOME': '/home/cltbld', 08:34:59 INFO - 'LANG': 'en_US.UTF-8', 08:34:59 INFO - 'LANGUAGE': 'en_US:en', 08:34:59 INFO - 'LOGNAME': 'cltbld', 08:34:59 INFO - 'MAIL': '/var/mail/cltbld', 08:34:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:59 INFO - 'MOZ_NO_REMOTE': '1', 08:34:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:34:59 INFO - 'NO_EM_RESTART': '1', 08:34:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:34:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:59 INFO - 'PWD': '/builds/slave/test', 08:34:59 INFO - 'SHELL': '/bin/bash', 08:34:59 INFO - 'SHLVL': '1', 08:34:59 INFO - 'TERM': 'linux', 08:34:59 INFO - 'TMOUT': '86400', 08:34:59 INFO - 'USER': 'cltbld', 08:34:59 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', 08:34:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:59 INFO - '_': '/tools/buildbot/bin/python'} 08:34:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:34:59 INFO - Running setup.py (path:/tmp/pip-so1MAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:34:59 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)) 08:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:34:59 INFO - Running setup.py (path:/tmp/pip-koXGKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:34:59 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)) 08:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:34:59 INFO - Running setup.py (path:/tmp/pip-N79GKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:34:59 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)) 08:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:34:59 INFO - Running setup.py (path:/tmp/pip-5TaMaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:34:59 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)) 08:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:34:59 INFO - Running setup.py (path:/tmp/pip-T_7y5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:34:59 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)) 08:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:34:59 INFO - Running setup.py (path:/tmp/pip-P7nw3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:34:59 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)) 08:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:34:59 INFO - Running setup.py (path:/tmp/pip-it1ACf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:34:59 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)) 08:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:34:59 INFO - Running setup.py (path:/tmp/pip-N0oiHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:35:00 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)) 08:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:35:00 INFO - Running setup.py (path:/tmp/pip-zsM3mH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:35:00 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)) 08:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:35:00 INFO - Running setup.py (path:/tmp/pip-Io8NLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:35:00 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)) 08:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:35:00 INFO - Running setup.py (path:/tmp/pip-rjhV6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:35:00 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)) 08:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:35:00 INFO - Running setup.py (path:/tmp/pip-cdKZoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:35:00 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)) 08:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:35:00 INFO - Running setup.py (path:/tmp/pip-ZRCPsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:35:00 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)) 08:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:35:00 INFO - Running setup.py (path:/tmp/pip-14GmNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:35:00 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)) 08:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:00 INFO - Running setup.py (path:/tmp/pip-2_eaZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:00 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)) 08:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:35:00 INFO - Running setup.py (path:/tmp/pip-xUR5fK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:35:00 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)) 08:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:35:00 INFO - Running setup.py (path:/tmp/pip-PMDQps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:35:00 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)) 08:35:00 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)) 08:35:00 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)) 08:35:00 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)) 08:35:00 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)) 08:35:00 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)) 08:35:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:35:00 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 08:35:00 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 08:35:00 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 08:35:00 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 08:35:01 INFO - Downloading blessings-1.6.tar.gz 08:35:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:35:01 INFO - Installing collected packages: blessings 08:35:01 INFO - Running setup.py install for blessings 08:35:01 INFO - Successfully installed blessings 08:35:01 INFO - Cleaning up... 08:35:02 INFO - Return code: 0 08:35:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:35:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:35:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:35:02 INFO - Reading from file tmpfile_stdout 08:35:02 INFO - Current package versions: 08:35:02 INFO - argparse == 1.2.1 08:35:02 INFO - blessings == 1.6 08:35:02 INFO - blobuploader == 1.2.4 08:35:02 INFO - docopt == 0.6.1 08:35:02 INFO - functools32 == 3.2.3-2 08:35:02 INFO - jsonschema == 2.5.1 08:35:02 INFO - manifestparser == 1.1 08:35:02 INFO - mozInstall == 1.12 08:35:02 INFO - mozcrash == 1.0 08:35:02 INFO - mozdebug == 0.1 08:35:02 INFO - mozdevice == 0.49 08:35:02 INFO - mozfile == 1.2 08:35:02 INFO - mozhttpd == 0.7 08:35:02 INFO - mozinfo == 0.9 08:35:02 INFO - mozleak == 0.1 08:35:02 INFO - mozlog == 3.4 08:35:02 INFO - moznetwork == 0.27 08:35:02 INFO - mozprocess == 0.25 08:35:02 INFO - mozprofile == 0.28 08:35:02 INFO - mozrunner == 6.13 08:35:02 INFO - mozscreenshot == 0.1 08:35:02 INFO - mozsystemmonitor == 0.3 08:35:02 INFO - moztest == 0.8 08:35:02 INFO - mozversion == 1.4 08:35:02 INFO - psutil == 3.1.1 08:35:02 INFO - requests == 1.2.3 08:35:02 INFO - wsgiref == 0.1.2 08:35:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:02 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 0xb681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe06310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03400>, '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 0xe043b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe04840>, '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': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', '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 08:35:02 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 08:35:02 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 08:35:02 INFO - Using env: {'DISPLAY': ':0', 08:35:02 INFO - 'HOME': '/home/cltbld', 08:35:02 INFO - 'LANG': 'en_US.UTF-8', 08:35:02 INFO - 'LANGUAGE': 'en_US:en', 08:35:02 INFO - 'LOGNAME': 'cltbld', 08:35:02 INFO - 'MAIL': '/var/mail/cltbld', 08:35:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:02 INFO - 'MOZ_NO_REMOTE': '1', 08:35:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:35:02 INFO - 'NO_EM_RESTART': '1', 08:35:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:35:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:02 INFO - 'PWD': '/builds/slave/test', 08:35:02 INFO - 'SHELL': '/bin/bash', 08:35:02 INFO - 'SHLVL': '1', 08:35:02 INFO - 'TERM': 'linux', 08:35:02 INFO - 'TMOUT': '86400', 08:35:02 INFO - 'USER': 'cltbld', 08:35:02 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', 08:35:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:02 INFO - '_': '/tools/buildbot/bin/python'} 08:35:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:02 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)) 08:35:02 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)) 08:35:02 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)) 08:35:02 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)) 08:35:02 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)) 08:35:02 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)) 08:35:02 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)) 08:35:02 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)) 08:35:02 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)) 08:35:02 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)) 08:35:02 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)) 08:35:02 INFO - Cleaning up... 08:35:02 INFO - Return code: 0 08:35:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xba6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe06310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03400>, '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 0xe043b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe04840>, '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': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', '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 08:35:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:35:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:35:02 INFO - Using env: {'DISPLAY': ':0', 08:35:02 INFO - 'HOME': '/home/cltbld', 08:35:02 INFO - 'LANG': 'en_US.UTF-8', 08:35:02 INFO - 'LANGUAGE': 'en_US:en', 08:35:02 INFO - 'LOGNAME': 'cltbld', 08:35:02 INFO - 'MAIL': '/var/mail/cltbld', 08:35:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:02 INFO - 'MOZ_NO_REMOTE': '1', 08:35:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:35:02 INFO - 'NO_EM_RESTART': '1', 08:35:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:35:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:02 INFO - 'PWD': '/builds/slave/test', 08:35:02 INFO - 'SHELL': '/bin/bash', 08:35:02 INFO - 'SHLVL': '1', 08:35:02 INFO - 'TERM': 'linux', 08:35:02 INFO - 'TMOUT': '86400', 08:35:02 INFO - 'USER': 'cltbld', 08:35:02 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', 08:35:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:02 INFO - '_': '/tools/buildbot/bin/python'} 08:35:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:35:02 INFO - Cleaning up... 08:35:02 INFO - Return code: 0 08:35:02 INFO - Running post-action listener: _resource_record_post_action 08:35:02 INFO - Running post-action listener: _start_resource_monitoring 08:35:02 INFO - Starting resource monitoring. 08:35:02 INFO - [mozharness: 2017-05-19 15:35:02.715785Z] Finished create-virtualenv step (success) 08:35:02 INFO - [mozharness: 2017-05-19 15:35:02.716249Z] Running install step. 08:35:02 INFO - Running pre-action listener: _resource_record_pre_action 08:35:02 INFO - Running main action method: install 08:35:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:35:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:35:02 INFO - Reading from file tmpfile_stdout 08:35:02 INFO - Detecting whether we're running mozinstall >=1.0... 08:35:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:35:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:35:03 INFO - Reading from file tmpfile_stdout 08:35:03 INFO - Output received: 08:35:03 INFO - Usage: mozinstall [options] installer 08:35:03 INFO - Options: 08:35:03 INFO - -h, --help show this help message and exit 08:35:03 INFO - -d DEST, --destination=DEST 08:35:03 INFO - Directory to install application into. [default: 08:35:03 INFO - "/builds/slave/test"] 08:35:03 INFO - --app=APP Application being installed. [default: firefox] 08:35:03 INFO - mkdir: /builds/slave/test/build/application 08:35:03 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'] 08:35:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:35:17 INFO - Reading from file tmpfile_stdout 08:35:17 INFO - Output received: 08:35:17 INFO - /builds/slave/test/build/application/firefox/firefox 08:35:17 INFO - Running post-action listener: _resource_record_post_action 08:35:17 INFO - [mozharness: 2017-05-19 15:35:17.085513Z] Finished install step (success) 08:35:17 INFO - [mozharness: 2017-05-19 15:35:17.085820Z] Running run-tests step. 08:35:17 INFO - Running pre-action listener: _resource_record_pre_action 08:35:17 INFO - Running main action method: run_tests 08:35:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:35:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:35:17 INFO - Python 2.7.3 08:35:17 INFO - Return code: 0 08:35:17 INFO - grabbing minidump binary from tooltool 08:35:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:17 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 0xe03400>, '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 0xe043b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe04840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:35:17 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 08:35:17 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 08:35:17 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 08:35:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:35:17 INFO - Return code: 0 08:35:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:35:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:35:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:35:17 INFO - ENV: RUST_BACKTRACE is now 1 08:35:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:35:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:35:17 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/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 08:35:17 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/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-009 --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 08:35:17 INFO - Using env: {'DISPLAY': ':0', 08:35:17 INFO - 'HOME': '/home/cltbld', 08:35:17 INFO - 'LANG': 'en_US.UTF-8', 08:35:17 INFO - 'LANGUAGE': 'en_US:en', 08:35:17 INFO - 'LOGNAME': 'cltbld', 08:35:17 INFO - 'MAIL': '/var/mail/cltbld', 08:35:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:35:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:35:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:17 INFO - 'MOZ_NO_REMOTE': '1', 08:35:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:35:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:35:17 INFO - 'NO_EM_RESTART': '1', 08:35:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:35:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:17 INFO - 'PWD': '/builds/slave/test', 08:35:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:35:17 INFO - 'RUST_BACKTRACE': '1', 08:35:17 INFO - 'SHELL': '/bin/bash', 08:35:17 INFO - 'SHLVL': '1', 08:35:17 INFO - 'TERM': 'linux', 08:35:17 INFO - 'TMOUT': '86400', 08:35:17 INFO - 'USER': 'cltbld', 08:35:17 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457', 08:35:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:17 INFO - '_': '/tools/buildbot/bin/python'} 08:35:17 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/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 08:35:17 INFO - mozversion application_buildid: 20170519144518 08:35:17 INFO - mozversion application_changeset: 61932206bf73a5c510f97eb3089b823b97b1141e 08:35:17 INFO - mozversion application_display_name: Firefox 08:35:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:35:17 INFO - mozversion application_name: Firefox 08:35:17 INFO - mozversion application_remotingname: firefox 08:35:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:35:17 INFO - mozversion application_vendor: Mozilla 08:35:17 INFO - mozversion application_version: 54.0 08:35:17 INFO - mozversion platform_buildid: 20170519144518 08:35:17 INFO - mozversion platform_changeset: 61932206bf73a5c510f97eb3089b823b97b1141e 08:35:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:35:17 INFO - mozversion platform_version: 54.0 08:35:17 INFO - using testdate: 1495208117 08:35:17 INFO - actual date: 1495208117 08:35:17 INFO - starting webserver on 'localhost:55883' 08:35:17 INFO - SUITE-START | Running 2 tests 08:35:17 INFO - TEST-START | dromaeo_css 08:35:17 INFO - operating with platform_type : linux_ 08:35:17 INFO - Initialising browser for dromaeo_css test... 08:35:17 INFO - TEST-INFO | started process 24625 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKX_j2r/profile http://localhost:55883/getInfo.html) 08:35:21 INFO - PROCESS | 24625 | __metrics Screen width/height:1600/1200 08:35:21 INFO - PROCESS | 24625 | colorDepth:24 08:35:21 INFO - PROCESS | 24625 | Browser inner width/height: 1024/697 08:35:21 INFO - PROCESS | 24625 | __metrics 08:35:22 INFO - PROCESS | 24625 | 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" 08:35:22 INFO - PROCESS | 24625 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:35:23 INFO - TEST-INFO | 24625: exit 0 08:35:23 INFO - Browser initialized. 08:35:23 INFO - Running cycle 1/1 for dromaeo_css test... 08:35:23 INFO - TEST-INFO | started process 24719 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKX_j2r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:35:35 INFO - PROCESS | 24719 | RSS: Main: 141402112 08:35:35 INFO - PROCESS | 24719 | 08:38:36 INFO - PROCESS | 24719 | Cycle 1(1): loaded http://localhost:55883/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55883/tests/dromaeo/cssquery-ext.html) 08:38:38 INFO - PROCESS | 24719 | RSS: Main: 281325568 08:38:38 INFO - PROCESS | 24719 | 08:41:40 INFO - PROCESS | 24719 | Cycle 1(1): loaded http://localhost:55883/tests/dromaeo/cssquery-ext.html (next: http://localhost:55883/tests/dromaeo/cssquery-jquery.html) 08:41:40 INFO - PROCESS | 24719 | RSS: Main: 225193984 08:41:40 INFO - PROCESS | 24719 | 08:44:42 INFO - PROCESS | 24719 | Cycle 1(1): loaded http://localhost:55883/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55883/tests/dromaeo/cssquery-mootools.html) 08:44:43 INFO - PROCESS | 24719 | RSS: Main: 170713088 08:44:43 INFO - PROCESS | 24719 | 08:47:45 INFO - PROCESS | 24719 | Cycle 1(1): loaded http://localhost:55883/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55883/tests/dromaeo/cssquery-prototype.html) 08:47:45 INFO - PROCESS | 24719 | RSS: Main: 171802624 08:47:45 INFO - PROCESS | 24719 | 08:50:48 INFO - PROCESS | 24719 | Cycle 1(1): loaded http://localhost:55883/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55883/tests/dromaeo/cssquery-yui.html) 08:50:49 INFO - PROCESS | 24719 | RSS: Main: 175198208 08:50:49 INFO - PROCESS | 24719 | 08:53:51 INFO - PROCESS | 24719 | Cycle 1(1): loaded http://localhost:55883/tests/dromaeo/cssquery-yui.html (next: http://localhost:55883/tests/dromaeo/cssquery-dojo.html) 08:53:51 INFO - PROCESS | 24719 | RSS: Main: 266067968 08:53:51 INFO - PROCESS | 24719 | 08:53:51 INFO - PROCESS | 24719 | __start_tp_report 08:53:51 INFO - PROCESS | 24719 | _x_x_mozilla_page_load 08:53:51 INFO - PROCESS | 24719 | _x_x_mozilla_page_load_details 08:53:51 INFO - PROCESS | 24719 | |i|pagename|runs| 08:53:51 INFO - PROCESS | 24719 | |0;dojo.html;3403.64261752516;3432.4508078707395;3441.019309496792;3465.601455832577;3482.651734826516;5671.149327600862;5753.769849206027;5785.2189954356145;5794.188813566104;5799.246098007258;5718.857028574277;5766.769329226837;5769.1923130761725;5771.826845194641;5772.942270577293;5124.0776660201145;5130.102232109366;5175.974120129394;5179.585633149339;5204.843854684346;5008.599312055045;5019.523145301208;5234.057869583474;5234.214867769846;5240.79036838526;5117.309163262949;5125.897482050356;5129.179331306992;5148.485151484859;5167.974160129194;5204.739763011853;5215.426303106655;5216.478352164791;5227.11139106349;5259.921101183485;4946.2580612908;5089.338386009832;5169.224616307546;5200.401953775288;5209.2446595243855;5581.079121944915;5584.078627026536;5633.267675202238;5659.8585035374035;5660.575456840712;6078.848028799272;6115.082737589352;6122.713581872198;6127.892078906864;6150.692465376714;6170.136180934673;6185.257769067695;6201.348858369896;6202.968985155046;6203.689815509207;5602.411746766572;5636.520895723868;5717.371089180187;5753.510951569164;5829.09649004405;6309.053641953744;6322.589031712924;6326.620402775849;6327.873442531123;6331.986882098842;6025.548083893726;6093.055576385667;6094.634321940698;6096.878062438726;6098.573099883054;5948.137520059568;5951.851203719942;6074;6173.81478555644;6186.566940314135;6115.188456275656;6138.010293309385;6139.124180917401;6139.614800607407;6150.661713605705;5775.740091695884;5802.274715660543;5806.68063256525;6056.697165141725;6150.7232174552255;4649.465311489222;4731.077439899255;4733.9763301183275;4750.61995040396;4761.738104404221;4129.566395528426;4140.441040459499;4206.284931561642;4219.324908014764;4245.8513952011535;5837.29952405714;5843.474087332075;5849.268841394825;5884.058550631964;5901.439363260483;6215.409536094064;6222.408871157233;6227.408396202353;6232.626042437469;6240.282367527701;5709.8287051388525;5937.376575459601;5944;5946.167536544887;5947.464728174485;5177.197534382177;5198.298229739013;5221.034108689904;5221.582273418117;5223.738813059332;6365.172527571386;6390.456811171102;6393.8721225576155;6403.551751377358;6403.935960640334;6104;6139.3246742857355;6181.505479561534;6202.689865506616;6206.224221972254;6102.115193297036;6117.188044311343;6211.81364559064;6241.157443745036;6278.183836101278;6112.929053818819;6127.128444391353;6131.804871721949;6137.346341658241;6137.62214577108;6203.7828675996125;6218.036204388327;6222.408871157233;6224.62652240867;6229.190205273286;5717.571182161438;5827.329857066492;5969.910451343147;6005.939940600713;6077.939220607915;5986.970065149821;6010.338862725184;6040.244969378828;6058.303295120942;6101.38986101404;6052.515798736003;6061.969690151521;6063.727132279147;6065.150878877049;6067.54493412987;5832.795852145325;5864.208331875142;5906;6013.789517366871;6042.667653279112;5960.046392577166;5963.552733545088;5974.462298393166;5985.820425387071;5997.430244126801 08:53:51 INFO - PROCESS | 24719 | |1;ext.html;769.607500174911;841.8863453433787;858.6436628799049;907.9909200907989;913.5751875377948;31751.206219844487;43211.567884321186;43987.78006109973;44180.33729494056;44181.77909110458;46510.53489465104;46827;46828.0634387312;46829.53170468303;46926;7801.726939557117;7926.326262267714;9249.53752312385;9385.577649005778;9391.34260601758;10669.946650266738;10924.071453926426;10951.507182176822;10968.341899486057;10970.177236707239;11067;11106.61126860556;11109.666709998713;11114.6109886154;11116.61091861785;11027.117830573576;11047.116230701566;11048.613298534512;11056.613018544353;11058.612948546763;10726.302790318605;10749.247552671317;10772;10773.353598784099;10776.5689372425;43126.353104703456;43146.84265786698;43219.13561728748;43338.783306083584;43428;4250.617444429985;4340.587644173798;4345.087531618364;4452.376667266552;4474.328850672425;30286.697133028607;30567.69432305671;30973;31056.689433105606;31682.049538513882;4496.168208881368;4529.977350113261;4548.8862778430475;4551.8634440966825;4556.749378784166;249734;250472;250575;250656;250657;55372.446275537535;55441.44558554443;55509;55518;55526.444735552126;58892.82214355689;58893.70553147207;58894.116588250356;58899.41100589025;58927.410725892194;15308.311125999357;15613.843861561467;15645.765313520305;15676.764848527282;15696.686066278611;12104.515819367303;12251.754964900652;12623.684407889692;13306.866931330756;13328.933355333354;13960.441582336796;13981.300934953211;14004.089734167246;14159.78760318596;14178.432862685584;5709.286339207599;5805.941940580625;5850.8166660837705;5861.824145275648;5911.940880591225;1006.00211370116;1014.1633152648947;1014.9086582207489;1042.6142327338932;1049.0610903241557;28943.56584651232;29069.418611628073;29075.273118171794;29432.705672943426;29433.55849662257;1092.9016388525072;1106.6679996001167;1107.429673718036;1110.245033377311;1120.6862078618;14373.353199106064;15259.389624415122;15463.304151313218;15465.845341546441;15474.922625387026;324351;324684;324784;324805;325074;56685.43314566884;56774;56806.43193568094;56818.715906420206;56983.43016569864;1468.875145612635;1490.5006822714295;1494.9551513454614;1548.256836718359;1552.8214255360551;27476;28555.28611784722;28562.285942851595;29247.008726422064;29763.255918602208;36569.45145822868;45249.77375113103;46315.305270421646;51423.742881285354;51488.97022059613;348430;351031;352060;353007;353329;25567.360815979748;25631.23106306814;30327.090187294416;30569.54145687772;30864.382712346076;39714.40428393634;39738;39805.80097099496;39887.40168897527;39956.40065399079;30683.2329191772;31143;31204.219894502818;31425.371492570477;31431.842840785648;29920.401591967602;30176.54735179018;30465.695343046285;30580.84709576438;30696;34774.304513909075;35141.47287790634;35399.64600353964;35493.82253088718;35828.10429739173;28973.130806075853;29058.27354316159;29382.70617293885;29464.852675736485;29676.554851677673;23638.52722945566;23832.642510362013;23968.52062958766;24139.396515087265;24222.87888560546 08:53:51 INFO - PROCESS | 24719 | |2;jquery.html;457.7894168682407;464.4890620317651;472.723456777785;487.08428155068464;495.51192075805335;21478.89260553697;22049.448763780892;22629.434264143405;22760.31719048427;22925.885370573167;20726.170953161854;20795.896020519915;20900.581988360223;20939.68590471144;21426.357209283706;5037.949620503785;6788.660566971644;6995.720171193147;7035.472339574552;7060.7881763547175;4121.835126594932;4313.374560688699;6803.761868334609;7564.773056808304;7953.045634523865;7881.448298619071;7985.4809437386675;8138.104808471064;8180.222878826502;8187;7796.376289896794;7999.920000800005;8055.395845311625;8066.475679080841;8079.919200807975;7765.912772211895;7778.261065198853;7785.610719464004;7799.7270095546955;7804.180561041066;27322.180334589993;27518.174454766387;27650.861745691345;28608.284792880135;28772.424551509062;3243.4972579250016;3253.6665936996383;3268.8038717676777;3305.2067503798917;3342.8495717692763;16409.83590164095;16532.256048477848;16912.238949247192;16961.66076678472;17043.744343834853;2339.836211465198;2366.325597204788;2380.3216083416314;2422.345966589011;2450.3139121046133;117918;120722.79277207203;120761;120881.39559302235;121498.39250803778;18847.476605614553;19944.401667949984;20081.59836803285;20127.798722012885;22079.33761987111;36967.81516092402;37105.44341834875;37197.25605487875;37266.62733372686;37670.05824854346;10709.250352475252;10963.83554246687;11077.279976801483;11109;11223.887761122285;9009.774755631031;9035.638574456949;9055.18503334703;9159.496227707406;9262.95368523153;10276.948615256875;10356.17150627948;10797;10808.675739727822;10997.285176463503;3413.4367829307885;3798.582155962842;4271.615554600101;4276.706935659525;4410.757408342508;966.429806414219;996.5366104761321;1001.9098281154731;1025.9076683098558;1030.6547306652205;23057.769422305897;23365.065397384253;23382.298531044096;23468.413289667897;23506.64740028901;992.4987881119988;998.7952469743685;1003.4832061488339;1005.3967619428431;1027.50679673757;12465.5013799447;12489.50041998328;12491.750164996649;12791.296478693761;12974.675633109247;613622;617812;620809;622407;625015;112050;115780.99999999831;117813.4109329465;119166;119818.4009079949;1374.154894739728;1392.5265409760527;1406.4514839212716;1420.7016526529544;1423.4163992762917;19253.133608987348;19516.31692890742;19769.802301977372;19891.204351826345;19938.800611993694;48858.5114148854;49891.25380611806;50234.49765502445;50710.746446267534;51068.74465627648;67376.32623673897;67644;67825.3217467819;68084.6595767018;68626.65686671734;12944.611661650166;13164.670883227996;13502;13718.794218086538;13988;13850.584482465541;14369;15412.306446209723;15648.139352335276;16043.278052487432;12631.305278209788;12639.115261931576;12656.74686506283;12665.620031399072;12809.103362764517;12394.81407778866;12442.066844986843;12584.244945303311;12641.747165056464;12795.488180472677;12973.935130324287;12990.545330913372;13032.674183145496;13114.803277950648;13387.933060334635;12698.30159341245;12858.099933004974;12877.678058048623;12880.227186368847;12990.350482476028;12750.744985100431;12835.614931552069;12847.357632118543;12909.612711618667;13374.398152082986 08:53:51 INFO - PROCESS | 24719 | |3;mootools.html;1545.1192820092544;1545.2737213509647;1561.898476599021;1562.562482504899;1569.764535319702;10598.523066462009;11350.659480215596;11350.772984540305;11366.3748493833;11367.772644547114;10977.28647637903;11128.05411540018;11175.273607215546;11666.941665291663;11683.182177247574;2403.7956773674214;2441.426264827764;2444.303373538542;2445.987770061156;2447.571674956884;2403.122860156039;2464.37158524576;2491.451880586268;2491.825572209946;2496.475740094573;2432.7567243275707;2459.8401103928186;2475.8143139264625;2502.5370306493355;2506.2105436787447;2384.749601291874;2386.3198988288427;2418.093215044358;2439.695038120235;2448.8040956723507;2412.8311018228733;2419.3830573203895;2430.538197742423;2432.9513409731708;2436.8659723715186;11533;11751.647450576496;11781.646550603497;11783.528658853722;11785.764284714343;5673.120666296731;5829.329627092896;5830.4461076197695;5832.766689332421;5837.211976383219;3434.107132145635;3518.8416521256604;3571.1072231942017;3585.229175727231;3633.218857945528;1362.461827578111;1363.3864760857655;1411.6329754263966;1411.8517555656713;1434.519435988945;16742.24659890308;16942.915285423616;17264.30942762288;17575;17756.200970956208;4693.718376897397;4746.216874215785;4776.952230477651;4831.396075490564;4952.009598080398;12901.096923215286;12911.935440322739;12916.612501624966;12919.418626161845;12924.224546527239;5806.1581070744505;5807.970960145257;5813;5817.331006934172;5823.854403639858;5186.273921650972;5223.608229382811;5224.582033437316;5261.631685781958;5340.732963351817;4062.979154158025;4108.465899433055;4118.9588104118575;4125.278076336629;4142;1100.290312748289;1105.0441368216595;1112.3214838948236;1124.2748427264532;1125.983110253347;1094.8631421072366;1106.623747925709;1107.1751545098953;1109.5118148014847;1117.9776404471866;7080.433565314763;7258.346748792634;7342.412606991535;7378.815529611803;7444.106707195063;1031.9535620897077;1036.4196050211906;1051.984220236697;1062.8990245926625;1083.815751322277;4184.602462766033;4318.5681431856565;4368.191884501377;4424.80088396023;4539.841105561355;14251.21618311003;14810.703785924221;14818.110913345234;14836;14927.104373737611;11391.601293954673;13819.378127984264;14210.857891420954;14798.926005369904;14824.110553366616;8946.642134314685;9929.950350248348;10054.19566434698;10102.292839501164;10103.7474063149;7484.887726684213;7510.849783004419;7677.692892284469;7725.845483090419;7797.532148071134;12574.434150463434;12826.038047146314;12841.807372889602;13086.672833179247;13092.083554151119;12705.745885082431;13006.804897926728;13230.536931207747;13278.136921100098;13343.466261349437;9764.365316254421;10465;10530;10554.10290125348;10649.9467502662;10890.564377425131;10960.890391095987;11110.944445277722;11389.886101138882;11539.538418463168;9319.813603728024;9389.29580281468;9473.763155921157;9649.710508684751;9889.406635601887;9176.128267814547;9227.44635321883;9447.905520944703;9449.385789923634;9567.760805979906;7838.490498117604;7979.241972012649;8005.599720014093;8064.516129032277;8093.878591821245;5760.222369979999;5818.389069147678;5819.738111784895;5873.236479257669;5959.046552551571;6398.392152745481;6572.507061970467;6592.340765923369;6689.163854518185;6724.562903411263 08:53:51 INFO - PROCESS | 24719 | |4;prototype.html;421.1471769666423;432.64523091065325;445.2386419223128;455.7311186400023;461.96766226364144;5286.947130528698;5361.624686271963;5374.5700343972485;5390.946090539093;5421.457854214586;5117.8464646060675;5217.139172036619;5229.790808367661;5251.422343542207;5274.393444753854;4196.286631272684;4235.813972087821;4239.0500369829915;4245.688363076805;4281.314989601664;4241.915161696764;4263.616274535291;4265.936010959838;4279.935800962988;4297.828086876521;4113.115680128772;4235.788210589473;4280.764557949312;4282.314829627245;4286.435451060372;4224.556421575752;4241.766702831343;4243.130158317537;4247.193033323674;4254.191703576342;3872.2255548890334;3889.6888248940018;3892.3966785148345;3893.3770596704676;3894.6689531389584;5204.256574047698;5259.342582177228;5261.552768014723;5262.815801446969;5273.578113750891;4858.344123543312;4886.780094895738;4901;4926.33494478248;4951.282064100721;5543.695096769676;5563.221149039137;5571.359293681238;5599.32808063031;5604.467575580314;4343.153085148366;4346.782660866976;4373.387725718402;4379;4412.382266482695;5557.5831812614215;5581.972090139564;5646.6047376684055;5647.548196144339;5774.682392468412;5056.443791182967;5060.84817455477;5061.746912654353;5063.316452278968;5073.594112470993;5083.313752643419;5091;5128.256402821645;5132.769025393792;5145.266354953452;4904.852854414374;5092.210707340369;5165.483451654804;5189.870253243699;5235.83232144547;5512.255845460892;5532.917006244909;5595.832125036255;5601.831945041656;5604.215409842625;1642.3923148435156;1665.1757380096687;1676.1786724504905;1676.7652528646;1681.8318168183084;1301.2062641788502;1309.8277042047314;1317.7166909114587;1331.8534961154264;1336.7927971164486;5275.261463395128;5483.930633526424;5487.314085739283;5496.7526461309335;5579.218909352694;5716.657000579979;5750.769969201268;5752.884942301131;5761.798337058267;5766.855828604234;5392.838214853561;5489.423610520837;5490.725463726878;5526.557875370041;5533.806316778893;2876.6548014238424;3008.293051132982;3008.9619081417313;3028.348904985439;3032.2116249775218;5892.204552385373;5958.255218097738;5977.193078934288;5988.867681299889;6042.860640461298;5575.693336866511;5788.363280039192;5859.912101318484;5979.730912108966;6000.809704457842;5911.615744976562;5924.7926322578505;5935;5936.141508019821;5937.940620593825;5505.889882202414;5735.684537350486;5766.509846663081;5806.096951524238;5823.505002074871;5486.862828429162;5545.805896793593;5665.716714164358;5705.743241554057;5710.914336285042;5807.925533776265;5835.095560188008;5846.736896839738;5903.527717782653;5925.466707996301;4776.808927642995;4788.3056956741775;4792.640551958687;4794.688345257547;4840.782164802522;5465.289512363367;5702;5717.22817419643;5744.741486633196;5753.223314852441;5592.580556458201;5604.719764011865;5642.332483885507;5665;5691.467297052772;5352.518273355417;5647.265855438766;5690.118031705092;5696.433480792815;5724.198612194213;5481.424450432647;5569.637973531708;5640.85897852557;5657.462541058593;5674.659520428788;5588;5669.744861481326;5677.7161141941915;5731.770729170787;5733.971330143323;5315.681059136464;5458.972705136608;5465.836024919259;5467;5597.860053498533 08:53:51 INFO - PROCESS | 24719 | |5;yui.html;914.4558987402497;915.8717779510865;933.2720478027139;944.8535477001064;959.3668179001855;16985.490435286945;18127.365542206026;18181.272749090054;18207.908960455195;18237;15944.123073230967;16089.758653620176;16704.58238544034;16910.1544922754;16968.49094527166;867.3234876796109;891.3493150000503;896.4083704754863;899.4468401932797;900.8423525882977;905.6286922361833;906.6735975048977;906.9455832650029;907.2741806554762;908.7637214324291;941.0119374656617;941.7410212191634;942.7595963029418;943.2077055273569;943.2642538819697;933.2160984772755;934.4953724988566;939.906009399058;939.9801215681028;940.3981451870808;685.25649670108;688.0779755128102;688.5696439725172;690.989635155468;692.7090621938747;18517.814821851745;18591.814081859142;18782.154803033896;19364.031798410022;19485.9025704872;16026.839731602651;16053.518394448069;16177.602703432294;16319.265633046542;16640.66718665632;13624.659383515393;13748.831349335318;13860;14422.639434014234;14890.702185956221;4085.060436099684;4117.444145040441;4122.56713045129;4191.895202619959;4280.593343632381;90323;90841;90903.54548227217;92838;93735;12654.683632909251;12851.935740321238;12853.16454430459;12860.742785144244;12873.613791586267;24097.518049638908;24150.758492415203;24216.7578324218;24527.386815329402;24638.630420543705;8806.20744133031;9448.57481413338;9618.422894626345;9640.42157470554;9648;5034.622403319766;5054.949450505522;5136.512031357015;5171.456997015334;5299.629025968223;3766.5856755756845;3819.8281077351585;3848.3457812171996;3850.7497012694084;3856.4408160817084;650.4666173737489;650.8307839961644;653.4543656047159;658.1937127019363;661.8113837556274;843.8649816029406;846.9830603387898;868.6221493650282;884.6992022712309;894.6019021535354;11552.826707599394;11758.470868810924;12066.638000859988;12169.756604868031;12293.446794894251;770.8150043989402;773.8568364852521;782.5422128055152;791.3629528229831;804.5373910001772;5283.630145889751;5332.30680011596;5333.973330133324;5373.7581808818695;5395.70323632204;61194.694026529585;78818.45316960636;79777.60111199407;84663.15336846675;85717.57141214379;73859.26140738631;75111.62444187744;75508;76080;77878.61060694774;65803;66308.33691663117;66439.66780166165;67135.99296010564;67412.66293668599;10449.477526123816;10653.733656658336;10706.571737130427;10787.838182427427;10827.891721082688;38358.2328353437;39564.60435395609;46026;46451.30323045225;46586.53413465822;54244.45755542394;54627.72686136544;56085;57146.42853571411;57655.71172144113;14324.427022918966;14398.85601144017;14411.42354305816;14526.346314415665;14594;16054.036757794569;16235.918820406296;16414.261358239146;16437.917810410872;18069.457916262534;15220;15559.37762489533;16437.506874793777;16709.582260443585;17833.375831845824;14871.553853384416;15211.239438028275;15956.281967311732;16743.413980510624;16910.492685219462;15484.28217883424;15796.332357211146;16284.43004494837;16339.264733087277;16500.92743971686;10723.356598604109;10822.567097316334;10858.67423977282;10864.619738309122;11119.388433635904;13269.270190139636;13276.203427794364;13284.667883302995;13295.66760830987;13618.591442256748 08:53:51 INFO - PROCESS | 24719 | __end_tp_report 08:53:51 INFO - PROCESS | 24719 | __start_cc_report 08:53:51 INFO - PROCESS | 24719 | _x_x_mozilla_cycle_collect,1384 08:53:51 INFO - PROCESS | 24719 | __end_cc_report 08:53:51 INFO - PROCESS | 24719 | __startTimestamp1495209231730__endTimestamp 08:53:51 INFO - PROCESS | 24719 | 08:53:51 INFO - PROCESS | 24719 | ------- Summary: start ------- 08:53:51 INFO - PROCESS | 24719 | Number of tests: 6 08:53:51 INFO - PROCESS | 24719 | 08:53:51 INFO - PROCESS | 24719 | [#0] dojo.html Cycles:165 Average:5699.04 Median:5947.46 stddev:644.97 (10.8%) stddev-sans-first:621.31 08:53:51 INFO - PROCESS | 24719 | Values: 3403.6 3432.5 3441.0 3465.6 3482.7 5671.1 5753.8 5785.2 5794.2 5799.2 5718.9 5766.8 5769.2 5771.8 5772.9 5124.1 5130.1 5176.0 5179.6 5204.8 5008.6 5019.5 5234.1 5234.2 5240.8 5117.3 5125.9 5129.2 5148.5 5168.0 5204.7 5215.4 5216.5 5227.1 5259.9 4946.3 5089.3 5169.2 5200.4 5209.2 5581.1 5584.1 5633.3 5659.9 5660.6 6078.8 6115.1 6122.7 6127.9 6150.7 6170.1 6185.3 6201.3 6203.0 6203.7 5602.4 5636.5 5717.4 5753.5 5829.1 6309.1 6322.6 6326.6 6327.9 6332.0 6025.5 6093.1 6094.6 6096.9 6098.6 5948.1 5951.9 6074.0 6173.8 6186.6 6115.2 6138.0 6139.1 6139.6 6150.7 5775.7 5802.3 5806.7 6056.7 6150.7 4649.5 4731.1 4734.0 4750.6 4761.7 4129.6 4140.4 4206.3 4219.3 4245.9 5837.3 5843.5 5849.3 5884.1 5901.4 6215.4 6222.4 6227.4 6232.6 6240.3 5709.8 5937.4 5944.0 5946.2 5947.5 5177.2 5198.3 5221.0 5221.6 5223.7 6365.2 6390.5 6393.9 6403.6 6403.9 6104.0 6139.3 6181.5 6202.7 6206.2 6102.1 6117.2 6211.8 6241.2 6278.2 6112.9 6127.1 6131.8 6137.3 6137.6 6203.8 6218.0 6222.4 6224.6 6229.2 5717.6 5827.3 5969.9 6005.9 6077.9 5987.0 6010.3 6040.2 6058.3 6101.4 6052.5 6062.0 6063.7 6065.2 6067.5 5832.8 5864.2 5906.0 6013.8 6042.7 5960.0 5963.6 5974.5 5985.8 5997.4 08:53:51 INFO - PROCESS | 24719 | 08:53:51 INFO - PROCESS | 24719 | [#1] ext.html Cycles:180 Average:47566.14 Median:30689.62 stddev:81732.36 (266.3%) stddev-sans-first:81886.12 08:53:51 INFO - PROCESS | 24719 | Values: 769.6 841.9 858.6 908.0 913.6 31751.2 43211.6 43987.8 44180.3 44181.8 46510.5 46827.0 46828.1 46829.5 46926.0 7801.7 7926.3 9249.5 9385.6 9391.3 10669.9 10924.1 10951.5 10968.3 10970.2 11067.0 11106.6 11109.7 11114.6 11116.6 11027.1 11047.1 11048.6 11056.6 11058.6 10726.3 10749.2 10772.0 10773.4 10776.6 43126.4 43146.8 43219.1 43338.8 43428.0 4250.6 4340.6 4345.1 4452.4 4474.3 30286.7 30567.7 30973.0 31056.7 31682.0 4496.2 4530.0 4548.9 4551.9 4556.7 249734.0 250472.0 250575.0 250656.0 250657.0 55372.4 55441.4 55509.0 55518.0 55526.4 58892.8 58893.7 58894.1 58899.4 58927.4 15308.3 15613.8 15645.8 15676.8 15696.7 12104.5 12251.8 12623.7 13306.9 13328.9 13960.4 13981.3 14004.1 14159.8 14178.4 5709.3 5805.9 5850.8 5861.8 5911.9 1006.0 1014.2 1014.9 1042.6 1049.1 28943.6 29069.4 29075.3 29432.7 29433.6 1092.9 1106.7 1107.4 1110.2 1120.7 14373.4 15259.4 15463.3 15465.8 15474.9 324351.0 324684.0 324784.0 324805.0 325074.0 56685.4 56774.0 56806.4 56818.7 56983.4 1468.9 1490.5 1495.0 1548.3 1552.8 27476.0 28555.3 28562.3 29247.0 29763.3 36569.5 45249.8 46315.3 51423.7 51489.0 348430.0 351031.0 352060.0 353007.0 353329.0 25567.4 25631.2 30327.1 30569.5 30864.4 39714.4 39738.0 39805.8 39887.4 39956.4 30683.2 31143.0 31204.2 31425.4 31431.8 29920.4 30176.5 30465.7 30580.8 30696.0 34774.3 35141.5 35399.6 35493.8 35828.1 28973.1 29058.3 29382.7 29464.9 29676.6 23638.5 23832.6 23968.5 24139.4 24222.9 08:53:51 INFO - PROCESS | 24719 | 08:53:51 INFO - PROCESS | 24719 | [#2] jquery.html Cycles:180 Average:37503.58 Median:21764.17 stddev:102448.73 (470.7%) stddev-sans-first:102698.36 08:53:51 INFO - PROCESS | 24719 | Values: 457.8 464.5 472.7 487.1 495.5 21478.9 22049.4 22629.4 22760.3 22925.9 20726.2 20795.9 20900.6 20939.7 21426.4 5037.9 6788.7 6995.7 7035.5 7060.8 4121.8 4313.4 6803.8 7564.8 7953.0 7881.4 7985.5 8138.1 8180.2 8187.0 7796.4 7999.9 8055.4 8066.5 8079.9 7765.9 7778.3 7785.6 7799.7 7804.2 27322.2 27518.2 27650.9 28608.3 28772.4 3243.5 3253.7 3268.8 3305.2 3342.8 16409.8 16532.3 16912.2 16961.7 17043.7 2339.8 2366.3 2380.3 2422.3 2450.3 117918.0 120722.8 120761.0 120881.4 121498.4 18847.5 19944.4 20081.6 20127.8 22079.3 36967.8 37105.4 37197.3 37266.6 37670.1 10709.3 10963.8 11077.3 11109.0 11223.9 9009.8 9035.6 9055.2 9159.5 9263.0 10276.9 10356.2 10797.0 10808.7 10997.3 3413.4 3798.6 4271.6 4276.7 4410.8 966.4 996.5 1001.9 1025.9 1030.7 23057.8 23365.1 23382.3 23468.4 23506.6 992.5 998.8 1003.5 1005.4 1027.5 12465.5 12489.5 12491.8 12791.3 12974.7 613622.0 617812.0 620809.0 622407.0 625015.0 112050.0 115781.0 117813.4 119166.0 119818.4 1374.2 1392.5 1406.5 1420.7 1423.4 19253.1 19516.3 19769.8 19891.2 19938.8 48858.5 49891.3 50234.5 50710.7 51068.7 67376.3 67644.0 67825.3 68084.7 68626.7 12944.6 13164.7 13502.0 13718.8 13988.0 13850.6 14369.0 15412.3 15648.1 16043.3 12631.3 12639.1 12656.7 12665.6 12809.1 12394.8 12442.1 12584.2 12641.7 12795.5 12973.9 12990.5 13032.7 13114.8 13387.9 12698.3 12858.1 12877.7 12880.2 12990.4 12750.7 12835.6 12847.4 12909.6 13374.4 08:53:51 INFO - PROCESS | 24719 | 08:53:51 INFO - PROCESS | 24719 | [#3] mootools.html Cycles:180 Average:7133.35 Median:2432.85 stddev:4541.86 (186.7%) stddev-sans-first:4535.19 08:53:51 INFO - PROCESS | 24719 | Values: 1545.1 1545.3 1561.9 1562.6 1569.8 10598.5 11350.7 11350.8 11366.4 11367.8 10977.3 11128.1 11175.3 11666.9 11683.2 2403.8 2441.4 2444.3 2446.0 2447.6 2403.1 2464.4 2491.5 2491.8 2496.5 2432.8 2459.8 2475.8 2502.5 2506.2 2384.7 2386.3 2418.1 2439.7 2448.8 2412.8 2419.4 2430.5 2433.0 2436.9 11533.0 11751.6 11781.6 11783.5 11785.8 5673.1 5829.3 5830.4 5832.8 5837.2 3434.1 3518.8 3571.1 3585.2 3633.2 1362.5 1363.4 1411.6 1411.9 1434.5 16742.2 16942.9 17264.3 17575.0 17756.2 4693.7 4746.2 4777.0 4831.4 4952.0 12901.1 12911.9 12916.6 12919.4 12924.2 5806.2 5808.0 5813.0 5817.3 5823.9 5186.3 5223.6 5224.6 5261.6 5340.7 4063.0 4108.5 4119.0 4125.3 4142.0 1100.3 1105.0 1112.3 1124.3 1126.0 1094.9 1106.6 1107.2 1109.5 1118.0 7080.4 7258.3 7342.4 7378.8 7444.1 1032.0 1036.4 1052.0 1062.9 1083.8 4184.6 4318.6 4368.2 4424.8 4539.8 14251.2 14810.7 14818.1 14836.0 14927.1 11391.6 13819.4 14210.9 14798.9 14824.1 8946.6 9930.0 10054.2 10102.3 10103.7 7484.9 7510.8 7677.7 7725.8 7797.5 12574.4 12826.0 12841.8 13086.7 13092.1 12705.7 13006.8 13230.5 13278.1 13343.5 9764.4 10465.0 10530.0 10554.1 10649.9 10890.6 10960.9 11110.9 11389.9 11539.5 9319.8 9389.3 9473.8 9649.7 9889.4 9176.1 9227.4 9447.9 9449.4 9567.8 7838.5 7979.2 8005.6 8064.5 8093.9 5760.2 5818.4 5819.7 5873.2 5959.0 6398.4 6572.5 6592.3 6689.2 6724.6 08:53:51 INFO - PROCESS | 24719 | 08:53:51 INFO - PROCESS | 24719 | [#4] prototype.html Cycles:180 Average:4851.09 Median:5382.76 stddev:1311.46 (24.4%) stddev-sans-first:1272.29 08:53:51 INFO - PROCESS | 24719 | Values: 421.1 432.6 445.2 455.7 462.0 5286.9 5361.6 5374.6 5390.9 5421.5 5117.8 5217.1 5229.8 5251.4 5274.4 4196.3 4235.8 4239.1 4245.7 4281.3 4241.9 4263.6 4265.9 4279.9 4297.8 4113.1 4235.8 4280.8 4282.3 4286.4 4224.6 4241.8 4243.1 4247.2 4254.2 3872.2 3889.7 3892.4 3893.4 3894.7 5204.3 5259.3 5261.6 5262.8 5273.6 4858.3 4886.8 4901.0 4926.3 4951.3 5543.7 5563.2 5571.4 5599.3 5604.5 4343.2 4346.8 4373.4 4379.0 4412.4 5557.6 5582.0 5646.6 5647.5 5774.7 5056.4 5060.8 5061.7 5063.3 5073.6 5083.3 5091.0 5128.3 5132.8 5145.3 4904.9 5092.2 5165.5 5189.9 5235.8 5512.3 5532.9 5595.8 5601.8 5604.2 1642.4 1665.2 1676.2 1676.8 1681.8 1301.2 1309.8 1317.7 1331.9 1336.8 5275.3 5483.9 5487.3 5496.8 5579.2 5716.7 5750.8 5752.9 5761.8 5766.9 5392.8 5489.4 5490.7 5526.6 5533.8 2876.7 3008.3 3009.0 3028.3 3032.2 5892.2 5958.3 5977.2 5988.9 6042.9 5575.7 5788.4 5859.9 5979.7 6000.8 5911.6 5924.8 5935.0 5936.1 5937.9 5505.9 5735.7 5766.5 5806.1 5823.5 5486.9 5545.8 5665.7 5705.7 5710.9 5807.9 5835.1 5846.7 5903.5 5925.5 4776.8 4788.3 4792.6 4794.7 4840.8 5465.3 5702.0 5717.2 5744.7 5753.2 5592.6 5604.7 5642.3 5665.0 5691.5 5352.5 5647.3 5690.1 5696.4 5724.2 5481.4 5569.6 5640.9 5657.5 5674.7 5588.0 5669.7 5677.7 5731.8 5734.0 5315.7 5459.0 5465.8 5467.0 5597.9 08:53:51 INFO - PROCESS | 24719 | 08:53:51 INFO - PROCESS | 24719 | [#5] yui.html Cycles:180 Average:19283.14 Median:4236.24 stddev:23972.51 (565.9%) stddev-sans-first:24000.08 08:53:51 INFO - PROCESS | 24719 | Values: 914.5 915.9 933.3 944.9 959.4 16985.5 18127.4 18181.3 18207.9 18237.0 15944.1 16089.8 16704.6 16910.2 16968.5 867.3 891.3 896.4 899.4 900.8 905.6 906.7 906.9 907.3 908.8 941.0 941.7 942.8 943.2 943.3 933.2 934.5 939.9 940.0 940.4 685.3 688.1 688.6 691.0 692.7 18517.8 18591.8 18782.2 19364.0 19485.9 16026.8 16053.5 16177.6 16319.3 16640.7 13624.7 13748.8 13860.0 14422.6 14890.7 4085.1 4117.4 4122.6 4191.9 4280.6 90323.0 90841.0 90903.5 92838.0 93735.0 12654.7 12851.9 12853.2 12860.7 12873.6 24097.5 24150.8 24216.8 24527.4 24638.6 8806.2 9448.6 9618.4 9640.4 9648.0 5034.6 5054.9 5136.5 5171.5 5299.6 3766.6 3819.8 3848.3 3850.7 3856.4 650.5 650.8 653.5 658.2 661.8 843.9 847.0 868.6 884.7 894.6 11552.8 11758.5 12066.6 12169.8 12293.4 770.8 773.9 782.5 791.4 804.5 5283.6 5332.3 5334.0 5373.8 5395.7 61194.7 78818.5 79777.6 84663.2 85717.6 73859.3 75111.6 75508.0 76080.0 77878.6 65803.0 66308.3 66439.7 67136.0 67412.7 10449.5 10653.7 10706.6 10787.8 10827.9 38358.2 39564.6 46026.0 46451.3 46586.5 54244.5 54627.7 56085.0 57146.4 57655.7 14324.4 14398.9 14411.4 14526.3 14594.0 16054.0 16235.9 16414.3 16437.9 18069.5 15220.0 15559.4 16437.5 16709.6 17833.4 14871.6 15211.2 15956.3 16743.4 16910.5 15484.3 15796.3 16284.4 16339.3 16500.9 10723.4 10822.6 10858.7 10864.6 11119.4 13269.3 13276.2 13284.7 13295.7 13618.6 08:53:51 INFO - PROCESS | 24719 | -------- Summary: end -------- 08:53:51 INFO - PROCESS | 24719 | 08:53:52 INFO - TEST-INFO | 24719: exit 0 08:53:52 INFO - TEST-OK | dromaeo_css | took 1115138ms 08:53:52 INFO - TEST-START | kraken 08:53:52 INFO - operating with platform_type : linux_ 08:53:52 INFO - Initialising browser for kraken test... 08:53:52 INFO - TEST-INFO | started process 25019 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_4xMmw/profile http://localhost:55883/getInfo.html) 08:53:56 INFO - PROCESS | 25019 | __metrics Screen width/height:1600/1200 08:53:56 INFO - PROCESS | 25019 | colorDepth:24 08:53:56 INFO - PROCESS | 25019 | Browser inner width/height: 1024/697 08:53:56 INFO - PROCESS | 25019 | __metrics 08:53:57 INFO - PROCESS | 25019 | 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" 08:53:57 INFO - PROCESS | 25019 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:53:58 INFO - TEST-INFO | 25019: exit 0 08:53:58 INFO - Browser initialized. 08:53:58 INFO - Running cycle 1/1 for kraken test... 08:53:58 INFO - TEST-INFO | started process 25111 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_4xMmw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:54:09 INFO - PROCESS | 25111 | RSS: Main: 140922880 08:54:09 INFO - PROCESS | 25111 | 08:54:12 INFO - PROCESS | 25111 | Cycle 1(1): loaded http://localhost:55883/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55883/tests/kraken/driver.html?testName=audio-beat-detection) 08:54:13 INFO - PROCESS | 25111 | RSS: Main: 183996416 08:54:13 INFO - PROCESS | 25111 | 08:54:15 INFO - PROCESS | 25111 | Cycle 1(1): loaded http://localhost:55883/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55883/tests/kraken/driver.html?testName=audio-dft) 08:54:16 INFO - PROCESS | 25111 | RSS: Main: 174010368 08:54:16 INFO - PROCESS | 25111 | 08:54:18 INFO - PROCESS | 25111 | Cycle 1(1): loaded http://localhost:55883/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55883/tests/kraken/driver.html?testName=audio-fft) 08:54:19 INFO - PROCESS | 25111 | RSS: Main: 265375744 08:54:19 INFO - PROCESS | 25111 | 08:54:21 INFO - PROCESS | 25111 | Cycle 1(1): loaded http://localhost:55883/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55883/tests/kraken/driver.html?testName=audio-oscillator) 08:54:22 INFO - PROCESS | 25111 | RSS: Main: 171061248 08:54:22 INFO - PROCESS | 25111 | 08:54:24 INFO - PROCESS | 25111 | Cycle 1(1): loaded http://localhost:55883/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55883/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:54:24 INFO - PROCESS | 25111 | RSS: Main: 174350336 08:54:24 INFO - PROCESS | 25111 | 08:54:27 INFO - PROCESS | 25111 | Cycle 1(1): loaded http://localhost:55883/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55883/tests/kraken/driver.html?testName=imaging-darkroom) 08:54:28 INFO - PROCESS | 25111 | RSS: Main: 209276928 08:54:28 INFO - PROCESS | 25111 | 08:54:31 INFO - PROCESS | 25111 | Cycle 1(1): loaded http://localhost:55883/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55883/tests/kraken/driver.html?testName=imaging-desaturate) 08:54:32 INFO - PROCESS | 25111 | RSS: Main: 212762624 08:54:32 INFO - PROCESS | 25111 | 08:54:35 INFO - PROCESS | 25111 | Cycle 1(1): loaded http://localhost:55883/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55883/tests/kraken/driver.html?testName=json-parse-financial) 08:54:35 INFO - PROCESS | 25111 | RSS: Main: 211144704 08:54:35 INFO - PROCESS | 25111 | 08:54:37 INFO - PROCESS | 25111 | Cycle 1(1): loaded http://localhost:55883/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55883/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:54:37 INFO - PROCESS | 25111 | RSS: Main: 173592576 08:54:37 INFO - PROCESS | 25111 | 08:54:39 INFO - PROCESS | 25111 | Cycle 1(1): loaded http://localhost:55883/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55883/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:54:40 INFO - PROCESS | 25111 | RSS: Main: 180224000 08:54:40 INFO - PROCESS | 25111 | 08:54:42 INFO - PROCESS | 25111 | Cycle 1(1): loaded http://localhost:55883/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55883/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:54:42 INFO - PROCESS | 25111 | RSS: Main: 190492672 08:54:42 INFO - PROCESS | 25111 | 08:54:45 INFO - PROCESS | 25111 | Cycle 1(1): loaded http://localhost:55883/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55883/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:54:46 INFO - PROCESS | 25111 | RSS: Main: 199819264 08:54:46 INFO - PROCESS | 25111 | 08:54:48 INFO - PROCESS | 25111 | Cycle 1(1): loaded http://localhost:55883/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55883/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:54:49 INFO - PROCESS | 25111 | RSS: Main: 195342336 08:54:49 INFO - PROCESS | 25111 | 08:54:50 INFO - PROCESS | 25111 | Cycle 1(1): loaded http://localhost:55883/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55883/tests/kraken/driver.html?testName=ai-astar) 08:54:51 INFO - PROCESS | 25111 | RSS: Main: 245698560 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | __start_tp_report 08:54:51 INFO - PROCESS | 25111 | _x_x_mozilla_page_load 08:54:51 INFO - PROCESS | 25111 | _x_x_mozilla_page_load_details 08:54:51 INFO - PROCESS | 25111 | |i|pagename|runs| 08:54:51 INFO - PROCESS | 25111 | |0;ai-astar;104;112;113;112;112;112;110;112;112;114 08:54:51 INFO - PROCESS | 25111 | |1;audio-beat-detection;146;114;117;151;118;115;114;117;133;116 08:54:51 INFO - PROCESS | 25111 | |2;audio-dft;162;161;161;162;162;161;161;160;162;160 08:54:51 INFO - PROCESS | 25111 | |3;audio-fft;74;75;75;111;75;76;76;75;102;79 08:54:51 INFO - PROCESS | 25111 | |4;audio-oscillator;82;82;106;82;82;82;92;82;82;103 08:54:51 INFO - PROCESS | 25111 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;113;113;113 08:54:51 INFO - PROCESS | 25111 | |6;imaging-darkroom;147;147;146;147;147;146;147;146;147;147 08:54:51 INFO - PROCESS | 25111 | |7;imaging-desaturate;94;94;94;94;94;94;95;94;94;94 08:54:51 INFO - PROCESS | 25111 | |8;json-parse-financial;56;55;56;54;55;56;54;57;55;53 08:54:51 INFO - PROCESS | 25111 | |9;json-stringify-tinderbox;52;51;51;52;51;52;52;52;52;53 08:54:51 INFO - PROCESS | 25111 | |10;stanford-crypto-aes;84;83;76;75;77;79;82;75;78;79 08:54:51 INFO - PROCESS | 25111 | |11;stanford-crypto-ccm;123;120;127;124;121;126;130;131;159;124 08:54:51 INFO - PROCESS | 25111 | |12;stanford-crypto-pbkdf2;165;159;158;158;159;159;162;156;161;158 08:54:51 INFO - PROCESS | 25111 | |13;stanford-crypto-sha256-iterative;61;59;60;60;60;59;59;59;59;61 08:54:51 INFO - PROCESS | 25111 | __end_tp_report 08:54:51 INFO - PROCESS | 25111 | __start_cc_report 08:54:51 INFO - PROCESS | 25111 | _x_x_mozilla_cycle_collect,1648 08:54:51 INFO - PROCESS | 25111 | __end_cc_report 08:54:51 INFO - PROCESS | 25111 | __startTimestamp1495209291192__endTimestamp 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | ------- Summary: start ------- 08:54:51 INFO - PROCESS | 25111 | Number of tests: 14 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:2.75 (2.5%) stddev-sans-first:1.05 08:54:51 INFO - PROCESS | 25111 | Values: 104.0 112.0 113.0 112.0 112.0 112.0 110.0 112.0 112.0 114.0 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | [#1] audio-beat-detection Cycles:10 Average:124.10 Median:117.50 stddev:14.04 (11.9%) stddev-sans-first:12.45 08:54:51 INFO - PROCESS | 25111 | Values: 146.0 114.0 117.0 151.0 118.0 115.0 114.0 117.0 133.0 116.0 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:0.79 (0.5%) stddev-sans-first:0.78 08:54:51 INFO - PROCESS | 25111 | Values: 162.0 161.0 161.0 162.0 162.0 161.0 161.0 160.0 162.0 160.0 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | [#3] audio-fft Cycles:10 Average:81.80 Median:75.00 stddev:13.26 (17.7%) stddev-sans-first:13.76 08:54:51 INFO - PROCESS | 25111 | Values: 74.0 75.0 75.0 111.0 75.0 76.0 76.0 75.0 102.0 79.0 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | [#4] audio-oscillator Cycles:10 Average:87.50 Median:82.00 stddev:9.51 (11.6%) stddev-sans-first:9.88 08:54:51 INFO - PROCESS | 25111 | Values: 82.0 82.0 106.0 82.0 82.0 82.0 92.0 82.0 82.0 103.0 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 08:54:51 INFO - PROCESS | 25111 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 08:54:51 INFO - PROCESS | 25111 | Values: 147.0 147.0 146.0 147.0 147.0 146.0 147.0 146.0 147.0 147.0 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 08:54:51 INFO - PROCESS | 25111 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.50 stddev:1.20 (2.2%) stddev-sans-first:1.22 08:54:51 INFO - PROCESS | 25111 | Values: 56.0 55.0 56.0 54.0 55.0 56.0 54.0 57.0 55.0 53.0 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | [#9] json-stringify-tinderbox Cycles:10 Average:51.80 Median:52.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 08:54:51 INFO - PROCESS | 25111 | Values: 52.0 51.0 51.0 52.0 51.0 52.0 52.0 52.0 52.0 53.0 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:79.00 stddev:3.26 (4.1%) stddev-sans-first:2.86 08:54:51 INFO - PROCESS | 25111 | Values: 84.0 83.0 76.0 75.0 77.0 79.0 82.0 75.0 78.0 79.0 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | [#11] stanford-crypto-ccm Cycles:10 Average:128.50 Median:126.50 stddev:11.29 (8.9%) stddev-sans-first:11.79 08:54:51 INFO - PROCESS | 25111 | Values: 123.0 120.0 127.0 124.0 121.0 126.0 130.0 131.0 159.0 124.0 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.50 Median:159.00 stddev:2.55 (1.6%) stddev-sans-first:1.76 08:54:51 INFO - PROCESS | 25111 | Values: 165.0 159.0 158.0 158.0 159.0 159.0 162.0 156.0 161.0 158.0 08:54:51 INFO - PROCESS | 25111 | 08:54:51 INFO - PROCESS | 25111 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.70 Median:60.00 stddev:0.82 (1.4%) stddev-sans-first:0.73 08:54:51 INFO - PROCESS | 25111 | Values: 61.0 59.0 60.0 60.0 60.0 59.0 59.0 59.0 59.0 61.0 08:54:51 INFO - PROCESS | 25111 | -------- Summary: end -------- 08:54:51 INFO - PROCESS | 25111 | 08:54:52 INFO - TEST-INFO | 25111: exit 0 08:54:52 INFO - TEST-OK | kraken | took 59895ms 08:54:52 INFO - SUITE-END | took 1175s 08:54:52 INFO - Completed test suite (00:19:35) 08:54:52 INFO - javascript benchmark 08:54:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7976.944264269062, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3403.64261752516, 3432.4508078707395, 3441.019309496792, 3465.601455832577, 3482.651734826516, 5671.149327600862, 5753.769849206027, 5785.2189954356145, 5794.188813566104, 5799.246098007258, 5718.857028574277, 5766.769329226837, 5769.1923130761725, 5771.826845194641, 5772.942270577293, 5124.0776660201145, 5130.102232109366, 5175.974120129394, 5179.585633149339, 5204.843854684346, 5008.599312055045, 5019.523145301208, 5234.057869583474, 5234.214867769846, 5240.79036838526, 5117.309163262949, 5125.897482050356, 5129.179331306992, 5148.485151484859, 5167.974160129194, 5204.739763011853, 5215.426303106655, 5216.478352164791, 5227.11139106349, 5259.921101183485, 4946.2580612908, 5089.338386009832, 5169.224616307546, 5200.401953775288, 5209.2446595243855, 5581.079121944915, 5584.078627026536, 5633.267675202238, 5659.8585035374035, 5660.575456840712, 6078.848028799272, 6115.082737589352, 6122.713581872198, 6127.892078906864, 6150.692465376714, 6170.136180934673, 6185.257769067695, 6201.348858369896, 6202.968985155046, 6203.689815509207, 5602.411746766572, 5636.520895723868, 5717.371089180187, 5753.510951569164, 5829.09649004405, 6309.053641953744, 6322.589031712924, 6326.620402775849, 6327.873442531123, 6331.986882098842, 6025.548083893726, 6093.055576385667, 6094.634321940698, 6096.878062438726, 6098.573099883054, 5948.137520059568, 5951.851203719942, 6074.0, 6173.81478555644, 6186.566940314135, 6115.188456275656, 6138.010293309385, 6139.124180917401, 6139.614800607407, 6150.661713605705, 5775.740091695884, 5802.274715660543, 5806.68063256525, 6056.697165141725, 6150.7232174552255, 4649.465311489222, 4731.077439899255, 4733.9763301183275, 4750.61995040396, 4761.738104404221, 4129.566395528426, 4140.441040459499, 4206.284931561642, 4219.324908014764, 4245.8513952011535, 5837.29952405714, 5843.474087332075, 5849.268841394825, 5884.058550631964, 5901.439363260483, 6215.409536094064, 6222.408871157233, 6227.408396202353, 6232.626042437469, 6240.282367527701, 5709.8287051388525, 5937.376575459601, 5944.0, 5946.167536544887, 5947.464728174485, 5177.197534382177, 5198.298229739013, 5221.034108689904, 5221.582273418117, 5223.738813059332, 6365.172527571386, 6390.456811171102, 6393.8721225576155, 6403.551751377358, 6403.935960640334, 6104.0, 6139.3246742857355, 6181.505479561534, 6202.689865506616, 6206.224221972254, 6102.115193297036, 6117.188044311343, 6211.81364559064, 6241.157443745036, 6278.183836101278, 6112.929053818819, 6127.128444391353, 6131.804871721949, 6137.346341658241, 6137.62214577108, 6203.7828675996125, 6218.036204388327, 6222.408871157233, 6224.62652240867, 6229.190205273286, 5717.571182161438, 5827.329857066492, 5969.910451343147, 6005.939940600713, 6077.939220607915, 5986.970065149821, 6010.338862725184, 6040.244969378828, 6058.303295120942, 6101.38986101404, 6052.515798736003, 6061.969690151521, 6063.727132279147, 6065.150878877049, 6067.54493412987, 5832.795852145325, 5864.208331875142, 5906.0, 6013.789517366871, 6042.667653279112, 5960.046392577166, 5963.552733545088, 5974.462298393166, 5985.820425387071, 5997.430244126801], "value": 5656.640362034931, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [769.607500174911, 841.8863453433787, 858.6436628799049, 907.9909200907989, 913.5751875377948, 31751.206219844487, 43211.567884321186, 43987.78006109973, 44180.33729494056, 44181.77909110458, 46510.53489465104, 46827.0, 46828.0634387312, 46829.53170468303, 46926.0, 7801.726939557117, 7926.326262267714, 9249.53752312385, 9385.577649005778, 9391.34260601758, 10669.946650266738, 10924.071453926426, 10951.507182176822, 10968.341899486057, 10970.177236707239, 11067.0, 11106.61126860556, 11109.666709998713, 11114.6109886154, 11116.61091861785, 11027.117830573576, 11047.116230701566, 11048.613298534512, 11056.613018544353, 11058.612948546763, 10726.302790318605, 10749.247552671317, 10772.0, 10773.353598784099, 10776.5689372425, 43126.353104703456, 43146.84265786698, 43219.13561728748, 43338.783306083584, 43428.0, 4250.617444429985, 4340.587644173798, 4345.087531618364, 4452.376667266552, 4474.328850672425, 30286.697133028607, 30567.69432305671, 30973.0, 31056.689433105606, 31682.049538513882, 4496.168208881368, 4529.977350113261, 4548.8862778430475, 4551.8634440966825, 4556.749378784166, 249734.0, 250472.0, 250575.0, 250656.0, 250657.0, 55372.446275537535, 55441.44558554443, 55509.0, 55518.0, 55526.444735552126, 58892.82214355689, 58893.70553147207, 58894.116588250356, 58899.41100589025, 58927.410725892194, 15308.311125999357, 15613.843861561467, 15645.765313520305, 15676.764848527282, 15696.686066278611, 12104.515819367303, 12251.754964900652, 12623.684407889692, 13306.866931330756, 13328.933355333354, 13960.441582336796, 13981.300934953211, 14004.089734167246, 14159.78760318596, 14178.432862685584, 5709.286339207599, 5805.941940580625, 5850.8166660837705, 5861.824145275648, 5911.940880591225, 1006.00211370116, 1014.1633152648947, 1014.9086582207489, 1042.6142327338932, 1049.0610903241557, 28943.56584651232, 29069.418611628073, 29075.273118171794, 29432.705672943426, 29433.55849662257, 1092.9016388525072, 1106.6679996001167, 1107.429673718036, 1110.245033377311, 1120.6862078618, 14373.353199106064, 15259.389624415122, 15463.304151313218, 15465.845341546441, 15474.922625387026, 324351.0, 324684.0, 324784.0, 324805.0, 325074.0, 56685.43314566884, 56774.0, 56806.43193568094, 56818.715906420206, 56983.43016569864, 1468.875145612635, 1490.5006822714295, 1494.9551513454614, 1548.256836718359, 1552.8214255360551, 27476.0, 28555.28611784722, 28562.285942851595, 29247.008726422064, 29763.255918602208, 36569.45145822868, 45249.77375113103, 46315.305270421646, 51423.742881285354, 51488.97022059613, 348430.0, 351031.0, 352060.0, 353007.0, 353329.0, 25567.360815979748, 25631.23106306814, 30327.090187294416, 30569.54145687772, 30864.382712346076, 39714.40428393634, 39738.0, 39805.80097099496, 39887.40168897527, 39956.40065399079, 30683.2329191772, 31143.0, 31204.219894502818, 31425.371492570477, 31431.842840785648, 29920.401591967602, 30176.54735179018, 30465.695343046285, 30580.84709576438, 30696.0, 34774.304513909075, 35141.47287790634, 35399.64600353964, 35493.82253088718, 35828.10429739173, 28973.130806075853, 29058.27354316159, 29382.70617293885, 29464.852675736485, 29676.554851677673, 23638.52722945566, 23832.642510362013, 23968.52062958766, 24139.396515087265, 24222.87888560546], "value": 19096.309381145165, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.7894168682407, 464.4890620317651, 472.723456777785, 487.08428155068464, 495.51192075805335, 21478.89260553697, 22049.448763780892, 22629.434264143405, 22760.31719048427, 22925.885370573167, 20726.170953161854, 20795.896020519915, 20900.581988360223, 20939.68590471144, 21426.357209283706, 5037.949620503785, 6788.660566971644, 6995.720171193147, 7035.472339574552, 7060.7881763547175, 4121.835126594932, 4313.374560688699, 6803.761868334609, 7564.773056808304, 7953.045634523865, 7881.448298619071, 7985.4809437386675, 8138.104808471064, 8180.222878826502, 8187.0, 7796.376289896794, 7999.920000800005, 8055.395845311625, 8066.475679080841, 8079.919200807975, 7765.912772211895, 7778.261065198853, 7785.610719464004, 7799.7270095546955, 7804.180561041066, 27322.180334589993, 27518.174454766387, 27650.861745691345, 28608.284792880135, 28772.424551509062, 3243.4972579250016, 3253.6665936996383, 3268.8038717676777, 3305.2067503798917, 3342.8495717692763, 16409.83590164095, 16532.256048477848, 16912.238949247192, 16961.66076678472, 17043.744343834853, 2339.836211465198, 2366.325597204788, 2380.3216083416314, 2422.345966589011, 2450.3139121046133, 117918.0, 120722.79277207203, 120761.0, 120881.39559302235, 121498.39250803778, 18847.476605614553, 19944.401667949984, 20081.59836803285, 20127.798722012885, 22079.33761987111, 36967.81516092402, 37105.44341834875, 37197.25605487875, 37266.62733372686, 37670.05824854346, 10709.250352475252, 10963.83554246687, 11077.279976801483, 11109.0, 11223.887761122285, 9009.774755631031, 9035.638574456949, 9055.18503334703, 9159.496227707406, 9262.95368523153, 10276.948615256875, 10356.17150627948, 10797.0, 10808.675739727822, 10997.285176463503, 3413.4367829307885, 3798.582155962842, 4271.615554600101, 4276.706935659525, 4410.757408342508, 966.429806414219, 996.5366104761321, 1001.9098281154731, 1025.9076683098558, 1030.6547306652205, 23057.769422305897, 23365.065397384253, 23382.298531044096, 23468.413289667897, 23506.64740028901, 992.4987881119988, 998.7952469743685, 1003.4832061488339, 1005.3967619428431, 1027.50679673757, 12465.5013799447, 12489.50041998328, 12491.750164996649, 12791.296478693761, 12974.675633109247, 613622.0, 617812.0, 620809.0, 622407.0, 625015.0, 112050.0, 115780.99999999831, 117813.4109329465, 119166.0, 119818.4009079949, 1374.154894739728, 1392.5265409760527, 1406.4514839212716, 1420.7016526529544, 1423.4163992762917, 19253.133608987348, 19516.31692890742, 19769.802301977372, 19891.204351826345, 19938.800611993694, 48858.5114148854, 49891.25380611806, 50234.49765502445, 50710.746446267534, 51068.74465627648, 67376.32623673897, 67644.0, 67825.3217467819, 68084.6595767018, 68626.65686671734, 12944.611661650166, 13164.670883227996, 13502.0, 13718.794218086538, 13988.0, 13850.584482465541, 14369.0, 15412.306446209723, 15648.139352335276, 16043.278052487432, 12631.305278209788, 12639.115261931576, 12656.74686506283, 12665.620031399072, 12809.103362764517, 12394.81407778866, 12442.066844986843, 12584.244945303311, 12641.747165056464, 12795.488180472677, 12973.935130324287, 12990.545330913372, 13032.674183145496, 13114.803277950648, 13387.933060334635, 12698.30159341245, 12858.099933004974, 12877.678058048623, 12880.227186368847, 12990.350482476028, 12750.744985100431, 12835.614931552069, 12847.357632118543, 12909.612711618667, 13374.398152082986], "value": 12094.643967015178, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1545.1192820092544, 1545.2737213509647, 1561.898476599021, 1562.562482504899, 1569.764535319702, 10598.523066462009, 11350.659480215596, 11350.772984540305, 11366.3748493833, 11367.772644547114, 10977.28647637903, 11128.05411540018, 11175.273607215546, 11666.941665291663, 11683.182177247574, 2403.7956773674214, 2441.426264827764, 2444.303373538542, 2445.987770061156, 2447.571674956884, 2403.122860156039, 2464.37158524576, 2491.451880586268, 2491.825572209946, 2496.475740094573, 2432.7567243275707, 2459.8401103928186, 2475.8143139264625, 2502.5370306493355, 2506.2105436787447, 2384.749601291874, 2386.3198988288427, 2418.093215044358, 2439.695038120235, 2448.8040956723507, 2412.8311018228733, 2419.3830573203895, 2430.538197742423, 2432.9513409731708, 2436.8659723715186, 11533.0, 11751.647450576496, 11781.646550603497, 11783.528658853722, 11785.764284714343, 5673.120666296731, 5829.329627092896, 5830.4461076197695, 5832.766689332421, 5837.211976383219, 3434.107132145635, 3518.8416521256604, 3571.1072231942017, 3585.229175727231, 3633.218857945528, 1362.461827578111, 1363.3864760857655, 1411.6329754263966, 1411.8517555656713, 1434.519435988945, 16742.24659890308, 16942.915285423616, 17264.30942762288, 17575.0, 17756.200970956208, 4693.718376897397, 4746.216874215785, 4776.952230477651, 4831.396075490564, 4952.009598080398, 12901.096923215286, 12911.935440322739, 12916.612501624966, 12919.418626161845, 12924.224546527239, 5806.1581070744505, 5807.970960145257, 5813.0, 5817.331006934172, 5823.854403639858, 5186.273921650972, 5223.608229382811, 5224.582033437316, 5261.631685781958, 5340.732963351817, 4062.979154158025, 4108.465899433055, 4118.9588104118575, 4125.278076336629, 4142.0, 1100.290312748289, 1105.0441368216595, 1112.3214838948236, 1124.2748427264532, 1125.983110253347, 1094.8631421072366, 1106.623747925709, 1107.1751545098953, 1109.5118148014847, 1117.9776404471866, 7080.433565314763, 7258.346748792634, 7342.412606991535, 7378.815529611803, 7444.106707195063, 1031.9535620897077, 1036.4196050211906, 1051.984220236697, 1062.8990245926625, 1083.815751322277, 4184.602462766033, 4318.5681431856565, 4368.191884501377, 4424.80088396023, 4539.841105561355, 14251.21618311003, 14810.703785924221, 14818.110913345234, 14836.0, 14927.104373737611, 11391.601293954673, 13819.378127984264, 14210.857891420954, 14798.926005369904, 14824.110553366616, 8946.642134314685, 9929.950350248348, 10054.19566434698, 10102.292839501164, 10103.7474063149, 7484.887726684213, 7510.849783004419, 7677.692892284469, 7725.845483090419, 7797.532148071134, 12574.434150463434, 12826.038047146314, 12841.807372889602, 13086.672833179247, 13092.083554151119, 12705.745885082431, 13006.804897926728, 13230.536931207747, 13278.136921100098, 13343.466261349437, 9764.365316254421, 10465.0, 10530.0, 10554.10290125348, 10649.9467502662, 10890.564377425131, 10960.890391095987, 11110.944445277722, 11389.886101138882, 11539.538418463168, 9319.813603728024, 9389.29580281468, 9473.763155921157, 9649.710508684751, 9889.406635601887, 9176.128267814547, 9227.44635321883, 9447.905520944703, 9449.385789923634, 9567.760805979906, 7838.490498117604, 7979.241972012649, 8005.599720014093, 8064.516129032277, 8093.878591821245, 5760.222369979999, 5818.389069147678, 5819.738111784895, 5873.236479257669, 5959.046552551571, 6398.392152745481, 6572.507061970467, 6592.340765923369, 6689.163854518185, 6724.562903411263], "value": 5421.434076003787, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.1471769666423, 432.64523091065325, 445.2386419223128, 455.7311186400023, 461.96766226364144, 5286.947130528698, 5361.624686271963, 5374.5700343972485, 5390.946090539093, 5421.457854214586, 5117.8464646060675, 5217.139172036619, 5229.790808367661, 5251.422343542207, 5274.393444753854, 4196.286631272684, 4235.813972087821, 4239.0500369829915, 4245.688363076805, 4281.314989601664, 4241.915161696764, 4263.616274535291, 4265.936010959838, 4279.935800962988, 4297.828086876521, 4113.115680128772, 4235.788210589473, 4280.764557949312, 4282.314829627245, 4286.435451060372, 4224.556421575752, 4241.766702831343, 4243.130158317537, 4247.193033323674, 4254.191703576342, 3872.2255548890334, 3889.6888248940018, 3892.3966785148345, 3893.3770596704676, 3894.6689531389584, 5204.256574047698, 5259.342582177228, 5261.552768014723, 5262.815801446969, 5273.578113750891, 4858.344123543312, 4886.780094895738, 4901.0, 4926.33494478248, 4951.282064100721, 5543.695096769676, 5563.221149039137, 5571.359293681238, 5599.32808063031, 5604.467575580314, 4343.153085148366, 4346.782660866976, 4373.387725718402, 4379.0, 4412.382266482695, 5557.5831812614215, 5581.972090139564, 5646.6047376684055, 5647.548196144339, 5774.682392468412, 5056.443791182967, 5060.84817455477, 5061.746912654353, 5063.316452278968, 5073.594112470993, 5083.313752643419, 5091.0, 5128.256402821645, 5132.769025393792, 5145.266354953452, 4904.852854414374, 5092.210707340369, 5165.483451654804, 5189.870253243699, 5235.83232144547, 5512.255845460892, 5532.917006244909, 5595.832125036255, 5601.831945041656, 5604.215409842625, 1642.3923148435156, 1665.1757380096687, 1676.1786724504905, 1676.7652528646, 1681.8318168183084, 1301.2062641788502, 1309.8277042047314, 1317.7166909114587, 1331.8534961154264, 1336.7927971164486, 5275.261463395128, 5483.930633526424, 5487.314085739283, 5496.7526461309335, 5579.218909352694, 5716.657000579979, 5750.769969201268, 5752.884942301131, 5761.798337058267, 5766.855828604234, 5392.838214853561, 5489.423610520837, 5490.725463726878, 5526.557875370041, 5533.806316778893, 2876.6548014238424, 3008.293051132982, 3008.9619081417313, 3028.348904985439, 3032.2116249775218, 5892.204552385373, 5958.255218097738, 5977.193078934288, 5988.867681299889, 6042.860640461298, 5575.693336866511, 5788.363280039192, 5859.912101318484, 5979.730912108966, 6000.809704457842, 5911.615744976562, 5924.7926322578505, 5935.0, 5936.141508019821, 5937.940620593825, 5505.889882202414, 5735.684537350486, 5766.509846663081, 5806.096951524238, 5823.505002074871, 5486.862828429162, 5545.805896793593, 5665.716714164358, 5705.743241554057, 5710.914336285042, 5807.925533776265, 5835.095560188008, 5846.736896839738, 5903.527717782653, 5925.466707996301, 4776.808927642995, 4788.3056956741775, 4792.640551958687, 4794.688345257547, 4840.782164802522, 5465.289512363367, 5702.0, 5717.22817419643, 5744.741486633196, 5753.223314852441, 5592.580556458201, 5604.719764011865, 5642.332483885507, 5665.0, 5691.467297052772, 5352.518273355417, 5647.265855438766, 5690.118031705092, 5696.433480792815, 5724.198612194213, 5481.424450432647, 5569.637973531708, 5640.85897852557, 5657.462541058593, 5674.659520428788, 5588.0, 5669.744861481326, 5677.7161141941915, 5731.770729170787, 5733.971330143323, 5315.681059136464, 5458.972705136608, 5465.836024919259, 5467.0, 5597.860053498533], "value": 4477.744753820434, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [914.4558987402497, 915.8717779510865, 933.2720478027139, 944.8535477001064, 959.3668179001855, 16985.490435286945, 18127.365542206026, 18181.272749090054, 18207.908960455195, 18237.0, 15944.123073230967, 16089.758653620176, 16704.58238544034, 16910.1544922754, 16968.49094527166, 867.3234876796109, 891.3493150000503, 896.4083704754863, 899.4468401932797, 900.8423525882977, 905.6286922361833, 906.6735975048977, 906.9455832650029, 907.2741806554762, 908.7637214324291, 941.0119374656617, 941.7410212191634, 942.7595963029418, 943.2077055273569, 943.2642538819697, 933.2160984772755, 934.4953724988566, 939.906009399058, 939.9801215681028, 940.3981451870808, 685.25649670108, 688.0779755128102, 688.5696439725172, 690.989635155468, 692.7090621938747, 18517.814821851745, 18591.814081859142, 18782.154803033896, 19364.031798410022, 19485.9025704872, 16026.839731602651, 16053.518394448069, 16177.602703432294, 16319.265633046542, 16640.66718665632, 13624.659383515393, 13748.831349335318, 13860.0, 14422.639434014234, 14890.702185956221, 4085.060436099684, 4117.444145040441, 4122.56713045129, 4191.895202619959, 4280.593343632381, 90323.0, 90841.0, 90903.54548227217, 92838.0, 93735.0, 12654.683632909251, 12851.935740321238, 12853.16454430459, 12860.742785144244, 12873.613791586267, 24097.518049638908, 24150.758492415203, 24216.7578324218, 24527.386815329402, 24638.630420543705, 8806.20744133031, 9448.57481413338, 9618.422894626345, 9640.42157470554, 9648.0, 5034.622403319766, 5054.949450505522, 5136.512031357015, 5171.456997015334, 5299.629025968223, 3766.5856755756845, 3819.8281077351585, 3848.3457812171996, 3850.7497012694084, 3856.4408160817084, 650.4666173737489, 650.8307839961644, 653.4543656047159, 658.1937127019363, 661.8113837556274, 843.8649816029406, 846.9830603387898, 868.6221493650282, 884.6992022712309, 894.6019021535354, 11552.826707599394, 11758.470868810924, 12066.638000859988, 12169.756604868031, 12293.446794894251, 770.8150043989402, 773.8568364852521, 782.5422128055152, 791.3629528229831, 804.5373910001772, 5283.630145889751, 5332.30680011596, 5333.973330133324, 5373.7581808818695, 5395.70323632204, 61194.694026529585, 78818.45316960636, 79777.60111199407, 84663.15336846675, 85717.57141214379, 73859.26140738631, 75111.62444187744, 75508.0, 76080.0, 77878.61060694774, 65803.0, 66308.33691663117, 66439.66780166165, 67135.99296010564, 67412.66293668599, 10449.477526123816, 10653.733656658336, 10706.571737130427, 10787.838182427427, 10827.891721082688, 38358.2328353437, 39564.60435395609, 46026.0, 46451.30323045225, 46586.53413465822, 54244.45755542394, 54627.72686136544, 56085.0, 57146.42853571411, 57655.71172144113, 14324.427022918966, 14398.85601144017, 14411.42354305816, 14526.346314415665, 14594.0, 16054.036757794569, 16235.918820406296, 16414.261358239146, 16437.917810410872, 18069.457916262534, 15220.0, 15559.37762489533, 16437.506874793777, 16709.582260443585, 17833.375831845824, 14871.553853384416, 15211.239438028275, 15956.281967311732, 16743.413980510624, 16910.492685219462, 15484.28217883424, 15796.332357211146, 16284.43004494837, 16339.264733087277, 16500.92743971686, 10723.356598604109, 10822.567097316334, 10858.67423977282, 10864.619738309122, 11119.388433635904, 13269.270190139636, 13276.203427794364, 13284.667883302995, 13295.66760830987, 13618.591442256748], "value": 8122.793549919216, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1453.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 113.0, 112.0, 112.0, 112.0, 110.0, 112.0, 112.0, 114.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 114.0, 117.0, 151.0, 118.0, 115.0, 114.0, 117.0, 133.0, 116.0], "value": 124.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 162.0, 162.0, 161.0, 161.0, 160.0, 162.0, 160.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 111.0, 75.0, 76.0, 76.0, 75.0, 102.0, 79.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 106.0, 82.0, 82.0, 82.0, 92.0, 82.0, 82.0, 103.0], "value": 87.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 147.0, 146.0, 147.0, 146.0, 147.0, 147.0], "value": 146.7, "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": [56.0, 55.0, 56.0, 54.0, 55.0, 56.0, 54.0, 57.0, 55.0, 53.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 52.0, 51.0, 52.0, 52.0, 52.0, 52.0, 53.0], "value": 51.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 76.0, 75.0, 77.0, 79.0, 82.0, 75.0, 78.0, 79.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 120.0, 127.0, 124.0, 121.0, 126.0, 130.0, 131.0, 159.0, 124.0], "value": 128.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 159.0, 158.0, 158.0, 159.0, 159.0, 162.0, 156.0, 161.0, 158.0], "value": 159.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 60.0, 60.0, 60.0, 59.0, 59.0, 59.0, 59.0, 61.0], "value": 59.7, "unit": "score"}]}]} 08:54:52 INFO - Return code: 0 08:54:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:54:52 INFO - # TBPL SUCCESS # 08:54:52 INFO - Running post-action listener: _resource_record_post_action 08:54:52 INFO - [mozharness: 2017-05-19 15:54:52.997742Z] Finished run-tests step (success) 08:54:52 INFO - Running post-run listener: _resource_record_post_run 08:54:53 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328175616; Read time: 8; Write time: 207712 08:54:53 INFO - TinderboxPrint: CPU usage
13.1% 08:54:53 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 08:54:53 INFO - TinderboxPrint: I/O write bytes / time
328,175,616 / 207,712 08:54:53 INFO - TinderboxPrint: CPU idle
8,256.1 (86.9%) 08:54:53 INFO - TinderboxPrint: CPU user
1,197.6 (12.6%) 08:54:53 INFO - TinderboxPrint: Swap in / out
0 / 0 08:54:53 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 90406912; Read time: 0; Write time: 82576 08:54:53 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 237768704; Read time: 0; Write time: 125136 08:54:53 INFO - Running post-run listener: _upload_blobber_files 08:54:53 INFO - Blob upload gear active. 08:54:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:54:53 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 08:54:53 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'] 08:54:53 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 08:54:54 INFO - (blobuploader) - INFO - Open directory for files ... 08:54:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:54:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:54:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:54:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:54:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:54:54 INFO - (blobuploader) - INFO - Done attempting. 08:54:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:54:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:54:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:54:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:54:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:54:55 INFO - (blobuploader) - INFO - Done attempting. 08:54:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:54:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:54:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:54:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:54:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:54:56 INFO - (blobuploader) - INFO - Done attempting. 08:54:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:54:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:54:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:54:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:54:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:54:59 INFO - (blobuploader) - INFO - Done attempting. 08:54:59 INFO - (blobuploader) - INFO - Iteration through files over. 08:54:59 INFO - Return code: 0 08:54:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:54:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:54:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1c515ad89d2fdd57e5a1e3a0737b253fe506c56046886344c8e8ba4d57722620b36a204387c7db0120a80d125f9e45a242915377fff6de839da2b2ec98f06247", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/73ea1822c16f45ed61f4347c86f37e9212fa7dfd40a37075a927eb361c1da5458959cebdc157d2eebd6af867f93b0af0b9c08b8c8a1ede4aeca18f1ba298031a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c0f1f0b71a3cd9344d6ca0bf34bc952e67d2bb52374cc76489b7f596a41663293b35bc9d3adaa763ece30cca3995bf132faf324441387c37504d8b1421555d1d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/10cb9a18bac58dc60828055631439f764471a47af9ab1f534d60132d497f1ed7831e26be0d4364c37eb314c877646dccc9ddac073f39c8ef5eec278599c9a6bb"} 08:54:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:54:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:54:59 INFO - Contents: 08:54:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1c515ad89d2fdd57e5a1e3a0737b253fe506c56046886344c8e8ba4d57722620b36a204387c7db0120a80d125f9e45a242915377fff6de839da2b2ec98f06247", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/73ea1822c16f45ed61f4347c86f37e9212fa7dfd40a37075a927eb361c1da5458959cebdc157d2eebd6af867f93b0af0b9c08b8c8a1ede4aeca18f1ba298031a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c0f1f0b71a3cd9344d6ca0bf34bc952e67d2bb52374cc76489b7f596a41663293b35bc9d3adaa763ece30cca3995bf132faf324441387c37504d8b1421555d1d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/10cb9a18bac58dc60828055631439f764471a47af9ab1f534d60132d497f1ed7831e26be0d4364c37eb314c877646dccc9ddac073f39c8ef5eec278599c9a6bb"} 08:54:59 INFO - Running post-run listener: copy_logs_to_upload_dir 08:54:59 INFO - Copying logs to upload dir... 08:54:59 INFO - mkdir: /builds/slave/test/build/upload/logs 08:54:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.777815 ========= master_lag: -0.22 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-05-19 08:54:59.015806) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 08:54:59.021186) ========= 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=17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1c515ad89d2fdd57e5a1e3a0737b253fe506c56046886344c8e8ba4d57722620b36a204387c7db0120a80d125f9e45a242915377fff6de839da2b2ec98f06247", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/73ea1822c16f45ed61f4347c86f37e9212fa7dfd40a37075a927eb361c1da5458959cebdc157d2eebd6af867f93b0af0b9c08b8c8a1ede4aeca18f1ba298031a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c0f1f0b71a3cd9344d6ca0bf34bc952e67d2bb52374cc76489b7f596a41663293b35bc9d3adaa763ece30cca3995bf132faf324441387c37504d8b1421555d1d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/10cb9a18bac58dc60828055631439f764471a47af9ab1f534d60132d497f1ed7831e26be0d4364c37eb314c877646dccc9ddac073f39c8ef5eec278599c9a6bb"} build_url:https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011274 build_url: 'https://queue.taskcluster.net/v1/task/LOTEA0biQh-r5RQNNMcs-w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1c515ad89d2fdd57e5a1e3a0737b253fe506c56046886344c8e8ba4d57722620b36a204387c7db0120a80d125f9e45a242915377fff6de839da2b2ec98f06247", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/73ea1822c16f45ed61f4347c86f37e9212fa7dfd40a37075a927eb361c1da5458959cebdc157d2eebd6af867f93b0af0b9c08b8c8a1ede4aeca18f1ba298031a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c0f1f0b71a3cd9344d6ca0bf34bc952e67d2bb52374cc76489b7f596a41663293b35bc9d3adaa763ece30cca3995bf132faf324441387c37504d8b1421555d1d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/10cb9a18bac58dc60828055631439f764471a47af9ab1f534d60132d497f1ed7831e26be0d4364c37eb314c877646dccc9ddac073f39c8ef5eec278599c9a6bb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 08:54:59.053294) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 08:54:59.055651) ========= 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=17051fcd5208b27b50bedfeb000001dd-1495207736.957436-2004531457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004528 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 08:54:59.138565) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 08:54:59.140280) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 08:54:59.140582) ========= ========= Total master_lag: -0.05 =========