builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-089 starttime: 1500423235.04 results: success (0) revision: 1b065ffd8a535a0ad4c39a912af18e948e6a42c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-18 17:13:55.043963) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-18 17:13:55.044482) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-18 17:13:55.044788) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-18 17:13:55.065900) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 17:13:55.066232) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-18 17:13:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.6M=0s 2017-07-18 17:13:55 (42.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111903 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 17:13:55.191713) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 17:13:55.191995) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013687 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 17:13:55.254275) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 17:13:55.254586) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1b065ffd8a535a0ad4c39a912af18e948e6a42c1 --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 1b065ffd8a535a0ad4c39a912af18e948e6a42c1 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-18 17:13:55,341 truncating revision to first 12 chars 2017-07-18 17:13:55,341 Setting DEBUG logging. 2017-07-18 17:13:55,341 attempt 1/10 2017-07-18 17:13:55,341 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1b065ffd8a53?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-18 17:13:55,949 unpacking tar archive at: mozilla-central-1b065ffd8a53/testing/mozharness/ program finished with exit code 0 elapsedTime=0.960016 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 17:13:56.244897) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-18 17:13:56.245201) ========= script_repo_revision: 1b065ffd8a535a0ad4c39a912af18e948e6a42c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-18 17:13:56.245551) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-18 17:13:56.245830) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-18 17:13:56.266748) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-07-18 17:13:56.267019) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:13:56 INFO - MultiFileLogger online at 20170718 17:13:56 in /builds/slave/test 17:13:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 17:13:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:13:56 INFO - {'append_to_log': False, 17:13:56 INFO - 'base_work_dir': '/builds/slave/test', 17:13:56 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 17:13:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:13:56 INFO - 'branch': 'Firefox-Non-PGO', 17:13:56 INFO - 'buildbot_json_path': 'buildprops.json', 17:13:56 INFO - 'code_coverage': False, 17:13:56 INFO - 'config_files': ('talos/linux_config.py',), 17:13:56 INFO - 'default_actions': ('clobber', 17:13:56 INFO - 'read-buildbot-config', 17:13:56 INFO - 'download-and-extract', 17:13:56 INFO - 'populate-webroot', 17:13:56 INFO - 'create-virtualenv', 17:13:56 INFO - 'install', 17:13:56 INFO - 'setup-mitmproxy', 17:13:56 INFO - 'run-tests'), 17:13:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:13:56 INFO - 'disable_ccov_upload': False, 17:13:56 INFO - 'download_minidump_stackwalk': True, 17:13:56 INFO - 'download_symbols': 'ondemand', 17:13:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:13:56 INFO - 'tooltool.py': '/tools/tooltool.py', 17:13:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:13:56 INFO - '/tools/misc-python/virtualenv.py')}, 17:13:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:13:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:13:56 INFO - 'gecko_profile': False, 17:13:56 INFO - 'gecko_profile_interval': 0, 17:13:56 INFO - 'installer_path': 'installer.exe', 17:13:56 INFO - 'log_level': 'info', 17:13:56 INFO - 'log_name': 'talos', 17:13:56 INFO - 'log_to_console': True, 17:13:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:13:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:13:56 INFO - 'opt_config_files': (), 17:13:56 INFO - 'pip_index': False, 17:13:56 INFO - 'suite': 'dromaeojs-e10s', 17:13:56 INFO - 'system_bits': '32', 17:13:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:13:56 INFO - 'title': 'talos-linux64-ix-089', 17:13:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:13:56 INFO - 'use_talos_json': True, 17:13:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:13:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:13:56 INFO - 'work_dir': 'build'} 17:13:56 INFO - [mozharness: 2017-07-19 00:13:56.460920Z] Running clobber step. 17:13:56 INFO - Running pre-action listener: _resource_record_pre_action 17:13:56 INFO - Running main action method: clobber 17:13:56 INFO - rmtree: /builds/slave/test/build 17:13:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:13:57 INFO - Running post-action listener: _resource_record_post_action 17:13:57 INFO - [mozharness: 2017-07-19 00:13:57.488801Z] Finished clobber step (success) 17:13:57 INFO - [mozharness: 2017-07-19 00:13:57.488902Z] Running read-buildbot-config step. 17:13:57 INFO - Running pre-action listener: _resource_record_pre_action 17:13:57 INFO - Running main action method: read_buildbot_config 17:13:57 INFO - Using buildbot properties: 17:13:57 INFO - { 17:13:57 INFO - "project": "", 17:13:57 INFO - "product": "firefox", 17:13:57 INFO - "who": "kwierso@gmail.com", 17:13:57 INFO - "installer_path": "public/build/target.tar.bz2", 17:13:57 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 17:13:57 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 17:13:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 17:13:57 INFO - "stage_platform": "linux64", 17:13:57 INFO - "basedir": "/builds/slave/test", 17:13:57 INFO - "buildnumber": 1016, 17:13:57 INFO - "platform": "ubuntu64_hw", 17:13:57 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:13:57 INFO - "slavebuilddir": "test", 17:13:57 INFO - "taskId": "fZVyZe4NSKyA4CxOgk1XPQ", 17:13:57 INFO - "branch": "mozilla-central", 17:13:57 INFO - "script_repo_revision": "production", 17:13:57 INFO - "revision": "1b065ffd8a535a0ad4c39a912af18e948e6a42c1", 17:13:57 INFO - "slavename": "talos-linux64-ix-089", 17:13:57 INFO - "repo_path": "mozilla-central" 17:13:57 INFO - } 17:13:57 INFO - Finding installer, test and symbols from parent task. 17:13:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fZVyZe4NSKyA4CxOgk1XPQ'}, attempt #1 17:13:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fZVyZe4NSKyA4CxOgk1XPQ'}, attempt #1 17:13:58 INFO - Task dependencies: fGcggan9T8GnTEk2lna9eQ 17:13:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ'}, attempt #1 17:13:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.tar.bz2 17:13:58 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/test_packages.json 17:13:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.crashreporter-symbols.zip 17:13:58 INFO - Running post-action listener: _resource_record_post_action 17:13:58 INFO - [mozharness: 2017-07-19 00:13:58.848971Z] Finished read-buildbot-config step (success) 17:13:58 INFO - [mozharness: 2017-07-19 00:13:58.849191Z] Running download-and-extract step. 17:13:58 INFO - Running pre-action listener: _resource_record_pre_action 17:13:58 INFO - Running main action method: download_and_extract 17:13:58 INFO - mkdir: /builds/slave/test/build/tests 17:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:13:58 INFO - trying https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.test_packages.json 17:13:58 INFO - Downloading https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:13:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:13:59 INFO - Downloaded 989 bytes. 17:13:59 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:13:59 INFO - Using the following test package requirements: 17:13:59 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:13:59 INFO - u'common': [u'target.common.tests.zip'], 17:13:59 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:13:59 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:13:59 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:13:59 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:13:59 INFO - u'mozbase': [u'target.common.tests.zip'], 17:13:59 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:13:59 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:13:59 INFO - u'web-platform': [u'target.common.tests.zip', 17:13:59 INFO - u'target.web-platform.tests.tar.gz'], 17:13:59 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:13:59 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:13:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.common.tests.zip 17:13:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:13:59 INFO - Fetch https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.common.tests.zip into memory 17:14:02 INFO - Content-Length response header: 72754956 17:14:02 INFO - Bytes received: 72754956 17:14:13 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:14:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.common.tests.zip 17:14:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:14:13 INFO - Fetch https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.common.tests.zip into memory 17:14:15 INFO - Content-Length response header: 72754956 17:14:15 INFO - Bytes received: 72754956 17:14:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.talos.tests.zip 17:14:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:14:29 INFO - Fetch https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.talos.tests.zip into memory 17:14:30 INFO - Content-Length response header: 14495033 17:14:30 INFO - Bytes received: 14495033 17:14: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:14:30 INFO - trying https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.tar.bz2 17:14:30 INFO - Downloading https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:14:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:14:32 INFO - Downloaded 61893168 bytes. 17:14:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.tar.bz2 17:14:32 INFO - mkdir: /builds/slave/test/properties 17:14:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:14:32 INFO - Writing to file /builds/slave/test/properties/build_url 17:14:32 INFO - Contents: 17:14:32 INFO - build_url:https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.tar.bz2 17:14:32 INFO - Running post-action listener: _resource_record_post_action 17:14:32 INFO - Running post-action listener: set_extra_try_arguments 17:14:32 INFO - [mozharness: 2017-07-19 00:14:32.003824Z] Finished download-and-extract step (success) 17:14:32 INFO - [mozharness: 2017-07-19 00:14:32.003921Z] Running populate-webroot step. 17:14:32 INFO - Running pre-action listener: _resource_record_pre_action 17:14:32 INFO - Running main action method: populate_webroot 17:14:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:14:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:14:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:14:32 INFO - '--tppagecycles', 17:14:32 INFO - '7'], 17:14:32 INFO - 'tests': ['tsvgm']}}, 17:14:32 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:14:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:14:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:14:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:14:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:14:32 INFO - 'talos_options': ['--disable-e10s'], 17:14:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:14:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:14:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:14:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:14:32 INFO - 'talos_options': ['--disable-e10s'], 17:14:32 INFO - 'tests': ['damp', 'tps']}, 17:14:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:14:32 INFO - 'tests': ['damp', 'tps']}, 17:14:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:14:32 INFO - 'tests': ['dromaeo_dom']}, 17:14:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:14:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:14:32 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:14:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:14:32 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:14:32 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:14:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:14:32 INFO - 'tests': ['a11yr', 17:14:32 INFO - 'ts_paint', 17:14:32 INFO - 'tpaint', 17:14:32 INFO - 'sessionrestore', 17:14:32 INFO - 'sessionrestore_no_auto_restore', 17:14:32 INFO - 'tabpaint']}, 17:14:32 INFO - 'other-e10s': {'tests': ['a11yr', 17:14:32 INFO - 'ts_paint', 17:14:32 INFO - 'tpaint', 17:14:32 INFO - 'sessionrestore', 17:14:32 INFO - 'sessionrestore_many_windows', 17:14:32 INFO - 'sessionrestore_no_auto_restore', 17:14:32 INFO - 'tabpaint']}, 17:14:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:14:32 INFO - 'ts_paint', 17:14:32 INFO - 'tpaint', 17:14:32 INFO - 'sessionrestore', 17:14:32 INFO - 'sessionrestore_many_windows', 17:14:32 INFO - 'sessionrestore_no_auto_restore', 17:14:32 INFO - 'tabpaint']}, 17:14:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:14:32 INFO - 'ts_paint', 17:14:32 INFO - 'tpaint', 17:14:32 INFO - 'sessionrestore', 17:14:32 INFO - 'sessionrestore_many_windows', 17:14:32 INFO - 'sessionrestore_no_auto_restore', 17:14:32 INFO - 'tabpaint']}, 17:14:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:14:32 INFO - 'tests': ['a11yr', 17:14:32 INFO - 'ts_paint', 17:14:32 INFO - 'tpaint', 17:14:32 INFO - 'sessionrestore', 17:14:32 INFO - 'sessionrestore_no_auto_restore', 17:14:32 INFO - 'tabpaint']}, 17:14:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:14:32 INFO - 'tests': ['a11yr', 17:14:32 INFO - 'ts_paint', 17:14:32 INFO - 'tpaint', 17:14:32 INFO - 'sessionrestore', 17:14:32 INFO - 'sessionrestore_no_auto_restore', 17:14:32 INFO - 'tabpaint']}, 17:14:32 INFO - 'perf-reftest': {'tests': ['bloom_basic']}, 17:14:32 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 17:14:32 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 17:14:32 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:14:32 INFO - 'talos_options': ['--mitmproxy', 17:14:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:14:32 INFO - '--firstNonBlankPaint'], 17:14:32 INFO - 'tests': ['quantum_pageload_google', 17:14:32 INFO - 'quantum_pageload_youtube', 17:14:32 INFO - 'quantum_pageload_amazon', 17:14:32 INFO - 'quantum_pageload_facebook']}, 17:14:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:14:32 INFO - 'tests': ['tsvgx', 17:14:32 INFO - 'tsvgr_opacity', 17:14:32 INFO - 'tart', 17:14:32 INFO - 'tscrollx', 17:14:32 INFO - 'cart', 17:14:32 INFO - 'tsvg_static']}, 17:14:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:14:32 INFO - 'tsvgr_opacity', 17:14:32 INFO - 'tart', 17:14:32 INFO - 'tscrollx', 17:14:32 INFO - 'cart', 17:14:32 INFO - 'tsvg_static']}, 17:14:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:14:32 INFO - 'talos_options': ['--disable-e10s'], 17:14:32 INFO - 'tests': ['tp5o']}, 17:14:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:14:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:14:32 INFO - 'talos_options': ['--disable-e10s', 17:14:32 INFO - '--xperf_path', 17:14:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:14:32 INFO - 'tests': ['tp5n']}, 17:14:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:14:32 INFO - 'talos_options': ['--xperf_path', 17:14:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:14:32 INFO - 'tests': ['tp5n']}}, 17:14:32 INFO - 'talos.zip': {'path': '', 17:14:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:14:32 INFO - Running post-action listener: _resource_record_post_action 17:14:32 INFO - [mozharness: 2017-07-19 00:14:32.019310Z] Finished populate-webroot step (success) 17:14:32 INFO - [mozharness: 2017-07-19 00:14:32.019401Z] Running create-virtualenv step. 17:14:32 INFO - Running pre-action listener: _resource_record_pre_action 17:14:32 INFO - Running main action method: create_virtualenv 17:14:32 INFO - Creating virtualenv /builds/slave/test/build/venv 17:14:32 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:14:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:14:32 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:14:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:14:32 INFO - Using real prefix '/usr' 17:14:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:14:32 INFO - Installing distribute.............................................................................................................................................................................................done. 17:14:34 INFO - Installing pip.................done. 17:14:34 INFO - Return code: 0 17:14:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:14:34 INFO - Reading from file tmpfile_stdout 17:14:34 INFO - Output received: 17:14:34 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:14:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:14: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:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14: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:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:34 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 0x218e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ffb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, '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 0x24fdc10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fe0c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', '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:14:34 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:14:34 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:14:34 INFO - Using env: {'DISPLAY': ':0', 17:14:34 INFO - 'HOME': '/home/cltbld', 17:14:34 INFO - 'LANG': 'en_US.UTF-8', 17:14:34 INFO - 'LANGUAGE': 'en_US:en', 17:14:34 INFO - 'LOGNAME': 'cltbld', 17:14:34 INFO - 'MAIL': '/var/mail/cltbld', 17:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:34 INFO - 'MOZ_NO_REMOTE': '1', 17:14:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:34 INFO - 'NO_EM_RESTART': '1', 17:14:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:34 INFO - 'PWD': '/builds/slave/test', 17:14:34 INFO - 'SHELL': '/bin/bash', 17:14:34 INFO - 'SHLVL': '1', 17:14:34 INFO - 'TERM': 'linux', 17:14:34 INFO - 'TMOUT': '86400', 17:14:34 INFO - 'USER': 'cltbld', 17:14:34 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', 17:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:34 INFO - '_': '/tools/buildbot/bin/python'} 17:14:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:35 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:14:35 INFO - Cleaning up... 17:14:35 INFO - Return code: 0 17:14:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:14:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x218e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ffb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, '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 0x24fdc10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fe0c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', '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:14:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:14:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:14:35 INFO - Using env: {'DISPLAY': ':0', 17:14:35 INFO - 'HOME': '/home/cltbld', 17:14:35 INFO - 'LANG': 'en_US.UTF-8', 17:14:35 INFO - 'LANGUAGE': 'en_US:en', 17:14:35 INFO - 'LOGNAME': 'cltbld', 17:14:35 INFO - 'MAIL': '/var/mail/cltbld', 17:14:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:35 INFO - 'MOZ_NO_REMOTE': '1', 17:14:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:35 INFO - 'NO_EM_RESTART': '1', 17:14:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:35 INFO - 'PWD': '/builds/slave/test', 17:14:35 INFO - 'SHELL': '/bin/bash', 17:14:35 INFO - 'SHLVL': '1', 17:14:35 INFO - 'TERM': 'linux', 17:14:35 INFO - 'TMOUT': '86400', 17:14:35 INFO - 'USER': 'cltbld', 17:14:35 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', 17:14:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:35 INFO - '_': '/tools/buildbot/bin/python'} 17:14:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:35 INFO - Downloading/unpacking psutil>=3.1.1 17:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:14:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:14:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:14:36 INFO - Installing collected packages: psutil 17:14:36 INFO - Running setup.py install for psutil 17:14:36 INFO - building 'psutil._psutil_linux' extension 17:14:36 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:14:36 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:14:36 INFO - building 'psutil._psutil_posix' extension 17:14:36 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:14:36 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:14:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:14:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:14:36 INFO - Successfully installed psutil 17:14:36 INFO - Cleaning up... 17:14:36 INFO - Return code: 0 17:14:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:14:36 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:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:36 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:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:36 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 0x218e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ffb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, '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 0x24fdc10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fe0c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', '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:14:36 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:14:36 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:14:36 INFO - Using env: {'DISPLAY': ':0', 17:14:36 INFO - 'HOME': '/home/cltbld', 17:14:36 INFO - 'LANG': 'en_US.UTF-8', 17:14:36 INFO - 'LANGUAGE': 'en_US:en', 17:14:36 INFO - 'LOGNAME': 'cltbld', 17:14:36 INFO - 'MAIL': '/var/mail/cltbld', 17:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:36 INFO - 'MOZ_NO_REMOTE': '1', 17:14:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:36 INFO - 'NO_EM_RESTART': '1', 17:14:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:36 INFO - 'PWD': '/builds/slave/test', 17:14:36 INFO - 'SHELL': '/bin/bash', 17:14:36 INFO - 'SHLVL': '1', 17:14:36 INFO - 'TERM': 'linux', 17:14:36 INFO - 'TMOUT': '86400', 17:14:36 INFO - 'USER': 'cltbld', 17:14:36 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', 17:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:36 INFO - '_': '/tools/buildbot/bin/python'} 17:14:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:14:37 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:14:37 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:14:37 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:14:37 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:14:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:14:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:14:38 INFO - Installing collected packages: mozsystemmonitor 17:14:38 INFO - Running setup.py install for mozsystemmonitor 17:14:38 INFO - Successfully installed mozsystemmonitor 17:14:38 INFO - Cleaning up... 17:14:38 INFO - Return code: 0 17:14:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:14:38 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:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:38 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:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:38 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 0x218e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ffb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, '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 0x24fdc10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fe0c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', '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:14:38 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:14:38 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:14:38 INFO - Using env: {'DISPLAY': ':0', 17:14:38 INFO - 'HOME': '/home/cltbld', 17:14:38 INFO - 'LANG': 'en_US.UTF-8', 17:14:38 INFO - 'LANGUAGE': 'en_US:en', 17:14:38 INFO - 'LOGNAME': 'cltbld', 17:14:38 INFO - 'MAIL': '/var/mail/cltbld', 17:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:38 INFO - 'MOZ_NO_REMOTE': '1', 17:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:38 INFO - 'NO_EM_RESTART': '1', 17:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:38 INFO - 'PWD': '/builds/slave/test', 17:14:38 INFO - 'SHELL': '/bin/bash', 17:14:38 INFO - 'SHLVL': '1', 17:14:38 INFO - 'TERM': 'linux', 17:14:38 INFO - 'TMOUT': '86400', 17:14:38 INFO - 'USER': 'cltbld', 17:14:38 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', 17:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:38 INFO - '_': '/tools/buildbot/bin/python'} 17:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:38 INFO - Downloading/unpacking jsonschema==2.5.1 17:14:38 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:14:38 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:14:38 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:14:38 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:14:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:14:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:14:40 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:14:40 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:14:40 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:14:40 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:14:40 INFO - Downloading functools32-3.2.3-2.tar.gz 17:14:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:14:40 INFO - warning: no files found matching '*.txt' 17:14:40 INFO - no previously-included directories found matching 'build' 17:14:40 INFO - no previously-included directories found matching 'dist' 17:14:40 INFO - no previously-included directories found matching '.git*' 17:14:40 INFO - Installing collected packages: jsonschema, functools32 17:14:40 INFO - Running setup.py install for functools32 17:14:40 INFO - warning: no files found matching '*.txt' 17:14:40 INFO - no previously-included directories found matching 'build' 17:14:40 INFO - no previously-included directories found matching 'dist' 17:14:40 INFO - no previously-included directories found matching '.git*' 17:14:40 INFO - Successfully installed jsonschema functools32 17:14:40 INFO - Cleaning up... 17:14:40 INFO - Return code: 0 17:14:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:14: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:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14: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:14:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:40 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 0x218e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ffb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, '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 0x24fdc10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fe0c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', '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:14:40 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:14:40 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:14:40 INFO - Using env: {'DISPLAY': ':0', 17:14:40 INFO - 'HOME': '/home/cltbld', 17:14:40 INFO - 'LANG': 'en_US.UTF-8', 17:14:40 INFO - 'LANGUAGE': 'en_US:en', 17:14:40 INFO - 'LOGNAME': 'cltbld', 17:14:40 INFO - 'MAIL': '/var/mail/cltbld', 17:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:40 INFO - 'MOZ_NO_REMOTE': '1', 17:14:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:40 INFO - 'NO_EM_RESTART': '1', 17:14:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:40 INFO - 'PWD': '/builds/slave/test', 17:14:40 INFO - 'SHELL': '/bin/bash', 17:14:40 INFO - 'SHLVL': '1', 17:14:40 INFO - 'TERM': 'linux', 17:14:40 INFO - 'TMOUT': '86400', 17:14:40 INFO - 'USER': 'cltbld', 17:14:40 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', 17:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:40 INFO - '_': '/tools/buildbot/bin/python'} 17:14:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:14:40 INFO - Cleaning up... 17:14:40 INFO - Return code: 0 17:14:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:14: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:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14: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:14:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:40 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 0x218e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ffb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, '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 0x24fdc10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fe0c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', '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:14:40 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:14:40 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:14:40 INFO - Using env: {'DISPLAY': ':0', 17:14:40 INFO - 'HOME': '/home/cltbld', 17:14:40 INFO - 'LANG': 'en_US.UTF-8', 17:14:40 INFO - 'LANGUAGE': 'en_US:en', 17:14:40 INFO - 'LOGNAME': 'cltbld', 17:14:40 INFO - 'MAIL': '/var/mail/cltbld', 17:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:40 INFO - 'MOZ_NO_REMOTE': '1', 17:14:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:40 INFO - 'NO_EM_RESTART': '1', 17:14:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:40 INFO - 'PWD': '/builds/slave/test', 17:14:40 INFO - 'SHELL': '/bin/bash', 17:14:40 INFO - 'SHLVL': '1', 17:14:40 INFO - 'TERM': 'linux', 17:14:40 INFO - 'TMOUT': '86400', 17:14:40 INFO - 'USER': 'cltbld', 17:14:40 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', 17:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:40 INFO - '_': '/tools/buildbot/bin/python'} 17:14:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:40 INFO - Downloading/unpacking blobuploader==1.2.4 17:14:40 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:14:40 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:14:40 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:14:40 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:14:41 INFO - Downloading blobuploader-1.2.4.tar.gz 17:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:14:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:14: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:14: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:14: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:14: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:14:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:14:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:14: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:14: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:14: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:14: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:14:42 INFO - Downloading docopt-0.6.1.tar.gz 17:14:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:14:42 INFO - Installing collected packages: blobuploader, requests, docopt 17:14:42 INFO - Running setup.py install for blobuploader 17:14:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:14:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:14:42 INFO - Running setup.py install for requests 17:14:42 INFO - Running setup.py install for docopt 17:14:43 INFO - Successfully installed blobuploader requests docopt 17:14:43 INFO - Cleaning up... 17:14:43 INFO - Return code: 0 17:14:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14: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:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14: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:14:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:43 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 0x218e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ffb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, '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 0x24fdc10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fe0c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', '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:14:43 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:14:43 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:14:43 INFO - Using env: {'DISPLAY': ':0', 17:14:43 INFO - 'HOME': '/home/cltbld', 17:14:43 INFO - 'LANG': 'en_US.UTF-8', 17:14:43 INFO - 'LANGUAGE': 'en_US:en', 17:14:43 INFO - 'LOGNAME': 'cltbld', 17:14:43 INFO - 'MAIL': '/var/mail/cltbld', 17:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:43 INFO - 'MOZ_NO_REMOTE': '1', 17:14:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:43 INFO - 'NO_EM_RESTART': '1', 17:14:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:43 INFO - 'PWD': '/builds/slave/test', 17:14:43 INFO - 'SHELL': '/bin/bash', 17:14:43 INFO - 'SHLVL': '1', 17:14:43 INFO - 'TERM': 'linux', 17:14:43 INFO - 'TMOUT': '86400', 17:14:43 INFO - 'USER': 'cltbld', 17:14:43 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', 17:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:43 INFO - '_': '/tools/buildbot/bin/python'} 17:14:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:14:43 INFO - Running setup.py (path:/tmp/pip-ebXlx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:14:43 INFO - Running setup.py (path:/tmp/pip-qO0_ze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:14:43 INFO - Running setup.py (path:/tmp/pip-rsiMOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:14:43 INFO - Running setup.py (path:/tmp/pip-1KTKjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:14:43 INFO - Running setup.py (path:/tmp/pip-3XnyqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:14:43 INFO - Running setup.py (path:/tmp/pip-8Y5kH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:14:43 INFO - Running setup.py (path:/tmp/pip-DBgRUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:14:43 INFO - Running setup.py (path:/tmp/pip-0oK22Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:14:43 INFO - Running setup.py (path:/tmp/pip-DmOZp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:14:43 INFO - Running setup.py (path:/tmp/pip-J2lumR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:14:43 INFO - Running setup.py (path:/tmp/pip-8oV6PW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:14:44 INFO - Running setup.py (path:/tmp/pip-PFqjkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:14:44 INFO - Running setup.py (path:/tmp/pip-Joz9tN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:14:44 INFO - Running setup.py (path:/tmp/pip-nPFy_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:44 INFO - Running setup.py (path:/tmp/pip-dENe5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:14:44 INFO - Running setup.py (path:/tmp/pip-exSmV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:14:44 INFO - Running setup.py (path:/tmp/pip-0LuDsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:14:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:14:44 INFO - Running setup.py install for manifestparser 17:14:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:14:44 INFO - Running setup.py install for mozcrash 17:14:44 INFO - Running setup.py install for mozdebug 17:14:44 INFO - Running setup.py install for mozdevice 17:14:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:14:44 INFO - Running setup.py install for mozfile 17:14:45 INFO - Running setup.py install for mozhttpd 17:14:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:14:45 INFO - Running setup.py install for mozinfo 17:14:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:14:45 INFO - Running setup.py install for mozInstall 17:14:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:14:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:14:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:14:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:14:45 INFO - Running setup.py install for mozleak 17:14:45 INFO - Running setup.py install for mozlog 17:14:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:14:45 INFO - Running setup.py install for moznetwork 17:14:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:14:45 INFO - Running setup.py install for mozprocess 17:14:45 INFO - Running setup.py install for mozprofile 17:14:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:14:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:14:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:14:45 INFO - Running setup.py install for mozrunner 17:14:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:14:45 INFO - Running setup.py install for mozscreenshot 17:14:46 INFO - Running setup.py install for moztest 17:14:46 INFO - Running setup.py install for mozversion 17:14:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:14:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:14:46 INFO - Cleaning up... 17:14:46 INFO - Return code: 0 17:14:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14:46 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:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:46 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:14:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:46 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 0x218e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ffb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, '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 0x24fdc10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fe0c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', '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:14:46 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:14:46 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:14:46 INFO - Using env: {'DISPLAY': ':0', 17:14:46 INFO - 'HOME': '/home/cltbld', 17:14:46 INFO - 'LANG': 'en_US.UTF-8', 17:14:46 INFO - 'LANGUAGE': 'en_US:en', 17:14:46 INFO - 'LOGNAME': 'cltbld', 17:14:46 INFO - 'MAIL': '/var/mail/cltbld', 17:14:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:46 INFO - 'MOZ_NO_REMOTE': '1', 17:14:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:46 INFO - 'NO_EM_RESTART': '1', 17:14:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:46 INFO - 'PWD': '/builds/slave/test', 17:14:46 INFO - 'SHELL': '/bin/bash', 17:14:46 INFO - 'SHLVL': '1', 17:14:46 INFO - 'TERM': 'linux', 17:14:46 INFO - 'TMOUT': '86400', 17:14:46 INFO - 'USER': 'cltbld', 17:14:46 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', 17:14:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:46 INFO - '_': '/tools/buildbot/bin/python'} 17:14:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:14:46 INFO - Running setup.py (path:/tmp/pip-kvqzKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:14:46 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:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:14:46 INFO - Running setup.py (path:/tmp/pip-P6Soty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:14:46 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:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:14:46 INFO - Running setup.py (path:/tmp/pip-bLREOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:14:46 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:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:14:46 INFO - Running setup.py (path:/tmp/pip-56hOnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:14:46 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:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:14:46 INFO - Running setup.py (path:/tmp/pip-aPD_zI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:14:46 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:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:14:46 INFO - Running setup.py (path:/tmp/pip-SnDwKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:14:46 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:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:14:46 INFO - Running setup.py (path:/tmp/pip-m9yHX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:14:46 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:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:14:46 INFO - Running setup.py (path:/tmp/pip-3WTR_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:14:47 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:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:14:47 INFO - Running setup.py (path:/tmp/pip-xCALPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:14:47 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:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:14:47 INFO - Running setup.py (path:/tmp/pip-277ST0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:14:47 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:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:14:47 INFO - Running setup.py (path:/tmp/pip-yoIepr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:14:47 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:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:14:47 INFO - Running setup.py (path:/tmp/pip-GUrZok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:14:47 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:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:14:47 INFO - Running setup.py (path:/tmp/pip-7dgoqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:14:47 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:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:14:47 INFO - Running setup.py (path:/tmp/pip-V8CKyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:14:47 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:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:47 INFO - Running setup.py (path:/tmp/pip-41Wtp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:47 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:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:14:47 INFO - Running setup.py (path:/tmp/pip-OCgKaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:14:47 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:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:14:47 INFO - Running setup.py (path:/tmp/pip-QTDGQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:14:47 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:14:47 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:14:47 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:14:47 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:14:47 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:14:47 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:14:47 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:14:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:14:47 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:14:47 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:14:47 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:14:47 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:14:48 INFO - Downloading blessings-1.6.tar.gz 17:14:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:14:48 INFO - Installing collected packages: blessings 17:14:48 INFO - Running setup.py install for blessings 17:14:48 INFO - Successfully installed blessings 17:14:48 INFO - Cleaning up... 17:14:49 INFO - Return code: 0 17:14:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:14:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:14:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:14:49 INFO - Reading from file tmpfile_stdout 17:14:49 INFO - Current package versions: 17:14:49 INFO - argparse == 1.2.1 17:14:49 INFO - blessings == 1.6 17:14:49 INFO - blobuploader == 1.2.4 17:14:49 INFO - docopt == 0.6.1 17:14:49 INFO - functools32 == 3.2.3-2 17:14:49 INFO - jsonschema == 2.5.1 17:14:49 INFO - manifestparser == 1.1 17:14:49 INFO - mozInstall == 1.13 17:14:49 INFO - mozcrash == 1.0 17:14:49 INFO - mozdebug == 0.1 17:14:49 INFO - mozdevice == 0.51 17:14:49 INFO - mozfile == 1.2 17:14:49 INFO - mozhttpd == 0.7 17:14:49 INFO - mozinfo == 0.10 17:14:49 INFO - mozleak == 0.1 17:14:49 INFO - mozlog == 3.5 17:14:49 INFO - moznetwork == 0.27 17:14:49 INFO - mozprocess == 0.25 17:14:49 INFO - mozprofile == 0.28 17:14:49 INFO - mozrunner == 6.13 17:14:49 INFO - mozscreenshot == 0.1 17:14:49 INFO - mozsystemmonitor == 0.3 17:14:49 INFO - moztest == 0.8 17:14:49 INFO - mozversion == 1.4 17:14:49 INFO - psutil == 3.1.1 17:14:49 INFO - requests == 1.2.3 17:14:49 INFO - wsgiref == 0.1.2 17:14:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:49 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 0x218e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ffb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, '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 0x24fdc10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fe0c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', '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:14:49 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:14:49 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:14:49 INFO - Using env: {'DISPLAY': ':0', 17:14:49 INFO - 'HOME': '/home/cltbld', 17:14:49 INFO - 'LANG': 'en_US.UTF-8', 17:14:49 INFO - 'LANGUAGE': 'en_US:en', 17:14:49 INFO - 'LOGNAME': 'cltbld', 17:14:49 INFO - 'MAIL': '/var/mail/cltbld', 17:14:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:49 INFO - 'MOZ_NO_REMOTE': '1', 17:14:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:49 INFO - 'NO_EM_RESTART': '1', 17:14:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:49 INFO - 'PWD': '/builds/slave/test', 17:14:49 INFO - 'SHELL': '/bin/bash', 17:14:49 INFO - 'SHLVL': '1', 17:14:49 INFO - 'TERM': 'linux', 17:14:49 INFO - 'TMOUT': '86400', 17:14:49 INFO - 'USER': 'cltbld', 17:14:49 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', 17:14:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:49 INFO - '_': '/tools/buildbot/bin/python'} 17:14:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:49 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:14:49 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:14:49 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:14:49 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:14:49 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:14:49 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:14:49 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:14:49 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:14:49 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:14:49 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:14:49 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:14:49 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:14:49 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:14:49 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:14:50 INFO - Downloading marionette_driver-1.4.0.tar.gz 17:14:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 17:14:50 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 17:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:14:50 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:14:50 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:14:50 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:14:50 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:14:50 INFO - Installing collected packages: marionette-driver, simplejson 17:14:50 INFO - Running setup.py install for marionette-driver 17:14:50 INFO - Running setup.py install for simplejson 17:14:51 INFO - building 'simplejson._speedups' extension 17:14:51 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:14:51 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:14:51 INFO - Successfully installed marionette-driver simplejson 17:14:51 INFO - Cleaning up... 17:14:52 INFO - Return code: 0 17:14:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:14:52 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:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:52 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:14:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:52 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 0x218e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ffb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, '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 0x24fdc10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fe0c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', '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:14:52 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:14:52 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:14:52 INFO - Using env: {'DISPLAY': ':0', 17:14:52 INFO - 'HOME': '/home/cltbld', 17:14:52 INFO - 'LANG': 'en_US.UTF-8', 17:14:52 INFO - 'LANGUAGE': 'en_US:en', 17:14:52 INFO - 'LOGNAME': 'cltbld', 17:14:52 INFO - 'MAIL': '/var/mail/cltbld', 17:14:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:52 INFO - 'MOZ_NO_REMOTE': '1', 17:14:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:52 INFO - 'NO_EM_RESTART': '1', 17:14:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:52 INFO - 'PWD': '/builds/slave/test', 17:14:52 INFO - 'SHELL': '/bin/bash', 17:14:52 INFO - 'SHLVL': '1', 17:14:52 INFO - 'TERM': 'linux', 17:14:52 INFO - 'TMOUT': '86400', 17:14:52 INFO - 'USER': 'cltbld', 17:14:52 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', 17:14:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:52 INFO - '_': '/tools/buildbot/bin/python'} 17:14:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:14:52 INFO - Cleaning up... 17:14:52 INFO - Return code: 0 17:14:52 INFO - Running post-action listener: _resource_record_post_action 17:14:52 INFO - Running post-action listener: _start_resource_monitoring 17:14:52 INFO - Starting resource monitoring. 17:14:52 INFO - [mozharness: 2017-07-19 00:14:52.317825Z] Finished create-virtualenv step (success) 17:14:52 INFO - [mozharness: 2017-07-19 00:14:52.318335Z] Running install step. 17:14:52 INFO - Running pre-action listener: _resource_record_pre_action 17:14:52 INFO - Running main action method: install 17:14:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:14:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:14:52 INFO - Reading from file tmpfile_stdout 17:14:52 INFO - Detecting whether we're running mozinstall >=1.0... 17:14:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:14:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:14:52 INFO - Reading from file tmpfile_stdout 17:14:52 INFO - Output received: 17:14:52 INFO - Usage: mozinstall [options] installer 17:14:52 INFO - Options: 17:14:52 INFO - -h, --help show this help message and exit 17:14:52 INFO - -d DEST, --destination=DEST 17:14:52 INFO - Directory to install application into. [default: 17:14:52 INFO - "/builds/slave/test"] 17:14:52 INFO - --app=APP Application being installed. [default: firefox] 17:14:52 INFO - mkdir: /builds/slave/test/build/application 17:14:52 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:14:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:15:10 INFO - Reading from file tmpfile_stdout 17:15:10 INFO - Output received: 17:15:10 INFO - /builds/slave/test/build/application/firefox/firefox 17:15:10 INFO - Running post-action listener: _resource_record_post_action 17:15:10 INFO - [mozharness: 2017-07-19 00:15:10.595100Z] Finished install step (success) 17:15:10 INFO - [mozharness: 2017-07-19 00:15:10.595350Z] Running setup-mitmproxy step. 17:15:10 INFO - Running pre-action listener: _resource_record_pre_action 17:15:10 INFO - Running main action method: setup_mitmproxy 17:15:10 INFO - Skipping: mitmproxy is not required 17:15:10 INFO - Running post-action listener: _resource_record_post_action 17:15:10 INFO - [mozharness: 2017-07-19 00:15:10.596530Z] Finished setup-mitmproxy step (success) 17:15:10 INFO - [mozharness: 2017-07-19 00:15:10.596751Z] Running run-tests step. 17:15:10 INFO - Running pre-action listener: _resource_record_pre_action 17:15:10 INFO - Running pre-action listener: _set_gcov_prefix 17:15:10 INFO - Running main action method: run_tests 17:15:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:15:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:15:10 INFO - Python 2.7.3 17:15:10 INFO - Return code: 0 17:15:10 INFO - grabbing minidump binary from tooltool 17:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:10 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 0x24fcd10>, '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 0x24fdc10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fe0c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:15:10 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:15:10 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:15:10 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:15:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:15:10 INFO - Return code: 0 17:15:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:15:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:15:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:15:10 INFO - ENV: RUST_BACKTRACE is now 1 17:15:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:15:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:15:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:15:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-089 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:15:10 INFO - Using env: {'DISPLAY': ':0', 17:15:10 INFO - 'HOME': '/home/cltbld', 17:15:10 INFO - 'LANG': 'en_US.UTF-8', 17:15:10 INFO - 'LANGUAGE': 'en_US:en', 17:15:10 INFO - 'LOGNAME': 'cltbld', 17:15:10 INFO - 'MAIL': '/var/mail/cltbld', 17:15:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:15:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:15:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:10 INFO - 'MOZ_NO_REMOTE': '1', 17:15:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:15:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:15:10 INFO - 'NO_EM_RESTART': '1', 17:15:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:15:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:10 INFO - 'PWD': '/builds/slave/test', 17:15:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:15:10 INFO - 'RUST_BACKTRACE': '1', 17:15:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:15:10 INFO - 'SHELL': '/bin/bash', 17:15:10 INFO - 'SHLVL': '1', 17:15:10 INFO - 'TERM': 'linux', 17:15:10 INFO - 'TMOUT': '86400', 17:15:10 INFO - 'USER': 'cltbld', 17:15:10 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455', 17:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:10 INFO - '_': '/tools/buildbot/bin/python'} 17:15:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:15:10 INFO - mozversion application_buildid: 20170718233341 17:15:10 INFO - mozversion application_changeset: 1b065ffd8a535a0ad4c39a912af18e948e6a42c1 17:15:10 INFO - mozversion application_display_name: Nightly 17:15:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:15:10 INFO - mozversion application_name: Firefox 17:15:10 INFO - mozversion application_remotingname: firefox 17:15:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:15:10 INFO - mozversion application_vendor: Mozilla 17:15:10 INFO - mozversion application_version: 56.0a1 17:15:10 INFO - mozversion platform_buildid: 20170718233341 17:15:10 INFO - mozversion platform_changeset: 1b065ffd8a535a0ad4c39a912af18e948e6a42c1 17:15:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:15:10 INFO - mozversion platform_version: 56.0a1 17:15:10 INFO - using testdate: 1500423310 17:15:10 INFO - actual date: 1500423310 17:15:10 INFO - starting webserver on 'localhost:56472' 17:15:10 INFO - SUITE-START | Running 2 tests 17:15:10 INFO - TEST-START | dromaeo_css 17:15:10 INFO - Initialising browser for dromaeo_css test... 17:15:10 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56472/getInfo.html -profile /tmp/tmpForI9x/profile 17:15:10 INFO - TEST-INFO | started process 5626 (/builds/slave/test/build/application/firefox/firefox http://localhost:56472/getInfo.html) 17:15:17 INFO - TEST-INFO | 5626: exit 0 17:15:17 INFO - Browser initialized. 17:15:17 INFO - Running cycle 1/1 for dromaeo_css test... 17:15:17 INFO - TEST-INFO | started process 5878 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpForI9x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:15:28 INFO - PID 5878 | RSS: Main: 173780992 17:15:28 INFO - PID 5878 | 17:18:26 INFO - PID 5878 | Cycle 1(1): loaded http://localhost:56472/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56472/tests/dromaeo/cssquery-ext.html) 17:18:28 INFO - PID 5878 | RSS: Main: 178278400 17:18:28 INFO - PID 5878 | 17:21:30 INFO - PID 5878 | Cycle 1(1): loaded http://localhost:56472/tests/dromaeo/cssquery-ext.html (next: http://localhost:56472/tests/dromaeo/cssquery-jquery.html) 17:21:31 INFO - PID 5878 | RSS: Main: 176553984 17:21:31 INFO - PID 5878 | 17:24:32 INFO - PID 5878 | Cycle 1(1): loaded http://localhost:56472/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56472/tests/dromaeo/cssquery-mootools.html) 17:24:33 INFO - PID 5878 | RSS: Main: 178151424 17:24:33 INFO - PID 5878 | 17:27:35 INFO - PID 5878 | Cycle 1(1): loaded http://localhost:56472/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56472/tests/dromaeo/cssquery-prototype.html) 17:27:35 INFO - PID 5878 | RSS: Main: 174350336 17:27:35 INFO - PID 5878 | 17:30:38 INFO - PID 5878 | Cycle 1(1): loaded http://localhost:56472/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56472/tests/dromaeo/cssquery-yui.html) 17:30:39 INFO - PID 5878 | RSS: Main: 178061312 17:30:39 INFO - PID 5878 | 17:33:41 INFO - PID 5878 | Cycle 1(1): loaded http://localhost:56472/tests/dromaeo/cssquery-yui.html (next: http://localhost:56472/tests/dromaeo/cssquery-dojo.html) 17:33:41 INFO - PID 5878 | RSS: Main: 180228096 17:33:41 INFO - PID 5878 | 17:33:41 INFO - PID 5878 | __start_tp_report 17:33:41 INFO - PID 5878 | _x_x_mozilla_page_load 17:33:41 INFO - PID 5878 | _x_x_mozilla_page_load_details 17:33:41 INFO - PID 5878 | |i|pagename|runs| 17:33:41 INFO - PID 5878 | |0;dojo.html;3098.2409309719137;3143.0256620447676;3144.9842750786243;3148.7008734170254;3179.936401271975;6001.909971350427;6023.457888790008;6044.758209671619;6055.333913269536;6194.00895856663;6128.57100003;6165.599236049665;6215.844603884894;6218.1916350874435;6225.470834979033;5636.549076073925;5670.3479099903625;5679.432056794329;5682.829515114553;5739.569532285074;5525.4474552544825;5537.0863807471715;5625.38120806711;5704.229928959599;5735.827925162252;5484.890302193954;5550.888982220374;5552.750126244308;5569.526590239834;5617.269754931874;5507.944920550824;5526.198701188346;5526.557875370001;5550.639208451478;5570.108782594806;5436.103042997901;5494.835154945358;5504.284443022421;5538.833834984916;5539.723013849331;6004.699765011732;6009.09863520471;6017.969910150421;6018.247719035121;6030.185924900126;6465.094886715863;6478.708458119396;6504.60972341661;6505.674716264215;6531.183602049744;6565.606063636197;6566.277709451956;6567.310432404576;6587.868242635121;6592.472602191813;6030.12563178341;6068.696565171724;6114.602550834226;6163.352847950945;6229.127922090911;6767.052612634235;6783.762568310085;6848.623325717083;6882.070920425778;6930.826729331807;6302.905456418157;6441.066045423388;6448.193975753031;6461.321561236003;6559.180102487189;6537.477001839841;6544.934550654528;6547.9017814732815;6557.409833115043;6563.278039415661;5377.435369286247;6458.354164583504;6503.057056726748;6549.672516374162;6629.204495460479;6344.841378965562;6567;6635.734570617121;6639.867202656016;6644.368784965421;5238.423773384925;5251.4485978972425;5271.288376069181;5316.76074576645;5335.359756829128;4501.077279157765;4508.45898492183;4540.659450541222;4568.269076947672;4611.377464042378;5718.399568045378;5756.366799652035;5759.596828222068;5762.078067509178;5762.337331206881;5726.465503693216;6614.074029635759;6631.171103612049;6700.83247918806;6706.061151438705;5691.943080569225;5692.174634677948;5789.131630255495;5810.941890581124;5818.359980402152;5697.572682048863;5770;5827.650340979555;5881.441263080001;5886.852828679232;6375.649339286291;6380.617162970237;6395.072714456378;6403.519736019724;6424.935750642434;6475.222973243241;6550.541462097607;6552.770653027121;6553.967230163818;6566.080748695282;6376.617402955838;6382.585131966407;6383.265924418752;6386.105945167773;6399.168108145911;6383.36166383358;6443.613383197023;6464.547481676237;6480;6490.967545162244;6663.26704062543;6668.899966500409;6690.397864192246;6751.3923746861055;6768.898466523105;6416.390442907917;6427;6525.2169739631545;6526.836829079311;6551.279359270381;6362.491000720024;6390.456811171102;6419.711113000019;6429.2284925807335;6489.8702025960165;6301.905471417841;6331.430171284423;6343.524235682435;6347.3652634738;6364.904526432015;6318.304986451395;6455.160829092188;6459.903101453576;6475.384838440341;6509.707063182073;6239.032949892774;6244.687765611792;6252.218472690914;6255.96872015637;6273.121762953099 17:33:41 INFO - PID 5878 | |1;ext.html;711.8825393810022;782.9138794732582;811.8863359129723;832.7127141136308;839.9496030238187;41255.58744412559;42209.57790422095;42275.78862105693;42277.154456910845;42347.78826105869;40081;40189.799051004775;40308.59691403085;40402;40475.19049619006;6875.106236189301;7004.859902801966;7009.08881845362;7010.7546235881755;7021.473389495783;6857.554258973176;6913.446924246048;6949.6872640731035;6950.89573656393;6952.09622749042;6866.382025617693;6884.242733299358;6894.655267236643;6905.585664860099;6927.549709268881;6831.316868313179;6842.486813488983;6895.655217239143;6910.3089691031;6910.7581234656545;6220.502359811204;6253.312135665074;6273.341299163568;6276.623402595813;6283.057541368831;35531.11172220689;35758.463623045674;35825.46261806075;35842.641573584195;35920.28159436823;3365.4278772608714;3414.078198886287;3534.2224710563637;3574.035010547164;3576.2311103112957;32310.19224519406;32400.513992290136;32607.18482037968;32652.183695407566;32681.50977735336;4918.163912134945;4963.677360971561;4984.127777638899;5004.249362595603;5080.441151473335;184099;188749;189387;189632;190970;44138.55861441409;49765;49951.25073123906;50292.49707502951;50462.243066354036;51316;51430;51532.74233628808;51884;51888.48111518912;15862.44481443167;16011.95922265049;16062.116583587782;16123.516294511184;16136.435224767312;12902.161359511601;12968.740625187444;13067.411966461532;13076.215427074407;13081.345932703518;14463.78304325436;14499.275036248357;14767.778483322758;14795.186264755326;14932.402703891723;5803.680797556175;5953.642781433128;5984.371640977634;6064.727087280995;6088.786892458831;948.7011591348703;971.9416834989923;985.1724551376734;994.3139233928566;1004.5128112865254;22506.549869002527;22783.202587909345;22940.557938854654;23223.651645225335;23827.40431489192;1052.8631277933819;1065.1638463805875;1078.2937176149635;1089.8528698625742;1104.3429159649995;11541.596044138414;11854.170208085523;11866.466009029615;12039.458224379923;12144.878551214551;226743;228842;229074;229126;229823;50178.74910625497;50316.49683503118;50444.24333634999;50802.23796643053;50815.74592127016;1418.319206780751;1482.9110253384831;1495.3196295685655;1499.295331194337;1501.564546281588;21437.970575722276;21550.245741398976;21735.456613584785;21763.129474821464;21791.12835486563;36407.817960910026;36636.45045324269;36759.08102297464;36851;36966.815165923996;200869;202147;202193;202702;203286;33465.33069338648;37795.43306850454;38552.80723596364;39041.41437878491;39828.40257396083;47737.76131119322;48279.75860120677;48674.756626216644;48894.75552622214;49370;33383.66616333806;34581;34884;34910.12724681802;35297.47053794246;33906.49140262947;34405.655943440244;34488;35141.297174055864;35225.29549409049;38381.23237535178;38497.61502385052;38532.614673852906;38640.6135938637;38813.223735525695;33118;33359.33281334408;33488.66511334856;34124;34319;24053.51892962166;24478.51042979095;24537.263882083567;24786;24790 17:33:41 INFO - PID 5878 | |2;jquery.html;572.5619900775905;573.3034363248653;575.5568212476396;582.9213056237406;605.5760967322874;16840.57898552534;21744.3476695699;23262.418439539;23285.767142328594;23290.06839726413;19297.22811087555;19319.517012074706;19637;19671.31150409736;20902.581948361025;3833;6417.197850268716;6519.119918810971;6538.673066346688;6572.737090516397;6298.622082675031;6383.265924418682;6435.485161187117;6443.130177426035;6449.806505804833;6369.904451433209;6379.904301435482;6399.584027038228;6419.711112999903;6420.133282006916;6170.475509581691;6356.2054743157105;6385.138006369127;6401.935980640181;6412.903806442884;6685.53201275906;6694.263631000586;6694.966525167343;6696.497762667771;6723.462123030145;30531.542026869614;30936.071917842502;31067.223319416968;31091;31120.221994450094;1983.7817840037728;2005.809448102428;2015.838732901379;2023.7773844877051;2025.169680431016;19004.23983040677;19270.22919083235;19523;19700.901495492573;19844.900775496175;2352.2590384028977;2404.086447150089;2432.6472661464168;2450.2281781238853;2455.017992802875;125859;130918;132130;132925;136966;20047.39857804268;20227.494312642302;21060.578788424147;21992.670109948365;22253;42371;42509.57490425056;42532.57467425348;42755.35866961998;42775;11333.923277288644;11645.12661550387;11648.41757912118;11682.941585292188;11756.764864702829;9647.758806029766;9691.176250018827;9808.950955245178;9847.901520984842;9881.209503239723;11009.019389701196;11120.443977801078;11212.383318917538;11233.157513186543;11494.88505114955;3759.6804271637216;4064.7357921734992;4197.412362269285;4277.636400905895;4280.6789490788315;953.9618415263451;955.9665411710557;959.1319855530755;983.1643103362085;987.7728122531857;18890.244390224238;19359.516012099528;19369.4189174325;19377;19655.803441965683;1002.4336250018716;1041.671873359889;1061.3525749292926;1066.7653116314398;1068.690079876827;10669.946650266698;10717.624883129052;10741.624043158608;10749.925007499187;10841.295315804447;439936;444077;451340;452588;456341;108235;112339.43830280796;113969;116025;118837.99999999827;1197.1918954705538;1207.9577214797616;1256.0956111599633;1307.601181639609;1307.7123032932739;19495.317663881695;20014.19943202314;20167.193312267344;20267;20497.077631506916;54965.175522367994;56277.71861140668;57096;57170;58184.709076454346;66699;70895.64552177206;72062.63968680122;75090.24909750832;76599;13805.51680691171;14293.14241145493;14583.416663333348;14678.192699401636;14679.339429725474;23564.646530301718;23564.882175589013;23599.64600530959;23675.64486532669;23772.643410349203;18165.455036348932;18187.363442279457;18307.26770929148;18435.446936591925;18619;15410.152441615819;15724.213789310717;15754;15821.367145314058;16020.519384418487;19066.237350505824;19115.426537203904;19133.904330478257;19267.13297901626;19329.8067019328;14242.07426517273;14242.857571424567;14249.715005700034;14258.500952466615;14311.928440357731;17336.56658583495;18027.639447211244;18167;18474.076296184867;18586.163622636745 17:33:41 INFO - PID 5878 | |3;mootools.html;1362.9045966782326;1419.8012278281035;1425.5224499792569;1427.828660560733;1430.2419717549699;13581.660458488543;14117.152970821759;14196.07725497842;14256.643583910407;14273.857261427396;13876.653083672913;14640.926795366035;14726.116433014027;14753.926230368834;14780.778288325684;2566.4482136340685;2651.5757478803393;2655.721149279317;2659.295286749013;2663.706992230863;2552.132275026491;2632.1182403894622;2633.710291867893;2637.604359346104;2645.7354264573582;2568.3964268396912;2601.1806281021513;2611.986940065297;2616.738326167387;2617.4372509910368;2527.9241622751347;2531.5822889223255;2532.5441420544294;2534.037065914959;2535.733747956456;2396.125414223813;2417.5406672732124;2419.3911049152293;2428.6357046443;2435.683361163055;12823.87176128236;13066.346682665924;13084.476620935151;13097.279649619268;13116.081874268804;6928.61892595907;7233.132024157135;7248;7259.60072196029;7262.491625586211;4724.622030237572;4774.713517188945;4809.038192361541;4850.393700787402;4864.6108311335365;1836.898970556619;1838.586318078435;1857.1828395505934;1882.5011371986434;1889.6787546117193;20230.19079236829;20694.482637934023;20709.378718638316;20738;20771.27300544474;6358.7456501740335;6409.4231519163495;6595.142631457879;6615.437687796495;6627.966860165669;16696.582585435463;16747.162641867857;16867.325306987583;16869.240884159997;16883.32466701318;7215.891761623579;7413;7448.441366897504;7464.216257292906;7478.551286922802;5920.674362909996;6688.030235615808;6911.136107986502;6915.308469153045;6921.965390173017;5028.673136246132;5151.098557752378;5213.2701421800975;5226;5242.947570524246;1620.3113676687597;1664.650423411073;1666.0510895714517;1668.9749653755202;1675.6732437174878;904.7331037344;912.9589168487302;935.611721135721;947.0860619502075;951.7715748220377;7398.889016664754;7736.303732664087;8113;8229;8245.422820402515;682.3108660253081;694.2467422846167;705.9752908648172;716.6954044531053;722.299369611476;4547.795349209294;4639.837605683785;4651.2557990721325;4684.531546845288;4703.882402939954;17521.386751463637;18056.90971545134;18408.35570755017;18424;18592.907035464737;18004.63990720178;18394.540136496427;18988.43034708961;19161.52096197606;19357;10493.895061049292;10562.577496900192;10667.786644267226;10766.623168189233;10964.780704385868;7851.882221766564;8085.757427277191;8204.179582041748;8253.33973282124;8296.294814940557;15581.454649087227;16275.104869231833;16601.750973735645;16801.579960501087;16907;16307.918460407622;16856.66286674284;17473.47579572615;17688.380906668204;17864.642707146046;10425.113865321533;10768.300060496043;10814.837777433188;10843.24097313212;11081.5567377304;12001.63995080149;12194.51221951112;12209.938950305192;12313.56902508408;12339.75320493603;9821.803563928825;9996.450195239331;10127.291089623655;10175.542100605342;10473.9476302618;9193.218576421079;9607.711768646952;9703.9029609703;9872;9966.40201587907;9008.954955225181;9093.22707569864;9131.634734610541;9143.679971200976;9183.31125165602;6690.83272918181;6762.459003279824;6766.492513061442;6805.115335006418;6851.623160726208;6674.399304062657;6712.83217919556;6740.528163028541;6786.728530858711;6802.557833740791 17:33:41 INFO - PID 5878 | |4;prototype.html;478.58124141376294;499.98776937941824;511;511.85156304671636;519.7323378460092;6135.9079613805825;6186.257649082111;6190.41191086846;6197.318294987548;6206.689665516728;5891.293044834626;5942.435468630484;5968.432998865112;6059.727312270948;6088.969555152229;4966.751662416883;4976.402831660206;4979.626528010395;4988.551030407262;4998.425181104183;4805.222067834889;4939.407271127469;4952.306677065214;4956.87607809804;4995.925061124068;4864.367632207808;4919.581835543965;4954.182559877616;4957.677750946195;4980.626453016021;4625.283081122431;4745.406824146981;4767.308740232646;4875.733732983639;4896.7306798126265;4248.638865696419;4264.637505812009;4396.230659634552;4401.691881568291;4404.801783919699;6031.823794360102;6036.5170786336985;6041.395860413967;6063.757449702007;6063.818085457443;5624.29696287964;5633.15502674598;5634;5639.125935480007;5653.9151912721345;6468.154012234649;6481.287058423617;6519.869602607969;6552.705128269239;6554.442872355856;4835.975820120912;4843.830465933711;4849.8602828335315;4850.514948505156;4863.273537894067;6840.555363901331;6938.924466707668;6939.653017349112;6939.895901561481;6968.79093627192;4943.703377797344;4975.676581022222;4996.775145118406;5011.899762004739;5031.723255220998;5010.023045506165;5018.874528136826;5038.622103342264;5045.931168221012;5062.7215503146945;5620.9437905621235;5639.349505732299;5660.179274005329;5699.401562835926;5738.282714660668;6428.357164283534;6465.579737317059;6500.6424646643945;6544.247411547638;6567.146270984741;1705.6418152187932;1736.4530172995467;1744.6946784312947;1748.0560497331298;1755.350520307469;1119.9328040317607;1190.9047276217882;1202.1584890576457;1212.7635111153418;1217.2818037358002;5725.914111288334;5740.425957404226;5754.309482862;5812.215350927655;5826.766929322865;6403.231612206565;6494.870102597922;6571.934280657228;6651.534392592569;6751.657929325015;5707.942920570741;5738.8766156521515;5786.528083150076;5824.184614154022;5869.530437564985;3499.3001399720156;3530.258645684435;3572.5355703758346;3611.855525778992;3615.1504396466808;6458.903116453257;6478.546501744832;6479.449246814073;6504.764094821969;6563.278039415661;6782.287859774751;6846.5892046477375;6877;6932.232280768372;6947.861042779116;6439.22729272481;6468.8059358219325;6521.304860264933;6541.738330466823;6555.26414387411;6606.042123892104;6631.701573429304;6683.164604424447;6734.393904548614;6743.258241593323;6580.76967306142;6598.802035938929;6639.568428052355;6650.501212408992;6659.267480577229;6489.545731798728;6530.1920174323595;6553.311902250386;6573.638449885302;6622.337766223339;4890.951090489049;4891.119598472309;4902.14212512816;4938.308636790924;4946.109700253989;6606.630618384755;6669.9990002999875;6804.353586409283;6804.931950680628;6811.693473793592;6541.247756508063;6579.144711187515;6585.1439312889015;6601.834954125993;6621.702023409054;6516.446102081376;6520.087187793618;6538.117354157318;6547.574407663486;6565.343465653587;6398.5201109916015;6478.676066196577;6481.222253329442;6490.7079181436;6501.2523559791225;6485.513586480938;6486.805395838132;6516.380943810331;6586.341365863375;6596.350912271932;5939.802273158959;6210.285817130907;6255.968720156552;6258.123862659322;6409.679516024274 17:33:41 INFO - PID 5878 | |5;yui.html;906.5195446413403;907.7322189953962;930.1442672741077;931.4178638351028;934.0379409208517;18683.532911677215;18700.53248668784;19030.714539281886;19105.713414298796;19116.33092841749;18567.81432185681;19108;19949.700754488695;20199.798002019976;20426.69359959598;1002.709214327844;1017.5777052523194;1025.0262250861674;1025.8615086963277;1033.9431331276776;1012.4026824173736;1013.8732658417698;1015.8628585141022;1016.7508960304735;1026.4918865161756;1069.8823129455754;1072.951717172729;1073.2326386633556;1073.7154654016692;1086.4676308608764;1012.8683271174737;1042.807080690067;1043.0664555223034;1046.2885238038093;1052.184556968348;762.0055827145572;762.7101701353506;763.0118995900267;763.0118995900323;764.9940328468047;18715.344962926367;18800.811991880044;18885.81114188868;19089.522761930923;19155.23379064836;17461.650766984716;17517.474475765746;18280.085995700294;18409.35567255153;18445.815541844542;16278.348866045344;16289.266982985793;16430.424935127332;16432.342706291733;16488.917555412147;3426.794392336443;3534.2578058607724;3548.6451354864566;3563.073600863768;3822.1591249924973;123834;126318.10522842164;129205;130286;130722.34638826746;15195.848041519443;15275.38898444072;15377.769333460006;15405.229738513253;15446.995945263747;27036.59445108325;28845;29152.41695166085;29422.705772941998;30342.84828575887;8134.552599607078;8174.9182508175345;8625.439346442461;8662.133786621413;8705.260052895575;4084.6936479764136;4108.198901214294;4138.503379594407;4183.100633363842;4194.706370554032;4937.604991600663;5004.674696144739;5014.247862820606;5058.696478211319;5129.8717532062;1024.9180065594735;1066.962656307041;1069.0378659206776;1069.4171676436206;1071.8231491803922;563.4844117632321;670.1020632352671;698.4866123399297;707.9292070792983;715.377621469325;11246.325220486797;13392;13465.528706495226;13702;13745.24401157946;661.9040239165294;676.1514299554028;687.285223367692;706.5513398991677;717.1895757793764;5106.897862042739;5410.729463526886;5485.094959331665;5586.525145362609;5682.914756278659;97512.51243743878;102630;103165.48417257865;103574;104825;84922.5753871239;87064;89238.10761892427;89863;93728;75585.62207188929;76051.61974190094;76212.61893690495;79786.20213797904;79962.80055799168;10943.343399396063;11051.889481105087;11135.220534562826;11450.370229637118;11454.026407755435;36363.45454818233;36584.81707591445;36796.448053278684;37889.81055094707;38368.42447363236;44849.77575112104;46308.30537542007;46715.29927051029;47184.29223561581;47770.76114619405;15677.372905083668;15719.607009824846;15959.8404015963;17960.281588736303;19133.947632880325;19705.901470492558;19840.70238946388;19919.203231870564;20151.79848201558;20183.293584725052;17423.825761742726;17968.19143138588;18019.639607208046;18438.81561184371;18458.446246612624;17448.389306374214;18119.728204076684;18327;18333.724994124834;18367.081645917384;16900.492985210465;17077.23152458167;17085.57286067858;17335.566610834325;17686.646267074844;12819;12983.675408114872;12989.935050324688;13057.934710326388;13282.402291896695;13223;13325.267110309027;13521.391537380645;13660.7009322501;13720.796581794637 17:33:41 INFO - PID 5878 | __end_tp_report 17:33:41 INFO - PID 5878 | __start_cc_report 17:33:41 INFO - PID 5878 | _x_x_mozilla_cycle_collect,363 17:33:41 INFO - PID 5878 | __end_cc_report 17:33:41 INFO - PID 5878 | __startTimestamp1500424421477__endTimestamp 17:33:41 INFO - PID 5878 | 17:33:41 INFO - PID 5878 | ------- Summary: start ------- 17:33:41 INFO - PID 5878 | Number of tests: 6 17:33:41 INFO - PID 5878 | 17:33:41 INFO - PID 5878 | [#0] dojo.html Cycles:165 Average:6038.43 Median:6301.91 stddev:715.82 (11.4%) stddev-sans-first:679.84 17:33:41 INFO - PID 5878 | Values: 3098.2 3143.0 3145.0 3148.7 3179.9 6001.9 6023.5 6044.8 6055.3 6194.0 6128.6 6165.6 6215.8 6218.2 6225.5 5636.5 5670.3 5679.4 5682.8 5739.6 5525.4 5537.1 5625.4 5704.2 5735.8 5484.9 5550.9 5552.8 5569.5 5617.3 5507.9 5526.2 5526.6 5550.6 5570.1 5436.1 5494.8 5504.3 5538.8 5539.7 6004.7 6009.1 6018.0 6018.2 6030.2 6465.1 6478.7 6504.6 6505.7 6531.2 6565.6 6566.3 6567.3 6587.9 6592.5 6030.1 6068.7 6114.6 6163.4 6229.1 6767.1 6783.8 6848.6 6882.1 6930.8 6302.9 6441.1 6448.2 6461.3 6559.2 6537.5 6544.9 6547.9 6557.4 6563.3 5377.4 6458.4 6503.1 6549.7 6629.2 6344.8 6567.0 6635.7 6639.9 6644.4 5238.4 5251.4 5271.3 5316.8 5335.4 4501.1 4508.5 4540.7 4568.3 4611.4 5718.4 5756.4 5759.6 5762.1 5762.3 5726.5 6614.1 6631.2 6700.8 6706.1 5691.9 5692.2 5789.1 5810.9 5818.4 5697.6 5770.0 5827.7 5881.4 5886.9 6375.6 6380.6 6395.1 6403.5 6424.9 6475.2 6550.5 6552.8 6554.0 6566.1 6376.6 6382.6 6383.3 6386.1 6399.2 6383.4 6443.6 6464.5 6480.0 6491.0 6663.3 6668.9 6690.4 6751.4 6768.9 6416.4 6427.0 6525.2 6526.8 6551.3 6362.5 6390.5 6419.7 6429.2 6489.9 6301.9 6331.4 6343.5 6347.4 6364.9 6318.3 6455.2 6459.9 6475.4 6509.7 6239.0 6244.7 6252.2 6256.0 6273.1 17:33:41 INFO - PID 5878 | 17:33:41 INFO - PID 5878 | [#1] ext.html Cycles:180 Average:37872.64 Median:35881.46 stddev:53728.14 (149.7%) stddev-sans-first:53806.41 17:33:41 INFO - PID 5878 | Values: 711.9 782.9 811.9 832.7 839.9 41255.6 42209.6 42275.8 42277.2 42347.8 40081.0 40189.8 40308.6 40402.0 40475.2 6875.1 7004.9 7009.1 7010.8 7021.5 6857.6 6913.4 6949.7 6950.9 6952.1 6866.4 6884.2 6894.7 6905.6 6927.5 6831.3 6842.5 6895.7 6910.3 6910.8 6220.5 6253.3 6273.3 6276.6 6283.1 35531.1 35758.5 35825.5 35842.6 35920.3 3365.4 3414.1 3534.2 3574.0 3576.2 32310.2 32400.5 32607.2 32652.2 32681.5 4918.2 4963.7 4984.1 5004.2 5080.4 184099.0 188749.0 189387.0 189632.0 190970.0 44138.6 49765.0 49951.3 50292.5 50462.2 51316.0 51430.0 51532.7 51884.0 51888.5 15862.4 16012.0 16062.1 16123.5 16136.4 12902.2 12968.7 13067.4 13076.2 13081.3 14463.8 14499.3 14767.8 14795.2 14932.4 5803.7 5953.6 5984.4 6064.7 6088.8 948.7 971.9 985.2 994.3 1004.5 22506.5 22783.2 22940.6 23223.7 23827.4 1052.9 1065.2 1078.3 1089.9 1104.3 11541.6 11854.2 11866.5 12039.5 12144.9 226743.0 228842.0 229074.0 229126.0 229823.0 50178.7 50316.5 50444.2 50802.2 50815.7 1418.3 1482.9 1495.3 1499.3 1501.6 21438.0 21550.2 21735.5 21763.1 21791.1 36407.8 36636.5 36759.1 36851.0 36966.8 200869.0 202147.0 202193.0 202702.0 203286.0 33465.3 37795.4 38552.8 39041.4 39828.4 47737.8 48279.8 48674.8 48894.8 49370.0 33383.7 34581.0 34884.0 34910.1 35297.5 33906.5 34405.7 34488.0 35141.3 35225.3 38381.2 38497.6 38532.6 38640.6 38813.2 33118.0 33359.3 33488.7 34124.0 34319.0 24053.5 24478.5 24537.3 24786.0 24790.0 17:33:41 INFO - PID 5878 | 17:33:41 INFO - PID 5878 | [#2] jquery.html Cycles:180 Average:34159.93 Median:20382.04 stddev:75950.05 (372.6%) stddev-sans-first:76121.24 17:33:41 INFO - PID 5878 | Values: 572.6 573.3 575.6 582.9 605.6 16840.6 21744.3 23262.4 23285.8 23290.1 19297.2 19319.5 19637.0 19671.3 20902.6 3833.0 6417.2 6519.1 6538.7 6572.7 6298.6 6383.3 6435.5 6443.1 6449.8 6369.9 6379.9 6399.6 6419.7 6420.1 6170.5 6356.2 6385.1 6401.9 6412.9 6685.5 6694.3 6695.0 6696.5 6723.5 30531.5 30936.1 31067.2 31091.0 31120.2 1983.8 2005.8 2015.8 2023.8 2025.2 19004.2 19270.2 19523.0 19700.9 19844.9 2352.3 2404.1 2432.6 2450.2 2455.0 125859.0 130918.0 132130.0 132925.0 136966.0 20047.4 20227.5 21060.6 21992.7 22253.0 42371.0 42509.6 42532.6 42755.4 42775.0 11333.9 11645.1 11648.4 11682.9 11756.8 9647.8 9691.2 9809.0 9847.9 9881.2 11009.0 11120.4 11212.4 11233.2 11494.9 3759.7 4064.7 4197.4 4277.6 4280.7 954.0 956.0 959.1 983.2 987.8 18890.2 19359.5 19369.4 19377.0 19655.8 1002.4 1041.7 1061.4 1066.8 1068.7 10669.9 10717.6 10741.6 10749.9 10841.3 439936.0 444077.0 451340.0 452588.0 456341.0 108235.0 112339.4 113969.0 116025.0 118838.0 1197.2 1208.0 1256.1 1307.6 1307.7 19495.3 20014.2 20167.2 20267.0 20497.1 54965.2 56277.7 57096.0 57170.0 58184.7 66699.0 70895.6 72062.6 75090.2 76599.0 13805.5 14293.1 14583.4 14678.2 14679.3 23564.6 23564.9 23599.6 23675.6 23772.6 18165.5 18187.4 18307.3 18435.4 18619.0 15410.2 15724.2 15754.0 15821.4 16020.5 19066.2 19115.4 19133.9 19267.1 19329.8 14242.1 14242.9 14249.7 14258.5 14311.9 17336.6 18027.6 18167.0 18474.1 18586.2 17:33:41 INFO - PID 5878 | 17:33:41 INFO - PID 5878 | [#3] mootools.html Cycles:180 Average:8437.57 Median:2584.79 stddev:5705.19 (220.7%) stddev-sans-first:5696.43 17:33:41 INFO - PID 5878 | Values: 1362.9 1419.8 1425.5 1427.8 1430.2 13581.7 14117.2 14196.1 14256.6 14273.9 13876.7 14640.9 14726.1 14753.9 14780.8 2566.4 2651.6 2655.7 2659.3 2663.7 2552.1 2632.1 2633.7 2637.6 2645.7 2568.4 2601.2 2612.0 2616.7 2617.4 2527.9 2531.6 2532.5 2534.0 2535.7 2396.1 2417.5 2419.4 2428.6 2435.7 12823.9 13066.3 13084.5 13097.3 13116.1 6928.6 7233.1 7248.0 7259.6 7262.5 4724.6 4774.7 4809.0 4850.4 4864.6 1836.9 1838.6 1857.2 1882.5 1889.7 20230.2 20694.5 20709.4 20738.0 20771.3 6358.7 6409.4 6595.1 6615.4 6628.0 16696.6 16747.2 16867.3 16869.2 16883.3 7215.9 7413.0 7448.4 7464.2 7478.6 5920.7 6688.0 6911.1 6915.3 6922.0 5028.7 5151.1 5213.3 5226.0 5242.9 1620.3 1664.7 1666.1 1669.0 1675.7 904.7 913.0 935.6 947.1 951.8 7398.9 7736.3 8113.0 8229.0 8245.4 682.3 694.2 706.0 716.7 722.3 4547.8 4639.8 4651.3 4684.5 4703.9 17521.4 18056.9 18408.4 18424.0 18592.9 18004.6 18394.5 18988.4 19161.5 19357.0 10493.9 10562.6 10667.8 10766.6 10964.8 7851.9 8085.8 8204.2 8253.3 8296.3 15581.5 16275.1 16601.8 16801.6 16907.0 16307.9 16856.7 17473.5 17688.4 17864.6 10425.1 10768.3 10814.8 10843.2 11081.6 12001.6 12194.5 12209.9 12313.6 12339.8 9821.8 9996.5 10127.3 10175.5 10473.9 9193.2 9607.7 9703.9 9872.0 9966.4 9009.0 9093.2 9131.6 9143.7 9183.3 6690.8 6762.5 6766.5 6805.1 6851.6 6674.4 6712.8 6740.5 6786.7 6802.6 17:33:41 INFO - PID 5878 | 17:33:41 INFO - PID 5878 | [#4] prototype.html Cycles:180 Average:5477.31 Median:6038.96 stddev:1549.48 (25.7%) stddev-sans-first:1507.72 17:33:41 INFO - PID 5878 | Values: 478.6 500.0 511.0 511.9 519.7 6135.9 6186.3 6190.4 6197.3 6206.7 5891.3 5942.4 5968.4 6059.7 6089.0 4966.8 4976.4 4979.6 4988.6 4998.4 4805.2 4939.4 4952.3 4956.9 4995.9 4864.4 4919.6 4954.2 4957.7 4980.6 4625.3 4745.4 4767.3 4875.7 4896.7 4248.6 4264.6 4396.2 4401.7 4404.8 6031.8 6036.5 6041.4 6063.8 6063.8 5624.3 5633.2 5634.0 5639.1 5653.9 6468.2 6481.3 6519.9 6552.7 6554.4 4836.0 4843.8 4849.9 4850.5 4863.3 6840.6 6938.9 6939.7 6939.9 6968.8 4943.7 4975.7 4996.8 5011.9 5031.7 5010.0 5018.9 5038.6 5045.9 5062.7 5620.9 5639.3 5660.2 5699.4 5738.3 6428.4 6465.6 6500.6 6544.2 6567.1 1705.6 1736.5 1744.7 1748.1 1755.4 1119.9 1190.9 1202.2 1212.8 1217.3 5725.9 5740.4 5754.3 5812.2 5826.8 6403.2 6494.9 6571.9 6651.5 6751.7 5707.9 5738.9 5786.5 5824.2 5869.5 3499.3 3530.3 3572.5 3611.9 3615.2 6458.9 6478.5 6479.4 6504.8 6563.3 6782.3 6846.6 6877.0 6932.2 6947.9 6439.2 6468.8 6521.3 6541.7 6555.3 6606.0 6631.7 6683.2 6734.4 6743.3 6580.8 6598.8 6639.6 6650.5 6659.3 6489.5 6530.2 6553.3 6573.6 6622.3 4891.0 4891.1 4902.1 4938.3 4946.1 6606.6 6670.0 6804.4 6804.9 6811.7 6541.2 6579.1 6585.1 6601.8 6621.7 6516.4 6520.1 6538.1 6547.6 6565.3 6398.5 6478.7 6481.2 6490.7 6501.3 6485.5 6486.8 6516.4 6586.3 6596.4 5939.8 6210.3 6256.0 6258.1 6409.7 17:33:41 INFO - PID 5878 | 17:33:41 INFO - PID 5878 | [#5] yui.html Cycles:180 Average:22024.56 Median:18707.94 stddev:29962.89 (160.2%) stddev-sans-first:30004.99 17:33:41 INFO - PID 5878 | Values: 906.5 907.7 930.1 931.4 934.0 18683.5 18700.5 19030.7 19105.7 19116.3 18567.8 19108.0 19949.7 20199.8 20426.7 1002.7 1017.6 1025.0 1025.9 1033.9 1012.4 1013.9 1015.9 1016.8 1026.5 1069.9 1073.0 1073.2 1073.7 1086.5 1012.9 1042.8 1043.1 1046.3 1052.2 762.0 762.7 763.0 763.0 765.0 18715.3 18800.8 18885.8 19089.5 19155.2 17461.7 17517.5 18280.1 18409.4 18445.8 16278.3 16289.3 16430.4 16432.3 16488.9 3426.8 3534.3 3548.6 3563.1 3822.2 123834.0 126318.1 129205.0 130286.0 130722.3 15195.8 15275.4 15377.8 15405.2 15447.0 27036.6 28845.0 29152.4 29422.7 30342.8 8134.6 8174.9 8625.4 8662.1 8705.3 4084.7 4108.2 4138.5 4183.1 4194.7 4937.6 5004.7 5014.2 5058.7 5129.9 1024.9 1067.0 1069.0 1069.4 1071.8 563.5 670.1 698.5 707.9 715.4 11246.3 13392.0 13465.5 13702.0 13745.2 661.9 676.2 687.3 706.6 717.2 5106.9 5410.7 5485.1 5586.5 5682.9 97512.5 102630.0 103165.5 103574.0 104825.0 84922.6 87064.0 89238.1 89863.0 93728.0 75585.6 76051.6 76212.6 79786.2 79962.8 10943.3 11051.9 11135.2 11450.4 11454.0 36363.5 36584.8 36796.4 37889.8 38368.4 44849.8 46308.3 46715.3 47184.3 47770.8 15677.4 15719.6 15959.8 17960.3 19133.9 19705.9 19840.7 19919.2 20151.8 20183.3 17423.8 17968.2 18019.6 18438.8 18458.4 17448.4 18119.7 18327.0 18333.7 18367.1 16900.5 17077.2 17085.6 17335.6 17686.6 12819.0 12983.7 12989.9 13057.9 13282.4 13223.0 13325.3 13521.4 13660.7 13720.8 17:33:41 INFO - PID 5878 | -------- Summary: end -------- 17:33:41 INFO - PID 5878 | 17:33:42 INFO - TEST-INFO | 5878: exit 0 17:33:42 INFO - TEST-OK | dromaeo_css | took 1111262ms 17:33:42 INFO - TEST-START | kraken 17:33:42 INFO - Initialising browser for kraken test... 17:33:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56472/getInfo.html -profile /tmp/tmpmkQVnU/profile 17:33:42 INFO - TEST-INFO | started process 6276 (/builds/slave/test/build/application/firefox/firefox http://localhost:56472/getInfo.html) 17:33:48 INFO - TEST-INFO | 6276: exit 0 17:33:48 INFO - Browser initialized. 17:33:48 INFO - Running cycle 1/1 for kraken test... 17:33:48 INFO - TEST-INFO | started process 6526 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmkQVnU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:34:00 INFO - PID 6526 | RSS: Main: 173166592 17:34:00 INFO - PID 6526 | 17:34:02 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56472/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56472/tests/kraken/driver.html?testName=audio-beat-detection) 17:34:03 INFO - PID 6526 | RSS: Main: 177168384 17:34:03 INFO - PID 6526 | 17:34:06 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56472/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56472/tests/kraken/driver.html?testName=audio-dft) 17:34:06 INFO - PID 6526 | RSS: Main: 177803264 17:34:06 INFO - PID 6526 | 17:34:09 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56472/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56472/tests/kraken/driver.html?testName=audio-fft) 17:34:10 INFO - PID 6526 | RSS: Main: 171630592 17:34:10 INFO - PID 6526 | 17:34:12 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56472/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56472/tests/kraken/driver.html?testName=audio-oscillator) 17:34:13 INFO - PID 6526 | RSS: Main: 173068288 17:34:13 INFO - PID 6526 | 17:34:15 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56472/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56472/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:34:15 INFO - PID 6526 | RSS: Main: 173744128 17:34:15 INFO - PID 6526 | 17:34:18 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56472/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56472/tests/kraken/driver.html?testName=imaging-darkroom) 17:34:19 INFO - PID 6526 | RSS: Main: 176791552 17:34:19 INFO - PID 6526 | 17:34:22 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56472/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56472/tests/kraken/driver.html?testName=imaging-desaturate) 17:34:23 INFO - PID 6526 | RSS: Main: 177741824 17:34:23 INFO - PID 6526 | 17:34:26 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56472/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56472/tests/kraken/driver.html?testName=json-parse-financial) 17:34:26 INFO - PID 6526 | RSS: Main: 176242688 17:34:26 INFO - PID 6526 | 17:34:28 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56472/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56472/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:34:29 INFO - PID 6526 | RSS: Main: 176263168 17:34:29 INFO - PID 6526 | 17:34:30 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56472/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56472/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:34:31 INFO - PID 6526 | RSS: Main: 176312320 17:34:31 INFO - PID 6526 | 17:34:33 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56472/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56472/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:34:34 INFO - PID 6526 | RSS: Main: 176427008 17:34:34 INFO - PID 6526 | 17:34:36 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56472/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56472/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:34:37 INFO - PID 6526 | RSS: Main: 176197632 17:34:37 INFO - PID 6526 | 17:34:40 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56472/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56472/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:34:41 INFO - PID 6526 | RSS: Main: 176603136 17:34:41 INFO - PID 6526 | 17:34:42 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56472/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56472/tests/kraken/driver.html?testName=ai-astar) 17:34:43 INFO - PID 6526 | RSS: Main: 177881088 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | __start_tp_report 17:34:43 INFO - PID 6526 | _x_x_mozilla_page_load 17:34:43 INFO - PID 6526 | _x_x_mozilla_page_load_details 17:34:43 INFO - PID 6526 | |i|pagename|runs| 17:34:43 INFO - PID 6526 | |0;ai-astar;99;109;101;109;109;113;109;101;109;109 17:34:43 INFO - PID 6526 | |1;audio-beat-detection;155;121;148;175;119;151;147;129;172;119 17:34:43 INFO - PID 6526 | |2;audio-dft;163;163;163;162;160;168;164;165;173;163 17:34:43 INFO - PID 6526 | |3;audio-fft;84;75;76;167;75;75;74;76;96;78 17:34:43 INFO - PID 6526 | |4;audio-oscillator;80;81;133;80;79;81;135;81;80;129 17:34:43 INFO - PID 6526 | |5;imaging-gaussian-blur;114;114;114;113;113;114;113;114;114;114 17:34:43 INFO - PID 6526 | |6;imaging-darkroom;150;151;150;150;151;151;150;150;149;149 17:34:43 INFO - PID 6526 | |7;imaging-desaturate;94;94;93;93;94;97;94;94;93;94 17:34:43 INFO - PID 6526 | |8;json-parse-financial;55;58;55;55;56;57;56;57;58;57 17:34:43 INFO - PID 6526 | |9;json-stringify-tinderbox;54;54;55;54;54;54;54;53;54;55 17:34:43 INFO - PID 6526 | |10;stanford-crypto-aes;80;80;79;77;78;78;79;77;77;77 17:34:43 INFO - PID 6526 | |11;stanford-crypto-ccm;135;128;126;130;131;129;122;133;123;160 17:34:43 INFO - PID 6526 | |12;stanford-crypto-pbkdf2;176;179;181;180;178;182;178;189;181;196 17:34:43 INFO - PID 6526 | |13;stanford-crypto-sha256-iterative;72;68;69;70;70;69;67;69;70;70 17:34:43 INFO - PID 6526 | __end_tp_report 17:34:43 INFO - PID 6526 | __start_cc_report 17:34:43 INFO - PID 6526 | _x_x_mozilla_cycle_collect,978 17:34:43 INFO - PID 6526 | __end_cc_report 17:34:43 INFO - PID 6526 | __startTimestamp1500424483121__endTimestamp 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | ------- Summary: start ------- 17:34:43 INFO - PID 6526 | Number of tests: 14 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | [#0] ai-astar Cycles:10 Average:106.80 Median:109.00 stddev:4.66 (4.3%) stddev-sans-first:4.00 17:34:43 INFO - PID 6526 | Values: 99.0 109.0 101.0 109.0 109.0 113.0 109.0 101.0 109.0 109.0 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | [#1] audio-beat-detection Cycles:10 Average:143.60 Median:149.50 stddev:20.93 (14.0%) stddev-sans-first:21.79 17:34:43 INFO - PID 6526 | Values: 155.0 121.0 148.0 175.0 119.0 151.0 147.0 129.0 172.0 119.0 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | [#2] audio-dft Cycles:10 Average:164.40 Median:163.50 stddev:3.66 (2.2%) stddev-sans-first:3.84 17:34:43 INFO - PID 6526 | Values: 163.0 163.0 163.0 162.0 160.0 168.0 164.0 165.0 173.0 163.0 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | [#3] audio-fft Cycles:10 Average:87.60 Median:76.00 stddev:28.69 (37.8%) stddev-sans-first:30.41 17:34:43 INFO - PID 6526 | Values: 84.0 75.0 76.0 167.0 75.0 75.0 74.0 76.0 96.0 78.0 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:80.00 stddev:25.19 (31.5%) stddev-sans-first:26.05 17:34:43 INFO - PID 6526 | Values: 80.0 81.0 133.0 80.0 79.0 81.0 135.0 81.0 80.0 129.0 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 17:34:43 INFO - PID 6526 | Values: 114.0 114.0 114.0 113.0 113.0 114.0 113.0 114.0 114.0 114.0 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | [#6] imaging-darkroom Cycles:10 Average:150.10 Median:150.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 17:34:43 INFO - PID 6526 | Values: 150.0 151.0 150.0 150.0 151.0 151.0 150.0 150.0 149.0 149.0 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:1.15 (1.2%) stddev-sans-first:1.22 17:34:43 INFO - PID 6526 | Values: 94.0 94.0 93.0 93.0 94.0 97.0 94.0 94.0 93.0 94.0 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:57.00 stddev:1.17 (2.1%) stddev-sans-first:1.13 17:34:43 INFO - PID 6526 | Values: 55.0 58.0 55.0 55.0 56.0 57.0 56.0 57.0 58.0 57.0 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | [#9] json-stringify-tinderbox Cycles:10 Average:54.10 Median:54.00 stddev:0.57 (1.1%) stddev-sans-first:0.60 17:34:43 INFO - PID 6526 | Values: 54.0 54.0 55.0 54.0 54.0 54.0 54.0 53.0 54.0 55.0 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | [#10] stanford-crypto-aes Cycles:10 Average:78.20 Median:78.50 stddev:1.23 (1.6%) stddev-sans-first:1.12 17:34:43 INFO - PID 6526 | Values: 80.0 80.0 79.0 77.0 78.0 78.0 79.0 77.0 77.0 77.0 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | [#11] stanford-crypto-ccm Cycles:10 Average:131.70 Median:130.50 stddev:10.75 (8.2%) stddev-sans-first:11.34 17:34:43 INFO - PID 6526 | Values: 135.0 128.0 126.0 130.0 131.0 129.0 122.0 133.0 123.0 160.0 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.00 Median:181.00 stddev:6.04 (3.3%) stddev-sans-first:6.00 17:34:43 INFO - PID 6526 | Values: 176.0 179.0 181.0 180.0 178.0 182.0 178.0 189.0 181.0 196.0 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:70.00 stddev:1.35 (1.9%) stddev-sans-first:1.05 17:34:43 INFO - PID 6526 | Values: 72.0 68.0 69.0 70.0 70.0 69.0 67.0 69.0 70.0 70.0 17:34:43 INFO - PID 6526 | -------- Summary: end -------- 17:34:43 INFO - PID 6526 | 17:34:43 INFO - PID 6526 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:34:43 INFO - TEST-INFO | 6526: exit 0 17:34:43 INFO - TEST-OK | kraken | took 61583ms 17:34:43 INFO - SUITE-END | took 1172s 17:34:44 INFO - Completed test suite (00:19:33) 17:34:44 INFO - javascript benchmark 17:34:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8372.584476356878, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3098.2409309719137, 3143.0256620447676, 3144.9842750786243, 3148.7008734170254, 3179.936401271975, 6001.909971350427, 6023.457888790008, 6044.758209671619, 6055.333913269536, 6194.00895856663, 6128.57100003, 6165.599236049665, 6215.844603884894, 6218.1916350874435, 6225.470834979033, 5636.549076073925, 5670.3479099903625, 5679.432056794329, 5682.829515114553, 5739.569532285074, 5525.4474552544825, 5537.0863807471715, 5625.38120806711, 5704.229928959599, 5735.827925162252, 5484.890302193954, 5550.888982220374, 5552.750126244308, 5569.526590239834, 5617.269754931874, 5507.944920550824, 5526.198701188346, 5526.557875370001, 5550.639208451478, 5570.108782594806, 5436.103042997901, 5494.835154945358, 5504.284443022421, 5538.833834984916, 5539.723013849331, 6004.699765011732, 6009.09863520471, 6017.969910150421, 6018.247719035121, 6030.185924900126, 6465.094886715863, 6478.708458119396, 6504.60972341661, 6505.674716264215, 6531.183602049744, 6565.606063636197, 6566.277709451956, 6567.310432404576, 6587.868242635121, 6592.472602191813, 6030.12563178341, 6068.696565171724, 6114.602550834226, 6163.352847950945, 6229.127922090911, 6767.052612634235, 6783.762568310085, 6848.623325717083, 6882.070920425778, 6930.826729331807, 6302.905456418157, 6441.066045423388, 6448.193975753031, 6461.321561236003, 6559.180102487189, 6537.477001839841, 6544.934550654528, 6547.9017814732815, 6557.409833115043, 6563.278039415661, 5377.435369286247, 6458.354164583504, 6503.057056726748, 6549.672516374162, 6629.204495460479, 6344.841378965562, 6567.0, 6635.734570617121, 6639.867202656016, 6644.368784965421, 5238.423773384925, 5251.4485978972425, 5271.288376069181, 5316.76074576645, 5335.359756829128, 4501.077279157765, 4508.45898492183, 4540.659450541222, 4568.269076947672, 4611.377464042378, 5718.399568045378, 5756.366799652035, 5759.596828222068, 5762.078067509178, 5762.337331206881, 5726.465503693216, 6614.074029635759, 6631.171103612049, 6700.83247918806, 6706.061151438705, 5691.943080569225, 5692.174634677948, 5789.131630255495, 5810.941890581124, 5818.359980402152, 5697.572682048863, 5770.0, 5827.650340979555, 5881.441263080001, 5886.852828679232, 6375.649339286291, 6380.617162970237, 6395.072714456378, 6403.519736019724, 6424.935750642434, 6475.222973243241, 6550.541462097607, 6552.770653027121, 6553.967230163818, 6566.080748695282, 6376.617402955838, 6382.585131966407, 6383.265924418752, 6386.105945167773, 6399.168108145911, 6383.36166383358, 6443.613383197023, 6464.547481676237, 6480.0, 6490.967545162244, 6663.26704062543, 6668.899966500409, 6690.397864192246, 6751.3923746861055, 6768.898466523105, 6416.390442907917, 6427.0, 6525.2169739631545, 6526.836829079311, 6551.279359270381, 6362.491000720024, 6390.456811171102, 6419.711113000019, 6429.2284925807335, 6489.8702025960165, 6301.905471417841, 6331.430171284423, 6343.524235682435, 6347.3652634738, 6364.904526432015, 6318.304986451395, 6455.160829092188, 6459.903101453576, 6475.384838440341, 6509.707063182073, 6239.032949892774, 6244.687765611792, 6252.218472690914, 6255.96872015637, 6273.121762953099], "value": 5985.982121481954, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.8825393810022, 782.9138794732582, 811.8863359129723, 832.7127141136308, 839.9496030238187, 41255.58744412559, 42209.57790422095, 42275.78862105693, 42277.154456910845, 42347.78826105869, 40081.0, 40189.799051004775, 40308.59691403085, 40402.0, 40475.19049619006, 6875.106236189301, 7004.859902801966, 7009.08881845362, 7010.7546235881755, 7021.473389495783, 6857.554258973176, 6913.446924246048, 6949.6872640731035, 6950.89573656393, 6952.09622749042, 6866.382025617693, 6884.242733299358, 6894.655267236643, 6905.585664860099, 6927.549709268881, 6831.316868313179, 6842.486813488983, 6895.655217239143, 6910.3089691031, 6910.7581234656545, 6220.502359811204, 6253.312135665074, 6273.341299163568, 6276.623402595813, 6283.057541368831, 35531.11172220689, 35758.463623045674, 35825.46261806075, 35842.641573584195, 35920.28159436823, 3365.4278772608714, 3414.078198886287, 3534.2224710563637, 3574.035010547164, 3576.2311103112957, 32310.19224519406, 32400.513992290136, 32607.18482037968, 32652.183695407566, 32681.50977735336, 4918.163912134945, 4963.677360971561, 4984.127777638899, 5004.249362595603, 5080.441151473335, 184099.0, 188749.0, 189387.0, 189632.0, 190970.0, 44138.55861441409, 49765.0, 49951.25073123906, 50292.49707502951, 50462.243066354036, 51316.0, 51430.0, 51532.74233628808, 51884.0, 51888.48111518912, 15862.44481443167, 16011.95922265049, 16062.116583587782, 16123.516294511184, 16136.435224767312, 12902.161359511601, 12968.740625187444, 13067.411966461532, 13076.215427074407, 13081.345932703518, 14463.78304325436, 14499.275036248357, 14767.778483322758, 14795.186264755326, 14932.402703891723, 5803.680797556175, 5953.642781433128, 5984.371640977634, 6064.727087280995, 6088.786892458831, 948.7011591348703, 971.9416834989923, 985.1724551376734, 994.3139233928566, 1004.5128112865254, 22506.549869002527, 22783.202587909345, 22940.557938854654, 23223.651645225335, 23827.40431489192, 1052.8631277933819, 1065.1638463805875, 1078.2937176149635, 1089.8528698625742, 1104.3429159649995, 11541.596044138414, 11854.170208085523, 11866.466009029615, 12039.458224379923, 12144.878551214551, 226743.0, 228842.0, 229074.0, 229126.0, 229823.0, 50178.74910625497, 50316.49683503118, 50444.24333634999, 50802.23796643053, 50815.74592127016, 1418.319206780751, 1482.9110253384831, 1495.3196295685655, 1499.295331194337, 1501.564546281588, 21437.970575722276, 21550.245741398976, 21735.456613584785, 21763.129474821464, 21791.12835486563, 36407.817960910026, 36636.45045324269, 36759.08102297464, 36851.0, 36966.815165923996, 200869.0, 202147.0, 202193.0, 202702.0, 203286.0, 33465.33069338648, 37795.43306850454, 38552.80723596364, 39041.41437878491, 39828.40257396083, 47737.76131119322, 48279.75860120677, 48674.756626216644, 48894.75552622214, 49370.0, 33383.66616333806, 34581.0, 34884.0, 34910.12724681802, 35297.47053794246, 33906.49140262947, 34405.655943440244, 34488.0, 35141.297174055864, 35225.29549409049, 38381.23237535178, 38497.61502385052, 38532.614673852906, 38640.6135938637, 38813.223735525695, 33118.0, 33359.33281334408, 33488.66511334856, 34124.0, 34319.0, 24053.51892962166, 24478.51042979095, 24537.263882083567, 24786.0, 24790.0], "value": 16986.978964509042, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.5619900775905, 573.3034363248653, 575.5568212476396, 582.9213056237406, 605.5760967322874, 16840.57898552534, 21744.3476695699, 23262.418439539, 23285.767142328594, 23290.06839726413, 19297.22811087555, 19319.517012074706, 19637.0, 19671.31150409736, 20902.581948361025, 3833.0, 6417.197850268716, 6519.119918810971, 6538.673066346688, 6572.737090516397, 6298.622082675031, 6383.265924418682, 6435.485161187117, 6443.130177426035, 6449.806505804833, 6369.904451433209, 6379.904301435482, 6399.584027038228, 6419.711112999903, 6420.133282006916, 6170.475509581691, 6356.2054743157105, 6385.138006369127, 6401.935980640181, 6412.903806442884, 6685.53201275906, 6694.263631000586, 6694.966525167343, 6696.497762667771, 6723.462123030145, 30531.542026869614, 30936.071917842502, 31067.223319416968, 31091.0, 31120.221994450094, 1983.7817840037728, 2005.809448102428, 2015.838732901379, 2023.7773844877051, 2025.169680431016, 19004.23983040677, 19270.22919083235, 19523.0, 19700.901495492573, 19844.900775496175, 2352.2590384028977, 2404.086447150089, 2432.6472661464168, 2450.2281781238853, 2455.017992802875, 125859.0, 130918.0, 132130.0, 132925.0, 136966.0, 20047.39857804268, 20227.494312642302, 21060.578788424147, 21992.670109948365, 22253.0, 42371.0, 42509.57490425056, 42532.57467425348, 42755.35866961998, 42775.0, 11333.923277288644, 11645.12661550387, 11648.41757912118, 11682.941585292188, 11756.764864702829, 9647.758806029766, 9691.176250018827, 9808.950955245178, 9847.901520984842, 9881.209503239723, 11009.019389701196, 11120.443977801078, 11212.383318917538, 11233.157513186543, 11494.88505114955, 3759.6804271637216, 4064.7357921734992, 4197.412362269285, 4277.636400905895, 4280.6789490788315, 953.9618415263451, 955.9665411710557, 959.1319855530755, 983.1643103362085, 987.7728122531857, 18890.244390224238, 19359.516012099528, 19369.4189174325, 19377.0, 19655.803441965683, 1002.4336250018716, 1041.671873359889, 1061.3525749292926, 1066.7653116314398, 1068.690079876827, 10669.946650266698, 10717.624883129052, 10741.624043158608, 10749.925007499187, 10841.295315804447, 439936.0, 444077.0, 451340.0, 452588.0, 456341.0, 108235.0, 112339.43830280796, 113969.0, 116025.0, 118837.99999999827, 1197.1918954705538, 1207.9577214797616, 1256.0956111599633, 1307.601181639609, 1307.7123032932739, 19495.317663881695, 20014.19943202314, 20167.193312267344, 20267.0, 20497.077631506916, 54965.175522367994, 56277.71861140668, 57096.0, 57170.0, 58184.709076454346, 66699.0, 70895.64552177206, 72062.63968680122, 75090.24909750832, 76599.0, 13805.51680691171, 14293.14241145493, 14583.416663333348, 14678.192699401636, 14679.339429725474, 23564.646530301718, 23564.882175589013, 23599.64600530959, 23675.64486532669, 23772.643410349203, 18165.455036348932, 18187.363442279457, 18307.26770929148, 18435.446936591925, 18619.0, 15410.152441615819, 15724.213789310717, 15754.0, 15821.367145314058, 16020.519384418487, 19066.237350505824, 19115.426537203904, 19133.904330478257, 19267.13297901626, 19329.8067019328, 14242.07426517273, 14242.857571424567, 14249.715005700034, 14258.500952466615, 14311.928440357731, 17336.56658583495, 18027.639447211244, 18167.0, 18474.076296184867, 18586.163622636745], "value": 12438.024633494459, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1362.9045966782326, 1419.8012278281035, 1425.5224499792569, 1427.828660560733, 1430.2419717549699, 13581.660458488543, 14117.152970821759, 14196.07725497842, 14256.643583910407, 14273.857261427396, 13876.653083672913, 14640.926795366035, 14726.116433014027, 14753.926230368834, 14780.778288325684, 2566.4482136340685, 2651.5757478803393, 2655.721149279317, 2659.295286749013, 2663.706992230863, 2552.132275026491, 2632.1182403894622, 2633.710291867893, 2637.604359346104, 2645.7354264573582, 2568.3964268396912, 2601.1806281021513, 2611.986940065297, 2616.738326167387, 2617.4372509910368, 2527.9241622751347, 2531.5822889223255, 2532.5441420544294, 2534.037065914959, 2535.733747956456, 2396.125414223813, 2417.5406672732124, 2419.3911049152293, 2428.6357046443, 2435.683361163055, 12823.87176128236, 13066.346682665924, 13084.476620935151, 13097.279649619268, 13116.081874268804, 6928.61892595907, 7233.132024157135, 7248.0, 7259.60072196029, 7262.491625586211, 4724.622030237572, 4774.713517188945, 4809.038192361541, 4850.393700787402, 4864.6108311335365, 1836.898970556619, 1838.586318078435, 1857.1828395505934, 1882.5011371986434, 1889.6787546117193, 20230.19079236829, 20694.482637934023, 20709.378718638316, 20738.0, 20771.27300544474, 6358.7456501740335, 6409.4231519163495, 6595.142631457879, 6615.437687796495, 6627.966860165669, 16696.582585435463, 16747.162641867857, 16867.325306987583, 16869.240884159997, 16883.32466701318, 7215.891761623579, 7413.0, 7448.441366897504, 7464.216257292906, 7478.551286922802, 5920.674362909996, 6688.030235615808, 6911.136107986502, 6915.308469153045, 6921.965390173017, 5028.673136246132, 5151.098557752378, 5213.2701421800975, 5226.0, 5242.947570524246, 1620.3113676687597, 1664.650423411073, 1666.0510895714517, 1668.9749653755202, 1675.6732437174878, 904.7331037344, 912.9589168487302, 935.611721135721, 947.0860619502075, 951.7715748220377, 7398.889016664754, 7736.303732664087, 8113.0, 8229.0, 8245.422820402515, 682.3108660253081, 694.2467422846167, 705.9752908648172, 716.6954044531053, 722.299369611476, 4547.795349209294, 4639.837605683785, 4651.2557990721325, 4684.531546845288, 4703.882402939954, 17521.386751463637, 18056.90971545134, 18408.35570755017, 18424.0, 18592.907035464737, 18004.63990720178, 18394.540136496427, 18988.43034708961, 19161.52096197606, 19357.0, 10493.895061049292, 10562.577496900192, 10667.786644267226, 10766.623168189233, 10964.780704385868, 7851.882221766564, 8085.757427277191, 8204.179582041748, 8253.33973282124, 8296.294814940557, 15581.454649087227, 16275.104869231833, 16601.750973735645, 16801.579960501087, 16907.0, 16307.918460407622, 16856.66286674284, 17473.47579572615, 17688.380906668204, 17864.642707146046, 10425.113865321533, 10768.300060496043, 10814.837777433188, 10843.24097313212, 11081.5567377304, 12001.63995080149, 12194.51221951112, 12209.938950305192, 12313.56902508408, 12339.75320493603, 9821.803563928825, 9996.450195239331, 10127.291089623655, 10175.542100605342, 10473.9476302618, 9193.218576421079, 9607.711768646952, 9703.9029609703, 9872.0, 9966.40201587907, 9008.954955225181, 9093.22707569864, 9131.634734610541, 9143.679971200976, 9183.31125165602, 6690.83272918181, 6762.459003279824, 6766.492513061442, 6805.115335006418, 6851.623160726208, 6674.399304062657, 6712.83217919556, 6740.528163028541, 6786.728530858711, 6802.557833740791], "value": 6169.735155643648, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.58124141376294, 499.98776937941824, 511.0, 511.85156304671636, 519.7323378460092, 6135.9079613805825, 6186.257649082111, 6190.41191086846, 6197.318294987548, 6206.689665516728, 5891.293044834626, 5942.435468630484, 5968.432998865112, 6059.727312270948, 6088.969555152229, 4966.751662416883, 4976.402831660206, 4979.626528010395, 4988.551030407262, 4998.425181104183, 4805.222067834889, 4939.407271127469, 4952.306677065214, 4956.87607809804, 4995.925061124068, 4864.367632207808, 4919.581835543965, 4954.182559877616, 4957.677750946195, 4980.626453016021, 4625.283081122431, 4745.406824146981, 4767.308740232646, 4875.733732983639, 4896.7306798126265, 4248.638865696419, 4264.637505812009, 4396.230659634552, 4401.691881568291, 4404.801783919699, 6031.823794360102, 6036.5170786336985, 6041.395860413967, 6063.757449702007, 6063.818085457443, 5624.29696287964, 5633.15502674598, 5634.0, 5639.125935480007, 5653.9151912721345, 6468.154012234649, 6481.287058423617, 6519.869602607969, 6552.705128269239, 6554.442872355856, 4835.975820120912, 4843.830465933711, 4849.8602828335315, 4850.514948505156, 4863.273537894067, 6840.555363901331, 6938.924466707668, 6939.653017349112, 6939.895901561481, 6968.79093627192, 4943.703377797344, 4975.676581022222, 4996.775145118406, 5011.899762004739, 5031.723255220998, 5010.023045506165, 5018.874528136826, 5038.622103342264, 5045.931168221012, 5062.7215503146945, 5620.9437905621235, 5639.349505732299, 5660.179274005329, 5699.401562835926, 5738.282714660668, 6428.357164283534, 6465.579737317059, 6500.6424646643945, 6544.247411547638, 6567.146270984741, 1705.6418152187932, 1736.4530172995467, 1744.6946784312947, 1748.0560497331298, 1755.350520307469, 1119.9328040317607, 1190.9047276217882, 1202.1584890576457, 1212.7635111153418, 1217.2818037358002, 5725.914111288334, 5740.425957404226, 5754.309482862, 5812.215350927655, 5826.766929322865, 6403.231612206565, 6494.870102597922, 6571.934280657228, 6651.534392592569, 6751.657929325015, 5707.942920570741, 5738.8766156521515, 5786.528083150076, 5824.184614154022, 5869.530437564985, 3499.3001399720156, 3530.258645684435, 3572.5355703758346, 3611.855525778992, 3615.1504396466808, 6458.903116453257, 6478.546501744832, 6479.449246814073, 6504.764094821969, 6563.278039415661, 6782.287859774751, 6846.5892046477375, 6877.0, 6932.232280768372, 6947.861042779116, 6439.22729272481, 6468.8059358219325, 6521.304860264933, 6541.738330466823, 6555.26414387411, 6606.042123892104, 6631.701573429304, 6683.164604424447, 6734.393904548614, 6743.258241593323, 6580.76967306142, 6598.802035938929, 6639.568428052355, 6650.501212408992, 6659.267480577229, 6489.545731798728, 6530.1920174323595, 6553.311902250386, 6573.638449885302, 6622.337766223339, 4890.951090489049, 4891.119598472309, 4902.14212512816, 4938.308636790924, 4946.109700253989, 6606.630618384755, 6669.9990002999875, 6804.353586409283, 6804.931950680628, 6811.693473793592, 6541.247756508063, 6579.144711187515, 6585.1439312889015, 6601.834954125993, 6621.702023409054, 6516.446102081376, 6520.087187793618, 6538.117354157318, 6547.574407663486, 6565.343465653587, 6398.5201109916015, 6478.676066196577, 6481.222253329442, 6490.7079181436, 6501.2523559791225, 6485.513586480938, 6486.805395838132, 6516.380943810331, 6586.341365863375, 6596.350912271932, 5939.802273158959, 6210.285817130907, 6255.968720156552, 6258.123862659322, 6409.679516024274], "value": 5018.611542394372, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [906.5195446413403, 907.7322189953962, 930.1442672741077, 931.4178638351028, 934.0379409208517, 18683.532911677215, 18700.53248668784, 19030.714539281886, 19105.713414298796, 19116.33092841749, 18567.81432185681, 19108.0, 19949.700754488695, 20199.798002019976, 20426.69359959598, 1002.709214327844, 1017.5777052523194, 1025.0262250861674, 1025.8615086963277, 1033.9431331276776, 1012.4026824173736, 1013.8732658417698, 1015.8628585141022, 1016.7508960304735, 1026.4918865161756, 1069.8823129455754, 1072.951717172729, 1073.2326386633556, 1073.7154654016692, 1086.4676308608764, 1012.8683271174737, 1042.807080690067, 1043.0664555223034, 1046.2885238038093, 1052.184556968348, 762.0055827145572, 762.7101701353506, 763.0118995900267, 763.0118995900323, 764.9940328468047, 18715.344962926367, 18800.811991880044, 18885.81114188868, 19089.522761930923, 19155.23379064836, 17461.650766984716, 17517.474475765746, 18280.085995700294, 18409.35567255153, 18445.815541844542, 16278.348866045344, 16289.266982985793, 16430.424935127332, 16432.342706291733, 16488.917555412147, 3426.794392336443, 3534.2578058607724, 3548.6451354864566, 3563.073600863768, 3822.1591249924973, 123834.0, 126318.10522842164, 129205.0, 130286.0, 130722.34638826746, 15195.848041519443, 15275.38898444072, 15377.769333460006, 15405.229738513253, 15446.995945263747, 27036.59445108325, 28845.0, 29152.41695166085, 29422.705772941998, 30342.84828575887, 8134.552599607078, 8174.9182508175345, 8625.439346442461, 8662.133786621413, 8705.260052895575, 4084.6936479764136, 4108.198901214294, 4138.503379594407, 4183.100633363842, 4194.706370554032, 4937.604991600663, 5004.674696144739, 5014.247862820606, 5058.696478211319, 5129.8717532062, 1024.9180065594735, 1066.962656307041, 1069.0378659206776, 1069.4171676436206, 1071.8231491803922, 563.4844117632321, 670.1020632352671, 698.4866123399297, 707.9292070792983, 715.377621469325, 11246.325220486797, 13392.0, 13465.528706495226, 13702.0, 13745.24401157946, 661.9040239165294, 676.1514299554028, 687.285223367692, 706.5513398991677, 717.1895757793764, 5106.897862042739, 5410.729463526886, 5485.094959331665, 5586.525145362609, 5682.914756278659, 97512.51243743878, 102630.0, 103165.48417257865, 103574.0, 104825.0, 84922.5753871239, 87064.0, 89238.10761892427, 89863.0, 93728.0, 75585.62207188929, 76051.61974190094, 76212.61893690495, 79786.20213797904, 79962.80055799168, 10943.343399396063, 11051.889481105087, 11135.220534562826, 11450.370229637118, 11454.026407755435, 36363.45454818233, 36584.81707591445, 36796.448053278684, 37889.81055094707, 38368.42447363236, 44849.77575112104, 46308.30537542007, 46715.29927051029, 47184.29223561581, 47770.76114619405, 15677.372905083668, 15719.607009824846, 15959.8404015963, 17960.281588736303, 19133.947632880325, 19705.901470492558, 19840.70238946388, 19919.203231870564, 20151.79848201558, 20183.293584725052, 17423.825761742726, 17968.19143138588, 18019.639607208046, 18438.81561184371, 18458.446246612624, 17448.389306374214, 18119.728204076684, 18327.0, 18333.724994124834, 18367.081645917384, 16900.492985210465, 17077.23152458167, 17085.57286067858, 17335.566610834325, 17686.646267074844, 12819.0, 12983.675408114872, 12989.935050324688, 13057.934710326388, 13282.402291896695, 13223.0, 13325.267110309027, 13521.391537380645, 13660.7009322501, 13720.796581794637], "value": 8795.814136804562, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1527.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 109.0, 101.0, 109.0, 109.0, 113.0, 109.0, 101.0, 109.0, 109.0], "value": 106.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 121.0, 148.0, 175.0, 119.0, 151.0, 147.0, 129.0, 172.0, 119.0], "value": 143.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 162.0, 160.0, 168.0, 164.0, 165.0, 173.0, 163.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 75.0, 76.0, 167.0, 75.0, 75.0, 74.0, 76.0, 96.0, 78.0], "value": 87.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 133.0, 80.0, 79.0, 81.0, 135.0, 81.0, 80.0, 129.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 113.0, 113.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 150.0, 150.0, 151.0, 151.0, 150.0, 150.0, 149.0, 149.0], "value": 150.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 93.0, 94.0, 97.0, 94.0, 94.0, 93.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 55.0, 55.0, 56.0, 57.0, 56.0, 57.0, 58.0, 57.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 54.0, 54.0, 54.0, 54.0, 53.0, 54.0, 55.0], "value": 54.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 77.0, 78.0, 78.0, 79.0, 77.0, 77.0, 77.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 128.0, 126.0, 130.0, 131.0, 129.0, 122.0, 133.0, 123.0, 160.0], "value": 131.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 179.0, 181.0, 180.0, 178.0, 182.0, 178.0, 189.0, 181.0, 196.0], "value": 182.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 69.0, 70.0, 70.0, 69.0, 67.0, 69.0, 70.0, 70.0], "value": 69.4, "unit": "score"}]}]} 17:34:44 INFO - Return code: 0 17:34:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:34:44 INFO - # TBPL SUCCESS # 17:34:44 INFO - Running post-action listener: _package_coverage_data 17:34:44 INFO - Running post-action listener: _resource_record_post_action 17:34:44 INFO - [mozharness: 2017-07-19 00:34:44.412745Z] Finished run-tests step (success) 17:34:44 INFO - Running post-run listener: _resource_record_post_run 17:34:44 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 663552; Write bytes: 448593920; Read time: 388; Write time: 315064 17:34:44 INFO - TinderboxPrint: CPU usage
13.1% 17:34:44 INFO - TinderboxPrint: I/O read bytes / time
663,552 / 388 17:34:44 INFO - TinderboxPrint: I/O write bytes / time
448,593,920 / 315,064 17:34:44 INFO - TinderboxPrint: CPU idle
8,260.7 (86.9%) 17:34:44 INFO - TinderboxPrint: CPU user
1,196.0 (12.6%) 17:34:44 INFO - TinderboxPrint: Swap in / out
0 / 0 17:34:44 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 119443456; Read time: 0; Write time: 79920 17:34:44 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:34:44 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 659456; Write bytes: 329150464; Read time: 376; Write time: 235144 17:34:45 INFO - Running post-run listener: _upload_blobber_files 17:34:45 INFO - Blob upload gear active. 17:34:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:34:45 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:34:45 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:34:45 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:34:45 INFO - Traceback (most recent call last): 17:34:45 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 17:34:45 INFO - main() 17:34:45 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 17:34:45 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 17:34:45 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 17:34:45 INFO - return set(response.json().get('whitelist', [])) 17:34:45 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 17:34:45 INFO - return json.loads(self.text or self.content, **kwargs) 17:34:45 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 17:34:45 INFO - return _default_decoder.decode(s) 17:34:45 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 17:34:45 INFO - obj, end = self.raw_decode(s) 17:34:45 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 17:34:45 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 17:34:45 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 17:34:45 ERROR - Return code: 1 17:34:45 INFO - Setting buildbot property blobber_files to {} 17:34:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:34:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:34:45 INFO - Contents: 17:34:45 INFO - blobber_files:{} 17:34:45 INFO - Running post-run listener: copy_logs_to_upload_dir 17:34:45 INFO - Copying logs to upload dir... 17:34:45 INFO - mkdir: /builds/slave/test/build/upload/logs 17:34:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.237061 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-07-18 17:34:45.513132) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-18 17:34:45.516969) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011284 build_url: 'https://queue.taskcluster.net/v1/task/fGcggan9T8GnTEk2lna9eQ/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-18 17:34:45.569078) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 17:34:45.569412) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8ec0ee4e6a493f968516bea6000001a5-1500422748.571590-2070671455 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-18 17:34:45.620570) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-18 17:34:45.620884) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-18 17:34:45.621208) ========= ========= Total master_lag: 0.19 =========