builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-007 starttime: 1496274228.87 results: success (0) revision: 10cfa295a989879affa59adeb70f7e0847d19e7f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 16:43:48.875198) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 16:43:48.875596) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 16:43:48.875878) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 16:43:48.906879) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 16:43:48.907143) ========= 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=923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-31 16:43:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.9M=0s 2017-05-31 16:43:49 (45.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123731 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 16:43:49.053475) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 16:43:49.053751) ========= 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=923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014721 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 16:43:49.114609) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-31 16:43:49.114906) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 10cfa295a989879affa59adeb70f7e0847d19e7f --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 10cfa295a989879affa59adeb70f7e0847d19e7f --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=923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-31 16:43:49,536 truncating revision to first 12 chars 2017-05-31 16:43:49,537 Setting DEBUG logging. 2017-05-31 16:43:49,537 attempt 1/10 2017-05-31 16:43:49,537 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/10cfa295a989?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-31 16:43:50,976 unpacking tar archive at: mozilla-beta-10cfa295a989/testing/mozharness/ program finished with exit code 0 elapsedTime=1.869360 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-31 16:43:51.007619) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 16:43:51.007977) ========= script_repo_revision: 10cfa295a989879affa59adeb70f7e0847d19e7f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 16:43:51.008381) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 16:43:51.008659) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 16:43:51.026697) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-05-31 16:43:51.027096) ========= /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=923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:43:51 INFO - MultiFileLogger online at 20170531 16:43:51 in /builds/slave/test 16:43:51 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 16:43:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:43:51 INFO - {'append_to_log': False, 16:43:51 INFO - 'base_work_dir': '/builds/slave/test', 16:43:51 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 16:43:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:43:51 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 16:43:51 INFO - 'buildbot_json_path': 'buildprops.json', 16:43:51 INFO - 'config_files': ('talos/linux_config.py',), 16:43:51 INFO - 'default_actions': ('clobber', 16:43:51 INFO - 'read-buildbot-config', 16:43:51 INFO - 'download-and-extract', 16:43:51 INFO - 'populate-webroot', 16:43:51 INFO - 'create-virtualenv', 16:43:51 INFO - 'install', 16:43:51 INFO - 'run-tests'), 16:43:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:43:51 INFO - 'download_minidump_stackwalk': True, 16:43:51 INFO - 'download_symbols': 'ondemand', 16:43:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:43:51 INFO - 'tooltool.py': '/tools/tooltool.py', 16:43:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:43:51 INFO - '/tools/misc-python/virtualenv.py')}, 16:43:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:43:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:43:51 INFO - 'gecko_profile': False, 16:43:51 INFO - 'gecko_profile_interval': 0, 16:43:51 INFO - 'installer_path': 'installer.exe', 16:43:51 INFO - 'log_level': 'info', 16:43:51 INFO - 'log_name': 'talos', 16:43:51 INFO - 'log_to_console': True, 16:43:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:43:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:43:51 INFO - 'opt_config_files': (), 16:43:51 INFO - 'pip_index': False, 16:43:51 INFO - 'suite': 'dromaeojs', 16:43:51 INFO - 'system_bits': '32', 16:43:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:43:51 INFO - 'title': 'talos-linux64-ix-007', 16:43:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:43:51 INFO - 'use_talos_json': True, 16:43:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:43:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:43:51 INFO - 'work_dir': 'build'} 16:43:51 INFO - [mozharness: 2017-05-31 23:43:51.545027Z] Running clobber step. 16:43:51 INFO - Running pre-action listener: _resource_record_pre_action 16:43:51 INFO - Running main action method: clobber 16:43:51 INFO - rmtree: /builds/slave/test/build 16:43:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:43:52 INFO - Running post-action listener: _resource_record_post_action 16:43:52 INFO - [mozharness: 2017-05-31 23:43:52.583142Z] Finished clobber step (success) 16:43:52 INFO - [mozharness: 2017-05-31 23:43:52.583240Z] Running read-buildbot-config step. 16:43:52 INFO - Running pre-action listener: _resource_record_pre_action 16:43:52 INFO - Running main action method: read_buildbot_config 16:43:52 INFO - Using buildbot properties: 16:43:52 INFO - { 16:43:52 INFO - "project": "", 16:43:52 INFO - "product": "firefox", 16:43:52 INFO - "who": "ryanvm@gmail.com", 16:43:52 INFO - "installer_path": "public/build/target.tar.bz2", 16:43:52 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:43:52 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 16:43:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 16:43:52 INFO - "stage_platform": "linux64", 16:43:52 INFO - "basedir": "/builds/slave/test", 16:43:52 INFO - "buildnumber": 63, 16:43:52 INFO - "platform": "ubuntu64_hw", 16:43:52 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:43:52 INFO - "slavebuilddir": "test", 16:43:52 INFO - "taskId": "Ucg0GYgYSY2tA-qUOyJL8A", 16:43:52 INFO - "branch": "mozilla-beta", 16:43:52 INFO - "script_repo_revision": "production", 16:43:52 INFO - "revision": "10cfa295a989879affa59adeb70f7e0847d19e7f", 16:43:52 INFO - "slavename": "talos-linux64-ix-007", 16:43:52 INFO - "repo_path": "releases/mozilla-beta" 16:43:52 INFO - } 16:43:52 INFO - Finding installer, test and symbols from parent task. 16:43:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ucg0GYgYSY2tA-qUOyJL8A'}, attempt #1 16:43:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ucg0GYgYSY2tA-qUOyJL8A'}, attempt #1 16:43:53 INFO - Task dependencies: NhgHqHj2TS2wkjMBXVFxDw 16:43:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw'}, attempt #1 16:43:53 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.tar.bz2 16:43:53 INFO - Set test_url: https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/test_packages.json 16:43:53 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.crashreporter-symbols.zip 16:43:53 INFO - Running post-action listener: _resource_record_post_action 16:43:53 INFO - [mozharness: 2017-05-31 23:43:53.926700Z] Finished read-buildbot-config step (success) 16:43:53 INFO - [mozharness: 2017-05-31 23:43:53.926919Z] Running download-and-extract step. 16:43:53 INFO - Running pre-action listener: _resource_record_pre_action 16:43:53 INFO - Running main action method: download_and_extract 16:43:53 INFO - mkdir: /builds/slave/test/build/tests 16:43: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')]} 16:43:53 INFO - trying https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.test_packages.json 16:43:53 INFO - Downloading https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:43:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:43:54 INFO - Downloaded 986 bytes. 16:43:54 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:43:54 INFO - Using the following test package requirements: 16:43:54 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:43:54 INFO - u'common': [u'target.common.tests.zip'], 16:43:54 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:43:54 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:43:54 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:43:54 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:43:54 INFO - u'mozbase': [u'target.common.tests.zip'], 16:43:54 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:43:54 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:43:54 INFO - u'web-platform': [u'target.common.tests.zip', 16:43:54 INFO - u'target.web-platform.tests.zip'], 16:43:54 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:43:54 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:43:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.common.tests.zip 16:43:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:43:54 INFO - Fetch https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.common.tests.zip into memory 16:43:57 INFO - Expected file size: 59716631 16:43:57 INFO - Obtained file size: 59716631 16:44:06 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:44:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.common.tests.zip 16:44:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:44:06 INFO - Fetch https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.common.tests.zip into memory 16:44:09 INFO - Expected file size: 59716631 16:44:09 INFO - Obtained file size: 59716631 16:44:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.talos.tests.zip 16:44:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:44:20 INFO - Fetch https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.talos.tests.zip into memory 16:44:20 INFO - Expected file size: 14404461 16:44:20 INFO - Obtained file size: 14404461 16:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:21 INFO - trying https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.tar.bz2 16:44:21 INFO - Downloading https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:44:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:44:26 INFO - Downloaded 59770748 bytes. 16:44:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.tar.bz2 16:44:26 INFO - mkdir: /builds/slave/test/properties 16:44:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:44:26 INFO - Writing to file /builds/slave/test/properties/build_url 16:44:26 INFO - Contents: 16:44:26 INFO - build_url:https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.tar.bz2 16:44:26 INFO - Running post-action listener: _resource_record_post_action 16:44:26 INFO - Running post-action listener: set_extra_try_arguments 16:44:26 INFO - [mozharness: 2017-05-31 23:44:26.909019Z] Finished download-and-extract step (success) 16:44:26 INFO - [mozharness: 2017-05-31 23:44:26.909252Z] Running populate-webroot step. 16:44:26 INFO - Running pre-action listener: _resource_record_pre_action 16:44:26 INFO - Running main action method: populate_webroot 16:44:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:44:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:44:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:44:26 INFO - '--tppagecycles', 16:44:26 INFO - '7'], 16:44:26 INFO - 'tests': ['tsvgm']}}, 16:44:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:44:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:44:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:44:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:44:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:44:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:44:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:44:26 INFO - 'talos_options': ['--disable-e10s'], 16:44:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:44:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:44:26 INFO - 'talos_options': ['--disable-e10s'], 16:44:26 INFO - 'tests': ['damp', 'tps']}, 16:44:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:44:26 INFO - 'tests': ['damp', 'tps']}, 16:44:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:44:26 INFO - 'tests': ['dromaeo_dom']}, 16:44:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:44:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:44:26 INFO - 'tests': ['basic_compositor_video']}, 16:44:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:44:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:44:26 INFO - 'tests': ['a11yr', 16:44:26 INFO - 'ts_paint', 16:44:26 INFO - 'tpaint', 16:44:26 INFO - 'sessionrestore', 16:44:26 INFO - 'sessionrestore_no_auto_restore', 16:44:26 INFO - 'tabpaint']}, 16:44:26 INFO - 'other-e10s': {'tests': ['a11yr', 16:44:26 INFO - 'ts_paint', 16:44:26 INFO - 'tpaint', 16:44:26 INFO - 'sessionrestore', 16:44:26 INFO - 'sessionrestore_no_auto_restore', 16:44:26 INFO - 'tabpaint']}, 16:44:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:44:26 INFO - 'ts_paint', 16:44:26 INFO - 'tpaint', 16:44:26 INFO - 'sessionrestore', 16:44:26 INFO - 'sessionrestore_no_auto_restore', 16:44:26 INFO - 'tabpaint']}, 16:44:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:44:26 INFO - 'ts_paint', 16:44:26 INFO - 'tpaint', 16:44:26 INFO - 'sessionrestore', 16:44:26 INFO - 'sessionrestore_no_auto_restore', 16:44:26 INFO - 'tabpaint']}, 16:44:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:44:26 INFO - 'tests': ['a11yr', 16:44:26 INFO - 'ts_paint', 16:44:26 INFO - 'tpaint', 16:44:26 INFO - 'sessionrestore', 16:44:26 INFO - 'sessionrestore_no_auto_restore', 16:44:26 INFO - 'tabpaint']}, 16:44:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:44:26 INFO - 'tests': ['a11yr', 16:44:26 INFO - 'ts_paint', 16:44:26 INFO - 'tpaint', 16:44:26 INFO - 'sessionrestore', 16:44:26 INFO - 'sessionrestore_no_auto_restore', 16:44:26 INFO - 'tabpaint']}, 16:44:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:44:26 INFO - 'tests': ['tsvgx', 16:44:26 INFO - 'tsvgr_opacity', 16:44:26 INFO - 'tart', 16:44:26 INFO - 'tscrollx', 16:44:26 INFO - 'cart', 16:44:26 INFO - 'tsvg_static']}, 16:44:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:44:26 INFO - 'tsvgr_opacity', 16:44:26 INFO - 'tart', 16:44:26 INFO - 'tscrollx', 16:44:26 INFO - 'cart', 16:44:26 INFO - 'tsvg_static']}, 16:44:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:44:26 INFO - 'talos_options': ['--disable-e10s'], 16:44:26 INFO - 'tests': ['tp5o']}, 16:44:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:44:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:44:26 INFO - 'talos_options': ['--disable-e10s', 16:44:26 INFO - '--xperf_path', 16:44:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:26 INFO - 'tests': ['tp5n']}, 16:44:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:44:26 INFO - 'talos_options': ['--xperf_path', 16:44:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:26 INFO - 'tests': ['tp5n']}}, 16:44:26 INFO - 'talos.zip': {'path': '', 16:44:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:44:26 INFO - Running post-action listener: _resource_record_post_action 16:44:26 INFO - [mozharness: 2017-05-31 23:44:26.927651Z] Finished populate-webroot step (success) 16:44:26 INFO - [mozharness: 2017-05-31 23:44:26.927741Z] Running create-virtualenv step. 16:44:26 INFO - Running pre-action listener: _resource_record_pre_action 16:44:26 INFO - Running main action method: create_virtualenv 16:44:26 INFO - Creating virtualenv /builds/slave/test/build/venv 16:44:26 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 16:44:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:44:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:44:27 INFO - Using real prefix '/usr' 16:44:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:44:27 INFO - Installing distribute.............................................................................................................................................................................................done. 16:44:29 INFO - Installing pip.................done. 16:44:29 INFO - Return code: 0 16:44:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:44:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:44:29 INFO - Reading from file tmpfile_stdout 16:44:29 INFO - Output received: 16:44:29 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) 16:44:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:29 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 0x13331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ce400>, '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 0x15cf3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cf840>, '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': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:44:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:44:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:44:29 INFO - Using env: {'DISPLAY': ':0', 16:44:29 INFO - 'HOME': '/home/cltbld', 16:44:29 INFO - 'LANG': 'en_US.UTF-8', 16:44:29 INFO - 'LANGUAGE': 'en_US:en', 16:44:29 INFO - 'LOGNAME': 'cltbld', 16:44:29 INFO - 'MAIL': '/var/mail/cltbld', 16:44:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:29 INFO - 'MOZ_NO_REMOTE': '1', 16:44:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:29 INFO - 'NO_EM_RESTART': '1', 16:44:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:29 INFO - 'PWD': '/builds/slave/test', 16:44:29 INFO - 'SHELL': '/bin/bash', 16:44:29 INFO - 'SHLVL': '1', 16:44:29 INFO - 'TERM': 'linux', 16:44:29 INFO - 'TMOUT': '86400', 16:44:29 INFO - 'USER': 'cltbld', 16:44:29 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 16:44:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:29 INFO - '_': '/tools/buildbot/bin/python'} 16:44:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:30 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 16:44:30 INFO - Cleaning up... 16:44:30 INFO - Return code: 0 16:44:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:30 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 0x13331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ce400>, '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 0x15cf3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cf840>, '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': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:44:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:44:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:44:30 INFO - Using env: {'DISPLAY': ':0', 16:44:30 INFO - 'HOME': '/home/cltbld', 16:44:30 INFO - 'LANG': 'en_US.UTF-8', 16:44:30 INFO - 'LANGUAGE': 'en_US:en', 16:44:30 INFO - 'LOGNAME': 'cltbld', 16:44:30 INFO - 'MAIL': '/var/mail/cltbld', 16:44:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:30 INFO - 'MOZ_NO_REMOTE': '1', 16:44:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:30 INFO - 'NO_EM_RESTART': '1', 16:44:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:30 INFO - 'PWD': '/builds/slave/test', 16:44:30 INFO - 'SHELL': '/bin/bash', 16:44:30 INFO - 'SHLVL': '1', 16:44:30 INFO - 'TERM': 'linux', 16:44:30 INFO - 'TMOUT': '86400', 16:44:30 INFO - 'USER': 'cltbld', 16:44:30 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 16:44:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:30 INFO - '_': '/tools/buildbot/bin/python'} 16:44:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:30 INFO - Downloading/unpacking psutil>=3.1.1 16:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:44:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:44:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:44:31 INFO - Installing collected packages: psutil 16:44:31 INFO - Running setup.py install for psutil 16:44:31 INFO - building 'psutil._psutil_linux' extension 16:44:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:44:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:44:31 INFO - building 'psutil._psutil_posix' extension 16:44:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:44:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:44:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:44:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:44:31 INFO - Successfully installed psutil 16:44:31 INFO - Cleaning up... 16:44:31 INFO - Return code: 0 16:44:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ce400>, '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 0x15cf3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cf840>, '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': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:44:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:44:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:44:31 INFO - Using env: {'DISPLAY': ':0', 16:44:31 INFO - 'HOME': '/home/cltbld', 16:44:31 INFO - 'LANG': 'en_US.UTF-8', 16:44:31 INFO - 'LANGUAGE': 'en_US:en', 16:44:31 INFO - 'LOGNAME': 'cltbld', 16:44:31 INFO - 'MAIL': '/var/mail/cltbld', 16:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:31 INFO - 'MOZ_NO_REMOTE': '1', 16:44:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:31 INFO - 'NO_EM_RESTART': '1', 16:44:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:31 INFO - 'PWD': '/builds/slave/test', 16:44:31 INFO - 'SHELL': '/bin/bash', 16:44:31 INFO - 'SHLVL': '1', 16:44:31 INFO - 'TERM': 'linux', 16:44:31 INFO - 'TMOUT': '86400', 16:44:31 INFO - 'USER': 'cltbld', 16:44:31 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 16:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:31 INFO - '_': '/tools/buildbot/bin/python'} 16:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:44:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:44:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:44:33 INFO - Installing collected packages: mozsystemmonitor 16:44:33 INFO - Running setup.py install for mozsystemmonitor 16:44:33 INFO - Successfully installed mozsystemmonitor 16:44:33 INFO - Cleaning up... 16:44:33 INFO - Return code: 0 16:44:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:44:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ce400>, '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 0x15cf3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cf840>, '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': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:44:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:44:33 INFO - Using env: {'DISPLAY': ':0', 16:44:33 INFO - 'HOME': '/home/cltbld', 16:44:33 INFO - 'LANG': 'en_US.UTF-8', 16:44:33 INFO - 'LANGUAGE': 'en_US:en', 16:44:33 INFO - 'LOGNAME': 'cltbld', 16:44:33 INFO - 'MAIL': '/var/mail/cltbld', 16:44:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:33 INFO - 'MOZ_NO_REMOTE': '1', 16:44:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:33 INFO - 'NO_EM_RESTART': '1', 16:44:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:33 INFO - 'PWD': '/builds/slave/test', 16:44:33 INFO - 'SHELL': '/bin/bash', 16:44:33 INFO - 'SHLVL': '1', 16:44:33 INFO - 'TERM': 'linux', 16:44:33 INFO - 'TMOUT': '86400', 16:44:33 INFO - 'USER': 'cltbld', 16:44:33 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 16:44:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:33 INFO - '_': '/tools/buildbot/bin/python'} 16:44:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:33 INFO - Downloading/unpacking jsonschema==2.5.1 16:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:44:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:34 INFO - Downloading functools32-3.2.3-2.tar.gz 16:44:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:44:34 INFO - warning: no files found matching '*.txt' 16:44:34 INFO - no previously-included directories found matching 'build' 16:44:34 INFO - no previously-included directories found matching 'dist' 16:44:34 INFO - no previously-included directories found matching '.git*' 16:44:34 INFO - Installing collected packages: jsonschema, functools32 16:44:34 INFO - Running setup.py install for functools32 16:44:35 INFO - warning: no files found matching '*.txt' 16:44:35 INFO - no previously-included directories found matching 'build' 16:44:35 INFO - no previously-included directories found matching 'dist' 16:44:35 INFO - no previously-included directories found matching '.git*' 16:44:35 INFO - Successfully installed jsonschema functools32 16:44:35 INFO - Cleaning up... 16:44:35 INFO - Return code: 0 16:44:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:35 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 0x13331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ce400>, '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 0x15cf3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cf840>, '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': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:44:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:44:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:44:35 INFO - Using env: {'DISPLAY': ':0', 16:44:35 INFO - 'HOME': '/home/cltbld', 16:44:35 INFO - 'LANG': 'en_US.UTF-8', 16:44:35 INFO - 'LANGUAGE': 'en_US:en', 16:44:35 INFO - 'LOGNAME': 'cltbld', 16:44:35 INFO - 'MAIL': '/var/mail/cltbld', 16:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:35 INFO - 'MOZ_NO_REMOTE': '1', 16:44:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:35 INFO - 'NO_EM_RESTART': '1', 16:44:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:35 INFO - 'PWD': '/builds/slave/test', 16:44:35 INFO - 'SHELL': '/bin/bash', 16:44:35 INFO - 'SHLVL': '1', 16:44:35 INFO - 'TERM': 'linux', 16:44:35 INFO - 'TMOUT': '86400', 16:44:35 INFO - 'USER': 'cltbld', 16:44:35 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 16:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:35 INFO - '_': '/tools/buildbot/bin/python'} 16:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:44:35 INFO - Cleaning up... 16:44:35 INFO - Return code: 0 16:44:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:35 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 0x13331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ce400>, '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 0x15cf3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cf840>, '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': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:44:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:44:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:44:35 INFO - Using env: {'DISPLAY': ':0', 16:44:35 INFO - 'HOME': '/home/cltbld', 16:44:35 INFO - 'LANG': 'en_US.UTF-8', 16:44:35 INFO - 'LANGUAGE': 'en_US:en', 16:44:35 INFO - 'LOGNAME': 'cltbld', 16:44:35 INFO - 'MAIL': '/var/mail/cltbld', 16:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:35 INFO - 'MOZ_NO_REMOTE': '1', 16:44:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:35 INFO - 'NO_EM_RESTART': '1', 16:44:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:35 INFO - 'PWD': '/builds/slave/test', 16:44:35 INFO - 'SHELL': '/bin/bash', 16:44:35 INFO - 'SHLVL': '1', 16:44:35 INFO - 'TERM': 'linux', 16:44:35 INFO - 'TMOUT': '86400', 16:44:35 INFO - 'USER': 'cltbld', 16:44:35 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 16:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:35 INFO - '_': '/tools/buildbot/bin/python'} 16:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:35 INFO - Downloading/unpacking blobuploader==1.2.4 16:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:36 INFO - Downloading blobuploader-1.2.4.tar.gz 16:44:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:44:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:44:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:37 INFO - Downloading docopt-0.6.1.tar.gz 16:44:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:44:37 INFO - Installing collected packages: blobuploader, requests, docopt 16:44:37 INFO - Running setup.py install for blobuploader 16:44:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:44:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:44:37 INFO - Running setup.py install for requests 16:44:37 INFO - Running setup.py install for docopt 16:44:37 INFO - Successfully installed blobuploader requests docopt 16:44:37 INFO - Cleaning up... 16:44:37 INFO - Return code: 0 16:44:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:37 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 0x13331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ce400>, '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 0x15cf3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cf840>, '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': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:44:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:44:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:44:37 INFO - Using env: {'DISPLAY': ':0', 16:44:37 INFO - 'HOME': '/home/cltbld', 16:44:37 INFO - 'LANG': 'en_US.UTF-8', 16:44:37 INFO - 'LANGUAGE': 'en_US:en', 16:44:37 INFO - 'LOGNAME': 'cltbld', 16:44:37 INFO - 'MAIL': '/var/mail/cltbld', 16:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:37 INFO - 'MOZ_NO_REMOTE': '1', 16:44:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:37 INFO - 'NO_EM_RESTART': '1', 16:44:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:37 INFO - 'PWD': '/builds/slave/test', 16:44:37 INFO - 'SHELL': '/bin/bash', 16:44:37 INFO - 'SHLVL': '1', 16:44:37 INFO - 'TERM': 'linux', 16:44:37 INFO - 'TMOUT': '86400', 16:44:37 INFO - 'USER': 'cltbld', 16:44:37 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 16:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:37 INFO - '_': '/tools/buildbot/bin/python'} 16:44:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:44:37 INFO - Running setup.py (path:/tmp/pip-hyroSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:44:38 INFO - Running setup.py (path:/tmp/pip-qJnHOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:44:38 INFO - Running setup.py (path:/tmp/pip-Z7AYCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:44:38 INFO - Running setup.py (path:/tmp/pip-O886cl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:44:38 INFO - Running setup.py (path:/tmp/pip-8L9Idm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:44:38 INFO - Running setup.py (path:/tmp/pip-Gil8h5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:44:38 INFO - Running setup.py (path:/tmp/pip-F9vpha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:44:38 INFO - Running setup.py (path:/tmp/pip-zwQmJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:44:38 INFO - Running setup.py (path:/tmp/pip-QJu5XA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:44:38 INFO - Running setup.py (path:/tmp/pip-HJHdfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:44:38 INFO - Running setup.py (path:/tmp/pip-AuUJvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:44:38 INFO - Running setup.py (path:/tmp/pip-qjhr4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:44:38 INFO - Running setup.py (path:/tmp/pip-LwS2ps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:44:38 INFO - Running setup.py (path:/tmp/pip-GoFB4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:39 INFO - Running setup.py (path:/tmp/pip-Be1X8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:44:39 INFO - Running setup.py (path:/tmp/pip-kiNppu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:44:39 INFO - Running setup.py (path:/tmp/pip-NfvHd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:44:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:44:39 INFO - Running setup.py install for manifestparser 16:44:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:44:39 INFO - Running setup.py install for mozcrash 16:44:39 INFO - Running setup.py install for mozdebug 16:44:39 INFO - Running setup.py install for mozdevice 16:44:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:44:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:44:39 INFO - Running setup.py install for mozfile 16:44:39 INFO - Running setup.py install for mozhttpd 16:44:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:44:39 INFO - Running setup.py install for mozinfo 16:44:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:44:39 INFO - Running setup.py install for mozInstall 16:44:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:44:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:44:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:44:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:44:40 INFO - Running setup.py install for mozleak 16:44:40 INFO - Running setup.py install for mozlog 16:44:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:44:40 INFO - Running setup.py install for moznetwork 16:44:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:44:40 INFO - Running setup.py install for mozprocess 16:44:40 INFO - Running setup.py install for mozprofile 16:44:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:44:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:44:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:44:40 INFO - Running setup.py install for mozrunner 16:44:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:44:40 INFO - Running setup.py install for mozscreenshot 16:44:40 INFO - Running setup.py install for moztest 16:44:40 INFO - Running setup.py install for mozversion 16:44:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:44:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:44:41 INFO - Cleaning up... 16:44:41 INFO - Return code: 0 16:44:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:41 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 0x13331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ce400>, '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 0x15cf3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cf840>, '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': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:44:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:44:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:44:41 INFO - Using env: {'DISPLAY': ':0', 16:44:41 INFO - 'HOME': '/home/cltbld', 16:44:41 INFO - 'LANG': 'en_US.UTF-8', 16:44:41 INFO - 'LANGUAGE': 'en_US:en', 16:44:41 INFO - 'LOGNAME': 'cltbld', 16:44:41 INFO - 'MAIL': '/var/mail/cltbld', 16:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:41 INFO - 'MOZ_NO_REMOTE': '1', 16:44:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:41 INFO - 'NO_EM_RESTART': '1', 16:44:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:41 INFO - 'PWD': '/builds/slave/test', 16:44:41 INFO - 'SHELL': '/bin/bash', 16:44:41 INFO - 'SHLVL': '1', 16:44:41 INFO - 'TERM': 'linux', 16:44:41 INFO - 'TMOUT': '86400', 16:44:41 INFO - 'USER': 'cltbld', 16:44:41 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 16:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:41 INFO - '_': '/tools/buildbot/bin/python'} 16:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:44:41 INFO - Running setup.py (path:/tmp/pip-dbO6Ky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:44:41 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:44:41 INFO - Running setup.py (path:/tmp/pip-PYSyPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:44:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:44:41 INFO - Running setup.py (path:/tmp/pip-snsRc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:44:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:44:41 INFO - Running setup.py (path:/tmp/pip-I5hqQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:44:41 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)) 16:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:44:41 INFO - Running setup.py (path:/tmp/pip-CsffHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:44:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:44:41 INFO - Running setup.py (path:/tmp/pip-np4ZyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:44:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:44:41 INFO - Running setup.py (path:/tmp/pip-FJfd3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:44:41 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)) 16:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:44:41 INFO - Running setup.py (path:/tmp/pip-zFtmTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:44:41 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)) 16:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:44:41 INFO - Running setup.py (path:/tmp/pip-0TGGYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:44:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:44:41 INFO - Running setup.py (path:/tmp/pip-67iP_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:44:41 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)) 16:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:44:42 INFO - Running setup.py (path:/tmp/pip-JU3WNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:44:42 INFO - Running setup.py (path:/tmp/pip-1uKeVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:44:42 INFO - Running setup.py (path:/tmp/pip-lKj0RO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:44:42 INFO - Running setup.py (path:/tmp/pip-LdLKn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:42 INFO - Running setup.py (path:/tmp/pip-f80QI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:44:42 INFO - Running setup.py (path:/tmp/pip-P7_DeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:44:42 INFO - Running setup.py (path:/tmp/pip-y_D5Q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:44:42 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)) 16:44:42 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)) 16:44:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:43 INFO - Downloading blessings-1.6.tar.gz 16:44:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:44:43 INFO - Installing collected packages: blessings 16:44:43 INFO - Running setup.py install for blessings 16:44:43 INFO - Successfully installed blessings 16:44:43 INFO - Cleaning up... 16:44:43 INFO - Return code: 0 16:44:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:44:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:44:44 INFO - Reading from file tmpfile_stdout 16:44:44 INFO - Current package versions: 16:44:44 INFO - argparse == 1.2.1 16:44:44 INFO - blessings == 1.6 16:44:44 INFO - blobuploader == 1.2.4 16:44:44 INFO - docopt == 0.6.1 16:44:44 INFO - functools32 == 3.2.3-2 16:44:44 INFO - jsonschema == 2.5.1 16:44:44 INFO - manifestparser == 1.1 16:44:44 INFO - mozInstall == 1.12 16:44:44 INFO - mozcrash == 1.0 16:44:44 INFO - mozdebug == 0.1 16:44:44 INFO - mozdevice == 0.49 16:44:44 INFO - mozfile == 1.2 16:44:44 INFO - mozhttpd == 0.7 16:44:44 INFO - mozinfo == 0.9 16:44:44 INFO - mozleak == 0.1 16:44:44 INFO - mozlog == 3.4 16:44:44 INFO - moznetwork == 0.27 16:44:44 INFO - mozprocess == 0.25 16:44:44 INFO - mozprofile == 0.28 16:44:44 INFO - mozrunner == 6.13 16:44:44 INFO - mozscreenshot == 0.1 16:44:44 INFO - mozsystemmonitor == 0.3 16:44:44 INFO - moztest == 0.8 16:44:44 INFO - mozversion == 1.4 16:44:44 INFO - psutil == 3.1.1 16:44:44 INFO - requests == 1.2.3 16:44:44 INFO - wsgiref == 0.1.2 16:44:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:44 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 0x13331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ce400>, '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 0x15cf3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cf840>, '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': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:44:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:44:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:44:44 INFO - Using env: {'DISPLAY': ':0', 16:44:44 INFO - 'HOME': '/home/cltbld', 16:44:44 INFO - 'LANG': 'en_US.UTF-8', 16:44:44 INFO - 'LANGUAGE': 'en_US:en', 16:44:44 INFO - 'LOGNAME': 'cltbld', 16:44:44 INFO - 'MAIL': '/var/mail/cltbld', 16:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:44 INFO - 'MOZ_NO_REMOTE': '1', 16:44:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:44 INFO - 'NO_EM_RESTART': '1', 16:44:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:44 INFO - 'PWD': '/builds/slave/test', 16:44:44 INFO - 'SHELL': '/bin/bash', 16:44:44 INFO - 'SHLVL': '1', 16:44:44 INFO - 'TERM': 'linux', 16:44:44 INFO - 'TMOUT': '86400', 16:44:44 INFO - 'USER': 'cltbld', 16:44:44 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 16:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:44 INFO - '_': '/tools/buildbot/bin/python'} 16:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:44:44 INFO - Cleaning up... 16:44:44 INFO - Return code: 0 16:44:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:44 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 0x13331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ce400>, '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 0x15cf3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cf840>, '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': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:44:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:44:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:44:44 INFO - Using env: {'DISPLAY': ':0', 16:44:44 INFO - 'HOME': '/home/cltbld', 16:44:44 INFO - 'LANG': 'en_US.UTF-8', 16:44:44 INFO - 'LANGUAGE': 'en_US:en', 16:44:44 INFO - 'LOGNAME': 'cltbld', 16:44:44 INFO - 'MAIL': '/var/mail/cltbld', 16:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:44 INFO - 'MOZ_NO_REMOTE': '1', 16:44:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:44 INFO - 'NO_EM_RESTART': '1', 16:44:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:44 INFO - 'PWD': '/builds/slave/test', 16:44:44 INFO - 'SHELL': '/bin/bash', 16:44:44 INFO - 'SHLVL': '1', 16:44:44 INFO - 'TERM': 'linux', 16:44:44 INFO - 'TMOUT': '86400', 16:44:44 INFO - 'USER': 'cltbld', 16:44:44 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 16:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:44 INFO - '_': '/tools/buildbot/bin/python'} 16:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:44:44 INFO - Cleaning up... 16:44:44 INFO - Return code: 0 16:44:44 INFO - Running post-action listener: _resource_record_post_action 16:44:44 INFO - Running post-action listener: _start_resource_monitoring 16:44:44 INFO - Starting resource monitoring. 16:44:44 INFO - [mozharness: 2017-05-31 23:44:44.530352Z] Finished create-virtualenv step (success) 16:44:44 INFO - [mozharness: 2017-05-31 23:44:44.530877Z] Running install step. 16:44:44 INFO - Running pre-action listener: _resource_record_pre_action 16:44:44 INFO - Running main action method: install 16:44:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:44:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:44:44 INFO - Reading from file tmpfile_stdout 16:44:44 INFO - Detecting whether we're running mozinstall >=1.0... 16:44:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:44:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:44:44 INFO - Reading from file tmpfile_stdout 16:44:44 INFO - Output received: 16:44:44 INFO - Usage: mozinstall [options] installer 16:44:44 INFO - Options: 16:44:44 INFO - -h, --help show this help message and exit 16:44:44 INFO - -d DEST, --destination=DEST 16:44:44 INFO - Directory to install application into. [default: 16:44:44 INFO - "/builds/slave/test"] 16:44:44 INFO - --app=APP Application being installed. [default: firefox] 16:44:44 INFO - mkdir: /builds/slave/test/build/application 16:44:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:44:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:44:58 INFO - Reading from file tmpfile_stdout 16:44:58 INFO - Output received: 16:44:58 INFO - /builds/slave/test/build/application/firefox/firefox 16:44:58 INFO - Running post-action listener: _resource_record_post_action 16:44:58 INFO - [mozharness: 2017-05-31 23:44:58.910043Z] Finished install step (success) 16:44:58 INFO - [mozharness: 2017-05-31 23:44:58.910288Z] Running run-tests step. 16:44:58 INFO - Running pre-action listener: _resource_record_pre_action 16:44:58 INFO - Running main action method: run_tests 16:44:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:44:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:44:58 INFO - Python 2.7.3 16:44:58 INFO - Return code: 0 16:44:58 INFO - grabbing minidump binary from tooltool 16:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:58 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 0x15ce400>, '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 0x15cf3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cf840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:44:58 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 16:44:58 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 16:44:58 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 16:44:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:44:58 INFO - Return code: 0 16:44:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:44:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:44:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:44:58 INFO - ENV: RUST_BACKTRACE is now 1 16:44:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:44:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:44:58 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/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 16:44:58 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/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-007 --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 16:44:58 INFO - Using env: {'DISPLAY': ':0', 16:44:58 INFO - 'HOME': '/home/cltbld', 16:44:58 INFO - 'LANG': 'en_US.UTF-8', 16:44:58 INFO - 'LANGUAGE': 'en_US:en', 16:44:58 INFO - 'LOGNAME': 'cltbld', 16:44:58 INFO - 'MAIL': '/var/mail/cltbld', 16:44:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:44:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:44:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:58 INFO - 'MOZ_NO_REMOTE': '1', 16:44:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:44:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:58 INFO - 'NO_EM_RESTART': '1', 16:44:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:58 INFO - 'PWD': '/builds/slave/test', 16:44:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:44:58 INFO - 'RUST_BACKTRACE': '1', 16:44:58 INFO - 'SHELL': '/bin/bash', 16:44:58 INFO - 'SHLVL': '1', 16:44:58 INFO - 'TERM': 'linux', 16:44:58 INFO - 'TMOUT': '86400', 16:44:58 INFO - 'USER': 'cltbld', 16:44:58 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967', 16:44:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:58 INFO - '_': '/tools/buildbot/bin/python'} 16:44:58 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/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 16:44:59 INFO - mozversion application_buildid: 20170531224509 16:44:59 INFO - mozversion application_changeset: 10cfa295a989879affa59adeb70f7e0847d19e7f 16:44:59 INFO - mozversion application_display_name: Firefox 16:44:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:44:59 INFO - mozversion application_name: Firefox 16:44:59 INFO - mozversion application_remotingname: firefox 16:44:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:44:59 INFO - mozversion application_vendor: Mozilla 16:44:59 INFO - mozversion application_version: 54.0 16:44:59 INFO - mozversion platform_buildid: 20170531224509 16:44:59 INFO - mozversion platform_changeset: 10cfa295a989879affa59adeb70f7e0847d19e7f 16:44:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:44:59 INFO - mozversion platform_version: 54.0 16:44:59 INFO - using testdate: 1496274299 16:44:59 INFO - actual date: 1496274299 16:44:59 INFO - starting webserver on 'localhost:39260' 16:44:59 INFO - SUITE-START | Running 2 tests 16:44:59 INFO - TEST-START | dromaeo_css 16:44:59 INFO - operating with platform_type : linux_ 16:44:59 INFO - Initialising browser for dromaeo_css test... 16:44:59 INFO - TEST-INFO | started process 1071 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwQqyhw/profile http://localhost:39260/getInfo.html) 16:45:02 INFO - PROCESS | 1071 | __metrics Screen width/height:1600/1200 16:45:02 INFO - PROCESS | 1071 | colorDepth:24 16:45:02 INFO - PROCESS | 1071 | Browser inner width/height: 1024/697 16:45:02 INFO - PROCESS | 1071 | __metrics 16:45:03 INFO - PROCESS | 1071 | 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" 16:45:03 INFO - PROCESS | 1071 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:45:04 INFO - TEST-INFO | 1071: exit 0 16:45:04 INFO - Browser initialized. 16:45:04 INFO - Running cycle 1/1 for dromaeo_css test... 16:45:04 INFO - TEST-INFO | started process 1226 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwQqyhw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:45:16 INFO - PROCESS | 1226 | RSS: Main: 141709312 16:45:16 INFO - PROCESS | 1226 | 16:48:17 INFO - PROCESS | 1226 | Cycle 1(1): loaded http://localhost:39260/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39260/tests/dromaeo/cssquery-ext.html) 16:48:19 INFO - PROCESS | 1226 | RSS: Main: 282488832 16:48:19 INFO - PROCESS | 1226 | 16:51:21 INFO - PROCESS | 1226 | Cycle 1(1): loaded http://localhost:39260/tests/dromaeo/cssquery-ext.html (next: http://localhost:39260/tests/dromaeo/cssquery-jquery.html) 16:51:21 INFO - PROCESS | 1226 | RSS: Main: 227512320 16:51:21 INFO - PROCESS | 1226 | 16:54:23 INFO - PROCESS | 1226 | Cycle 1(1): loaded http://localhost:39260/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39260/tests/dromaeo/cssquery-mootools.html) 16:54:24 INFO - PROCESS | 1226 | RSS: Main: 205578240 16:54:24 INFO - PROCESS | 1226 | 16:57:26 INFO - PROCESS | 1226 | Cycle 1(1): loaded http://localhost:39260/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39260/tests/dromaeo/cssquery-prototype.html) 16:57:26 INFO - PROCESS | 1226 | RSS: Main: 214601728 16:57:26 INFO - PROCESS | 1226 | 17:00:29 INFO - PROCESS | 1226 | Cycle 1(1): loaded http://localhost:39260/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39260/tests/dromaeo/cssquery-yui.html) 17:00:30 INFO - PROCESS | 1226 | RSS: Main: 174469120 17:00:30 INFO - PROCESS | 1226 | 17:03:32 INFO - PROCESS | 1226 | Cycle 1(1): loaded http://localhost:39260/tests/dromaeo/cssquery-yui.html (next: http://localhost:39260/tests/dromaeo/cssquery-dojo.html) 17:03:32 INFO - PROCESS | 1226 | RSS: Main: 264925184 17:03:32 INFO - PROCESS | 1226 | 17:03:32 INFO - PROCESS | 1226 | __start_tp_report 17:03:32 INFO - PROCESS | 1226 | _x_x_mozilla_page_load 17:03:32 INFO - PROCESS | 1226 | _x_x_mozilla_page_load_details 17:03:32 INFO - PROCESS | 1226 | |i|pagename|runs| 17:03:32 INFO - PROCESS | 1226 | |0;dojo.html;3424.7431442641805;3468.7225021998233;3487.8604855805784;3512.5960514540834;3524.08373822806;5743.712814359281;5818.301803783546;5826.854328641786;5834.620749651275;5852.356240813507;5734.827955161341;5778.335491418488;5791.044477661181;5794.449527294911;5802.187693722872;5142.228665700138;5171.793128274864;5185.092608793465;5190.662606930538;5220.3996540397775;5081.060003899291;5104.055749686301;5121.948780512184;5209.453007434222;5225.8954820903555;5239.476052394749;5245.79016839326;5252.658577192489;5278.709670968095;5278.841634750964;5240.371155461331;5248.265242866002;5262.500062494057;5262.736863156846;5277.8416647500635;4993.600511959071;5072.213806859942;5179.067767801795;5188.351456067991;5239.13554263546;5654.773809047633;5671.149327600851;5707.857303567403;5732.512736417368;5740.684262365568;6214.285357183891;6249.937500624981;6262.467690246334;6268.216472940882;6270.811875643738;6047.12316714074;6303.338149494283;6319;6324.494040476751;6329.651869147195;5757.942420575782;5761.193432919395;5781.739821708034;5805.506531944832;5813.912791308134;6344.619322840645;6374.171357723466;6375.585586936834;6382;6383.936160638427;6102.084687296852;6163.876722465526;6167.167432396569;6167.4140956609945;6177.166082578884;6044.546659000593;6086.269647642312;6129.049997250433;6136.54971344134;6137.039921714347;6143.938560614426;6156.846078847975;6183.907241391383;6209.65846878417;6231.906521402183;5936.5250779937505;5992.700364981733;6074.969625151846;6100.877982440326;6142.324344322121;4702.623790096784;4762.618990480753;4775.498572649891;4800;4820.638452116035;4134;4161.667066634662;4267.210566045292;4273.551277115858;4279.7218180818145;5769;5851.6781577012825;5857.765689372377;5862.824115276549;5863.442972917651;6228.532860035515;6276.560640755103;6277.340879207709;6280.654563999024;6280.842978925563;5744.655320680772;5925.229720136441;5931.555133364928;5938.198343223696;5970.044792833126;5262.684238945599;5286.81496147633;5319.361676598755;5320.494553017457;5330.786768529293;6508.121403610452;6533.771318003847;6536.346365363425;6537;6543.312952139957;6234.9688251558455;6243.500519958302;6283.434490895841;6290.0879372491645;6306.65313407767;6215.098810672337;6245.937540624535;6371.203599550056;6426.871462570629;6520.771772987922;6156.938430615636;6162.383761623802;6180.289266734383;6180.3201647817805;6188.597741146811;6278.277998030102;6278.466330361788;6284.214473190851;6284.74861005573;6293.024581189923;5842.591018628656;5951.672658003678;6004.0693692477735;6020;6096.725647345968;6110.175126358063;6119.746288422617;6128.044828652092;6133.76314253068;6136.549713441251;6076.969615152073;6102.938970610237;6108.450239478469;6112.144299798121;6128.356522565245;5898.35118136996;5932.139839723302;5970.76116955317;5999.730012149376;6030.7889223876955;5990.161377407079;5991.281046274475;5996.67018313997;6019.307779605401;6029.728662210299 17:03:32 INFO - PROCESS | 1226 | |1;ext.html;772.3743767548286;823.9958800205998;848.1560846957278;911.2937473458073;926.6108234541492;30755.23111922203;44743;45573.77213113938;45612.54387456124;45702.08595828081;41545.37681934774;41812.58187418125;41952.370714439305;46549.53450465494;46605.30092048622;7635.770926872203;7815.921840781604;9203.125703058215;9342;9344;10725.892741072606;10963.83554246687;10969.451527423636;10973.451327433637;11292.887071129308;11115.277506962022;11128.276662016984;11139.387333696643;11139.498722557464;11148.888511114907;10994.560217591286;11011.944940275327;11020.283681560713;11048.226624136314;11060.612878549213;10843.349399036004;10896.782064358747;10900.672979810619;10903.182261330352;10906.34561926287;43318.78340608309;43676.781616092034;43730.34404483935;43810;43860.561394386285;4264.125854199912;4290.797460406035;4330.350447432878;4439.245328294165;4449.28811390176;30231.546526801896;30535.69464305373;30679.846600767076;30756.077317680283;32279.51580726291;4347.2826983547675;4373.582681491716;4399.802008909607;4404.537523560044;4510.616597589209;241391;242012;243111;243614.781926091;243828;53429;53471.19793203105;53550.7322463393;53704.73147634237;53888.19167712487;57082.7145864268;57293;57343.42656573464;57392;57475.71262143746;17085.48743537696;17432.91283543574;17470.213840377215;17475.650486990187;17488.912555437142;12064.939675301568;12247.93876030632;14957.70084598302;15551.30019149141;15553.84446155524;13435.059545831698;13462.663433414244;13466;13521.121127126706;13587.048906576445;5501.587380946445;5517.227588137664;5565.2486914266865;5604.747786349624;5679.545636349082;1011.3224139826334;1016.3952448293254;1019.928604997643;1024.2420608749621;1033.7932413517326;29071.273218169714;29134.708652913625;29135.27161820929;29320.853395732884;29437.55843662347;1072.4262519552003;1074.9623763168252;1088.3796236145479;1099.087757161554;1101.6089288302696;14757.92621036888;15607.609809754847;15717.764233536505;15722.68554628901;15810.36758529669;326651;326856;327307;327514;327539;58597.70701146467;58628.4137158623;58670.70664646735;58766;58774;1467.6917847251982;1475.8450362711976;1514.954551363461;1515.3105337071606;1525.85504377084;27697.169084927486;28923;29030.12909612715;29128.513416673268;29563.704362956094;38130.809345953094;38270.23459530738;38285;38288.617113828506;38401.23197535978;284170;284901;285541;286720;286880;24977.750222497543;25735.613965790155;27945.860270698515;28917.277068072624;30171.698283017766;37827.62172378241;37899.621003790715;37914.24171516609;38032.80983595175;38338.6166138335;30549;30616.84691576528;30629.387412252076;30711.2322191947;30961.225969350948;30045.399092018473;30138.3972320548;30180.84909575438;30392.696073038987;30852.845735771178;33647.663523364456;34249.65750342465;34551.4817277736;34714;34716.826415867756;28755.568666470437;28992;28994.850465439034;29057;29304.70695293105;23665.881670592225;23680;24017.879910600335;24153.15463958753;24412.87793561092 17:03:32 INFO - PROCESS | 1226 | |2;jquery.html;457.94443807024817;458.8371128249469;479.9640026997976;481.2564587711985;485.8639580917343;20523.38429847105;21741.23905663302;21751.6737248941;22171.44571385714;22344.776552234474;22485.437864053405;22709;22757.658635120486;22894.198703045357;23244.65133023002;6733.192016957972;6779.72881084756;6801.69392377343;6832.965835170818;6856.314368563154;6794.76218332356;6917.412019978308;6918.550294230884;7216.494845360827;7531.585762783045;7683.078030636331;7685.231476852326;7688.846223075535;7694.076710794685;7696.576688282143;7435.851282974338;7595.848083038336;7617.200193979663;7631.694732210705;7634.6182690865235;7255.23819998903;7403.592802395866;7472.327490525824;7509.962450187769;7515.173330933593;25481;25491.617625735627;25631;26493.867530662417;26584.20247392562;3554.5201397811225;3592.2276710507367;3638.5633723953297;3640.453931910208;3671.963280367189;16130.516084517483;16547.503574892773;16637.50087497377;17044.65910681792;17279.3952211672;2122.1235085775365;2236.932892013242;2282.760310167425;2285.908563657452;2371.703537057868;93528;121414;121861;122569.99999999911;125116.37441812732;17396.739048914278;17835.10824458772;17925.910370448324;18064;18335.54161145982;31719.365612687616;31748.841255793574;31813.36373272568;31823.840880795447;32007.679923200936;10921.508532116073;11025.338479691245;11061.502232399562;11171.16216283782;11309.377984210947;9309.115634014757;9332.860007099898;9419;9421.152096311365;9628.614855405705;8957.955210223907;9236.445813251226;9297.67458138962;9356.95321523388;9390.389624674373;3763.9623603764157;3869.6323849234277;3873.86441474547;3963.365861462152;4113.732607380511;955.9665411710557;957.2243488329188;967.0957654592978;976.1263669016332;996.9750756231152;20304;20815.479613009797;21021.159153633987;21086.789132108788;21121.471963201042;988.4365911430416;992.731962370156;996.1283876607968;1006.3408467453829;1019.50553981319;10971.670849874517;11502.194846360675;11530;11554.884451155549;11569.305841649528;610041;619190;634407;656372;667452;91066;95324;97983;112559;116631.41684291525;1224.9877501225053;1269.7016201192712;1284.4348486665838;1297.8053292006086;1311.8097875808146;18508.537286567942;18772.87362758239;18936.337228196946;19009.714854277474;19395.612087757883;51567.48432515627;51877.22184167165;52136.47863521316;52651;52943;65827;67463;69856.6507167461;77949.61025194838;78512.21487785049;12508.56220032331;12900.354982250663;12980.480780769041;12995.350232488527;13180.340982951006;14074.296285185495;14250.002499824912;14423.206723630301;14476.493322733653;14585.562433127023;12532.37338133108;12554.435050422935;12580.119391642496;12703.682407939876;13094.869051309364;11592.304461732323;11803.822942655695;11879;11884.168108232341;12290;12513.249205047727;12583.119181657195;12642.115051946275;12677.8098328527;12680.048996325497;12236.694082648006;12327.445264962918;12516.687082823002;12623.810642840548;12677.30274834893;12280.324582148067;12417;12469.750604987668;12487.500499979898;12609.306488142873 17:03:32 INFO - PROCESS | 1226 | |3;mootools.html;1577.9763303550442;1587.1746691720314;1612.3469994652166;1619.6679680665466;1629.0470075006128;10950.616728414507;11701.239419437741;11712.648620541397;11718.941405292971;11731.47208375623;11188.272762270468;11544.249623774449;11552.768944621102;11560.59537916173;11904.107191960617;2474.146419485284;2508.4606809535944;2509.29739672891;2513.6229565565127;2515.333436639283;2388.1163969331283;2452.558539462896;2493.6633554470095;2500.3499090236487;2504.962425563609;2400.8199385046105;2421.2978236311446;2423.430493833948;2464.9876750616313;2466.7409921958206;2352.5294941011693;2367.775061369176;2389.880505974703;2392.7009123859516;2392.7726865947707;2347.225415612844;2376.9881150594138;2377.0372998935286;2379.1316169598144;2383.6067048936907;11473.770524589545;11615.419229038514;11687.590934317343;11693.473793679304;11699.88300117005;5910.172575839386;6076.756929722806;6079.300880398767;6081.908771368433;6088.878222435526;3438.82805859706;3513.578370595519;3525.876594319212;3549.804760738158;3589.4436362363613;1321.3195204469705;1325.0856908732944;1327.7676406628802;1375.7592421326228;1392.8537503562184;14759.852401475955;14762.040467369587;16354.918225408916;17472.301107955667;17704.380346687805;4178.91642167155;4726.676530571445;4904.0191961607825;4913.31213630092;4913.852584422473;13017.739645207232;13189.736205276033;13200.537981170613;13206;13207.735845283041;5946.375630558729;6015.729292181863;6020.608660437058;6026.638401695912;6033.276006879115;5563.193336966198;5586;5636.689982051026;5653.717314134359;5697;3892.980535097363;3988.501437320335;4015.417764424142;4042.7574345538774;4061.4517040199785;1010.4947526236881;1011.4639241202175;1021.305512251676;1030.7423144213947;1032.544112800962;945.2485274207022;948.4025064209642;972.7422233108232;987.2052997337213;1100.983485247722;7436.256374362521;7616.23837616234;7762.7671169865;7771.805704857311;7826;1126.69016020595;1150.7295785490403;1163.5055101581793;1168.0888906652824;1193.8089905615234;4936.876578085505;5089.236614507853;5121.359830021247;5185.714785686823;5211.400688920747;13357.265350405822;13954.790678139836;13977.72044559103;14038;14042;13915.30423478822;13946.58160255194;13951.720965580631;13978.930105349407;14018.579442616534;9462.90537094634;9712.17446517054;10506.212034097472;10694.465276736131;10773.83839242412;7697;7854.371650267851;7917.208279172267;7937.2459616336355;8075.313598344206;13218.60344189676;13289.401976911255;13313.866861331262;13364;13679.794803077762;13550.186988780704;13699.589012329647;13714.862851371758;13764.24296663653;13802.240876751875;8688.695895643623;8871.778205544913;8894.73315800527;8896.5996530155;8897.866532002156;10038;10134.138598219233;10190.541425635716;10226.488675566341;10320.845187322346;8797.38418310712;8798.34012449056;9267.212286955684;9413.058694130532;9432.764180895532;9548.952255238679;9613.182879455324;9778.804423911624;9794.951025245113;9927.751806204613;8370.246677799027;8395.24442800151;8458.703945361884;8475.237228649452;8476.49141051539;6153.692315384303;6259.467945224706;6262.749490020348;6314.273858506331;6368.203974503187;6469.288378278292;6581.506387020897;6590.670466476753;6610.669466526559;6704.564203326768 17:03:32 INFO - PROCESS | 1226 | |4;prototype.html;411.21252800886305;423.0650262918949;423.208599918153;437.34180059011186;439.9758013309268;5121.2318152277085;5131.871703207417;5184.248283998818;5312.814051508198;5332.733363331832;5066.290719299301;5144.922826157601;5148.150555158394;5181.766820493078;5247.396549396821;4503.797329120181;4504.130785723921;4550.726956382611;4556.612687921531;4565.863024109282;4391.13376814854;4470;4503.369528266046;4549.454065512143;4557.20248956433;4458.843940462085;4469.240229161034;4471.172833025885;4501.932471012938;4509.436320459942;4454.443194600691;4462.486814016397;4468.218061839165;4478.708883922534;4497.842575509858;4103.600057434552;4327.913441731179;4360.978195109036;4364.541723119059;4375.234333991539;5025.547700706918;5037.2695959086095;5044.9495505045215;5054.396985552444;5085.79656813727;5140.614453915971;5194.298769666122;5197.896042079137;5252.737363131828;5266.183741520071;5382.1366439981;5403.216938410315;5531.363893152258;5541.2873374838955;5543.196236545678;4759.547842954914;4762.57136857681;4829.468758436569;4839.975800121012;4857.514248575149;5720.427957204288;5747.195392644991;5749.9425005750245;5750.7987220447085;5786.276715410574;4475.731456112579;4721;4760.714357138583;5049.772760225798;5085.237214417867;4802.375691160127;4804.807807687654;4813.7593120343845;4837.903241935212;4848.684835485682;5140.768665409991;5141.53726164647;5142.948570514247;5155.74221288934;5162.870928226749;5203.401608814959;5437.4018857926385;5487.204355368449;5538.981255215836;5539.944600553943;1569.2624466500727;1613.491750098715;1639.8606118479825;1659.7676325314465;1671.6740235654174;1240.2186622427812;1275.910686251972;1278.846538415396;1280.8527019392889;1284.5825106840314;5282.8943421132135;5294.92057619136;5300.628955973122;5307.8142265021315;5308.23030660552;5534.778608855681;5602.327720673546;5612.691301978431;5639.746211420496;5644.6330988485615;5227.3465816772905;5294.2852714883;5319.4414586467865;5373.408925018245;5425.755840987165;2956.822590644525;2971.747401470856;2972.464956307885;2973.078345712836;2980;5541.556675466034;5797.855053623609;5804.767809287666;5820.621659592113;5852.239208902815;5590.552755779528;5687.658740475585;5752.36723960364;5762.798302059407;5783.71081445926;5860.912086318709;5878.29460464747;5885.529157667376;5888.823335299777;5906.5570082244;5436.864078398072;5593.636413633101;5650.378458369494;5660.405657406078;5669.404712505293;5558.499735023705;5570.526505246938;5598.076317407582;5643.379228284967;5666.4333566644655;5636.661800291996;5911.527077833849;5915.260592425947;5930.703464826827;5944.49471794885;4724.740139292372;4756.096341695067;4779.713217206979;4784.928226076681;4804.327394164749;5298.311219541435;5541.889162216814;5587.525060369751;5749.367569567428;5800.419958004166;5439.265699130567;5529.806456773994;5531.368246367251;5537.750801213874;5547.55619550443;5439.700816455133;5477.616566840283;5506.091494903296;5520.889582208253;5576.832695019156;5266.920996185137;5440.591955603425;5538.169274608841;5542.168674698827;5553.583481238842;5466.207399927068;5574.303212098488;5575.609707320611;5581.441855814386;5592.468715472024;5180.7409629517615;5428.945710543002;5449.836504904859;5462.3445186577865;5468.617196796186 17:03:32 INFO - PROCESS | 1226 | |5;yui.html;836.3810780022785;880.5949263338864;902.9661038111367;920.5549176620291;951.6859436385992;17670.38153664622;17671.82328176721;17771.28914843406;17825;17847.64304713905;16843.74734378985;17549.385771498;17667.11664416777;17680.46958591241;17702.822971770278;867.2758246863876;867.405827008498;885.7652722028636;888.9066648001964;890.3100097424483;875.120503893586;876.51791514667;876.9166929141721;878.3368556739653;881.2861582118472;936.5504557812254;938.629241449627;938.8403971324857;939.0327962198912;948.6063283737241;940.0693313619536;941.8728471656307;942.4392486470539;946.8390373636498;947.114447991127;688.5593220338988;689.4105539763531;689.6207086102673;690.5925503952692;692.5844493304028;18055.909720451313;18246.817531824778;18336.816631833644;18820;19026.619467610708;16230.594235144215;16372.754408683879;16389.508314750576;16523.091229982347;16755.83244167567;13436.328183590782;13554.390052447565;13697.246651434167;14150.292485375792;14227.07524010936;4089.631933126033;4113.547509773923;4139.172165566899;4179.143275628519;4295.420118284054;52821.47178528204;54490.72754636202;65803;69824.30175698228;70254;12519.248845069325;13060.085793994329;13065.608031758873;13128.474861005452;13165.210087394787;23400.7659923402;23439.296821095395;23675.7632423677;23695.881520592287;23887.761122388554;9489.66786162481;9539.14147726708;9577.23382129428;9585.760355991157;9680.419174849532;4904.166291730415;5060.822871199491;5096.923546146811;5101.642884998088;5215.348081489814;3698.8335524901445;3777.7166712496673;3799.430085487199;3802.8478860845257;3803.8858834235016;636.2174525333866;638.9105525226472;640.1006585747033;646.767163821024;646.7703965092325;731.5537522111508;732.9486935914434;736.9042024536776;745.4297462441236;754.4341743692231;10533.315334503233;10854.782904342026;12241.265524068407;12275;12655.240685558896;692.5533031194951;713.817976416021;748.1508487866203;864.8097418567905;872.2150064941603;4889.584385327287;5631.929933312657;5639.971800141055;5646.604737668324;5704.971475142598;63284.683576581825;77391.61304193443;80940.19059809444;81054.594727026;82769.58615207006;61806.69096654488;70408.6479567609;70846;70891.2910870895;71696.64151679208;50657;58160;61371.69314153401;62180.06729899055;62264;11958.162928594915;12097.57658481949;12367.62897113088;12374.319412432402;12615.810163038914;43048.139037218454;43482.78258608687;43794.56205437905;43907.599545050994;44189.77905110454;51153.74423127861;51686.483135168164;52401.737991311325;52751.20873186828;52761.7361913188;13713.588592342247;13740.10689305232;13785.241811700453;13888.166709997433;13931.303434828016;16292.51122466328;16337.918310408371;16556.83443165601;16801.32794688199;16986.830131698523;15692.68614627724;15842.52472425829;16699;16769;17292.04893730857;14573.708525829212;14999.550013499613;15215.315310811262;15571.610709732347;15902.840971590136;15478.845211547741;15498.922505387402;15610.687786244438;15696.21518924072;15968.600784980468;10436.216772065483;10473.21450891171;10687.946560267148;10792.730181745204;11418.0865530759;13183.802242966554;13197.20816750998;13310.60068197917;13341.19952802796;13352.129292202224 17:03:32 INFO - PROCESS | 1226 | __end_tp_report 17:03:32 INFO - PROCESS | 1226 | __start_cc_report 17:03:32 INFO - PROCESS | 1226 | _x_x_mozilla_cycle_collect,1376 17:03:32 INFO - PROCESS | 1226 | __end_cc_report 17:03:32 INFO - PROCESS | 1226 | __startTimestamp1496275412828__endTimestamp 17:03:32 INFO - PROCESS | 1226 | 17:03:32 INFO - PROCESS | 1226 | ------- Summary: start ------- 17:03:32 INFO - PROCESS | 1226 | Number of tests: 6 17:03:32 INFO - PROCESS | 1226 | 17:03:32 INFO - PROCESS | 1226 | [#0] dojo.html Cycles:165 Average:5759.03 Median:5991.28 stddev:657.42 (11.0%) stddev-sans-first:633.42 17:03:32 INFO - PROCESS | 1226 | Values: 3424.7 3468.7 3487.9 3512.6 3524.1 5743.7 5818.3 5826.9 5834.6 5852.4 5734.8 5778.3 5791.0 5794.4 5802.2 5142.2 5171.8 5185.1 5190.7 5220.4 5081.1 5104.1 5121.9 5209.5 5225.9 5239.5 5245.8 5252.7 5278.7 5278.8 5240.4 5248.3 5262.5 5262.7 5277.8 4993.6 5072.2 5179.1 5188.4 5239.1 5654.8 5671.1 5707.9 5732.5 5740.7 6214.3 6249.9 6262.5 6268.2 6270.8 6047.1 6303.3 6319.0 6324.5 6329.7 5757.9 5761.2 5781.7 5805.5 5813.9 6344.6 6374.2 6375.6 6382.0 6383.9 6102.1 6163.9 6167.2 6167.4 6177.2 6044.5 6086.3 6129.0 6136.5 6137.0 6143.9 6156.8 6183.9 6209.7 6231.9 5936.5 5992.7 6075.0 6100.9 6142.3 4702.6 4762.6 4775.5 4800.0 4820.6 4134.0 4161.7 4267.2 4273.6 4279.7 5769.0 5851.7 5857.8 5862.8 5863.4 6228.5 6276.6 6277.3 6280.7 6280.8 5744.7 5925.2 5931.6 5938.2 5970.0 5262.7 5286.8 5319.4 5320.5 5330.8 6508.1 6533.8 6536.3 6537.0 6543.3 6235.0 6243.5 6283.4 6290.1 6306.7 6215.1 6245.9 6371.2 6426.9 6520.8 6156.9 6162.4 6180.3 6180.3 6188.6 6278.3 6278.5 6284.2 6284.7 6293.0 5842.6 5951.7 6004.1 6020.0 6096.7 6110.2 6119.7 6128.0 6133.8 6136.5 6077.0 6102.9 6108.5 6112.1 6128.4 5898.4 5932.1 5970.8 5999.7 6030.8 5990.2 5991.3 5996.7 6019.3 6029.7 17:03:32 INFO - PROCESS | 1226 | 17:03:32 INFO - PROCESS | 1226 | [#1] ext.html Cycles:180 Average:45229.71 Median:30155.05 stddev:75030.68 (248.8%) stddev-sans-first:75166.91 17:03:32 INFO - PROCESS | 1226 | Values: 772.4 824.0 848.2 911.3 926.6 30755.2 44743.0 45573.8 45612.5 45702.1 41545.4 41812.6 41952.4 46549.5 46605.3 7635.8 7815.9 9203.1 9342.0 9344.0 10725.9 10963.8 10969.5 10973.5 11292.9 11115.3 11128.3 11139.4 11139.5 11148.9 10994.6 11011.9 11020.3 11048.2 11060.6 10843.3 10896.8 10900.7 10903.2 10906.3 43318.8 43676.8 43730.3 43810.0 43860.6 4264.1 4290.8 4330.4 4439.2 4449.3 30231.5 30535.7 30679.8 30756.1 32279.5 4347.3 4373.6 4399.8 4404.5 4510.6 241391.0 242012.0 243111.0 243614.8 243828.0 53429.0 53471.2 53550.7 53704.7 53888.2 57082.7 57293.0 57343.4 57392.0 57475.7 17085.5 17432.9 17470.2 17475.7 17488.9 12064.9 12247.9 14957.7 15551.3 15553.8 13435.1 13462.7 13466.0 13521.1 13587.0 5501.6 5517.2 5565.2 5604.7 5679.5 1011.3 1016.4 1019.9 1024.2 1033.8 29071.3 29134.7 29135.3 29320.9 29437.6 1072.4 1075.0 1088.4 1099.1 1101.6 14757.9 15607.6 15717.8 15722.7 15810.4 326651.0 326856.0 327307.0 327514.0 327539.0 58597.7 58628.4 58670.7 58766.0 58774.0 1467.7 1475.8 1515.0 1515.3 1525.9 27697.2 28923.0 29030.1 29128.5 29563.7 38130.8 38270.2 38285.0 38288.6 38401.2 284170.0 284901.0 285541.0 286720.0 286880.0 24977.8 25735.6 27945.9 28917.3 30171.7 37827.6 37899.6 37914.2 38032.8 38338.6 30549.0 30616.8 30629.4 30711.2 30961.2 30045.4 30138.4 30180.8 30392.7 30852.8 33647.7 34249.7 34551.5 34714.0 34716.8 28755.6 28992.0 28994.9 29057.0 29304.7 23665.9 23680.0 24017.9 24153.2 24412.9 17:03:32 INFO - PROCESS | 1226 | 17:03:32 INFO - PROCESS | 1226 | [#2] jquery.html Cycles:180 Average:37185.43 Median:23069.43 stddev:105096.85 (455.6%) stddev-sans-first:105355.49 17:03:32 INFO - PROCESS | 1226 | Values: 457.9 458.8 480.0 481.3 485.9 20523.4 21741.2 21751.7 22171.4 22344.8 22485.4 22709.0 22757.7 22894.2 23244.7 6733.2 6779.7 6801.7 6833.0 6856.3 6794.8 6917.4 6918.6 7216.5 7531.6 7683.1 7685.2 7688.8 7694.1 7696.6 7435.9 7595.8 7617.2 7631.7 7634.6 7255.2 7403.6 7472.3 7510.0 7515.2 25481.0 25491.6 25631.0 26493.9 26584.2 3554.5 3592.2 3638.6 3640.5 3672.0 16130.5 16547.5 16637.5 17044.7 17279.4 2122.1 2236.9 2282.8 2285.9 2371.7 93528.0 121414.0 121861.0 122570.0 125116.4 17396.7 17835.1 17925.9 18064.0 18335.5 31719.4 31748.8 31813.4 31823.8 32007.7 10921.5 11025.3 11061.5 11171.2 11309.4 9309.1 9332.9 9419.0 9421.2 9628.6 8958.0 9236.4 9297.7 9357.0 9390.4 3764.0 3869.6 3873.9 3963.4 4113.7 956.0 957.2 967.1 976.1 997.0 20304.0 20815.5 21021.2 21086.8 21121.5 988.4 992.7 996.1 1006.3 1019.5 10971.7 11502.2 11530.0 11554.9 11569.3 610041.0 619190.0 634407.0 656372.0 667452.0 91066.0 95324.0 97983.0 112559.0 116631.4 1225.0 1269.7 1284.4 1297.8 1311.8 18508.5 18772.9 18936.3 19009.7 19395.6 51567.5 51877.2 52136.5 52651.0 52943.0 65827.0 67463.0 69856.7 77949.6 78512.2 12508.6 12900.4 12980.5 12995.4 13180.3 14074.3 14250.0 14423.2 14476.5 14585.6 12532.4 12554.4 12580.1 12703.7 13094.9 11592.3 11803.8 11879.0 11884.2 12290.0 12513.2 12583.1 12642.1 12677.8 12680.0 12236.7 12327.4 12516.7 12623.8 12677.3 12280.3 12417.0 12469.8 12487.5 12609.3 17:03:32 INFO - PROCESS | 1226 | 17:03:32 INFO - PROCESS | 1226 | [#3] mootools.html Cycles:180 Average:7149.45 Median:2470.44 stddev:4465.21 (180.7%) stddev-sans-first:4458.11 17:03:32 INFO - PROCESS | 1226 | Values: 1578.0 1587.2 1612.3 1619.7 1629.0 10950.6 11701.2 11712.6 11718.9 11731.5 11188.3 11544.2 11552.8 11560.6 11904.1 2474.1 2508.5 2509.3 2513.6 2515.3 2388.1 2452.6 2493.7 2500.3 2505.0 2400.8 2421.3 2423.4 2465.0 2466.7 2352.5 2367.8 2389.9 2392.7 2392.8 2347.2 2377.0 2377.0 2379.1 2383.6 11473.8 11615.4 11687.6 11693.5 11699.9 5910.2 6076.8 6079.3 6081.9 6088.9 3438.8 3513.6 3525.9 3549.8 3589.4 1321.3 1325.1 1327.8 1375.8 1392.9 14759.9 14762.0 16354.9 17472.3 17704.4 4178.9 4726.7 4904.0 4913.3 4913.9 13017.7 13189.7 13200.5 13206.0 13207.7 5946.4 6015.7 6020.6 6026.6 6033.3 5563.2 5586.0 5636.7 5653.7 5697.0 3893.0 3988.5 4015.4 4042.8 4061.5 1010.5 1011.5 1021.3 1030.7 1032.5 945.2 948.4 972.7 987.2 1101.0 7436.3 7616.2 7762.8 7771.8 7826.0 1126.7 1150.7 1163.5 1168.1 1193.8 4936.9 5089.2 5121.4 5185.7 5211.4 13357.3 13954.8 13977.7 14038.0 14042.0 13915.3 13946.6 13951.7 13978.9 14018.6 9462.9 9712.2 10506.2 10694.5 10773.8 7697.0 7854.4 7917.2 7937.2 8075.3 13218.6 13289.4 13313.9 13364.0 13679.8 13550.2 13699.6 13714.9 13764.2 13802.2 8688.7 8871.8 8894.7 8896.6 8897.9 10038.0 10134.1 10190.5 10226.5 10320.8 8797.4 8798.3 9267.2 9413.1 9432.8 9549.0 9613.2 9778.8 9795.0 9927.8 8370.2 8395.2 8458.7 8475.2 8476.5 6153.7 6259.5 6262.7 6314.3 6368.2 6469.3 6581.5 6590.7 6610.7 6704.6 17:03:32 INFO - PROCESS | 1226 | 17:03:32 INFO - PROCESS | 1226 | [#4] prototype.html Cycles:180 Average:4832.85 Median:5266.55 stddev:1270.21 (24.1%) stddev-sans-first:1229.65 17:03:32 INFO - PROCESS | 1226 | Values: 411.2 423.1 423.2 437.3 440.0 5121.2 5131.9 5184.2 5312.8 5332.7 5066.3 5144.9 5148.2 5181.8 5247.4 4503.8 4504.1 4550.7 4556.6 4565.9 4391.1 4470.0 4503.4 4549.5 4557.2 4458.8 4469.2 4471.2 4501.9 4509.4 4454.4 4462.5 4468.2 4478.7 4497.8 4103.6 4327.9 4361.0 4364.5 4375.2 5025.5 5037.3 5044.9 5054.4 5085.8 5140.6 5194.3 5197.9 5252.7 5266.2 5382.1 5403.2 5531.4 5541.3 5543.2 4759.5 4762.6 4829.5 4840.0 4857.5 5720.4 5747.2 5749.9 5750.8 5786.3 4475.7 4721.0 4760.7 5049.8 5085.2 4802.4 4804.8 4813.8 4837.9 4848.7 5140.8 5141.5 5142.9 5155.7 5162.9 5203.4 5437.4 5487.2 5539.0 5539.9 1569.3 1613.5 1639.9 1659.8 1671.7 1240.2 1275.9 1278.8 1280.9 1284.6 5282.9 5294.9 5300.6 5307.8 5308.2 5534.8 5602.3 5612.7 5639.7 5644.6 5227.3 5294.3 5319.4 5373.4 5425.8 2956.8 2971.7 2972.5 2973.1 2980.0 5541.6 5797.9 5804.8 5820.6 5852.2 5590.6 5687.7 5752.4 5762.8 5783.7 5860.9 5878.3 5885.5 5888.8 5906.6 5436.9 5593.6 5650.4 5660.4 5669.4 5558.5 5570.5 5598.1 5643.4 5666.4 5636.7 5911.5 5915.3 5930.7 5944.5 4724.7 4756.1 4779.7 4784.9 4804.3 5298.3 5541.9 5587.5 5749.4 5800.4 5439.3 5529.8 5531.4 5537.8 5547.6 5439.7 5477.6 5506.1 5520.9 5576.8 5266.9 5440.6 5538.2 5542.2 5553.6 5466.2 5574.3 5575.6 5581.4 5592.5 5180.7 5428.9 5449.8 5462.3 5468.6 17:03:32 INFO - PROCESS | 1226 | 17:03:32 INFO - PROCESS | 1226 | [#5] yui.html Cycles:180 Average:17931.18 Median:43265.46 stddev:20770.81 (48.0%) stddev-sans-first:20789.40 17:03:32 INFO - PROCESS | 1226 | Values: 836.4 880.6 903.0 920.6 951.7 17670.4 17671.8 17771.3 17825.0 17847.6 16843.7 17549.4 17667.1 17680.5 17702.8 867.3 867.4 885.8 888.9 890.3 875.1 876.5 876.9 878.3 881.3 936.6 938.6 938.8 939.0 948.6 940.1 941.9 942.4 946.8 947.1 688.6 689.4 689.6 690.6 692.6 18055.9 18246.8 18336.8 18820.0 19026.6 16230.6 16372.8 16389.5 16523.1 16755.8 13436.3 13554.4 13697.2 14150.3 14227.1 4089.6 4113.5 4139.2 4179.1 4295.4 52821.5 54490.7 65803.0 69824.3 70254.0 12519.2 13060.1 13065.6 13128.5 13165.2 23400.8 23439.3 23675.8 23695.9 23887.8 9489.7 9539.1 9577.2 9585.8 9680.4 4904.2 5060.8 5096.9 5101.6 5215.3 3698.8 3777.7 3799.4 3802.8 3803.9 636.2 638.9 640.1 646.8 646.8 731.6 732.9 736.9 745.4 754.4 10533.3 10854.8 12241.3 12275.0 12655.2 692.6 713.8 748.2 864.8 872.2 4889.6 5631.9 5640.0 5646.6 5705.0 63284.7 77391.6 80940.2 81054.6 82769.6 61806.7 70408.6 70846.0 70891.3 71696.6 50657.0 58160.0 61371.7 62180.1 62264.0 11958.2 12097.6 12367.6 12374.3 12615.8 43048.1 43482.8 43794.6 43907.6 44189.8 51153.7 51686.5 52401.7 52751.2 52761.7 13713.6 13740.1 13785.2 13888.2 13931.3 16292.5 16337.9 16556.8 16801.3 16986.8 15692.7 15842.5 16699.0 16769.0 17292.0 14573.7 14999.6 15215.3 15571.6 15902.8 15478.8 15498.9 15610.7 15696.2 15968.6 10436.2 10473.2 10687.9 10792.7 11418.1 13183.8 13197.2 13310.6 13341.2 13352.1 17:03:32 INFO - PROCESS | 1226 | -------- Summary: end -------- 17:03:32 INFO - PROCESS | 1226 | 17:03:33 INFO - TEST-INFO | 1226: exit 0 17:03:33 INFO - TEST-OK | dromaeo_css | took 1114413ms 17:03:33 INFO - TEST-START | kraken 17:03:33 INFO - operating with platform_type : linux_ 17:03:33 INFO - Initialising browser for kraken test... 17:03:33 INFO - TEST-INFO | started process 1546 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUC6cSz/profile http://localhost:39260/getInfo.html) 17:03:37 INFO - PROCESS | 1546 | __metrics Screen width/height:1600/1200 17:03:37 INFO - PROCESS | 1546 | colorDepth:24 17:03:37 INFO - PROCESS | 1546 | Browser inner width/height: 1024/697 17:03:37 INFO - PROCESS | 1546 | __metrics 17:03:37 INFO - PROCESS | 1546 | 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" 17:03:37 INFO - PROCESS | 1546 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:03:38 INFO - TEST-INFO | 1546: exit 0 17:03:38 INFO - Browser initialized. 17:03:38 INFO - Running cycle 1/1 for kraken test... 17:03:38 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUC6cSz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:03:50 INFO - PROCESS | 1640 | RSS: Main: 141815808 17:03:50 INFO - PROCESS | 1640 | 17:03:52 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:39260/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39260/tests/kraken/driver.html?testName=audio-beat-detection) 17:03:53 INFO - PROCESS | 1640 | RSS: Main: 184119296 17:03:53 INFO - PROCESS | 1640 | 17:03:56 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:39260/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39260/tests/kraken/driver.html?testName=audio-dft) 17:03:56 INFO - PROCESS | 1640 | RSS: Main: 174350336 17:03:56 INFO - PROCESS | 1640 | 17:03:59 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:39260/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39260/tests/kraken/driver.html?testName=audio-fft) 17:04:00 INFO - PROCESS | 1640 | RSS: Main: 265404416 17:04:00 INFO - PROCESS | 1640 | 17:04:02 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:39260/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39260/tests/kraken/driver.html?testName=audio-oscillator) 17:04:02 INFO - PROCESS | 1640 | RSS: Main: 171712512 17:04:02 INFO - PROCESS | 1640 | 17:04:04 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:39260/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39260/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:04:05 INFO - PROCESS | 1640 | RSS: Main: 174489600 17:04:05 INFO - PROCESS | 1640 | 17:04:08 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:39260/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39260/tests/kraken/driver.html?testName=imaging-darkroom) 17:04:09 INFO - PROCESS | 1640 | RSS: Main: 209645568 17:04:09 INFO - PROCESS | 1640 | 17:04:12 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:39260/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39260/tests/kraken/driver.html?testName=imaging-desaturate) 17:04:13 INFO - PROCESS | 1640 | RSS: Main: 212422656 17:04:13 INFO - PROCESS | 1640 | 17:04:15 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:39260/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39260/tests/kraken/driver.html?testName=json-parse-financial) 17:04:16 INFO - PROCESS | 1640 | RSS: Main: 211390464 17:04:16 INFO - PROCESS | 1640 | 17:04:17 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:39260/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39260/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:04:18 INFO - PROCESS | 1640 | RSS: Main: 173821952 17:04:18 INFO - PROCESS | 1640 | 17:04:20 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:39260/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39260/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:04:20 INFO - PROCESS | 1640 | RSS: Main: 180518912 17:04:20 INFO - PROCESS | 1640 | 17:04:22 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:39260/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39260/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:04:23 INFO - PROCESS | 1640 | RSS: Main: 190521344 17:04:23 INFO - PROCESS | 1640 | 17:04:25 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:39260/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39260/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:04:26 INFO - PROCESS | 1640 | RSS: Main: 198860800 17:04:26 INFO - PROCESS | 1640 | 17:04:29 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:39260/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39260/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:04:29 INFO - PROCESS | 1640 | RSS: Main: 195784704 17:04:29 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:39260/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39260/tests/kraken/driver.html?testName=ai-astar) 17:04:31 INFO - PROCESS | 1640 | RSS: Main: 246644736 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | __start_tp_report 17:04:31 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 17:04:31 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 17:04:31 INFO - PROCESS | 1640 | |i|pagename|runs| 17:04:31 INFO - PROCESS | 1640 | |0;ai-astar;103;111;113;111;114;111;111;112;111;112 17:04:31 INFO - PROCESS | 1640 | |1;audio-beat-detection;147;115;117;153;117;116;118;114;131;115 17:04:31 INFO - PROCESS | 1640 | |2;audio-dft;160;161;159;160;160;160;160;163;159;161 17:04:31 INFO - PROCESS | 1640 | |3;audio-fft;76;75;75;109;78;76;75;75;95;75 17:04:31 INFO - PROCESS | 1640 | |4;audio-oscillator;82;81;103;82;82;83;94;82;82;98 17:04:31 INFO - PROCESS | 1640 | |5;imaging-gaussian-blur;113;113;114;113;113;114;113;113;113;112 17:04:31 INFO - PROCESS | 1640 | |6;imaging-darkroom;146;147;147;147;148;147;147;147;147;147 17:04:31 INFO - PROCESS | 1640 | |7;imaging-desaturate;94;95;94;94;94;94;94;94;94;94 17:04:31 INFO - PROCESS | 1640 | |8;json-parse-financial;53;54;59;53;54;55;54;55;56;54 17:04:31 INFO - PROCESS | 1640 | |9;json-stringify-tinderbox;52;52;52;52;51;53;53;52;53;52 17:04:31 INFO - PROCESS | 1640 | |10;stanford-crypto-aes;80;75;79;75;76;78;75;78;84;75 17:04:31 INFO - PROCESS | 1640 | |11;stanford-crypto-ccm;132;135;120;127;133;119;118;137;158;136 17:04:31 INFO - PROCESS | 1640 | |12;stanford-crypto-pbkdf2;163;166;159;159;156;158;158;158;160;162 17:04:31 INFO - PROCESS | 1640 | |13;stanford-crypto-sha256-iterative;63;60;60;59;60;59;59;59;59;58 17:04:31 INFO - PROCESS | 1640 | __end_tp_report 17:04:31 INFO - PROCESS | 1640 | __start_cc_report 17:04:31 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,1705 17:04:31 INFO - PROCESS | 1640 | __end_cc_report 17:04:31 INFO - PROCESS | 1640 | __startTimestamp1496275471862__endTimestamp 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | ------- Summary: start ------- 17:04:31 INFO - PROCESS | 1640 | Number of tests: 14 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | [#0] ai-astar Cycles:10 Average:110.90 Median:111.50 stddev:2.96 (2.7%) stddev-sans-first:1.09 17:04:31 INFO - PROCESS | 1640 | Values: 103.0 111.0 113.0 111.0 114.0 111.0 111.0 112.0 111.0 112.0 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | [#1] audio-beat-detection Cycles:10 Average:124.30 Median:117.50 stddev:14.45 (12.3%) stddev-sans-first:12.77 17:04:31 INFO - PROCESS | 1640 | Values: 147.0 115.0 117.0 153.0 117.0 116.0 118.0 114.0 131.0 115.0 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:1.16 (0.7%) stddev-sans-first:1.22 17:04:31 INFO - PROCESS | 1640 | Values: 160.0 161.0 159.0 160.0 160.0 160.0 160.0 163.0 159.0 161.0 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | [#3] audio-fft Cycles:10 Average:80.90 Median:75.50 stddev:11.64 (15.4%) stddev-sans-first:12.21 17:04:31 INFO - PROCESS | 1640 | Values: 76.0 75.0 75.0 109.0 78.0 76.0 75.0 75.0 95.0 75.0 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | [#4] audio-oscillator Cycles:10 Average:86.90 Median:82.00 stddev:8.18 (10.0%) stddev-sans-first:8.49 17:04:31 INFO - PROCESS | 1640 | Values: 82.0 81.0 103.0 82.0 82.0 83.0 94.0 82.0 82.0 98.0 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 17:04:31 INFO - PROCESS | 1640 | Values: 113.0 113.0 114.0 113.0 113.0 114.0 113.0 113.0 113.0 112.0 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.47 (0.3%) stddev-sans-first:0.33 17:04:31 INFO - PROCESS | 1640 | Values: 146.0 147.0 147.0 147.0 148.0 147.0 147.0 147.0 147.0 147.0 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 17:04:31 INFO - PROCESS | 1640 | Values: 94.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:54.50 stddev:1.77 (3.2%) stddev-sans-first:1.76 17:04:31 INFO - PROCESS | 1640 | Values: 53.0 54.0 59.0 53.0 54.0 55.0 54.0 55.0 56.0 54.0 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | [#9] json-stringify-tinderbox Cycles:10 Average:52.20 Median:52.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 17:04:31 INFO - PROCESS | 1640 | Values: 52.0 52.0 52.0 52.0 51.0 53.0 53.0 52.0 53.0 52.0 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | [#10] stanford-crypto-aes Cycles:10 Average:77.50 Median:78.00 stddev:2.95 (3.8%) stddev-sans-first:2.99 17:04:31 INFO - PROCESS | 1640 | Values: 80.0 75.0 79.0 75.0 76.0 78.0 75.0 78.0 84.0 75.0 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | [#11] stanford-crypto-ccm Cycles:10 Average:131.50 Median:134.00 stddev:11.83 (8.8%) stddev-sans-first:12.54 17:04:31 INFO - PROCESS | 1640 | Values: 132.0 135.0 120.0 127.0 133.0 119.0 118.0 137.0 158.0 136.0 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.90 Median:159.50 stddev:2.96 (1.9%) stddev-sans-first:2.92 17:04:31 INFO - PROCESS | 1640 | Values: 163.0 166.0 159.0 159.0 156.0 158.0 158.0 158.0 160.0 162.0 17:04:31 INFO - PROCESS | 1640 | 17:04:31 INFO - PROCESS | 1640 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.60 Median:59.50 stddev:1.35 (2.3%) stddev-sans-first:0.67 17:04:31 INFO - PROCESS | 1640 | Values: 63.0 60.0 60.0 59.0 60.0 59.0 59.0 59.0 59.0 58.0 17:04:31 INFO - PROCESS | 1640 | -------- Summary: end -------- 17:04:31 INFO - PROCESS | 1640 | 17:04:32 INFO - TEST-INFO | 1640: exit 0 17:04:32 INFO - TEST-OK | kraken | took 59263ms 17:04:32 INFO - SUITE-END | took 1173s 17:04:33 INFO - Completed test suite (00:19:33) 17:04:33 INFO - javascript benchmark 17:04:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7899.765828681304, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3424.7431442641805, 3468.7225021998233, 3487.8604855805784, 3512.5960514540834, 3524.08373822806, 5743.712814359281, 5818.301803783546, 5826.854328641786, 5834.620749651275, 5852.356240813507, 5734.827955161341, 5778.335491418488, 5791.044477661181, 5794.449527294911, 5802.187693722872, 5142.228665700138, 5171.793128274864, 5185.092608793465, 5190.662606930538, 5220.3996540397775, 5081.060003899291, 5104.055749686301, 5121.948780512184, 5209.453007434222, 5225.8954820903555, 5239.476052394749, 5245.79016839326, 5252.658577192489, 5278.709670968095, 5278.841634750964, 5240.371155461331, 5248.265242866002, 5262.500062494057, 5262.736863156846, 5277.8416647500635, 4993.600511959071, 5072.213806859942, 5179.067767801795, 5188.351456067991, 5239.13554263546, 5654.773809047633, 5671.149327600851, 5707.857303567403, 5732.512736417368, 5740.684262365568, 6214.285357183891, 6249.937500624981, 6262.467690246334, 6268.216472940882, 6270.811875643738, 6047.12316714074, 6303.338149494283, 6319.0, 6324.494040476751, 6329.651869147195, 5757.942420575782, 5761.193432919395, 5781.739821708034, 5805.506531944832, 5813.912791308134, 6344.619322840645, 6374.171357723466, 6375.585586936834, 6382.0, 6383.936160638427, 6102.084687296852, 6163.876722465526, 6167.167432396569, 6167.4140956609945, 6177.166082578884, 6044.546659000593, 6086.269647642312, 6129.049997250433, 6136.54971344134, 6137.039921714347, 6143.938560614426, 6156.846078847975, 6183.907241391383, 6209.65846878417, 6231.906521402183, 5936.5250779937505, 5992.700364981733, 6074.969625151846, 6100.877982440326, 6142.324344322121, 4702.623790096784, 4762.618990480753, 4775.498572649891, 4800.0, 4820.638452116035, 4134.0, 4161.667066634662, 4267.210566045292, 4273.551277115858, 4279.7218180818145, 5769.0, 5851.6781577012825, 5857.765689372377, 5862.824115276549, 5863.442972917651, 6228.532860035515, 6276.560640755103, 6277.340879207709, 6280.654563999024, 6280.842978925563, 5744.655320680772, 5925.229720136441, 5931.555133364928, 5938.198343223696, 5970.044792833126, 5262.684238945599, 5286.81496147633, 5319.361676598755, 5320.494553017457, 5330.786768529293, 6508.121403610452, 6533.771318003847, 6536.346365363425, 6537.0, 6543.312952139957, 6234.9688251558455, 6243.500519958302, 6283.434490895841, 6290.0879372491645, 6306.65313407767, 6215.098810672337, 6245.937540624535, 6371.203599550056, 6426.871462570629, 6520.771772987922, 6156.938430615636, 6162.383761623802, 6180.289266734383, 6180.3201647817805, 6188.597741146811, 6278.277998030102, 6278.466330361788, 6284.214473190851, 6284.74861005573, 6293.024581189923, 5842.591018628656, 5951.672658003678, 6004.0693692477735, 6020.0, 6096.725647345968, 6110.175126358063, 6119.746288422617, 6128.044828652092, 6133.76314253068, 6136.549713441251, 6076.969615152073, 6102.938970610237, 6108.450239478469, 6112.144299798121, 6128.356522565245, 5898.35118136996, 5932.139839723302, 5970.76116955317, 5999.730012149376, 6030.7889223876955, 5990.161377407079, 5991.281046274475, 5996.67018313997, 6019.307779605401, 6029.728662210299], "value": 5715.4625824369805, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [772.3743767548286, 823.9958800205998, 848.1560846957278, 911.2937473458073, 926.6108234541492, 30755.23111922203, 44743.0, 45573.77213113938, 45612.54387456124, 45702.08595828081, 41545.37681934774, 41812.58187418125, 41952.370714439305, 46549.53450465494, 46605.30092048622, 7635.770926872203, 7815.921840781604, 9203.125703058215, 9342.0, 9344.0, 10725.892741072606, 10963.83554246687, 10969.451527423636, 10973.451327433637, 11292.887071129308, 11115.277506962022, 11128.276662016984, 11139.387333696643, 11139.498722557464, 11148.888511114907, 10994.560217591286, 11011.944940275327, 11020.283681560713, 11048.226624136314, 11060.612878549213, 10843.349399036004, 10896.782064358747, 10900.672979810619, 10903.182261330352, 10906.34561926287, 43318.78340608309, 43676.781616092034, 43730.34404483935, 43810.0, 43860.561394386285, 4264.125854199912, 4290.797460406035, 4330.350447432878, 4439.245328294165, 4449.28811390176, 30231.546526801896, 30535.69464305373, 30679.846600767076, 30756.077317680283, 32279.51580726291, 4347.2826983547675, 4373.582681491716, 4399.802008909607, 4404.537523560044, 4510.616597589209, 241391.0, 242012.0, 243111.0, 243614.781926091, 243828.0, 53429.0, 53471.19793203105, 53550.7322463393, 53704.73147634237, 53888.19167712487, 57082.7145864268, 57293.0, 57343.42656573464, 57392.0, 57475.71262143746, 17085.48743537696, 17432.91283543574, 17470.213840377215, 17475.650486990187, 17488.912555437142, 12064.939675301568, 12247.93876030632, 14957.70084598302, 15551.30019149141, 15553.84446155524, 13435.059545831698, 13462.663433414244, 13466.0, 13521.121127126706, 13587.048906576445, 5501.587380946445, 5517.227588137664, 5565.2486914266865, 5604.747786349624, 5679.545636349082, 1011.3224139826334, 1016.3952448293254, 1019.928604997643, 1024.2420608749621, 1033.7932413517326, 29071.273218169714, 29134.708652913625, 29135.27161820929, 29320.853395732884, 29437.55843662347, 1072.4262519552003, 1074.9623763168252, 1088.3796236145479, 1099.087757161554, 1101.6089288302696, 14757.92621036888, 15607.609809754847, 15717.764233536505, 15722.68554628901, 15810.36758529669, 326651.0, 326856.0, 327307.0, 327514.0, 327539.0, 58597.70701146467, 58628.4137158623, 58670.70664646735, 58766.0, 58774.0, 1467.6917847251982, 1475.8450362711976, 1514.954551363461, 1515.3105337071606, 1525.85504377084, 27697.169084927486, 28923.0, 29030.12909612715, 29128.513416673268, 29563.704362956094, 38130.809345953094, 38270.23459530738, 38285.0, 38288.617113828506, 38401.23197535978, 284170.0, 284901.0, 285541.0, 286720.0, 286880.0, 24977.750222497543, 25735.613965790155, 27945.860270698515, 28917.277068072624, 30171.698283017766, 37827.62172378241, 37899.621003790715, 37914.24171516609, 38032.80983595175, 38338.6166138335, 30549.0, 30616.84691576528, 30629.387412252076, 30711.2322191947, 30961.225969350948, 30045.399092018473, 30138.3972320548, 30180.84909575438, 30392.696073038987, 30852.845735771178, 33647.663523364456, 34249.65750342465, 34551.4817277736, 34714.0, 34716.826415867756, 28755.568666470437, 28992.0, 28994.850465439034, 29057.0, 29304.70695293105, 23665.881670592225, 23680.0, 24017.879910600335, 24153.15463958753, 24412.87793561092], "value": 18821.091845680687, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.94443807024817, 458.8371128249469, 479.9640026997976, 481.2564587711985, 485.8639580917343, 20523.38429847105, 21741.23905663302, 21751.6737248941, 22171.44571385714, 22344.776552234474, 22485.437864053405, 22709.0, 22757.658635120486, 22894.198703045357, 23244.65133023002, 6733.192016957972, 6779.72881084756, 6801.69392377343, 6832.965835170818, 6856.314368563154, 6794.76218332356, 6917.412019978308, 6918.550294230884, 7216.494845360827, 7531.585762783045, 7683.078030636331, 7685.231476852326, 7688.846223075535, 7694.076710794685, 7696.576688282143, 7435.851282974338, 7595.848083038336, 7617.200193979663, 7631.694732210705, 7634.6182690865235, 7255.23819998903, 7403.592802395866, 7472.327490525824, 7509.962450187769, 7515.173330933593, 25481.0, 25491.617625735627, 25631.0, 26493.867530662417, 26584.20247392562, 3554.5201397811225, 3592.2276710507367, 3638.5633723953297, 3640.453931910208, 3671.963280367189, 16130.516084517483, 16547.503574892773, 16637.50087497377, 17044.65910681792, 17279.3952211672, 2122.1235085775365, 2236.932892013242, 2282.760310167425, 2285.908563657452, 2371.703537057868, 93528.0, 121414.0, 121861.0, 122569.99999999911, 125116.37441812732, 17396.739048914278, 17835.10824458772, 17925.910370448324, 18064.0, 18335.54161145982, 31719.365612687616, 31748.841255793574, 31813.36373272568, 31823.840880795447, 32007.679923200936, 10921.508532116073, 11025.338479691245, 11061.502232399562, 11171.16216283782, 11309.377984210947, 9309.115634014757, 9332.860007099898, 9419.0, 9421.152096311365, 9628.614855405705, 8957.955210223907, 9236.445813251226, 9297.67458138962, 9356.95321523388, 9390.389624674373, 3763.9623603764157, 3869.6323849234277, 3873.86441474547, 3963.365861462152, 4113.732607380511, 955.9665411710557, 957.2243488329188, 967.0957654592978, 976.1263669016332, 996.9750756231152, 20304.0, 20815.479613009797, 21021.159153633987, 21086.789132108788, 21121.471963201042, 988.4365911430416, 992.731962370156, 996.1283876607968, 1006.3408467453829, 1019.50553981319, 10971.670849874517, 11502.194846360675, 11530.0, 11554.884451155549, 11569.305841649528, 610041.0, 619190.0, 634407.0, 656372.0, 667452.0, 91066.0, 95324.0, 97983.0, 112559.0, 116631.41684291525, 1224.9877501225053, 1269.7016201192712, 1284.4348486665838, 1297.8053292006086, 1311.8097875808146, 18508.537286567942, 18772.87362758239, 18936.337228196946, 19009.714854277474, 19395.612087757883, 51567.48432515627, 51877.22184167165, 52136.47863521316, 52651.0, 52943.0, 65827.0, 67463.0, 69856.6507167461, 77949.61025194838, 78512.21487785049, 12508.56220032331, 12900.354982250663, 12980.480780769041, 12995.350232488527, 13180.340982951006, 14074.296285185495, 14250.002499824912, 14423.206723630301, 14476.493322733653, 14585.562433127023, 12532.37338133108, 12554.435050422935, 12580.119391642496, 12703.682407939876, 13094.869051309364, 11592.304461732323, 11803.822942655695, 11879.0, 11884.168108232341, 12290.0, 12513.249205047727, 12583.119181657195, 12642.115051946275, 12677.8098328527, 12680.048996325497, 12236.694082648006, 12327.445264962918, 12516.687082823002, 12623.810642840548, 12677.30274834893, 12280.324582148067, 12417.0, 12469.750604987668, 12487.500499979898, 12609.306488142873], "value": 11750.054125566925, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1577.9763303550442, 1587.1746691720314, 1612.3469994652166, 1619.6679680665466, 1629.0470075006128, 10950.616728414507, 11701.239419437741, 11712.648620541397, 11718.941405292971, 11731.47208375623, 11188.272762270468, 11544.249623774449, 11552.768944621102, 11560.59537916173, 11904.107191960617, 2474.146419485284, 2508.4606809535944, 2509.29739672891, 2513.6229565565127, 2515.333436639283, 2388.1163969331283, 2452.558539462896, 2493.6633554470095, 2500.3499090236487, 2504.962425563609, 2400.8199385046105, 2421.2978236311446, 2423.430493833948, 2464.9876750616313, 2466.7409921958206, 2352.5294941011693, 2367.775061369176, 2389.880505974703, 2392.7009123859516, 2392.7726865947707, 2347.225415612844, 2376.9881150594138, 2377.0372998935286, 2379.1316169598144, 2383.6067048936907, 11473.770524589545, 11615.419229038514, 11687.590934317343, 11693.473793679304, 11699.88300117005, 5910.172575839386, 6076.756929722806, 6079.300880398767, 6081.908771368433, 6088.878222435526, 3438.82805859706, 3513.578370595519, 3525.876594319212, 3549.804760738158, 3589.4436362363613, 1321.3195204469705, 1325.0856908732944, 1327.7676406628802, 1375.7592421326228, 1392.8537503562184, 14759.852401475955, 14762.040467369587, 16354.918225408916, 17472.301107955667, 17704.380346687805, 4178.91642167155, 4726.676530571445, 4904.0191961607825, 4913.31213630092, 4913.852584422473, 13017.739645207232, 13189.736205276033, 13200.537981170613, 13206.0, 13207.735845283041, 5946.375630558729, 6015.729292181863, 6020.608660437058, 6026.638401695912, 6033.276006879115, 5563.193336966198, 5586.0, 5636.689982051026, 5653.717314134359, 5697.0, 3892.980535097363, 3988.501437320335, 4015.417764424142, 4042.7574345538774, 4061.4517040199785, 1010.4947526236881, 1011.4639241202175, 1021.305512251676, 1030.7423144213947, 1032.544112800962, 945.2485274207022, 948.4025064209642, 972.7422233108232, 987.2052997337213, 1100.983485247722, 7436.256374362521, 7616.23837616234, 7762.7671169865, 7771.805704857311, 7826.0, 1126.69016020595, 1150.7295785490403, 1163.5055101581793, 1168.0888906652824, 1193.8089905615234, 4936.876578085505, 5089.236614507853, 5121.359830021247, 5185.714785686823, 5211.400688920747, 13357.265350405822, 13954.790678139836, 13977.72044559103, 14038.0, 14042.0, 13915.30423478822, 13946.58160255194, 13951.720965580631, 13978.930105349407, 14018.579442616534, 9462.90537094634, 9712.17446517054, 10506.212034097472, 10694.465276736131, 10773.83839242412, 7697.0, 7854.371650267851, 7917.208279172267, 7937.2459616336355, 8075.313598344206, 13218.60344189676, 13289.401976911255, 13313.866861331262, 13364.0, 13679.794803077762, 13550.186988780704, 13699.589012329647, 13714.862851371758, 13764.24296663653, 13802.240876751875, 8688.695895643623, 8871.778205544913, 8894.73315800527, 8896.5996530155, 8897.866532002156, 10038.0, 10134.138598219233, 10190.541425635716, 10226.488675566341, 10320.845187322346, 8797.38418310712, 8798.34012449056, 9267.212286955684, 9413.058694130532, 9432.764180895532, 9548.952255238679, 9613.182879455324, 9778.804423911624, 9794.951025245113, 9927.751806204613, 8370.246677799027, 8395.24442800151, 8458.703945361884, 8475.237228649452, 8476.49141051539, 6153.692315384303, 6259.467945224706, 6262.749490020348, 6314.273858506331, 6368.203974503187, 6469.288378278292, 6581.506387020897, 6590.670466476753, 6610.669466526559, 6704.564203326768], "value": 5442.4700591325945, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.21252800886305, 423.0650262918949, 423.208599918153, 437.34180059011186, 439.9758013309268, 5121.2318152277085, 5131.871703207417, 5184.248283998818, 5312.814051508198, 5332.733363331832, 5066.290719299301, 5144.922826157601, 5148.150555158394, 5181.766820493078, 5247.396549396821, 4503.797329120181, 4504.130785723921, 4550.726956382611, 4556.612687921531, 4565.863024109282, 4391.13376814854, 4470.0, 4503.369528266046, 4549.454065512143, 4557.20248956433, 4458.843940462085, 4469.240229161034, 4471.172833025885, 4501.932471012938, 4509.436320459942, 4454.443194600691, 4462.486814016397, 4468.218061839165, 4478.708883922534, 4497.842575509858, 4103.600057434552, 4327.913441731179, 4360.978195109036, 4364.541723119059, 4375.234333991539, 5025.547700706918, 5037.2695959086095, 5044.9495505045215, 5054.396985552444, 5085.79656813727, 5140.614453915971, 5194.298769666122, 5197.896042079137, 5252.737363131828, 5266.183741520071, 5382.1366439981, 5403.216938410315, 5531.363893152258, 5541.2873374838955, 5543.196236545678, 4759.547842954914, 4762.57136857681, 4829.468758436569, 4839.975800121012, 4857.514248575149, 5720.427957204288, 5747.195392644991, 5749.9425005750245, 5750.7987220447085, 5786.276715410574, 4475.731456112579, 4721.0, 4760.714357138583, 5049.772760225798, 5085.237214417867, 4802.375691160127, 4804.807807687654, 4813.7593120343845, 4837.903241935212, 4848.684835485682, 5140.768665409991, 5141.53726164647, 5142.948570514247, 5155.74221288934, 5162.870928226749, 5203.401608814959, 5437.4018857926385, 5487.204355368449, 5538.981255215836, 5539.944600553943, 1569.2624466500727, 1613.491750098715, 1639.8606118479825, 1659.7676325314465, 1671.6740235654174, 1240.2186622427812, 1275.910686251972, 1278.846538415396, 1280.8527019392889, 1284.5825106840314, 5282.8943421132135, 5294.92057619136, 5300.628955973122, 5307.8142265021315, 5308.23030660552, 5534.778608855681, 5602.327720673546, 5612.691301978431, 5639.746211420496, 5644.6330988485615, 5227.3465816772905, 5294.2852714883, 5319.4414586467865, 5373.408925018245, 5425.755840987165, 2956.822590644525, 2971.747401470856, 2972.464956307885, 2973.078345712836, 2980.0, 5541.556675466034, 5797.855053623609, 5804.767809287666, 5820.621659592113, 5852.239208902815, 5590.552755779528, 5687.658740475585, 5752.36723960364, 5762.798302059407, 5783.71081445926, 5860.912086318709, 5878.29460464747, 5885.529157667376, 5888.823335299777, 5906.5570082244, 5436.864078398072, 5593.636413633101, 5650.378458369494, 5660.405657406078, 5669.404712505293, 5558.499735023705, 5570.526505246938, 5598.076317407582, 5643.379228284967, 5666.4333566644655, 5636.661800291996, 5911.527077833849, 5915.260592425947, 5930.703464826827, 5944.49471794885, 4724.740139292372, 4756.096341695067, 4779.713217206979, 4784.928226076681, 4804.327394164749, 5298.311219541435, 5541.889162216814, 5587.525060369751, 5749.367569567428, 5800.419958004166, 5439.265699130567, 5529.806456773994, 5531.368246367251, 5537.750801213874, 5547.55619550443, 5439.700816455133, 5477.616566840283, 5506.091494903296, 5520.889582208253, 5576.832695019156, 5266.920996185137, 5440.591955603425, 5538.169274608841, 5542.168674698827, 5553.583481238842, 5466.207399927068, 5574.303212098488, 5575.609707320611, 5581.441855814386, 5592.468715472024, 5180.7409629517615, 5428.945710543002, 5449.836504904859, 5462.3445186577865, 5468.617196796186], "value": 4463.118620913529, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [836.3810780022785, 880.5949263338864, 902.9661038111367, 920.5549176620291, 951.6859436385992, 17670.38153664622, 17671.82328176721, 17771.28914843406, 17825.0, 17847.64304713905, 16843.74734378985, 17549.385771498, 17667.11664416777, 17680.46958591241, 17702.822971770278, 867.2758246863876, 867.405827008498, 885.7652722028636, 888.9066648001964, 890.3100097424483, 875.120503893586, 876.51791514667, 876.9166929141721, 878.3368556739653, 881.2861582118472, 936.5504557812254, 938.629241449627, 938.8403971324857, 939.0327962198912, 948.6063283737241, 940.0693313619536, 941.8728471656307, 942.4392486470539, 946.8390373636498, 947.114447991127, 688.5593220338988, 689.4105539763531, 689.6207086102673, 690.5925503952692, 692.5844493304028, 18055.909720451313, 18246.817531824778, 18336.816631833644, 18820.0, 19026.619467610708, 16230.594235144215, 16372.754408683879, 16389.508314750576, 16523.091229982347, 16755.83244167567, 13436.328183590782, 13554.390052447565, 13697.246651434167, 14150.292485375792, 14227.07524010936, 4089.631933126033, 4113.547509773923, 4139.172165566899, 4179.143275628519, 4295.420118284054, 52821.47178528204, 54490.72754636202, 65803.0, 69824.30175698228, 70254.0, 12519.248845069325, 13060.085793994329, 13065.608031758873, 13128.474861005452, 13165.210087394787, 23400.7659923402, 23439.296821095395, 23675.7632423677, 23695.881520592287, 23887.761122388554, 9489.66786162481, 9539.14147726708, 9577.23382129428, 9585.760355991157, 9680.419174849532, 4904.166291730415, 5060.822871199491, 5096.923546146811, 5101.642884998088, 5215.348081489814, 3698.8335524901445, 3777.7166712496673, 3799.430085487199, 3802.8478860845257, 3803.8858834235016, 636.2174525333866, 638.9105525226472, 640.1006585747033, 646.767163821024, 646.7703965092325, 731.5537522111508, 732.9486935914434, 736.9042024536776, 745.4297462441236, 754.4341743692231, 10533.315334503233, 10854.782904342026, 12241.265524068407, 12275.0, 12655.240685558896, 692.5533031194951, 713.817976416021, 748.1508487866203, 864.8097418567905, 872.2150064941603, 4889.584385327287, 5631.929933312657, 5639.971800141055, 5646.604737668324, 5704.971475142598, 63284.683576581825, 77391.61304193443, 80940.19059809444, 81054.594727026, 82769.58615207006, 61806.69096654488, 70408.6479567609, 70846.0, 70891.2910870895, 71696.64151679208, 50657.0, 58160.0, 61371.69314153401, 62180.06729899055, 62264.0, 11958.162928594915, 12097.57658481949, 12367.62897113088, 12374.319412432402, 12615.810163038914, 43048.139037218454, 43482.78258608687, 43794.56205437905, 43907.599545050994, 44189.77905110454, 51153.74423127861, 51686.483135168164, 52401.737991311325, 52751.20873186828, 52761.7361913188, 13713.588592342247, 13740.10689305232, 13785.241811700453, 13888.166709997433, 13931.303434828016, 16292.51122466328, 16337.918310408371, 16556.83443165601, 16801.32794688199, 16986.830131698523, 15692.68614627724, 15842.52472425829, 16699.0, 16769.0, 17292.04893730857, 14573.708525829212, 14999.550013499613, 15215.315310811262, 15571.610709732347, 15902.840971590136, 15478.845211547741, 15498.922505387402, 15610.687786244438, 15696.21518924072, 15968.600784980468, 10436.216772065483, 10473.21450891171, 10687.946560267148, 10792.730181745204, 11418.0865530759, 13183.802242966554, 13197.20816750998, 13310.60068197917, 13341.19952802796, 13352.129292202224], "value": 7915.468620969395, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1452.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 113.0, 111.0, 114.0, 111.0, 111.0, 112.0, 111.0, 112.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 115.0, 117.0, 153.0, 117.0, 116.0, 118.0, 114.0, 131.0, 115.0], "value": 124.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 159.0, 160.0, 160.0, 160.0, 160.0, 163.0, 159.0, 161.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 109.0, 78.0, 76.0, 75.0, 75.0, 95.0, 75.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 103.0, 82.0, 82.0, 83.0, 94.0, 82.0, 82.0, 98.0], "value": 86.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 112.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 59.0, 53.0, 54.0, 55.0, 54.0, 55.0, 56.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 52.0, 51.0, 53.0, 53.0, 52.0, 53.0, 52.0], "value": 52.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 79.0, 75.0, 76.0, 78.0, 75.0, 78.0, 84.0, 75.0], "value": 77.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 135.0, 120.0, 127.0, 133.0, 119.0, 118.0, 137.0, 158.0, 136.0], "value": 131.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 166.0, 159.0, 159.0, 156.0, 158.0, 158.0, 158.0, 160.0, 162.0], "value": 159.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 60.0, 59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 58.0], "value": 59.6, "unit": "score"}]}]} 17:04:33 INFO - Return code: 0 17:04:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:04:33 INFO - # TBPL SUCCESS # 17:04:33 INFO - Running post-action listener: _resource_record_post_action 17:04:33 INFO - [mozharness: 2017-06-01 00:04:33.168695Z] Finished run-tests step (success) 17:04:33 INFO - Running post-run listener: _resource_record_post_run 17:04:33 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 317329408; Read time: 8; Write time: 157420 17:04:33 INFO - TinderboxPrint: CPU usage
13.1% 17:04:33 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 17:04:33 INFO - TinderboxPrint: I/O write bytes / time
317,329,408 / 157,420 17:04:33 INFO - TinderboxPrint: CPU idle
8,242.8 (86.9%) 17:04:33 INFO - TinderboxPrint: CPU user
1,198.3 (12.6%) 17:04:33 INFO - TinderboxPrint: Swap in / out
0 / 0 17:04:33 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 90238976; Read time: 0; Write time: 64284 17:04:33 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 213737472; Read time: 0; Write time: 89880 17:04:33 INFO - Running post-run listener: _upload_blobber_files 17:04:33 INFO - Blob upload gear active. 17:04:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:04:33 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 17:04:33 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'] 17:04:33 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 17:04:34 INFO - (blobuploader) - INFO - Open directory for files ... 17:04:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:04:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:04:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:04:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:04:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:04:35 INFO - (blobuploader) - INFO - Done attempting. 17:04:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:04:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:04:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:04:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:04:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:04:38 INFO - (blobuploader) - INFO - Done attempting. 17:04:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:04:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:04:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:04:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:04:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:04:40 INFO - (blobuploader) - INFO - Done attempting. 17:04:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:04:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:04:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:04:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:04:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:04:40 INFO - (blobuploader) - INFO - Done attempting. 17:04:40 INFO - (blobuploader) - INFO - Iteration through files over. 17:04:40 INFO - Return code: 0 17:04:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:04:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:04:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4142dd511b6da352b434ab046dd51a71dd0655485ed3a790179a036563db4c904ae56f74d5eec7281f0af9b413ed1869d3b03c63928ec572d364088f72d54570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6fc49a20f0e697c514889f029a8fd3084acbaa6a0aacb2182c05d0a37594ec29eb7dd559e661fb4d2a18e1bbc18dfcacc322c4143562a41f1023bb6c891e962b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3453fc91d65d48195edd01336bbe92d745e56cfb8001c894225f2654db97b00cef7ca204e50e577a5962bbe57311c238b9a1dbfd48d08aaff49e2b37e6e0f127", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/08c927bdc4b2eef4f7fab2015ab16bd217e5e339990e79aa53506b64b7d1b346d5665dda20bf9418bc0b5ee27cec8cf3bd2f8c98ec9c11bd97ca6699ff97d189"} 17:04:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:04:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:04:40 INFO - Contents: 17:04:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4142dd511b6da352b434ab046dd51a71dd0655485ed3a790179a036563db4c904ae56f74d5eec7281f0af9b413ed1869d3b03c63928ec572d364088f72d54570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6fc49a20f0e697c514889f029a8fd3084acbaa6a0aacb2182c05d0a37594ec29eb7dd559e661fb4d2a18e1bbc18dfcacc322c4143562a41f1023bb6c891e962b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3453fc91d65d48195edd01336bbe92d745e56cfb8001c894225f2654db97b00cef7ca204e50e577a5962bbe57311c238b9a1dbfd48d08aaff49e2b37e6e0f127", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/08c927bdc4b2eef4f7fab2015ab16bd217e5e339990e79aa53506b64b7d1b346d5665dda20bf9418bc0b5ee27cec8cf3bd2f8c98ec9c11bd97ca6699ff97d189"} 17:04:40 INFO - Running post-run listener: copy_logs_to_upload_dir 17:04:40 INFO - Copying logs to upload dir... 17:04:40 INFO - mkdir: /builds/slave/test/build/upload/logs 17:04:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.379054 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-05-31 17:04:40.448812) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 17:04:40.456954) ========= 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=923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967 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/4142dd511b6da352b434ab046dd51a71dd0655485ed3a790179a036563db4c904ae56f74d5eec7281f0af9b413ed1869d3b03c63928ec572d364088f72d54570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6fc49a20f0e697c514889f029a8fd3084acbaa6a0aacb2182c05d0a37594ec29eb7dd559e661fb4d2a18e1bbc18dfcacc322c4143562a41f1023bb6c891e962b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3453fc91d65d48195edd01336bbe92d745e56cfb8001c894225f2654db97b00cef7ca204e50e577a5962bbe57311c238b9a1dbfd48d08aaff49e2b37e6e0f127", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/08c927bdc4b2eef4f7fab2015ab16bd217e5e339990e79aa53506b64b7d1b346d5665dda20bf9418bc0b5ee27cec8cf3bd2f8c98ec9c11bd97ca6699ff97d189"} build_url:https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011228 build_url: 'https://queue.taskcluster.net/v1/task/NhgHqHj2TS2wkjMBXVFxDw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4142dd511b6da352b434ab046dd51a71dd0655485ed3a790179a036563db4c904ae56f74d5eec7281f0af9b413ed1869d3b03c63928ec572d364088f72d54570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6fc49a20f0e697c514889f029a8fd3084acbaa6a0aacb2182c05d0a37594ec29eb7dd559e661fb4d2a18e1bbc18dfcacc322c4143562a41f1023bb6c891e962b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3453fc91d65d48195edd01336bbe92d745e56cfb8001c894225f2654db97b00cef7ca204e50e577a5962bbe57311c238b9a1dbfd48d08aaff49e2b37e6e0f127", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/08c927bdc4b2eef4f7fab2015ab16bd217e5e339990e79aa53506b64b7d1b346d5665dda20bf9418bc0b5ee27cec8cf3bd2f8c98ec9c11bd97ca6699ff97d189"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 17:04:40.489172) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 17:04:40.489578) ========= 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=923d835db2ba3686ebe126b700000192-1496272224.746010-2031448967 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004599 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 17:04:40.568279) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 17:04:40.571754) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 17:04:40.572156) ========= ========= Total master_lag: 0.23 =========