builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-089 starttime: 1496690787.15 results: success (0) revision: 6f0be3ad8512b63a13cb4ec87ae4efae31411ecf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 12:26:27.149865) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 12:26:27.150345) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 12:26:27.150681) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 12:26:27.199400) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 12:26:27.199696) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-06-05 12:26:27-- 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% 110M=0s 2017-06-05 12:26:27 (110 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130118 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 12:26:27.346569) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 12:26:27.346972) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020741 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 12:26:27.409308) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 12:26:27.409670) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6f0be3ad8512b63a13cb4ec87ae4efae31411ecf --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 6f0be3ad8512b63a13cb4ec87ae4efae31411ecf --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=8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-06-05 12:26:27,683 truncating revision to first 12 chars 2017-06-05 12:26:27,683 Setting DEBUG logging. 2017-06-05 12:26:27,683 attempt 1/10 2017-06-05 12:26:27,683 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6f0be3ad8512?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 12:26:28,222 unpacking tar archive at: mozilla-beta-6f0be3ad8512/testing/mozharness/ program finished with exit code 0 elapsedTime=0.953103 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 12:26:28.375627) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 12:26:28.376020) ========= script_repo_revision: 6f0be3ad8512b63a13cb4ec87ae4efae31411ecf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 12:26:28.376476) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 12:26:28.376766) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 12:26:28.390333) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-06-05 12:26:28.390821) ========= /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=8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:26:28 INFO - MultiFileLogger online at 20170605 12:26:28 in /builds/slave/test 12:26:28 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 12:26:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:26:28 INFO - {'append_to_log': False, 12:26:28 INFO - 'base_work_dir': '/builds/slave/test', 12:26:28 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 12:26:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:26:28 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 12:26:28 INFO - 'buildbot_json_path': 'buildprops.json', 12:26:28 INFO - 'config_files': ('talos/linux_config.py',), 12:26:28 INFO - 'default_actions': ('clobber', 12:26:28 INFO - 'read-buildbot-config', 12:26:28 INFO - 'download-and-extract', 12:26:28 INFO - 'populate-webroot', 12:26:28 INFO - 'create-virtualenv', 12:26:28 INFO - 'install', 12:26:28 INFO - 'run-tests'), 12:26:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:26:28 INFO - 'download_minidump_stackwalk': True, 12:26:28 INFO - 'download_symbols': 'ondemand', 12:26:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:26:28 INFO - 'tooltool.py': '/tools/tooltool.py', 12:26:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:26:28 INFO - '/tools/misc-python/virtualenv.py')}, 12:26:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:26:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:26:28 INFO - 'gecko_profile': False, 12:26:28 INFO - 'gecko_profile_interval': 0, 12:26:28 INFO - 'installer_path': 'installer.exe', 12:26:28 INFO - 'log_level': 'info', 12:26:28 INFO - 'log_name': 'talos', 12:26:28 INFO - 'log_to_console': True, 12:26:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:26:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:26:28 INFO - 'opt_config_files': (), 12:26:28 INFO - 'pip_index': False, 12:26:28 INFO - 'suite': 'dromaeojs', 12:26:28 INFO - 'system_bits': '32', 12:26:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:26:28 INFO - 'title': 'talos-linux64-ix-089', 12:26:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:26:28 INFO - 'use_talos_json': True, 12:26:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:26:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:26:28 INFO - 'work_dir': 'build'} 12:26:28 INFO - [mozharness: 2017-06-05 19:26:28.761558Z] Running clobber step. 12:26:28 INFO - Running pre-action listener: _resource_record_pre_action 12:26:28 INFO - Running main action method: clobber 12:26:28 INFO - rmtree: /builds/slave/test/build 12:26:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:26:29 INFO - Running post-action listener: _resource_record_post_action 12:26:29 INFO - [mozharness: 2017-06-05 19:26:29.790784Z] Finished clobber step (success) 12:26:29 INFO - [mozharness: 2017-06-05 19:26:29.790883Z] Running read-buildbot-config step. 12:26:29 INFO - Running pre-action listener: _resource_record_pre_action 12:26:29 INFO - Running main action method: read_buildbot_config 12:26:29 INFO - Using buildbot properties: 12:26:29 INFO - { 12:26:29 INFO - "project": "", 12:26:29 INFO - "product": "firefox", 12:26:29 INFO - "who": "asasaki@mozilla.com", 12:26:29 INFO - "installer_path": "public/build/target.tar.bz2", 12:26:29 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 12:26:29 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 12:26:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 12:26:29 INFO - "stage_platform": "linux64", 12:26:29 INFO - "basedir": "/builds/slave/test", 12:26:29 INFO - "buildnumber": 69, 12:26:29 INFO - "platform": "ubuntu64_hw", 12:26:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:26:29 INFO - "slavebuilddir": "test", 12:26:29 INFO - "taskId": "VaXoBg3ZRV6TgMtE_ZNYdw", 12:26:29 INFO - "branch": "mozilla-beta", 12:26:29 INFO - "script_repo_revision": "production", 12:26:29 INFO - "revision": "6f0be3ad8512b63a13cb4ec87ae4efae31411ecf", 12:26:29 INFO - "slavename": "talos-linux64-ix-089", 12:26:29 INFO - "repo_path": "releases/mozilla-beta" 12:26:29 INFO - } 12:26:29 INFO - Finding installer, test and symbols from parent task. 12:26:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VaXoBg3ZRV6TgMtE_ZNYdw'}, attempt #1 12:26:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VaXoBg3ZRV6TgMtE_ZNYdw'}, attempt #1 12:26:30 INFO - Task dependencies: Bk19fZP9RwO3kgxz1JkymQ 12:26:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ'}, attempt #1 12:26:31 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.tar.bz2 12:26:31 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/test_packages.json 12:26:31 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.crashreporter-symbols.zip 12:26:31 INFO - Running post-action listener: _resource_record_post_action 12:26:31 INFO - [mozharness: 2017-06-05 19:26:31.255240Z] Finished read-buildbot-config step (success) 12:26:31 INFO - [mozharness: 2017-06-05 19:26:31.255458Z] Running download-and-extract step. 12:26:31 INFO - Running pre-action listener: _resource_record_pre_action 12:26:31 INFO - Running main action method: download_and_extract 12:26:31 INFO - mkdir: /builds/slave/test/build/tests 12:26: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')]} 12:26:31 INFO - trying https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.test_packages.json 12:26:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:26:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:26:31 INFO - Downloaded 986 bytes. 12:26:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:26:31 INFO - Using the following test package requirements: 12:26:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:26:31 INFO - u'common': [u'target.common.tests.zip'], 12:26:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:26:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:26:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:26:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:26:31 INFO - u'mozbase': [u'target.common.tests.zip'], 12:26:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:26:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:26:31 INFO - u'web-platform': [u'target.common.tests.zip', 12:26:31 INFO - u'target.web-platform.tests.zip'], 12:26:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:26:31 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:26:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.common.tests.zip 12:26:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:26:31 INFO - Fetch https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.common.tests.zip into memory 12:26:35 INFO - Expected file size: 59712189 12:26:35 INFO - Obtained file size: 59712189 12:26:45 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:26:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.common.tests.zip 12:26:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:26:45 INFO - Fetch https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.common.tests.zip into memory 12:26:49 INFO - Expected file size: 59712189 12:26:49 INFO - Obtained file size: 59712189 12:26:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.talos.tests.zip 12:26:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:26:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.talos.tests.zip into memory 12:27:00 INFO - Expected file size: 14404461 12:27:00 INFO - Obtained file size: 14404461 12:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:27:00 INFO - trying https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.tar.bz2 12:27:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:27:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:27:03 INFO - Downloaded 59819024 bytes. 12:27:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.tar.bz2 12:27:03 INFO - mkdir: /builds/slave/test/properties 12:27:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:27:03 INFO - Writing to file /builds/slave/test/properties/build_url 12:27:03 INFO - Contents: 12:27:03 INFO - build_url:https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.tar.bz2 12:27:03 INFO - Running post-action listener: _resource_record_post_action 12:27:03 INFO - Running post-action listener: set_extra_try_arguments 12:27:03 INFO - [mozharness: 2017-06-05 19:27:03.742072Z] Finished download-and-extract step (success) 12:27:03 INFO - [mozharness: 2017-06-05 19:27:03.742299Z] Running populate-webroot step. 12:27:03 INFO - Running pre-action listener: _resource_record_pre_action 12:27:03 INFO - Running main action method: populate_webroot 12:27:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:27:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:27:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:27:03 INFO - '--tppagecycles', 12:27:03 INFO - '7'], 12:27:03 INFO - 'tests': ['tsvgm']}}, 12:27:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:27:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:27:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:27:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:27:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:27:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:27:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:27:03 INFO - 'talos_options': ['--disable-e10s'], 12:27:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:27:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:27:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:27:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:27:03 INFO - 'talos_options': ['--disable-e10s'], 12:27:03 INFO - 'tests': ['damp', 'tps']}, 12:27:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:27:03 INFO - 'tests': ['damp', 'tps']}, 12:27:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:27:03 INFO - 'tests': ['dromaeo_dom']}, 12:27:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:27:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:27:03 INFO - 'tests': ['basic_compositor_video']}, 12:27:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:27:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:27:03 INFO - 'tests': ['a11yr', 12:27:03 INFO - 'ts_paint', 12:27:03 INFO - 'tpaint', 12:27:03 INFO - 'sessionrestore', 12:27:03 INFO - 'sessionrestore_no_auto_restore', 12:27:03 INFO - 'tabpaint']}, 12:27:03 INFO - 'other-e10s': {'tests': ['a11yr', 12:27:03 INFO - 'ts_paint', 12:27:03 INFO - 'tpaint', 12:27:03 INFO - 'sessionrestore', 12:27:03 INFO - 'sessionrestore_no_auto_restore', 12:27:03 INFO - 'tabpaint']}, 12:27:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:27:03 INFO - 'ts_paint', 12:27:03 INFO - 'tpaint', 12:27:03 INFO - 'sessionrestore', 12:27:03 INFO - 'sessionrestore_no_auto_restore', 12:27:03 INFO - 'tabpaint']}, 12:27:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:27:03 INFO - 'ts_paint', 12:27:03 INFO - 'tpaint', 12:27:03 INFO - 'sessionrestore', 12:27:03 INFO - 'sessionrestore_no_auto_restore', 12:27:03 INFO - 'tabpaint']}, 12:27:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:27:03 INFO - 'tests': ['a11yr', 12:27:03 INFO - 'ts_paint', 12:27:03 INFO - 'tpaint', 12:27:03 INFO - 'sessionrestore', 12:27:03 INFO - 'sessionrestore_no_auto_restore', 12:27:03 INFO - 'tabpaint']}, 12:27:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:27:03 INFO - 'tests': ['a11yr', 12:27:03 INFO - 'ts_paint', 12:27:03 INFO - 'tpaint', 12:27:03 INFO - 'sessionrestore', 12:27:03 INFO - 'sessionrestore_no_auto_restore', 12:27:03 INFO - 'tabpaint']}, 12:27:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:27:03 INFO - 'tests': ['tsvgx', 12:27:03 INFO - 'tsvgr_opacity', 12:27:03 INFO - 'tart', 12:27:03 INFO - 'tscrollx', 12:27:03 INFO - 'cart', 12:27:03 INFO - 'tsvg_static']}, 12:27:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:27:03 INFO - 'tsvgr_opacity', 12:27:03 INFO - 'tart', 12:27:03 INFO - 'tscrollx', 12:27:03 INFO - 'cart', 12:27:03 INFO - 'tsvg_static']}, 12:27:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:27:03 INFO - 'talos_options': ['--disable-e10s'], 12:27:03 INFO - 'tests': ['tp5o']}, 12:27:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:27:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:27:03 INFO - 'talos_options': ['--disable-e10s', 12:27:03 INFO - '--xperf_path', 12:27:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:27:03 INFO - 'tests': ['tp5n']}, 12:27:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:27:03 INFO - 'talos_options': ['--xperf_path', 12:27:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:27:03 INFO - 'tests': ['tp5n']}}, 12:27:03 INFO - 'talos.zip': {'path': '', 12:27:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:27:03 INFO - Running post-action listener: _resource_record_post_action 12:27:03 INFO - [mozharness: 2017-06-05 19:27:03.759810Z] Finished populate-webroot step (success) 12:27:03 INFO - [mozharness: 2017-06-05 19:27:03.759900Z] Running create-virtualenv step. 12:27:03 INFO - Running pre-action listener: _resource_record_pre_action 12:27:03 INFO - Running main action method: create_virtualenv 12:27:03 INFO - Creating virtualenv /builds/slave/test/build/venv 12:27:03 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 12:27:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:27:03 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:27:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:27:03 INFO - Using real prefix '/usr' 12:27:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:27:04 INFO - Installing distribute.............................................................................................................................................................................................done. 12:27:06 INFO - Installing pip.................done. 12:27:06 INFO - Return code: 0 12:27:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:27:06 INFO - Reading from file tmpfile_stdout 12:27:06 INFO - Output received: 12:27:06 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) 12:27:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:27:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:27:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:27:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:27:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2904310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2901400>, '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 0x29023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2902840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', '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 12:27:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:27:06 INFO - Using env: {'DISPLAY': ':0', 12:27:06 INFO - 'HOME': '/home/cltbld', 12:27:06 INFO - 'LANG': 'en_US.UTF-8', 12:27:06 INFO - 'LANGUAGE': 'en_US:en', 12:27:06 INFO - 'LOGNAME': 'cltbld', 12:27:06 INFO - 'MAIL': '/var/mail/cltbld', 12:27:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:27:06 INFO - 'MOZ_NO_REMOTE': '1', 12:27:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:27:06 INFO - 'NO_EM_RESTART': '1', 12:27:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:27:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:27:06 INFO - 'PWD': '/builds/slave/test', 12:27:06 INFO - 'SHELL': '/bin/bash', 12:27:06 INFO - 'SHLVL': '1', 12:27:06 INFO - 'TERM': 'linux', 12:27:06 INFO - 'TMOUT': '86400', 12:27:06 INFO - 'USER': 'cltbld', 12:27:06 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', 12:27:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:27:06 INFO - '_': '/tools/buildbot/bin/python'} 12:27:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:27:06 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 12:27:06 INFO - Cleaning up... 12:27:06 INFO - Return code: 0 12:27:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:27:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:27:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:27:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:27:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2904310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2901400>, '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 0x29023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2902840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', '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 12:27:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:27:06 INFO - Using env: {'DISPLAY': ':0', 12:27:06 INFO - 'HOME': '/home/cltbld', 12:27:06 INFO - 'LANG': 'en_US.UTF-8', 12:27:06 INFO - 'LANGUAGE': 'en_US:en', 12:27:06 INFO - 'LOGNAME': 'cltbld', 12:27:06 INFO - 'MAIL': '/var/mail/cltbld', 12:27:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:27:06 INFO - 'MOZ_NO_REMOTE': '1', 12:27:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:27:06 INFO - 'NO_EM_RESTART': '1', 12:27:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:27:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:27:06 INFO - 'PWD': '/builds/slave/test', 12:27:06 INFO - 'SHELL': '/bin/bash', 12:27:06 INFO - 'SHLVL': '1', 12:27:06 INFO - 'TERM': 'linux', 12:27:06 INFO - 'TMOUT': '86400', 12:27:06 INFO - 'USER': 'cltbld', 12:27:06 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', 12:27:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:27:06 INFO - '_': '/tools/buildbot/bin/python'} 12:27:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:27:06 INFO - Downloading/unpacking psutil>=3.1.1 12:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:27:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:27:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:27:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:27:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:27:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:27:08 INFO - Installing collected packages: psutil 12:27:08 INFO - Running setup.py install for psutil 12:27:08 INFO - building 'psutil._psutil_linux' extension 12:27:08 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 12:27:08 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 12:27:08 INFO - building 'psutil._psutil_posix' extension 12:27:08 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 12:27:08 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 12:27:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:27:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:27:08 INFO - Successfully installed psutil 12:27:08 INFO - Cleaning up... 12:27:08 INFO - Return code: 0 12:27:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:27:08 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')]} 12:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:27:08 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')]} 12:27:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:27:08 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 0x26661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2904310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2901400>, '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 0x29023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2902840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', '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 12:27:08 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 12:27:08 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 12:27:08 INFO - Using env: {'DISPLAY': ':0', 12:27:08 INFO - 'HOME': '/home/cltbld', 12:27:08 INFO - 'LANG': 'en_US.UTF-8', 12:27:08 INFO - 'LANGUAGE': 'en_US:en', 12:27:08 INFO - 'LOGNAME': 'cltbld', 12:27:08 INFO - 'MAIL': '/var/mail/cltbld', 12:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:27:08 INFO - 'MOZ_NO_REMOTE': '1', 12:27:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:27:08 INFO - 'NO_EM_RESTART': '1', 12:27:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:27:08 INFO - 'PWD': '/builds/slave/test', 12:27:08 INFO - 'SHELL': '/bin/bash', 12:27:08 INFO - 'SHLVL': '1', 12:27:08 INFO - 'TERM': 'linux', 12:27:08 INFO - 'TMOUT': '86400', 12:27:08 INFO - 'USER': 'cltbld', 12:27:08 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', 12:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:27:08 INFO - '_': '/tools/buildbot/bin/python'} 12:27:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:27:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:27:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:27:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:27:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:27:10 INFO - Installing collected packages: mozsystemmonitor 12:27:10 INFO - Running setup.py install for mozsystemmonitor 12:27:10 INFO - Successfully installed mozsystemmonitor 12:27:10 INFO - Cleaning up... 12:27:10 INFO - Return code: 0 12:27:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:27:10 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')]} 12:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:27:10 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')]} 12:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:27:10 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 0x26661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2904310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2901400>, '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 0x29023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2902840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', '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 12:27:10 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 12:27:10 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 12:27:10 INFO - Using env: {'DISPLAY': ':0', 12:27:10 INFO - 'HOME': '/home/cltbld', 12:27:10 INFO - 'LANG': 'en_US.UTF-8', 12:27:10 INFO - 'LANGUAGE': 'en_US:en', 12:27:10 INFO - 'LOGNAME': 'cltbld', 12:27:10 INFO - 'MAIL': '/var/mail/cltbld', 12:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:27:10 INFO - 'MOZ_NO_REMOTE': '1', 12:27:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:27:10 INFO - 'NO_EM_RESTART': '1', 12:27:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:27:10 INFO - 'PWD': '/builds/slave/test', 12:27:10 INFO - 'SHELL': '/bin/bash', 12:27:10 INFO - 'SHLVL': '1', 12:27:10 INFO - 'TERM': 'linux', 12:27:10 INFO - 'TMOUT': '86400', 12:27:10 INFO - 'USER': 'cltbld', 12:27:10 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', 12:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:27:10 INFO - '_': '/tools/buildbot/bin/python'} 12:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:27:10 INFO - Downloading/unpacking jsonschema==2.5.1 12:27:10 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 12:27:10 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 12:27:10 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 12:27:10 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 12:27:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:27:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:27:11 INFO - Downloading functools32-3.2.3-2.tar.gz 12:27:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:27:11 INFO - warning: no files found matching '*.txt' 12:27:11 INFO - no previously-included directories found matching 'build' 12:27:11 INFO - no previously-included directories found matching 'dist' 12:27:11 INFO - no previously-included directories found matching '.git*' 12:27:11 INFO - Installing collected packages: jsonschema, functools32 12:27:11 INFO - Running setup.py install for functools32 12:27:11 INFO - warning: no files found matching '*.txt' 12:27:11 INFO - no previously-included directories found matching 'build' 12:27:11 INFO - no previously-included directories found matching 'dist' 12:27:11 INFO - no previously-included directories found matching '.git*' 12:27:11 INFO - Successfully installed jsonschema functools32 12:27:11 INFO - Cleaning up... 12:27:11 INFO - Return code: 0 12:27:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:27:11 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')]} 12:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:27:11 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')]} 12:27:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:27:11 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 0x26661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2904310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2901400>, '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 0x29023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2902840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', '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 12:27:11 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 12:27:11 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 12:27:11 INFO - Using env: {'DISPLAY': ':0', 12:27:11 INFO - 'HOME': '/home/cltbld', 12:27:11 INFO - 'LANG': 'en_US.UTF-8', 12:27:11 INFO - 'LANGUAGE': 'en_US:en', 12:27:11 INFO - 'LOGNAME': 'cltbld', 12:27:11 INFO - 'MAIL': '/var/mail/cltbld', 12:27:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:27:11 INFO - 'MOZ_NO_REMOTE': '1', 12:27:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:27:11 INFO - 'NO_EM_RESTART': '1', 12:27:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:27:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:27:11 INFO - 'PWD': '/builds/slave/test', 12:27:11 INFO - 'SHELL': '/bin/bash', 12:27:11 INFO - 'SHLVL': '1', 12:27:11 INFO - 'TERM': 'linux', 12:27:11 INFO - 'TMOUT': '86400', 12:27:11 INFO - 'USER': 'cltbld', 12:27:11 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', 12:27:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:27:11 INFO - '_': '/tools/buildbot/bin/python'} 12:27:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:27:12 INFO - Cleaning up... 12:27:12 INFO - Return code: 0 12:27:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:27:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:27:12 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 0x26661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2904310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2901400>, '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 0x29023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2902840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', '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 12:27:12 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 12:27:12 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 12:27:12 INFO - Using env: {'DISPLAY': ':0', 12:27:12 INFO - 'HOME': '/home/cltbld', 12:27:12 INFO - 'LANG': 'en_US.UTF-8', 12:27:12 INFO - 'LANGUAGE': 'en_US:en', 12:27:12 INFO - 'LOGNAME': 'cltbld', 12:27:12 INFO - 'MAIL': '/var/mail/cltbld', 12:27:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:27:12 INFO - 'MOZ_NO_REMOTE': '1', 12:27:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:27:12 INFO - 'NO_EM_RESTART': '1', 12:27:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:27:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:27:12 INFO - 'PWD': '/builds/slave/test', 12:27:12 INFO - 'SHELL': '/bin/bash', 12:27:12 INFO - 'SHLVL': '1', 12:27:12 INFO - 'TERM': 'linux', 12:27:12 INFO - 'TMOUT': '86400', 12:27:12 INFO - 'USER': 'cltbld', 12:27:12 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', 12:27:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:27:12 INFO - '_': '/tools/buildbot/bin/python'} 12:27:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:27:12 INFO - Downloading/unpacking blobuploader==1.2.4 12:27:12 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 12:27:12 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 12:27:12 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 12:27:12 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 12:27:13 INFO - Downloading blobuploader-1.2.4.tar.gz 12:27:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:27:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:27:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:27:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:27:13 INFO - Downloading docopt-0.6.1.tar.gz 12:27:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:27:13 INFO - Installing collected packages: blobuploader, requests, docopt 12:27:13 INFO - Running setup.py install for blobuploader 12:27:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:27:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:27:14 INFO - Running setup.py install for requests 12:27:14 INFO - Running setup.py install for docopt 12:27:14 INFO - Successfully installed blobuploader requests docopt 12:27:14 INFO - Cleaning up... 12:27:14 INFO - Return code: 0 12:27:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:27:14 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')]} 12:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:27:14 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')]} 12:27:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:27:14 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 0x26661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2904310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2901400>, '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 0x29023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2902840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', '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 12:27:14 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 12:27:14 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 12:27:14 INFO - Using env: {'DISPLAY': ':0', 12:27:14 INFO - 'HOME': '/home/cltbld', 12:27:14 INFO - 'LANG': 'en_US.UTF-8', 12:27:14 INFO - 'LANGUAGE': 'en_US:en', 12:27:14 INFO - 'LOGNAME': 'cltbld', 12:27:14 INFO - 'MAIL': '/var/mail/cltbld', 12:27:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:27:14 INFO - 'MOZ_NO_REMOTE': '1', 12:27:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:27:14 INFO - 'NO_EM_RESTART': '1', 12:27:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:27:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:27:14 INFO - 'PWD': '/builds/slave/test', 12:27:14 INFO - 'SHELL': '/bin/bash', 12:27:14 INFO - 'SHLVL': '1', 12:27:14 INFO - 'TERM': 'linux', 12:27:14 INFO - 'TMOUT': '86400', 12:27:14 INFO - 'USER': 'cltbld', 12:27:14 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', 12:27:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:27:14 INFO - '_': '/tools/buildbot/bin/python'} 12:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:27:14 INFO - Running setup.py (path:/tmp/pip-up01N9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:27:14 INFO - Running setup.py (path:/tmp/pip-egY_xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:27:14 INFO - Running setup.py (path:/tmp/pip-ZZepWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:27:15 INFO - Running setup.py (path:/tmp/pip-OnimIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:27:15 INFO - Running setup.py (path:/tmp/pip-Dra0_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:27:15 INFO - Running setup.py (path:/tmp/pip-KCbLhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:27:15 INFO - Running setup.py (path:/tmp/pip-NNRDu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:27:15 INFO - Running setup.py (path:/tmp/pip-el9g8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:27:15 INFO - Running setup.py (path:/tmp/pip-h7GJ7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:27:15 INFO - Running setup.py (path:/tmp/pip-u3YZCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:27:15 INFO - Running setup.py (path:/tmp/pip-9yL13v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:27:15 INFO - Running setup.py (path:/tmp/pip-lC7tB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:27:15 INFO - Running setup.py (path:/tmp/pip-lK4Gb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:27:15 INFO - Running setup.py (path:/tmp/pip-TKzEnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:27:15 INFO - Running setup.py (path:/tmp/pip-cnqYTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:27:15 INFO - Running setup.py (path:/tmp/pip-ArpR39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:27:15 INFO - Running setup.py (path:/tmp/pip-BEOmXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:27:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:27:16 INFO - Running setup.py install for manifestparser 12:27:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:27:16 INFO - Running setup.py install for mozcrash 12:27:16 INFO - Running setup.py install for mozdebug 12:27:16 INFO - Running setup.py install for mozdevice 12:27:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:27:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:27:16 INFO - Running setup.py install for mozfile 12:27:16 INFO - Running setup.py install for mozhttpd 12:27:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:27:16 INFO - Running setup.py install for mozinfo 12:27:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:27:16 INFO - Running setup.py install for mozInstall 12:27:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:27:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:27:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:27:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:27:16 INFO - Running setup.py install for mozleak 12:27:16 INFO - Running setup.py install for mozlog 12:27:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:27:17 INFO - Running setup.py install for moznetwork 12:27:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:27:17 INFO - Running setup.py install for mozprocess 12:27:17 INFO - Running setup.py install for mozprofile 12:27:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:27:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:27:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:27:17 INFO - Running setup.py install for mozrunner 12:27:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:27:17 INFO - Running setup.py install for mozscreenshot 12:27:17 INFO - Running setup.py install for moztest 12:27:17 INFO - Running setup.py install for mozversion 12:27:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:27:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:27:17 INFO - Cleaning up... 12:27:17 INFO - Return code: 0 12:27:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:27:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:27:17 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 0x26661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2904310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2901400>, '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 0x29023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2902840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', '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 12:27:17 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 12:27:17 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 12:27:17 INFO - Using env: {'DISPLAY': ':0', 12:27:17 INFO - 'HOME': '/home/cltbld', 12:27:17 INFO - 'LANG': 'en_US.UTF-8', 12:27:17 INFO - 'LANGUAGE': 'en_US:en', 12:27:17 INFO - 'LOGNAME': 'cltbld', 12:27:17 INFO - 'MAIL': '/var/mail/cltbld', 12:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:27:17 INFO - 'MOZ_NO_REMOTE': '1', 12:27:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:27:17 INFO - 'NO_EM_RESTART': '1', 12:27:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:27:17 INFO - 'PWD': '/builds/slave/test', 12:27:17 INFO - 'SHELL': '/bin/bash', 12:27:17 INFO - 'SHLVL': '1', 12:27:17 INFO - 'TERM': 'linux', 12:27:17 INFO - 'TMOUT': '86400', 12:27:17 INFO - 'USER': 'cltbld', 12:27:17 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', 12:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:27:17 INFO - '_': '/tools/buildbot/bin/python'} 12:27:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:27:18 INFO - Running setup.py (path:/tmp/pip-vJsw3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:27:18 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)) 12:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:27:18 INFO - Running setup.py (path:/tmp/pip-X3yC9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:27:18 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)) 12:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:27:18 INFO - Running setup.py (path:/tmp/pip-cXTQUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:27:18 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)) 12:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:27:18 INFO - Running setup.py (path:/tmp/pip-NYnBVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:27:18 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)) 12:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:27:18 INFO - Running setup.py (path:/tmp/pip-pKWByD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:27:18 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)) 12:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:27:18 INFO - Running setup.py (path:/tmp/pip-55vlEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:27:18 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)) 12:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:27:18 INFO - Running setup.py (path:/tmp/pip-SkbUfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:27:18 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)) 12:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:27:18 INFO - Running setup.py (path:/tmp/pip-WC0nTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:27:18 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)) 12:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:27:18 INFO - Running setup.py (path:/tmp/pip-BkRiaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:27:18 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)) 12:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:27:18 INFO - Running setup.py (path:/tmp/pip-b1U6vX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:27:18 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)) 12:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:27:18 INFO - Running setup.py (path:/tmp/pip-sipeEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:27:18 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)) 12:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:27:18 INFO - Running setup.py (path:/tmp/pip-rT41PY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:27:18 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)) 12:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:27:18 INFO - Running setup.py (path:/tmp/pip-Mpc4Us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:27:18 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)) 12:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:27:18 INFO - Running setup.py (path:/tmp/pip-R235qj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:27:19 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)) 12:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:27:19 INFO - Running setup.py (path:/tmp/pip-n_1e01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:27:19 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)) 12:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:27:19 INFO - Running setup.py (path:/tmp/pip-QCGdg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:27:19 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)) 12:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:27:19 INFO - Running setup.py (path:/tmp/pip-fugLv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:27:19 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)) 12:27:19 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)) 12:27:19 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)) 12:27:19 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)) 12:27:19 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)) 12:27:19 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)) 12:27:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:27:19 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 12:27:19 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 12:27:19 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 12:27:19 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 12:27:20 INFO - Downloading blessings-1.6.tar.gz 12:27:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:27:20 INFO - Installing collected packages: blessings 12:27:20 INFO - Running setup.py install for blessings 12:27:20 INFO - Successfully installed blessings 12:27:20 INFO - Cleaning up... 12:27:20 INFO - Return code: 0 12:27:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:27:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:27:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:27:20 INFO - Reading from file tmpfile_stdout 12:27:20 INFO - Current package versions: 12:27:20 INFO - argparse == 1.2.1 12:27:20 INFO - blessings == 1.6 12:27:20 INFO - blobuploader == 1.2.4 12:27:20 INFO - docopt == 0.6.1 12:27:20 INFO - functools32 == 3.2.3-2 12:27:20 INFO - jsonschema == 2.5.1 12:27:20 INFO - manifestparser == 1.1 12:27:20 INFO - mozInstall == 1.12 12:27:20 INFO - mozcrash == 1.0 12:27:20 INFO - mozdebug == 0.1 12:27:20 INFO - mozdevice == 0.49 12:27:20 INFO - mozfile == 1.2 12:27:20 INFO - mozhttpd == 0.7 12:27:20 INFO - mozinfo == 0.9 12:27:20 INFO - mozleak == 0.1 12:27:20 INFO - mozlog == 3.4 12:27:20 INFO - moznetwork == 0.27 12:27:20 INFO - mozprocess == 0.25 12:27:20 INFO - mozprofile == 0.28 12:27:20 INFO - mozrunner == 6.13 12:27:20 INFO - mozscreenshot == 0.1 12:27:20 INFO - mozsystemmonitor == 0.3 12:27:20 INFO - moztest == 0.8 12:27:20 INFO - mozversion == 1.4 12:27:20 INFO - psutil == 3.1.1 12:27:20 INFO - requests == 1.2.3 12:27:20 INFO - wsgiref == 0.1.2 12:27:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:27:20 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')]} 12:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:27:20 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')]} 12:27:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:27:20 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 0x26661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2904310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2901400>, '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 0x29023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2902840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', '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 12:27:20 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 12:27:20 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 12:27:20 INFO - Using env: {'DISPLAY': ':0', 12:27:20 INFO - 'HOME': '/home/cltbld', 12:27:20 INFO - 'LANG': 'en_US.UTF-8', 12:27:20 INFO - 'LANGUAGE': 'en_US:en', 12:27:20 INFO - 'LOGNAME': 'cltbld', 12:27:20 INFO - 'MAIL': '/var/mail/cltbld', 12:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:27:20 INFO - 'MOZ_NO_REMOTE': '1', 12:27:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:27:20 INFO - 'NO_EM_RESTART': '1', 12:27:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:27:20 INFO - 'PWD': '/builds/slave/test', 12:27:20 INFO - 'SHELL': '/bin/bash', 12:27:20 INFO - 'SHLVL': '1', 12:27:20 INFO - 'TERM': 'linux', 12:27:20 INFO - 'TMOUT': '86400', 12:27:20 INFO - 'USER': 'cltbld', 12:27:20 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', 12:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:27:20 INFO - '_': '/tools/buildbot/bin/python'} 12:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:27:21 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)) 12:27:21 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)) 12:27:21 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)) 12:27:21 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)) 12:27:21 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)) 12:27:21 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)) 12:27:21 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)) 12:27:21 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)) 12:27:21 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)) 12:27:21 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)) 12:27:21 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)) 12:27:21 INFO - Cleaning up... 12:27:21 INFO - Return code: 0 12:27:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:27: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')]} 12:27:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:27: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')]} 12:27:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:27:21 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 0x26661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2904310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2901400>, '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 0x29023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2902840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', '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 12:27:21 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 12:27:21 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 12:27:21 INFO - Using env: {'DISPLAY': ':0', 12:27:21 INFO - 'HOME': '/home/cltbld', 12:27:21 INFO - 'LANG': 'en_US.UTF-8', 12:27:21 INFO - 'LANGUAGE': 'en_US:en', 12:27:21 INFO - 'LOGNAME': 'cltbld', 12:27:21 INFO - 'MAIL': '/var/mail/cltbld', 12:27:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:27:21 INFO - 'MOZ_NO_REMOTE': '1', 12:27:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:27:21 INFO - 'NO_EM_RESTART': '1', 12:27:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:27:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:27:21 INFO - 'PWD': '/builds/slave/test', 12:27:21 INFO - 'SHELL': '/bin/bash', 12:27:21 INFO - 'SHLVL': '1', 12:27:21 INFO - 'TERM': 'linux', 12:27:21 INFO - 'TMOUT': '86400', 12:27:21 INFO - 'USER': 'cltbld', 12:27:21 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', 12:27:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:27:21 INFO - '_': '/tools/buildbot/bin/python'} 12:27:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:27:21 INFO - Cleaning up... 12:27:21 INFO - Return code: 0 12:27:21 INFO - Running post-action listener: _resource_record_post_action 12:27:21 INFO - Running post-action listener: _start_resource_monitoring 12:27:21 INFO - Starting resource monitoring. 12:27:21 INFO - [mozharness: 2017-06-05 19:27:21.284556Z] Finished create-virtualenv step (success) 12:27:21 INFO - [mozharness: 2017-06-05 19:27:21.285067Z] Running install step. 12:27:21 INFO - Running pre-action listener: _resource_record_pre_action 12:27:21 INFO - Running main action method: install 12:27:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:27:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:27:21 INFO - Reading from file tmpfile_stdout 12:27:21 INFO - Detecting whether we're running mozinstall >=1.0... 12:27:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:27:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:27:21 INFO - Reading from file tmpfile_stdout 12:27:21 INFO - Output received: 12:27:21 INFO - Usage: mozinstall [options] installer 12:27:21 INFO - Options: 12:27:21 INFO - -h, --help show this help message and exit 12:27:21 INFO - -d DEST, --destination=DEST 12:27:21 INFO - Directory to install application into. [default: 12:27:21 INFO - "/builds/slave/test"] 12:27:21 INFO - --app=APP Application being installed. [default: firefox] 12:27:21 INFO - mkdir: /builds/slave/test/build/application 12:27:21 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'] 12:27:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:27:35 INFO - Reading from file tmpfile_stdout 12:27:35 INFO - Output received: 12:27:35 INFO - /builds/slave/test/build/application/firefox/firefox 12:27:35 INFO - Running post-action listener: _resource_record_post_action 12:27:35 INFO - [mozharness: 2017-06-05 19:27:35.647585Z] Finished install step (success) 12:27:35 INFO - [mozharness: 2017-06-05 19:27:35.647829Z] Running run-tests step. 12:27:35 INFO - Running pre-action listener: _resource_record_pre_action 12:27:35 INFO - Running main action method: run_tests 12:27:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:27:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:27:35 INFO - Python 2.7.3 12:27:35 INFO - Return code: 0 12:27:35 INFO - grabbing minidump binary from tooltool 12:27: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')]} 12:27:35 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 0x2901400>, '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 0x29023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2902840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:27:35 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 12:27:35 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 12:27:35 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 12:27:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:27:35 INFO - Return code: 0 12:27:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:27:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:27:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:27:35 INFO - ENV: RUST_BACKTRACE is now 1 12:27:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:27:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:27:35 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/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--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 12:27:35 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/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-089 --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 12:27:35 INFO - Using env: {'DISPLAY': ':0', 12:27:35 INFO - 'HOME': '/home/cltbld', 12:27:35 INFO - 'LANG': 'en_US.UTF-8', 12:27:35 INFO - 'LANGUAGE': 'en_US:en', 12:27:35 INFO - 'LOGNAME': 'cltbld', 12:27:35 INFO - 'MAIL': '/var/mail/cltbld', 12:27:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:27:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:27:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:27:35 INFO - 'MOZ_NO_REMOTE': '1', 12:27:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:27:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:27:35 INFO - 'NO_EM_RESTART': '1', 12:27:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:27:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:27:35 INFO - 'PWD': '/builds/slave/test', 12:27:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:27:35 INFO - 'RUST_BACKTRACE': '1', 12:27:35 INFO - 'SHELL': '/bin/bash', 12:27:35 INFO - 'SHLVL': '1', 12:27:35 INFO - 'TERM': 'linux', 12:27:35 INFO - 'TMOUT': '86400', 12:27:35 INFO - 'USER': 'cltbld', 12:27:35 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907', 12:27:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:27:35 INFO - '_': '/tools/buildbot/bin/python'} 12:27:35 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/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--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 12:27:35 INFO - mozversion application_buildid: 20170605183709 12:27:35 INFO - mozversion application_changeset: 6f0be3ad8512b63a13cb4ec87ae4efae31411ecf 12:27:35 INFO - mozversion application_display_name: Firefox 12:27:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:27:35 INFO - mozversion application_name: Firefox 12:27:35 INFO - mozversion application_remotingname: firefox 12:27:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:27:35 INFO - mozversion application_vendor: Mozilla 12:27:35 INFO - mozversion application_version: 54.0 12:27:35 INFO - mozversion platform_buildid: 20170605183709 12:27:35 INFO - mozversion platform_changeset: 6f0be3ad8512b63a13cb4ec87ae4efae31411ecf 12:27:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:27:35 INFO - mozversion platform_version: 54.0 12:27:35 INFO - using testdate: 1496690855 12:27:35 INFO - actual date: 1496690855 12:27:35 INFO - starting webserver on 'localhost:60710' 12:27:35 INFO - SUITE-START | Running 2 tests 12:27:35 INFO - TEST-START | dromaeo_css 12:27:35 INFO - operating with platform_type : linux_ 12:27:35 INFO - Initialising browser for dromaeo_css test... 12:27:35 INFO - TEST-INFO | started process 27493 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXdf5j9/profile http://localhost:60710/getInfo.html) 12:27:40 INFO - PROCESS | 27493 | __metrics Screen width/height:1600/1200 12:27:40 INFO - PROCESS | 27493 | colorDepth:24 12:27:40 INFO - PROCESS | 27493 | Browser inner width/height: 1024/697 12:27:40 INFO - PROCESS | 27493 | __metrics 12:27:41 INFO - PROCESS | 27493 | 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" 12:27:41 INFO - PROCESS | 27493 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:27:41 INFO - PROCESS | 27493 | console.error: PushService: 12:27:41 INFO - PROCESS | 27493 | stateChangeProcessEnqueue: Error transitioning state 12:27:41 INFO - PROCESS | 27493 | UnknownError 12:27:42 INFO - TEST-INFO | 27493: exit 0 12:27:42 INFO - Browser initialized. 12:27:42 INFO - Running cycle 1/1 for dromaeo_css test... 12:27:42 INFO - TEST-INFO | started process 27589 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXdf5j9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:27:54 INFO - PROCESS | 27589 | RSS: Main: 141500416 12:27:54 INFO - PROCESS | 27589 | 12:30:55 INFO - PROCESS | 27589 | Cycle 1(1): loaded http://localhost:60710/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60710/tests/dromaeo/cssquery-ext.html) 12:30:57 INFO - PROCESS | 27589 | RSS: Main: 271732736 12:30:57 INFO - PROCESS | 27589 | 12:33:59 INFO - PROCESS | 27589 | Cycle 1(1): loaded http://localhost:60710/tests/dromaeo/cssquery-ext.html (next: http://localhost:60710/tests/dromaeo/cssquery-jquery.html) 12:34:00 INFO - PROCESS | 27589 | RSS: Main: 220934144 12:34:00 INFO - PROCESS | 27589 | 12:37:01 INFO - PROCESS | 27589 | Cycle 1(1): loaded http://localhost:60710/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60710/tests/dromaeo/cssquery-mootools.html) 12:37:02 INFO - PROCESS | 27589 | RSS: Main: 172023808 12:37:02 INFO - PROCESS | 27589 | 12:40:04 INFO - PROCESS | 27589 | Cycle 1(1): loaded http://localhost:60710/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60710/tests/dromaeo/cssquery-prototype.html) 12:40:04 INFO - PROCESS | 27589 | RSS: Main: 172544000 12:40:04 INFO - PROCESS | 27589 | 12:43:07 INFO - PROCESS | 27589 | Cycle 1(1): loaded http://localhost:60710/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60710/tests/dromaeo/cssquery-yui.html) 12:43:08 INFO - PROCESS | 27589 | RSS: Main: 175247360 12:43:08 INFO - PROCESS | 27589 | 12:46:10 INFO - PROCESS | 27589 | Cycle 1(1): loaded http://localhost:60710/tests/dromaeo/cssquery-yui.html (next: http://localhost:60710/tests/dromaeo/cssquery-dojo.html) 12:46:10 INFO - PROCESS | 27589 | RSS: Main: 263360512 12:46:10 INFO - PROCESS | 27589 | 12:46:10 INFO - PROCESS | 27589 | __start_tp_report 12:46:10 INFO - PROCESS | 27589 | _x_x_mozilla_page_load 12:46:10 INFO - PROCESS | 27589 | _x_x_mozilla_page_load_details 12:46:10 INFO - PROCESS | 27589 | |i|pagename|runs| 12:46:10 INFO - PROCESS | 27589 | |0;dojo.html;3390.288039511702;3469.98265008675;3488.4244099723533;3507.403741363968;3528.752987290889;5822.155787410823;5883.558733095019;5890.587658863881;5892.64644121353;5899.52803775698;5900.940990590093;5925.02237130874;5925.288965324167;5927.170196172528;5941.64350138992;5344.331958505197;5370.248165256867;5405.486478784529;5412.702301373423;5454.290942177511;5224.033553792541;5260.421353651095;5466.535344495723;5470.480304371078;5472.945270547303;5305.761240744176;5360.035193665139;5434.510894019538;5444.6188766786145;5451.918221226685;5457.781688732446;5457.918131228034;5458.426865179139;5463.945360546423;5464.890702185934;5184.6629969052265;5317.069512835238;5357.249985002103;5398;5452.318460192476;5735.11105778605;5763.913541296842;5830.067189249742;5832.854178645525;5837.5913686041995;6299.149614801989;6312.400321969406;6335.809925702236;6339.524535659844;6347.143135676717;6378.170837791104;6381.1066450696935;6391.520635952322;6394.616323020634;6402.167718196652;5696.430356964312;5727.312722473288;5834.299884013945;5860.033094539439;5880.353161152269;6414.518911081687;6433.131527243856;6458.903116453257;6460.483161347081;6467.708953097122;6100.755969761249;6226.813195604139;6228.56400051992;6232.376762323732;6232.594881332699;6077.453029227391;6135.294441139237;6174.969125154345;6277.089821975879;6287.748490060347;6283.8114856554375;6292.937070629327;6295.622262664255;6305.337939516285;6305.96847015762;6057.242844644419;6065.909011364833;6105.786297479655;6109.389061093855;6293.496520278367;4744.833930812405;4751.786169622375;4795.712257264506;4854.587360074363;4857.465678775332;4284.10033892886;4298.892527686833;4309.396684464177;4325.870223893288;4339.956600434019;5833.620814647034;5868.031774757117;5929.14027466015;5982.521398288127;5995.940040599626;6371.39471750183;6387.808365749035;6388.233411990591;6389.520785941073;6394.136791533177;6027.30685971119;6034.788782392595;6035.245594300713;6035.245594300713;6042.456178943916;5236.895262094737;5278.181881808326;5311.3891902431715;5413.458654134555;5437.184422336682;6532.346765323429;6574.572652777554;6584;6590.8022759318255;6595.637239951849;6298.905516417349;6358.968205158944;6360.395762402564;6368.936310636835;6376.489880809617;6315.96842015787;6356.3325850785;6579.144711187515;6582.210134783857;6589.835254118685;6120.081455037999;6146.231721034871;6207.720652570555;6222.377762223741;6226.1905952225925;6344.968275158594;6349.460295874902;6349.809505714836;6350.428461438492;6353.428191462605;5987.850303742267;6131.509479241561;6193.81418557444;6195.349488303663;6201.131841542278;6056.636601803906;6069.977774573452;6086.756529738761;6161.784337548164;6172.814815555541;6135.202423684892;6153.3538978408415;6165.907511387422;6176.104464852661;6201.720922558406;5988.341282458839;6022.698865056817;6059.757609695739;6260.436560709558;6262.123302737529;5988.221531200916;6021.668808215591;6090.299615544269;6120.9693951531735;6126 12:46:10 INFO - PROCESS | 27589 | |1;ext.html;690.875642384371;756.8826831841063;778.5134291068081;818.3248819723726;824.8020475085981;32841.50737738933;46359.30461043083;46924.0615187696;47067;47192.05615887685;45020.54979450205;45318.32022519665;45351.09297814042;45352.09295814082;45359.77320113395;7844.960775196116;7957.801054973614;9081.091890810916;9195.172434480895;9202.401843880127;10946.343219406821;11203;11523.942380288086;11536.653900383002;11543.365114918675;11283.435828208598;11300.660980170607;11304.660860174208;11310.377929213932;11319.32084074954;11194.66416007517;11224.607138750105;11232.213745037892;11240.437978101103;11254.381009044499;10917.344959302387;10978.066864316543;10997.670069897837;10998.12015038795;11001.394923279217;43424.78287608573;43568.564314356765;43658;43748.56251437509;43784;4263.275243208631;4301.462317210348;4376.890577735582;4462.665300102472;4467.843625473093;31274.843625781727;31487;31677.20806979821;31957.201069973205;33033.50449743256;4390.538993405678;4416.889577760549;4417.293233082723;4452.421185245929;4474.284114541658;242819;243881;244054;244058;244560;41769.164616707494;41838.581614183466;41948.79025604852;41977.580224197976;55637;57338.7133064332;57384;57628;57633.13550296749;57652.135217971765;16873.746893796604;17034.403795867333;17149;17184.914075429544;17190.91404542994;12334.383280835922;12462.065345098972;15174.848251517564;15190.620234494227;15204.46784362542;13986.86013139876;14130.50543230982;14213.644658883404;14234.501792437215;14284;5421.83734487966;5467.1799230115;5484.149956756709;5560.860978475571;5561.972190139023;1000.494750151169;1001.1190152665607;1012.9493525323704;1021.3820638513592;1045.7960697664034;28705.712942870723;28861.711382885904;29388.26529336741;29426;29587;1080.9243352965218;1083.1118482843992;1085.137315833914;1086.5545126498098;1092.3773449134076;15114.319855606524;15476.613084672748;15502.379904803682;15582.83796835642;15615.921920390552;309341;309494;309823;312685;312827;58061.12908306379;58214;58238.12642810361;58251.12623310654;58365.12452313219;1480.8148981377328;1484.2801241397913;1531.433369653213;1532.6398296400114;1543.0433131458567;28340.149795506168;28709;28959.85520072386;29396.26509337198;29685.703142968294;42202.577974219865;42318.57681423146;42346.78826605847;42377.78811105925;42393.1521369577;310712;311124;311346;311398;311981;26066.86966565155;26414.603780942918;30004;31192.22019449532;33851.32297354088;35485.112872178404;35998.4600231002;43340.34989475223;43893.561064388945;44003.11993760043;30716.692833072277;31536.68463315429;31757.523637145;31779.841100795275;31788.364232714754;30832.383352333276;31151;31186;31490.212744680648;31633.683663163072;36085.27829443449;36090.45864311985;36897.26205475929;37824.243515130096;40176.1964760709;30323.241918952204;30651;30697.079087627408;30851.38297233998;31104.844475777478;24666.876665616557;25933.870330648228;25937.22188334353;25985;26253 12:46:10 INFO - PROCESS | 27589 | |2;jquery.html;450.5517010574479;461.40017976630384;465.4298484356664;476.8116593945391;477.1840153337792;20278.797212027876;20405.08177132029;21165;21310.680339794875;21338.679919801216;21436.678449823226;22417.103315867385;22445;22571.322860314176;23111.191108311214;6904.5512041717375;6969.23338432772;6975.0932378790685;7006.929930700704;7052.04797352356;6695.799126026227;6950.235474097871;6952.756653517128;6954.40887524561;6958.547694399873;6948.200956889972;7447.180810110884;7696.191899850519;7737.845243095135;7738.922610773905;7646.197149299299;7667.5782831944225;7809.101953275389;7835.373170146375;7866;6965.303469653045;7629.771106866803;7638.8472230555635;7641.808954776119;7645.15903250642;26012.219633411027;26104.216873493635;26445.3388665283;26568.46863062747;26729.599056014176;2976.7320941115404;3011.864466099031;3084.7686423518103;3093.752499800011;3195.785601471426;15827.762583561256;16425;16621.916890415472;16840.157992100347;17074.914625426918;2222.100049479963;2255.7180352455944;2270.636698128308;2330.7552706965694;2368.2303251443177;108451.45774271157;108478.91521084767;110563;114078;118272;17420.738688919675;18047.458576242734;18053.368132115313;18278.908605456887;18321.450356489328;31628.841855791034;31682;31709.84145079306;31811.522827157612;31823;10319.896801031893;10373.636922707668;10383.532741026673;10398.168146548258;10500;8867.379283450096;8896.644134234559;8941.776455588662;9094.408863423856;9128.954355228314;8973.32700047499;9097.681581144629;9169.724908252763;9259.67591134307;9411.717648470556;2950.4836653585535;3812.8856134316015;3878.185581028016;4034.8184331705143;4052.8176232069036;934.5981228071859;1043.7025447747508;1064.4943651765504;1071.885239351066;1083.5611577311201;21005.68491472629;21143;21171.576568468543;21193.576128477343;21208.681869771965;1102.8841971593029;1110.8056090184186;1113.276370359273;1121.6522877907876;1122.898939095469;11181.664550063511;11598.18812683104;11817.881821181678;11833.171677982631;11848.88151118495;651503;656689;683326;705379;708907;109660.06529706182;116199.41900290444;116331;117880.41059794647;118825.40587297008;1300.408314217048;1303;1358.219024061149;1358.9660258493418;1359.7756370198806;17945.102744862965;17945.641087178443;18356.908215458836;18700.7194892074;19257.32599359016;53436.73281633567;53860.19209711936;54241.45758542364;54537;55255.44744552664;68403.65798170977;68661.65669171623;68868.6556567214;69114;69141.65429172822;9186.08139186076;9286.95356523213;12492.312922789333;12508.312042837731;12952.676183095498;14628;14629.707405852036;14697.04469209498;14709.632259193606;14800.925995369955;12579.811302830281;12609.180403273758;12869.034822388545;12981.545645902348;13328.067035307824;11615.245009074382;12187.878121218675;12471.376431178587;12514.874851251736;12574.811377829157;13269.336533173495;13423;13432.731345373233;13438;13591.252481113634;12895.388076490439;13116.737665246832;13149.671258218621;13152.736945261233;13174.538891138764;12455.626331210078;12512.687182820502;12604.243745375306;12821.166624169398;12827.230366178059 12:46:10 INFO - PROCESS | 27589 | |3;mootools.html;1549.6125968507872;1554.35494269878;1563.8201606815212;1572.1431819658285;1575.3226112771508;10783.676489705302;11521.423928803548;11540.596079137233;11545.249558778683;11552.65342039738;11089.390083545402;11390.145739069581;11393.145514086435;11404.714882127952;11419.828702569468;2500.1499490173337;2532.2529853693204;2535.150724507292;2535.4041800177038;2537.746225377466;2412.3124909400826;2457.213691618683;2507.0723832182116;2515.1197081021587;2515.9874200629065;2440.1093600835743;2440.9389765255924;2462.938426539342;2464.186818349949;2471.085698291635;2331.1258278145697;2331.2773040357542;2354;2354.458474550854;2391.8564886106888;2309.041747674713;2327.2203811723093;2334.4163959010248;2339.7075365579303;2340.742518322983;11451.255668381611;11654.242474239232;11716.472758725879;11718.179727419085;11726.765464690743;5767.6539407635255;5886.470217680387;5901.23283973085;5901.3803550627;5908.793192238294;3515.876944306937;3557.6442355764475;3591.6408359164134;3653.3241350350004;3680.7607505512055;1425.8289005319325;1428.278719246784;1434.6054834920376;1446.5804916574182;1455.4437764152594;16560.83439165617;16879.493615191564;17007.319707211696;17019;17038.74441883373;4605.8848528786375;4659.184642687516;4750.12122757291;4776.785044672929;4779.593734532604;12910.354482275849;12947.223166610034;12974.416151273215;13002.219866807833;13003;5967.254093238345;6011.098335249748;6026.5178785697035;6031.336552979169;6032.607880487754;5398.595105367113;5458.099413596712;5488.094464413407;5512.035393806068;5533.197686335539;3957.050307926077;4003.319435695939;4023.839046438168;4053.310937140693;4093.7543747375253;1003.2375394699934;1003.5684655598163;1010.8685870836745;1014.0822555587206;1015.1624909449733;1072.8658917635296;1076.4133547216786;1086.8315411111291;1089.8637670291214;1094.4199574225672;6993.300669933068;7106.964465177641;7143.8571228576175;7194.258985327705;7294;1042.8279333909973;1060.867391576053;1061.8991195836384;1077.9622713205158;1089.591403223791;4645.442546894395;4650.55819697135;4661.370714953506;4690.929636055462;4743.0988112258565;14092.154470731788;14795.852041479448;14891.553253402415;14896.776548351783;14900.850991490162;12357.75284494305;12793.104482686123;14776.187309698069;14789.852101479062;14839.851601483848;10335.586576537005;10409.330658940673;10447.634332798316;10507.579696812041;10967.287126336922;7211.8557628848175;7353.5955522446775;7447.92552074494;7456.925430745623;7566.167721550735;12981.480740770263;13011.544595939096;13033.47866085384;13212.141210821266;13347;13308.866911330762;13312;13410.12834165776;13438.664033399244;13515.391807368494;9037.322200834833;9162.26701863836;9188.310876684409;9247.537623118951;9292.163705266288;10857.945710271399;10874.891251087703;10892.128629709763;10998.06516446111;11015.724606884893;9163.129502697233;9223.216026637543;9338.486383248986;9523.666671666459;9563.521823908637;9039.548022598976;9053.456792592466;9085.863712044193;9203;9385.296102792181;8116;8131.430799843954;8218.21926916942;8224.588770561568;8226.547539885363;6228;6294.181756371826;6328.082428047999;6336.936630633819;6340.461060809883;6507.414332709889;6514.153160089348;6564.9671751640935;6703.3296670332575;6751.797446076625 12:46:10 INFO - PROCESS | 27589 | |4;prototype.html;417.5490470292085;440.1746724890831;440.5506383488843;446.2413896376161;451.2351564098853;5200.959808038393;5305.734713264341;5328.413874473815;5342.732863356831;5347.572194224463;5097.566706829914;5200.271961925334;5260.579153667707;5261.447872877501;5264.868378290545;4455;4461.620762235206;4471.39636149119;4479.081788233404;4487.842925497608;4476.865694029184;4483.753393563353;4486.8109950863045;4510.872281929518;4519.435070616173;4395.230834603947;4518.819247230123;4525.456945166585;4532.206863798839;4542.273236282195;4403.744932694167;4415.690901636903;4432;4484.820607175741;4510.729356238619;4159.7920103994675;4172.499300083979;4181.310083836193;4188.764314527191;4198.244316023115;5098.490150984909;5121.948780512184;5125.820596279113;5133.204353325204;5156.143788236232;4925.128451188525;5101.948980510185;5130.717320669833;5131.4098878629065;5154.38147422308;5501.257330260403;5520.929651380732;5539.556835453154;5542.168674698787;5560.833175004756;4524.5701658342405;4599.402077729907;4604.88487787808;4644.6052085572765;4691.413573303337;5517.2551705519645;5613.719314034322;5662.971685141548;5772.826815195509;5789.710514474302;4713;4727.787249573777;4735.455422626442;4752.643551733634;4761.714297142183;4785.186518291899;4792.568668819823;4796.400449943757;4800.831970881072;4827.348307978448;4959.20652695567;5054.873628159252;5074.873128171826;5109.131447653907;5164.92252616211;5527.364353099365;5527.5577953764405;5535.169724541351;5541.473560011793;5547.833564993057;1622.3997121065045;1630.910299933515;1641.1958140511063;1646.860016898577;1656.1884676508423;1237.5297386992886;1254.4355040231933;1265.9177153484993;1267.2523211305374;1283.57641978147;5306.230596563477;5321.414644389114;5353.705546194997;5368.248445217672;5382.650127741685;5640.238567793377;5641.379448260688;5682.971585142048;5682.971585142131;5704.657720536781;5131.435542090367;5401.972990135024;5424.294841670637;5425.755840987165;5441.646292990943;2975.9553606695918;2979.746721528651;2993.6407631084408;3010.8946186883354;3017.456857765623;5696.778256479976;5855.736491857877;5864.589478736533;5931.673757943355;5965.403459654087;5705.972924873563;5766.154830454215;5785.739641716133;5796.275465566804;5832.358440571534;5844.766209351664;5861.882762344814;5878.970605146947;5880.470757631748;5913.733881975236;5350.598705097055;5547.750351234285;5606.943930560642;5632.88734225305;5653.491185793298;5393.595480339069;5592.853465039576;5602.355729091044;5614.88770224585;5617.269754931833;5745.626534275259;5831.434168965641;5913.408659134052;5927.8221653350465;5956.374580668968;4733.763311834326;4740.620750340034;4755.977464845075;4756.738379389167;4761.190597598347;5464.23500709893;5671.716414179357;5699.717563548168;5710.685912274865;5841.014055221245;5508.697021663847;5522.420145884625;5532.640378375554;5533.280673512418;5573.554115670656;5494.450554944474;5546.947629377156;5555.805546806004;5621.409751975983;5634.4365563443325;5232.362433914829;5458.972705136449;5494.472542630435;5534.806281780118;5538.307711536058;5544.63959842609;5554.97222513901;5573.300143456285;5585.804496842591;5585.916211256753;5319.335083114611;5434.184872269191;5492.752826122914;5520.199571062093;5522.696251706195 12:46:10 INFO - PROCESS | 27589 | |5;yui.html;746.2313816768726;779.9376049916008;824.1962598747342;853.9274161696255;868.2533021601422;16875.578110547227;17207;18005.81994180058;18008.819911800878;18055.277788888445;16419.83580164198;16559.751603725952;16607.418740344092;16665.583360415996;16669.49991500254;867.8134201146752;876.9780755481132;887.8224355128967;893.7363477774072;905.5924833824772;886.2776836877956;887.6893087419384;892.2415946445534;892.7366426904047;893.2675206330812;929.4330458420392;935.1957316707626;935.9859602105973;936.8641546975684;937.1003836317144;911.1662828511903;917.6283605139929;920.0109881876975;921.0789210789211;922.1193759959257;682.1711620381211;683.1528904158857;683.3542302524116;683.4942142814332;684.2575805251308;16000.75998860018;16056.75914861278;16206.189690515426;16213.675726485404;16453.341866325332;15079.246037698182;15325.846741532554;15390.15354155521;15448.922755386264;15740.370385184578;13064.346782660925;13396.464141434331;13409.46362145513;13500.257485838274;13763;4048.9454834858557;4054.3107671695884;4093.5701751315983;4177.26897792885;4227.302495088306;44714.32928506075;49723;53087.203691944655;78459;79851.6007419965;12112.454939527743;12531.498740050478;12546.372681365896;12565.434555445026;12843;21312.147514099262;21540.892295538735;21604.45988850291;21621.891890540446;21802.127914883546;8974.19232269099;9161.175494205554;9222.769430764285;9295.488748118783;9310.627574896927;5049.990001999615;5144.974275128675;5180.84457466269;5190.948090519047;5232.764525596357;3852.5954774748247;3903.6486716195677;3921.784301863425;3922.1371298314325;3951.980240098781;671.5198632977445;684.0696652552524;684.3635419060322;688.132952479879;699.8635266123159;780.4302858913026;781.9374450043982;804.3203493048364;806.0891192952043;807.8545861744825;11427.200095993367;11533.480993355319;11882;11948.761024779456;12245.75508489843;703.2615753458848;703.7783098324012;717.4798271253404;719.4855678190119;720.9639518024078;5049.192129259301;5347.946520534822;5424.213489044067;5425.348958124971;5464.180372944011;77645;77988;80095;83662.58168709118;85463.57268213619;70359.64820175867;70494.64752676204;72524;73858.26141738621;75277.24722752812;64041;64919;65190.674046629465;65335;65934.67032664806;10051.748706282402;10293.176545876162;10376.377417354983;10401.635942741968;10407;44901.77549112364;45619.315710263705;45813.77093114513;45826;46880.29679554878;48845;49976.25035624396;50788.74605626948;50886.236706450174;51818.74090629523;14123.505677301246;14301.499447519287;14447.566573002827;14505.347259373564;14560.053596516193;16601.223814554014;16921.323147073977;16926.492205233862;17670.939743615425;17794.288228470716;15399.692006160038;15446.613834654223;16083.597910051874;16306.918465407596;16409.507714768577;14549.85450145485;14928.776068358766;15319.46381876629;15670.299754592384;15811.920940395225;14084.15495070299;14664.853351466776;14995.325210365341;15423.768643470132;15505.30226139849;10323.027797717707;10717.732056698645;11045.226834121535;11102.444877756241;11618.593349232737;12765.553205637758;12831.743365132832;12888.162269452456;12906.548270810477;13010.609681709564 12:46:10 INFO - PROCESS | 27589 | __end_tp_report 12:46:10 INFO - PROCESS | 27589 | __start_cc_report 12:46:10 INFO - PROCESS | 27589 | _x_x_mozilla_cycle_collect,1235 12:46:10 INFO - PROCESS | 27589 | __end_cc_report 12:46:10 INFO - PROCESS | 27589 | __startTimestamp1496691970871__endTimestamp 12:46:10 INFO - PROCESS | 27589 | 12:46:10 INFO - PROCESS | 27589 | ------- Summary: start ------- 12:46:10 INFO - PROCESS | 27589 | Number of tests: 6 12:46:10 INFO - PROCESS | 27589 | 12:46:10 INFO - PROCESS | 27589 | [#0] dojo.html Cycles:165 Average:5853.99 Median:6059.76 stddev:657.05 (10.8%) stddev-sans-first:630.00 12:46:10 INFO - PROCESS | 27589 | Values: 3390.3 3470.0 3488.4 3507.4 3528.8 5822.2 5883.6 5890.6 5892.6 5899.5 5900.9 5925.0 5925.3 5927.2 5941.6 5344.3 5370.2 5405.5 5412.7 5454.3 5224.0 5260.4 5466.5 5470.5 5472.9 5305.8 5360.0 5434.5 5444.6 5451.9 5457.8 5457.9 5458.4 5463.9 5464.9 5184.7 5317.1 5357.2 5398.0 5452.3 5735.1 5763.9 5830.1 5832.9 5837.6 6299.1 6312.4 6335.8 6339.5 6347.1 6378.2 6381.1 6391.5 6394.6 6402.2 5696.4 5727.3 5834.3 5860.0 5880.4 6414.5 6433.1 6458.9 6460.5 6467.7 6100.8 6226.8 6228.6 6232.4 6232.6 6077.5 6135.3 6175.0 6277.1 6287.7 6283.8 6292.9 6295.6 6305.3 6306.0 6057.2 6065.9 6105.8 6109.4 6293.5 4744.8 4751.8 4795.7 4854.6 4857.5 4284.1 4298.9 4309.4 4325.9 4340.0 5833.6 5868.0 5929.1 5982.5 5995.9 6371.4 6387.8 6388.2 6389.5 6394.1 6027.3 6034.8 6035.2 6035.2 6042.5 5236.9 5278.2 5311.4 5413.5 5437.2 6532.3 6574.6 6584.0 6590.8 6595.6 6298.9 6359.0 6360.4 6368.9 6376.5 6316.0 6356.3 6579.1 6582.2 6589.8 6120.1 6146.2 6207.7 6222.4 6226.2 6345.0 6349.5 6349.8 6350.4 6353.4 5987.9 6131.5 6193.8 6195.3 6201.1 6056.6 6070.0 6086.8 6161.8 6172.8 6135.2 6153.4 6165.9 6176.1 6201.7 5988.3 6022.7 6059.8 6260.4 6262.1 5988.2 6021.7 6090.3 6121.0 6126.0 12:46:10 INFO - PROCESS | 27589 | 12:46:10 INFO - PROCESS | 27589 | [#1] ext.html Cycles:180 Average:45876.34 Median:311372.00 stddev:75764.59 (24.3%) stddev-sans-first:75901.17 12:46:10 INFO - PROCESS | 27589 | Values: 690.9 756.9 778.5 818.3 824.8 32841.5 46359.3 46924.1 47067.0 47192.1 45020.5 45318.3 45351.1 45352.1 45359.8 7845.0 7957.8 9081.1 9195.2 9202.4 10946.3 11203.0 11523.9 11536.7 11543.4 11283.4 11300.7 11304.7 11310.4 11319.3 11194.7 11224.6 11232.2 11240.4 11254.4 10917.3 10978.1 10997.7 10998.1 11001.4 43424.8 43568.6 43658.0 43748.6 43784.0 4263.3 4301.5 4376.9 4462.7 4467.8 31274.8 31487.0 31677.2 31957.2 33033.5 4390.5 4416.9 4417.3 4452.4 4474.3 242819.0 243881.0 244054.0 244058.0 244560.0 41769.2 41838.6 41948.8 41977.6 55637.0 57338.7 57384.0 57628.0 57633.1 57652.1 16873.7 17034.4 17149.0 17184.9 17190.9 12334.4 12462.1 15174.8 15190.6 15204.5 13986.9 14130.5 14213.6 14234.5 14284.0 5421.8 5467.2 5484.1 5560.9 5562.0 1000.5 1001.1 1012.9 1021.4 1045.8 28705.7 28861.7 29388.3 29426.0 29587.0 1080.9 1083.1 1085.1 1086.6 1092.4 15114.3 15476.6 15502.4 15582.8 15615.9 309341.0 309494.0 309823.0 312685.0 312827.0 58061.1 58214.0 58238.1 58251.1 58365.1 1480.8 1484.3 1531.4 1532.6 1543.0 28340.1 28709.0 28959.9 29396.3 29685.7 42202.6 42318.6 42346.8 42377.8 42393.2 310712.0 311124.0 311346.0 311398.0 311981.0 26066.9 26414.6 30004.0 31192.2 33851.3 35485.1 35998.5 43340.3 43893.6 44003.1 30716.7 31536.7 31757.5 31779.8 31788.4 30832.4 31151.0 31186.0 31490.2 31633.7 36085.3 36090.5 36897.3 37824.2 40176.2 30323.2 30651.0 30697.1 30851.4 31104.8 24666.9 25933.9 25937.2 25985.0 26253.0 12:46:10 INFO - PROCESS | 27589 | 12:46:10 INFO - PROCESS | 27589 | [#2] jquery.html Cycles:180 Average:38550.76 Median:21324.68 stddev:112191.33 (526.1%) stddev-sans-first:112469.58 12:46:10 INFO - PROCESS | 27589 | Values: 450.6 461.4 465.4 476.8 477.2 20278.8 20405.1 21165.0 21310.7 21338.7 21436.7 22417.1 22445.0 22571.3 23111.2 6904.6 6969.2 6975.1 7006.9 7052.0 6695.8 6950.2 6952.8 6954.4 6958.5 6948.2 7447.2 7696.2 7737.8 7738.9 7646.2 7667.6 7809.1 7835.4 7866.0 6965.3 7629.8 7638.8 7641.8 7645.2 26012.2 26104.2 26445.3 26568.5 26729.6 2976.7 3011.9 3084.8 3093.8 3195.8 15827.8 16425.0 16621.9 16840.2 17074.9 2222.1 2255.7 2270.6 2330.8 2368.2 108451.5 108478.9 110563.0 114078.0 118272.0 17420.7 18047.5 18053.4 18278.9 18321.5 31628.8 31682.0 31709.8 31811.5 31823.0 10319.9 10373.6 10383.5 10398.2 10500.0 8867.4 8896.6 8941.8 9094.4 9129.0 8973.3 9097.7 9169.7 9259.7 9411.7 2950.5 3812.9 3878.2 4034.8 4052.8 934.6 1043.7 1064.5 1071.9 1083.6 21005.7 21143.0 21171.6 21193.6 21208.7 1102.9 1110.8 1113.3 1121.7 1122.9 11181.7 11598.2 11817.9 11833.2 11848.9 651503.0 656689.0 683326.0 705379.0 708907.0 109660.1 116199.4 116331.0 117880.4 118825.4 1300.4 1303.0 1358.2 1359.0 1359.8 17945.1 17945.6 18356.9 18700.7 19257.3 53436.7 53860.2 54241.5 54537.0 55255.4 68403.7 68661.7 68868.7 69114.0 69141.7 9186.1 9287.0 12492.3 12508.3 12952.7 14628.0 14629.7 14697.0 14709.6 14800.9 12579.8 12609.2 12869.0 12981.5 13328.1 11615.2 12187.9 12471.4 12514.9 12574.8 13269.3 13423.0 13432.7 13438.0 13591.3 12895.4 13116.7 13149.7 13152.7 13174.5 12455.6 12512.7 12604.2 12821.2 12827.2 12:46:10 INFO - PROCESS | 27589 | 12:46:10 INFO - PROCESS | 27589 | [#3] mootools.html Cycles:180 Average:7136.05 Median:2426.21 stddev:4521.11 (186.3%) stddev-sans-first:4514.31 12:46:10 INFO - PROCESS | 27589 | Values: 1549.6 1554.4 1563.8 1572.1 1575.3 10783.7 11521.4 11540.6 11545.2 11552.7 11089.4 11390.1 11393.1 11404.7 11419.8 2500.1 2532.3 2535.2 2535.4 2537.7 2412.3 2457.2 2507.1 2515.1 2516.0 2440.1 2440.9 2462.9 2464.2 2471.1 2331.1 2331.3 2354.0 2354.5 2391.9 2309.0 2327.2 2334.4 2339.7 2340.7 11451.3 11654.2 11716.5 11718.2 11726.8 5767.7 5886.5 5901.2 5901.4 5908.8 3515.9 3557.6 3591.6 3653.3 3680.8 1425.8 1428.3 1434.6 1446.6 1455.4 16560.8 16879.5 17007.3 17019.0 17038.7 4605.9 4659.2 4750.1 4776.8 4779.6 12910.4 12947.2 12974.4 13002.2 13003.0 5967.3 6011.1 6026.5 6031.3 6032.6 5398.6 5458.1 5488.1 5512.0 5533.2 3957.1 4003.3 4023.8 4053.3 4093.8 1003.2 1003.6 1010.9 1014.1 1015.2 1072.9 1076.4 1086.8 1089.9 1094.4 6993.3 7107.0 7143.9 7194.3 7294.0 1042.8 1060.9 1061.9 1078.0 1089.6 4645.4 4650.6 4661.4 4690.9 4743.1 14092.2 14795.9 14891.6 14896.8 14900.9 12357.8 12793.1 14776.2 14789.9 14839.9 10335.6 10409.3 10447.6 10507.6 10967.3 7211.9 7353.6 7447.9 7456.9 7566.2 12981.5 13011.5 13033.5 13212.1 13347.0 13308.9 13312.0 13410.1 13438.7 13515.4 9037.3 9162.3 9188.3 9247.5 9292.2 10857.9 10874.9 10892.1 10998.1 11015.7 9163.1 9223.2 9338.5 9523.7 9563.5 9039.5 9053.5 9085.9 9203.0 9385.3 8116.0 8131.4 8218.2 8224.6 8226.5 6228.0 6294.2 6328.1 6336.9 6340.5 6507.4 6514.2 6565.0 6703.3 6751.8 12:46:10 INFO - PROCESS | 27589 | 12:46:10 INFO - PROCESS | 27589 | [#4] prototype.html Cycles:180 Average:4834.52 Median:5320.37 stddev:1278.52 (24.0%) stddev-sans-first:1238.38 12:46:10 INFO - PROCESS | 27589 | Values: 417.5 440.2 440.6 446.2 451.2 5201.0 5305.7 5328.4 5342.7 5347.6 5097.6 5200.3 5260.6 5261.4 5264.9 4455.0 4461.6 4471.4 4479.1 4487.8 4476.9 4483.8 4486.8 4510.9 4519.4 4395.2 4518.8 4525.5 4532.2 4542.3 4403.7 4415.7 4432.0 4484.8 4510.7 4159.8 4172.5 4181.3 4188.8 4198.2 5098.5 5121.9 5125.8 5133.2 5156.1 4925.1 5101.9 5130.7 5131.4 5154.4 5501.3 5520.9 5539.6 5542.2 5560.8 4524.6 4599.4 4604.9 4644.6 4691.4 5517.3 5613.7 5663.0 5772.8 5789.7 4713.0 4727.8 4735.5 4752.6 4761.7 4785.2 4792.6 4796.4 4800.8 4827.3 4959.2 5054.9 5074.9 5109.1 5164.9 5527.4 5527.6 5535.2 5541.5 5547.8 1622.4 1630.9 1641.2 1646.9 1656.2 1237.5 1254.4 1265.9 1267.3 1283.6 5306.2 5321.4 5353.7 5368.2 5382.7 5640.2 5641.4 5683.0 5683.0 5704.7 5131.4 5402.0 5424.3 5425.8 5441.6 2976.0 2979.7 2993.6 3010.9 3017.5 5696.8 5855.7 5864.6 5931.7 5965.4 5706.0 5766.2 5785.7 5796.3 5832.4 5844.8 5861.9 5879.0 5880.5 5913.7 5350.6 5547.8 5606.9 5632.9 5653.5 5393.6 5592.9 5602.4 5614.9 5617.3 5745.6 5831.4 5913.4 5927.8 5956.4 4733.8 4740.6 4756.0 4756.7 4761.2 5464.2 5671.7 5699.7 5710.7 5841.0 5508.7 5522.4 5532.6 5533.3 5573.6 5494.5 5546.9 5555.8 5621.4 5634.4 5232.4 5459.0 5494.5 5534.8 5538.3 5544.6 5555.0 5573.3 5585.8 5585.9 5319.3 5434.2 5492.8 5520.2 5522.7 12:46:10 INFO - PROCESS | 27589 | 12:46:10 INFO - PROCESS | 27589 | [#5] yui.html Cycles:180 Average:18000.30 Median:44808.05 stddev:21675.30 (48.4%) stddev-sans-first:21697.38 12:46:10 INFO - PROCESS | 27589 | Values: 746.2 779.9 824.2 853.9 868.3 16875.6 17207.0 18005.8 18008.8 18055.3 16419.8 16559.8 16607.4 16665.6 16669.5 867.8 877.0 887.8 893.7 905.6 886.3 887.7 892.2 892.7 893.3 929.4 935.2 936.0 936.9 937.1 911.2 917.6 920.0 921.1 922.1 682.2 683.2 683.4 683.5 684.3 16000.8 16056.8 16206.2 16213.7 16453.3 15079.2 15325.8 15390.2 15448.9 15740.4 13064.3 13396.5 13409.5 13500.3 13763.0 4048.9 4054.3 4093.6 4177.3 4227.3 44714.3 49723.0 53087.2 78459.0 79851.6 12112.5 12531.5 12546.4 12565.4 12843.0 21312.1 21540.9 21604.5 21621.9 21802.1 8974.2 9161.2 9222.8 9295.5 9310.6 5050.0 5145.0 5180.8 5190.9 5232.8 3852.6 3903.6 3921.8 3922.1 3952.0 671.5 684.1 684.4 688.1 699.9 780.4 781.9 804.3 806.1 807.9 11427.2 11533.5 11882.0 11948.8 12245.8 703.3 703.8 717.5 719.5 721.0 5049.2 5347.9 5424.2 5425.3 5464.2 77645.0 77988.0 80095.0 83662.6 85463.6 70359.6 70494.6 72524.0 73858.3 75277.2 64041.0 64919.0 65190.7 65335.0 65934.7 10051.7 10293.2 10376.4 10401.6 10407.0 44901.8 45619.3 45813.8 45826.0 46880.3 48845.0 49976.3 50788.7 50886.2 51818.7 14123.5 14301.5 14447.6 14505.3 14560.1 16601.2 16921.3 16926.5 17670.9 17794.3 15399.7 15446.6 16083.6 16306.9 16409.5 14549.9 14928.8 15319.5 15670.3 15811.9 14084.2 14664.9 14995.3 15423.8 15505.3 10323.0 10717.7 11045.2 11102.4 11618.6 12765.6 12831.7 12888.2 12906.5 13010.6 12:46:10 INFO - PROCESS | 27589 | -------- Summary: end -------- 12:46:10 INFO - PROCESS | 27589 | 12:46:11 INFO - TEST-INFO | 27589: exit 0 12:46:11 INFO - TEST-OK | dromaeo_css | took 1115659ms 12:46:11 INFO - TEST-START | kraken 12:46:11 INFO - operating with platform_type : linux_ 12:46:11 INFO - Initialising browser for kraken test... 12:46:11 INFO - TEST-INFO | started process 27890 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4zmNXi/profile http://localhost:60710/getInfo.html) 12:46:15 INFO - PROCESS | 27890 | __metrics Screen width/height:1600/1200 12:46:15 INFO - PROCESS | 27890 | colorDepth:24 12:46:15 INFO - PROCESS | 27890 | Browser inner width/height: 1024/697 12:46:15 INFO - PROCESS | 27890 | __metrics 12:46:16 INFO - PROCESS | 27890 | 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" 12:46:16 INFO - PROCESS | 27890 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:46:17 INFO - PROCESS | 27890 | console.error: PushService: 12:46:17 INFO - PROCESS | 27890 | stateChangeProcessEnqueue: Error transitioning state 12:46:17 INFO - PROCESS | 27890 | UnknownError 12:46:17 INFO - TEST-INFO | 27890: exit 0 12:46:17 INFO - Browser initialized. 12:46:17 INFO - Running cycle 1/1 for kraken test... 12:46:17 INFO - TEST-INFO | started process 27984 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4zmNXi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:46:29 INFO - PROCESS | 27984 | RSS: Main: 141971456 12:46:29 INFO - PROCESS | 27984 | 12:46:31 INFO - PROCESS | 27984 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60710/tests/kraken/driver.html?testName=audio-beat-detection) 12:46:32 INFO - PROCESS | 27984 | RSS: Main: 184123392 12:46:32 INFO - PROCESS | 27984 | 12:46:35 INFO - PROCESS | 27984 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60710/tests/kraken/driver.html?testName=audio-dft) 12:46:35 INFO - PROCESS | 27984 | RSS: Main: 173424640 12:46:35 INFO - PROCESS | 27984 | 12:46:38 INFO - PROCESS | 27984 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60710/tests/kraken/driver.html?testName=audio-fft) 12:46:39 INFO - PROCESS | 27984 | RSS: Main: 265314304 12:46:39 INFO - PROCESS | 27984 | 12:46:41 INFO - PROCESS | 27984 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60710/tests/kraken/driver.html?testName=audio-oscillator) 12:46:41 INFO - PROCESS | 27984 | RSS: Main: 171925504 12:46:41 INFO - PROCESS | 27984 | 12:46:43 INFO - PROCESS | 27984 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60710/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:46:44 INFO - PROCESS | 27984 | RSS: Main: 174481408 12:46:44 INFO - PROCESS | 27984 | 12:46:47 INFO - PROCESS | 27984 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60710/tests/kraken/driver.html?testName=imaging-darkroom) 12:46:48 INFO - PROCESS | 27984 | RSS: Main: 209199104 12:46:48 INFO - PROCESS | 27984 | 12:46:51 INFO - PROCESS | 27984 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60710/tests/kraken/driver.html?testName=imaging-desaturate) 12:46:52 INFO - PROCESS | 27984 | RSS: Main: 211062784 12:46:52 INFO - PROCESS | 27984 | 12:46:54 INFO - PROCESS | 27984 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60710/tests/kraken/driver.html?testName=json-parse-financial) 12:46:55 INFO - PROCESS | 27984 | RSS: Main: 210837504 12:46:55 INFO - PROCESS | 27984 | 12:46:56 INFO - PROCESS | 27984 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60710/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:46:57 INFO - PROCESS | 27984 | RSS: Main: 173871104 12:46:57 INFO - PROCESS | 27984 | 12:46:59 INFO - PROCESS | 27984 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:46:59 INFO - PROCESS | 27984 | RSS: Main: 180330496 12:46:59 INFO - PROCESS | 27984 | 12:47:01 INFO - PROCESS | 27984 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:47:02 INFO - PROCESS | 27984 | RSS: Main: 185090048 12:47:02 INFO - PROCESS | 27984 | 12:47:04 INFO - PROCESS | 27984 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:47:05 INFO - PROCESS | 27984 | RSS: Main: 180736000 12:47:05 INFO - PROCESS | 27984 | 12:47:08 INFO - PROCESS | 27984 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:47:08 INFO - PROCESS | 27984 | RSS: Main: 184967168 12:47:08 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60710/tests/kraken/driver.html?testName=ai-astar) 12:47:10 INFO - PROCESS | 27984 | RSS: Main: 237780992 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | __start_tp_report 12:47:10 INFO - PROCESS | 27984 | _x_x_mozilla_page_load 12:47:10 INFO - PROCESS | 27984 | _x_x_mozilla_page_load_details 12:47:10 INFO - PROCESS | 27984 | |i|pagename|runs| 12:47:10 INFO - PROCESS | 27984 | |0;ai-astar;104;112;112;113;112;112;112;112;111;112 12:47:10 INFO - PROCESS | 27984 | |1;audio-beat-detection;147;115;114;152;116;117;117;118;129;114 12:47:10 INFO - PROCESS | 27984 | |2;audio-dft;160;160;162;160;160;162;161;162;161;161 12:47:10 INFO - PROCESS | 27984 | |3;audio-fft;74;75;76;109;75;75;75;76;93;76 12:47:10 INFO - PROCESS | 27984 | |4;audio-oscillator;82;81;105;82;82;82;93;82;81;104 12:47:10 INFO - PROCESS | 27984 | |5;imaging-gaussian-blur;113;113;113;113;114;113;112;113;113;113 12:47:10 INFO - PROCESS | 27984 | |6;imaging-darkroom;146;146;146;146;146;146;147;146;146;147 12:47:10 INFO - PROCESS | 27984 | |7;imaging-desaturate;93;94;94;94;94;95;94;95;94;93 12:47:10 INFO - PROCESS | 27984 | |8;json-parse-financial;55;54;54;52;54;55;55;54;54;54 12:47:10 INFO - PROCESS | 27984 | |9;json-stringify-tinderbox;52;52;52;52;52;52;52;52;52;52 12:47:10 INFO - PROCESS | 27984 | |10;stanford-crypto-aes;79;85;76;79;81;82;79;83;80;98 12:47:10 INFO - PROCESS | 27984 | |11;stanford-crypto-ccm;130;130;119;119;129;132;117;119;118;126 12:47:10 INFO - PROCESS | 27984 | |12;stanford-crypto-pbkdf2;158;164;159;157;164;162;156;162;165;158 12:47:10 INFO - PROCESS | 27984 | |13;stanford-crypto-sha256-iterative;61;60;59;59;58;65;58;58;57;56 12:47:10 INFO - PROCESS | 27984 | __end_tp_report 12:47:10 INFO - PROCESS | 27984 | __start_cc_report 12:47:10 INFO - PROCESS | 27984 | _x_x_mozilla_cycle_collect,1603 12:47:10 INFO - PROCESS | 27984 | __end_cc_report 12:47:10 INFO - PROCESS | 27984 | __startTimestamp1496692030796__endTimestamp 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | ------- Summary: start ------- 12:47:10 INFO - PROCESS | 27984 | Number of tests: 14 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:2.57 (2.3%) stddev-sans-first:0.50 12:47:10 INFO - PROCESS | 27984 | Values: 104.0 112.0 112.0 113.0 112.0 112.0 112.0 112.0 111.0 112.0 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | [#1] audio-beat-detection Cycles:10 Average:123.90 Median:117.50 stddev:14.21 (12.1%) stddev-sans-first:12.37 12:47:10 INFO - PROCESS | 27984 | Values: 147.0 115.0 114.0 152.0 116.0 117.0 117.0 118.0 129.0 114.0 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.88 (0.5%) stddev-sans-first:0.87 12:47:10 INFO - PROCESS | 27984 | Values: 160.0 160.0 162.0 160.0 160.0 162.0 161.0 162.0 161.0 161.0 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | [#3] audio-fft Cycles:10 Average:80.40 Median:75.50 stddev:11.51 (15.2%) stddev-sans-first:11.97 12:47:10 INFO - PROCESS | 27984 | Values: 74.0 75.0 76.0 109.0 75.0 75.0 75.0 76.0 93.0 76.0 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | [#4] audio-oscillator Cycles:10 Average:87.40 Median:82.00 stddev:9.69 (11.8%) stddev-sans-first:10.07 12:47:10 INFO - PROCESS | 27984 | Values: 82.0 81.0 105.0 82.0 82.0 82.0 93.0 82.0 81.0 104.0 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 12:47:10 INFO - PROCESS | 27984 | Values: 113.0 113.0 113.0 113.0 114.0 113.0 112.0 113.0 113.0 113.0 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 12:47:10 INFO - PROCESS | 27984 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 147.0 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 12:47:10 INFO - PROCESS | 27984 | Values: 93.0 94.0 94.0 94.0 94.0 95.0 94.0 95.0 94.0 93.0 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:0.88 (1.6%) stddev-sans-first:0.87 12:47:10 INFO - PROCESS | 27984 | Values: 55.0 54.0 54.0 52.0 54.0 55.0 55.0 54.0 54.0 54.0 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | [#9] json-stringify-tinderbox Cycles:10 Average:52.00 Median:52.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 12:47:10 INFO - PROCESS | 27984 | Values: 52.0 52.0 52.0 52.0 52.0 52.0 52.0 52.0 52.0 52.0 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:81.50 stddev:6.09 (7.5%) stddev-sans-first:6.35 12:47:10 INFO - PROCESS | 27984 | Values: 79.0 85.0 76.0 79.0 81.0 82.0 79.0 83.0 80.0 98.0 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:127.50 stddev:6.01 (4.7%) stddev-sans-first:5.95 12:47:10 INFO - PROCESS | 27984 | Values: 130.0 130.0 119.0 119.0 129.0 132.0 117.0 119.0 118.0 126.0 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.50 Median:162.00 stddev:3.27 (2.0%) stddev-sans-first:3.35 12:47:10 INFO - PROCESS | 27984 | Values: 158.0 164.0 159.0 157.0 164.0 162.0 156.0 162.0 165.0 158.0 12:47:10 INFO - PROCESS | 27984 | 12:47:10 INFO - PROCESS | 27984 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.10 Median:59.00 stddev:2.51 (4.3%) stddev-sans-first:2.57 12:47:10 INFO - PROCESS | 27984 | Values: 61.0 60.0 59.0 59.0 58.0 65.0 58.0 58.0 57.0 56.0 12:47:10 INFO - PROCESS | 27984 | -------- Summary: end -------- 12:47:10 INFO - PROCESS | 27984 | 12:47:11 INFO - TEST-INFO | 27984: exit 0 12:47:11 INFO - TEST-OK | kraken | took 60274ms 12:47:11 INFO - SUITE-END | took 1175s 12:47:11 INFO - Completed test suite (00:19:36) 12:47:11 INFO - javascript benchmark 12:47:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7917.406557764878, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3390.288039511702, 3469.98265008675, 3488.4244099723533, 3507.403741363968, 3528.752987290889, 5822.155787410823, 5883.558733095019, 5890.587658863881, 5892.64644121353, 5899.52803775698, 5900.940990590093, 5925.02237130874, 5925.288965324167, 5927.170196172528, 5941.64350138992, 5344.331958505197, 5370.248165256867, 5405.486478784529, 5412.702301373423, 5454.290942177511, 5224.033553792541, 5260.421353651095, 5466.535344495723, 5470.480304371078, 5472.945270547303, 5305.761240744176, 5360.035193665139, 5434.510894019538, 5444.6188766786145, 5451.918221226685, 5457.781688732446, 5457.918131228034, 5458.426865179139, 5463.945360546423, 5464.890702185934, 5184.6629969052265, 5317.069512835238, 5357.249985002103, 5398.0, 5452.318460192476, 5735.11105778605, 5763.913541296842, 5830.067189249742, 5832.854178645525, 5837.5913686041995, 6299.149614801989, 6312.400321969406, 6335.809925702236, 6339.524535659844, 6347.143135676717, 6378.170837791104, 6381.1066450696935, 6391.520635952322, 6394.616323020634, 6402.167718196652, 5696.430356964312, 5727.312722473288, 5834.299884013945, 5860.033094539439, 5880.353161152269, 6414.518911081687, 6433.131527243856, 6458.903116453257, 6460.483161347081, 6467.708953097122, 6100.755969761249, 6226.813195604139, 6228.56400051992, 6232.376762323732, 6232.594881332699, 6077.453029227391, 6135.294441139237, 6174.969125154345, 6277.089821975879, 6287.748490060347, 6283.8114856554375, 6292.937070629327, 6295.622262664255, 6305.337939516285, 6305.96847015762, 6057.242844644419, 6065.909011364833, 6105.786297479655, 6109.389061093855, 6293.496520278367, 4744.833930812405, 4751.786169622375, 4795.712257264506, 4854.587360074363, 4857.465678775332, 4284.10033892886, 4298.892527686833, 4309.396684464177, 4325.870223893288, 4339.956600434019, 5833.620814647034, 5868.031774757117, 5929.14027466015, 5982.521398288127, 5995.940040599626, 6371.39471750183, 6387.808365749035, 6388.233411990591, 6389.520785941073, 6394.136791533177, 6027.30685971119, 6034.788782392595, 6035.245594300713, 6035.245594300713, 6042.456178943916, 5236.895262094737, 5278.181881808326, 5311.3891902431715, 5413.458654134555, 5437.184422336682, 6532.346765323429, 6574.572652777554, 6584.0, 6590.8022759318255, 6595.637239951849, 6298.905516417349, 6358.968205158944, 6360.395762402564, 6368.936310636835, 6376.489880809617, 6315.96842015787, 6356.3325850785, 6579.144711187515, 6582.210134783857, 6589.835254118685, 6120.081455037999, 6146.231721034871, 6207.720652570555, 6222.377762223741, 6226.1905952225925, 6344.968275158594, 6349.460295874902, 6349.809505714836, 6350.428461438492, 6353.428191462605, 5987.850303742267, 6131.509479241561, 6193.81418557444, 6195.349488303663, 6201.131841542278, 6056.636601803906, 6069.977774573452, 6086.756529738761, 6161.784337548164, 6172.814815555541, 6135.202423684892, 6153.3538978408415, 6165.907511387422, 6176.104464852661, 6201.720922558406, 5988.341282458839, 6022.698865056817, 6059.757609695739, 6260.436560709558, 6262.123302737529, 5988.221531200916, 6021.668808215591, 6090.299615544269, 6120.9693951531735, 6126.0], "value": 5810.588840621815, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.875642384371, 756.8826831841063, 778.5134291068081, 818.3248819723726, 824.8020475085981, 32841.50737738933, 46359.30461043083, 46924.0615187696, 47067.0, 47192.05615887685, 45020.54979450205, 45318.32022519665, 45351.09297814042, 45352.09295814082, 45359.77320113395, 7844.960775196116, 7957.801054973614, 9081.091890810916, 9195.172434480895, 9202.401843880127, 10946.343219406821, 11203.0, 11523.942380288086, 11536.653900383002, 11543.365114918675, 11283.435828208598, 11300.660980170607, 11304.660860174208, 11310.377929213932, 11319.32084074954, 11194.66416007517, 11224.607138750105, 11232.213745037892, 11240.437978101103, 11254.381009044499, 10917.344959302387, 10978.066864316543, 10997.670069897837, 10998.12015038795, 11001.394923279217, 43424.78287608573, 43568.564314356765, 43658.0, 43748.56251437509, 43784.0, 4263.275243208631, 4301.462317210348, 4376.890577735582, 4462.665300102472, 4467.843625473093, 31274.843625781727, 31487.0, 31677.20806979821, 31957.201069973205, 33033.50449743256, 4390.538993405678, 4416.889577760549, 4417.293233082723, 4452.421185245929, 4474.284114541658, 242819.0, 243881.0, 244054.0, 244058.0, 244560.0, 41769.164616707494, 41838.581614183466, 41948.79025604852, 41977.580224197976, 55637.0, 57338.7133064332, 57384.0, 57628.0, 57633.13550296749, 57652.135217971765, 16873.746893796604, 17034.403795867333, 17149.0, 17184.914075429544, 17190.91404542994, 12334.383280835922, 12462.065345098972, 15174.848251517564, 15190.620234494227, 15204.46784362542, 13986.86013139876, 14130.50543230982, 14213.644658883404, 14234.501792437215, 14284.0, 5421.83734487966, 5467.1799230115, 5484.149956756709, 5560.860978475571, 5561.972190139023, 1000.494750151169, 1001.1190152665607, 1012.9493525323704, 1021.3820638513592, 1045.7960697664034, 28705.712942870723, 28861.711382885904, 29388.26529336741, 29426.0, 29587.0, 1080.9243352965218, 1083.1118482843992, 1085.137315833914, 1086.5545126498098, 1092.3773449134076, 15114.319855606524, 15476.613084672748, 15502.379904803682, 15582.83796835642, 15615.921920390552, 309341.0, 309494.0, 309823.0, 312685.0, 312827.0, 58061.12908306379, 58214.0, 58238.12642810361, 58251.12623310654, 58365.12452313219, 1480.8148981377328, 1484.2801241397913, 1531.433369653213, 1532.6398296400114, 1543.0433131458567, 28340.149795506168, 28709.0, 28959.85520072386, 29396.26509337198, 29685.703142968294, 42202.577974219865, 42318.57681423146, 42346.78826605847, 42377.78811105925, 42393.1521369577, 310712.0, 311124.0, 311346.0, 311398.0, 311981.0, 26066.86966565155, 26414.603780942918, 30004.0, 31192.22019449532, 33851.32297354088, 35485.112872178404, 35998.4600231002, 43340.34989475223, 43893.561064388945, 44003.11993760043, 30716.692833072277, 31536.68463315429, 31757.523637145, 31779.841100795275, 31788.364232714754, 30832.383352333276, 31151.0, 31186.0, 31490.212744680648, 31633.683663163072, 36085.27829443449, 36090.45864311985, 36897.26205475929, 37824.243515130096, 40176.1964760709, 30323.241918952204, 30651.0, 30697.079087627408, 30851.38297233998, 31104.844475777478, 24666.876665616557, 25933.870330648228, 25937.22188334353, 25985.0, 26253.0], "value": 19063.695008792263, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.5517010574479, 461.40017976630384, 465.4298484356664, 476.8116593945391, 477.1840153337792, 20278.797212027876, 20405.08177132029, 21165.0, 21310.680339794875, 21338.679919801216, 21436.678449823226, 22417.103315867385, 22445.0, 22571.322860314176, 23111.191108311214, 6904.5512041717375, 6969.23338432772, 6975.0932378790685, 7006.929930700704, 7052.04797352356, 6695.799126026227, 6950.235474097871, 6952.756653517128, 6954.40887524561, 6958.547694399873, 6948.200956889972, 7447.180810110884, 7696.191899850519, 7737.845243095135, 7738.922610773905, 7646.197149299299, 7667.5782831944225, 7809.101953275389, 7835.373170146375, 7866.0, 6965.303469653045, 7629.771106866803, 7638.8472230555635, 7641.808954776119, 7645.15903250642, 26012.219633411027, 26104.216873493635, 26445.3388665283, 26568.46863062747, 26729.599056014176, 2976.7320941115404, 3011.864466099031, 3084.7686423518103, 3093.752499800011, 3195.785601471426, 15827.762583561256, 16425.0, 16621.916890415472, 16840.157992100347, 17074.914625426918, 2222.100049479963, 2255.7180352455944, 2270.636698128308, 2330.7552706965694, 2368.2303251443177, 108451.45774271157, 108478.91521084767, 110563.0, 114078.0, 118272.0, 17420.738688919675, 18047.458576242734, 18053.368132115313, 18278.908605456887, 18321.450356489328, 31628.841855791034, 31682.0, 31709.84145079306, 31811.522827157612, 31823.0, 10319.896801031893, 10373.636922707668, 10383.532741026673, 10398.168146548258, 10500.0, 8867.379283450096, 8896.644134234559, 8941.776455588662, 9094.408863423856, 9128.954355228314, 8973.32700047499, 9097.681581144629, 9169.724908252763, 9259.67591134307, 9411.717648470556, 2950.4836653585535, 3812.8856134316015, 3878.185581028016, 4034.8184331705143, 4052.8176232069036, 934.5981228071859, 1043.7025447747508, 1064.4943651765504, 1071.885239351066, 1083.5611577311201, 21005.68491472629, 21143.0, 21171.576568468543, 21193.576128477343, 21208.681869771965, 1102.8841971593029, 1110.8056090184186, 1113.276370359273, 1121.6522877907876, 1122.898939095469, 11181.664550063511, 11598.18812683104, 11817.881821181678, 11833.171677982631, 11848.88151118495, 651503.0, 656689.0, 683326.0, 705379.0, 708907.0, 109660.06529706182, 116199.41900290444, 116331.0, 117880.41059794647, 118825.40587297008, 1300.408314217048, 1303.0, 1358.219024061149, 1358.9660258493418, 1359.7756370198806, 17945.102744862965, 17945.641087178443, 18356.908215458836, 18700.7194892074, 19257.32599359016, 53436.73281633567, 53860.19209711936, 54241.45758542364, 54537.0, 55255.44744552664, 68403.65798170977, 68661.65669171623, 68868.6556567214, 69114.0, 69141.65429172822, 9186.08139186076, 9286.95356523213, 12492.312922789333, 12508.312042837731, 12952.676183095498, 14628.0, 14629.707405852036, 14697.04469209498, 14709.632259193606, 14800.925995369955, 12579.811302830281, 12609.180403273758, 12869.034822388545, 12981.545645902348, 13328.067035307824, 11615.245009074382, 12187.878121218675, 12471.376431178587, 12514.874851251736, 12574.811377829157, 13269.336533173495, 13423.0, 13432.731345373233, 13438.0, 13591.252481113634, 12895.388076490439, 13116.737665246832, 13149.671258218621, 13152.736945261233, 13174.538891138764, 12455.626331210078, 12512.687182820502, 12604.243745375306, 12821.166624169398, 12827.230366178059], "value": 11769.995645060837, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1549.6125968507872, 1554.35494269878, 1563.8201606815212, 1572.1431819658285, 1575.3226112771508, 10783.676489705302, 11521.423928803548, 11540.596079137233, 11545.249558778683, 11552.65342039738, 11089.390083545402, 11390.145739069581, 11393.145514086435, 11404.714882127952, 11419.828702569468, 2500.1499490173337, 2532.2529853693204, 2535.150724507292, 2535.4041800177038, 2537.746225377466, 2412.3124909400826, 2457.213691618683, 2507.0723832182116, 2515.1197081021587, 2515.9874200629065, 2440.1093600835743, 2440.9389765255924, 2462.938426539342, 2464.186818349949, 2471.085698291635, 2331.1258278145697, 2331.2773040357542, 2354.0, 2354.458474550854, 2391.8564886106888, 2309.041747674713, 2327.2203811723093, 2334.4163959010248, 2339.7075365579303, 2340.742518322983, 11451.255668381611, 11654.242474239232, 11716.472758725879, 11718.179727419085, 11726.765464690743, 5767.6539407635255, 5886.470217680387, 5901.23283973085, 5901.3803550627, 5908.793192238294, 3515.876944306937, 3557.6442355764475, 3591.6408359164134, 3653.3241350350004, 3680.7607505512055, 1425.8289005319325, 1428.278719246784, 1434.6054834920376, 1446.5804916574182, 1455.4437764152594, 16560.83439165617, 16879.493615191564, 17007.319707211696, 17019.0, 17038.74441883373, 4605.8848528786375, 4659.184642687516, 4750.12122757291, 4776.785044672929, 4779.593734532604, 12910.354482275849, 12947.223166610034, 12974.416151273215, 13002.219866807833, 13003.0, 5967.254093238345, 6011.098335249748, 6026.5178785697035, 6031.336552979169, 6032.607880487754, 5398.595105367113, 5458.099413596712, 5488.094464413407, 5512.035393806068, 5533.197686335539, 3957.050307926077, 4003.319435695939, 4023.839046438168, 4053.310937140693, 4093.7543747375253, 1003.2375394699934, 1003.5684655598163, 1010.8685870836745, 1014.0822555587206, 1015.1624909449733, 1072.8658917635296, 1076.4133547216786, 1086.8315411111291, 1089.8637670291214, 1094.4199574225672, 6993.300669933068, 7106.964465177641, 7143.8571228576175, 7194.258985327705, 7294.0, 1042.8279333909973, 1060.867391576053, 1061.8991195836384, 1077.9622713205158, 1089.591403223791, 4645.442546894395, 4650.55819697135, 4661.370714953506, 4690.929636055462, 4743.0988112258565, 14092.154470731788, 14795.852041479448, 14891.553253402415, 14896.776548351783, 14900.850991490162, 12357.75284494305, 12793.104482686123, 14776.187309698069, 14789.852101479062, 14839.851601483848, 10335.586576537005, 10409.330658940673, 10447.634332798316, 10507.579696812041, 10967.287126336922, 7211.8557628848175, 7353.5955522446775, 7447.92552074494, 7456.925430745623, 7566.167721550735, 12981.480740770263, 13011.544595939096, 13033.47866085384, 13212.141210821266, 13347.0, 13308.866911330762, 13312.0, 13410.12834165776, 13438.664033399244, 13515.391807368494, 9037.322200834833, 9162.26701863836, 9188.310876684409, 9247.537623118951, 9292.163705266288, 10857.945710271399, 10874.891251087703, 10892.128629709763, 10998.06516446111, 11015.724606884893, 9163.129502697233, 9223.216026637543, 9338.486383248986, 9523.666671666459, 9563.521823908637, 9039.548022598976, 9053.456792592466, 9085.863712044193, 9203.0, 9385.296102792181, 8116.0, 8131.430799843954, 8218.21926916942, 8224.588770561568, 8226.547539885363, 6228.0, 6294.181756371826, 6328.082428047999, 6336.936630633819, 6340.461060809883, 6507.414332709889, 6514.153160089348, 6564.9671751640935, 6703.3296670332575, 6751.797446076625], "value": 5420.751069890752, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.5490470292085, 440.1746724890831, 440.5506383488843, 446.2413896376161, 451.2351564098853, 5200.959808038393, 5305.734713264341, 5328.413874473815, 5342.732863356831, 5347.572194224463, 5097.566706829914, 5200.271961925334, 5260.579153667707, 5261.447872877501, 5264.868378290545, 4455.0, 4461.620762235206, 4471.39636149119, 4479.081788233404, 4487.842925497608, 4476.865694029184, 4483.753393563353, 4486.8109950863045, 4510.872281929518, 4519.435070616173, 4395.230834603947, 4518.819247230123, 4525.456945166585, 4532.206863798839, 4542.273236282195, 4403.744932694167, 4415.690901636903, 4432.0, 4484.820607175741, 4510.729356238619, 4159.7920103994675, 4172.499300083979, 4181.310083836193, 4188.764314527191, 4198.244316023115, 5098.490150984909, 5121.948780512184, 5125.820596279113, 5133.204353325204, 5156.143788236232, 4925.128451188525, 5101.948980510185, 5130.717320669833, 5131.4098878629065, 5154.38147422308, 5501.257330260403, 5520.929651380732, 5539.556835453154, 5542.168674698787, 5560.833175004756, 4524.5701658342405, 4599.402077729907, 4604.88487787808, 4644.6052085572765, 4691.413573303337, 5517.2551705519645, 5613.719314034322, 5662.971685141548, 5772.826815195509, 5789.710514474302, 4713.0, 4727.787249573777, 4735.455422626442, 4752.643551733634, 4761.714297142183, 4785.186518291899, 4792.568668819823, 4796.400449943757, 4800.831970881072, 4827.348307978448, 4959.20652695567, 5054.873628159252, 5074.873128171826, 5109.131447653907, 5164.92252616211, 5527.364353099365, 5527.5577953764405, 5535.169724541351, 5541.473560011793, 5547.833564993057, 1622.3997121065045, 1630.910299933515, 1641.1958140511063, 1646.860016898577, 1656.1884676508423, 1237.5297386992886, 1254.4355040231933, 1265.9177153484993, 1267.2523211305374, 1283.57641978147, 5306.230596563477, 5321.414644389114, 5353.705546194997, 5368.248445217672, 5382.650127741685, 5640.238567793377, 5641.379448260688, 5682.971585142048, 5682.971585142131, 5704.657720536781, 5131.435542090367, 5401.972990135024, 5424.294841670637, 5425.755840987165, 5441.646292990943, 2975.9553606695918, 2979.746721528651, 2993.6407631084408, 3010.8946186883354, 3017.456857765623, 5696.778256479976, 5855.736491857877, 5864.589478736533, 5931.673757943355, 5965.403459654087, 5705.972924873563, 5766.154830454215, 5785.739641716133, 5796.275465566804, 5832.358440571534, 5844.766209351664, 5861.882762344814, 5878.970605146947, 5880.470757631748, 5913.733881975236, 5350.598705097055, 5547.750351234285, 5606.943930560642, 5632.88734225305, 5653.491185793298, 5393.595480339069, 5592.853465039576, 5602.355729091044, 5614.88770224585, 5617.269754931833, 5745.626534275259, 5831.434168965641, 5913.408659134052, 5927.8221653350465, 5956.374580668968, 4733.763311834326, 4740.620750340034, 4755.977464845075, 4756.738379389167, 4761.190597598347, 5464.23500709893, 5671.716414179357, 5699.717563548168, 5710.685912274865, 5841.014055221245, 5508.697021663847, 5522.420145884625, 5532.640378375554, 5533.280673512418, 5573.554115670656, 5494.450554944474, 5546.947629377156, 5555.805546806004, 5621.409751975983, 5634.4365563443325, 5232.362433914829, 5458.972705136449, 5494.472542630435, 5534.806281780118, 5538.307711536058, 5544.63959842609, 5554.97222513901, 5573.300143456285, 5585.804496842591, 5585.916211256753, 5319.335083114611, 5434.184872269191, 5492.752826122914, 5520.199571062093, 5522.696251706195], "value": 4465.402059685883, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.2313816768726, 779.9376049916008, 824.1962598747342, 853.9274161696255, 868.2533021601422, 16875.578110547227, 17207.0, 18005.81994180058, 18008.819911800878, 18055.277788888445, 16419.83580164198, 16559.751603725952, 16607.418740344092, 16665.583360415996, 16669.49991500254, 867.8134201146752, 876.9780755481132, 887.8224355128967, 893.7363477774072, 905.5924833824772, 886.2776836877956, 887.6893087419384, 892.2415946445534, 892.7366426904047, 893.2675206330812, 929.4330458420392, 935.1957316707626, 935.9859602105973, 936.8641546975684, 937.1003836317144, 911.1662828511903, 917.6283605139929, 920.0109881876975, 921.0789210789211, 922.1193759959257, 682.1711620381211, 683.1528904158857, 683.3542302524116, 683.4942142814332, 684.2575805251308, 16000.75998860018, 16056.75914861278, 16206.189690515426, 16213.675726485404, 16453.341866325332, 15079.246037698182, 15325.846741532554, 15390.15354155521, 15448.922755386264, 15740.370385184578, 13064.346782660925, 13396.464141434331, 13409.46362145513, 13500.257485838274, 13763.0, 4048.9454834858557, 4054.3107671695884, 4093.5701751315983, 4177.26897792885, 4227.302495088306, 44714.32928506075, 49723.0, 53087.203691944655, 78459.0, 79851.6007419965, 12112.454939527743, 12531.498740050478, 12546.372681365896, 12565.434555445026, 12843.0, 21312.147514099262, 21540.892295538735, 21604.45988850291, 21621.891890540446, 21802.127914883546, 8974.19232269099, 9161.175494205554, 9222.769430764285, 9295.488748118783, 9310.627574896927, 5049.990001999615, 5144.974275128675, 5180.84457466269, 5190.948090519047, 5232.764525596357, 3852.5954774748247, 3903.6486716195677, 3921.784301863425, 3922.1371298314325, 3951.980240098781, 671.5198632977445, 684.0696652552524, 684.3635419060322, 688.132952479879, 699.8635266123159, 780.4302858913026, 781.9374450043982, 804.3203493048364, 806.0891192952043, 807.8545861744825, 11427.200095993367, 11533.480993355319, 11882.0, 11948.761024779456, 12245.75508489843, 703.2615753458848, 703.7783098324012, 717.4798271253404, 719.4855678190119, 720.9639518024078, 5049.192129259301, 5347.946520534822, 5424.213489044067, 5425.348958124971, 5464.180372944011, 77645.0, 77988.0, 80095.0, 83662.58168709118, 85463.57268213619, 70359.64820175867, 70494.64752676204, 72524.0, 73858.26141738621, 75277.24722752812, 64041.0, 64919.0, 65190.674046629465, 65335.0, 65934.67032664806, 10051.748706282402, 10293.176545876162, 10376.377417354983, 10401.635942741968, 10407.0, 44901.77549112364, 45619.315710263705, 45813.77093114513, 45826.0, 46880.29679554878, 48845.0, 49976.25035624396, 50788.74605626948, 50886.236706450174, 51818.74090629523, 14123.505677301246, 14301.499447519287, 14447.566573002827, 14505.347259373564, 14560.053596516193, 16601.223814554014, 16921.323147073977, 16926.492205233862, 17670.939743615425, 17794.288228470716, 15399.692006160038, 15446.613834654223, 16083.597910051874, 16306.918465407596, 16409.507714768577, 14549.85450145485, 14928.776068358766, 15319.46381876629, 15670.299754592384, 15811.920940395225, 14084.15495070299, 14664.853351466776, 14995.325210365341, 15423.768643470132, 15505.30226139849, 10323.027797717707, 10717.732056698645, 11045.226834121535, 11102.444877756241, 11618.593349232737, 12765.553205637758, 12831.743365132832, 12888.162269452456, 12906.548270810477, 13010.609681709564], "value": 7804.333171686716, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1448.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 112.0, 113.0, 112.0, 112.0, 112.0, 112.0, 111.0, 112.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 115.0, 114.0, 152.0, 116.0, 117.0, 117.0, 118.0, 129.0, 114.0], "value": 123.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 162.0, 160.0, 160.0, 162.0, 161.0, 162.0, 161.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 109.0, 75.0, 75.0, 75.0, 76.0, 93.0, 76.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 105.0, 82.0, 82.0, 82.0, 93.0, 82.0, 81.0, 104.0], "value": 87.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 112.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 147.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 52.0, 54.0, 55.0, 55.0, 54.0, 54.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 52.0, 52.0, 52.0, 52.0, 52.0, 52.0, 52.0], "value": 52.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 85.0, 76.0, 79.0, 81.0, 82.0, 79.0, 83.0, 80.0, 98.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 130.0, 119.0, 119.0, 129.0, 132.0, 117.0, 119.0, 118.0, 126.0], "value": 123.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 164.0, 159.0, 157.0, 164.0, 162.0, 156.0, 162.0, 165.0, 158.0], "value": 160.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 59.0, 59.0, 58.0, 65.0, 58.0, 58.0, 57.0, 56.0], "value": 59.1, "unit": "score"}]}]} 12:47:12 INFO - Return code: 0 12:47:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:47:12 INFO - # TBPL SUCCESS # 12:47:12 INFO - Running post-action listener: _resource_record_post_action 12:47:12 INFO - [mozharness: 2017-06-05 19:47:12.146526Z] Finished run-tests step (success) 12:47:12 INFO - Running post-run listener: _resource_record_post_run 12:47:12 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 325705728; Read time: 8; Write time: 204172 12:47:12 INFO - TinderboxPrint: CPU usage
13.1% 12:47:12 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 12:47:12 INFO - TinderboxPrint: I/O write bytes / time
325,705,728 / 204,172 12:47:12 INFO - TinderboxPrint: CPU idle
8,252.2 (86.9%) 12:47:12 INFO - TinderboxPrint: CPU user
1,200.1 (12.6%) 12:47:12 INFO - TinderboxPrint: Swap in / out
0 / 0 12:47:12 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 90468352; Read time: 0; Write time: 78428 12:47:12 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 235237376; Read time: 0; Write time: 125744 12:47:12 INFO - Running post-run listener: _upload_blobber_files 12:47:12 INFO - Blob upload gear active. 12:47:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:47:12 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 12:47:12 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'] 12:47:12 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 12:47:13 INFO - (blobuploader) - INFO - Open directory for files ... 12:47:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:47:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:47:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:47:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:47:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:47:14 INFO - (blobuploader) - INFO - Done attempting. 12:47:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:47:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:47:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:47:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:47:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:47:16 INFO - (blobuploader) - INFO - Done attempting. 12:47:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:47:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:47:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:47:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:47:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:47:17 INFO - (blobuploader) - INFO - Done attempting. 12:47:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:47:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:47:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:47:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:47:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:47:25 INFO - (blobuploader) - INFO - Done attempting. 12:47:25 INFO - (blobuploader) - INFO - Iteration through files over. 12:47:25 INFO - Return code: 0 12:47:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:47:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:47:25 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/eb0143d35fbd254ec579aee99d66290c4e0c796eed530978df960d6fe39896dea5c1d401dfcfda3e182cc49b95640f28624290ece9d8c4795975b96b2f4da114", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fe909eaf482d47c091745a76e4ad5a1469ae0e331a96783f42c9c5ceff6f010037e7b26025b79874df0cff05502bc5cda644fa0d89bef1562b61f305afb5c1dc", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9461985e21cb060fe47c9a904b2184779ea74c01e3a927177d5d9e5f77afbf3b56d3e8bee905f7405b729f880c0a489e84bdc0d686692402bf255f2d28194ccc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ca219163d1275fce5968e77885d5c4cc24df134e112cfaa54f69520682801bcd74343682cb0ef3b91ea5fe81b9dd5d15d90e7688dd097df8c0a6505388338147"} 12:47:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:47:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:47:25 INFO - Contents: 12:47:25 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/eb0143d35fbd254ec579aee99d66290c4e0c796eed530978df960d6fe39896dea5c1d401dfcfda3e182cc49b95640f28624290ece9d8c4795975b96b2f4da114", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fe909eaf482d47c091745a76e4ad5a1469ae0e331a96783f42c9c5ceff6f010037e7b26025b79874df0cff05502bc5cda644fa0d89bef1562b61f305afb5c1dc", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9461985e21cb060fe47c9a904b2184779ea74c01e3a927177d5d9e5f77afbf3b56d3e8bee905f7405b729f880c0a489e84bdc0d686692402bf255f2d28194ccc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ca219163d1275fce5968e77885d5c4cc24df134e112cfaa54f69520682801bcd74343682cb0ef3b91ea5fe81b9dd5d15d90e7688dd097df8c0a6505388338147"} 12:47:25 INFO - Running post-run listener: copy_logs_to_upload_dir 12:47:25 INFO - Copying logs to upload dir... 12:47:25 INFO - mkdir: /builds/slave/test/build/upload/logs 12:47:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.005001 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-06-05 12:47:25.402791) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 12:47:25.406100) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/eb0143d35fbd254ec579aee99d66290c4e0c796eed530978df960d6fe39896dea5c1d401dfcfda3e182cc49b95640f28624290ece9d8c4795975b96b2f4da114", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fe909eaf482d47c091745a76e4ad5a1469ae0e331a96783f42c9c5ceff6f010037e7b26025b79874df0cff05502bc5cda644fa0d89bef1562b61f305afb5c1dc", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9461985e21cb060fe47c9a904b2184779ea74c01e3a927177d5d9e5f77afbf3b56d3e8bee905f7405b729f880c0a489e84bdc0d686692402bf255f2d28194ccc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ca219163d1275fce5968e77885d5c4cc24df134e112cfaa54f69520682801bcd74343682cb0ef3b91ea5fe81b9dd5d15d90e7688dd097df8c0a6505388338147"} build_url:https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011260 build_url: 'https://queue.taskcluster.net/v1/task/Bk19fZP9RwO3kgxz1JkymQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/eb0143d35fbd254ec579aee99d66290c4e0c796eed530978df960d6fe39896dea5c1d401dfcfda3e182cc49b95640f28624290ece9d8c4795975b96b2f4da114", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fe909eaf482d47c091745a76e4ad5a1469ae0e331a96783f42c9c5ceff6f010037e7b26025b79874df0cff05502bc5cda644fa0d89bef1562b61f305afb5c1dc", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9461985e21cb060fe47c9a904b2184779ea74c01e3a927177d5d9e5f77afbf3b56d3e8bee905f7405b729f880c0a489e84bdc0d686692402bf255f2d28194ccc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ca219163d1275fce5968e77885d5c4cc24df134e112cfaa54f69520682801bcd74343682cb0ef3b91ea5fe81b9dd5d15d90e7688dd097df8c0a6505388338147"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 12:47:25.430291) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 12:47:25.430643) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1496689860.31477-2059883907 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004415 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 12:47:25.514552) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 12:47:25.514871) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 12:47:25.515180) ========= ========= Total master_lag: 0.17 =========