builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-067 starttime: 1500338628.83 results: success (0) revision: efc0b1525edbd357818dc7195537364e76f709e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-17 17:43:48.830638) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-17 17:43:48.831051) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-17 17:43:48.831328) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-17 17:43:48.847157) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 17:43:48.847433) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-17 17:43:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2017-07-17 17:43:50 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122644 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 17:43:48.983550) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 17:43:48.983872) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016877 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 17:43:49.033615) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 17:43:49.033947) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev efc0b1525edbd357818dc7195537364e76f709e7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev efc0b1525edbd357818dc7195537364e76f709e7 --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=e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-17 17:43:51,092 truncating revision to first 12 chars 2017-07-17 17:43:51,093 Setting DEBUG logging. 2017-07-17 17:43:51,093 attempt 1/10 2017-07-17 17:43:51,093 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/efc0b1525edb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-17 17:43:51,698 unpacking tar archive at: mozilla-central-efc0b1525edb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.891684 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 17:43:49.938223) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-17 17:43:49.938545) ========= script_repo_revision: efc0b1525edbd357818dc7195537364e76f709e7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-17 17:43:49.938939) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-17 17:43:49.939204) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-17 17:43:49.946705) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-07-17 17:43:49.947006) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:43:52 INFO - MultiFileLogger online at 20170717 17:43:52 in /builds/slave/test 17:43:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:43:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:43:52 INFO - {'append_to_log': False, 17:43:52 INFO - 'base_work_dir': '/builds/slave/test', 17:43:52 INFO - 'blob_upload_branch': 'Firefox', 17:43:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:43:52 INFO - 'branch': 'Firefox', 17:43:52 INFO - 'buildbot_json_path': 'buildprops.json', 17:43:52 INFO - 'code_coverage': False, 17:43:52 INFO - 'config_files': ('talos/linux_config.py',), 17:43:52 INFO - 'default_actions': ('clobber', 17:43:52 INFO - 'read-buildbot-config', 17:43:52 INFO - 'download-and-extract', 17:43:52 INFO - 'populate-webroot', 17:43:52 INFO - 'create-virtualenv', 17:43:52 INFO - 'install', 17:43:52 INFO - 'setup-mitmproxy', 17:43:52 INFO - 'run-tests'), 17:43:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:43:52 INFO - 'disable_ccov_upload': False, 17:43:52 INFO - 'download_minidump_stackwalk': True, 17:43:52 INFO - 'download_symbols': 'ondemand', 17:43:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:43:52 INFO - 'tooltool.py': '/tools/tooltool.py', 17:43:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:43:52 INFO - '/tools/misc-python/virtualenv.py')}, 17:43:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:43:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:43:52 INFO - 'gecko_profile': False, 17:43:52 INFO - 'gecko_profile_interval': 0, 17:43:52 INFO - 'installer_path': 'installer.exe', 17:43:52 INFO - 'log_level': 'info', 17:43:52 INFO - 'log_name': 'talos', 17:43:52 INFO - 'log_to_console': True, 17:43:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:43:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:43:52 INFO - 'opt_config_files': (), 17:43:52 INFO - 'pip_index': False, 17:43:52 INFO - 'suite': 'dromaeojs-e10s', 17:43:52 INFO - 'system_bits': '32', 17:43:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:43:52 INFO - 'title': 'talos-linux64-ix-067', 17:43:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:43:52 INFO - 'use_talos_json': True, 17:43:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:43:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:43:52 INFO - 'work_dir': 'build'} 17:43:52 INFO - [mozharness: 2017-07-18 00:43:52.108541Z] Running clobber step. 17:43:52 INFO - Running pre-action listener: _resource_record_pre_action 17:43:52 INFO - Running main action method: clobber 17:43:52 INFO - rmtree: /builds/slave/test/build 17:43:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:43:53 INFO - Running post-action listener: _resource_record_post_action 17:43:53 INFO - [mozharness: 2017-07-18 00:43:53.678037Z] Finished clobber step (success) 17:43:53 INFO - [mozharness: 2017-07-18 00:43:53.678139Z] Running read-buildbot-config step. 17:43:53 INFO - Running pre-action listener: _resource_record_pre_action 17:43:53 INFO - Running main action method: read_buildbot_config 17:43:53 INFO - Using buildbot properties: 17:43:53 INFO - { 17:43:53 INFO - "project": "", 17:43:53 INFO - "product": "firefox", 17:43:53 INFO - "who": "kwierso@gmail.com", 17:43:53 INFO - "installer_path": "public/build/target.tar.bz2", 17:43:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 17:43:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 17:43:53 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 17:43:53 INFO - "stage_platform": "linux64-stylo", 17:43:53 INFO - "basedir": "/builds/slave/test", 17:43:53 INFO - "buildnumber": 216, 17:43:53 INFO - "platform": "ubuntu64_hw_stylo", 17:43:53 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:43:53 INFO - "slavebuilddir": "test", 17:43:53 INFO - "taskId": "Z76G8otSSEm9OuQclqbS7Q", 17:43:53 INFO - "branch": "mozilla-central", 17:43:53 INFO - "script_repo_revision": "production", 17:43:53 INFO - "revision": "efc0b1525edbd357818dc7195537364e76f709e7", 17:43:53 INFO - "slavename": "talos-linux64-ix-067", 17:43:53 INFO - "repo_path": "mozilla-central" 17:43:53 INFO - } 17:43:53 INFO - Finding installer, test and symbols from parent task. 17:43:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z76G8otSSEm9OuQclqbS7Q'}, attempt #1 17:43:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z76G8otSSEm9OuQclqbS7Q'}, attempt #1 17:43:54 INFO - Task dependencies: QAVNQk1qRdSbN-F7KCbs2g 17:43:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g'}, attempt #1 17:43:55 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.tar.bz2 17:43:55 INFO - Set test_url: https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/test_packages.json 17:43:55 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.crashreporter-symbols.zip 17:43:55 INFO - Running post-action listener: _resource_record_post_action 17:43:55 INFO - [mozharness: 2017-07-18 00:43:55.036607Z] Finished read-buildbot-config step (success) 17:43:55 INFO - [mozharness: 2017-07-18 00:43:55.036829Z] Running download-and-extract step. 17:43:55 INFO - Running pre-action listener: _resource_record_pre_action 17:43:55 INFO - Running main action method: download_and_extract 17:43:55 INFO - mkdir: /builds/slave/test/build/tests 17:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:43:55 INFO - trying https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.test_packages.json 17:43:55 INFO - Downloading https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:43:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:43:55 INFO - Downloaded 989 bytes. 17:43:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:43:55 INFO - Using the following test package requirements: 17:43:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:43:55 INFO - u'common': [u'target.common.tests.zip'], 17:43:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:43:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:43:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:43:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:43:55 INFO - u'mozbase': [u'target.common.tests.zip'], 17:43:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:43:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:43:55 INFO - u'web-platform': [u'target.common.tests.zip', 17:43:55 INFO - u'target.web-platform.tests.tar.gz'], 17:43:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:43:55 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:43:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.common.tests.zip 17:43:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:43:55 INFO - Fetch https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.common.tests.zip into memory 17:43:56 INFO - Content-Length response header: 72769115 17:43:56 INFO - Bytes received: 72769115 17:44:07 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:44:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.common.tests.zip 17:44:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:44:07 INFO - Fetch https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.common.tests.zip into memory 17:44:09 INFO - Content-Length response header: 72769115 17:44:09 INFO - Bytes received: 72769115 17:44:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.talos.tests.zip 17:44:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:44:21 INFO - Fetch https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.talos.tests.zip into memory 17:44:22 INFO - Content-Length response header: 14495033 17:44:22 INFO - Bytes received: 14495033 17:44:22 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')]} 17:44:22 INFO - trying https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.tar.bz2 17:44:22 INFO - Downloading https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:44:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:44:23 INFO - Downloaded 61949324 bytes. 17:44:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.tar.bz2 17:44:23 INFO - mkdir: /builds/slave/test/properties 17:44:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:44:23 INFO - Writing to file /builds/slave/test/properties/build_url 17:44:23 INFO - Contents: 17:44:23 INFO - build_url:https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.tar.bz2 17:44:23 INFO - Running post-action listener: _resource_record_post_action 17:44:23 INFO - Running post-action listener: set_extra_try_arguments 17:44:23 INFO - [mozharness: 2017-07-18 00:44:23.752541Z] Finished download-and-extract step (success) 17:44:23 INFO - [mozharness: 2017-07-18 00:44:23.752639Z] Running populate-webroot step. 17:44:23 INFO - Running pre-action listener: _resource_record_pre_action 17:44:23 INFO - Running main action method: populate_webroot 17:44:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:44:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:44:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:44:23 INFO - '--tppagecycles', 17:44:23 INFO - '7'], 17:44:23 INFO - 'tests': ['tsvgm']}}, 17:44:23 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:44:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:44:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:44:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:44:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:44:23 INFO - 'talos_options': ['--disable-e10s'], 17:44:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:44:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:44:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:44:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:44:23 INFO - 'talos_options': ['--disable-e10s'], 17:44:23 INFO - 'tests': ['damp', 'tps']}, 17:44:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:44:23 INFO - 'tests': ['damp', 'tps']}, 17:44:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:44:23 INFO - 'tests': ['dromaeo_dom']}, 17:44:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:44:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:44:23 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:44:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:44:23 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:44:23 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:44:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:44:23 INFO - 'tests': ['a11yr', 17:44:23 INFO - 'ts_paint', 17:44:23 INFO - 'tpaint', 17:44:23 INFO - 'sessionrestore', 17:44:23 INFO - 'sessionrestore_no_auto_restore', 17:44:23 INFO - 'tabpaint']}, 17:44:23 INFO - 'other-e10s': {'tests': ['a11yr', 17:44:23 INFO - 'ts_paint', 17:44:23 INFO - 'tpaint', 17:44:23 INFO - 'sessionrestore', 17:44:23 INFO - 'sessionrestore_many_windows', 17:44:23 INFO - 'sessionrestore_no_auto_restore', 17:44:23 INFO - 'tabpaint']}, 17:44:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:44:23 INFO - 'ts_paint', 17:44:23 INFO - 'tpaint', 17:44:23 INFO - 'sessionrestore', 17:44:23 INFO - 'sessionrestore_many_windows', 17:44:23 INFO - 'sessionrestore_no_auto_restore', 17:44:23 INFO - 'tabpaint']}, 17:44:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:44:23 INFO - 'ts_paint', 17:44:23 INFO - 'tpaint', 17:44:23 INFO - 'sessionrestore', 17:44:23 INFO - 'sessionrestore_many_windows', 17:44:23 INFO - 'sessionrestore_no_auto_restore', 17:44:23 INFO - 'tabpaint']}, 17:44:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:44:23 INFO - 'tests': ['a11yr', 17:44:23 INFO - 'ts_paint', 17:44:23 INFO - 'tpaint', 17:44:23 INFO - 'sessionrestore', 17:44:23 INFO - 'sessionrestore_no_auto_restore', 17:44:23 INFO - 'tabpaint']}, 17:44:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:44:23 INFO - 'tests': ['a11yr', 17:44:23 INFO - 'ts_paint', 17:44:23 INFO - 'tpaint', 17:44:23 INFO - 'sessionrestore', 17:44:23 INFO - 'sessionrestore_no_auto_restore', 17:44:23 INFO - 'tabpaint']}, 17:44:23 INFO - 'perf-reftest': {'tests': ['bloom_basic']}, 17:44:23 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 17:44:23 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 17:44:23 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:44:23 INFO - 'talos_options': ['--mitmproxy', 17:44:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:44:23 INFO - '--firstNonBlankPaint'], 17:44:23 INFO - 'tests': ['quantum_pageload_google', 17:44:23 INFO - 'quantum_pageload_youtube', 17:44:23 INFO - 'quantum_pageload_amazon', 17:44:23 INFO - 'quantum_pageload_facebook']}, 17:44:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:44:23 INFO - 'tests': ['tsvgx', 17:44:23 INFO - 'tsvgr_opacity', 17:44:23 INFO - 'tart', 17:44:23 INFO - 'tscrollx', 17:44:23 INFO - 'cart', 17:44:23 INFO - 'tsvg_static']}, 17:44:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:44:23 INFO - 'tsvgr_opacity', 17:44:23 INFO - 'tart', 17:44:23 INFO - 'tscrollx', 17:44:23 INFO - 'cart', 17:44:23 INFO - 'tsvg_static']}, 17:44:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:44:23 INFO - 'talos_options': ['--disable-e10s'], 17:44:23 INFO - 'tests': ['tp5o']}, 17:44:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:44:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:44:23 INFO - 'talos_options': ['--disable-e10s', 17:44:23 INFO - '--xperf_path', 17:44:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:44:23 INFO - 'tests': ['tp5n']}, 17:44:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:44:23 INFO - 'talos_options': ['--xperf_path', 17:44:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:44:23 INFO - 'tests': ['tp5n']}}, 17:44:23 INFO - 'talos.zip': {'path': '', 17:44:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:44:23 INFO - Running post-action listener: _resource_record_post_action 17:44:23 INFO - [mozharness: 2017-07-18 00:44:23.768037Z] Finished populate-webroot step (success) 17:44:23 INFO - [mozharness: 2017-07-18 00:44:23.768128Z] Running create-virtualenv step. 17:44:23 INFO - Running pre-action listener: _resource_record_pre_action 17:44:23 INFO - Running main action method: create_virtualenv 17:44:23 INFO - Creating virtualenv /builds/slave/test/build/venv 17:44:23 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 17:44:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:44:23 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:44:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:44:23 INFO - Using real prefix '/usr' 17:44:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:44:24 INFO - Installing distribute.............................................................................................................................................................................................done. 17:44:26 INFO - Installing pip.................done. 17:44:26 INFO - Return code: 0 17:44:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:44:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:44:26 INFO - Reading from file tmpfile_stdout 17:44:26 INFO - Output received: 17:44:26 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) 17:44:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:44:26 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')]} 17:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:26 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')]} 17:44:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:26 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 0x28e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c59b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c56d00>, '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 0x2c57c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c58090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', '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 17:44:26 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 17:44:26 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 17:44:26 INFO - Using env: {'DISPLAY': ':0', 17:44:26 INFO - 'HOME': '/home/cltbld', 17:44:26 INFO - 'LANG': 'en_US.UTF-8', 17:44:26 INFO - 'LANGUAGE': 'en_US:en', 17:44:26 INFO - 'LOGNAME': 'cltbld', 17:44:26 INFO - 'MAIL': '/var/mail/cltbld', 17:44:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:26 INFO - 'MOZ_NO_REMOTE': '1', 17:44:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:26 INFO - 'NO_EM_RESTART': '1', 17:44:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:26 INFO - 'PWD': '/builds/slave/test', 17:44:26 INFO - 'SHELL': '/bin/bash', 17:44:26 INFO - 'SHLVL': '1', 17:44:26 INFO - 'TERM': 'linux', 17:44:26 INFO - 'TMOUT': '86400', 17:44:26 INFO - 'USER': 'cltbld', 17:44:26 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', 17:44:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:26 INFO - '_': '/tools/buildbot/bin/python'} 17:44:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:26 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 17:44:26 INFO - Cleaning up... 17:44:26 INFO - Return code: 0 17:44:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:44:26 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')]} 17:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:26 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')]} 17:44:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:26 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 0x28e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c59b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c56d00>, '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 0x2c57c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c58090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', '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 17:44:26 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 17:44:26 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 17:44:26 INFO - Using env: {'DISPLAY': ':0', 17:44:26 INFO - 'HOME': '/home/cltbld', 17:44:26 INFO - 'LANG': 'en_US.UTF-8', 17:44:26 INFO - 'LANGUAGE': 'en_US:en', 17:44:26 INFO - 'LOGNAME': 'cltbld', 17:44:26 INFO - 'MAIL': '/var/mail/cltbld', 17:44:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:26 INFO - 'MOZ_NO_REMOTE': '1', 17:44:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:26 INFO - 'NO_EM_RESTART': '1', 17:44:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:26 INFO - 'PWD': '/builds/slave/test', 17:44:26 INFO - 'SHELL': '/bin/bash', 17:44:26 INFO - 'SHLVL': '1', 17:44:26 INFO - 'TERM': 'linux', 17:44:26 INFO - 'TMOUT': '86400', 17:44:26 INFO - 'USER': 'cltbld', 17:44:26 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', 17:44:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:26 INFO - '_': '/tools/buildbot/bin/python'} 17:44:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:26 INFO - Downloading/unpacking psutil>=3.1.1 17:44:26 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 17:44:26 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 17:44:26 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 17:44:26 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 17:44:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:44:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:44:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:44:28 INFO - Installing collected packages: psutil 17:44:28 INFO - Running setup.py install for psutil 17:44:28 INFO - building 'psutil._psutil_linux' extension 17:44:28 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 17:44:28 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 17:44:28 INFO - building 'psutil._psutil_posix' extension 17:44:28 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 17:44:28 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 17:44:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:44:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:44:28 INFO - Successfully installed psutil 17:44:28 INFO - Cleaning up... 17:44:28 INFO - Return code: 0 17:44:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:44:28 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')]} 17:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:28 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')]} 17:44:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:28 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 0x28e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c59b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c56d00>, '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 0x2c57c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c58090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', '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 17:44:28 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 17:44:28 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 17:44:28 INFO - Using env: {'DISPLAY': ':0', 17:44:28 INFO - 'HOME': '/home/cltbld', 17:44:28 INFO - 'LANG': 'en_US.UTF-8', 17:44:28 INFO - 'LANGUAGE': 'en_US:en', 17:44:28 INFO - 'LOGNAME': 'cltbld', 17:44:28 INFO - 'MAIL': '/var/mail/cltbld', 17:44:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:28 INFO - 'MOZ_NO_REMOTE': '1', 17:44:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:28 INFO - 'NO_EM_RESTART': '1', 17:44:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:28 INFO - 'PWD': '/builds/slave/test', 17:44:28 INFO - 'SHELL': '/bin/bash', 17:44:28 INFO - 'SHLVL': '1', 17:44:28 INFO - 'TERM': 'linux', 17:44:28 INFO - 'TMOUT': '86400', 17:44:28 INFO - 'USER': 'cltbld', 17:44:28 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', 17:44:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:28 INFO - '_': '/tools/buildbot/bin/python'} 17:44:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:44:28 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 17:44:28 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 17:44:28 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 17:44:28 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 17:44:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:44:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:44:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:44:30 INFO - Installing collected packages: mozsystemmonitor 17:44:30 INFO - Running setup.py install for mozsystemmonitor 17:44:30 INFO - Successfully installed mozsystemmonitor 17:44:30 INFO - Cleaning up... 17:44:30 INFO - Return code: 0 17:44:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:44:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x28e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c59b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c56d00>, '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 0x2c57c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c58090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', '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 17:44:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:44:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:44:30 INFO - Using env: {'DISPLAY': ':0', 17:44:30 INFO - 'HOME': '/home/cltbld', 17:44:30 INFO - 'LANG': 'en_US.UTF-8', 17:44:30 INFO - 'LANGUAGE': 'en_US:en', 17:44:30 INFO - 'LOGNAME': 'cltbld', 17:44:30 INFO - 'MAIL': '/var/mail/cltbld', 17:44:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:30 INFO - 'MOZ_NO_REMOTE': '1', 17:44:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:30 INFO - 'NO_EM_RESTART': '1', 17:44:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:30 INFO - 'PWD': '/builds/slave/test', 17:44:30 INFO - 'SHELL': '/bin/bash', 17:44:30 INFO - 'SHLVL': '1', 17:44:30 INFO - 'TERM': 'linux', 17:44:30 INFO - 'TMOUT': '86400', 17:44:30 INFO - 'USER': 'cltbld', 17:44:30 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', 17:44:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:30 INFO - '_': '/tools/buildbot/bin/python'} 17:44:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:30 INFO - Downloading/unpacking jsonschema==2.5.1 17:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:44:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:31 INFO - Downloading functools32-3.2.3-2.tar.gz 17:44:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:44:31 INFO - warning: no files found matching '*.txt' 17:44:31 INFO - no previously-included directories found matching 'build' 17:44:31 INFO - no previously-included directories found matching 'dist' 17:44:31 INFO - no previously-included directories found matching '.git*' 17:44:31 INFO - Installing collected packages: jsonschema, functools32 17:44:31 INFO - Running setup.py install for functools32 17:44:31 INFO - warning: no files found matching '*.txt' 17:44:31 INFO - no previously-included directories found matching 'build' 17:44:31 INFO - no previously-included directories found matching 'dist' 17:44:31 INFO - no previously-included directories found matching '.git*' 17:44:31 INFO - Successfully installed jsonschema functools32 17:44:31 INFO - Cleaning up... 17:44:31 INFO - Return code: 0 17:44:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x28e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c59b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c56d00>, '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 0x2c57c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c58090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', '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 17:44:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:44:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:44:31 INFO - Using env: {'DISPLAY': ':0', 17:44:31 INFO - 'HOME': '/home/cltbld', 17:44:31 INFO - 'LANG': 'en_US.UTF-8', 17:44:31 INFO - 'LANGUAGE': 'en_US:en', 17:44:31 INFO - 'LOGNAME': 'cltbld', 17:44:31 INFO - 'MAIL': '/var/mail/cltbld', 17:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:31 INFO - 'MOZ_NO_REMOTE': '1', 17:44:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:31 INFO - 'NO_EM_RESTART': '1', 17:44:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:31 INFO - 'PWD': '/builds/slave/test', 17:44:31 INFO - 'SHELL': '/bin/bash', 17:44:31 INFO - 'SHLVL': '1', 17:44:31 INFO - 'TERM': 'linux', 17:44:31 INFO - 'TMOUT': '86400', 17:44:31 INFO - 'USER': 'cltbld', 17:44:31 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', 17:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:31 INFO - '_': '/tools/buildbot/bin/python'} 17:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:44:32 INFO - Cleaning up... 17:44:32 INFO - Return code: 0 17:44:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:44:32 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')]} 17:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:32 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')]} 17:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:32 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 0x28e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c59b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c56d00>, '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 0x2c57c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c58090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', '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 17:44:32 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 17:44:32 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 17:44:32 INFO - Using env: {'DISPLAY': ':0', 17:44:32 INFO - 'HOME': '/home/cltbld', 17:44:32 INFO - 'LANG': 'en_US.UTF-8', 17:44:32 INFO - 'LANGUAGE': 'en_US:en', 17:44:32 INFO - 'LOGNAME': 'cltbld', 17:44:32 INFO - 'MAIL': '/var/mail/cltbld', 17:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:32 INFO - 'MOZ_NO_REMOTE': '1', 17:44:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:32 INFO - 'NO_EM_RESTART': '1', 17:44:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:32 INFO - 'PWD': '/builds/slave/test', 17:44:32 INFO - 'SHELL': '/bin/bash', 17:44:32 INFO - 'SHLVL': '1', 17:44:32 INFO - 'TERM': 'linux', 17:44:32 INFO - 'TMOUT': '86400', 17:44:32 INFO - 'USER': 'cltbld', 17:44:32 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', 17:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:32 INFO - '_': '/tools/buildbot/bin/python'} 17:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:32 INFO - Downloading/unpacking blobuploader==1.2.4 17:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:33 INFO - Downloading blobuploader-1.2.4.tar.gz 17:44:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:44:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:44:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:33 INFO - Downloading docopt-0.6.1.tar.gz 17:44:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:44:33 INFO - Installing collected packages: blobuploader, requests, docopt 17:44:33 INFO - Running setup.py install for blobuploader 17:44:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:44:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:44:34 INFO - Running setup.py install for requests 17:44:34 INFO - Running setup.py install for docopt 17:44:34 INFO - Successfully installed blobuploader requests docopt 17:44:34 INFO - Cleaning up... 17:44:34 INFO - Return code: 0 17:44:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:44:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:44:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:34 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 0x28e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c59b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c56d00>, '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 0x2c57c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c58090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', '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 17:44:34 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 17:44:34 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 17:44:34 INFO - Using env: {'DISPLAY': ':0', 17:44:34 INFO - 'HOME': '/home/cltbld', 17:44:34 INFO - 'LANG': 'en_US.UTF-8', 17:44:34 INFO - 'LANGUAGE': 'en_US:en', 17:44:34 INFO - 'LOGNAME': 'cltbld', 17:44:34 INFO - 'MAIL': '/var/mail/cltbld', 17:44:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:34 INFO - 'MOZ_NO_REMOTE': '1', 17:44:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:34 INFO - 'NO_EM_RESTART': '1', 17:44:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:34 INFO - 'PWD': '/builds/slave/test', 17:44:34 INFO - 'SHELL': '/bin/bash', 17:44:34 INFO - 'SHLVL': '1', 17:44:34 INFO - 'TERM': 'linux', 17:44:34 INFO - 'TMOUT': '86400', 17:44:34 INFO - 'USER': 'cltbld', 17:44:34 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', 17:44:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:34 INFO - '_': '/tools/buildbot/bin/python'} 17:44:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:44:34 INFO - Running setup.py (path:/tmp/pip-w0SqZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:44:34 INFO - Running setup.py (path:/tmp/pip-QSzwrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:44:34 INFO - Running setup.py (path:/tmp/pip-yGmnag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:44:34 INFO - Running setup.py (path:/tmp/pip-oWCvHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:44:35 INFO - Running setup.py (path:/tmp/pip-K7IcIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:44:35 INFO - Running setup.py (path:/tmp/pip-7KMfTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:44:35 INFO - Running setup.py (path:/tmp/pip-MfkVfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:44:35 INFO - Running setup.py (path:/tmp/pip-udXzpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:44:35 INFO - Running setup.py (path:/tmp/pip-JKwO6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:44:35 INFO - Running setup.py (path:/tmp/pip-xAYVNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:44:35 INFO - Running setup.py (path:/tmp/pip-tFzz3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:44:35 INFO - Running setup.py (path:/tmp/pip-iSJodK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:44:35 INFO - Running setup.py (path:/tmp/pip-FAuE92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:44:35 INFO - Running setup.py (path:/tmp/pip-L_GX7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:44:35 INFO - Running setup.py (path:/tmp/pip-qiZNdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:44:35 INFO - Running setup.py (path:/tmp/pip-qaY04K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:44:35 INFO - Running setup.py (path:/tmp/pip-KoH9V_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:44:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:44:35 INFO - Running setup.py install for manifestparser 17:44:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:44:36 INFO - Running setup.py install for mozcrash 17:44:36 INFO - Running setup.py install for mozdebug 17:44:36 INFO - Running setup.py install for mozdevice 17:44:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:44:36 INFO - Running setup.py install for mozfile 17:44:36 INFO - Running setup.py install for mozhttpd 17:44:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:44:36 INFO - Running setup.py install for mozinfo 17:44:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:44:36 INFO - Running setup.py install for mozInstall 17:44:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:44:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:44:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:44:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:44:36 INFO - Running setup.py install for mozleak 17:44:36 INFO - Running setup.py install for mozlog 17:44:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:44:37 INFO - Running setup.py install for moznetwork 17:44:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:44:37 INFO - Running setup.py install for mozprocess 17:44:37 INFO - Running setup.py install for mozprofile 17:44:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:44:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:44:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:44:37 INFO - Running setup.py install for mozrunner 17:44:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:44:37 INFO - Running setup.py install for mozscreenshot 17:44:37 INFO - Running setup.py install for moztest 17:44:37 INFO - Running setup.py install for mozversion 17:44:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:44:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:44:37 INFO - Cleaning up... 17:44:37 INFO - Return code: 0 17:44:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:37 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 0x28e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c59b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c56d00>, '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 0x2c57c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c58090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', '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 17:44:37 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 17:44:37 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 17:44:37 INFO - Using env: {'DISPLAY': ':0', 17:44:37 INFO - 'HOME': '/home/cltbld', 17:44:37 INFO - 'LANG': 'en_US.UTF-8', 17:44:37 INFO - 'LANGUAGE': 'en_US:en', 17:44:37 INFO - 'LOGNAME': 'cltbld', 17:44:37 INFO - 'MAIL': '/var/mail/cltbld', 17:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:37 INFO - 'MOZ_NO_REMOTE': '1', 17:44:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:37 INFO - 'NO_EM_RESTART': '1', 17:44:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:37 INFO - 'PWD': '/builds/slave/test', 17:44:37 INFO - 'SHELL': '/bin/bash', 17:44:37 INFO - 'SHLVL': '1', 17:44:37 INFO - 'TERM': 'linux', 17:44:37 INFO - 'TMOUT': '86400', 17:44:37 INFO - 'USER': 'cltbld', 17:44:37 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', 17:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:37 INFO - '_': '/tools/buildbot/bin/python'} 17:44:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:44:38 INFO - Running setup.py (path:/tmp/pip-1tgx4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:44:38 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)) 17:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:44:38 INFO - Running setup.py (path:/tmp/pip-vW50B3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:44:38 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)) 17:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:44:38 INFO - Running setup.py (path:/tmp/pip-Du58Y7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:44:38 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)) 17:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:44:38 INFO - Running setup.py (path:/tmp/pip-qUnnuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:44:38 INFO - Running setup.py (path:/tmp/pip-cDRDGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:44:38 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)) 17:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:44:38 INFO - Running setup.py (path:/tmp/pip-oau513-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:44:38 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)) 17:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:44:38 INFO - Running setup.py (path:/tmp/pip-pxA_w5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 17:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:44:38 INFO - Running setup.py (path:/tmp/pip-npcSK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 17:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:44:38 INFO - Running setup.py (path:/tmp/pip-Jm6AMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:44:38 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)) 17:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:44:38 INFO - Running setup.py (path:/tmp/pip-oovTq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 17:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:44:38 INFO - Running setup.py (path:/tmp/pip-B1UqS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:44:38 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)) 17:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:44:38 INFO - Running setup.py (path:/tmp/pip-eFe9Fc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:44:38 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)) 17:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:44:38 INFO - Running setup.py (path:/tmp/pip-ZGbzF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:44:38 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)) 17:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:44:38 INFO - Running setup.py (path:/tmp/pip-OvfDRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:44:39 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)) 17:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:44:39 INFO - Running setup.py (path:/tmp/pip-OmeyPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:44:39 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)) 17:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:44:39 INFO - Running setup.py (path:/tmp/pip-ikQT5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:44:39 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)) 17:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:44:39 INFO - Running setup.py (path:/tmp/pip-J_vNOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:44:39 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)) 17:44:39 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)) 17:44:39 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)) 17:44:39 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)) 17:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:44:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:44:39 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 17:44:39 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 17:44:39 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 17:44:39 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 17:44:40 INFO - Downloading blessings-1.6.tar.gz 17:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:44:40 INFO - Installing collected packages: blessings 17:44:40 INFO - Running setup.py install for blessings 17:44:40 INFO - Successfully installed blessings 17:44:40 INFO - Cleaning up... 17:44:40 INFO - Return code: 0 17:44:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:44:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:44:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:44:40 INFO - Reading from file tmpfile_stdout 17:44:40 INFO - Current package versions: 17:44:40 INFO - argparse == 1.2.1 17:44:40 INFO - blessings == 1.6 17:44:40 INFO - blobuploader == 1.2.4 17:44:40 INFO - docopt == 0.6.1 17:44:40 INFO - functools32 == 3.2.3-2 17:44:40 INFO - jsonschema == 2.5.1 17:44:40 INFO - manifestparser == 1.1 17:44:40 INFO - mozInstall == 1.13 17:44:40 INFO - mozcrash == 1.0 17:44:40 INFO - mozdebug == 0.1 17:44:40 INFO - mozdevice == 0.51 17:44:40 INFO - mozfile == 1.2 17:44:40 INFO - mozhttpd == 0.7 17:44:40 INFO - mozinfo == 0.10 17:44:40 INFO - mozleak == 0.1 17:44:40 INFO - mozlog == 3.5 17:44:40 INFO - moznetwork == 0.27 17:44:40 INFO - mozprocess == 0.25 17:44:40 INFO - mozprofile == 0.28 17:44:40 INFO - mozrunner == 6.13 17:44:40 INFO - mozscreenshot == 0.1 17:44:40 INFO - mozsystemmonitor == 0.3 17:44:40 INFO - moztest == 0.8 17:44:40 INFO - mozversion == 1.4 17:44:40 INFO - psutil == 3.1.1 17:44:40 INFO - requests == 1.2.3 17:44:40 INFO - wsgiref == 0.1.2 17:44:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:44:40 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')]} 17:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:40 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')]} 17:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:40 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 0x28e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c59b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c56d00>, '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 0x2c57c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c58090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', '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 17:44:40 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 17:44:40 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 17:44:40 INFO - Using env: {'DISPLAY': ':0', 17:44:40 INFO - 'HOME': '/home/cltbld', 17:44:40 INFO - 'LANG': 'en_US.UTF-8', 17:44:40 INFO - 'LANGUAGE': 'en_US:en', 17:44:40 INFO - 'LOGNAME': 'cltbld', 17:44:40 INFO - 'MAIL': '/var/mail/cltbld', 17:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:40 INFO - 'MOZ_NO_REMOTE': '1', 17:44:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:40 INFO - 'NO_EM_RESTART': '1', 17:44:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:40 INFO - 'PWD': '/builds/slave/test', 17:44:40 INFO - 'SHELL': '/bin/bash', 17:44:40 INFO - 'SHLVL': '1', 17:44:40 INFO - 'TERM': 'linux', 17:44:40 INFO - 'TMOUT': '86400', 17:44:40 INFO - 'USER': 'cltbld', 17:44:40 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', 17:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:40 INFO - '_': '/tools/buildbot/bin/python'} 17:44:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:40 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)) 17:44:40 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)) 17:44:40 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)) 17:44:40 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)) 17:44:40 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)) 17:44:40 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)) 17:44:40 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)) 17:44:40 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)) 17:44:41 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)) 17:44:41 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:44:41 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 17:44:41 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 17:44:41 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 17:44:41 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 17:44:42 INFO - Downloading marionette_driver-1.4.0.tar.gz 17:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 17:44:42 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 17:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:44:42 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)) 17:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:44:42 INFO - Installing collected packages: marionette-driver, simplejson 17:44:42 INFO - Running setup.py install for marionette-driver 17:44:42 INFO - Running setup.py install for simplejson 17:44:42 INFO - building 'simplejson._speedups' extension 17:44:42 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 17:44:43 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 17:44:43 INFO - Successfully installed marionette-driver simplejson 17:44:43 INFO - Cleaning up... 17:44:43 INFO - Return code: 0 17:44:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:44:43 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')]} 17:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:43 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')]} 17:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:43 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 0x28e81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c59b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c56d00>, '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 0x2c57c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c58090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', '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 17:44:43 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 17:44:43 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 17:44:43 INFO - Using env: {'DISPLAY': ':0', 17:44:43 INFO - 'HOME': '/home/cltbld', 17:44:43 INFO - 'LANG': 'en_US.UTF-8', 17:44:43 INFO - 'LANGUAGE': 'en_US:en', 17:44:43 INFO - 'LOGNAME': 'cltbld', 17:44:43 INFO - 'MAIL': '/var/mail/cltbld', 17:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:43 INFO - 'MOZ_NO_REMOTE': '1', 17:44:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:43 INFO - 'NO_EM_RESTART': '1', 17:44:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:43 INFO - 'PWD': '/builds/slave/test', 17:44:43 INFO - 'SHELL': '/bin/bash', 17:44:43 INFO - 'SHLVL': '1', 17:44:43 INFO - 'TERM': 'linux', 17:44:43 INFO - 'TMOUT': '86400', 17:44:43 INFO - 'USER': 'cltbld', 17:44:43 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', 17:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:43 INFO - '_': '/tools/buildbot/bin/python'} 17:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:44:43 INFO - Cleaning up... 17:44:43 INFO - Return code: 0 17:44:43 INFO - Running post-action listener: _resource_record_post_action 17:44:43 INFO - Running post-action listener: _start_resource_monitoring 17:44:43 INFO - Starting resource monitoring. 17:44:43 INFO - [mozharness: 2017-07-18 00:44:43.830144Z] Finished create-virtualenv step (success) 17:44:43 INFO - [mozharness: 2017-07-18 00:44:43.830732Z] Running install step. 17:44:43 INFO - Running pre-action listener: _resource_record_pre_action 17:44:43 INFO - Running main action method: install 17:44:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:44:44 INFO - Reading from file tmpfile_stdout 17:44:44 INFO - Detecting whether we're running mozinstall >=1.0... 17:44:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:44:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:44:44 INFO - Reading from file tmpfile_stdout 17:44:44 INFO - Output received: 17:44:44 INFO - Usage: mozinstall [options] installer 17:44:44 INFO - Options: 17:44:44 INFO - -h, --help show this help message and exit 17:44:44 INFO - -d DEST, --destination=DEST 17:44:44 INFO - Directory to install application into. [default: 17:44:44 INFO - "/builds/slave/test"] 17:44:44 INFO - --app=APP Application being installed. [default: firefox] 17:44:44 INFO - mkdir: /builds/slave/test/build/application 17:44:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:44:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:45:02 INFO - Reading from file tmpfile_stdout 17:45:02 INFO - Output received: 17:45:02 INFO - /builds/slave/test/build/application/firefox/firefox 17:45:02 INFO - Running post-action listener: _resource_record_post_action 17:45:02 INFO - [mozharness: 2017-07-18 00:45:02.198057Z] Finished install step (success) 17:45:02 INFO - [mozharness: 2017-07-18 00:45:02.198314Z] Running setup-mitmproxy step. 17:45:02 INFO - Running pre-action listener: _resource_record_pre_action 17:45:02 INFO - Running main action method: setup_mitmproxy 17:45:02 INFO - Skipping: mitmproxy is not required 17:45:02 INFO - Running post-action listener: _resource_record_post_action 17:45:02 INFO - [mozharness: 2017-07-18 00:45:02.199542Z] Finished setup-mitmproxy step (success) 17:45:02 INFO - [mozharness: 2017-07-18 00:45:02.199766Z] Running run-tests step. 17:45:02 INFO - Running pre-action listener: _resource_record_pre_action 17:45:02 INFO - Running pre-action listener: _set_gcov_prefix 17:45:02 INFO - Running main action method: run_tests 17:45:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:45:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:45:02 INFO - Python 2.7.3 17:45:02 INFO - Return code: 0 17:45:02 INFO - grabbing minidump binary from tooltool 17:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:45:02 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 0x2c56d00>, '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 0x2c57c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c58090>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:45:02 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 17:45:02 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 17:45:02 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 17:45:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:45:02 INFO - Return code: 0 17:45:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:45:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:45:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:45:02 INFO - ENV: RUST_BACKTRACE is now 1 17:45:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:45:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:45:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:45:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-067 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:45:02 INFO - Using env: {'DISPLAY': ':0', 17:45:02 INFO - 'HOME': '/home/cltbld', 17:45:02 INFO - 'LANG': 'en_US.UTF-8', 17:45:02 INFO - 'LANGUAGE': 'en_US:en', 17:45:02 INFO - 'LOGNAME': 'cltbld', 17:45:02 INFO - 'MAIL': '/var/mail/cltbld', 17:45:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:45:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:02 INFO - 'MOZ_NO_REMOTE': '1', 17:45:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:45:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:45:02 INFO - 'NO_EM_RESTART': '1', 17:45:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:02 INFO - 'PWD': '/builds/slave/test', 17:45:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:45:02 INFO - 'RUST_BACKTRACE': '1', 17:45:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:45:02 INFO - 'SHELL': '/bin/bash', 17:45:02 INFO - 'SHLVL': '1', 17:45:02 INFO - 'TERM': 'linux', 17:45:02 INFO - 'TMOUT': '86400', 17:45:02 INFO - 'USER': 'cltbld', 17:45:02 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472', 17:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:02 INFO - '_': '/tools/buildbot/bin/python'} 17:45:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:45:02 INFO - mozversion application_buildid: 20170718000101 17:45:02 INFO - mozversion application_changeset: efc0b1525edbd357818dc7195537364e76f709e7 17:45:02 INFO - mozversion application_display_name: Nightly 17:45:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:45:02 INFO - mozversion application_name: Firefox 17:45:02 INFO - mozversion application_remotingname: firefox 17:45:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:45:02 INFO - mozversion application_vendor: Mozilla 17:45:02 INFO - mozversion application_version: 56.0a1 17:45:02 INFO - mozversion platform_buildid: 20170718000101 17:45:02 INFO - mozversion platform_changeset: efc0b1525edbd357818dc7195537364e76f709e7 17:45:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:45:02 INFO - mozversion platform_version: 56.0a1 17:45:02 INFO - using testdate: 1500338702 17:45:02 INFO - actual date: 1500338702 17:45:02 INFO - starting webserver on 'localhost:58863' 17:45:02 INFO - SUITE-START | Running 2 tests 17:45:02 INFO - TEST-START | dromaeo_css 17:45:02 INFO - Initialising browser for dromaeo_css test... 17:45:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58863/getInfo.html -profile /tmp/tmp9xtDNU/profile 17:45:02 INFO - TEST-INFO | started process 31840 (/builds/slave/test/build/application/firefox/firefox http://localhost:58863/getInfo.html) 17:45:08 INFO - TEST-INFO | 31840: exit 0 17:45:08 INFO - Browser initialized. 17:45:08 INFO - Running cycle 1/1 for dromaeo_css test... 17:45:08 INFO - TEST-INFO | started process 32062 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9xtDNU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:45:19 INFO - PID 32062 | RSS: Main: 168939520 17:45:19 INFO - PID 32062 | 17:48:17 INFO - PID 32062 | Cycle 1(1): loaded http://localhost:58863/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58863/tests/dromaeo/cssquery-ext.html) 17:48:19 INFO - PID 32062 | RSS: Main: 177090560 17:48:19 INFO - PID 32062 | 17:51:21 INFO - PID 32062 | Cycle 1(1): loaded http://localhost:58863/tests/dromaeo/cssquery-ext.html (next: http://localhost:58863/tests/dromaeo/cssquery-jquery.html) 17:51:21 INFO - PID 32062 | RSS: Main: 176205824 17:51:21 INFO - PID 32062 | 17:54:23 INFO - PID 32062 | Cycle 1(1): loaded http://localhost:58863/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58863/tests/dromaeo/cssquery-mootools.html) 17:54:24 INFO - PID 32062 | RSS: Main: 178454528 17:54:24 INFO - PID 32062 | 17:57:25 INFO - PID 32062 | Cycle 1(1): loaded http://localhost:58863/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58863/tests/dromaeo/cssquery-prototype.html) 17:57:26 INFO - PID 32062 | RSS: Main: 176476160 17:57:26 INFO - PID 32062 | 18:00:29 INFO - PID 32062 | Cycle 1(1): loaded http://localhost:58863/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58863/tests/dromaeo/cssquery-yui.html) 18:00:30 INFO - PID 32062 | RSS: Main: 175816704 18:00:30 INFO - PID 32062 | 18:03:32 INFO - PID 32062 | Cycle 1(1): loaded http://localhost:58863/tests/dromaeo/cssquery-yui.html (next: http://localhost:58863/tests/dromaeo/cssquery-dojo.html) 18:03:32 INFO - PID 32062 | RSS: Main: 179179520 18:03:32 INFO - PID 32062 | 18:03:32 INFO - PID 32062 | __start_tp_report 18:03:32 INFO - PID 32062 | _x_x_mozilla_page_load 18:03:32 INFO - PID 32062 | _x_x_mozilla_page_load_details 18:03:32 INFO - PID 32062 | |i|pagename|runs| 18:03:32 INFO - PID 32062 | |0;dojo.html;3033.4539782839106;3048.603681521402;3099.1012606344175;3107.5183346581275;3108.5648009278707;5951.315598706145;6021.126936594197;6028.788992385267;6030.33666296707;6038.69806509675;5982.042873140298;6038.094285857123;6039.456448919596;6047.213862197919;6056.969715151429;5519.530839878616;5521.613487055918;5526.115821468566;5526.58550608704;5566.8608284793;5549.0289199390145;5554.361248456439;5557.305336832896;5565.2486914266465;5577.553795696334;5565.6104072714925;5570.969370666419;5579.38626751057;5581;5591.1054231323;5561.610687251894;5570;5578.832635020915;5587.888242235153;5599.1041433370465;5491;5526.226328313999;5526.419725928759;5533;5646.858828529279;6060.909086363708;6098.908516372258;6099.512039036867;6105.877882442326;6138;6266.248050233956;6383.265924418705;6516.804495865084;6542.476601871839;6574.8356291093105;6465.450436712885;6544.672766361663;6625.834354141137;6657.533972621918;6659.200895892475;5946.8810623787285;5958.463738263534;5974.820755377345;5978.252718410199;5986.371430999726;6511.28375878653;6522.869542609121;6588.637624930579;6605.768798092044;6632.734690612369;6222.222222222223;6233.189685340877;6272.341404152589;6284.842878927972;6292.182016337938;6318.336574659686;6328.651924144124;6372.203474565679;6407.13503677007;6412.006139048455;6315.336889626615;6340.619562826245;6351.301356850743;6366.936330636634;6446.645434501147;6490.415862572297;6492.156019717407;6561.671916404161;6574.572652777554;6577.835554111186;5266.578673706094;5277.683338999673;5351.866203344947;5377.435369286169;5391.056565101092;4383.956160438419;4472.44094488189;4479.686421950432;4487.282034874404;4492.528284530143;5513.917291240635;5527.944720552823;5535.778568857281;5539.529140023063;5540.889182216333;6400.7119679614525;6402.6158430494315;6436.259830119502;6442.967785161138;6456.935430645727;5476.479734425223;5544.029794785977;5545.639533430314;5570.526505247019;5571.498565129158;5627.465390787868;5683.346415162309;5694.629849059798;5706.71466426677;5707.200991861143;6189.938100618936;6243.96878015607;6333.524985626096;6342.778002769881;6403.231612206472;6522.902156467562;6541.149650545399;6618.768343107968;6640.634765087966;6648.867022659617;6309.274433440213;6315.652639104894;6372;6425.004124360731;6444;6267.905981410374;6335.303116657072;6362.522810789117;6379.266384365672;6427.357264273535;5662.8301150965535;6407;6483.448906842971;6554.508411869034;6671.432928201158;6318.6840657965995;6333.429991300621;6404.583702059351;6423.389777971085;6427.068075129174;6341.936580634319;6343.9365606343345;6414.134091897534;6416.807495775134;6430.035494675836;6041.365656605993;6122.204113465221;6139.693015349126;6141.355157708376;6273.905891411724;6272.341404152498;6300.621962682254;6323.747050118128;6325.96837015812;6351.618902865843;6055.727492262947;6118.326984031664;6154.969225153845;6222.284437289589;6303.747850086129 18:03:32 INFO - PID 32062 | |1;ext.html;629.6222266640017;654.2312782480585;685.4139710547482;690.6891898645611;1150.5570355413163;29066;29711.257218569546;29937.55093673594;39493.210135797264;40289;39376.21247575054;39431.605683943155;39849.60526381577;39987.60012399875;40020;7423.665935032936;7619.42854285928;7627.771166865003;7630;7638.5798781067015;7411.8147046324;7488.213737557559;7533.773986780406;7534.660940257674;7550.3204711575945;7529.811754706148;7552.622368881562;7577.204393538682;7638.236176382345;7676.155622881479;7536.397088232956;7607.847843043163;7618.466707330462;7625.923740762577;7631.885521717179;7339.633018349061;7445.85108297831;7450.217727138681;7456.067991501062;7474.177840437547;36268.63731362679;36298.8185059073;36308.818455907814;36617.81691091527;36674.26651466983;3499.842507087187;3548.716102711777;3583.283343331344;3584.4981702561663;3585.354636165489;29906.55140172899;29940.550891736642;29951;30092.699073009207;30214.395712085854;4764.332993380929;4770.427548694179;4782.258749893772;4814.398200224972;4866.489018653061;179462.10268948702;180030.0998494999;232186;233722;235704;48491.75754121207;48553;48667.513324867;48671.513284867404;49050;50220;50332;50534.220617865634;50558.74720626447;50736.238956415684;14416.062955907832;14512.637184070272;14524.927375363266;14563.708725825636;14566;11780;11831.112666550043;11901.047916166686;11903.702407439883;11908.761824763456;14199.929000354932;14270.928645356707;14323.713525729636;14329.85670143306;14364.35360408784;5639.697090474341;5688.744006519716;5854.385289544622;5859.1797148399255;5919.289685237799;976.4531862157214;981.4160574458186;987.4815721745997;994.7910938702809;1022.2639699416557;21572.244971425924;22113.336599902028;22377;22415.551688966454;22457.43856403603;1070.1687957113515;1101.3391964821044;1104.5471356743697;1107.3189988157387;1116.1684545014016;11458.713532161762;11699;11817.290962542274;11916;12031.458584363725;235426;236102;236771;237195;237450;51645.48354516482;51698.74150629223;51850.740746296025;52037.47962520402;52071.73964130155;1371.2732251906505;1379.0829098649485;1390;1390.0825455199722;1411.5694713112393;25448.745512545378;27014.594781078693;27047.594286086118;27287.863560682068;27373.58939615947;35193;35487.757928472376;35489.29021419609;35523.46714799228;35547.46678799872;181520;181902;182182;182345;182630;34122.48816267808;34255.82872085624;34419.483707744905;34638.13404664904;34788.478172827934;41279;41911.371329430694;42225.577744222166;42601.36097958471;42963.140737184454;28943.986960456285;30327;31286.217844554067;31724.524132137576;32002.359952800347;30508.474576271543;30621;30943.845280773454;30950.22624434407;31238;33078;33911.66088339085;34337.65662343444;34372;34547.65452345445;29617.25956851096;30178.54732179063;30462.086137415914;32647.510287345234;33304.1673958153;24108.87945560331;24183.395415114763;24353.39116522101;24394;24589.893454794936 18:03:32 INFO - PID 32062 | |2;jquery.html;558.1739026241161;564.3143580549631;570.2501210907653;578.8176724331837;592.7362323765924;20596.691049634268;21393.251236206717;21430.89284553577;21472.033758480866;22023.559528809415;24354.904029318677;26177.34556636085;26190.345241368927;26225.73774262257;26280.21159365222;6186.721597528111;6274.59215151016;6347.8413039673915;6348.365163483661;6357.872842543169;6154.692265386735;6221.12904193411;6230.439260466557;6255.40573645503;6272.749090036393;3767.585565587783;5010.649254552182;6261.311255761862;6265.8120256392385;6316.778912738055;6289.37106289372;6290.874182516347;6295.024271237966;6303.527235457359;6317.715702793385;5819.970900145515;5850.795222167247;5866.882662346772;5886.9705651471895;5891.351951285355;27423.588646170323;29404.411911761857;29697.851510742523;29730.70269297301;29754.404911901856;1910.5319196796734;1951.024487756122;1961.656710075731;1965.0862349007637;1966.2724791827115;18715.438536843918;18933.33733319327;18993.905030474896;19007.90496047511;19066.904665476723;2260.4348912771807;2310.803581695558;2333.8482998605036;2357.7642235776457;2360.433495960957;132227.33886330508;135437;135793.3210333942;141356;141540;14261.928690356483;14354.784678229835;14483.78274325865;14577.92711036438;15039.849601503845;23346.18288359899;23496.882515587655;23523;23525.764742352356;23557.882210588836;9906.900930990741;10121.1903047756;10132.493375331203;10263.538140783683;10367.377957322586;9259.953700231456;9401.388909720847;9401.952990235142;9445.19715824149;9453.43279403238;8852.734417967471;9070.229030532479;9160.770980725536;9313.53432328381;9403.29475289356;2600.9609855852177;2618.7250338714543;2679.169457468152;2725.2914242296747;2725.822821516595;943.7216021273604;948.1892981500717;948.4735971535864;967.2068903499204;988.9950550247347;19898.204071837255;20005.299814506423;20303;23185.884070579876;25008.749912501007;1033.7829055898346;1040.1990467340256;1045.9738506537244;1048.743057950807;1060.5863713151878;11050.889491105147;11485.942570287096;11527.596534121265;11622.418879056182;11783.705407364885;467908;468561;484055;486487;488264;116251;118657.40671296588;118746.40626796984;118859;121159.39420302842;1228.2139430764319;1243.7325974915436;1278.5461161287794;1285.607889593683;1286.3311078239262;17940.91029544844;17977;18139.637207256044;18149.27402903869;18443.17005734772;53035.20447193372;53721.73139134279;54547;58555.70722146532;58765.70617146887;69544;71293.64353178201;72322.63838680772;72972.63513682397;73020.26979730347;14180.645483862985;14917.776233356726;14919.328630211883;14929.10425374481;15059.096454212782;22755.658665119707;22789;22996.310110696704;23222.187223447097;23465.413364665335;17852.19665115047;17895.463136105936;18058.909705451388;18118.909405452887;18384.264629415207;15559.688806224038;16907.323707052074;17002.659946801243;17217.91391043037;17782.555436114202;19128.330508432136;19233.61532769309;19263.133159008157;19332;19364.8063519363;14744.410223590936;20875.895620521802;22116;22723.318300451014;22787.31638050861;18271.54321141982;19399.515012124808;19579.216831326587;19596.60806783828;19618.803811961698 18:03:32 INFO - PID 32062 | |3;mootools.html;1328.2827273272433;1366.883505607216;1375.6217040313913;1380.1443105274727;1386.126740153703;13122.737545249101;13673.179609223454;13794.724105517884;13842.930785346072;13856.58430247093;13250.867491325083;14369.56891293263;14371.496997605085;14401.855981440183;14419;2643.352378667229;2671.091828778215;2673.5588627876473;2676.4647070585866;2676.69218039925;2577.05937332874;2621.646077779504;2621.7509336612993;2623.986880065597;2627.8686065696734;2501.6372625969225;2538.70804857441;2556.169244995375;2569.794416446689;2571.768540831325;2599.9220023399234;2669.973300266992;2678.2902530829347;2678.9196324110308;2683.409649877024;2546.9108581199753;2548.7578680025554;2563.6410902473667;2568.22953114065;2569.601711734684;13566.796498052538;13747.38136783847;13773.517926872611;13784.793228101486;13837.169769813843;7534.849303013963;7806.921930780733;7844;7855.253750893656;7858.057033156001;4887.511248875119;4947.134251506008;4983.950160498421;5138.614603904684;5156.9742151289;1830.725391191332;1846.2153584726439;1864.7482589850333;1895.7061655443429;1903.038965322517;22959.196428124935;23129.768702313097;23135.76864231353;23255;23409;6290.779822706183;6347.787215020153;6479.254885688206;6524.184476940382;6562.77030303937;15479.5356139316;15839.841601584067;15901.52295431139;15930.920345398199;16003.119828409317;7144.857102857914;7242.23956484572;7305.269473052652;7318.634068296564;7320.487565870444;6724.193096828412;6758.729650814011;6768.932310676929;6778.762743303961;6818.488613353919;4387.670924680661;4573.931391029138;4588.26587745959;4609.6542759292515;4652.720501862015;1408.9295535223403;1469.2947385255138;1477.1727832413667;1488.6501672107047;1489.508462207469;864.481311213267;878.042933202812;880.3925291548811;893.687209476691;918.9724308270762;6944.201416837027;7024.683889224997;7069.505134640629;7088.539244949062;7130.42956563484;684.3430306905327;691;716.6774951271949;723.4971694672153;729.0231090338971;4015.9558514786368;4159;4326.437563116775;4773;4900.656954013256;17283;18283.72574411385;19063.61872762537;19065.523361916064;19154.329598463988;18541.907290463732;18544.536386590444;18620.81379186218;18786;18797.436076917715;10320.587176513005;10581.470926453647;10685;10854.891451085705;11041.116710663127;7168.856622867409;7717.305442510201;7827.295543401121;7900.565468899265;8077;15666.530004099895;15859.762103568224;15903;16256.268467918735;16602;15733.134677592841;16162.838371616133;16493.257803398636;16542.09018503994;16921.576960576083;10994.450277485934;11145.498452569816;11263.887361126612;11265.774684506428;11283.492242848928;12745.872541274468;13065.608031759062;13106.082574220094;13113.803292950422;13136.27750473733;10096.69709908704;10222.488875556044;10506.789864202827;10555.683329500127;10639.255252132276;9599.136077752757;10142.54358553852;10228.59085636596;10307.639232626821;10340.965903409598;8620.181082797124;9056.773580660536;9323.347365684336;9363.391379560308;9416.576254068446;6407.135036769976;6552.967235163794;6598.381133206679;6604.306547812603;6804.285550017177;7012.112189838446;7033.437325014089;7234.96382518084;7252.2594577302125;7290.453216008715 18:03:32 INFO - PID 32062 | |4;prototype.html;494.2684826456846;496.3200415430859;513.6096566609377;525.8632755483578;530.0300450176178;6032.788852390167;6035.486983606394;6121.510279177672;6146.631202127875;6159.384061593844;5796;5944.256967879015;5998.310194327653;5998.310194327653;6015.248093988252;4860.198067318888;4880.219164933611;4888.413390393158;4900.534449227335;4909.3126962225315;4839.717474869162;4887.584555312785;4895.461499235082;4911.410630724318;4921.434035085975;4841.951580484185;4875.756212189394;4907.2884431757375;4912.336834527347;4934.876628084308;4693.953060469403;4872.902541949141;4883.755812209375;4892.5596696297325;4909.435414927258;4416.098131074505;4420.778961051967;4424.690271680984;4436.179306828247;4444.844430444951;5892.558058145656;5914;5915.438033386821;5924.1267985027225;5944.167816505693;5619.662820230799;5622.212890195376;5631.690257035862;5654.992477670409;5668;6416.871662566769;6425.485961123099;6432.742690292383;6445.419912207877;6478.125453063823;4812.879678008043;4832.405306248929;4832.806687732486;4846.1761500544635;4892.461829198785;6759.254633561433;6767.1879374475375;6769.492288078364;6797.52417330787;6798.592084474947;4858.198397264412;4933.629977751683;4974.626902982219;4994.9500504994485;5007.549320561167;4977.776000080005;4995.800167993312;5019.272205530251;5029.2958985742025;5051.87370315745;5545.1682247663175;5577.2191893135;5596.748146333425;5619.600141914165;5640.85897852557;6365.777197798055;6368.395002474757;6397.4562162216735;6475.611463312216;6518.706658200297;1726.9395571154948;1754.7367894815625;1756.7101428264448;1769.4160926894094;1770.7958588160175;1084.6583326252205;1171.619223752284;1181.0905602685884;1190.5356910804794;1213.660175150959;5355.223492593552;5477.561795056386;5517.972410138004;5587.284455836904;5627.859303517363;6508.316626754217;6532.967335163389;6563.995962462894;6626.7017984190825;6653;5523;5577.247071645276;5586.217929489874;5630.380658127603;5647.463490968352;3438.157651375429;3470.722342212617;3487.511748355232;3506.263684626257;3534.5228394166975;6345.650989195638;6369.108324834527;6400.743970241138;6437.839054023687;6477.6437295948235;6727.192736871607;6739.52823302374;6740.39336459721;6786.728530858809;6827;6376.1392212051705;6394.104825324458;6428.55000149985;6459.999999999906;6480.4815614750705;6525.2822189558165;6551.737930482727;6552.57408268461;6580.479443497706;6600;6548.770793022221;6574.934250657432;6585.407313341822;6607.735690572324;6638;6211.534134939988;6377.118749968863;6429.210238372442;6448.258450278278;6498.772542960974;4825.734584597741;4841.564259216688;4884.267359896044;4889.315495830658;4927.507249275044;6572.047053177358;6631.469482441489;6667.699953502201;6690.230623478362;6708.932910670831;6317.399847014526;6380.9042864358;6438.774642887476;6440.388163124496;6480.416762491398;6335.841603959938;6403.775867844603;6440.871182576416;6453.128827608213;6496.642684652389;6279.591826531261;6294.261996031741;6338.683065846782;6390.7763228286785;6396.769631336146;6235.594686345373;6352.618842869258;6415.781001609679;6416.165898433173;6453.45145662624;5655.830325090254;6127.417895299939;6139.171211886338;6180.47465965398;6181.258249010148 18:03:32 INFO - PID 32062 | |5;yui.html;892.4065496444863;895.0780695883243;897.6274845938935;899.8515244984576;916.8349697054532;16251.51245462638;18016.279348826047;18683.252669893187;18757;18873.71689424658;14781.704365912676;14889.329980150893;18293.817061829377;19256.326028589003;20386.490337741565;1036.3833519056168;1073.0557109743415;1074.4359211413991;1074.5594306334403;1076.6877605494396;1045.6601604478574;1064.872215334161;1065.3075500924383;1066.7973085113842;1067.8611780468527;1050.7950949564838;1057.7672911959357;1098.412349393075;1098.7088421568292;1103.0183137008069;1080.8108580998294;1086.022579678288;1093.6445655162065;1095.934243945362;1096.9616063437782;765.3150430364792;784.5684873319651;791.0941971442724;797.1510341486298;798.1140933563739;18973.715394269097;19243.422697319103;19246.326378576683;19285.903570482198;19370;18925.43223703291;19627.214911403527;20165.395038148734;20266.392008239778;20390.388288351372;14833.92583037078;15008.699826003418;15277.61805954849;15333.003354781904;15339.923300383427;3518.5074089627215;3521.0669172669263;3541.769784963969;3554.413521768905;3557.6442355764216;107733;111338.44330778375;111364;112145.99999999919;112423;14052.789208161887;14518;14787.334569944378;14902.701945961237;14984.100953942798;27408.573928258967;27978;28173.43653126967;28322.71677283201;28353.574696379572;7962.442629016029;8310.376721745894;8383.455075420079;8387.119352467904;8434.15658434164;4215.810288537024;4280.614744672995;4314.654827613784;4338.8047537860875;4348.130373925228;4903.239997800347;4953.157963146274;4982.327385802942;5066.392032956069;5094.821681241213;860.8235311761199;899.2670973156856;900.4417261298037;902.3232575568323;906.0758026812614;636.2015670333701;645.2740666749906;660.9438197753149;671.1074271219267;680.3706571421515;11138.33170009802;11883.58407455735;12003.759924801456;12085;12234.81647775284;681.1961884975677;681.9863602728017;690.9101816763889;701.824543864034;701.8350687588413;5199.740012999335;5745.942540574624;5854.912176317359;5913.408659134052;6238.656873871981;102481.48759256156;103746.48126759469;104053.47973260086;104190;106417.46791265994;86867;89593.55203223941;89708.10291897129;90532.09467905236;93669;78475;81516.59241703872;82204;83097;84995;12300.446479908247;12318.137730358789;12335.69160770952;12822.487100515875;13061;36539.817300913324;36756.632433676394;37310.94789786588;37503.624963750015;39232.215355692155;46666.533334666216;48204.51795482;48730.75634621804;48774.75612622056;49781;18647;18959.90520047391;19035.333763318216;19164.137613807132;19217.231310747415;17732.202050908;19516.414507564798;19670.40988770339;20985.790142098384;21037.354058618883;17954.192061357007;18930.52673683114;19153.52116197106;19245.422637320902;19322.130504127068;17802;17811.643767124326;17867.463976080737;17958.461246162635;18229.45311640653;16807.831921681114;18274.543136421697;18304.81695183031;18330;19301.806981930564;11495.252808567417;12065.638030859089;12353.62939111828;12540.184887982618;12687.11190216676;12983;13436.798448023092;14201.715965680834;14318.642033949234;15055 18:03:32 INFO - PID 32062 | __end_tp_report 18:03:32 INFO - PID 32062 | __start_cc_report 18:03:32 INFO - PID 32062 | _x_x_mozilla_cycle_collect,363 18:03:32 INFO - PID 32062 | __end_cc_report 18:03:32 INFO - PID 32062 | __startTimestamp1500339812270__endTimestamp 18:03:32 INFO - PID 32062 | 18:03:32 INFO - PID 32062 | ------- Summary: start ------- 18:03:32 INFO - PID 32062 | Number of tests: 6 18:03:32 INFO - PID 32062 | 18:03:32 INFO - PID 32062 | [#0] dojo.html Cycles:165 Average:5954.68 Median:6154.97 stddev:697.61 (11.3%) stddev-sans-first:661.04 18:03:32 INFO - PID 32062 | Values: 3033.5 3048.6 3099.1 3107.5 3108.6 5951.3 6021.1 6028.8 6030.3 6038.7 5982.0 6038.1 6039.5 6047.2 6057.0 5519.5 5521.6 5526.1 5526.6 5566.9 5549.0 5554.4 5557.3 5565.2 5577.6 5565.6 5571.0 5579.4 5581.0 5591.1 5561.6 5570.0 5578.8 5587.9 5599.1 5491.0 5526.2 5526.4 5533.0 5646.9 6060.9 6098.9 6099.5 6105.9 6138.0 6266.2 6383.3 6516.8 6542.5 6574.8 6465.5 6544.7 6625.8 6657.5 6659.2 5946.9 5958.5 5974.8 5978.3 5986.4 6511.3 6522.9 6588.6 6605.8 6632.7 6222.2 6233.2 6272.3 6284.8 6292.2 6318.3 6328.7 6372.2 6407.1 6412.0 6315.3 6340.6 6351.3 6366.9 6446.6 6490.4 6492.2 6561.7 6574.6 6577.8 5266.6 5277.7 5351.9 5377.4 5391.1 4384.0 4472.4 4479.7 4487.3 4492.5 5513.9 5527.9 5535.8 5539.5 5540.9 6400.7 6402.6 6436.3 6443.0 6456.9 5476.5 5544.0 5545.6 5570.5 5571.5 5627.5 5683.3 5694.6 5706.7 5707.2 6189.9 6244.0 6333.5 6342.8 6403.2 6522.9 6541.1 6618.8 6640.6 6648.9 6309.3 6315.7 6372.0 6425.0 6444.0 6267.9 6335.3 6362.5 6379.3 6427.4 5662.8 6407.0 6483.4 6554.5 6671.4 6318.7 6333.4 6404.6 6423.4 6427.1 6341.9 6343.9 6414.1 6416.8 6430.0 6041.4 6122.2 6139.7 6141.4 6273.9 6272.3 6300.6 6323.7 6326.0 6351.6 6055.7 6118.3 6155.0 6222.3 6303.7 18:03:32 INFO - PID 32062 | 18:03:32 INFO - PID 32062 | [#1] ext.html Cycles:180 Average:37301.56 Median:34395.74 stddev:55050.67 (160.1%) stddev-sans-first:55136.24 18:03:32 INFO - PID 32062 | Values: 629.6 654.2 685.4 690.7 1150.6 29066.0 29711.3 29937.6 39493.2 40289.0 39376.2 39431.6 39849.6 39987.6 40020.0 7423.7 7619.4 7627.8 7630.0 7638.6 7411.8 7488.2 7533.8 7534.7 7550.3 7529.8 7552.6 7577.2 7638.2 7676.2 7536.4 7607.8 7618.5 7625.9 7631.9 7339.6 7445.9 7450.2 7456.1 7474.2 36268.6 36298.8 36308.8 36617.8 36674.3 3499.8 3548.7 3583.3 3584.5 3585.4 29906.6 29940.6 29951.0 30092.7 30214.4 4764.3 4770.4 4782.3 4814.4 4866.5 179462.1 180030.1 232186.0 233722.0 235704.0 48491.8 48553.0 48667.5 48671.5 49050.0 50220.0 50332.0 50534.2 50558.7 50736.2 14416.1 14512.6 14524.9 14563.7 14566.0 11780.0 11831.1 11901.0 11903.7 11908.8 14199.9 14270.9 14323.7 14329.9 14364.4 5639.7 5688.7 5854.4 5859.2 5919.3 976.5 981.4 987.5 994.8 1022.3 21572.2 22113.3 22377.0 22415.6 22457.4 1070.2 1101.3 1104.5 1107.3 1116.2 11458.7 11699.0 11817.3 11916.0 12031.5 235426.0 236102.0 236771.0 237195.0 237450.0 51645.5 51698.7 51850.7 52037.5 52071.7 1371.3 1379.1 1390.0 1390.1 1411.6 25448.7 27014.6 27047.6 27287.9 27373.6 35193.0 35487.8 35489.3 35523.5 35547.5 181520.0 181902.0 182182.0 182345.0 182630.0 34122.5 34255.8 34419.5 34638.1 34788.5 41279.0 41911.4 42225.6 42601.4 42963.1 28944.0 30327.0 31286.2 31724.5 32002.4 30508.5 30621.0 30943.8 30950.2 31238.0 33078.0 33911.7 34337.7 34372.0 34547.7 29617.3 30178.5 30462.1 32647.5 33304.2 24108.9 24183.4 24353.4 24394.0 24589.9 18:03:32 INFO - PID 32062 | 18:03:32 INFO - PID 32062 | [#2] jquery.html Cycles:180 Average:34695.95 Median:12653.56 stddev:80938.00 (639.6%) stddev-sans-first:81124.47 18:03:32 INFO - PID 32062 | Values: 558.2 564.3 570.3 578.8 592.7 20596.7 21393.3 21430.9 21472.0 22023.6 24354.9 26177.3 26190.3 26225.7 26280.2 6186.7 6274.6 6347.8 6348.4 6357.9 6154.7 6221.1 6230.4 6255.4 6272.7 3767.6 5010.6 6261.3 6265.8 6316.8 6289.4 6290.9 6295.0 6303.5 6317.7 5820.0 5850.8 5866.9 5887.0 5891.4 27423.6 29404.4 29697.9 29730.7 29754.4 1910.5 1951.0 1961.7 1965.1 1966.3 18715.4 18933.3 18993.9 19007.9 19066.9 2260.4 2310.8 2333.8 2357.8 2360.4 132227.3 135437.0 135793.3 141356.0 141540.0 14261.9 14354.8 14483.8 14577.9 15039.8 23346.2 23496.9 23523.0 23525.8 23557.9 9906.9 10121.2 10132.5 10263.5 10367.4 9260.0 9401.4 9402.0 9445.2 9453.4 8852.7 9070.2 9160.8 9313.5 9403.3 2601.0 2618.7 2679.2 2725.3 2725.8 943.7 948.2 948.5 967.2 989.0 19898.2 20005.3 20303.0 23185.9 25008.7 1033.8 1040.2 1046.0 1048.7 1060.6 11050.9 11485.9 11527.6 11622.4 11783.7 467908.0 468561.0 484055.0 486487.0 488264.0 116251.0 118657.4 118746.4 118859.0 121159.4 1228.2 1243.7 1278.5 1285.6 1286.3 17940.9 17977.0 18139.6 18149.3 18443.2 53035.2 53721.7 54547.0 58555.7 58765.7 69544.0 71293.6 72322.6 72972.6 73020.3 14180.6 14917.8 14919.3 14929.1 15059.1 22755.7 22789.0 22996.3 23222.2 23465.4 17852.2 17895.5 18058.9 18118.9 18384.3 15559.7 16907.3 17002.7 17217.9 17782.6 19128.3 19233.6 19263.1 19332.0 19364.8 14744.4 20875.9 22116.0 22723.3 22787.3 18271.5 19399.5 19579.2 19596.6 19618.8 18:03:32 INFO - PID 32062 | 18:03:32 INFO - PID 32062 | [#3] mootools.html Cycles:180 Average:8472.42 Median:2568.92 stddev:5832.71 (227.0%) stddev-sans-first:5824.37 18:03:32 INFO - PID 32062 | Values: 1328.3 1366.9 1375.6 1380.1 1386.1 13122.7 13673.2 13794.7 13842.9 13856.6 13250.9 14369.6 14371.5 14401.9 14419.0 2643.4 2671.1 2673.6 2676.5 2676.7 2577.1 2621.6 2621.8 2624.0 2627.9 2501.6 2538.7 2556.2 2569.8 2571.8 2599.9 2670.0 2678.3 2678.9 2683.4 2546.9 2548.8 2563.6 2568.2 2569.6 13566.8 13747.4 13773.5 13784.8 13837.2 7534.8 7806.9 7844.0 7855.3 7858.1 4887.5 4947.1 4984.0 5138.6 5157.0 1830.7 1846.2 1864.7 1895.7 1903.0 22959.2 23129.8 23135.8 23255.0 23409.0 6290.8 6347.8 6479.3 6524.2 6562.8 15479.5 15839.8 15901.5 15930.9 16003.1 7144.9 7242.2 7305.3 7318.6 7320.5 6724.2 6758.7 6768.9 6778.8 6818.5 4387.7 4573.9 4588.3 4609.7 4652.7 1408.9 1469.3 1477.2 1488.7 1489.5 864.5 878.0 880.4 893.7 919.0 6944.2 7024.7 7069.5 7088.5 7130.4 684.3 691.0 716.7 723.5 729.0 4016.0 4159.0 4326.4 4773.0 4900.7 17283.0 18283.7 19063.6 19065.5 19154.3 18541.9 18544.5 18620.8 18786.0 18797.4 10320.6 10581.5 10685.0 10854.9 11041.1 7168.9 7717.3 7827.3 7900.6 8077.0 15666.5 15859.8 15903.0 16256.3 16602.0 15733.1 16162.8 16493.3 16542.1 16921.6 10994.5 11145.5 11263.9 11265.8 11283.5 12745.9 13065.6 13106.1 13113.8 13136.3 10096.7 10222.5 10506.8 10555.7 10639.3 9599.1 10142.5 10228.6 10307.6 10341.0 8620.2 9056.8 9323.3 9363.4 9416.6 6407.1 6553.0 6598.4 6604.3 6804.3 7012.1 7033.4 7235.0 7252.3 7290.5 18:03:32 INFO - PID 32062 | 18:03:32 INFO - PID 32062 | [#4] prototype.html Cycles:180 Average:5402.73 Median:5934.15 stddev:1513.79 (25.5%) stddev-sans-first:1472.52 18:03:32 INFO - PID 32062 | Values: 494.3 496.3 513.6 525.9 530.0 6032.8 6035.5 6121.5 6146.6 6159.4 5796.0 5944.3 5998.3 5998.3 6015.2 4860.2 4880.2 4888.4 4900.5 4909.3 4839.7 4887.6 4895.5 4911.4 4921.4 4842.0 4875.8 4907.3 4912.3 4934.9 4694.0 4872.9 4883.8 4892.6 4909.4 4416.1 4420.8 4424.7 4436.2 4444.8 5892.6 5914.0 5915.4 5924.1 5944.2 5619.7 5622.2 5631.7 5655.0 5668.0 6416.9 6425.5 6432.7 6445.4 6478.1 4812.9 4832.4 4832.8 4846.2 4892.5 6759.3 6767.2 6769.5 6797.5 6798.6 4858.2 4933.6 4974.6 4995.0 5007.5 4977.8 4995.8 5019.3 5029.3 5051.9 5545.2 5577.2 5596.7 5619.6 5640.9 6365.8 6368.4 6397.5 6475.6 6518.7 1726.9 1754.7 1756.7 1769.4 1770.8 1084.7 1171.6 1181.1 1190.5 1213.7 5355.2 5477.6 5518.0 5587.3 5627.9 6508.3 6533.0 6564.0 6626.7 6653.0 5523.0 5577.2 5586.2 5630.4 5647.5 3438.2 3470.7 3487.5 3506.3 3534.5 6345.7 6369.1 6400.7 6437.8 6477.6 6727.2 6739.5 6740.4 6786.7 6827.0 6376.1 6394.1 6428.6 6460.0 6480.5 6525.3 6551.7 6552.6 6580.5 6600.0 6548.8 6574.9 6585.4 6607.7 6638.0 6211.5 6377.1 6429.2 6448.3 6498.8 4825.7 4841.6 4884.3 4889.3 4927.5 6572.0 6631.5 6667.7 6690.2 6708.9 6317.4 6380.9 6438.8 6440.4 6480.4 6335.8 6403.8 6440.9 6453.1 6496.6 6279.6 6294.3 6338.7 6390.8 6396.8 6235.6 6352.6 6415.8 6416.2 6453.5 5655.8 6127.4 6139.2 6180.5 6181.3 18:03:32 INFO - PID 32062 | 18:03:32 INFO - PID 32062 | [#5] yui.html Cycles:180 Average:21818.76 Median:19251.33 stddev:28882.07 (150.0%) stddev-sans-first:28920.35 18:03:32 INFO - PID 32062 | Values: 892.4 895.1 897.6 899.9 916.8 16251.5 18016.3 18683.3 18757.0 18873.7 14781.7 14889.3 18293.8 19256.3 20386.5 1036.4 1073.1 1074.4 1074.6 1076.7 1045.7 1064.9 1065.3 1066.8 1067.9 1050.8 1057.8 1098.4 1098.7 1103.0 1080.8 1086.0 1093.6 1095.9 1097.0 765.3 784.6 791.1 797.2 798.1 18973.7 19243.4 19246.3 19285.9 19370.0 18925.4 19627.2 20165.4 20266.4 20390.4 14833.9 15008.7 15277.6 15333.0 15339.9 3518.5 3521.1 3541.8 3554.4 3557.6 107733.0 111338.4 111364.0 112146.0 112423.0 14052.8 14518.0 14787.3 14902.7 14984.1 27408.6 27978.0 28173.4 28322.7 28353.6 7962.4 8310.4 8383.5 8387.1 8434.2 4215.8 4280.6 4314.7 4338.8 4348.1 4903.2 4953.2 4982.3 5066.4 5094.8 860.8 899.3 900.4 902.3 906.1 636.2 645.3 660.9 671.1 680.4 11138.3 11883.6 12003.8 12085.0 12234.8 681.2 682.0 690.9 701.8 701.8 5199.7 5745.9 5854.9 5913.4 6238.7 102481.5 103746.5 104053.5 104190.0 106417.5 86867.0 89593.6 89708.1 90532.1 93669.0 78475.0 81516.6 82204.0 83097.0 84995.0 12300.4 12318.1 12335.7 12822.5 13061.0 36539.8 36756.6 37310.9 37503.6 39232.2 46666.5 48204.5 48730.8 48774.8 49781.0 18647.0 18959.9 19035.3 19164.1 19217.2 17732.2 19516.4 19670.4 20985.8 21037.4 17954.2 18930.5 19153.5 19245.4 19322.1 17802.0 17811.6 17867.5 17958.5 18229.5 16807.8 18274.5 18304.8 18330.0 19301.8 11495.3 12065.6 12353.6 12540.2 12687.1 12983.0 13436.8 14201.7 14318.6 15055.0 18:03:32 INFO - PID 32062 | -------- Summary: end -------- 18:03:32 INFO - PID 32062 | 18:03:32 INFO - TEST-INFO | 32062: exit 0 18:03:32 INFO - TEST-OK | dromaeo_css | took 1110423ms 18:03:32 INFO - TEST-START | kraken 18:03:32 INFO - Initialising browser for kraken test... 18:03:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58863/getInfo.html -profile /tmp/tmphC4Y_F/profile 18:03:33 INFO - TEST-INFO | started process 32474 (/builds/slave/test/build/application/firefox/firefox http://localhost:58863/getInfo.html) 18:03:38 INFO - TEST-INFO | 32474: exit 0 18:03:38 INFO - Browser initialized. 18:03:38 INFO - Running cycle 1/1 for kraken test... 18:03:38 INFO - TEST-INFO | started process 32695 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphC4Y_F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:03:50 INFO - PID 32695 | RSS: Main: 169787392 18:03:50 INFO - PID 32695 | 18:03:52 INFO - PID 32695 | Cycle 1(1): loaded http://localhost:58863/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58863/tests/kraken/driver.html?testName=audio-beat-detection) 18:03:53 INFO - PID 32695 | RSS: Main: 176705536 18:03:53 INFO - PID 32695 | 18:03:56 INFO - PID 32695 | Cycle 1(1): loaded http://localhost:58863/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58863/tests/kraken/driver.html?testName=audio-dft) 18:03:56 INFO - PID 32695 | RSS: Main: 176615424 18:03:56 INFO - PID 32695 | 18:03:59 INFO - PID 32695 | Cycle 1(1): loaded http://localhost:58863/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58863/tests/kraken/driver.html?testName=audio-fft) 18:04:00 INFO - PID 32695 | RSS: Main: 170917888 18:04:00 INFO - PID 32695 | 18:04:02 INFO - PID 32695 | Cycle 1(1): loaded http://localhost:58863/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58863/tests/kraken/driver.html?testName=audio-oscillator) 18:04:03 INFO - PID 32695 | RSS: Main: 171466752 18:04:03 INFO - PID 32695 | 18:04:05 INFO - PID 32695 | Cycle 1(1): loaded http://localhost:58863/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58863/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:04:05 INFO - PID 32695 | RSS: Main: 171499520 18:04:05 INFO - PID 32695 | 18:04:08 INFO - PID 32695 | Cycle 1(1): loaded http://localhost:58863/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58863/tests/kraken/driver.html?testName=imaging-darkroom) 18:04:09 INFO - PID 32695 | RSS: Main: 175058944 18:04:09 INFO - PID 32695 | 18:04:12 INFO - PID 32695 | Cycle 1(1): loaded http://localhost:58863/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58863/tests/kraken/driver.html?testName=imaging-desaturate) 18:04:13 INFO - PID 32695 | RSS: Main: 176226304 18:04:13 INFO - PID 32695 | 18:04:16 INFO - PID 32695 | Cycle 1(1): loaded http://localhost:58863/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58863/tests/kraken/driver.html?testName=json-parse-financial) 18:04:17 INFO - PID 32695 | RSS: Main: 175222784 18:04:17 INFO - PID 32695 | 18:04:18 INFO - PID 32695 | Cycle 1(1): loaded http://localhost:58863/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58863/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:04:19 INFO - PID 32695 | RSS: Main: 175120384 18:04:19 INFO - PID 32695 | 18:04:20 INFO - PID 32695 | Cycle 1(1): loaded http://localhost:58863/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58863/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:04:21 INFO - PID 32695 | RSS: Main: 175296512 18:04:21 INFO - PID 32695 | 18:04:23 INFO - PID 32695 | Cycle 1(1): loaded http://localhost:58863/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58863/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:04:24 INFO - PID 32695 | RSS: Main: 174751744 18:04:24 INFO - PID 32695 | 18:04:26 INFO - PID 32695 | Cycle 1(1): loaded http://localhost:58863/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58863/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:04:27 INFO - PID 32695 | RSS: Main: 175529984 18:04:27 INFO - PID 32695 | 18:04:30 INFO - PID 32695 | Cycle 1(1): loaded http://localhost:58863/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58863/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:04:31 INFO - PID 32695 | RSS: Main: 175783936 18:04:31 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | Cycle 1(1): loaded http://localhost:58863/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58863/tests/kraken/driver.html?testName=ai-astar) 18:04:33 INFO - PID 32695 | RSS: Main: 177086464 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | __start_tp_report 18:04:33 INFO - PID 32695 | _x_x_mozilla_page_load 18:04:33 INFO - PID 32695 | _x_x_mozilla_page_load_details 18:04:33 INFO - PID 32695 | |i|pagename|runs| 18:04:33 INFO - PID 32695 | |0;ai-astar;100;108;109;107;109;110;110;110;113;109 18:04:33 INFO - PID 32695 | |1;audio-beat-detection;155;124;160;172;125;153;145;125;183;121 18:04:33 INFO - PID 32695 | |2;audio-dft;164;163;165;164;161;168;165;170;166;168 18:04:33 INFO - PID 32695 | |3;audio-fft;76;75;76;167;75;75;74;74;96;75 18:04:33 INFO - PID 32695 | |4;audio-oscillator;82;81;133;81;81;83;140;82;82;131 18:04:33 INFO - PID 32695 | |5;imaging-gaussian-blur;115;114;114;113;115;114;114;114;113;115 18:04:33 INFO - PID 32695 | |6;imaging-darkroom;151;150;150;151;150;150;150;149;151;150 18:04:33 INFO - PID 32695 | |7;imaging-desaturate;94;94;94;94;94;94;94;95;94;95 18:04:33 INFO - PID 32695 | |8;json-parse-financial;55;56;55;61;55;55;56;57;55;59 18:04:33 INFO - PID 32695 | |9;json-stringify-tinderbox;55;55;54;55;55;54;56;55;56;55 18:04:33 INFO - PID 32695 | |10;stanford-crypto-aes;75;77;78;77;78;77;76;77;76;77 18:04:33 INFO - PID 32695 | |11;stanford-crypto-ccm;137;127;127;124;126;127;126;134;136;156 18:04:33 INFO - PID 32695 | |12;stanford-crypto-pbkdf2;186;183;194;196;182;187;180;188;195;182 18:04:33 INFO - PID 32695 | |13;stanford-crypto-sha256-iterative;66;67;71;72;72;73;69;69;68;72 18:04:33 INFO - PID 32695 | __end_tp_report 18:04:33 INFO - PID 32695 | __start_cc_report 18:04:33 INFO - PID 32695 | _x_x_mozilla_cycle_collect,955 18:04:33 INFO - PID 32695 | __end_cc_report 18:04:33 INFO - PID 32695 | __startTimestamp1500339873339__endTimestamp 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | ------- Summary: start ------- 18:04:33 INFO - PID 32695 | Number of tests: 14 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | [#0] ai-astar Cycles:10 Average:108.50 Median:109.50 stddev:3.37 (3.1%) stddev-sans-first:1.67 18:04:33 INFO - PID 32695 | Values: 100.0 108.0 109.0 107.0 109.0 110.0 110.0 110.0 113.0 109.0 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | [#1] audio-beat-detection Cycles:10 Average:146.30 Median:154.00 stddev:22.02 (14.3%) stddev-sans-first:23.12 18:04:33 INFO - PID 32695 | Values: 155.0 124.0 160.0 172.0 125.0 153.0 145.0 125.0 183.0 121.0 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | [#2] audio-dft Cycles:10 Average:165.40 Median:165.50 stddev:2.67 (1.6%) stddev-sans-first:2.79 18:04:33 INFO - PID 32695 | Values: 164.0 163.0 165.0 164.0 161.0 168.0 165.0 170.0 166.0 168.0 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | [#3] audio-fft Cycles:10 Average:86.30 Median:75.00 stddev:29.12 (38.8%) stddev-sans-first:30.65 18:04:33 INFO - PID 32695 | Values: 76.0 75.0 76.0 167.0 75.0 75.0 74.0 74.0 96.0 75.0 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | [#4] audio-oscillator Cycles:10 Average:97.60 Median:81.50 stddev:25.68 (31.5%) stddev-sans-first:26.61 18:04:33 INFO - PID 32695 | Values: 82.0 81.0 133.0 81.0 81.0 83.0 140.0 82.0 82.0 131.0 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.71 18:04:33 INFO - PID 32695 | Values: 115.0 114.0 114.0 113.0 115.0 114.0 114.0 114.0 113.0 115.0 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | [#6] imaging-darkroom Cycles:10 Average:150.20 Median:150.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 18:04:33 INFO - PID 32695 | Values: 151.0 150.0 150.0 151.0 150.0 150.0 150.0 149.0 151.0 150.0 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:04:33 INFO - PID 32695 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 95.0 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:56.00 stddev:2.07 (3.7%) stddev-sans-first:2.13 18:04:33 INFO - PID 32695 | Values: 55.0 56.0 55.0 61.0 55.0 55.0 56.0 57.0 55.0 59.0 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | [#9] json-stringify-tinderbox Cycles:10 Average:55.00 Median:55.00 stddev:0.67 (1.2%) stddev-sans-first:0.71 18:04:33 INFO - PID 32695 | Values: 55.0 55.0 54.0 55.0 55.0 54.0 56.0 55.0 56.0 55.0 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | [#10] stanford-crypto-aes Cycles:10 Average:76.80 Median:77.00 stddev:0.92 (1.2%) stddev-sans-first:0.71 18:04:33 INFO - PID 32695 | Values: 75.0 77.0 78.0 77.0 78.0 77.0 76.0 77.0 76.0 77.0 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | [#11] stanford-crypto-ccm Cycles:10 Average:132.00 Median:130.50 stddev:9.61 (7.4%) stddev-sans-first:10.03 18:04:33 INFO - PID 32695 | Values: 137.0 127.0 127.0 124.0 126.0 127.0 126.0 134.0 136.0 156.0 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.30 Median:187.50 stddev:5.87 (3.1%) stddev-sans-first:6.21 18:04:33 INFO - PID 32695 | Values: 186.0 183.0 194.0 196.0 182.0 187.0 180.0 188.0 195.0 182.0 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:71.50 stddev:2.42 (3.4%) stddev-sans-first:2.12 18:04:33 INFO - PID 32695 | Values: 66.0 67.0 71.0 72.0 72.0 73.0 69.0 69.0 68.0 72.0 18:04:33 INFO - PID 32695 | -------- Summary: end -------- 18:04:33 INFO - PID 32695 | 18:04:33 INFO - PID 32695 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:04:34 INFO - TEST-INFO | 32695: exit 0 18:04:34 INFO - TEST-OK | kraken | took 61082ms 18:04:34 INFO - SUITE-END | took 1171s 18:04:34 INFO - Completed test suite (00:19:31) 18:04:34 INFO - javascript benchmark 18:04:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8259.683160064358, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3033.4539782839106, 3048.603681521402, 3099.1012606344175, 3107.5183346581275, 3108.5648009278707, 5951.315598706145, 6021.126936594197, 6028.788992385267, 6030.33666296707, 6038.69806509675, 5982.042873140298, 6038.094285857123, 6039.456448919596, 6047.213862197919, 6056.969715151429, 5519.530839878616, 5521.613487055918, 5526.115821468566, 5526.58550608704, 5566.8608284793, 5549.0289199390145, 5554.361248456439, 5557.305336832896, 5565.2486914266465, 5577.553795696334, 5565.6104072714925, 5570.969370666419, 5579.38626751057, 5581.0, 5591.1054231323, 5561.610687251894, 5570.0, 5578.832635020915, 5587.888242235153, 5599.1041433370465, 5491.0, 5526.226328313999, 5526.419725928759, 5533.0, 5646.858828529279, 6060.909086363708, 6098.908516372258, 6099.512039036867, 6105.877882442326, 6138.0, 6266.248050233956, 6383.265924418705, 6516.804495865084, 6542.476601871839, 6574.8356291093105, 6465.450436712885, 6544.672766361663, 6625.834354141137, 6657.533972621918, 6659.200895892475, 5946.8810623787285, 5958.463738263534, 5974.820755377345, 5978.252718410199, 5986.371430999726, 6511.28375878653, 6522.869542609121, 6588.637624930579, 6605.768798092044, 6632.734690612369, 6222.222222222223, 6233.189685340877, 6272.341404152589, 6284.842878927972, 6292.182016337938, 6318.336574659686, 6328.651924144124, 6372.203474565679, 6407.13503677007, 6412.006139048455, 6315.336889626615, 6340.619562826245, 6351.301356850743, 6366.936330636634, 6446.645434501147, 6490.415862572297, 6492.156019717407, 6561.671916404161, 6574.572652777554, 6577.835554111186, 5266.578673706094, 5277.683338999673, 5351.866203344947, 5377.435369286169, 5391.056565101092, 4383.956160438419, 4472.44094488189, 4479.686421950432, 4487.282034874404, 4492.528284530143, 5513.917291240635, 5527.944720552823, 5535.778568857281, 5539.529140023063, 5540.889182216333, 6400.7119679614525, 6402.6158430494315, 6436.259830119502, 6442.967785161138, 6456.935430645727, 5476.479734425223, 5544.029794785977, 5545.639533430314, 5570.526505247019, 5571.498565129158, 5627.465390787868, 5683.346415162309, 5694.629849059798, 5706.71466426677, 5707.200991861143, 6189.938100618936, 6243.96878015607, 6333.524985626096, 6342.778002769881, 6403.231612206472, 6522.902156467562, 6541.149650545399, 6618.768343107968, 6640.634765087966, 6648.867022659617, 6309.274433440213, 6315.652639104894, 6372.0, 6425.004124360731, 6444.0, 6267.905981410374, 6335.303116657072, 6362.522810789117, 6379.266384365672, 6427.357264273535, 5662.8301150965535, 6407.0, 6483.448906842971, 6554.508411869034, 6671.432928201158, 6318.6840657965995, 6333.429991300621, 6404.583702059351, 6423.389777971085, 6427.068075129174, 6341.936580634319, 6343.9365606343345, 6414.134091897534, 6416.807495775134, 6430.035494675836, 6041.365656605993, 6122.204113465221, 6139.693015349126, 6141.355157708376, 6273.905891411724, 6272.341404152498, 6300.621962682254, 6323.747050118128, 6325.96837015812, 6351.618902865843, 6055.727492262947, 6118.326984031664, 6154.969225153845, 6222.284437289589, 6303.747850086129], "value": 5903.0813712480485, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [629.6222266640017, 654.2312782480585, 685.4139710547482, 690.6891898645611, 1150.5570355413163, 29066.0, 29711.257218569546, 29937.55093673594, 39493.210135797264, 40289.0, 39376.21247575054, 39431.605683943155, 39849.60526381577, 39987.60012399875, 40020.0, 7423.665935032936, 7619.42854285928, 7627.771166865003, 7630.0, 7638.5798781067015, 7411.8147046324, 7488.213737557559, 7533.773986780406, 7534.660940257674, 7550.3204711575945, 7529.811754706148, 7552.622368881562, 7577.204393538682, 7638.236176382345, 7676.155622881479, 7536.397088232956, 7607.847843043163, 7618.466707330462, 7625.923740762577, 7631.885521717179, 7339.633018349061, 7445.85108297831, 7450.217727138681, 7456.067991501062, 7474.177840437547, 36268.63731362679, 36298.8185059073, 36308.818455907814, 36617.81691091527, 36674.26651466983, 3499.842507087187, 3548.716102711777, 3583.283343331344, 3584.4981702561663, 3585.354636165489, 29906.55140172899, 29940.550891736642, 29951.0, 30092.699073009207, 30214.395712085854, 4764.332993380929, 4770.427548694179, 4782.258749893772, 4814.398200224972, 4866.489018653061, 179462.10268948702, 180030.0998494999, 232186.0, 233722.0, 235704.0, 48491.75754121207, 48553.0, 48667.513324867, 48671.513284867404, 49050.0, 50220.0, 50332.0, 50534.220617865634, 50558.74720626447, 50736.238956415684, 14416.062955907832, 14512.637184070272, 14524.927375363266, 14563.708725825636, 14566.0, 11780.0, 11831.112666550043, 11901.047916166686, 11903.702407439883, 11908.761824763456, 14199.929000354932, 14270.928645356707, 14323.713525729636, 14329.85670143306, 14364.35360408784, 5639.697090474341, 5688.744006519716, 5854.385289544622, 5859.1797148399255, 5919.289685237799, 976.4531862157214, 981.4160574458186, 987.4815721745997, 994.7910938702809, 1022.2639699416557, 21572.244971425924, 22113.336599902028, 22377.0, 22415.551688966454, 22457.43856403603, 1070.1687957113515, 1101.3391964821044, 1104.5471356743697, 1107.3189988157387, 1116.1684545014016, 11458.713532161762, 11699.0, 11817.290962542274, 11916.0, 12031.458584363725, 235426.0, 236102.0, 236771.0, 237195.0, 237450.0, 51645.48354516482, 51698.74150629223, 51850.740746296025, 52037.47962520402, 52071.73964130155, 1371.2732251906505, 1379.0829098649485, 1390.0, 1390.0825455199722, 1411.5694713112393, 25448.745512545378, 27014.594781078693, 27047.594286086118, 27287.863560682068, 27373.58939615947, 35193.0, 35487.757928472376, 35489.29021419609, 35523.46714799228, 35547.46678799872, 181520.0, 181902.0, 182182.0, 182345.0, 182630.0, 34122.48816267808, 34255.82872085624, 34419.483707744905, 34638.13404664904, 34788.478172827934, 41279.0, 41911.371329430694, 42225.577744222166, 42601.36097958471, 42963.140737184454, 28943.986960456285, 30327.0, 31286.217844554067, 31724.524132137576, 32002.359952800347, 30508.474576271543, 30621.0, 30943.845280773454, 30950.22624434407, 31238.0, 33078.0, 33911.66088339085, 34337.65662343444, 34372.0, 34547.65452345445, 29617.25956851096, 30178.54732179063, 30462.086137415914, 32647.510287345234, 33304.1673958153, 24108.87945560331, 24183.395415114763, 24353.39116522101, 24394.0, 24589.893454794936], "value": 16703.664371835115, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.1739026241161, 564.3143580549631, 570.2501210907653, 578.8176724331837, 592.7362323765924, 20596.691049634268, 21393.251236206717, 21430.89284553577, 21472.033758480866, 22023.559528809415, 24354.904029318677, 26177.34556636085, 26190.345241368927, 26225.73774262257, 26280.21159365222, 6186.721597528111, 6274.59215151016, 6347.8413039673915, 6348.365163483661, 6357.872842543169, 6154.692265386735, 6221.12904193411, 6230.439260466557, 6255.40573645503, 6272.749090036393, 3767.585565587783, 5010.649254552182, 6261.311255761862, 6265.8120256392385, 6316.778912738055, 6289.37106289372, 6290.874182516347, 6295.024271237966, 6303.527235457359, 6317.715702793385, 5819.970900145515, 5850.795222167247, 5866.882662346772, 5886.9705651471895, 5891.351951285355, 27423.588646170323, 29404.411911761857, 29697.851510742523, 29730.70269297301, 29754.404911901856, 1910.5319196796734, 1951.024487756122, 1961.656710075731, 1965.0862349007637, 1966.2724791827115, 18715.438536843918, 18933.33733319327, 18993.905030474896, 19007.90496047511, 19066.904665476723, 2260.4348912771807, 2310.803581695558, 2333.8482998605036, 2357.7642235776457, 2360.433495960957, 132227.33886330508, 135437.0, 135793.3210333942, 141356.0, 141540.0, 14261.928690356483, 14354.784678229835, 14483.78274325865, 14577.92711036438, 15039.849601503845, 23346.18288359899, 23496.882515587655, 23523.0, 23525.764742352356, 23557.882210588836, 9906.900930990741, 10121.1903047756, 10132.493375331203, 10263.538140783683, 10367.377957322586, 9259.953700231456, 9401.388909720847, 9401.952990235142, 9445.19715824149, 9453.43279403238, 8852.734417967471, 9070.229030532479, 9160.770980725536, 9313.53432328381, 9403.29475289356, 2600.9609855852177, 2618.7250338714543, 2679.169457468152, 2725.2914242296747, 2725.822821516595, 943.7216021273604, 948.1892981500717, 948.4735971535864, 967.2068903499204, 988.9950550247347, 19898.204071837255, 20005.299814506423, 20303.0, 23185.884070579876, 25008.749912501007, 1033.7829055898346, 1040.1990467340256, 1045.9738506537244, 1048.743057950807, 1060.5863713151878, 11050.889491105147, 11485.942570287096, 11527.596534121265, 11622.418879056182, 11783.705407364885, 467908.0, 468561.0, 484055.0, 486487.0, 488264.0, 116251.0, 118657.40671296588, 118746.40626796984, 118859.0, 121159.39420302842, 1228.2139430764319, 1243.7325974915436, 1278.5461161287794, 1285.607889593683, 1286.3311078239262, 17940.91029544844, 17977.0, 18139.637207256044, 18149.27402903869, 18443.17005734772, 53035.20447193372, 53721.73139134279, 54547.0, 58555.70722146532, 58765.70617146887, 69544.0, 71293.64353178201, 72322.63838680772, 72972.63513682397, 73020.26979730347, 14180.645483862985, 14917.776233356726, 14919.328630211883, 14929.10425374481, 15059.096454212782, 22755.658665119707, 22789.0, 22996.310110696704, 23222.187223447097, 23465.413364665335, 17852.19665115047, 17895.463136105936, 18058.909705451388, 18118.909405452887, 18384.264629415207, 15559.688806224038, 16907.323707052074, 17002.659946801243, 17217.91391043037, 17782.555436114202, 19128.330508432136, 19233.61532769309, 19263.133159008157, 19332.0, 19364.8063519363, 14744.410223590936, 20875.895620521802, 22116.0, 22723.318300451014, 22787.31638050861, 18271.54321141982, 19399.515012124808, 19579.216831326587, 19596.60806783828, 19618.803811961698], "value": 12009.46061623936, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.2827273272433, 1366.883505607216, 1375.6217040313913, 1380.1443105274727, 1386.126740153703, 13122.737545249101, 13673.179609223454, 13794.724105517884, 13842.930785346072, 13856.58430247093, 13250.867491325083, 14369.56891293263, 14371.496997605085, 14401.855981440183, 14419.0, 2643.352378667229, 2671.091828778215, 2673.5588627876473, 2676.4647070585866, 2676.69218039925, 2577.05937332874, 2621.646077779504, 2621.7509336612993, 2623.986880065597, 2627.8686065696734, 2501.6372625969225, 2538.70804857441, 2556.169244995375, 2569.794416446689, 2571.768540831325, 2599.9220023399234, 2669.973300266992, 2678.2902530829347, 2678.9196324110308, 2683.409649877024, 2546.9108581199753, 2548.7578680025554, 2563.6410902473667, 2568.22953114065, 2569.601711734684, 13566.796498052538, 13747.38136783847, 13773.517926872611, 13784.793228101486, 13837.169769813843, 7534.849303013963, 7806.921930780733, 7844.0, 7855.253750893656, 7858.057033156001, 4887.511248875119, 4947.134251506008, 4983.950160498421, 5138.614603904684, 5156.9742151289, 1830.725391191332, 1846.2153584726439, 1864.7482589850333, 1895.7061655443429, 1903.038965322517, 22959.196428124935, 23129.768702313097, 23135.76864231353, 23255.0, 23409.0, 6290.779822706183, 6347.787215020153, 6479.254885688206, 6524.184476940382, 6562.77030303937, 15479.5356139316, 15839.841601584067, 15901.52295431139, 15930.920345398199, 16003.119828409317, 7144.857102857914, 7242.23956484572, 7305.269473052652, 7318.634068296564, 7320.487565870444, 6724.193096828412, 6758.729650814011, 6768.932310676929, 6778.762743303961, 6818.488613353919, 4387.670924680661, 4573.931391029138, 4588.26587745959, 4609.6542759292515, 4652.720501862015, 1408.9295535223403, 1469.2947385255138, 1477.1727832413667, 1488.6501672107047, 1489.508462207469, 864.481311213267, 878.042933202812, 880.3925291548811, 893.687209476691, 918.9724308270762, 6944.201416837027, 7024.683889224997, 7069.505134640629, 7088.539244949062, 7130.42956563484, 684.3430306905327, 691.0, 716.6774951271949, 723.4971694672153, 729.0231090338971, 4015.9558514786368, 4159.0, 4326.437563116775, 4773.0, 4900.656954013256, 17283.0, 18283.72574411385, 19063.61872762537, 19065.523361916064, 19154.329598463988, 18541.907290463732, 18544.536386590444, 18620.81379186218, 18786.0, 18797.436076917715, 10320.587176513005, 10581.470926453647, 10685.0, 10854.891451085705, 11041.116710663127, 7168.856622867409, 7717.305442510201, 7827.295543401121, 7900.565468899265, 8077.0, 15666.530004099895, 15859.762103568224, 15903.0, 16256.268467918735, 16602.0, 15733.134677592841, 16162.838371616133, 16493.257803398636, 16542.09018503994, 16921.576960576083, 10994.450277485934, 11145.498452569816, 11263.887361126612, 11265.774684506428, 11283.492242848928, 12745.872541274468, 13065.608031759062, 13106.082574220094, 13113.803292950422, 13136.27750473733, 10096.69709908704, 10222.488875556044, 10506.789864202827, 10555.683329500127, 10639.255252132276, 9599.136077752757, 10142.54358553852, 10228.59085636596, 10307.639232626821, 10340.965903409598, 8620.181082797124, 9056.773580660536, 9323.347365684336, 9363.391379560308, 9416.576254068446, 6407.135036769976, 6552.967235163794, 6598.381133206679, 6604.306547812603, 6804.285550017177, 7012.112189838446, 7033.437325014089, 7234.96382518084, 7252.2594577302125, 7290.453216008715], "value": 6147.398609763209, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.2684826456846, 496.3200415430859, 513.6096566609377, 525.8632755483578, 530.0300450176178, 6032.788852390167, 6035.486983606394, 6121.510279177672, 6146.631202127875, 6159.384061593844, 5796.0, 5944.256967879015, 5998.310194327653, 5998.310194327653, 6015.248093988252, 4860.198067318888, 4880.219164933611, 4888.413390393158, 4900.534449227335, 4909.3126962225315, 4839.717474869162, 4887.584555312785, 4895.461499235082, 4911.410630724318, 4921.434035085975, 4841.951580484185, 4875.756212189394, 4907.2884431757375, 4912.336834527347, 4934.876628084308, 4693.953060469403, 4872.902541949141, 4883.755812209375, 4892.5596696297325, 4909.435414927258, 4416.098131074505, 4420.778961051967, 4424.690271680984, 4436.179306828247, 4444.844430444951, 5892.558058145656, 5914.0, 5915.438033386821, 5924.1267985027225, 5944.167816505693, 5619.662820230799, 5622.212890195376, 5631.690257035862, 5654.992477670409, 5668.0, 6416.871662566769, 6425.485961123099, 6432.742690292383, 6445.419912207877, 6478.125453063823, 4812.879678008043, 4832.405306248929, 4832.806687732486, 4846.1761500544635, 4892.461829198785, 6759.254633561433, 6767.1879374475375, 6769.492288078364, 6797.52417330787, 6798.592084474947, 4858.198397264412, 4933.629977751683, 4974.626902982219, 4994.9500504994485, 5007.549320561167, 4977.776000080005, 4995.800167993312, 5019.272205530251, 5029.2958985742025, 5051.87370315745, 5545.1682247663175, 5577.2191893135, 5596.748146333425, 5619.600141914165, 5640.85897852557, 6365.777197798055, 6368.395002474757, 6397.4562162216735, 6475.611463312216, 6518.706658200297, 1726.9395571154948, 1754.7367894815625, 1756.7101428264448, 1769.4160926894094, 1770.7958588160175, 1084.6583326252205, 1171.619223752284, 1181.0905602685884, 1190.5356910804794, 1213.660175150959, 5355.223492593552, 5477.561795056386, 5517.972410138004, 5587.284455836904, 5627.859303517363, 6508.316626754217, 6532.967335163389, 6563.995962462894, 6626.7017984190825, 6653.0, 5523.0, 5577.247071645276, 5586.217929489874, 5630.380658127603, 5647.463490968352, 3438.157651375429, 3470.722342212617, 3487.511748355232, 3506.263684626257, 3534.5228394166975, 6345.650989195638, 6369.108324834527, 6400.743970241138, 6437.839054023687, 6477.6437295948235, 6727.192736871607, 6739.52823302374, 6740.39336459721, 6786.728530858809, 6827.0, 6376.1392212051705, 6394.104825324458, 6428.55000149985, 6459.999999999906, 6480.4815614750705, 6525.2822189558165, 6551.737930482727, 6552.57408268461, 6580.479443497706, 6600.0, 6548.770793022221, 6574.934250657432, 6585.407313341822, 6607.735690572324, 6638.0, 6211.534134939988, 6377.118749968863, 6429.210238372442, 6448.258450278278, 6498.772542960974, 4825.734584597741, 4841.564259216688, 4884.267359896044, 4889.315495830658, 4927.507249275044, 6572.047053177358, 6631.469482441489, 6667.699953502201, 6690.230623478362, 6708.932910670831, 6317.399847014526, 6380.9042864358, 6438.774642887476, 6440.388163124496, 6480.416762491398, 6335.841603959938, 6403.775867844603, 6440.871182576416, 6453.128827608213, 6496.642684652389, 6279.591826531261, 6294.261996031741, 6338.683065846782, 6390.7763228286785, 6396.769631336146, 6235.594686345373, 6352.618842869258, 6415.781001609679, 6416.165898433173, 6453.45145662624, 5655.830325090254, 6127.417895299939, 6139.171211886338, 6180.47465965398, 6181.258249010148], "value": 4958.470264005693, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [892.4065496444863, 895.0780695883243, 897.6274845938935, 899.8515244984576, 916.8349697054532, 16251.51245462638, 18016.279348826047, 18683.252669893187, 18757.0, 18873.71689424658, 14781.704365912676, 14889.329980150893, 18293.817061829377, 19256.326028589003, 20386.490337741565, 1036.3833519056168, 1073.0557109743415, 1074.4359211413991, 1074.5594306334403, 1076.6877605494396, 1045.6601604478574, 1064.872215334161, 1065.3075500924383, 1066.7973085113842, 1067.8611780468527, 1050.7950949564838, 1057.7672911959357, 1098.412349393075, 1098.7088421568292, 1103.0183137008069, 1080.8108580998294, 1086.022579678288, 1093.6445655162065, 1095.934243945362, 1096.9616063437782, 765.3150430364792, 784.5684873319651, 791.0941971442724, 797.1510341486298, 798.1140933563739, 18973.715394269097, 19243.422697319103, 19246.326378576683, 19285.903570482198, 19370.0, 18925.43223703291, 19627.214911403527, 20165.395038148734, 20266.392008239778, 20390.388288351372, 14833.92583037078, 15008.699826003418, 15277.61805954849, 15333.003354781904, 15339.923300383427, 3518.5074089627215, 3521.0669172669263, 3541.769784963969, 3554.413521768905, 3557.6442355764216, 107733.0, 111338.44330778375, 111364.0, 112145.99999999919, 112423.0, 14052.789208161887, 14518.0, 14787.334569944378, 14902.701945961237, 14984.100953942798, 27408.573928258967, 27978.0, 28173.43653126967, 28322.71677283201, 28353.574696379572, 7962.442629016029, 8310.376721745894, 8383.455075420079, 8387.119352467904, 8434.15658434164, 4215.810288537024, 4280.614744672995, 4314.654827613784, 4338.8047537860875, 4348.130373925228, 4903.239997800347, 4953.157963146274, 4982.327385802942, 5066.392032956069, 5094.821681241213, 860.8235311761199, 899.2670973156856, 900.4417261298037, 902.3232575568323, 906.0758026812614, 636.2015670333701, 645.2740666749906, 660.9438197753149, 671.1074271219267, 680.3706571421515, 11138.33170009802, 11883.58407455735, 12003.759924801456, 12085.0, 12234.81647775284, 681.1961884975677, 681.9863602728017, 690.9101816763889, 701.824543864034, 701.8350687588413, 5199.740012999335, 5745.942540574624, 5854.912176317359, 5913.408659134052, 6238.656873871981, 102481.48759256156, 103746.48126759469, 104053.47973260086, 104190.0, 106417.46791265994, 86867.0, 89593.55203223941, 89708.10291897129, 90532.09467905236, 93669.0, 78475.0, 81516.59241703872, 82204.0, 83097.0, 84995.0, 12300.446479908247, 12318.137730358789, 12335.69160770952, 12822.487100515875, 13061.0, 36539.817300913324, 36756.632433676394, 37310.94789786588, 37503.624963750015, 39232.215355692155, 46666.533334666216, 48204.51795482, 48730.75634621804, 48774.75612622056, 49781.0, 18647.0, 18959.90520047391, 19035.333763318216, 19164.137613807132, 19217.231310747415, 17732.202050908, 19516.414507564798, 19670.40988770339, 20985.790142098384, 21037.354058618883, 17954.192061357007, 18930.52673683114, 19153.52116197106, 19245.422637320902, 19322.130504127068, 17802.0, 17811.643767124326, 17867.463976080737, 17958.461246162635, 18229.45311640653, 16807.831921681114, 18274.543136421697, 18304.81695183031, 18330.0, 19301.806981930564, 11495.252808567417, 12065.638030859089, 12353.62939111828, 12540.184887982618, 12687.11190216676, 12983.0, 13436.798448023092, 14201.715965680834, 14318.642033949234, 15055.0], "value": 8796.286418282738, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 108.0, 109.0, 107.0, 109.0, 110.0, 110.0, 110.0, 113.0, 109.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 124.0, 160.0, 172.0, 125.0, 153.0, 145.0, 125.0, 183.0, 121.0], "value": 146.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 165.0, 164.0, 161.0, 168.0, 165.0, 170.0, 166.0, 168.0], "value": 165.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 167.0, 75.0, 75.0, 74.0, 74.0, 96.0, 75.0], "value": 86.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 133.0, 81.0, 81.0, 83.0, 140.0, 82.0, 82.0, 131.0], "value": 97.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 113.0, 115.0, 114.0, 114.0, 114.0, 113.0, 115.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 150.0, 151.0, 150.0, 150.0, 150.0, 149.0, 151.0, 150.0], "value": 150.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 61.0, 55.0, 55.0, 56.0, 57.0, 55.0, 59.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 55.0, 55.0, 54.0, 56.0, 55.0, 56.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 78.0, 77.0, 78.0, 77.0, 76.0, 77.0, 76.0, 77.0], "value": 76.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 127.0, 127.0, 124.0, 126.0, 127.0, 126.0, 134.0, 136.0, 156.0], "value": 132.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 194.0, 196.0, 182.0, 187.0, 180.0, 188.0, 195.0, 182.0], "value": 187.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 71.0, 72.0, 72.0, 73.0, 69.0, 69.0, 68.0, 72.0], "value": 69.9, "unit": "score"}]}]} 18:04:34 INFO - Return code: 0 18:04:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:04:34 INFO - # TBPL SUCCESS # 18:04:34 INFO - Running post-action listener: _package_coverage_data 18:04:34 INFO - Running post-action listener: _resource_record_post_action 18:04:34 INFO - [mozharness: 2017-07-18 01:04:34.601864Z] Finished run-tests step (success) 18:04:34 INFO - Running post-run listener: _resource_record_post_run 18:04:34 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 450002944; Read time: 36; Write time: 325100 18:04:34 INFO - TinderboxPrint: CPU usage
13.1% 18:04:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 36 18:04:34 INFO - TinderboxPrint: I/O write bytes / time
450,002,944 / 325,100 18:04:34 INFO - TinderboxPrint: CPU idle
8,245.2 (86.9%) 18:04:34 INFO - TinderboxPrint: CPU user
1,199.4 (12.6%) 18:04:34 INFO - TinderboxPrint: Swap in / out
0 / 0 18:04:34 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 120320000; Read time: 0; Write time: 88488 18:04:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:04:34 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 329682944; Read time: 0; Write time: 236612 18:04:35 INFO - Running post-run listener: _upload_blobber_files 18:04:35 INFO - Blob upload gear active. 18:04:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:04:35 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 18:04:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:04:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:04:35 INFO - Traceback (most recent call last): 18:04:35 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 18:04:35 INFO - main() 18:04:35 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 18:04:35 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 18:04:35 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 18:04:35 INFO - return set(response.json().get('whitelist', [])) 18:04:35 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 18:04:35 INFO - return json.loads(self.text or self.content, **kwargs) 18:04:35 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 18:04:35 INFO - return _default_decoder.decode(s) 18:04:35 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 18:04:35 INFO - obj, end = self.raw_decode(s) 18:04:35 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 18:04:35 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 18:04:35 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 18:04:35 ERROR - Return code: 1 18:04:35 INFO - Setting buildbot property blobber_files to {} 18:04:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:04:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:04:35 INFO - Contents: 18:04:35 INFO - blobber_files:{} 18:04:35 INFO - Running post-run listener: copy_logs_to_upload_dir 18:04:35 INFO - Copying logs to upload dir... 18:04:35 INFO - mkdir: /builds/slave/test/build/upload/logs 18:04:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.717181 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-07-17 18:04:33.664767) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-17 18:04:33.668046) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011421 build_url: 'https://queue.taskcluster.net/v1/task/QAVNQk1qRdSbN-F7KCbs2g/artifacts/public/build/target.tar.bz2' blobber_files: '{}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-17 18:04:33.720323) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:04:33.720682) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1500338630.353049-1660104472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004524 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:04:33.770877) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-17 18:04:33.771274) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-17 18:04:33.771628) ========= ========= Total master_lag: 0.15 =========